0s autopkgtest [10:21:33]: starting date and time: 2025-12-03 10:21:33+0000 0s autopkgtest [10:21:33]: git checkout: 508d4a25 a-v-ssh wait_for_ssh: demote "ssh connection failed" to a debug message 0s autopkgtest [10:21:33]: host juju-7f2275-prod-proposed-migration-environment-9; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.mn0874vq/out --timeout-copy=6000 --setup-commands 'ln -s /dev/null /etc/systemd/system/bluetooth.service; printf "http_proxy=http://squid.internal:3128\nhttps_proxy=http://squid.internal:3128\nno_proxy=127.0.0.1,127.0.1.1,localhost,localdomain,internal,login.ubuntu.com,archive.ubuntu.com,ports.ubuntu.com,security.ubuntu.com,ddebs.ubuntu.com,changelogs.ubuntu.com,keyserver.ubuntu.com,launchpadlibrarian.net,launchpadcontent.net,launchpad.net,keystone.ps5.canonical.com,objectstorage.prodstack5.canonical.com,radosgw.ps5.canonical.com\n" >> /etc/environment' --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 -- lxd -r lxd-armhf-10.145.243.39 lxd-armhf-10.145.243.39:autopkgtest/ubuntu/resolute/armhf 24s autopkgtest [10:21:57]: testbed dpkg architecture: armhf 26s autopkgtest [10:21:59]: testbed apt version: 3.1.12 30s autopkgtest [10:22:03]: @@@@@@@@@@@@@@@@@@@@ test bed setup 33s autopkgtest [10:22:06]: testbed release detected to be: None 41s autopkgtest [10:22:14]: updating testbed package index (apt update) 43s Get:1 http://ftpmaster.internal/ubuntu resolute-proposed InRelease [124 kB] 43s Get:2 http://ftpmaster.internal/ubuntu resolute InRelease [124 kB] 43s Get:3 http://ftpmaster.internal/ubuntu resolute-updates InRelease [124 kB] 43s Get:4 http://ftpmaster.internal/ubuntu resolute-security InRelease [124 kB] 43s Get:5 http://ftpmaster.internal/ubuntu resolute-proposed/universe Sources [981 kB] 44s Get:6 http://ftpmaster.internal/ubuntu resolute-proposed/multiverse Sources [25.2 kB] 44s Get:7 http://ftpmaster.internal/ubuntu resolute-proposed/main Sources [147 kB] 44s Get:8 http://ftpmaster.internal/ubuntu resolute-proposed/main armhf Packages [187 kB] 44s Get:9 http://ftpmaster.internal/ubuntu resolute-proposed/universe armhf Packages [678 kB] 44s Get:10 http://ftpmaster.internal/ubuntu resolute-proposed/multiverse armhf Packages [8468 B] 44s Get:11 http://ftpmaster.internal/ubuntu resolute/multiverse Sources [305 kB] 44s Get:12 http://ftpmaster.internal/ubuntu resolute/main Sources [1414 kB] 44s Get:13 http://ftpmaster.internal/ubuntu resolute/universe Sources [21.4 MB] 45s Get:14 http://ftpmaster.internal/ubuntu resolute/main armhf Packages [1366 kB] 45s Get:15 http://ftpmaster.internal/ubuntu resolute/universe armhf Packages [15.5 MB] 46s Get:16 http://ftpmaster.internal/ubuntu resolute/multiverse armhf Packages [175 kB] 49s Fetched 42.6 MB in 6s (7133 kB/s) 50s Reading package lists... 57s autopkgtest [10:22:30]: upgrading testbed (apt dist-upgrade and autopurge) 59s Reading package lists... 59s Building dependency tree... 59s Reading state information... 59s Calculating upgrade... 60s The following packages will be upgraded: 60s libpython3-stdlib python3 python3-gdbm python3-minimal 60s 4 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 60s Need to get 72.9 kB of archives. 60s After this operation, 2048 B of additional disk space will be used. 60s Get:1 http://ftpmaster.internal/ubuntu resolute-proposed/main armhf python3-minimal armhf 3.13.9-2 [28.1 kB] 60s Get:2 http://ftpmaster.internal/ubuntu resolute-proposed/main armhf python3 armhf 3.13.9-2 [23.0 kB] 60s Get:3 http://ftpmaster.internal/ubuntu resolute-proposed/main armhf libpython3-stdlib armhf 3.13.9-2 [10.8 kB] 60s Get:4 http://ftpmaster.internal/ubuntu resolute-proposed/main armhf python3-gdbm armhf 3.13.9-2 [11.0 kB] 61s Fetched 72.9 kB in 1s (136 kB/s) 61s (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 ... 65928 files and directories currently installed.) 61s Preparing to unpack .../python3-minimal_3.13.9-2_armhf.deb ... 61s Unpacking python3-minimal (3.13.9-2) over (3.13.7-1) ... 61s Setting up python3-minimal (3.13.9-2) ... 61s (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 ... 65928 files and directories currently installed.) 61s Preparing to unpack .../python3_3.13.9-2_armhf.deb ... 61s Unpacking python3 (3.13.9-2) over (3.13.7-1) ... 61s Preparing to unpack .../libpython3-stdlib_3.13.9-2_armhf.deb ... 61s Unpacking libpython3-stdlib:armhf (3.13.9-2) over (3.13.7-1) ... 61s Preparing to unpack .../python3-gdbm_3.13.9-2_armhf.deb ... 61s Unpacking python3-gdbm (3.13.9-2) over (3.13.9-1) ... 62s Setting up python3-gdbm (3.13.9-2) ... 62s Setting up libpython3-stdlib:armhf (3.13.9-2) ... 62s Setting up python3 (3.13.9-2) ... 62s Processing triggers for man-db (2.13.1-1) ... 64s Reading package lists... 65s Building dependency tree... 65s Reading state information... 65s Solving dependencies... 65s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 67s autopkgtest [10:22:40]: rebooting testbed after setup commands that affected boot 113s autopkgtest [10:23:26]: testbed running kernel: Linux 6.8.0-87-generic #88~22.04.1-Ubuntu SMP PREEMPT_DYNAMIC Tue Oct 14 14:00:09 UTC 2 138s autopkgtest [10:23:51]: @@@@@@@@@@@@@@@@@@@@ apt-source habluetooth 149s Get:1 http://ftpmaster.internal/ubuntu resolute/universe habluetooth 4.0.1-1 (dsc) [2584 B] 149s Get:2 http://ftpmaster.internal/ubuntu resolute/universe habluetooth 4.0.1-1 (tar) [133 kB] 149s Get:3 http://ftpmaster.internal/ubuntu resolute/universe habluetooth 4.0.1-1 (diff) [4508 B] 149s gpgv: Signature made Mon Jul 7 19:49:22 2025 UTC 149s gpgv: using RSA key FB8ACFA78C726089C38AD0269605A1098C63B92A 149s gpgv: Can't check signature: No public key 149s dpkg-source: warning: cannot verify inline signature for ./habluetooth_4.0.1-1.dsc: no acceptable signature found 149s autopkgtest [10:24:02]: testing package habluetooth version 4.0.1-1 152s autopkgtest [10:24:05]: build not needed 154s autopkgtest [10:24:07]: test pybuild-autopkgtest: preparing testbed 157s Reading package lists... 157s Building dependency tree... 157s Reading state information... 157s Solving dependencies... 158s The following NEW packages will be installed: 158s autoconf automake autopoint autotools-dev bluez build-essential cpp cpp-15 158s cpp-15-arm-linux-gnueabihf cpp-arm-linux-gnueabihf debhelper debugedit 158s dh-autoreconf dh-python dh-strip-nondeterminism docutils-common dwz 158s fonts-font-awesome fonts-lato furo g++ g++-15 g++-15-arm-linux-gnueabihf 158s g++-arm-linux-gnueabihf gcc gcc-15 gcc-15-arm-linux-gnueabihf 158s gcc-arm-linux-gnueabihf gettext ieee-data intltool-debian 158s libarchive-zip-perl libasan8 libasound2-data libasound2t64 libc-dev-bin 158s libc6-dev libcc1-0 libcrypt-dev libdebhelper-perl libdw1t64 158s libfile-stripnondeterminism-perl libgcc-15-dev libgomp1 libisl23 158s libjs-jquery libjs-jquery-hotkeys libjs-jquery-isonscreen 158s libjs-jquery-metadata libjs-jquery-tablesorter 158s libjs-jquery-throttle-debounce libjs-sphinxdoc libjs-underscore libjson-perl 158s libmpc3 libpython3.14-minimal libpython3.14-stdlib libstdc++-15-dev libtool 158s libubsan1 linux-libc-dev m4 po-debconf pybuild-plugin-autopkgtest 158s pybuild-plugin-pyproject python3-accessible-pygments python3-aiooui 158s python3-alabaster python3-all python3-async-generator 158s python3-async-interrupt python3-bleak python3-bleak-retry-connector 158s python3-bluetooth-adapters python3-bluetooth-auto-recovery 158s python3-bluetooth-data-tools python3-bs4 python3-btsocket python3-build 158s python3-coverage python3-dbus-fast python3-defusedxml python3-docutils 158s python3-freezegun python3-habluetooth python3-imagesize python3-iniconfig 158s python3-installer python3-mdit-py-plugins python3-myst-parser python3-pluggy 158s python3-poetry-core python3-pyproject-hooks python3-pyric python3-pytest 158s python3-pytest-asyncio python3-pytest-cov python3-roman 158s python3-snowballstemmer python3-soupsieve python3-sphinx 158s python3-uart-devices python3-usb-devices python3-wheel python3.14 158s python3.14-minimal rpcsvc-proto sgml-base sphinx-basic-ng sphinx-common 158s sphinx-rtd-theme-common xml-core 158s 0 upgraded, 112 newly installed, 0 to remove and 0 not upgraded. 158s Need to get 79.1 MB of archives. 158s After this operation, 254 MB of additional disk space will be used. 158s Get:1 http://ftpmaster.internal/ubuntu resolute/main armhf fonts-lato all 2.015-1build1 [2779 kB] 160s Get:2 http://ftpmaster.internal/ubuntu resolute/main armhf libpython3.14-minimal armhf 3.14.0-4 [892 kB] 160s Get:3 http://ftpmaster.internal/ubuntu resolute/main armhf python3.14-minimal armhf 3.14.0-4 [2146 kB] 161s Get:4 http://ftpmaster.internal/ubuntu resolute/main armhf m4 armhf 1.4.20-2 [212 kB] 161s Get:5 http://ftpmaster.internal/ubuntu resolute/main armhf autoconf all 2.72-3.1ubuntu1 [384 kB] 161s Get:6 http://ftpmaster.internal/ubuntu resolute/main armhf autotools-dev all 20240727.1 [43.4 kB] 161s Get:7 http://ftpmaster.internal/ubuntu resolute/main armhf automake all 1:1.18.1-3 [582 kB] 161s Get:8 http://ftpmaster.internal/ubuntu resolute/main armhf autopoint all 0.23.2-1 [620 kB] 161s Get:9 http://ftpmaster.internal/ubuntu resolute/main armhf libasound2-data all 1.2.14-2ubuntu1 [21.3 kB] 161s Get:10 http://ftpmaster.internal/ubuntu resolute/main armhf libasound2t64 armhf 1.2.14-2ubuntu1 [355 kB] 161s Get:11 http://ftpmaster.internal/ubuntu resolute/main armhf libdw1t64 armhf 0.194-1 [254 kB] 161s Get:12 http://ftpmaster.internal/ubuntu resolute/main armhf bluez armhf 5.84-1 [1443 kB] 161s Get:13 http://ftpmaster.internal/ubuntu resolute/main armhf libc-dev-bin armhf 2.42-2ubuntu2 [21.8 kB] 161s Get:14 http://ftpmaster.internal/ubuntu resolute/main armhf linux-libc-dev armhf 6.17.0-6.6 [1772 kB] 161s Get:15 http://ftpmaster.internal/ubuntu resolute/main armhf libcrypt-dev armhf 1:4.5.1-1 [128 kB] 161s Get:16 http://ftpmaster.internal/ubuntu resolute/main armhf rpcsvc-proto armhf 1.4.3-1 [62.3 kB] 161s Get:17 http://ftpmaster.internal/ubuntu resolute/main armhf libc6-dev armhf 2.42-2ubuntu2 [1416 kB] 162s Get:18 http://ftpmaster.internal/ubuntu resolute/main armhf libisl23 armhf 0.27-1 [546 kB] 162s Get:19 http://ftpmaster.internal/ubuntu resolute/main armhf libmpc3 armhf 1.3.1-2 [47.0 kB] 162s Get:20 http://ftpmaster.internal/ubuntu resolute/main armhf cpp-15-arm-linux-gnueabihf armhf 15.2.0-9ubuntu1 [10.1 MB] 162s Get:21 http://ftpmaster.internal/ubuntu resolute/main armhf cpp-15 armhf 15.2.0-9ubuntu1 [1032 B] 162s Get:22 http://ftpmaster.internal/ubuntu resolute/main armhf cpp-arm-linux-gnueabihf armhf 4:15.2.0-4ubuntu1 [5756 B] 162s Get:23 http://ftpmaster.internal/ubuntu resolute/main armhf cpp armhf 4:15.2.0-4ubuntu1 [22.4 kB] 162s Get:24 http://ftpmaster.internal/ubuntu resolute/main armhf libcc1-0 armhf 15.2.0-9ubuntu1 [43.5 kB] 162s Get:25 http://ftpmaster.internal/ubuntu resolute/main armhf libgomp1 armhf 15.2.0-9ubuntu1 [129 kB] 162s Get:26 http://ftpmaster.internal/ubuntu resolute/main armhf libasan8 armhf 15.2.0-9ubuntu1 [2951 kB] 162s Get:27 http://ftpmaster.internal/ubuntu resolute/main armhf libubsan1 armhf 15.2.0-9ubuntu1 [1187 kB] 163s Get:28 http://ftpmaster.internal/ubuntu resolute/main armhf libgcc-15-dev armhf 15.2.0-9ubuntu1 [897 kB] 163s Get:29 http://ftpmaster.internal/ubuntu resolute/main armhf gcc-15-arm-linux-gnueabihf armhf 15.2.0-9ubuntu1 [19.5 MB] 164s Get:30 http://ftpmaster.internal/ubuntu resolute/main armhf gcc-15 armhf 15.2.0-9ubuntu1 [496 kB] 164s Get:31 http://ftpmaster.internal/ubuntu resolute/main armhf gcc-arm-linux-gnueabihf armhf 4:15.2.0-4ubuntu1 [1220 B] 164s Get:32 http://ftpmaster.internal/ubuntu resolute/main armhf gcc armhf 4:15.2.0-4ubuntu1 [5022 B] 164s Get:33 http://ftpmaster.internal/ubuntu resolute/main armhf libstdc++-15-dev armhf 15.2.0-9ubuntu1 [2637 kB] 164s Get:34 http://ftpmaster.internal/ubuntu resolute/main armhf g++-15-arm-linux-gnueabihf armhf 15.2.0-9ubuntu1 [11.4 MB] 164s Get:35 http://ftpmaster.internal/ubuntu resolute/main armhf g++-15 armhf 15.2.0-9ubuntu1 [24.3 kB] 164s Get:36 http://ftpmaster.internal/ubuntu resolute/main armhf g++-arm-linux-gnueabihf armhf 4:15.2.0-4ubuntu1 [968 B] 164s Get:37 http://ftpmaster.internal/ubuntu resolute/main armhf g++ armhf 4:15.2.0-4ubuntu1 [1086 B] 164s Get:38 http://ftpmaster.internal/ubuntu resolute/main armhf build-essential armhf 12.12ubuntu2 [5256 B] 164s Get:39 http://ftpmaster.internal/ubuntu resolute/main armhf libdebhelper-perl all 13.24.2ubuntu1 [95.7 kB] 164s Get:40 http://ftpmaster.internal/ubuntu resolute/main armhf libtool all 2.5.4-7 [169 kB] 164s Get:41 http://ftpmaster.internal/ubuntu resolute/main armhf dh-autoreconf all 21 [12.5 kB] 164s Get:42 http://ftpmaster.internal/ubuntu resolute/main armhf libarchive-zip-perl all 1.68-1 [90.2 kB] 164s Get:43 http://ftpmaster.internal/ubuntu resolute/main armhf libfile-stripnondeterminism-perl all 1.15.0-1 [20.5 kB] 164s Get:44 http://ftpmaster.internal/ubuntu resolute/main armhf dh-strip-nondeterminism all 1.15.0-1 [5090 B] 164s Get:45 http://ftpmaster.internal/ubuntu resolute/main armhf debugedit armhf 1:5.2-3 [48.9 kB] 164s Get:46 http://ftpmaster.internal/ubuntu resolute/main armhf dwz armhf 0.16-2 [114 kB] 164s Get:47 http://ftpmaster.internal/ubuntu resolute/main armhf gettext armhf 0.23.2-1 [1059 kB] 164s Get:48 http://ftpmaster.internal/ubuntu resolute/main armhf intltool-debian all 0.35.0+20060710.6build1 [24.1 kB] 164s Get:49 http://ftpmaster.internal/ubuntu resolute/main armhf po-debconf all 1.0.21+nmu1 [233 kB] 164s Get:50 http://ftpmaster.internal/ubuntu resolute/main armhf debhelper all 13.24.2ubuntu1 [896 kB] 164s Get:51 http://ftpmaster.internal/ubuntu resolute/universe armhf dh-python all 6.20251029 [120 kB] 164s Get:52 http://ftpmaster.internal/ubuntu resolute/main armhf sgml-base all 1.31+nmu1 [11.0 kB] 164s Get:53 http://ftpmaster.internal/ubuntu resolute/main armhf xml-core all 0.19 [20.3 kB] 164s Get:54 http://ftpmaster.internal/ubuntu resolute/main armhf docutils-common all 0.21.2+dfsg-2 [131 kB] 164s Get:55 http://ftpmaster.internal/ubuntu resolute/main armhf fonts-font-awesome all 5.0.10+really4.7.0~dfsg-4.1build1 [531 kB] 165s Get:56 http://ftpmaster.internal/ubuntu resolute/universe armhf python3-accessible-pygments all 0.0.5-2 [790 kB] 165s Get:57 http://ftpmaster.internal/ubuntu resolute/main armhf python3-soupsieve all 2.7-2 [33.6 kB] 165s Get:58 http://ftpmaster.internal/ubuntu resolute/main armhf python3-bs4 all 4.14.2-1 [82.8 kB] 165s Get:59 http://ftpmaster.internal/ubuntu resolute/main armhf python3-defusedxml all 0.7.1-3 [42.2 kB] 165s Get:60 http://ftpmaster.internal/ubuntu resolute/main armhf libjs-jquery all 3.7.1+dfsg+~3.5.33-1build1 [321 kB] 165s Get:61 http://ftpmaster.internal/ubuntu resolute/main armhf libjs-underscore all 1.13.4~dfsg+~1.11.4-3 [118 kB] 165s Get:62 http://ftpmaster.internal/ubuntu resolute/main armhf libjs-sphinxdoc all 8.2.3-1ubuntu2 [28.0 kB] 165s Get:63 http://ftpmaster.internal/ubuntu resolute/main armhf libjson-perl all 4.10000-1 [81.9 kB] 165s Get:64 http://ftpmaster.internal/ubuntu resolute/main armhf sphinx-common all 8.2.3-1ubuntu2 [656 kB] 165s Get:65 http://ftpmaster.internal/ubuntu resolute/main armhf python3-alabaster all 0.7.16-0.1 [18.5 kB] 165s Get:66 http://ftpmaster.internal/ubuntu resolute/main armhf python3-roman all 5.1-1 [10.6 kB] 165s Get:67 http://ftpmaster.internal/ubuntu resolute/main armhf python3-docutils all 0.21.2+dfsg-2 [409 kB] 165s Get:68 http://ftpmaster.internal/ubuntu resolute/main armhf python3-imagesize all 1.4.1-1build1 [6900 B] 165s Get:69 http://ftpmaster.internal/ubuntu resolute/main armhf python3-snowballstemmer all 3.0.1-1 [65.9 kB] 165s Get:70 http://ftpmaster.internal/ubuntu resolute/main armhf python3-sphinx all 8.2.3-1ubuntu2 [482 kB] 165s Get:71 http://ftpmaster.internal/ubuntu resolute/universe armhf sphinx-basic-ng all 1.0.0~beta2-1 [12.0 kB] 165s Get:72 http://ftpmaster.internal/ubuntu resolute/universe armhf furo all 2025.09.25+dfsg-2 [53.7 kB] 165s Get:73 http://ftpmaster.internal/ubuntu resolute/main armhf ieee-data all 20240722 [2300 kB] 165s Get:74 http://ftpmaster.internal/ubuntu resolute/universe armhf libjs-jquery-metadata all 12-4 [6582 B] 165s Get:75 http://ftpmaster.internal/ubuntu resolute/universe armhf libjs-jquery-tablesorter all 1:2.31.3+dfsg1-4 [192 kB] 165s Get:76 http://ftpmaster.internal/ubuntu resolute/universe armhf libjs-jquery-throttle-debounce all 1.1+dfsg.1-2 [12.5 kB] 165s Get:77 http://ftpmaster.internal/ubuntu resolute/main armhf libpython3.14-stdlib armhf 3.14.0-4 [2270 kB] 165s Get:78 http://ftpmaster.internal/ubuntu resolute/universe armhf pybuild-plugin-autopkgtest all 6.20251029 [1746 B] 165s Get:79 http://ftpmaster.internal/ubuntu resolute/universe armhf python3-pyproject-hooks all 1.2.0-1 [10.2 kB] 165s Get:80 http://ftpmaster.internal/ubuntu resolute/universe armhf python3-wheel all 0.46.1-2 [22.1 kB] 165s Get:81 http://ftpmaster.internal/ubuntu resolute/universe armhf python3-build all 1.2.2-4 [31.0 kB] 165s Get:82 http://ftpmaster.internal/ubuntu resolute/universe armhf python3-installer all 0.7.0+dfsg1-3 [17.4 kB] 165s Get:83 http://ftpmaster.internal/ubuntu resolute/universe armhf pybuild-plugin-pyproject all 6.20251029 [1732 B] 165s Get:84 http://ftpmaster.internal/ubuntu resolute/universe armhf python3-aiooui all 0.1.9-1 [312 kB] 165s Get:85 http://ftpmaster.internal/ubuntu resolute/main armhf python3.14 armhf 3.14.0-4 [805 kB] 165s Get:86 http://ftpmaster.internal/ubuntu resolute-proposed/main armhf python3-all armhf 3.13.9-2 [892 B] 165s Get:87 http://ftpmaster.internal/ubuntu resolute/universe armhf python3-async-generator all 1.10-7 [18.1 kB] 165s Get:88 http://ftpmaster.internal/ubuntu resolute/universe armhf python3-async-interrupt all 1.2.2-1 [6604 B] 165s Get:89 http://ftpmaster.internal/ubuntu resolute/universe armhf python3-dbus-fast armhf 3.1.2-1 [960 kB] 165s Get:90 http://ftpmaster.internal/ubuntu resolute/universe armhf python3-bleak all 1.1.1-1 [95.0 kB] 165s Get:91 http://ftpmaster.internal/ubuntu resolute/universe armhf python3-uart-devices all 0.1.1-1 [6742 B] 165s Get:92 http://ftpmaster.internal/ubuntu resolute/universe armhf python3-usb-devices all 0.4.5-2 [9014 B] 165s Get:93 http://ftpmaster.internal/ubuntu resolute/universe armhf python3-bluetooth-adapters all 2.1.1-1 [13.7 kB] 165s Get:94 http://ftpmaster.internal/ubuntu resolute/universe armhf python3-bleak-retry-connector all 4.4.4-1 [20.1 kB] 165s Get:95 http://ftpmaster.internal/ubuntu resolute/universe armhf python3-btsocket all 0.3.0-1 [19.0 kB] 165s Get:96 http://ftpmaster.internal/ubuntu resolute/universe armhf python3-pyric all 0.1.6.4+git20170421.0562b17-1 [114 kB] 165s Get:97 http://ftpmaster.internal/ubuntu resolute/main armhf sphinx-rtd-theme-common all 3.0.2+dfsg-3 [1013 kB] 165s Get:98 http://ftpmaster.internal/ubuntu resolute/universe armhf python3-bluetooth-auto-recovery all 1.5.3-1 [29.4 kB] 165s Get:99 http://ftpmaster.internal/ubuntu resolute/universe armhf python3-bluetooth-data-tools armhf 1.28.4-1 [11.7 kB] 165s Get:100 http://ftpmaster.internal/ubuntu resolute/universe armhf python3-coverage armhf 7.8.2+dfsg1-1 [156 kB] 165s Get:101 http://ftpmaster.internal/ubuntu resolute/universe armhf python3-freezegun all 1.5.1-1.2 [15.9 kB] 165s Get:102 http://ftpmaster.internal/ubuntu resolute/universe armhf python3-habluetooth armhf 4.0.1-1 [65.3 kB] 166s Get:103 http://ftpmaster.internal/ubuntu resolute/universe armhf python3-iniconfig all 2.1.0-1 [6840 B] 166s Get:104 http://ftpmaster.internal/ubuntu resolute/universe armhf python3-mdit-py-plugins all 0.5.0-1 [31.5 kB] 166s Get:105 http://ftpmaster.internal/ubuntu resolute/universe armhf python3-myst-parser all 4.0.1-1 [64.9 kB] 166s Get:106 http://ftpmaster.internal/ubuntu resolute/universe armhf python3-pluggy all 1.6.0-1 [21.0 kB] 166s Get:107 http://ftpmaster.internal/ubuntu resolute/universe armhf python3-poetry-core all 2.2.1-2 [226 kB] 166s Get:108 http://ftpmaster.internal/ubuntu resolute/universe armhf python3-pytest all 8.3.5-2 [252 kB] 166s Get:109 http://ftpmaster.internal/ubuntu resolute/universe armhf python3-pytest-asyncio all 0.25.1-1 [17.0 kB] 166s Get:110 http://ftpmaster.internal/ubuntu resolute/universe armhf libjs-jquery-hotkeys all 0.2.0-1 [13.3 kB] 166s Get:111 http://ftpmaster.internal/ubuntu resolute/universe armhf libjs-jquery-isonscreen all 1.2.0-2 [3814 B] 166s Get:112 http://ftpmaster.internal/ubuntu resolute/universe armhf python3-pytest-cov all 5.0.0-1 [21.3 kB] 167s Fetched 79.1 MB in 9s (9192 kB/s) 167s Selecting previously unselected package fonts-lato. 167s (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 ... 65928 files and directories currently installed.) 167s Preparing to unpack .../000-fonts-lato_2.015-1build1_all.deb ... 167s Unpacking fonts-lato (2.015-1build1) ... 167s Selecting previously unselected package libpython3.14-minimal:armhf. 167s Preparing to unpack .../001-libpython3.14-minimal_3.14.0-4_armhf.deb ... 167s Unpacking libpython3.14-minimal:armhf (3.14.0-4) ... 167s Selecting previously unselected package python3.14-minimal. 167s Preparing to unpack .../002-python3.14-minimal_3.14.0-4_armhf.deb ... 167s Unpacking python3.14-minimal (3.14.0-4) ... 167s Selecting previously unselected package m4. 167s Preparing to unpack .../003-m4_1.4.20-2_armhf.deb ... 167s Unpacking m4 (1.4.20-2) ... 167s Selecting previously unselected package autoconf. 167s Preparing to unpack .../004-autoconf_2.72-3.1ubuntu1_all.deb ... 167s Unpacking autoconf (2.72-3.1ubuntu1) ... 167s Selecting previously unselected package autotools-dev. 167s Preparing to unpack .../005-autotools-dev_20240727.1_all.deb ... 167s Unpacking autotools-dev (20240727.1) ... 167s Selecting previously unselected package automake. 167s Preparing to unpack .../006-automake_1%3a1.18.1-3_all.deb ... 167s Unpacking automake (1:1.18.1-3) ... 168s Selecting previously unselected package autopoint. 168s Preparing to unpack .../007-autopoint_0.23.2-1_all.deb ... 168s Unpacking autopoint (0.23.2-1) ... 168s Selecting previously unselected package libasound2-data. 168s Preparing to unpack .../008-libasound2-data_1.2.14-2ubuntu1_all.deb ... 168s Unpacking libasound2-data (1.2.14-2ubuntu1) ... 168s Selecting previously unselected package libasound2t64:armhf. 168s Preparing to unpack .../009-libasound2t64_1.2.14-2ubuntu1_armhf.deb ... 168s Unpacking libasound2t64:armhf (1.2.14-2ubuntu1) ... 168s Selecting previously unselected package libdw1t64:armhf. 168s Preparing to unpack .../010-libdw1t64_0.194-1_armhf.deb ... 168s Unpacking libdw1t64:armhf (0.194-1) ... 168s Selecting previously unselected package bluez. 168s Preparing to unpack .../011-bluez_5.84-1_armhf.deb ... 168s Unpacking bluez (5.84-1) ... 168s Selecting previously unselected package libc-dev-bin. 168s Preparing to unpack .../012-libc-dev-bin_2.42-2ubuntu2_armhf.deb ... 168s Unpacking libc-dev-bin (2.42-2ubuntu2) ... 168s Selecting previously unselected package linux-libc-dev:armhf. 168s Preparing to unpack .../013-linux-libc-dev_6.17.0-6.6_armhf.deb ... 168s Unpacking linux-libc-dev:armhf (6.17.0-6.6) ... 168s Selecting previously unselected package libcrypt-dev:armhf. 168s Preparing to unpack .../014-libcrypt-dev_1%3a4.5.1-1_armhf.deb ... 168s Unpacking libcrypt-dev:armhf (1:4.5.1-1) ... 168s Selecting previously unselected package rpcsvc-proto. 168s Preparing to unpack .../015-rpcsvc-proto_1.4.3-1_armhf.deb ... 168s Unpacking rpcsvc-proto (1.4.3-1) ... 168s Selecting previously unselected package libc6-dev:armhf. 168s Preparing to unpack .../016-libc6-dev_2.42-2ubuntu2_armhf.deb ... 168s Unpacking libc6-dev:armhf (2.42-2ubuntu2) ... 168s Selecting previously unselected package libisl23:armhf. 168s Preparing to unpack .../017-libisl23_0.27-1_armhf.deb ... 168s Unpacking libisl23:armhf (0.27-1) ... 168s Selecting previously unselected package libmpc3:armhf. 168s Preparing to unpack .../018-libmpc3_1.3.1-2_armhf.deb ... 168s Unpacking libmpc3:armhf (1.3.1-2) ... 168s Selecting previously unselected package cpp-15-arm-linux-gnueabihf. 168s Preparing to unpack .../019-cpp-15-arm-linux-gnueabihf_15.2.0-9ubuntu1_armhf.deb ... 168s Unpacking cpp-15-arm-linux-gnueabihf (15.2.0-9ubuntu1) ... 169s Selecting previously unselected package cpp-15. 169s Preparing to unpack .../020-cpp-15_15.2.0-9ubuntu1_armhf.deb ... 169s Unpacking cpp-15 (15.2.0-9ubuntu1) ... 169s Selecting previously unselected package cpp-arm-linux-gnueabihf. 169s Preparing to unpack .../021-cpp-arm-linux-gnueabihf_4%3a15.2.0-4ubuntu1_armhf.deb ... 169s Unpacking cpp-arm-linux-gnueabihf (4:15.2.0-4ubuntu1) ... 169s Selecting previously unselected package cpp. 169s Preparing to unpack .../022-cpp_4%3a15.2.0-4ubuntu1_armhf.deb ... 169s Unpacking cpp (4:15.2.0-4ubuntu1) ... 169s Selecting previously unselected package libcc1-0:armhf. 169s Preparing to unpack .../023-libcc1-0_15.2.0-9ubuntu1_armhf.deb ... 169s Unpacking libcc1-0:armhf (15.2.0-9ubuntu1) ... 169s Selecting previously unselected package libgomp1:armhf. 169s Preparing to unpack .../024-libgomp1_15.2.0-9ubuntu1_armhf.deb ... 169s Unpacking libgomp1:armhf (15.2.0-9ubuntu1) ... 169s Selecting previously unselected package libasan8:armhf. 169s Preparing to unpack .../025-libasan8_15.2.0-9ubuntu1_armhf.deb ... 169s Unpacking libasan8:armhf (15.2.0-9ubuntu1) ... 169s Selecting previously unselected package libubsan1:armhf. 169s Preparing to unpack .../026-libubsan1_15.2.0-9ubuntu1_armhf.deb ... 169s Unpacking libubsan1:armhf (15.2.0-9ubuntu1) ... 169s Selecting previously unselected package libgcc-15-dev:armhf. 169s Preparing to unpack .../027-libgcc-15-dev_15.2.0-9ubuntu1_armhf.deb ... 169s Unpacking libgcc-15-dev:armhf (15.2.0-9ubuntu1) ... 169s Selecting previously unselected package gcc-15-arm-linux-gnueabihf. 169s Preparing to unpack .../028-gcc-15-arm-linux-gnueabihf_15.2.0-9ubuntu1_armhf.deb ... 169s Unpacking gcc-15-arm-linux-gnueabihf (15.2.0-9ubuntu1) ... 169s Selecting previously unselected package gcc-15. 169s Preparing to unpack .../029-gcc-15_15.2.0-9ubuntu1_armhf.deb ... 169s Unpacking gcc-15 (15.2.0-9ubuntu1) ... 169s Selecting previously unselected package gcc-arm-linux-gnueabihf. 170s Preparing to unpack .../030-gcc-arm-linux-gnueabihf_4%3a15.2.0-4ubuntu1_armhf.deb ... 170s Unpacking gcc-arm-linux-gnueabihf (4:15.2.0-4ubuntu1) ... 170s Selecting previously unselected package gcc. 170s Preparing to unpack .../031-gcc_4%3a15.2.0-4ubuntu1_armhf.deb ... 170s Unpacking gcc (4:15.2.0-4ubuntu1) ... 170s Selecting previously unselected package libstdc++-15-dev:armhf. 170s Preparing to unpack .../032-libstdc++-15-dev_15.2.0-9ubuntu1_armhf.deb ... 170s Unpacking libstdc++-15-dev:armhf (15.2.0-9ubuntu1) ... 170s Selecting previously unselected package g++-15-arm-linux-gnueabihf. 170s Preparing to unpack .../033-g++-15-arm-linux-gnueabihf_15.2.0-9ubuntu1_armhf.deb ... 170s Unpacking g++-15-arm-linux-gnueabihf (15.2.0-9ubuntu1) ... 170s Selecting previously unselected package g++-15. 170s Preparing to unpack .../034-g++-15_15.2.0-9ubuntu1_armhf.deb ... 170s Unpacking g++-15 (15.2.0-9ubuntu1) ... 170s Selecting previously unselected package g++-arm-linux-gnueabihf. 170s Preparing to unpack .../035-g++-arm-linux-gnueabihf_4%3a15.2.0-4ubuntu1_armhf.deb ... 170s Unpacking g++-arm-linux-gnueabihf (4:15.2.0-4ubuntu1) ... 170s Selecting previously unselected package g++. 170s Preparing to unpack .../036-g++_4%3a15.2.0-4ubuntu1_armhf.deb ... 170s Unpacking g++ (4:15.2.0-4ubuntu1) ... 170s Selecting previously unselected package build-essential. 170s Preparing to unpack .../037-build-essential_12.12ubuntu2_armhf.deb ... 170s Unpacking build-essential (12.12ubuntu2) ... 170s Selecting previously unselected package libdebhelper-perl. 170s Preparing to unpack .../038-libdebhelper-perl_13.24.2ubuntu1_all.deb ... 170s Unpacking libdebhelper-perl (13.24.2ubuntu1) ... 170s Selecting previously unselected package libtool. 170s Preparing to unpack .../039-libtool_2.5.4-7_all.deb ... 170s Unpacking libtool (2.5.4-7) ... 170s Selecting previously unselected package dh-autoreconf. 170s Preparing to unpack .../040-dh-autoreconf_21_all.deb ... 170s Unpacking dh-autoreconf (21) ... 170s Selecting previously unselected package libarchive-zip-perl. 170s Preparing to unpack .../041-libarchive-zip-perl_1.68-1_all.deb ... 170s Unpacking libarchive-zip-perl (1.68-1) ... 170s Selecting previously unselected package libfile-stripnondeterminism-perl. 170s Preparing to unpack .../042-libfile-stripnondeterminism-perl_1.15.0-1_all.deb ... 170s Unpacking libfile-stripnondeterminism-perl (1.15.0-1) ... 170s Selecting previously unselected package dh-strip-nondeterminism. 170s Preparing to unpack .../043-dh-strip-nondeterminism_1.15.0-1_all.deb ... 170s Unpacking dh-strip-nondeterminism (1.15.0-1) ... 170s Selecting previously unselected package debugedit. 170s Preparing to unpack .../044-debugedit_1%3a5.2-3_armhf.deb ... 170s Unpacking debugedit (1:5.2-3) ... 170s Selecting previously unselected package dwz. 170s Preparing to unpack .../045-dwz_0.16-2_armhf.deb ... 170s Unpacking dwz (0.16-2) ... 170s Selecting previously unselected package gettext. 170s Preparing to unpack .../046-gettext_0.23.2-1_armhf.deb ... 170s Unpacking gettext (0.23.2-1) ... 171s Selecting previously unselected package intltool-debian. 171s Preparing to unpack .../047-intltool-debian_0.35.0+20060710.6build1_all.deb ... 171s Unpacking intltool-debian (0.35.0+20060710.6build1) ... 171s Selecting previously unselected package po-debconf. 171s Preparing to unpack .../048-po-debconf_1.0.21+nmu1_all.deb ... 171s Unpacking po-debconf (1.0.21+nmu1) ... 171s Selecting previously unselected package debhelper. 171s Preparing to unpack .../049-debhelper_13.24.2ubuntu1_all.deb ... 171s Unpacking debhelper (13.24.2ubuntu1) ... 171s Selecting previously unselected package dh-python. 171s Preparing to unpack .../050-dh-python_6.20251029_all.deb ... 171s Unpacking dh-python (6.20251029) ... 171s Selecting previously unselected package sgml-base. 171s Preparing to unpack .../051-sgml-base_1.31+nmu1_all.deb ... 171s Unpacking sgml-base (1.31+nmu1) ... 171s Selecting previously unselected package xml-core. 171s Preparing to unpack .../052-xml-core_0.19_all.deb ... 171s Unpacking xml-core (0.19) ... 171s Selecting previously unselected package docutils-common. 171s Preparing to unpack .../053-docutils-common_0.21.2+dfsg-2_all.deb ... 171s Unpacking docutils-common (0.21.2+dfsg-2) ... 171s Selecting previously unselected package fonts-font-awesome. 171s Preparing to unpack .../054-fonts-font-awesome_5.0.10+really4.7.0~dfsg-4.1build1_all.deb ... 171s Unpacking fonts-font-awesome (5.0.10+really4.7.0~dfsg-4.1build1) ... 171s Selecting previously unselected package python3-accessible-pygments. 171s Preparing to unpack .../055-python3-accessible-pygments_0.0.5-2_all.deb ... 171s Unpacking python3-accessible-pygments (0.0.5-2) ... 171s Selecting previously unselected package python3-soupsieve. 171s Preparing to unpack .../056-python3-soupsieve_2.7-2_all.deb ... 171s Unpacking python3-soupsieve (2.7-2) ... 171s Selecting previously unselected package python3-bs4. 171s Preparing to unpack .../057-python3-bs4_4.14.2-1_all.deb ... 171s Unpacking python3-bs4 (4.14.2-1) ... 171s Selecting previously unselected package python3-defusedxml. 171s Preparing to unpack .../058-python3-defusedxml_0.7.1-3_all.deb ... 171s Unpacking python3-defusedxml (0.7.1-3) ... 171s Selecting previously unselected package libjs-jquery. 171s Preparing to unpack .../059-libjs-jquery_3.7.1+dfsg+~3.5.33-1build1_all.deb ... 171s Unpacking libjs-jquery (3.7.1+dfsg+~3.5.33-1build1) ... 171s Selecting previously unselected package libjs-underscore. 171s Preparing to unpack .../060-libjs-underscore_1.13.4~dfsg+~1.11.4-3_all.deb ... 171s Unpacking libjs-underscore (1.13.4~dfsg+~1.11.4-3) ... 171s Selecting previously unselected package libjs-sphinxdoc. 171s Preparing to unpack .../061-libjs-sphinxdoc_8.2.3-1ubuntu2_all.deb ... 171s Unpacking libjs-sphinxdoc (8.2.3-1ubuntu2) ... 171s Selecting previously unselected package libjson-perl. 171s Preparing to unpack .../062-libjson-perl_4.10000-1_all.deb ... 171s Unpacking libjson-perl (4.10000-1) ... 171s Selecting previously unselected package sphinx-common. 171s Preparing to unpack .../063-sphinx-common_8.2.3-1ubuntu2_all.deb ... 171s Unpacking sphinx-common (8.2.3-1ubuntu2) ... 171s Selecting previously unselected package python3-alabaster. 171s Preparing to unpack .../064-python3-alabaster_0.7.16-0.1_all.deb ... 171s Unpacking python3-alabaster (0.7.16-0.1) ... 171s Selecting previously unselected package python3-roman. 171s Preparing to unpack .../065-python3-roman_5.1-1_all.deb ... 171s Unpacking python3-roman (5.1-1) ... 171s Selecting previously unselected package python3-docutils. 171s Preparing to unpack .../066-python3-docutils_0.21.2+dfsg-2_all.deb ... 171s Unpacking python3-docutils (0.21.2+dfsg-2) ... 171s Selecting previously unselected package python3-imagesize. 172s Preparing to unpack .../067-python3-imagesize_1.4.1-1build1_all.deb ... 172s Unpacking python3-imagesize (1.4.1-1build1) ... 172s Selecting previously unselected package python3-snowballstemmer. 172s Preparing to unpack .../068-python3-snowballstemmer_3.0.1-1_all.deb ... 172s Unpacking python3-snowballstemmer (3.0.1-1) ... 172s Selecting previously unselected package python3-sphinx. 172s Preparing to unpack .../069-python3-sphinx_8.2.3-1ubuntu2_all.deb ... 172s Unpacking python3-sphinx (8.2.3-1ubuntu2) ... 172s Selecting previously unselected package sphinx-basic-ng. 172s Preparing to unpack .../070-sphinx-basic-ng_1.0.0~beta2-1_all.deb ... 172s Unpacking sphinx-basic-ng (1.0.0~beta2-1) ... 172s Selecting previously unselected package furo. 172s Preparing to unpack .../071-furo_2025.09.25+dfsg-2_all.deb ... 172s Unpacking furo (2025.09.25+dfsg-2) ... 172s Selecting previously unselected package ieee-data. 172s Preparing to unpack .../072-ieee-data_20240722_all.deb ... 172s Unpacking ieee-data (20240722) ... 172s Selecting previously unselected package libjs-jquery-metadata. 172s Preparing to unpack .../073-libjs-jquery-metadata_12-4_all.deb ... 172s Unpacking libjs-jquery-metadata (12-4) ... 172s Selecting previously unselected package libjs-jquery-tablesorter. 172s Preparing to unpack .../074-libjs-jquery-tablesorter_1%3a2.31.3+dfsg1-4_all.deb ... 172s Unpacking libjs-jquery-tablesorter (1:2.31.3+dfsg1-4) ... 172s Selecting previously unselected package libjs-jquery-throttle-debounce. 172s Preparing to unpack .../075-libjs-jquery-throttle-debounce_1.1+dfsg.1-2_all.deb ... 172s Unpacking libjs-jquery-throttle-debounce (1.1+dfsg.1-2) ... 172s Selecting previously unselected package libpython3.14-stdlib:armhf. 172s Preparing to unpack .../076-libpython3.14-stdlib_3.14.0-4_armhf.deb ... 172s Unpacking libpython3.14-stdlib:armhf (3.14.0-4) ... 172s Selecting previously unselected package pybuild-plugin-autopkgtest. 172s Preparing to unpack .../077-pybuild-plugin-autopkgtest_6.20251029_all.deb ... 172s Unpacking pybuild-plugin-autopkgtest (6.20251029) ... 172s Selecting previously unselected package python3-pyproject-hooks. 172s Preparing to unpack .../078-python3-pyproject-hooks_1.2.0-1_all.deb ... 172s Unpacking python3-pyproject-hooks (1.2.0-1) ... 172s Selecting previously unselected package python3-wheel. 172s Preparing to unpack .../079-python3-wheel_0.46.1-2_all.deb ... 172s Unpacking python3-wheel (0.46.1-2) ... 172s Selecting previously unselected package python3-build. 172s Preparing to unpack .../080-python3-build_1.2.2-4_all.deb ... 172s Unpacking python3-build (1.2.2-4) ... 172s Selecting previously unselected package python3-installer. 172s Preparing to unpack .../081-python3-installer_0.7.0+dfsg1-3_all.deb ... 172s Unpacking python3-installer (0.7.0+dfsg1-3) ... 172s Selecting previously unselected package pybuild-plugin-pyproject. 172s Preparing to unpack .../082-pybuild-plugin-pyproject_6.20251029_all.deb ... 172s Unpacking pybuild-plugin-pyproject (6.20251029) ... 172s Selecting previously unselected package python3-aiooui. 172s Preparing to unpack .../083-python3-aiooui_0.1.9-1_all.deb ... 172s Unpacking python3-aiooui (0.1.9-1) ... 172s Selecting previously unselected package python3.14. 173s Preparing to unpack .../084-python3.14_3.14.0-4_armhf.deb ... 173s Unpacking python3.14 (3.14.0-4) ... 173s Selecting previously unselected package python3-all. 173s Preparing to unpack .../085-python3-all_3.13.9-2_armhf.deb ... 173s Unpacking python3-all (3.13.9-2) ... 173s Selecting previously unselected package python3-async-generator. 173s Preparing to unpack .../086-python3-async-generator_1.10-7_all.deb ... 173s Unpacking python3-async-generator (1.10-7) ... 173s Selecting previously unselected package python3-async-interrupt. 173s Preparing to unpack .../087-python3-async-interrupt_1.2.2-1_all.deb ... 173s Unpacking python3-async-interrupt (1.2.2-1) ... 173s Selecting previously unselected package python3-dbus-fast. 173s Preparing to unpack .../088-python3-dbus-fast_3.1.2-1_armhf.deb ... 173s Unpacking python3-dbus-fast (3.1.2-1) ... 173s Selecting previously unselected package python3-bleak. 173s Preparing to unpack .../089-python3-bleak_1.1.1-1_all.deb ... 173s Unpacking python3-bleak (1.1.1-1) ... 173s Selecting previously unselected package python3-uart-devices. 173s Preparing to unpack .../090-python3-uart-devices_0.1.1-1_all.deb ... 173s Unpacking python3-uart-devices (0.1.1-1) ... 173s Selecting previously unselected package python3-usb-devices. 173s Preparing to unpack .../091-python3-usb-devices_0.4.5-2_all.deb ... 173s Unpacking python3-usb-devices (0.4.5-2) ... 173s Selecting previously unselected package python3-bluetooth-adapters. 173s Preparing to unpack .../092-python3-bluetooth-adapters_2.1.1-1_all.deb ... 173s Unpacking python3-bluetooth-adapters (2.1.1-1) ... 173s Selecting previously unselected package python3-bleak-retry-connector. 173s Preparing to unpack .../093-python3-bleak-retry-connector_4.4.4-1_all.deb ... 173s Unpacking python3-bleak-retry-connector (4.4.4-1) ... 173s Selecting previously unselected package python3-btsocket. 173s Preparing to unpack .../094-python3-btsocket_0.3.0-1_all.deb ... 173s Unpacking python3-btsocket (0.3.0-1) ... 173s Selecting previously unselected package python3-pyric. 173s Preparing to unpack .../095-python3-pyric_0.1.6.4+git20170421.0562b17-1_all.deb ... 173s Unpacking python3-pyric (0.1.6.4+git20170421.0562b17-1) ... 173s Selecting previously unselected package sphinx-rtd-theme-common. 173s Preparing to unpack .../096-sphinx-rtd-theme-common_3.0.2+dfsg-3_all.deb ... 173s Unpacking sphinx-rtd-theme-common (3.0.2+dfsg-3) ... 173s Selecting previously unselected package python3-bluetooth-auto-recovery. 173s Preparing to unpack .../097-python3-bluetooth-auto-recovery_1.5.3-1_all.deb ... 173s Unpacking python3-bluetooth-auto-recovery (1.5.3-1) ... 173s Selecting previously unselected package python3-bluetooth-data-tools. 173s Preparing to unpack .../098-python3-bluetooth-data-tools_1.28.4-1_armhf.deb ... 173s Unpacking python3-bluetooth-data-tools (1.28.4-1) ... 173s Selecting previously unselected package python3-coverage. 173s Preparing to unpack .../099-python3-coverage_7.8.2+dfsg1-1_armhf.deb ... 173s Unpacking python3-coverage (7.8.2+dfsg1-1) ... 173s Selecting previously unselected package python3-freezegun. 173s Preparing to unpack .../100-python3-freezegun_1.5.1-1.2_all.deb ... 173s Unpacking python3-freezegun (1.5.1-1.2) ... 173s Selecting previously unselected package python3-habluetooth. 173s Preparing to unpack .../101-python3-habluetooth_4.0.1-1_armhf.deb ... 173s Unpacking python3-habluetooth (4.0.1-1) ... 173s Selecting previously unselected package python3-iniconfig. 173s Preparing to unpack .../102-python3-iniconfig_2.1.0-1_all.deb ... 173s Unpacking python3-iniconfig (2.1.0-1) ... 173s Selecting previously unselected package python3-mdit-py-plugins. 173s Preparing to unpack .../103-python3-mdit-py-plugins_0.5.0-1_all.deb ... 173s Unpacking python3-mdit-py-plugins (0.5.0-1) ... 173s Selecting previously unselected package python3-myst-parser. 173s Preparing to unpack .../104-python3-myst-parser_4.0.1-1_all.deb ... 173s Unpacking python3-myst-parser (4.0.1-1) ... 173s Selecting previously unselected package python3-pluggy. 173s Preparing to unpack .../105-python3-pluggy_1.6.0-1_all.deb ... 173s Unpacking python3-pluggy (1.6.0-1) ... 173s Selecting previously unselected package python3-poetry-core. 173s Preparing to unpack .../106-python3-poetry-core_2.2.1-2_all.deb ... 173s Unpacking python3-poetry-core (2.2.1-2) ... 174s Selecting previously unselected package python3-pytest. 174s Preparing to unpack .../107-python3-pytest_8.3.5-2_all.deb ... 174s Unpacking python3-pytest (8.3.5-2) ... 174s Selecting previously unselected package python3-pytest-asyncio. 174s Preparing to unpack .../108-python3-pytest-asyncio_0.25.1-1_all.deb ... 174s Unpacking python3-pytest-asyncio (0.25.1-1) ... 174s Selecting previously unselected package libjs-jquery-hotkeys. 174s Preparing to unpack .../109-libjs-jquery-hotkeys_0.2.0-1_all.deb ... 174s Unpacking libjs-jquery-hotkeys (0.2.0-1) ... 174s Selecting previously unselected package libjs-jquery-isonscreen. 174s Preparing to unpack .../110-libjs-jquery-isonscreen_1.2.0-2_all.deb ... 174s Unpacking libjs-jquery-isonscreen (1.2.0-2) ... 174s Selecting previously unselected package python3-pytest-cov. 174s Preparing to unpack .../111-python3-pytest-cov_5.0.0-1_all.deb ... 174s Unpacking python3-pytest-cov (5.0.0-1) ... 174s Setting up dh-python (6.20251029) ... 174s Setting up python3-iniconfig (2.1.0-1) ... 174s Setting up python3-aiooui (0.1.9-1) ... 175s Setting up python3-uart-devices (0.1.1-1) ... 175s Setting up fonts-lato (2.015-1build1) ... 175s Setting up python3-usb-devices (0.4.5-2) ... 175s Setting up python3-async-generator (1.10-7) ... 175s Setting up python3-bluetooth-data-tools (1.28.4-1) ... 175s Setting up python3-defusedxml (0.7.1-3) ... 176s Setting up libarchive-zip-perl (1.68-1) ... 176s Setting up python3-alabaster (0.7.16-0.1) ... 176s Setting up libdebhelper-perl (13.24.2ubuntu1) ... 176s Setting up libpython3.14-minimal:armhf (3.14.0-4) ... 176s Setting up python3-mdit-py-plugins (0.5.0-1) ... 176s Setting up linux-libc-dev:armhf (6.17.0-6.6) ... 176s Setting up m4 (1.4.20-2) ... 176s Setting up python3-coverage (7.8.2+dfsg1-1) ... 176s Setting up libjs-jquery-throttle-debounce (1.1+dfsg.1-2) ... 176s Setting up libgomp1:armhf (15.2.0-9ubuntu1) ... 176s Setting up python3-wheel (0.46.1-2) ... 177s Setting up python3-btsocket (0.3.0-1) ... 177s Setting up libdw1t64:armhf (0.194-1) ... 177s Setting up libasound2-data (1.2.14-2ubuntu1) ... 177s Setting up python3-roman (5.1-1) ... 177s Setting up autotools-dev (20240727.1) ... 177s Setting up rpcsvc-proto (1.4.3-1) ... 177s Setting up libasound2t64:armhf (1.2.14-2ubuntu1) ... 177s Setting up python3-pyproject-hooks (1.2.0-1) ... 177s Setting up python3-accessible-pygments (0.0.5-2) ... 177s Setting up python3-snowballstemmer (3.0.1-1) ... 178s Setting up python3-poetry-core (2.2.1-2) ... 179s Setting up libmpc3:armhf (1.3.1-2) ... 179s Setting up autopoint (0.23.2-1) ... 179s Setting up python3-installer (0.7.0+dfsg1-3) ... 179s Setting up autoconf (2.72-3.1ubuntu1) ... 179s Setting up python3-pluggy (1.6.0-1) ... 179s Setting up python3-async-interrupt (1.2.2-1) ... 179s Setting up libubsan1:armhf (15.2.0-9ubuntu1) ... 179s Setting up dwz (0.16-2) ... 179s Setting up ieee-data (20240722) ... 179s Setting up libcrypt-dev:armhf (1:4.5.1-1) ... 179s Setting up libasan8:armhf (15.2.0-9ubuntu1) ... 179s Setting up libjson-perl (4.10000-1) ... 179s Setting up debugedit (1:5.2-3) ... 179s Setting up sgml-base (1.31+nmu1) ... 179s Setting up libjs-jquery (3.7.1+dfsg+~3.5.33-1build1) ... 179s Setting up libjs-jquery-hotkeys (0.2.0-1) ... 179s Setting up libisl23:armhf (0.27-1) ... 179s Setting up python3-build (1.2.2-4) ... 180s Setting up libc-dev-bin (2.42-2ubuntu2) ... 180s Setting up cpp-15-arm-linux-gnueabihf (15.2.0-9ubuntu1) ... 180s Setting up python3-soupsieve (2.7-2) ... 180s Setting up fonts-font-awesome (5.0.10+really4.7.0~dfsg-4.1build1) ... 180s Setting up sphinx-rtd-theme-common (3.0.2+dfsg-3) ... 180s Setting up python3.14-minimal (3.14.0-4) ... 181s Setting up libcc1-0:armhf (15.2.0-9ubuntu1) ... 181s Setting up python3-freezegun (1.5.1-1.2) ... 181s Setting up cpp-arm-linux-gnueabihf (4:15.2.0-4ubuntu1) ... 181s Setting up libjs-underscore (1.13.4~dfsg+~1.11.4-3) ... 181s Setting up python3-imagesize (1.4.1-1build1) ... 181s Setting up automake (1:1.18.1-3) ... 181s update-alternatives: using /usr/bin/automake-1.18 to provide /usr/bin/automake (automake) in auto mode 181s Setting up libfile-stripnondeterminism-perl (1.15.0-1) ... 181s Setting up python3-pyric (0.1.6.4+git20170421.0562b17-1) ... 181s Setting up gettext (0.23.2-1) ... 181s Setting up libgcc-15-dev:armhf (15.2.0-9ubuntu1) ... 181s Setting up gcc-15-arm-linux-gnueabihf (15.2.0-9ubuntu1) ... 181s Setting up libpython3.14-stdlib:armhf (3.14.0-4) ... 181s Setting up pybuild-plugin-pyproject (6.20251029) ... 181s Setting up python3-pytest (8.3.5-2) ... 182s Setting up python3-bs4 (4.14.2-1) ... 182s Setting up intltool-debian (0.35.0+20060710.6build1) ... 182s Setting up bluez (5.84-1) ... 183s Created symlink '/etc/systemd/user/default.target.wants/mpris-proxy.service' → '/usr/lib/systemd/user/mpris-proxy.service'. 183s Failed to preset unit: Unit /etc/systemd/system/bluetooth.service is masked 183s /usr/bin/deb-systemd-helper: error: systemctl preset failed on bluetooth.service: No such file or directory 183s bluetooth.service is a disabled or a static unit, not starting it. 183s Setting up libjs-jquery-metadata (12-4) ... 183s Setting up libjs-jquery-isonscreen (1.2.0-2) ... 183s Setting up libjs-sphinxdoc (8.2.3-1ubuntu2) ... 183s Setting up python3-pytest-asyncio (0.25.1-1) ... 184s Setting up dh-strip-nondeterminism (1.15.0-1) ... 184s Setting up libjs-jquery-tablesorter (1:2.31.3+dfsg1-4) ... 184s Setting up cpp-15 (15.2.0-9ubuntu1) ... 184s Setting up cpp (4:15.2.0-4ubuntu1) ... 184s Setting up xml-core (0.19) ... 184s Setting up libc6-dev:armhf (2.42-2ubuntu2) ... 184s Setting up python3.14 (3.14.0-4) ... 185s Setting up gcc-arm-linux-gnueabihf (4:15.2.0-4ubuntu1) ... 185s Setting up python3-dbus-fast (3.1.2-1) ... 185s Setting up po-debconf (1.0.21+nmu1) ... 185s Setting up python3-all (3.13.9-2) ... 185s Setting up python3-pytest-cov (5.0.0-1) ... 186s Setting up gcc-15 (15.2.0-9ubuntu1) ... 186s Setting up libstdc++-15-dev:armhf (15.2.0-9ubuntu1) ... 186s Setting up sphinx-common (8.2.3-1ubuntu2) ... 186s Setting up python3-bleak (1.1.1-1) ... 186s Setting up python3-bluetooth-adapters (2.1.1-1) ... 186s Setting up libtool (2.5.4-7) ... 186s Setting up python3-bleak-retry-connector (4.4.4-1) ... 186s Setting up g++-15-arm-linux-gnueabihf (15.2.0-9ubuntu1) ... 186s Setting up gcc (4:15.2.0-4ubuntu1) ... 186s Setting up dh-autoreconf (21) ... 186s Setting up python3-bluetooth-auto-recovery (1.5.3-1) ... 187s Setting up python3-habluetooth (4.0.1-1) ... 187s Setting up g++-15 (15.2.0-9ubuntu1) ... 187s Setting up g++-arm-linux-gnueabihf (4:15.2.0-4ubuntu1) ... 187s Setting up debhelper (13.24.2ubuntu1) ... 187s Setting up g++ (4:15.2.0-4ubuntu1) ... 187s update-alternatives: using /usr/bin/g++ to provide /usr/bin/c++ (c++) in auto mode 187s Setting up build-essential (12.12ubuntu2) ... 187s Setting up pybuild-plugin-autopkgtest (6.20251029) ... 187s Processing triggers for install-info (7.2-5) ... 187s Processing triggers for libc-bin (2.42-2ubuntu2) ... 187s Processing triggers for systemd (257.9-0ubuntu2) ... 187s Processing triggers for man-db (2.13.1-1) ... 189s Processing triggers for dbus (1.16.2-2ubuntu2) ... 189s Processing triggers for sgml-base (1.31+nmu1) ... 189s Setting up docutils-common (0.21.2+dfsg-2) ... 189s Processing triggers for sgml-base (1.31+nmu1) ... 189s Setting up python3-docutils (0.21.2+dfsg-2) ... 190s Setting up python3-sphinx (8.2.3-1ubuntu2) ... 191s Setting up python3-myst-parser (4.0.1-1) ... 192s Setting up sphinx-basic-ng (1.0.0~beta2-1) ... 192s Setting up furo (2025.09.25+dfsg-2) ... 201s autopkgtest [10:24:54]: test pybuild-autopkgtest: pybuild-autopkgtest 201s autopkgtest [10:24:54]: test pybuild-autopkgtest: [----------------------- 203s pybuild-autopkgtest 203s I: pybuild base:317: cd /tmp/autopkgtest.YDvXY4/autopkgtest_tmp/build; python3.14 -m pytest --no-cov --ignore=tests/test_benchmark_base_scanner.py 204s /usr/lib/python3/dist-packages/pytest_asyncio/plugin.py:207: PytestDeprecationWarning: The configuration option "asyncio_default_fixture_loop_scope" is unset. 204s 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" 204s 204s warnings.warn(PytestDeprecationWarning(_DEFAULT_FIXTURE_LOOP_SCOPE_UNSET)) 204s ============================= test session starts ============================== 204s platform linux -- Python 3.14.0, pytest-8.3.5, pluggy-1.6.0 -- /usr/bin/python3.14 204s cachedir: .pytest_cache 204s rootdir: /tmp/autopkgtest.YDvXY4/autopkgtest_tmp/build 204s configfile: pyproject.toml 204s plugins: typeguard-4.4.2, asyncio-0.25.1, cov-5.0.0 204s asyncio: mode=Mode.STRICT, asyncio_default_fixture_loop_scope=None 204s collecting ... collected 90 items 204s 204s tests/test_base_scanner.py::test_remote_scanner[None] 204s -------------------------------- live log setup -------------------------------- 204s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 204s WARNING habluetooth.manager:manager.py:189 BluetoothManager: does not implement _discover_service_info, subclasses must implement this method to consume discovery data 204s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 204s -------------------------------- live log call --------------------------------- 204s DEBUG habluetooth.manager:manager.py:765 Registering scanner esp32 204s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner esp32 204s PASSED [ 1%] 204s tests/test_base_scanner.py::test_remote_scanner[w] 204s -------------------------------- live log setup -------------------------------- 204s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 204s -------------------------------- live log call --------------------------------- 204s DEBUG habluetooth.manager:manager.py:765 Registering scanner esp32 204s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner esp32 204s PASSED [ 2%] 204s tests/test_base_scanner.py::test_remote_scanner_expires_connectable 204s -------------------------------- live log setup -------------------------------- 204s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 204s -------------------------------- live log call --------------------------------- 204s DEBUG habluetooth.manager:manager.py:765 Registering scanner esp32 204s DEBUG habluetooth.base_scanner:base_scanner.py:210 esp32: Scanner watchdog time_since_last_detection: 196.00075755198486 204s DEBUG habluetooth.base_scanner:base_scanner.py:225 esp32: Bluetooth scanner has gone quiet for 196.00075755198486s, check logs on the scanner device for more information 204s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner esp32 204s PASSED [ 3%] 204s tests/test_base_scanner.py::test_remote_scanner_expires_non_connectable 204s -------------------------------- live log setup -------------------------------- 204s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 204s -------------------------------- live log call --------------------------------- 204s DEBUG habluetooth.manager:manager.py:765 Registering scanner esp32 204s DEBUG habluetooth.base_scanner:base_scanner.py:210 esp32: Scanner watchdog time_since_last_detection: 196.00097434897907 204s DEBUG habluetooth.base_scanner:base_scanner.py:225 esp32: Bluetooth scanner has gone quiet for 196.00097434897907s, check logs on the scanner device for more information 204s DEBUG habluetooth.base_scanner:base_scanner.py:210 esp32: Scanner watchdog time_since_last_detection: 901.0009743489791 204s DEBUG habluetooth.base_scanner:base_scanner.py:225 esp32: Bluetooth scanner has gone quiet for 901.0009743489791s, check logs on the scanner device for more information 204s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner esp32 204s PASSED [ 4%] 204s tests/test_base_scanner.py::test_base_scanner_connecting_behavior 204s -------------------------------- live log setup -------------------------------- 204s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 204s -------------------------------- live log call --------------------------------- 204s DEBUG habluetooth.manager:manager.py:765 Registering scanner esp32 204s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner esp32 204s PASSED [ 5%] 204s tests/test_base_scanner.py::test_scanner_stops_responding 204s -------------------------------- live log setup -------------------------------- 204s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 204s -------------------------------- live log call --------------------------------- 204s DEBUG habluetooth.manager:manager.py:765 Registering scanner esp32 204s DEBUG habluetooth.base_scanner:base_scanner.py:210 esp32: Scanner watchdog time_since_last_detection: 120.00103458901867 204s DEBUG habluetooth.base_scanner:base_scanner.py:225 esp32: Bluetooth scanner has gone quiet for 120.00103458901867s, check logs on the scanner device for more information 204s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner esp32 204s PASSED [ 6%] 204s tests/test_base_scanner.py::test_merge_manufacturer_data_history_existing 204s -------------------------------- live log setup -------------------------------- 204s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 204s -------------------------------- live log call --------------------------------- 204s DEBUG habluetooth.manager:manager.py:765 Registering scanner esp32 204s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner esp32 204s PASSED [ 7%] 204s tests/test_base_scanner.py::test_merge_manufacturer_data_history_new 204s -------------------------------- live log setup -------------------------------- 204s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 204s -------------------------------- live log call --------------------------------- 204s DEBUG habluetooth.manager:manager.py:765 Registering scanner esp32 204s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner esp32 204s PASSED [ 8%] 204s tests/test_base_scanner.py::test_filter_apple_data 204s -------------------------------- live log setup -------------------------------- 204s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 204s -------------------------------- live log call --------------------------------- 204s DEBUG habluetooth.manager:manager.py:765 Registering scanner esp32 204s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner esp32 204s PASSED [ 10%] 204s tests/test_base_scanner.py::test_connection_history_count_in_progress 204s -------------------------------- live log setup -------------------------------- 204s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (AA:BB:CC:DD:EE:00) 204s PASSED [ 11%] 204s ------------------------------ live log teardown ------------------------------- 204s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (AA:BB:CC:DD:EE:00) 204s 204s tests/test_base_scanner.py::test_connection_history_failure_count 204s -------------------------------- live log setup -------------------------------- 204s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (AA:BB:CC:DD:EE:00) 204s -------------------------------- live log call --------------------------------- 204s 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 204s PASSED [ 12%] 204s ------------------------------ live log teardown ------------------------------- 204s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (AA:BB:CC:DD:EE:00) 204s 204s tests/test_init.py::test_create_scanner 204s -------------------------------- live log setup -------------------------------- 204s WARNING habluetooth.manager:manager.py:189 BluetoothManager: does not implement _discover_service_info, subclasses must implement this method to consume discovery data 204s PASSED [ 13%] 204s tests/test_init.py::test_create_remote_scanner PASSED [ 14%] 204s tests/test_init.py::test__async_on_advertisement PASSED [ 15%] 204s tests/test_init.py::test__async_on_advertisement_first PASSED [ 16%] 204s tests/test_init.py::test__async_on_advertisement_prefers_longest_local_name PASSED [ 17%] 204s tests/test_init.py::test_create_ha_scanner PASSED [ 18%] 204s tests/test_manager.py::test_async_recover_failed_adapters 204s -------------------------------- live log setup -------------------------------- 204s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 204s -------------------------------- live log call --------------------------------- 204s WARNING habluetooth.manager:manager.py:189 BluetoothManager: does not implement _discover_service_info, subclasses must implement this method to consume discovery data 204s PASSED [ 20%] 204s tests/test_manager.py::test_create_manager 204s -------------------------------- live log setup -------------------------------- 204s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 204s -------------------------------- live log call --------------------------------- 204s WARNING habluetooth.manager:manager.py:189 BluetoothManager: does not implement _discover_service_info, subclasses must implement this method to consume discovery data 204s PASSED [ 21%] 204s tests/test_manager.py::test_async_register_disappeared_callback 204s -------------------------------- live log setup -------------------------------- 204s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 204s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (AA:BB:CC:DD:EE:00) 204s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci1 (AA:BB:CC:DD:EE:11) 204s -------------------------------- live log call --------------------------------- 204s ERROR habluetooth.manager:manager.py:425 Error in disappeared callback 204s Traceback (most recent call last): 204s File "/usr/lib/python3/dist-packages/habluetooth/manager.py", line 423, in _async_check_unavailable 204s disappear_callback(address) 204s ~~~~~~~~~~~~~~~~~~^^^^^^^^^ 204s File "/tmp/autopkgtest.YDvXY4/autopkgtest_tmp/build/tests/test_manager.py", line 188, in _failing_callback 204s raise ValueError("This is a test") 204s ValueError: This is a test 204s PASSED [ 22%] 204s ------------------------------ live log teardown ------------------------------- 204s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci1 (AA:BB:CC:DD:EE:11) 204s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (AA:BB:CC:DD:EE:00) 204s 204s tests/test_manager.py::test_async_register_allocation_callback 204s -------------------------------- live log setup -------------------------------- 204s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 204s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (AA:BB:CC:DD:EE:00) 204s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci1 (AA:BB:CC:DD:EE:11) 204s -------------------------------- live log call --------------------------------- 204s ERROR habluetooth.manager:manager.py:850 Error in allocation callback 204s Traceback (most recent call last): 204s File "/usr/lib/python3/dist-packages/habluetooth/manager.py", line 848, in async_on_allocation_changed 204s callback_(ha_slot_allocations) 204s ~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^ 204s File "/tmp/autopkgtest.YDvXY4/autopkgtest_tmp/build/tests/test_manager.py", line 259, in _failing_callback 204s raise ValueError("This is a test") 204s ValueError: This is a test 204s ERROR habluetooth.manager:manager.py:850 Error in allocation callback 204s Traceback (most recent call last): 204s File "/usr/lib/python3/dist-packages/habluetooth/manager.py", line 848, in async_on_allocation_changed 204s callback_(ha_slot_allocations) 204s ~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^ 204s File "/tmp/autopkgtest.YDvXY4/autopkgtest_tmp/build/tests/test_manager.py", line 259, in _failing_callback 204s raise ValueError("This is a test") 204s ValueError: This is a test 204s PASSED [ 23%] 204s ------------------------------ live log teardown ------------------------------- 204s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci1 (AA:BB:CC:DD:EE:11) 204s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (AA:BB:CC:DD:EE:00) 204s 204s tests/test_manager.py::test_async_register_allocation_callback_non_connectable 204s -------------------------------- live log setup -------------------------------- 204s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 204s DEBUG habluetooth.manager:manager.py:765 Registering scanner non connectable (AA:BB:CC:DD:EE:FF) 204s PASSED [ 24%] 204s ------------------------------ live log teardown ------------------------------- 204s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner non connectable (AA:BB:CC:DD:EE:FF) 204s 204s tests/test_manager.py::test_async_register_scanner_registration_callback 204s -------------------------------- live log setup -------------------------------- 204s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 204s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (AA:BB:CC:DD:EE:00) 204s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci1 (AA:BB:CC:DD:EE:11) 204s -------------------------------- live log call --------------------------------- 204s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci3 (AA:BB:CC:DD:EE:33) 204s ERROR habluetooth.manager:manager.py:867 Error in scanner callback 204s Traceback (most recent call last): 204s File "/usr/lib/python3/dist-packages/habluetooth/manager.py", line 865, in _async_on_scanner_registration 204s callback_(HaScannerRegistration(event, scanner)) 204s ~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 204s File "/tmp/autopkgtest.YDvXY4/autopkgtest_tmp/build/tests/test_manager.py", line 381, in _failing_callback 204s raise ValueError("This is a test") 204s ValueError: This is a test 204s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci3 (AA:BB:CC:DD:EE:33) 204s ERROR habluetooth.manager:manager.py:867 Error in scanner callback 204s Traceback (most recent call last): 204s File "/usr/lib/python3/dist-packages/habluetooth/manager.py", line 865, in _async_on_scanner_registration 204s callback_(HaScannerRegistration(event, scanner)) 204s ~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 204s File "/tmp/autopkgtest.YDvXY4/autopkgtest_tmp/build/tests/test_manager.py", line 381, in _failing_callback 204s raise ValueError("This is a test") 204s ValueError: This is a test 204s PASSED [ 25%] 204s ------------------------------ live log teardown ------------------------------- 204s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci1 (AA:BB:CC:DD:EE:11) 204s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (AA:BB:CC:DD:EE:00) 204s 204s tests/test_manager.py::test_async_register_scanner_with_connection_slots 204s -------------------------------- live log setup -------------------------------- 204s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 204s -------------------------------- live log call --------------------------------- 204s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci3 (AA:BB:CC:DD:EE:33) 204s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci3 (AA:BB:CC:DD:EE:33) 204s PASSED [ 26%] 204s tests/test_manager.py::test_diagnostics 204s -------------------------------- live log setup -------------------------------- 204s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 204s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (AA:BB:CC:DD:EE:00) 204s PASSED [ 27%] 204s ------------------------------ live log teardown ------------------------------- 204s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (AA:BB:CC:DD:EE:00) 204s 204s tests/test_manager.py::test_advertisements_do_not_switch_adapters_for_no_reason 204s -------------------------------- live log setup -------------------------------- 204s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 204s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (AA:BB:CC:DD:EE:00) 204s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci1 (AA:BB:CC:DD:EE:11) 204s PASSED [ 28%] 204s ------------------------------ live log teardown ------------------------------- 204s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci1 (AA:BB:CC:DD:EE:11) 204s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (AA:BB:CC:DD:EE:00) 204s 204s tests/test_manager.py::test_switching_adapters_based_on_rssi 204s -------------------------------- live log setup -------------------------------- 204s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 204s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (AA:BB:CC:DD:EE:00) 204s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci1 (AA:BB:CC:DD:EE:11) 204s -------------------------------- live log call --------------------------------- 204s 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) 204s PASSED [ 30%] 204s ------------------------------ live log teardown ------------------------------- 204s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci1 (AA:BB:CC:DD:EE:11) 204s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (AA:BB:CC:DD:EE:00) 204s 204s tests/test_manager.py::test_switching_adapters_based_on_zero_rssi 204s -------------------------------- live log setup -------------------------------- 204s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 204s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (AA:BB:CC:DD:EE:00) 204s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci1 (AA:BB:CC:DD:EE:11) 204s -------------------------------- live log call --------------------------------- 204s 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) 204s PASSED [ 31%] 204s ------------------------------ live log teardown ------------------------------- 204s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci1 (AA:BB:CC:DD:EE:11) 204s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (AA:BB:CC:DD:EE:00) 204s 204s tests/test_manager.py::test_switching_adapters_based_on_stale 204s -------------------------------- live log setup -------------------------------- 204s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 204s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (AA:BB:CC:DD:EE:00) 204s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci1 (AA:BB:CC:DD:EE:11) 204s -------------------------------- live log call --------------------------------- 204s 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) 204s PASSED [ 32%] 204s ------------------------------ live log teardown ------------------------------- 204s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci1 (AA:BB:CC:DD:EE:11) 204s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (AA:BB:CC:DD:EE:00) 204s 204s tests/test_manager.py::test_switching_adapters_based_on_stale_with_discovered_interval 204s -------------------------------- live log setup -------------------------------- 204s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 204s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (AA:BB:CC:DD:EE:00) 204s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci1 (AA:BB:CC:DD:EE:11) 204s -------------------------------- live log call --------------------------------- 204s 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) 204s PASSED [ 33%] 204s ------------------------------ live log teardown ------------------------------- 204s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci1 (AA:BB:CC:DD:EE:11) 204s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (AA:BB:CC:DD:EE:00) 204s 204s tests/test_manager.py::test_switching_adapters_based_on_rssi_connectable_to_non_connectable 204s -------------------------------- live log setup -------------------------------- 204s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 204s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (AA:BB:CC:DD:EE:00) 204s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci1 (AA:BB:CC:DD:EE:11) 204s -------------------------------- live log call --------------------------------- 204s 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) 204s PASSED [ 34%] 204s ------------------------------ live log teardown ------------------------------- 204s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci1 (AA:BB:CC:DD:EE:11) 204s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (AA:BB:CC:DD:EE:00) 204s 204s tests/test_manager.py::test_connectable_advertisement_can_be_retrieved_best_path_is_non_connectable 204s -------------------------------- live log setup -------------------------------- 204s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 204s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (AA:BB:CC:DD:EE:00) 204s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci1 (AA:BB:CC:DD:EE:11) 204s PASSED [ 35%] 204s ------------------------------ live log teardown ------------------------------- 204s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci1 (AA:BB:CC:DD:EE:11) 204s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (AA:BB:CC:DD:EE:00) 204s 204s tests/test_manager.py::test_switching_adapters_when_one_goes_away 204s -------------------------------- live log setup -------------------------------- 204s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 204s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (AA:BB:CC:DD:EE:00) 204s -------------------------------- live log call --------------------------------- 204s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci2 204s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci2 204s PASSED [ 36%] 204s ------------------------------ live log teardown ------------------------------- 204s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (AA:BB:CC:DD:EE:00) 204s 204s tests/test_manager.py::test_switching_adapters_when_one_stop_scanning 204s -------------------------------- live log setup -------------------------------- 204s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 204s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (AA:BB:CC:DD:EE:00) 204s -------------------------------- live log call --------------------------------- 204s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci2 204s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci2 204s PASSED [ 37%] 204s ------------------------------ live log teardown ------------------------------- 204s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (AA:BB:CC:DD:EE:00) 204s 204s tests/test_manager.py::test_set_fallback_interval_small 204s -------------------------------- live log setup -------------------------------- 204s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 204s PASSED [ 38%] 204s tests/test_manager.py::test_set_fallback_interval_big 204s -------------------------------- live log setup -------------------------------- 204s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 204s PASSED [ 40%] 204s tests/test_manager.py::test_subclassing_bluetooth_manager 204s -------------------------------- live log setup -------------------------------- 204s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 204s -------------------------------- live log call --------------------------------- 204s WARNING habluetooth.manager:manager.py:189 TestBluetoothManager2: does not implement _discover_service_info, subclasses must implement this method to consume discovery data 204s PASSED [ 41%] 204s tests/test_models.py::test_model PASSED [ 42%] 204s tests/test_models.py::test_model_from_bleak PASSED [ 43%] 204s tests/test_models.py::test_model_from_scanner PASSED [ 44%] 204s tests/test_models.py::test_construct_service_info_bleak PASSED [ 45%] 204s tests/test_models.py::test_from_device_and_advertisement_data PASSED [ 46%] 204s tests/test_models.py::test_pyobjc_compat PASSED [ 47%] 204s tests/test_models.py::test_as_connectable PASSED [ 48%] 204s tests/test_scanner.py::test_empty_data_no_scanner 204s -------------------------------- live log setup -------------------------------- 204s WARNING habluetooth.manager:manager.py:189 BluetoothManager: does not implement _discover_service_info, subclasses must implement this method to consume discovery data 204s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 204s PASSED [ 50%] 204s tests/test_scanner.py::test_dbus_socket_missing_in_container 204s -------------------------------- live log setup -------------------------------- 204s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 204s -------------------------------- live log call --------------------------------- 204s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 204s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (1/4) 204s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 204s DEBUG habluetooth.scanner:scanner.py:462 hci0 (AA:BB:CC:DD:EE:FF): FileNotFoundError while starting bluetooth: 204s Traceback (most recent call last): 204s File "/usr/lib/python3/dist-packages/habluetooth/scanner.py", line 344, in _async_start_attempt 204s await self.scanner.start() 204s File "/usr/lib/python3.14/unittest/mock.py", line 2326, in _execute_mock_call 204s raise effect 204s FileNotFoundError 204s PASSED [ 51%] 204s tests/test_scanner.py::test_dbus_socket_missing 204s -------------------------------- live log setup -------------------------------- 204s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 204s -------------------------------- live log call --------------------------------- 204s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 204s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (1/4) 204s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 204s DEBUG habluetooth.scanner:scanner.py:462 hci0 (AA:BB:CC:DD:EE:FF): FileNotFoundError while starting bluetooth: 204s Traceback (most recent call last): 204s File "/usr/lib/python3/dist-packages/habluetooth/scanner.py", line 344, in _async_start_attempt 204s await self.scanner.start() 204s File "/usr/lib/python3.14/unittest/mock.py", line 2326, in _execute_mock_call 204s raise effect 204s FileNotFoundError 204s PASSED [ 52%] 204s tests/test_scanner.py::test_handle_cancellation 204s -------------------------------- live log setup -------------------------------- 204s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 204s -------------------------------- live log call --------------------------------- 204s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 204s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (1/4) 204s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 204s PASSED [ 53%] 204s tests/test_scanner.py::test_handle_stop_while_starting 204s -------------------------------- live log setup -------------------------------- 204s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 204s -------------------------------- live log call --------------------------------- 204s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 204s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (1/4) 204s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 204s DEBUG habluetooth.scanner:scanner.py:452 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth scanner aborted: 204s Traceback (most recent call last): 204s File "/usr/lib/python3/dist-packages/habluetooth/scanner.py", line 344, in _async_start_attempt 204s await self.scanner.start() 204s File "/tmp/autopkgtest.YDvXY4/autopkgtest_tmp/build/tests/test_scanner.py", line 149, in _start 204s await asyncio.sleep(1000) 204s File "/usr/lib/python3.14/asyncio/tasks.py", line 702, in sleep 204s return await future 204s ^^^^^^^^^^^^ 204s asyncio.exceptions.CancelledError: Interrupted by interrupt context manager 204s 204s The above exception was the direct cause of the following exception: 204s 204s Traceback (most recent call last): 204s File "/usr/lib/python3/dist-packages/habluetooth/scanner.py", line 342, in _async_start_attempt 204s async_interrupt.interrupt(self._start_future, _AbortStartError, None), 204s ~~~~~~~~~~~~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 204s File "/usr/lib/python3/dist-packages/async_interrupt/__init__.py", line 90, in __aexit__ 204s raise self._exception from exc_val 204s habluetooth.scanner._AbortStartError 204s DEBUG habluetooth.scanner:scanner.py:571 hci0 (AA:BB:CC:DD:EE:FF): Scanner is already stopped 204s PASSED [ 54%] 204s tests/test_scanner.py::test_dbus_broken_pipe_in_container 204s -------------------------------- live log setup -------------------------------- 204s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 204s -------------------------------- live log call --------------------------------- 204s ERROR asyncio:base_events.py:1875 Future exception was never retrieved 204s future: 204s habluetooth.scanner._AbortStartError 204s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 204s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (1/4) 204s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 204s DEBUG habluetooth.scanner:scanner.py:480 hci0 (AA:BB:CC:DD:EE:FF): DBus connection broken: 204s Traceback (most recent call last): 204s File "/usr/lib/python3/dist-packages/habluetooth/scanner.py", line 344, in _async_start_attempt 204s await self.scanner.start() 204s File "/usr/lib/python3.14/unittest/mock.py", line 2326, in _execute_mock_call 204s raise effect 204s BrokenPipeError 204s PASSED [ 55%] 204s tests/test_scanner.py::test_dbus_broken_pipe 204s -------------------------------- live log setup -------------------------------- 204s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 204s -------------------------------- live log call --------------------------------- 204s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 204s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (1/4) 204s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 204s DEBUG habluetooth.scanner:scanner.py:480 hci0 (AA:BB:CC:DD:EE:FF): DBus connection broken: 204s Traceback (most recent call last): 204s File "/usr/lib/python3/dist-packages/habluetooth/scanner.py", line 344, in _async_start_attempt 204s await self.scanner.start() 204s File "/usr/lib/python3.14/unittest/mock.py", line 2326, in _execute_mock_call 204s raise effect 204s BrokenPipeError 204s PASSED [ 56%] 204s tests/test_scanner.py::test_invalid_dbus_message 204s -------------------------------- live log setup -------------------------------- 204s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 204s -------------------------------- live log call --------------------------------- 204s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 204s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (1/4) 204s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 204s DEBUG habluetooth.scanner:scanner.py:495 hci0 (AA:BB:CC:DD:EE:FF): Invalid DBus message received: 204s Traceback (most recent call last): 204s File "/usr/lib/python3/dist-packages/habluetooth/scanner.py", line 344, in _async_start_attempt 204s await self.scanner.start() 204s File "/usr/lib/python3.14/unittest/mock.py", line 2326, in _execute_mock_call 204s raise effect 204s dbus_fast.errors.InvalidMessageError 204s PASSED [ 57%] 204s tests/test_scanner.py::test_adapter_needs_reset_at_start[org.bluez.Error.Failed] 204s -------------------------------- live log setup -------------------------------- 204s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 204s -------------------------------- live log call --------------------------------- 204s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 204s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (1/4) 204s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 204s DEBUG habluetooth.scanner:scanner.py:434 hci0 (AA:BB:CC:DD:EE:FF): BleakError while starting bluetooth; attempt: (1/4): org.bluez.Error.Failed 204s Traceback (most recent call last): 204s File "/usr/lib/python3/dist-packages/habluetooth/scanner.py", line 344, in _async_start_attempt 204s await self.scanner.start() 204s File "/tmp/autopkgtest.YDvXY4/autopkgtest_tmp/build/tests/test_scanner.py", line 249, in start 204s raise BleakError(error) 204s bleak.exc.BleakError: org.bluez.Error.Failed 204s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 204s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (2/4) 204s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 204s DEBUG habluetooth.scanner:scanner.py:555 hci0 (AA:BB:CC:DD:EE:FF): adapter stopped responding; executing reset 204s DEBUG habluetooth.scanner:scanner.py:557 hci0 (AA:BB:CC:DD:EE:FF): adapter reset result: True 204s DEBUG habluetooth.scanner:scanner.py:434 hci0 (AA:BB:CC:DD:EE:FF): BleakError while starting bluetooth; attempt: (2/4): org.bluez.Error.Failed 204s Traceback (most recent call last): 204s File "/usr/lib/python3/dist-packages/habluetooth/scanner.py", line 344, in _async_start_attempt 204s await self.scanner.start() 204s File "/tmp/autopkgtest.YDvXY4/autopkgtest_tmp/build/tests/test_scanner.py", line 249, in start 204s raise BleakError(error) 204s bleak.exc.BleakError: org.bluez.Error.Failed 204s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 204s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (3/4) 204s DEBUG habluetooth.scanner:scanner.py:418 hci0 (AA:BB:CC:DD:EE:FF): Success while starting bluetooth; attempt: (3/4) 204s DEBUG bleak_retry_connector.bleak_manager:bleak_manager.py:60 get_global_bluez_manager_with_timeout failed: [org.freedesktop.systemd1.NoSuchUnit] Unit dbus-org.bluez.service not found. 204s Traceback (most recent call last): 204s File "/usr/lib/python3/dist-packages/bleak_retry_connector/bleak_manager.py", line 46, in get_global_bluez_manager_with_timeout 204s return await get_global_bluez_manager() 204s ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 204s File "/usr/lib/python3/dist-packages/bleak/backends/bluezdbus/manager.py", line 1131, in get_global_bluez_manager 204s await instance.async_init() 204s File "/usr/lib/python3/dist-packages/bleak/backends/bluezdbus/manager.py", line 301, in async_init 204s assert_reply(reply) 204s ~~~~~~~~~~~~^^^^^^^ 204s File "/usr/lib/python3/dist-packages/bleak/backends/bluezdbus/utils.py", line 27, in assert_reply 204s raise BleakDBusError(reply.error_name, reply.body) 204s bleak.exc.BleakDBusError: [org.freedesktop.systemd1.NoSuchUnit] Unit dbus-org.bluez.service not found. 204s DEBUG bleak_retry_connector:__init__.py:639 Failed to restore discoveries for hci0 204s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 204s PASSED [ 58%] 204s tests/test_scanner.py::test_adapter_needs_reset_at_start[org.bluez.Error.InProgress] 204s -------------------------------- live log setup -------------------------------- 204s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 204s -------------------------------- live log call --------------------------------- 204s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 204s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (1/4) 204s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 204s DEBUG habluetooth.scanner:scanner.py:586 hci0 (AA:BB:CC:DD:EE:FF): Force stopping bluetooth discovery 204s DEBUG bleak_retry_connector.bleak_manager:bleak_manager.py:60 get_global_bluez_manager_with_timeout failed: [org.freedesktop.systemd1.NoSuchUnit] Unit dbus-org.bluez.service not found. 204s Traceback (most recent call last): 204s File "/usr/lib/python3/dist-packages/habluetooth/scanner.py", line 344, in _async_start_attempt 204s await self.scanner.start() 204s File "/tmp/autopkgtest.YDvXY4/autopkgtest_tmp/build/tests/test_scanner.py", line 249, in start 204s raise BleakError(error) 204s bleak.exc.BleakError: org.bluez.Error.InProgress 204s 204s During handling of the above exception, another exception occurred: 204s 204s Traceback (most recent call last): 204s File "/usr/lib/python3/dist-packages/bleak_retry_connector/bleak_manager.py", line 46, in get_global_bluez_manager_with_timeout 204s return await get_global_bluez_manager() 204s ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 204s File "/usr/lib/python3/dist-packages/bleak/backends/bluezdbus/manager.py", line 1131, in get_global_bluez_manager 204s await instance.async_init() 204s File "/usr/lib/python3/dist-packages/bleak/backends/bluezdbus/manager.py", line 301, in async_init 204s assert_reply(reply) 204s ~~~~~~~~~~~~^^^^^^^ 204s File "/usr/lib/python3/dist-packages/bleak/backends/bluezdbus/utils.py", line 27, in assert_reply 204s raise BleakDBusError(reply.error_name, reply.body) 204s bleak.exc.BleakDBusError: [org.freedesktop.systemd1.NoSuchUnit] Unit dbus-org.bluez.service not found. 204s ERROR bleak_retry_connector.bluez:bluez.py:324 Failed to stop discovery for hci0 because no manager 204s DEBUG habluetooth.scanner:scanner.py:434 hci0 (AA:BB:CC:DD:EE:FF): BleakError while starting bluetooth; attempt: (1/4): org.bluez.Error.InProgress 204s Traceback (most recent call last): 204s File "/usr/lib/python3/dist-packages/habluetooth/scanner.py", line 344, in _async_start_attempt 204s await self.scanner.start() 204s File "/tmp/autopkgtest.YDvXY4/autopkgtest_tmp/build/tests/test_scanner.py", line 249, in start 204s raise BleakError(error) 204s bleak.exc.BleakError: org.bluez.Error.InProgress 204s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 204s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (2/4) 204s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 204s DEBUG habluetooth.scanner:scanner.py:586 hci0 (AA:BB:CC:DD:EE:FF): Force stopping bluetooth discovery 204s ERROR habluetooth.scanner:scanner.py:593 hci0 (AA:BB:CC:DD:EE:FF): Failed to force stop scanner: 'NoneType' object has no attribute 'send' 204s DEBUG habluetooth.scanner:scanner.py:555 hci0 (AA:BB:CC:DD:EE:FF): adapter stopped responding; executing reset 204s DEBUG habluetooth.scanner:scanner.py:557 hci0 (AA:BB:CC:DD:EE:FF): adapter reset result: True 204s DEBUG habluetooth.scanner:scanner.py:434 hci0 (AA:BB:CC:DD:EE:FF): BleakError while starting bluetooth; attempt: (2/4): org.bluez.Error.InProgress 204s Traceback (most recent call last): 204s File "/usr/lib/python3/dist-packages/habluetooth/scanner.py", line 344, in _async_start_attempt 204s await self.scanner.start() 204s File "/tmp/autopkgtest.YDvXY4/autopkgtest_tmp/build/tests/test_scanner.py", line 249, in start 204s raise BleakError(error) 204s bleak.exc.BleakError: org.bluez.Error.InProgress 204s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 204s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (3/4) 204s DEBUG habluetooth.scanner:scanner.py:418 hci0 (AA:BB:CC:DD:EE:FF): Success while starting bluetooth; attempt: (3/4) 204s DEBUG bleak_retry_connector:__init__.py:639 Failed to restore discoveries for hci0 204s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 204s PASSED [ 60%] 204s tests/test_scanner.py::test_adapter_needs_reset_at_start[org.bluez.Error.NotReady] 204s -------------------------------- live log setup -------------------------------- 204s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 204s -------------------------------- live log call --------------------------------- 204s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 204s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (1/4) 204s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 204s DEBUG habluetooth.scanner:scanner.py:434 hci0 (AA:BB:CC:DD:EE:FF): BleakError while starting bluetooth; attempt: (1/4): org.bluez.Error.NotReady 204s Traceback (most recent call last): 204s File "/usr/lib/python3/dist-packages/habluetooth/scanner.py", line 344, in _async_start_attempt 204s await self.scanner.start() 204s File "/tmp/autopkgtest.YDvXY4/autopkgtest_tmp/build/tests/test_scanner.py", line 249, in start 204s raise BleakError(error) 204s bleak.exc.BleakError: org.bluez.Error.NotReady 204s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 204s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (2/4) 204s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 204s DEBUG habluetooth.scanner:scanner.py:555 hci0 (AA:BB:CC:DD:EE:FF): adapter stopped responding; executing reset 204s DEBUG habluetooth.scanner:scanner.py:557 hci0 (AA:BB:CC:DD:EE:FF): adapter reset result: True 204s DEBUG habluetooth.scanner:scanner.py:434 hci0 (AA:BB:CC:DD:EE:FF): BleakError while starting bluetooth; attempt: (2/4): org.bluez.Error.NotReady 204s Traceback (most recent call last): 204s File "/usr/lib/python3/dist-packages/habluetooth/scanner.py", line 344, in _async_start_attempt 204s await self.scanner.start() 204s File "/tmp/autopkgtest.YDvXY4/autopkgtest_tmp/build/tests/test_scanner.py", line 249, in start 204s raise BleakError(error) 204s bleak.exc.BleakError: org.bluez.Error.NotReady 204s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 204s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (3/4) 204s DEBUG habluetooth.scanner:scanner.py:418 hci0 (AA:BB:CC:DD:EE:FF): Success while starting bluetooth; attempt: (3/4) 204s DEBUG bleak_retry_connector.bleak_manager:bleak_manager.py:60 get_global_bluez_manager_with_timeout failed: [org.freedesktop.systemd1.NoSuchUnit] Unit dbus-org.bluez.service not found. 204s Traceback (most recent call last): 204s File "/usr/lib/python3/dist-packages/bleak_retry_connector/bleak_manager.py", line 46, in get_global_bluez_manager_with_timeout 204s return await get_global_bluez_manager() 204s ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 204s File "/usr/lib/python3/dist-packages/bleak/backends/bluezdbus/manager.py", line 1131, in get_global_bluez_manager 204s await instance.async_init() 204s File "/usr/lib/python3/dist-packages/bleak/backends/bluezdbus/manager.py", line 301, in async_init 204s assert_reply(reply) 204s ~~~~~~~~~~~~^^^^^^^ 204s File "/usr/lib/python3/dist-packages/bleak/backends/bluezdbus/utils.py", line 27, in assert_reply 204s raise BleakDBusError(reply.error_name, reply.body) 204s bleak.exc.BleakDBusError: [org.freedesktop.systemd1.NoSuchUnit] Unit dbus-org.bluez.service not found. 204s DEBUG bleak_retry_connector:__init__.py:639 Failed to restore discoveries for hci0 204s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 204s PASSED [ 61%] 204s tests/test_scanner.py::test_adapter_needs_reset_at_start[not found] 204s -------------------------------- live log setup -------------------------------- 204s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 204s -------------------------------- live log call --------------------------------- 204s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 204s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (1/4) 204s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 204s DEBUG habluetooth.scanner:scanner.py:434 hci0 (AA:BB:CC:DD:EE:FF): BleakError while starting bluetooth; attempt: (1/4): not found 204s Traceback (most recent call last): 204s File "/usr/lib/python3/dist-packages/habluetooth/scanner.py", line 344, in _async_start_attempt 204s await self.scanner.start() 204s File "/tmp/autopkgtest.YDvXY4/autopkgtest_tmp/build/tests/test_scanner.py", line 249, in start 204s raise BleakError(error) 204s bleak.exc.BleakError: not found 204s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 204s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (2/4) 204s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 204s DEBUG habluetooth.scanner:scanner.py:555 hci0 (AA:BB:CC:DD:EE:FF): adapter stopped responding; executing reset 204s DEBUG habluetooth.scanner:scanner.py:557 hci0 (AA:BB:CC:DD:EE:FF): adapter reset result: True 204s DEBUG habluetooth.scanner:scanner.py:434 hci0 (AA:BB:CC:DD:EE:FF): BleakError while starting bluetooth; attempt: (2/4): not found 204s Traceback (most recent call last): 204s File "/usr/lib/python3/dist-packages/habluetooth/scanner.py", line 344, in _async_start_attempt 204s await self.scanner.start() 204s File "/tmp/autopkgtest.YDvXY4/autopkgtest_tmp/build/tests/test_scanner.py", line 249, in start 204s raise BleakError(error) 204s bleak.exc.BleakError: not found 204s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 204s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (3/4) 204s DEBUG habluetooth.scanner:scanner.py:418 hci0 (AA:BB:CC:DD:EE:FF): Success while starting bluetooth; attempt: (3/4) 204s DEBUG bleak_retry_connector.bleak_manager:bleak_manager.py:60 get_global_bluez_manager_with_timeout failed: [org.freedesktop.systemd1.NoSuchUnit] Unit dbus-org.bluez.service not found. 204s Traceback (most recent call last): 204s File "/usr/lib/python3/dist-packages/bleak_retry_connector/bleak_manager.py", line 46, in get_global_bluez_manager_with_timeout 204s return await get_global_bluez_manager() 204s ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 204s File "/usr/lib/python3/dist-packages/bleak/backends/bluezdbus/manager.py", line 1131, in get_global_bluez_manager 204s await instance.async_init() 204s File "/usr/lib/python3/dist-packages/bleak/backends/bluezdbus/manager.py", line 301, in async_init 204s assert_reply(reply) 204s ~~~~~~~~~~~~^^^^^^^ 204s File "/usr/lib/python3/dist-packages/bleak/backends/bluezdbus/utils.py", line 27, in assert_reply 204s raise BleakDBusError(reply.error_name, reply.body) 204s bleak.exc.BleakDBusError: [org.freedesktop.systemd1.NoSuchUnit] Unit dbus-org.bluez.service not found. 204s DEBUG bleak_retry_connector:__init__.py:639 Failed to restore discoveries for hci0 204s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 204s PASSED [ 62%] 204s tests/test_scanner.py::test_recovery_from_dbus_restart 204s -------------------------------- live log setup -------------------------------- 204s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 204s -------------------------------- live log call --------------------------------- 204s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 204s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (1/4) 204s DEBUG habluetooth.scanner:scanner.py:418 hci0 (AA:BB:CC:DD:EE:FF): Success while starting bluetooth; attempt: (1/4) 204s DEBUG bleak_retry_connector.bleak_manager:bleak_manager.py:60 get_global_bluez_manager_with_timeout failed: [org.freedesktop.systemd1.NoSuchUnit] Unit dbus-org.bluez.service not found. 204s Traceback (most recent call last): 204s File "/usr/lib/python3/dist-packages/bleak_retry_connector/bleak_manager.py", line 46, in get_global_bluez_manager_with_timeout 204s return await get_global_bluez_manager() 204s ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 204s File "/usr/lib/python3/dist-packages/bleak/backends/bluezdbus/manager.py", line 1131, in get_global_bluez_manager 204s await instance.async_init() 204s File "/usr/lib/python3/dist-packages/bleak/backends/bluezdbus/manager.py", line 301, in async_init 204s assert_reply(reply) 204s ~~~~~~~~~~~~^^^^^^^ 204s File "/usr/lib/python3/dist-packages/bleak/backends/bluezdbus/utils.py", line 27, in assert_reply 204s raise BleakDBusError(reply.error_name, reply.body) 204s bleak.exc.BleakDBusError: [org.freedesktop.systemd1.NoSuchUnit] Unit dbus-org.bluez.service not found. 204s DEBUG bleak_retry_connector:__init__.py:639 Failed to restore discoveries for hci0 205s DEBUG habluetooth.base_scanner:base_scanner.py:210 hci0 (AA:BB:CC:DD:EE:FF): Scanner watchdog time_since_last_detection: 10.003289884072728 205s DEBUG habluetooth.base_scanner:base_scanner.py:210 hci0 (AA:BB:CC:DD:EE:FF): Scanner watchdog time_since_last_detection: 20.0 205s DEBUG habluetooth.base_scanner:base_scanner.py:210 hci0 (AA:BB:CC:DD:EE:FF): Scanner watchdog time_since_last_detection: 110.0 205s DEBUG habluetooth.scanner:scanner.py:517 hci0 (AA:BB:CC:DD:EE:FF): Bluetooth scanner has gone quiet for 110.0s, restarting 205s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 205s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 205s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (1/4) 205s DEBUG habluetooth.scanner:scanner.py:418 hci0 (AA:BB:CC:DD:EE:FF): Success while starting bluetooth; attempt: (1/4) 205s DEBUG bleak_retry_connector:__init__.py:639 Failed to restore discoveries for hci0 205s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 205s PASSED [ 63%] 205s tests/test_scanner.py::test_adapter_recovery 205s -------------------------------- live log setup -------------------------------- 205s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 205s -------------------------------- live log call --------------------------------- 205s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 205s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (1/4) 205s DEBUG habluetooth.scanner:scanner.py:418 hci0 (AA:BB:CC:DD:EE:FF): Success while starting bluetooth; attempt: (1/4) 205s DEBUG bleak_retry_connector.bleak_manager:bleak_manager.py:60 get_global_bluez_manager_with_timeout failed: [org.freedesktop.systemd1.NoSuchUnit] Unit dbus-org.bluez.service not found. 205s Traceback (most recent call last): 205s File "/usr/lib/python3/dist-packages/bleak_retry_connector/bleak_manager.py", line 46, in get_global_bluez_manager_with_timeout 205s return await get_global_bluez_manager() 205s ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 205s File "/usr/lib/python3/dist-packages/bleak/backends/bluezdbus/manager.py", line 1131, in get_global_bluez_manager 205s await instance.async_init() 205s File "/usr/lib/python3/dist-packages/bleak/backends/bluezdbus/manager.py", line 301, in async_init 205s assert_reply(reply) 205s ~~~~~~~~~~~~^^^^^^^ 205s File "/usr/lib/python3/dist-packages/bleak/backends/bluezdbus/utils.py", line 27, in assert_reply 205s raise BleakDBusError(reply.error_name, reply.body) 205s bleak.exc.BleakDBusError: [org.freedesktop.systemd1.NoSuchUnit] Unit dbus-org.bluez.service not found. 205s DEBUG bleak_retry_connector:__init__.py:639 Failed to restore discoveries for hci0 205s DEBUG habluetooth.base_scanner:base_scanner.py:210 hci0 (AA:BB:CC:DD:EE:FF): Scanner watchdog time_since_last_detection: 10.0 205s DEBUG habluetooth.base_scanner:base_scanner.py:210 hci0 (AA:BB:CC:DD:EE:FF): Scanner watchdog time_since_last_detection: 20.0 205s DEBUG habluetooth.base_scanner:base_scanner.py:210 hci0 (AA:BB:CC:DD:EE:FF): Scanner watchdog time_since_last_detection: 120.0 205s DEBUG habluetooth.scanner:scanner.py:517 hci0 (AA:BB:CC:DD:EE:FF): Bluetooth scanner has gone quiet for 120.0s, restarting 205s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 205s DEBUG habluetooth.scanner:scanner.py:555 hci0 (AA:BB:CC:DD:EE:FF): adapter stopped responding; executing reset 205s DEBUG habluetooth.scanner:scanner.py:557 hci0 (AA:BB:CC:DD:EE:FF): adapter reset result: True 205s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 205s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (1/4) 205s DEBUG habluetooth.scanner:scanner.py:418 hci0 (AA:BB:CC:DD:EE:FF): Success while starting bluetooth; attempt: (1/4) 205s DEBUG bleak_retry_connector:__init__.py:639 Failed to restore discoveries for hci0 205s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 205s PASSED [ 64%] 205s tests/test_scanner.py::test_adapter_scanner_fails_to_start_first_time 205s -------------------------------- live log setup -------------------------------- 205s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 205s -------------------------------- live log call --------------------------------- 205s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 205s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (1/4) 205s DEBUG habluetooth.scanner:scanner.py:418 hci0 (AA:BB:CC:DD:EE:FF): Success while starting bluetooth; attempt: (1/4) 205s DEBUG bleak_retry_connector.bleak_manager:bleak_manager.py:60 get_global_bluez_manager_with_timeout failed: [org.freedesktop.systemd1.NoSuchUnit] Unit dbus-org.bluez.service not found. 205s Traceback (most recent call last): 205s File "/usr/lib/python3/dist-packages/bleak_retry_connector/bleak_manager.py", line 46, in get_global_bluez_manager_with_timeout 205s return await get_global_bluez_manager() 205s ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 205s File "/usr/lib/python3/dist-packages/bleak/backends/bluezdbus/manager.py", line 1131, in get_global_bluez_manager 205s await instance.async_init() 205s File "/usr/lib/python3/dist-packages/bleak/backends/bluezdbus/manager.py", line 301, in async_init 205s assert_reply(reply) 205s ~~~~~~~~~~~~^^^^^^^ 205s File "/usr/lib/python3/dist-packages/bleak/backends/bluezdbus/utils.py", line 27, in assert_reply 205s raise BleakDBusError(reply.error_name, reply.body) 205s bleak.exc.BleakDBusError: [org.freedesktop.systemd1.NoSuchUnit] Unit dbus-org.bluez.service not found. 205s DEBUG bleak_retry_connector:__init__.py:639 Failed to restore discoveries for hci0 205s DEBUG habluetooth.base_scanner:base_scanner.py:210 hci0 (AA:BB:CC:DD:EE:FF): Scanner watchdog time_since_last_detection: 10.0 205s DEBUG habluetooth.base_scanner:base_scanner.py:210 hci0 (AA:BB:CC:DD:EE:FF): Scanner watchdog time_since_last_detection: 20.0 205s DEBUG habluetooth.base_scanner:base_scanner.py:210 hci0 (AA:BB:CC:DD:EE:FF): Scanner watchdog time_since_last_detection: 120.0 205s DEBUG habluetooth.scanner:scanner.py:517 hci0 (AA:BB:CC:DD:EE:FF): Bluetooth scanner has gone quiet for 120.0s, restarting 205s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 205s DEBUG habluetooth.scanner:scanner.py:555 hci0 (AA:BB:CC:DD:EE:FF): adapter stopped responding; executing reset 205s DEBUG habluetooth.scanner:scanner.py:557 hci0 (AA:BB:CC:DD:EE:FF): adapter reset result: True 205s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 205s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (1/4) 205s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 205s DEBUG habluetooth.scanner:scanner.py:434 hci0 (AA:BB:CC:DD:EE:FF): BleakError while starting bluetooth; attempt: (1/4): Failed to start 205s Traceback (most recent call last): 205s File "/usr/lib/python3/dist-packages/habluetooth/scanner.py", line 344, in _async_start_attempt 205s await self.scanner.start() 205s File "/tmp/autopkgtest.YDvXY4/autopkgtest_tmp/build/tests/test_scanner.py", line 485, in start 205s raise BleakError("Failed to start") 205s bleak.exc.BleakError: Failed to start 205s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 205s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (2/4) 205s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 205s DEBUG habluetooth.scanner:scanner.py:434 hci0 (AA:BB:CC:DD:EE:FF): BleakError while starting bluetooth; attempt: (2/4): Failed to start 205s Traceback (most recent call last): 205s File "/usr/lib/python3/dist-packages/habluetooth/scanner.py", line 344, in _async_start_attempt 205s await self.scanner.start() 205s File "/tmp/autopkgtest.YDvXY4/autopkgtest_tmp/build/tests/test_scanner.py", line 485, in start 205s raise BleakError("Failed to start") 205s bleak.exc.BleakError: Failed to start 205s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 205s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (3/4) 205s DEBUG habluetooth.scanner:scanner.py:418 hci0 (AA:BB:CC:DD:EE:FF): Success while starting bluetooth; attempt: (3/4) 205s DEBUG bleak_retry_connector:__init__.py:639 Failed to restore discoveries for hci0 205s DEBUG habluetooth.base_scanner:base_scanner.py:210 hci0 (AA:BB:CC:DD:EE:FF): Scanner watchdog time_since_last_detection: 90.01277442101855 205s DEBUG habluetooth.scanner:scanner.py:517 hci0 (AA:BB:CC:DD:EE:FF): Bluetooth scanner has gone quiet for 90.01277442101855s, restarting 205s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 205s DEBUG habluetooth.scanner:scanner.py:555 hci0 (AA:BB:CC:DD:EE:FF): adapter stopped responding; executing reset 205s DEBUG habluetooth.scanner:scanner.py:557 hci0 (AA:BB:CC:DD:EE:FF): adapter reset result: True 205s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 205s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (1/4) 205s DEBUG habluetooth.scanner:scanner.py:418 hci0 (AA:BB:CC:DD:EE:FF): Success while starting bluetooth; attempt: (1/4) 205s DEBUG bleak_retry_connector:__init__.py:639 Failed to restore discoveries for hci0 205s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 205s PASSED [ 65%] 205s tests/test_scanner.py::test_adapter_fails_to_start_and_takes_a_bit_to_init 205s -------------------------------- live log setup -------------------------------- 205s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 205s -------------------------------- live log call --------------------------------- 205s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 205s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (1/4) 205s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 205s DEBUG habluetooth.scanner:scanner.py:402 hci0 (AA:BB:CC:DD:EE:FF): Waiting for adapter to initialize; attempt (1/4) 205s 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 205s Traceback (most recent call last): 205s File "/usr/lib/python3/dist-packages/habluetooth/scanner.py", line 344, in _async_start_attempt 205s await self.scanner.start() 205s File "/tmp/autopkgtest.YDvXY4/autopkgtest_tmp/build/tests/test_scanner.py", line 599, in start 205s raise BleakError("org.freedesktop.DBus.Error.UnknownObject") 205s bleak.exc.BleakError: org.freedesktop.DBus.Error.UnknownObject 205s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 205s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (2/4) 205s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 205s DEBUG habluetooth.scanner:scanner.py:586 hci0 (AA:BB:CC:DD:EE:FF): Force stopping bluetooth discovery 205s DEBUG bleak_retry_connector.bleak_manager:bleak_manager.py:60 get_global_bluez_manager_with_timeout failed: [org.freedesktop.systemd1.NoSuchUnit] Unit dbus-org.bluez.service not found. 205s Traceback (most recent call last): 205s File "/usr/lib/python3/dist-packages/habluetooth/scanner.py", line 344, in _async_start_attempt 205s await self.scanner.start() 205s File "/tmp/autopkgtest.YDvXY4/autopkgtest_tmp/build/tests/test_scanner.py", line 601, in start 205s raise BleakError("org.bluez.Error.InProgress") 205s bleak.exc.BleakError: org.bluez.Error.InProgress 205s 205s During handling of the above exception, another exception occurred: 205s 205s Traceback (most recent call last): 205s File "/usr/lib/python3/dist-packages/bleak_retry_connector/bleak_manager.py", line 46, in get_global_bluez_manager_with_timeout 205s return await get_global_bluez_manager() 205s ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 205s File "/usr/lib/python3/dist-packages/bleak/backends/bluezdbus/manager.py", line 1131, in get_global_bluez_manager 205s await instance.async_init() 205s File "/usr/lib/python3/dist-packages/bleak/backends/bluezdbus/manager.py", line 301, in async_init 205s assert_reply(reply) 205s ~~~~~~~~~~~~^^^^^^^ 205s File "/usr/lib/python3/dist-packages/bleak/backends/bluezdbus/utils.py", line 27, in assert_reply 205s raise BleakDBusError(reply.error_name, reply.body) 205s bleak.exc.BleakDBusError: [org.freedesktop.systemd1.NoSuchUnit] Unit dbus-org.bluez.service not found. 205s ERROR bleak_retry_connector.bluez:bluez.py:324 Failed to stop discovery for hci0 because no manager 205s DEBUG habluetooth.scanner:scanner.py:555 hci0 (AA:BB:CC:DD:EE:FF): adapter stopped responding; executing reset 205s DEBUG habluetooth.scanner:scanner.py:557 hci0 (AA:BB:CC:DD:EE:FF): adapter reset result: True 205s DEBUG habluetooth.scanner:scanner.py:434 hci0 (AA:BB:CC:DD:EE:FF): BleakError while starting bluetooth; attempt: (2/4): org.bluez.Error.InProgress 205s Traceback (most recent call last): 205s File "/usr/lib/python3/dist-packages/habluetooth/scanner.py", line 344, in _async_start_attempt 205s await self.scanner.start() 205s File "/tmp/autopkgtest.YDvXY4/autopkgtest_tmp/build/tests/test_scanner.py", line 601, in start 205s raise BleakError("org.bluez.Error.InProgress") 205s bleak.exc.BleakError: org.bluez.Error.InProgress 205s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 205s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (3/4) 205s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 205s DEBUG habluetooth.scanner:scanner.py:586 hci0 (AA:BB:CC:DD:EE:FF): Force stopping bluetooth discovery 205s ERROR habluetooth.scanner:scanner.py:593 hci0 (AA:BB:CC:DD:EE:FF): Failed to force stop scanner: 'NoneType' object has no attribute 'send' 205s DEBUG habluetooth.scanner:scanner.py:434 hci0 (AA:BB:CC:DD:EE:FF): BleakError while starting bluetooth; attempt: (3/4): org.bluez.Error.InProgress 205s Traceback (most recent call last): 205s File "/usr/lib/python3/dist-packages/habluetooth/scanner.py", line 344, in _async_start_attempt 205s await self.scanner.start() 205s File "/tmp/autopkgtest.YDvXY4/autopkgtest_tmp/build/tests/test_scanner.py", line 603, in start 205s raise BleakError("org.bluez.Error.InProgress") 205s bleak.exc.BleakError: org.bluez.Error.InProgress 205s 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) 205s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'passive', 'adapter': 'hci0', 'bluez': } 205s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (4/4) 205s 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) 205s DEBUG habluetooth.scanner:scanner.py:418 hci0 (AA:BB:CC:DD:EE:FF): Success while starting bluetooth; attempt: (4/4) 205s DEBUG bleak_retry_connector:__init__.py:639 Failed to restore discoveries for hci0 205s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 205s PASSED [ 66%] 205s tests/test_scanner.py::test_restart_takes_longer_than_watchdog_time 205s -------------------------------- live log setup -------------------------------- 205s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 205s -------------------------------- live log call --------------------------------- 205s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 205s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (1/4) 205s DEBUG habluetooth.scanner:scanner.py:418 hci0 (AA:BB:CC:DD:EE:FF): Success while starting bluetooth; attempt: (1/4) 205s DEBUG bleak_retry_connector.bleak_manager:bleak_manager.py:60 get_global_bluez_manager_with_timeout failed: [org.freedesktop.systemd1.NoSuchUnit] Unit dbus-org.bluez.service not found. 205s Traceback (most recent call last): 205s File "/usr/lib/python3/dist-packages/bleak_retry_connector/bleak_manager.py", line 46, in get_global_bluez_manager_with_timeout 205s return await get_global_bluez_manager() 205s ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 205s File "/usr/lib/python3/dist-packages/bleak/backends/bluezdbus/manager.py", line 1131, in get_global_bluez_manager 205s await instance.async_init() 205s File "/usr/lib/python3/dist-packages/bleak/backends/bluezdbus/manager.py", line 301, in async_init 205s assert_reply(reply) 205s ~~~~~~~~~~~~^^^^^^^ 205s File "/usr/lib/python3/dist-packages/bleak/backends/bluezdbus/utils.py", line 27, in assert_reply 205s raise BleakDBusError(reply.error_name, reply.body) 205s bleak.exc.BleakDBusError: [org.freedesktop.systemd1.NoSuchUnit] Unit dbus-org.bluez.service not found. 205s DEBUG bleak_retry_connector:__init__.py:639 Failed to restore discoveries for hci0 205s DEBUG habluetooth.base_scanner:base_scanner.py:210 hci0 (AA:BB:CC:DD:EE:FF): Scanner watchdog time_since_last_detection: 120.0 205s DEBUG habluetooth.scanner:scanner.py:517 hci0 (AA:BB:CC:DD:EE:FF): Bluetooth scanner has gone quiet for 120.0s, restarting 205s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 205s DEBUG habluetooth.scanner:scanner.py:555 hci0 (AA:BB:CC:DD:EE:FF): adapter stopped responding; executing reset 205s DEBUG habluetooth.scanner:scanner.py:557 hci0 (AA:BB:CC:DD:EE:FF): adapter reset result: True 205s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 205s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (1/4) 205s DEBUG habluetooth.base_scanner:base_scanner.py:210 hci0 (AA:BB:CC:DD:EE:FF): Scanner watchdog time_since_last_detection: 120.0 205s DEBUG habluetooth.scanner:scanner.py:512 hci0 (AA:BB:CC:DD:EE:FF): Scanner is already restarting, deferring restart 205s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 205s DEBUG habluetooth.scanner:scanner.py:426 hci0 (AA:BB:CC:DD:EE:FF): TimeoutError while starting bluetooth; attempt: (1/4) 205s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 205s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (2/4) 205s DEBUG habluetooth.scanner:scanner.py:418 hci0 (AA:BB:CC:DD:EE:FF): Success while starting bluetooth; attempt: (2/4) 205s DEBUG bleak_retry_connector:__init__.py:639 Failed to restore discoveries for hci0 205s ERROR asyncio:base_events.py:1875 Future exception was never retrieved 205s future: 205s habluetooth.scanner._AbortStartError 205s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 205s PASSED [ 67%] 205s tests/test_scanner.py::test_setup_and_stop_macos SKIPPED (condition:...) [ 68%] 205s tests/test_scanner.py::test_adapter_init_fails_fallback_to_passive 205s -------------------------------- live log setup -------------------------------- 205s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 205s -------------------------------- live log call --------------------------------- 205s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 205s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (1/4) 205s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 205s DEBUG habluetooth.scanner:scanner.py:402 hci0 (AA:BB:CC:DD:EE:FF): Waiting for adapter to initialize; attempt (1/4) 205s 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 205s Traceback (most recent call last): 205s File "/usr/lib/python3/dist-packages/habluetooth/scanner.py", line 344, in _async_start_attempt 205s await self.scanner.start() 205s File "/tmp/autopkgtest.YDvXY4/autopkgtest_tmp/build/tests/test_scanner.py", line 780, in start 205s raise BleakError("org.freedesktop.DBus.Error.UnknownObject") 205s bleak.exc.BleakError: org.freedesktop.DBus.Error.UnknownObject 205s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 205s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (2/4) 205s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 205s DEBUG habluetooth.scanner:scanner.py:586 hci0 (AA:BB:CC:DD:EE:FF): Force stopping bluetooth discovery 205s DEBUG bleak_retry_connector.bleak_manager:bleak_manager.py:60 get_global_bluez_manager_with_timeout failed: [org.freedesktop.systemd1.NoSuchUnit] Unit dbus-org.bluez.service not found. 205s Traceback (most recent call last): 205s File "/usr/lib/python3/dist-packages/habluetooth/scanner.py", line 344, in _async_start_attempt 205s await self.scanner.start() 205s File "/tmp/autopkgtest.YDvXY4/autopkgtest_tmp/build/tests/test_scanner.py", line 782, in start 205s raise BleakError("org.bluez.Error.InProgress") 205s bleak.exc.BleakError: org.bluez.Error.InProgress 205s 205s During handling of the above exception, another exception occurred: 205s 205s Traceback (most recent call last): 205s File "/usr/lib/python3/dist-packages/bleak_retry_connector/bleak_manager.py", line 46, in get_global_bluez_manager_with_timeout 205s return await get_global_bluez_manager() 205s ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 205s File "/usr/lib/python3/dist-packages/bleak/backends/bluezdbus/manager.py", line 1131, in get_global_bluez_manager 205s await instance.async_init() 205s File "/usr/lib/python3/dist-packages/bleak/backends/bluezdbus/manager.py", line 301, in async_init 205s assert_reply(reply) 205s ~~~~~~~~~~~~^^^^^^^ 205s File "/usr/lib/python3/dist-packages/bleak/backends/bluezdbus/utils.py", line 27, in assert_reply 205s raise BleakDBusError(reply.error_name, reply.body) 205s bleak.exc.BleakDBusError: [org.freedesktop.systemd1.NoSuchUnit] Unit dbus-org.bluez.service not found. 205s ERROR bleak_retry_connector.bluez:bluez.py:324 Failed to stop discovery for hci0 because no manager 205s DEBUG habluetooth.scanner:scanner.py:555 hci0 (AA:BB:CC:DD:EE:FF): adapter stopped responding; executing reset 205s DEBUG habluetooth.scanner:scanner.py:557 hci0 (AA:BB:CC:DD:EE:FF): adapter reset result: True 205s DEBUG habluetooth.scanner:scanner.py:434 hci0 (AA:BB:CC:DD:EE:FF): BleakError while starting bluetooth; attempt: (2/4): org.bluez.Error.InProgress 205s Traceback (most recent call last): 205s File "/usr/lib/python3/dist-packages/habluetooth/scanner.py", line 344, in _async_start_attempt 205s await self.scanner.start() 205s File "/tmp/autopkgtest.YDvXY4/autopkgtest_tmp/build/tests/test_scanner.py", line 782, in start 205s raise BleakError("org.bluez.Error.InProgress") 205s bleak.exc.BleakError: org.bluez.Error.InProgress 205s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 205s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (3/4) 205s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 205s DEBUG habluetooth.scanner:scanner.py:586 hci0 (AA:BB:CC:DD:EE:FF): Force stopping bluetooth discovery 205s ERROR habluetooth.scanner:scanner.py:593 hci0 (AA:BB:CC:DD:EE:FF): Failed to force stop scanner: 'NoneType' object has no attribute 'send' 205s DEBUG habluetooth.scanner:scanner.py:434 hci0 (AA:BB:CC:DD:EE:FF): BleakError while starting bluetooth; attempt: (3/4): org.bluez.Error.InProgress 205s Traceback (most recent call last): 205s File "/usr/lib/python3/dist-packages/habluetooth/scanner.py", line 344, in _async_start_attempt 205s await self.scanner.start() 205s File "/tmp/autopkgtest.YDvXY4/autopkgtest_tmp/build/tests/test_scanner.py", line 784, in start 205s raise BleakError("org.bluez.Error.InProgress") 205s bleak.exc.BleakError: org.bluez.Error.InProgress 205s 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) 205s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'passive', 'adapter': 'hci0', 'bluez': } 205s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (4/4) 205s 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) 205s DEBUG habluetooth.scanner:scanner.py:418 hci0 (AA:BB:CC:DD:EE:FF): Success while starting bluetooth; attempt: (4/4) 205s DEBUG bleak_retry_connector:__init__.py:639 Failed to restore discoveries for hci0 205s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 205s PASSED [ 70%] 205s tests/test_storage.py::test_discovered_device_advertisement_data_to_dict PASSED [ 71%] 205s tests/test_storage.py::test_discovered_device_advertisement_data_from_dict PASSED [ 72%] 205s tests/test_storage.py::test_expire_stale_scanner_discovered_device_advertisement_data 205s -------------------------------- live log call --------------------------------- 205s DEBUG habluetooth.storage:storage.py:122 Loaded 1 fresh discovered devices for myscanner 205s DEBUG habluetooth.storage:storage.py:122 Loaded 0 fresh discovered devices for all_expired 205s PASSED [ 73%] 205s tests/test_storage.py::test_expire_future_discovered_device_advertisement_data 205s -------------------------------- live log call --------------------------------- 205s DEBUG habluetooth.storage:storage.py:122 Loaded 1 fresh discovered devices for myscanner 205s WARNING habluetooth.storage:storage.py:107 Discarding timestamp 1765757498.035432 for CC:DD:EE:FF:AA:BB on scanner all_future as it is the future (now = 1764757498.0354466) 205s DEBUG habluetooth.storage:storage.py:122 Loaded 0 fresh discovered devices for all_future 205s PASSED [ 74%] 205s tests/test_storage.py::test_discovered_device_advertisement_data_from_dict_corrupt 205s -------------------------------- live log call --------------------------------- 205s 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' 205s Traceback (most recent call last): 205s File "/usr/lib/python3/dist-packages/habluetooth/storage.py", line 138, in discovered_device_advertisement_data_from_dict 205s _deserialize_discovered_device_advertisement_datas( 205s ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~^ 205s data[DISCOVERED_DEVICE_ADVERTISEMENT_DATAS] 205s ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 205s ), 205s ^ 205s File "/usr/lib/python3/dist-packages/habluetooth/storage.py", line 198, in _deserialize_discovered_device_advertisement_datas 205s _ble_device_from_dict(device_advertisement_data["device"]), 205s ~~~~~~~~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 205s File "/usr/lib/python3/dist-packages/habluetooth/storage.py", line 215, in _ble_device_from_dict 205s return BLEDevice(**device_data) 205s TypeError: BLEDevice.__init__() missing 1 required positional argument: 'name' 205s PASSED [ 75%] 205s tests/test_storage.py::test_backward_compatibility_rssi_in_device_dict PASSED [ 76%] 205s tests/test_wrappers.py::test_test_switch_adapters_when_out_of_slots 205s -------------------------------- live log setup -------------------------------- 205s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 205s -------------------------------- live log call --------------------------------- 205s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (00:00:00:00:00:01) 205s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci1 (00:00:00:00:00:02) 205s DEBUG habluetooth.wrappers:wrappers.py:279 00:00:00:00:00:01: Looking for backend to connect 205s 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) 205s 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) 205s 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) 205s DEBUG habluetooth.wrappers:wrappers.py:279 00:00:00:00:00:02: Looking for backend to connect 205s 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) 205s DEBUG habluetooth.wrappers:wrappers.py:279 00:00:00:00:00:03: Looking for backend to connect 205s 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) 205s 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) 205s 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) 205s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (00:00:00:00:00:01) 205s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci1 (00:00:00:00:00:02) 205s PASSED [ 77%] 205s tests/test_wrappers.py::test_release_slot_on_connect_failure 205s -------------------------------- live log setup -------------------------------- 205s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 205s -------------------------------- live log call --------------------------------- 205s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (00:00:00:00:00:01) 205s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci1 (00:00:00:00:00:02) 205s DEBUG habluetooth.wrappers:wrappers.py:279 00:00:00:00:00:01: Looking for backend to connect 205s 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) 205s 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) 205s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (00:00:00:00:00:01) 205s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci1 (00:00:00:00:00:02) 205s PASSED [ 78%] 205s tests/test_wrappers.py::test_release_slot_on_connect_exception 205s -------------------------------- live log setup -------------------------------- 205s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 205s -------------------------------- live log call --------------------------------- 205s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (00:00:00:00:00:01) 205s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci1 (00:00:00:00:00:02) 205s DEBUG habluetooth.wrappers:wrappers.py:279 00:00:00:00:00:01: Looking for backend to connect 205s 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) 205s 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) 205s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (00:00:00:00:00:01) 205s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci1 (00:00:00:00:00:02) 205s PASSED [ 80%] 205s tests/test_wrappers.py::test_switch_adapters_on_failure 205s -------------------------------- live log setup -------------------------------- 205s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 205s -------------------------------- live log call --------------------------------- 205s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (00:00:00:00:00:01) 205s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci1 (00:00:00:00:00:02) 205s DEBUG habluetooth.wrappers:wrappers.py:279 00:00:00:00:00:01: Looking for backend to connect 205s 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) 205s 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) 205s DEBUG habluetooth.wrappers:wrappers.py:279 00:00:00:00:00:01: Looking for backend to connect 205s 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) 205s 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) 205s DEBUG habluetooth.wrappers:wrappers.py:279 00:00:00:00:00:01: Looking for backend to connect 205s 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) 205s 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) 205s 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) 205s DEBUG habluetooth.wrappers:wrappers.py:279 00:00:00:00:00:01: Looking for backend to connect 205s 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) 205s 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) 205s 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) 205s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (00:00:00:00:00:01) 205s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci1 (00:00:00:00:00:02) 205s PASSED [ 81%] 205s tests/test_wrappers.py::test_switch_adapters_on_connecting 205s -------------------------------- live log setup -------------------------------- 205s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 205s -------------------------------- live log call --------------------------------- 205s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (00:00:00:00:00:01) 205s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci1 (00:00:00:00:00:02) 205s DEBUG habluetooth.wrappers:wrappers.py:279 00:00:00:00:00:01: Looking for backend to connect 205s 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) 205s 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) 205s DEBUG habluetooth.wrappers:wrappers.py:279 00:00:00:00:00:01: Looking for backend to connect 205s 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) 205s 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) 205s 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) 205s 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) 205s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (00:00:00:00:00:01) 205s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci1 (00:00:00:00:00:02) 205s PASSED [ 82%] 205s tests/test_wrappers.py::test_single_adapter_connection_history 205s -------------------------------- live log setup -------------------------------- 205s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 205s -------------------------------- live log call --------------------------------- 205s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (AA:BB:CC:DD:EE:00) 205s DEBUG habluetooth.wrappers:wrappers.py:279 00:00:00:00:00:11: Looking for backend to connect 205s 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) 205s 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) 205s 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) 205s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (AA:BB:CC:DD:EE:00) 205s PASSED [ 83%] 205s tests/test_wrappers.py::test_passing_subclassed_str_as_address 205s -------------------------------- live log setup -------------------------------- 205s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 205s -------------------------------- live log call --------------------------------- 205s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (00:00:00:00:00:01) 205s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci1 (00:00:00:00:00:02) 205s DEBUG habluetooth.wrappers:wrappers.py:279 00:00:00:00:00:01: Looking for backend to connect 205s 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) 205s 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) 205s 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) 205s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (00:00:00:00:00:01) 205s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci1 (00:00:00:00:00:02) 205s PASSED [ 84%] 205s tests/test_wrappers.py::test_find_device_by_address 205s -------------------------------- live log setup -------------------------------- 205s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 205s -------------------------------- live log call --------------------------------- 205s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (00:00:00:00:00:01) 205s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci1 (00:00:00:00:00:02) 205s PASSED [ 85%] 205s tests/test_wrappers.py::test_discover 205s -------------------------------- live log setup -------------------------------- 205s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 205s -------------------------------- live log call --------------------------------- 205s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (00:00:00:00:00:01) 205s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci1 (00:00:00:00:00:02) 205s PASSED [ 86%] 205s tests/test_wrappers.py::test_raise_after_shutdown 205s -------------------------------- live log setup -------------------------------- 205s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 205s -------------------------------- live log call --------------------------------- 205s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (00:00:00:00:00:01) 205s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci1 (00:00:00:00:00:02) 205s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (00:00:00:00:00:01) 205s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci1 (00:00:00:00:00:02) 205s PASSED [ 87%] 205s tests/test_wrappers.py::test_wrapped_instance_with_filter 205s -------------------------------- live log setup -------------------------------- 205s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 205s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (AA:BB:CC:DD:EE:00) 205s PASSED [ 88%] 205s ------------------------------ live log teardown ------------------------------- 205s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (AA:BB:CC:DD:EE:00) 205s 205s tests/test_wrappers.py::test_wrapped_instance_with_service_uuids 205s -------------------------------- live log setup -------------------------------- 205s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 205s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (AA:BB:CC:DD:EE:00) 205s PASSED [ 90%] 205s ------------------------------ live log teardown ------------------------------- 205s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (AA:BB:CC:DD:EE:00) 205s 205s tests/test_wrappers.py::test_wrapped_instance_with_service_uuids_with_coro_callback 205s -------------------------------- live log setup -------------------------------- 205s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 205s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (AA:BB:CC:DD:EE:00) 205s PASSED [ 91%] 205s ------------------------------ live log teardown ------------------------------- 205s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (AA:BB:CC:DD:EE:00) 205s 205s tests/test_wrappers.py::test_wrapped_instance_with_broken_callbacks 205s -------------------------------- live log setup -------------------------------- 205s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 205s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (AA:BB:CC:DD:EE:00) 205s PASSED [ 92%] 205s ------------------------------ live log teardown ------------------------------- 205s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (AA:BB:CC:DD:EE:00) 205s 205s tests/test_wrappers.py::test_wrapped_instance_changes_uuids 205s -------------------------------- live log setup -------------------------------- 205s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 205s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (AA:BB:CC:DD:EE:00) 205s PASSED [ 93%] 205s ------------------------------ live log teardown ------------------------------- 205s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (AA:BB:CC:DD:EE:00) 205s 205s tests/test_wrappers.py::test_wrapped_instance_changes_filters 205s -------------------------------- live log setup -------------------------------- 205s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 205s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (AA:BB:CC:DD:EE:00) 205s PASSED [ 94%] 205s ------------------------------ live log teardown ------------------------------- 205s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (AA:BB:CC:DD:EE:00) 205s 205s tests/test_wrappers.py::test_wrapped_instance_unsupported_filter 205s -------------------------------- live log setup -------------------------------- 205s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 205s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (AA:BB:CC:DD:EE:00) 205s -------------------------------- live log call --------------------------------- 205s WARNING habluetooth.wrappers:wrappers.py:120 Only UUIDs filters are supported 205s PASSED [ 95%] 205s ------------------------------ live log teardown ------------------------------- 205s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (AA:BB:CC:DD:EE:00) 205s 205s tests/test_wrappers.py::test_client_with_services_parameter 205s -------------------------------- live log setup -------------------------------- 205s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 205s -------------------------------- live log call --------------------------------- 205s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (00:00:00:00:00:01) 205s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci1 (00:00:00:00:00:02) 205s DEBUG habluetooth.wrappers:wrappers.py:279 00:00:00:00:00:01: Looking for backend to connect 205s 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) 205s 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) 205s 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) 205s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (00:00:00:00:00:01) 205s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci1 (00:00:00:00:00:02) 205s PASSED [ 96%] 205s tests/test_wrappers.py::test_client_with_pair_parameter 205s -------------------------------- live log setup -------------------------------- 205s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 205s -------------------------------- live log call --------------------------------- 205s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (00:00:00:00:00:01) 205s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci1 (00:00:00:00:00:02) 205s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (00:00:00:00:00:01) 205s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci1 (00:00:00:00:00:02) 205s PASSED [ 97%] 205s tests/test_wrappers.py::test_client_services_normalization 205s -------------------------------- live log setup -------------------------------- 205s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 205s -------------------------------- live log call --------------------------------- 205s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (00:00:00:00:00:01) 205s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci1 (00:00:00:00:00:02) 205s DEBUG habluetooth.wrappers:wrappers.py:279 00:00:00:00:00:01: Looking for backend to connect 205s 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) 205s 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) 205s 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) 205s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (00:00:00:00:00:01) 205s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci1 (00:00:00:00:00:02) 205s PASSED [ 98%] 205s tests/test_wrappers.py::test_client_with_none_services 205s -------------------------------- live log setup -------------------------------- 205s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 205s -------------------------------- live log call --------------------------------- 205s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (00:00:00:00:00:01) 205s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci1 (00:00:00:00:00:02) 205s DEBUG habluetooth.wrappers:wrappers.py:279 00:00:00:00:00:01: Looking for backend to connect 205s 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) 205s 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) 205s 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) 205s DEBUG habluetooth.wrappers:wrappers.py:279 00:00:00:00:00:01: Looking for backend to connect 205s 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) 205s 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) 205s 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) 205s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (00:00:00:00:00:01) 205s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci1 (00:00:00:00:00:02) 205s PASSED [100%] 205s ------------------------------ live log teardown ------------------------------- 205s DEBUG habluetooth.manager:manager.py:322 Stopping bluetooth manager 205s 205s 205s =============================== warnings summary =============================== 205s tests/test_base_scanner.py::test_remote_scanner[None] 205s /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 205s return asyncio.get_event_loop_policy() 205s 205s tests/test_base_scanner.py: 10 warnings 205s tests/test_manager.py: 20 warnings 205s tests/test_scanner.py: 18 warnings 205s tests/test_wrappers.py: 21 warnings 205s /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 205s old_loop_policy = asyncio.get_event_loop_policy() 205s 205s tests/test_base_scanner.py: 10 warnings 205s tests/test_manager.py: 20 warnings 205s tests/test_scanner.py: 18 warnings 205s tests/test_wrappers.py: 21 warnings 205s /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 205s asyncio.set_event_loop_policy(policy) 205s 205s tests/test_base_scanner.py: 9 warnings 205s tests/test_manager.py: 20 warnings 205s tests/test_scanner.py: 18 warnings 205s tests/test_wrappers.py: 21 warnings 205s /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 205s _restore_event_loop_policy(asyncio.get_event_loop_policy()), 205s 205s tests/test_base_scanner.py: 9 warnings 205s tests/test_manager.py: 20 warnings 205s tests/test_scanner.py: 18 warnings 205s tests/test_wrappers.py: 21 warnings 205s /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 205s loop = asyncio.get_event_loop_policy().new_event_loop() 205s 205s tests/test_base_scanner.py: 9 warnings 205s tests/test_manager.py: 20 warnings 205s tests/test_scanner.py: 18 warnings 205s tests/test_wrappers.py: 21 warnings 205s /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 205s policy = asyncio.get_event_loop_policy() 205s 205s tests/test_base_scanner.py: 9 warnings 205s tests/test_manager.py: 3 warnings 205s tests/test_wrappers.py: 14 warnings 205s /tmp/autopkgtest.YDvXY4/autopkgtest_tmp/build/tests/__init__.py:64: DeprecationWarning: Passing additional arguments for BLEDevice is deprecated and has no effect. 205s return BLEDevice(**new) 205s 205s tests/test_base_scanner.py: 9 warnings 205s tests/test_manager.py: 20 warnings 205s tests/test_scanner.py: 18 warnings 205s tests/test_wrappers.py: 22 warnings 205s /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 205s asyncio.set_event_loop_policy(old_loop_policy) 205s 205s tests/test_base_scanner.py: 9 warnings 205s tests/test_manager.py: 20 warnings 205s tests/test_scanner.py: 18 warnings 205s tests/test_wrappers.py: 21 warnings 205s /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 205s policy = asyncio.get_event_loop_policy() 205s 205s tests/test_base_scanner.py: 9 warnings 205s tests/test_manager.py: 20 warnings 205s tests/test_scanner.py: 18 warnings 205s tests/test_wrappers.py: 21 warnings 205s /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 205s asyncio.set_event_loop_policy(previous_policy) 205s 205s tests/test_base_scanner.py: 9 warnings 205s tests/test_manager.py: 20 warnings 205s tests/test_scanner.py: 18 warnings 205s tests/test_wrappers.py: 21 warnings 205s /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 205s policy = asyncio.get_event_loop_policy() 205s 205s tests/test_base_scanner.py::test_remote_scanner_expires_non_connectable 205s /tmp/autopkgtest.YDvXY4/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 205s assert len(scanner._discovered_device_timestamps) == 1 205s 205s tests/test_base_scanner.py::test_remote_scanner_expires_non_connectable 205s /tmp/autopkgtest.YDvXY4/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 205s assert len(scanner._discovered_device_timestamps) == 0 205s 205s tests/test_base_scanner.py::test_remote_scanner_expires_non_connectable 205s /tmp/autopkgtest.YDvXY4/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 205s assert len(scanner._discovered_device_timestamps) == 0 205s 205s -- Docs: https://docs.pytest.org/en/stable/how-to/capture-warnings.html 205s ================= 89 passed, 1 skipped, 645 warnings in 1.28s ================== 205s I: pybuild base:317: cd /tmp/autopkgtest.YDvXY4/autopkgtest_tmp/build; python3.13 -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.13.9, pytest-8.3.5, pluggy-1.6.0 -- /usr/bin/python3.13 206s cachedir: .pytest_cache 206s rootdir: /tmp/autopkgtest.YDvXY4/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 206s collecting ... collected 90 items 206s 206s tests/test_base_scanner.py::test_remote_scanner[None] 206s -------------------------------- live log setup -------------------------------- 206s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 206s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 206s WARNING habluetooth.manager:manager.py:189 BluetoothManager: does not implement _discover_service_info, subclasses must implement this method to consume discovery data 206s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 206s -------------------------------- live log call --------------------------------- 206s DEBUG habluetooth.manager:manager.py:765 Registering scanner esp32 206s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner esp32 206s PASSED [ 1%] 206s tests/test_base_scanner.py::test_remote_scanner[w] 206s -------------------------------- live log setup -------------------------------- 206s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 206s -------------------------------- live log call --------------------------------- 206s DEBUG habluetooth.manager:manager.py:765 Registering scanner esp32 206s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner esp32 206s PASSED [ 2%] 206s tests/test_base_scanner.py::test_remote_scanner_expires_connectable 206s -------------------------------- live log setup -------------------------------- 206s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 206s -------------------------------- live log call --------------------------------- 206s DEBUG habluetooth.manager:manager.py:765 Registering scanner esp32 206s DEBUG habluetooth.base_scanner:base_scanner.py:210 esp32: Scanner watchdog time_since_last_detection: 196.0010910279816 206s DEBUG habluetooth.base_scanner:base_scanner.py:225 esp32: Bluetooth scanner has gone quiet for 196.0010910279816s, check logs on the scanner device for more information 206s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner esp32 206s PASSED [ 3%] 206s tests/test_base_scanner.py::test_remote_scanner_expires_non_connectable 206s -------------------------------- live log setup -------------------------------- 206s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 206s -------------------------------- live log call --------------------------------- 206s DEBUG habluetooth.manager:manager.py:765 Registering scanner esp32 206s DEBUG habluetooth.base_scanner:base_scanner.py:210 esp32: Scanner watchdog time_since_last_detection: 196.00026639690623 206s DEBUG habluetooth.base_scanner:base_scanner.py:225 esp32: Bluetooth scanner has gone quiet for 196.00026639690623s, check logs on the scanner device for more information 206s DEBUG habluetooth.base_scanner:base_scanner.py:210 esp32: Scanner watchdog time_since_last_detection: 901.0002663969062 206s DEBUG habluetooth.base_scanner:base_scanner.py:225 esp32: Bluetooth scanner has gone quiet for 901.0002663969062s, check logs on the scanner device for more information 206s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner esp32 206s PASSED [ 4%] 206s tests/test_base_scanner.py::test_base_scanner_connecting_behavior 206s -------------------------------- live log setup -------------------------------- 206s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 206s -------------------------------- live log call --------------------------------- 206s DEBUG habluetooth.manager:manager.py:765 Registering scanner esp32 206s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner esp32 206s PASSED [ 5%] 206s tests/test_base_scanner.py::test_scanner_stops_responding 206s -------------------------------- live log setup -------------------------------- 206s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 206s -------------------------------- live log call --------------------------------- 206s DEBUG habluetooth.manager:manager.py:765 Registering scanner esp32 206s DEBUG habluetooth.base_scanner:base_scanner.py:210 esp32: Scanner watchdog time_since_last_detection: 120.00079127203207 206s DEBUG habluetooth.base_scanner:base_scanner.py:225 esp32: Bluetooth scanner has gone quiet for 120.00079127203207s, check logs on the scanner device for more information 206s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner esp32 206s PASSED [ 6%] 206s tests/test_base_scanner.py::test_merge_manufacturer_data_history_existing 206s -------------------------------- live log setup -------------------------------- 206s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 206s -------------------------------- live log call --------------------------------- 206s DEBUG habluetooth.manager:manager.py:765 Registering scanner esp32 206s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner esp32 206s PASSED [ 7%] 206s tests/test_base_scanner.py::test_merge_manufacturer_data_history_new 206s -------------------------------- live log setup -------------------------------- 206s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 206s -------------------------------- live log call --------------------------------- 206s DEBUG habluetooth.manager:manager.py:765 Registering scanner esp32 206s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner esp32 206s PASSED [ 8%] 206s tests/test_base_scanner.py::test_filter_apple_data 206s -------------------------------- live log setup -------------------------------- 206s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 206s -------------------------------- live log call --------------------------------- 206s DEBUG habluetooth.manager:manager.py:765 Registering scanner esp32 206s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner esp32 206s PASSED [ 10%] 206s tests/test_base_scanner.py::test_connection_history_count_in_progress 206s -------------------------------- live log setup -------------------------------- 206s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (AA:BB:CC:DD:EE:00) 206s PASSED [ 11%] 206s ------------------------------ live log teardown ------------------------------- 206s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (AA:BB:CC:DD:EE:00) 206s 206s tests/test_base_scanner.py::test_connection_history_failure_count 206s -------------------------------- live log setup -------------------------------- 206s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (AA:BB:CC:DD:EE:00) 206s -------------------------------- live log call --------------------------------- 206s 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 206s PASSED [ 12%] 206s ------------------------------ live log teardown ------------------------------- 206s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (AA:BB:CC:DD:EE:00) 206s 206s tests/test_init.py::test_create_scanner 206s -------------------------------- live log setup -------------------------------- 206s WARNING habluetooth.manager:manager.py:189 BluetoothManager: does not implement _discover_service_info, subclasses must implement this method to consume discovery data 206s PASSED [ 13%] 206s tests/test_init.py::test_create_remote_scanner PASSED [ 14%] 206s tests/test_init.py::test__async_on_advertisement PASSED [ 15%] 206s tests/test_init.py::test__async_on_advertisement_first PASSED [ 16%] 206s tests/test_init.py::test__async_on_advertisement_prefers_longest_local_name PASSED [ 17%] 206s tests/test_init.py::test_create_ha_scanner PASSED [ 18%] 206s tests/test_manager.py::test_async_recover_failed_adapters 206s -------------------------------- live log setup -------------------------------- 206s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 206s -------------------------------- live log call --------------------------------- 206s WARNING habluetooth.manager:manager.py:189 BluetoothManager: does not implement _discover_service_info, subclasses must implement this method to consume discovery data 206s PASSED [ 20%] 206s tests/test_manager.py::test_create_manager 206s -------------------------------- live log setup -------------------------------- 206s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 206s -------------------------------- live log call --------------------------------- 206s WARNING habluetooth.manager:manager.py:189 BluetoothManager: does not implement _discover_service_info, subclasses must implement this method to consume discovery data 206s PASSED [ 21%] 206s tests/test_manager.py::test_async_register_disappeared_callback 206s -------------------------------- live log setup -------------------------------- 206s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 206s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (AA:BB:CC:DD:EE:00) 206s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci1 (AA:BB:CC:DD:EE:11) 206s -------------------------------- live log call --------------------------------- 206s ERROR habluetooth.manager:manager.py:425 Error in disappeared callback 206s Traceback (most recent call last): 206s File "/usr/lib/python3/dist-packages/habluetooth/manager.py", line 423, in _async_check_unavailable 206s disappear_callback(address) 206s ~~~~~~~~~~~~~~~~~~^^^^^^^^^ 206s File "/tmp/autopkgtest.YDvXY4/autopkgtest_tmp/build/tests/test_manager.py", line 188, in _failing_callback 206s raise ValueError("This is a test") 206s ValueError: This is a test 206s PASSED [ 22%] 206s ------------------------------ live log teardown ------------------------------- 206s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci1 (AA:BB:CC:DD:EE:11) 206s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (AA:BB:CC:DD:EE:00) 206s 206s tests/test_manager.py::test_async_register_allocation_callback 206s -------------------------------- live log setup -------------------------------- 206s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 206s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (AA:BB:CC:DD:EE:00) 206s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci1 (AA:BB:CC:DD:EE:11) 206s -------------------------------- live log call --------------------------------- 206s ERROR habluetooth.manager:manager.py:850 Error in allocation callback 206s Traceback (most recent call last): 206s File "/usr/lib/python3/dist-packages/habluetooth/manager.py", line 848, in async_on_allocation_changed 206s callback_(ha_slot_allocations) 206s ~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^ 206s File "/tmp/autopkgtest.YDvXY4/autopkgtest_tmp/build/tests/test_manager.py", line 259, in _failing_callback 206s raise ValueError("This is a test") 206s ValueError: This is a test 206s ERROR habluetooth.manager:manager.py:850 Error in allocation callback 206s Traceback (most recent call last): 206s File "/usr/lib/python3/dist-packages/habluetooth/manager.py", line 848, in async_on_allocation_changed 206s callback_(ha_slot_allocations) 206s ~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^ 206s File "/tmp/autopkgtest.YDvXY4/autopkgtest_tmp/build/tests/test_manager.py", line 259, in _failing_callback 206s raise ValueError("This is a test") 206s ValueError: This is a test 206s PASSED [ 23%] 206s ------------------------------ live log teardown ------------------------------- 206s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci1 (AA:BB:CC:DD:EE:11) 206s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (AA:BB:CC:DD:EE:00) 206s 206s tests/test_manager.py::test_async_register_allocation_callback_non_connectable 206s -------------------------------- live log setup -------------------------------- 206s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 206s DEBUG habluetooth.manager:manager.py:765 Registering scanner non connectable (AA:BB:CC:DD:EE:FF) 206s PASSED [ 24%] 206s ------------------------------ live log teardown ------------------------------- 206s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner non connectable (AA:BB:CC:DD:EE:FF) 206s 206s tests/test_manager.py::test_async_register_scanner_registration_callback 206s -------------------------------- live log setup -------------------------------- 206s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 206s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (AA:BB:CC:DD:EE:00) 206s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci1 (AA:BB:CC:DD:EE:11) 206s -------------------------------- live log call --------------------------------- 206s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci3 (AA:BB:CC:DD:EE:33) 206s ERROR habluetooth.manager:manager.py:867 Error in scanner callback 206s Traceback (most recent call last): 206s File "/usr/lib/python3/dist-packages/habluetooth/manager.py", line 865, in _async_on_scanner_registration 206s callback_(HaScannerRegistration(event, scanner)) 206s ~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 206s File "/tmp/autopkgtest.YDvXY4/autopkgtest_tmp/build/tests/test_manager.py", line 381, in _failing_callback 206s raise ValueError("This is a test") 206s ValueError: This is a test 206s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci3 (AA:BB:CC:DD:EE:33) 206s ERROR habluetooth.manager:manager.py:867 Error in scanner callback 206s Traceback (most recent call last): 206s File "/usr/lib/python3/dist-packages/habluetooth/manager.py", line 865, in _async_on_scanner_registration 206s callback_(HaScannerRegistration(event, scanner)) 206s ~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 206s File "/tmp/autopkgtest.YDvXY4/autopkgtest_tmp/build/tests/test_manager.py", line 381, in _failing_callback 206s raise ValueError("This is a test") 206s ValueError: This is a test 206s PASSED [ 25%] 206s ------------------------------ live log teardown ------------------------------- 206s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci1 (AA:BB:CC:DD:EE:11) 206s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (AA:BB:CC:DD:EE:00) 206s 206s tests/test_manager.py::test_async_register_scanner_with_connection_slots 206s -------------------------------- live log setup -------------------------------- 206s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 206s -------------------------------- live log call --------------------------------- 206s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci3 (AA:BB:CC:DD:EE:33) 206s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci3 (AA:BB:CC:DD:EE:33) 206s PASSED [ 26%] 206s tests/test_manager.py::test_diagnostics 206s -------------------------------- live log setup -------------------------------- 206s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 206s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (AA:BB:CC:DD:EE:00) 206s PASSED [ 27%] 206s ------------------------------ live log teardown ------------------------------- 206s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (AA:BB:CC:DD:EE:00) 206s 206s tests/test_manager.py::test_advertisements_do_not_switch_adapters_for_no_reason 206s -------------------------------- live log setup -------------------------------- 206s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 206s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (AA:BB:CC:DD:EE:00) 206s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci1 (AA:BB:CC:DD:EE:11) 206s PASSED [ 28%] 206s ------------------------------ live log teardown ------------------------------- 206s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci1 (AA:BB:CC:DD:EE:11) 206s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (AA:BB:CC:DD:EE:00) 206s 206s tests/test_manager.py::test_switching_adapters_based_on_rssi 206s -------------------------------- live log setup -------------------------------- 206s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 206s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (AA:BB:CC:DD:EE:00) 206s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci1 (AA:BB:CC:DD:EE:11) 206s -------------------------------- live log call --------------------------------- 206s 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) 206s PASSED [ 30%] 206s ------------------------------ live log teardown ------------------------------- 206s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci1 (AA:BB:CC:DD:EE:11) 206s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (AA:BB:CC:DD:EE:00) 206s 206s tests/test_manager.py::test_switching_adapters_based_on_zero_rssi 206s -------------------------------- live log setup -------------------------------- 206s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 206s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (AA:BB:CC:DD:EE:00) 206s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci1 (AA:BB:CC:DD:EE:11) 206s -------------------------------- live log call --------------------------------- 206s 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) 206s PASSED [ 31%] 206s ------------------------------ live log teardown ------------------------------- 206s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci1 (AA:BB:CC:DD:EE:11) 206s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (AA:BB:CC:DD:EE:00) 206s 206s tests/test_manager.py::test_switching_adapters_based_on_stale 206s -------------------------------- live log setup -------------------------------- 206s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 206s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (AA:BB:CC:DD:EE:00) 206s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci1 (AA:BB:CC:DD:EE:11) 206s -------------------------------- live log call --------------------------------- 206s 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) 206s PASSED [ 32%] 206s ------------------------------ live log teardown ------------------------------- 206s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci1 (AA:BB:CC:DD:EE:11) 206s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (AA:BB:CC:DD:EE:00) 206s 206s tests/test_manager.py::test_switching_adapters_based_on_stale_with_discovered_interval 206s -------------------------------- live log setup -------------------------------- 206s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 206s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (AA:BB:CC:DD:EE:00) 206s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci1 (AA:BB:CC:DD:EE:11) 206s -------------------------------- live log call --------------------------------- 206s 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) 206s PASSED [ 33%] 206s ------------------------------ live log teardown ------------------------------- 206s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci1 (AA:BB:CC:DD:EE:11) 206s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (AA:BB:CC:DD:EE:00) 206s 206s tests/test_manager.py::test_switching_adapters_based_on_rssi_connectable_to_non_connectable 206s -------------------------------- live log setup -------------------------------- 206s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 206s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (AA:BB:CC:DD:EE:00) 206s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci1 (AA:BB:CC:DD:EE:11) 206s -------------------------------- live log call --------------------------------- 206s 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) 206s PASSED [ 34%] 206s ------------------------------ live log teardown ------------------------------- 206s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci1 (AA:BB:CC:DD:EE:11) 206s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (AA:BB:CC:DD:EE:00) 206s 206s tests/test_manager.py::test_connectable_advertisement_can_be_retrieved_best_path_is_non_connectable 206s -------------------------------- live log setup -------------------------------- 206s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 206s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (AA:BB:CC:DD:EE:00) 206s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci1 (AA:BB:CC:DD:EE:11) 206s PASSED [ 35%] 206s ------------------------------ live log teardown ------------------------------- 206s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci1 (AA:BB:CC:DD:EE:11) 206s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (AA:BB:CC:DD:EE:00) 206s 206s tests/test_manager.py::test_switching_adapters_when_one_goes_away 206s -------------------------------- live log setup -------------------------------- 206s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 206s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (AA:BB:CC:DD:EE:00) 206s -------------------------------- live log call --------------------------------- 206s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci2 206s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci2 206s PASSED [ 36%] 206s ------------------------------ live log teardown ------------------------------- 206s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (AA:BB:CC:DD:EE:00) 206s 206s tests/test_manager.py::test_switching_adapters_when_one_stop_scanning 206s -------------------------------- live log setup -------------------------------- 206s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 206s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (AA:BB:CC:DD:EE:00) 206s -------------------------------- live log call --------------------------------- 206s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci2 206s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci2 206s PASSED [ 37%] 206s ------------------------------ live log teardown ------------------------------- 206s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (AA:BB:CC:DD:EE:00) 206s 206s tests/test_manager.py::test_set_fallback_interval_small 206s -------------------------------- live log setup -------------------------------- 206s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 206s PASSED [ 38%] 206s tests/test_manager.py::test_set_fallback_interval_big 206s -------------------------------- live log setup -------------------------------- 206s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 206s PASSED [ 40%] 206s tests/test_manager.py::test_subclassing_bluetooth_manager 206s -------------------------------- live log setup -------------------------------- 206s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 206s -------------------------------- live log call --------------------------------- 206s WARNING habluetooth.manager:manager.py:189 TestBluetoothManager2: does not implement _discover_service_info, subclasses must implement this method to consume discovery data 206s PASSED [ 41%] 206s tests/test_models.py::test_model PASSED [ 42%] 206s tests/test_models.py::test_model_from_bleak PASSED [ 43%] 206s tests/test_models.py::test_model_from_scanner PASSED [ 44%] 206s tests/test_models.py::test_construct_service_info_bleak PASSED [ 45%] 206s tests/test_models.py::test_from_device_and_advertisement_data PASSED [ 46%] 206s tests/test_models.py::test_pyobjc_compat PASSED [ 47%] 206s tests/test_models.py::test_as_connectable PASSED [ 48%] 206s tests/test_scanner.py::test_empty_data_no_scanner 206s -------------------------------- live log setup -------------------------------- 206s WARNING habluetooth.manager:manager.py:189 BluetoothManager: does not implement _discover_service_info, subclasses must implement this method to consume discovery data 206s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 206s PASSED [ 50%] 206s tests/test_scanner.py::test_dbus_socket_missing_in_container 206s -------------------------------- live log setup -------------------------------- 206s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 206s -------------------------------- live log call --------------------------------- 206s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 206s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (1/4) 206s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 206s DEBUG habluetooth.scanner:scanner.py:462 hci0 (AA:BB:CC:DD:EE:FF): FileNotFoundError while starting bluetooth: 206s Traceback (most recent call last): 206s File "/usr/lib/python3/dist-packages/habluetooth/scanner.py", line 344, in _async_start_attempt 206s await self.scanner.start() 206s File "/usr/lib/python3.13/unittest/mock.py", line 2321, in _execute_mock_call 206s raise effect 206s FileNotFoundError 206s PASSED [ 51%] 206s tests/test_scanner.py::test_dbus_socket_missing 206s -------------------------------- live log setup -------------------------------- 206s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 206s -------------------------------- live log call --------------------------------- 206s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 206s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (1/4) 206s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 206s DEBUG habluetooth.scanner:scanner.py:462 hci0 (AA:BB:CC:DD:EE:FF): FileNotFoundError while starting bluetooth: 206s Traceback (most recent call last): 206s File "/usr/lib/python3/dist-packages/habluetooth/scanner.py", line 344, in _async_start_attempt 206s await self.scanner.start() 206s File "/usr/lib/python3.13/unittest/mock.py", line 2321, in _execute_mock_call 206s raise effect 206s FileNotFoundError 206s PASSED [ 52%] 206s tests/test_scanner.py::test_handle_cancellation 206s -------------------------------- live log setup -------------------------------- 206s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 206s -------------------------------- live log call --------------------------------- 206s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 206s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (1/4) 206s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 206s PASSED [ 53%] 206s tests/test_scanner.py::test_handle_stop_while_starting 206s -------------------------------- live log setup -------------------------------- 206s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 206s -------------------------------- live log call --------------------------------- 206s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 206s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (1/4) 206s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 206s DEBUG habluetooth.scanner:scanner.py:452 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth scanner aborted: 206s Traceback (most recent call last): 206s File "/usr/lib/python3/dist-packages/habluetooth/scanner.py", line 344, in _async_start_attempt 206s await self.scanner.start() 206s File "/tmp/autopkgtest.YDvXY4/autopkgtest_tmp/build/tests/test_scanner.py", line 149, in _start 206s await asyncio.sleep(1000) 206s File "/usr/lib/python3.13/asyncio/tasks.py", line 718, in sleep 206s return await future 206s ^^^^^^^^^^^^ 206s asyncio.exceptions.CancelledError: Interrupted by interrupt context manager 206s 206s The above exception was the direct cause of the following exception: 206s 206s Traceback (most recent call last): 206s File "/usr/lib/python3/dist-packages/habluetooth/scanner.py", line 342, in _async_start_attempt 206s async_interrupt.interrupt(self._start_future, _AbortStartError, None), 206s ~~~~~~~~~~~~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 206s File "/usr/lib/python3/dist-packages/async_interrupt/__init__.py", line 90, in __aexit__ 206s raise self._exception from exc_val 206s habluetooth.scanner._AbortStartError 206s DEBUG habluetooth.scanner:scanner.py:571 hci0 (AA:BB:CC:DD:EE:FF): Scanner is already stopped 206s PASSED [ 54%] 206s tests/test_scanner.py::test_dbus_broken_pipe_in_container 206s -------------------------------- live log setup -------------------------------- 206s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 206s -------------------------------- live log call --------------------------------- 206s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 206s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (1/4) 206s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 206s DEBUG habluetooth.scanner:scanner.py:480 hci0 (AA:BB:CC:DD:EE:FF): DBus connection broken: 206s Traceback (most recent call last): 206s File "/usr/lib/python3/dist-packages/habluetooth/scanner.py", line 344, in _async_start_attempt 206s await self.scanner.start() 206s File "/usr/lib/python3.13/unittest/mock.py", line 2321, in _execute_mock_call 206s raise effect 206s BrokenPipeError 206s PASSED [ 55%] 206s tests/test_scanner.py::test_dbus_broken_pipe 206s -------------------------------- live log setup -------------------------------- 206s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 206s -------------------------------- live log call --------------------------------- 206s ERROR asyncio:base_events.py:1879 Future exception was never retrieved 206s future: 206s habluetooth.scanner._AbortStartError 206s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 206s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (1/4) 206s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 206s DEBUG habluetooth.scanner:scanner.py:480 hci0 (AA:BB:CC:DD:EE:FF): DBus connection broken: 206s Traceback (most recent call last): 206s File "/usr/lib/python3/dist-packages/habluetooth/scanner.py", line 344, in _async_start_attempt 206s await self.scanner.start() 206s File "/usr/lib/python3.13/unittest/mock.py", line 2321, in _execute_mock_call 206s raise effect 206s BrokenPipeError 206s PASSED [ 56%] 206s tests/test_scanner.py::test_invalid_dbus_message 206s -------------------------------- live log setup -------------------------------- 206s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 206s -------------------------------- live log call --------------------------------- 206s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 206s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (1/4) 206s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 206s DEBUG habluetooth.scanner:scanner.py:495 hci0 (AA:BB:CC:DD:EE:FF): Invalid DBus message received: 206s Traceback (most recent call last): 206s File "/usr/lib/python3/dist-packages/habluetooth/scanner.py", line 344, in _async_start_attempt 206s await self.scanner.start() 206s File "/usr/lib/python3.13/unittest/mock.py", line 2321, in _execute_mock_call 206s raise effect 206s dbus_fast.errors.InvalidMessageError 206s PASSED [ 57%] 206s tests/test_scanner.py::test_adapter_needs_reset_at_start[org.bluez.Error.Failed] 206s -------------------------------- live log setup -------------------------------- 206s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 206s -------------------------------- live log call --------------------------------- 206s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 206s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (1/4) 206s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 206s DEBUG habluetooth.scanner:scanner.py:434 hci0 (AA:BB:CC:DD:EE:FF): BleakError while starting bluetooth; attempt: (1/4): org.bluez.Error.Failed 206s Traceback (most recent call last): 206s File "/usr/lib/python3/dist-packages/habluetooth/scanner.py", line 344, in _async_start_attempt 206s await self.scanner.start() 206s File "/tmp/autopkgtest.YDvXY4/autopkgtest_tmp/build/tests/test_scanner.py", line 249, in start 206s raise BleakError(error) 206s bleak.exc.BleakError: org.bluez.Error.Failed 206s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 206s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (2/4) 206s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 206s DEBUG habluetooth.scanner:scanner.py:555 hci0 (AA:BB:CC:DD:EE:FF): adapter stopped responding; executing reset 206s DEBUG habluetooth.scanner:scanner.py:557 hci0 (AA:BB:CC:DD:EE:FF): adapter reset result: True 206s DEBUG habluetooth.scanner:scanner.py:434 hci0 (AA:BB:CC:DD:EE:FF): BleakError while starting bluetooth; attempt: (2/4): org.bluez.Error.Failed 206s Traceback (most recent call last): 206s File "/usr/lib/python3/dist-packages/habluetooth/scanner.py", line 344, in _async_start_attempt 206s await self.scanner.start() 206s File "/tmp/autopkgtest.YDvXY4/autopkgtest_tmp/build/tests/test_scanner.py", line 249, in start 206s raise BleakError(error) 206s bleak.exc.BleakError: org.bluez.Error.Failed 206s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 206s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (3/4) 206s DEBUG habluetooth.scanner:scanner.py:418 hci0 (AA:BB:CC:DD:EE:FF): Success while starting bluetooth; attempt: (3/4) 206s DEBUG bleak_retry_connector.bleak_manager:bleak_manager.py:60 get_global_bluez_manager_with_timeout failed: [org.freedesktop.systemd1.NoSuchUnit] Unit dbus-org.bluez.service not found. 206s Traceback (most recent call last): 206s File "/usr/lib/python3/dist-packages/bleak_retry_connector/bleak_manager.py", line 46, in get_global_bluez_manager_with_timeout 206s return await get_global_bluez_manager() 206s ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 206s File "/usr/lib/python3/dist-packages/bleak/backends/bluezdbus/manager.py", line 1131, in get_global_bluez_manager 206s await instance.async_init() 206s File "/usr/lib/python3/dist-packages/bleak/backends/bluezdbus/manager.py", line 301, in async_init 206s assert_reply(reply) 206s ~~~~~~~~~~~~^^^^^^^ 206s File "/usr/lib/python3/dist-packages/bleak/backends/bluezdbus/utils.py", line 27, in assert_reply 206s raise BleakDBusError(reply.error_name, reply.body) 206s bleak.exc.BleakDBusError: [org.freedesktop.systemd1.NoSuchUnit] Unit dbus-org.bluez.service not found. 206s DEBUG bleak_retry_connector:__init__.py:639 Failed to restore discoveries for hci0 206s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 206s PASSED [ 58%] 206s tests/test_scanner.py::test_adapter_needs_reset_at_start[org.bluez.Error.InProgress] 206s -------------------------------- live log setup -------------------------------- 206s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 206s -------------------------------- live log call --------------------------------- 206s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 206s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (1/4) 206s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 206s DEBUG habluetooth.scanner:scanner.py:586 hci0 (AA:BB:CC:DD:EE:FF): Force stopping bluetooth discovery 206s DEBUG bleak_retry_connector.bleak_manager:bleak_manager.py:60 get_global_bluez_manager_with_timeout failed: [org.freedesktop.systemd1.NoSuchUnit] Unit dbus-org.bluez.service not found. 206s Traceback (most recent call last): 206s File "/usr/lib/python3/dist-packages/habluetooth/scanner.py", line 344, in _async_start_attempt 206s await self.scanner.start() 206s File "/tmp/autopkgtest.YDvXY4/autopkgtest_tmp/build/tests/test_scanner.py", line 249, in start 206s raise BleakError(error) 206s bleak.exc.BleakError: org.bluez.Error.InProgress 206s 206s During handling of the above exception, another exception occurred: 206s 206s Traceback (most recent call last): 206s File "/usr/lib/python3/dist-packages/bleak_retry_connector/bleak_manager.py", line 46, in get_global_bluez_manager_with_timeout 206s return await get_global_bluez_manager() 206s ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 206s File "/usr/lib/python3/dist-packages/bleak/backends/bluezdbus/manager.py", line 1131, in get_global_bluez_manager 206s await instance.async_init() 206s File "/usr/lib/python3/dist-packages/bleak/backends/bluezdbus/manager.py", line 301, in async_init 206s assert_reply(reply) 206s ~~~~~~~~~~~~^^^^^^^ 206s File "/usr/lib/python3/dist-packages/bleak/backends/bluezdbus/utils.py", line 27, in assert_reply 206s raise BleakDBusError(reply.error_name, reply.body) 206s bleak.exc.BleakDBusError: [org.freedesktop.systemd1.NoSuchUnit] Unit dbus-org.bluez.service not found. 206s ERROR bleak_retry_connector.bluez:bluez.py:324 Failed to stop discovery for hci0 because no manager 206s DEBUG habluetooth.scanner:scanner.py:434 hci0 (AA:BB:CC:DD:EE:FF): BleakError while starting bluetooth; attempt: (1/4): org.bluez.Error.InProgress 206s Traceback (most recent call last): 206s File "/usr/lib/python3/dist-packages/habluetooth/scanner.py", line 344, in _async_start_attempt 206s await self.scanner.start() 206s File "/tmp/autopkgtest.YDvXY4/autopkgtest_tmp/build/tests/test_scanner.py", line 249, in start 206s raise BleakError(error) 206s bleak.exc.BleakError: org.bluez.Error.InProgress 206s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 206s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (2/4) 206s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 206s DEBUG habluetooth.scanner:scanner.py:586 hci0 (AA:BB:CC:DD:EE:FF): Force stopping bluetooth discovery 206s ERROR habluetooth.scanner:scanner.py:593 hci0 (AA:BB:CC:DD:EE:FF): Failed to force stop scanner: 'NoneType' object has no attribute 'send' 206s DEBUG habluetooth.scanner:scanner.py:555 hci0 (AA:BB:CC:DD:EE:FF): adapter stopped responding; executing reset 206s DEBUG habluetooth.scanner:scanner.py:557 hci0 (AA:BB:CC:DD:EE:FF): adapter reset result: True 206s DEBUG habluetooth.scanner:scanner.py:434 hci0 (AA:BB:CC:DD:EE:FF): BleakError while starting bluetooth; attempt: (2/4): org.bluez.Error.InProgress 206s Traceback (most recent call last): 206s File "/usr/lib/python3/dist-packages/habluetooth/scanner.py", line 344, in _async_start_attempt 206s await self.scanner.start() 206s File "/tmp/autopkgtest.YDvXY4/autopkgtest_tmp/build/tests/test_scanner.py", line 249, in start 206s raise BleakError(error) 206s bleak.exc.BleakError: org.bluez.Error.InProgress 206s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 206s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (3/4) 206s DEBUG habluetooth.scanner:scanner.py:418 hci0 (AA:BB:CC:DD:EE:FF): Success while starting bluetooth; attempt: (3/4) 206s DEBUG bleak_retry_connector:__init__.py:639 Failed to restore discoveries for hci0 206s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 206s PASSED [ 60%] 206s tests/test_scanner.py::test_adapter_needs_reset_at_start[org.bluez.Error.NotReady] 206s -------------------------------- live log setup -------------------------------- 206s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 206s -------------------------------- live log call --------------------------------- 206s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 206s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (1/4) 206s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 206s DEBUG habluetooth.scanner:scanner.py:434 hci0 (AA:BB:CC:DD:EE:FF): BleakError while starting bluetooth; attempt: (1/4): org.bluez.Error.NotReady 206s Traceback (most recent call last): 206s File "/usr/lib/python3/dist-packages/habluetooth/scanner.py", line 344, in _async_start_attempt 206s await self.scanner.start() 206s File "/tmp/autopkgtest.YDvXY4/autopkgtest_tmp/build/tests/test_scanner.py", line 249, in start 206s raise BleakError(error) 206s bleak.exc.BleakError: org.bluez.Error.NotReady 206s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 206s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (2/4) 206s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 206s DEBUG habluetooth.scanner:scanner.py:555 hci0 (AA:BB:CC:DD:EE:FF): adapter stopped responding; executing reset 206s DEBUG habluetooth.scanner:scanner.py:557 hci0 (AA:BB:CC:DD:EE:FF): adapter reset result: True 206s DEBUG habluetooth.scanner:scanner.py:434 hci0 (AA:BB:CC:DD:EE:FF): BleakError while starting bluetooth; attempt: (2/4): org.bluez.Error.NotReady 206s Traceback (most recent call last): 206s File "/usr/lib/python3/dist-packages/habluetooth/scanner.py", line 344, in _async_start_attempt 206s await self.scanner.start() 206s File "/tmp/autopkgtest.YDvXY4/autopkgtest_tmp/build/tests/test_scanner.py", line 249, in start 206s raise BleakError(error) 206s bleak.exc.BleakError: org.bluez.Error.NotReady 206s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 206s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (3/4) 206s DEBUG habluetooth.scanner:scanner.py:418 hci0 (AA:BB:CC:DD:EE:FF): Success while starting bluetooth; attempt: (3/4) 206s DEBUG bleak_retry_connector.bleak_manager:bleak_manager.py:60 get_global_bluez_manager_with_timeout failed: [org.freedesktop.systemd1.NoSuchUnit] Unit dbus-org.bluez.service not found. 206s Traceback (most recent call last): 206s File "/usr/lib/python3/dist-packages/bleak_retry_connector/bleak_manager.py", line 46, in get_global_bluez_manager_with_timeout 206s return await get_global_bluez_manager() 206s ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 206s File "/usr/lib/python3/dist-packages/bleak/backends/bluezdbus/manager.py", line 1131, in get_global_bluez_manager 206s await instance.async_init() 206s File "/usr/lib/python3/dist-packages/bleak/backends/bluezdbus/manager.py", line 301, in async_init 206s assert_reply(reply) 206s ~~~~~~~~~~~~^^^^^^^ 206s File "/usr/lib/python3/dist-packages/bleak/backends/bluezdbus/utils.py", line 27, in assert_reply 206s raise BleakDBusError(reply.error_name, reply.body) 206s bleak.exc.BleakDBusError: [org.freedesktop.systemd1.NoSuchUnit] Unit dbus-org.bluez.service not found. 206s DEBUG bleak_retry_connector:__init__.py:639 Failed to restore discoveries for hci0 206s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 206s PASSED [ 61%] 206s tests/test_scanner.py::test_adapter_needs_reset_at_start[not found] 206s -------------------------------- live log setup -------------------------------- 206s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 206s -------------------------------- live log call --------------------------------- 206s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 206s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (1/4) 206s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 206s DEBUG habluetooth.scanner:scanner.py:434 hci0 (AA:BB:CC:DD:EE:FF): BleakError while starting bluetooth; attempt: (1/4): not found 206s Traceback (most recent call last): 206s File "/usr/lib/python3/dist-packages/habluetooth/scanner.py", line 344, in _async_start_attempt 206s await self.scanner.start() 206s File "/tmp/autopkgtest.YDvXY4/autopkgtest_tmp/build/tests/test_scanner.py", line 249, in start 206s raise BleakError(error) 206s bleak.exc.BleakError: not found 206s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 206s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (2/4) 206s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 206s DEBUG habluetooth.scanner:scanner.py:555 hci0 (AA:BB:CC:DD:EE:FF): adapter stopped responding; executing reset 206s DEBUG habluetooth.scanner:scanner.py:557 hci0 (AA:BB:CC:DD:EE:FF): adapter reset result: True 206s DEBUG habluetooth.scanner:scanner.py:434 hci0 (AA:BB:CC:DD:EE:FF): BleakError while starting bluetooth; attempt: (2/4): not found 206s Traceback (most recent call last): 206s File "/usr/lib/python3/dist-packages/habluetooth/scanner.py", line 344, in _async_start_attempt 206s await self.scanner.start() 206s File "/tmp/autopkgtest.YDvXY4/autopkgtest_tmp/build/tests/test_scanner.py", line 249, in start 206s raise BleakError(error) 206s bleak.exc.BleakError: not found 206s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 206s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (3/4) 206s DEBUG habluetooth.scanner:scanner.py:418 hci0 (AA:BB:CC:DD:EE:FF): Success while starting bluetooth; attempt: (3/4) 206s DEBUG bleak_retry_connector.bleak_manager:bleak_manager.py:60 get_global_bluez_manager_with_timeout failed: [org.freedesktop.systemd1.NoSuchUnit] Unit dbus-org.bluez.service not found. 206s Traceback (most recent call last): 206s File "/usr/lib/python3/dist-packages/bleak_retry_connector/bleak_manager.py", line 46, in get_global_bluez_manager_with_timeout 206s return await get_global_bluez_manager() 206s ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 206s File "/usr/lib/python3/dist-packages/bleak/backends/bluezdbus/manager.py", line 1131, in get_global_bluez_manager 206s await instance.async_init() 206s File "/usr/lib/python3/dist-packages/bleak/backends/bluezdbus/manager.py", line 301, in async_init 206s assert_reply(reply) 206s ~~~~~~~~~~~~^^^^^^^ 206s File "/usr/lib/python3/dist-packages/bleak/backends/bluezdbus/utils.py", line 27, in assert_reply 206s raise BleakDBusError(reply.error_name, reply.body) 206s bleak.exc.BleakDBusError: [org.freedesktop.systemd1.NoSuchUnit] Unit dbus-org.bluez.service not found. 206s DEBUG bleak_retry_connector:__init__.py:639 Failed to restore discoveries for hci0 206s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 206s PASSED [ 62%] 206s tests/test_scanner.py::test_recovery_from_dbus_restart 206s -------------------------------- live log setup -------------------------------- 206s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 206s -------------------------------- live log call --------------------------------- 206s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 206s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (1/4) 206s DEBUG habluetooth.scanner:scanner.py:418 hci0 (AA:BB:CC:DD:EE:FF): Success while starting bluetooth; attempt: (1/4) 206s DEBUG bleak_retry_connector.bleak_manager:bleak_manager.py:60 get_global_bluez_manager_with_timeout failed: [org.freedesktop.systemd1.NoSuchUnit] Unit dbus-org.bluez.service not found. 206s Traceback (most recent call last): 206s File "/usr/lib/python3/dist-packages/bleak_retry_connector/bleak_manager.py", line 46, in get_global_bluez_manager_with_timeout 206s return await get_global_bluez_manager() 206s ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 206s File "/usr/lib/python3/dist-packages/bleak/backends/bluezdbus/manager.py", line 1131, in get_global_bluez_manager 206s await instance.async_init() 206s File "/usr/lib/python3/dist-packages/bleak/backends/bluezdbus/manager.py", line 301, in async_init 206s assert_reply(reply) 206s ~~~~~~~~~~~~^^^^^^^ 206s File "/usr/lib/python3/dist-packages/bleak/backends/bluezdbus/utils.py", line 27, in assert_reply 206s raise BleakDBusError(reply.error_name, reply.body) 206s bleak.exc.BleakDBusError: [org.freedesktop.systemd1.NoSuchUnit] Unit dbus-org.bluez.service not found. 206s DEBUG bleak_retry_connector:__init__.py:639 Failed to restore discoveries for hci0 206s DEBUG habluetooth.base_scanner:base_scanner.py:210 hci0 (AA:BB:CC:DD:EE:FF): Scanner watchdog time_since_last_detection: 10.003541322075762 206s DEBUG habluetooth.base_scanner:base_scanner.py:210 hci0 (AA:BB:CC:DD:EE:FF): Scanner watchdog time_since_last_detection: 20.0 206s DEBUG habluetooth.base_scanner:base_scanner.py:210 hci0 (AA:BB:CC:DD:EE:FF): Scanner watchdog time_since_last_detection: 110.0 206s DEBUG habluetooth.scanner:scanner.py:517 hci0 (AA:BB:CC:DD:EE:FF): Bluetooth scanner has gone quiet for 110.0s, restarting 206s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 206s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 206s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (1/4) 206s DEBUG habluetooth.scanner:scanner.py:418 hci0 (AA:BB:CC:DD:EE:FF): Success while starting bluetooth; attempt: (1/4) 206s DEBUG bleak_retry_connector:__init__.py:639 Failed to restore discoveries for hci0 206s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 206s PASSED [ 63%] 206s tests/test_scanner.py::test_adapter_recovery 206s -------------------------------- live log setup -------------------------------- 206s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 206s -------------------------------- live log call --------------------------------- 206s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 206s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (1/4) 206s DEBUG habluetooth.scanner:scanner.py:418 hci0 (AA:BB:CC:DD:EE:FF): Success while starting bluetooth; attempt: (1/4) 206s DEBUG bleak_retry_connector.bleak_manager:bleak_manager.py:60 get_global_bluez_manager_with_timeout failed: [org.freedesktop.systemd1.NoSuchUnit] Unit dbus-org.bluez.service not found. 206s Traceback (most recent call last): 206s File "/usr/lib/python3/dist-packages/bleak_retry_connector/bleak_manager.py", line 46, in get_global_bluez_manager_with_timeout 206s return await get_global_bluez_manager() 206s ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 206s File "/usr/lib/python3/dist-packages/bleak/backends/bluezdbus/manager.py", line 1131, in get_global_bluez_manager 206s await instance.async_init() 206s File "/usr/lib/python3/dist-packages/bleak/backends/bluezdbus/manager.py", line 301, in async_init 206s assert_reply(reply) 206s ~~~~~~~~~~~~^^^^^^^ 206s File "/usr/lib/python3/dist-packages/bleak/backends/bluezdbus/utils.py", line 27, in assert_reply 206s raise BleakDBusError(reply.error_name, reply.body) 206s bleak.exc.BleakDBusError: [org.freedesktop.systemd1.NoSuchUnit] Unit dbus-org.bluez.service not found. 206s DEBUG bleak_retry_connector:__init__.py:639 Failed to restore discoveries for hci0 206s DEBUG habluetooth.base_scanner:base_scanner.py:210 hci0 (AA:BB:CC:DD:EE:FF): Scanner watchdog time_since_last_detection: 10.0 206s DEBUG habluetooth.base_scanner:base_scanner.py:210 hci0 (AA:BB:CC:DD:EE:FF): Scanner watchdog time_since_last_detection: 20.0 206s DEBUG habluetooth.base_scanner:base_scanner.py:210 hci0 (AA:BB:CC:DD:EE:FF): Scanner watchdog time_since_last_detection: 120.0 206s DEBUG habluetooth.scanner:scanner.py:517 hci0 (AA:BB:CC:DD:EE:FF): Bluetooth scanner has gone quiet for 120.0s, restarting 206s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 206s DEBUG habluetooth.scanner:scanner.py:555 hci0 (AA:BB:CC:DD:EE:FF): adapter stopped responding; executing reset 206s DEBUG habluetooth.scanner:scanner.py:557 hci0 (AA:BB:CC:DD:EE:FF): adapter reset result: True 206s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 206s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (1/4) 206s DEBUG habluetooth.scanner:scanner.py:418 hci0 (AA:BB:CC:DD:EE:FF): Success while starting bluetooth; attempt: (1/4) 206s DEBUG bleak_retry_connector:__init__.py:639 Failed to restore discoveries for hci0 206s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 206s PASSED [ 64%] 206s tests/test_scanner.py::test_adapter_scanner_fails_to_start_first_time 206s -------------------------------- live log setup -------------------------------- 206s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 206s -------------------------------- live log call --------------------------------- 206s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 206s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (1/4) 206s DEBUG habluetooth.scanner:scanner.py:418 hci0 (AA:BB:CC:DD:EE:FF): Success while starting bluetooth; attempt: (1/4) 206s DEBUG bleak_retry_connector.bleak_manager:bleak_manager.py:60 get_global_bluez_manager_with_timeout failed: [org.freedesktop.systemd1.NoSuchUnit] Unit dbus-org.bluez.service not found. 206s Traceback (most recent call last): 206s File "/usr/lib/python3/dist-packages/bleak_retry_connector/bleak_manager.py", line 46, in get_global_bluez_manager_with_timeout 206s return await get_global_bluez_manager() 206s ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 206s File "/usr/lib/python3/dist-packages/bleak/backends/bluezdbus/manager.py", line 1131, in get_global_bluez_manager 206s await instance.async_init() 206s File "/usr/lib/python3/dist-packages/bleak/backends/bluezdbus/manager.py", line 301, in async_init 206s assert_reply(reply) 206s ~~~~~~~~~~~~^^^^^^^ 206s File "/usr/lib/python3/dist-packages/bleak/backends/bluezdbus/utils.py", line 27, in assert_reply 206s raise BleakDBusError(reply.error_name, reply.body) 206s bleak.exc.BleakDBusError: [org.freedesktop.systemd1.NoSuchUnit] Unit dbus-org.bluez.service not found. 206s DEBUG bleak_retry_connector:__init__.py:639 Failed to restore discoveries for hci0 206s DEBUG habluetooth.base_scanner:base_scanner.py:210 hci0 (AA:BB:CC:DD:EE:FF): Scanner watchdog time_since_last_detection: 10.0 206s DEBUG habluetooth.base_scanner:base_scanner.py:210 hci0 (AA:BB:CC:DD:EE:FF): Scanner watchdog time_since_last_detection: 20.0 206s DEBUG habluetooth.base_scanner:base_scanner.py:210 hci0 (AA:BB:CC:DD:EE:FF): Scanner watchdog time_since_last_detection: 120.0 206s DEBUG habluetooth.scanner:scanner.py:517 hci0 (AA:BB:CC:DD:EE:FF): Bluetooth scanner has gone quiet for 120.0s, restarting 206s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 206s DEBUG habluetooth.scanner:scanner.py:555 hci0 (AA:BB:CC:DD:EE:FF): adapter stopped responding; executing reset 206s DEBUG habluetooth.scanner:scanner.py:557 hci0 (AA:BB:CC:DD:EE:FF): adapter reset result: True 206s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 206s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (1/4) 206s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 206s DEBUG habluetooth.scanner:scanner.py:434 hci0 (AA:BB:CC:DD:EE:FF): BleakError while starting bluetooth; attempt: (1/4): Failed to start 206s Traceback (most recent call last): 206s File "/usr/lib/python3/dist-packages/habluetooth/scanner.py", line 344, in _async_start_attempt 206s await self.scanner.start() 206s File "/tmp/autopkgtest.YDvXY4/autopkgtest_tmp/build/tests/test_scanner.py", line 485, in start 206s raise BleakError("Failed to start") 206s bleak.exc.BleakError: Failed to start 206s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 206s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (2/4) 206s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 206s DEBUG habluetooth.scanner:scanner.py:434 hci0 (AA:BB:CC:DD:EE:FF): BleakError while starting bluetooth; attempt: (2/4): Failed to start 206s Traceback (most recent call last): 206s File "/usr/lib/python3/dist-packages/habluetooth/scanner.py", line 344, in _async_start_attempt 206s await self.scanner.start() 206s File "/tmp/autopkgtest.YDvXY4/autopkgtest_tmp/build/tests/test_scanner.py", line 485, in start 206s raise BleakError("Failed to start") 206s bleak.exc.BleakError: Failed to start 206s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 206s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (3/4) 206s DEBUG habluetooth.scanner:scanner.py:418 hci0 (AA:BB:CC:DD:EE:FF): Success while starting bluetooth; attempt: (3/4) 206s DEBUG bleak_retry_connector:__init__.py:639 Failed to restore discoveries for hci0 206s DEBUG habluetooth.base_scanner:base_scanner.py:210 hci0 (AA:BB:CC:DD:EE:FF): Scanner watchdog time_since_last_detection: 90.0087607849855 206s DEBUG habluetooth.scanner:scanner.py:517 hci0 (AA:BB:CC:DD:EE:FF): Bluetooth scanner has gone quiet for 90.0087607849855s, restarting 206s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 206s DEBUG habluetooth.scanner:scanner.py:555 hci0 (AA:BB:CC:DD:EE:FF): adapter stopped responding; executing reset 206s DEBUG habluetooth.scanner:scanner.py:557 hci0 (AA:BB:CC:DD:EE:FF): adapter reset result: True 206s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 206s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (1/4) 206s DEBUG habluetooth.scanner:scanner.py:418 hci0 (AA:BB:CC:DD:EE:FF): Success while starting bluetooth; attempt: (1/4) 206s DEBUG bleak_retry_connector:__init__.py:639 Failed to restore discoveries for hci0 206s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 206s PASSED [ 65%] 206s tests/test_scanner.py::test_adapter_fails_to_start_and_takes_a_bit_to_init 206s -------------------------------- live log setup -------------------------------- 206s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 206s -------------------------------- live log call --------------------------------- 206s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 206s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (1/4) 206s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 206s DEBUG habluetooth.scanner:scanner.py:402 hci0 (AA:BB:CC:DD:EE:FF): Waiting for adapter to initialize; attempt (1/4) 206s 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 206s Traceback (most recent call last): 206s File "/usr/lib/python3/dist-packages/habluetooth/scanner.py", line 344, in _async_start_attempt 206s await self.scanner.start() 206s File "/tmp/autopkgtest.YDvXY4/autopkgtest_tmp/build/tests/test_scanner.py", line 599, in start 206s raise BleakError("org.freedesktop.DBus.Error.UnknownObject") 206s bleak.exc.BleakError: org.freedesktop.DBus.Error.UnknownObject 206s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 206s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (2/4) 206s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 206s DEBUG habluetooth.scanner:scanner.py:586 hci0 (AA:BB:CC:DD:EE:FF): Force stopping bluetooth discovery 206s DEBUG bleak_retry_connector.bleak_manager:bleak_manager.py:60 get_global_bluez_manager_with_timeout failed: [org.freedesktop.systemd1.NoSuchUnit] Unit dbus-org.bluez.service not found. 206s Traceback (most recent call last): 206s File "/usr/lib/python3/dist-packages/habluetooth/scanner.py", line 344, in _async_start_attempt 206s await self.scanner.start() 206s File "/tmp/autopkgtest.YDvXY4/autopkgtest_tmp/build/tests/test_scanner.py", line 601, in start 206s raise BleakError("org.bluez.Error.InProgress") 206s bleak.exc.BleakError: org.bluez.Error.InProgress 206s 206s During handling of the above exception, another exception occurred: 206s 206s Traceback (most recent call last): 206s File "/usr/lib/python3/dist-packages/bleak_retry_connector/bleak_manager.py", line 46, in get_global_bluez_manager_with_timeout 206s return await get_global_bluez_manager() 206s ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 206s File "/usr/lib/python3/dist-packages/bleak/backends/bluezdbus/manager.py", line 1131, in get_global_bluez_manager 206s await instance.async_init() 206s File "/usr/lib/python3/dist-packages/bleak/backends/bluezdbus/manager.py", line 301, in async_init 206s assert_reply(reply) 206s ~~~~~~~~~~~~^^^^^^^ 206s File "/usr/lib/python3/dist-packages/bleak/backends/bluezdbus/utils.py", line 27, in assert_reply 206s raise BleakDBusError(reply.error_name, reply.body) 206s bleak.exc.BleakDBusError: [org.freedesktop.systemd1.NoSuchUnit] Unit dbus-org.bluez.service not found. 206s ERROR bleak_retry_connector.bluez:bluez.py:324 Failed to stop discovery for hci0 because no manager 206s DEBUG habluetooth.scanner:scanner.py:555 hci0 (AA:BB:CC:DD:EE:FF): adapter stopped responding; executing reset 206s DEBUG habluetooth.scanner:scanner.py:557 hci0 (AA:BB:CC:DD:EE:FF): adapter reset result: True 206s DEBUG habluetooth.scanner:scanner.py:434 hci0 (AA:BB:CC:DD:EE:FF): BleakError while starting bluetooth; attempt: (2/4): org.bluez.Error.InProgress 206s Traceback (most recent call last): 206s File "/usr/lib/python3/dist-packages/habluetooth/scanner.py", line 344, in _async_start_attempt 206s await self.scanner.start() 206s File "/tmp/autopkgtest.YDvXY4/autopkgtest_tmp/build/tests/test_scanner.py", line 601, in start 206s raise BleakError("org.bluez.Error.InProgress") 206s bleak.exc.BleakError: org.bluez.Error.InProgress 206s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 206s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (3/4) 206s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 206s DEBUG habluetooth.scanner:scanner.py:586 hci0 (AA:BB:CC:DD:EE:FF): Force stopping bluetooth discovery 206s ERROR habluetooth.scanner:scanner.py:593 hci0 (AA:BB:CC:DD:EE:FF): Failed to force stop scanner: 'NoneType' object has no attribute 'send' 206s DEBUG habluetooth.scanner:scanner.py:434 hci0 (AA:BB:CC:DD:EE:FF): BleakError while starting bluetooth; attempt: (3/4): org.bluez.Error.InProgress 206s Traceback (most recent call last): 206s File "/usr/lib/python3/dist-packages/habluetooth/scanner.py", line 344, in _async_start_attempt 206s await self.scanner.start() 206s File "/tmp/autopkgtest.YDvXY4/autopkgtest_tmp/build/tests/test_scanner.py", line 603, in start 206s raise BleakError("org.bluez.Error.InProgress") 206s bleak.exc.BleakError: org.bluez.Error.InProgress 206s 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) 206s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'passive', 'adapter': 'hci0', 'bluez': } 206s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (4/4) 206s 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) 206s DEBUG habluetooth.scanner:scanner.py:418 hci0 (AA:BB:CC:DD:EE:FF): Success while starting bluetooth; attempt: (4/4) 206s DEBUG bleak_retry_connector:__init__.py:639 Failed to restore discoveries for hci0 206s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 206s PASSED [ 66%] 206s tests/test_scanner.py::test_restart_takes_longer_than_watchdog_time 206s -------------------------------- live log setup -------------------------------- 206s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 206s -------------------------------- live log call --------------------------------- 206s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 206s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (1/4) 206s DEBUG habluetooth.scanner:scanner.py:418 hci0 (AA:BB:CC:DD:EE:FF): Success while starting bluetooth; attempt: (1/4) 206s DEBUG bleak_retry_connector.bleak_manager:bleak_manager.py:60 get_global_bluez_manager_with_timeout failed: [org.freedesktop.systemd1.NoSuchUnit] Unit dbus-org.bluez.service not found. 206s Traceback (most recent call last): 206s File "/usr/lib/python3/dist-packages/bleak_retry_connector/bleak_manager.py", line 46, in get_global_bluez_manager_with_timeout 206s return await get_global_bluez_manager() 206s ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 206s File "/usr/lib/python3/dist-packages/bleak/backends/bluezdbus/manager.py", line 1131, in get_global_bluez_manager 206s await instance.async_init() 206s File "/usr/lib/python3/dist-packages/bleak/backends/bluezdbus/manager.py", line 301, in async_init 206s assert_reply(reply) 206s ~~~~~~~~~~~~^^^^^^^ 206s File "/usr/lib/python3/dist-packages/bleak/backends/bluezdbus/utils.py", line 27, in assert_reply 206s raise BleakDBusError(reply.error_name, reply.body) 206s bleak.exc.BleakDBusError: [org.freedesktop.systemd1.NoSuchUnit] Unit dbus-org.bluez.service not found. 206s DEBUG bleak_retry_connector:__init__.py:639 Failed to restore discoveries for hci0 206s DEBUG habluetooth.base_scanner:base_scanner.py:210 hci0 (AA:BB:CC:DD:EE:FF): Scanner watchdog time_since_last_detection: 120.0 206s DEBUG habluetooth.scanner:scanner.py:517 hci0 (AA:BB:CC:DD:EE:FF): Bluetooth scanner has gone quiet for 120.0s, restarting 206s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 206s DEBUG habluetooth.scanner:scanner.py:555 hci0 (AA:BB:CC:DD:EE:FF): adapter stopped responding; executing reset 206s DEBUG habluetooth.scanner:scanner.py:557 hci0 (AA:BB:CC:DD:EE:FF): adapter reset result: True 206s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 206s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (1/4) 206s DEBUG habluetooth.base_scanner:base_scanner.py:210 hci0 (AA:BB:CC:DD:EE:FF): Scanner watchdog time_since_last_detection: 120.0 206s DEBUG habluetooth.scanner:scanner.py:512 hci0 (AA:BB:CC:DD:EE:FF): Scanner is already restarting, deferring restart 206s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 206s DEBUG habluetooth.scanner:scanner.py:426 hci0 (AA:BB:CC:DD:EE:FF): TimeoutError while starting bluetooth; attempt: (1/4) 206s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 206s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (2/4) 206s DEBUG habluetooth.scanner:scanner.py:418 hci0 (AA:BB:CC:DD:EE:FF): Success while starting bluetooth; attempt: (2/4) 206s DEBUG bleak_retry_connector:__init__.py:639 Failed to restore discoveries for hci0 206s ERROR asyncio:base_events.py:1879 Future exception was never retrieved 206s future: 206s habluetooth.scanner._AbortStartError 206s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 206s PASSED [ 67%] 206s tests/test_scanner.py::test_setup_and_stop_macos SKIPPED (condition:...) [ 68%] 206s tests/test_scanner.py::test_adapter_init_fails_fallback_to_passive 206s -------------------------------- live log setup -------------------------------- 206s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 206s -------------------------------- live log call --------------------------------- 206s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 206s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (1/4) 206s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 206s DEBUG habluetooth.scanner:scanner.py:402 hci0 (AA:BB:CC:DD:EE:FF): Waiting for adapter to initialize; attempt (1/4) 206s 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 206s Traceback (most recent call last): 206s File "/usr/lib/python3/dist-packages/habluetooth/scanner.py", line 344, in _async_start_attempt 206s await self.scanner.start() 206s File "/tmp/autopkgtest.YDvXY4/autopkgtest_tmp/build/tests/test_scanner.py", line 780, in start 206s raise BleakError("org.freedesktop.DBus.Error.UnknownObject") 206s bleak.exc.BleakError: org.freedesktop.DBus.Error.UnknownObject 206s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 206s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (2/4) 206s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 206s DEBUG habluetooth.scanner:scanner.py:586 hci0 (AA:BB:CC:DD:EE:FF): Force stopping bluetooth discovery 206s DEBUG bleak_retry_connector.bleak_manager:bleak_manager.py:60 get_global_bluez_manager_with_timeout failed: [org.freedesktop.systemd1.NoSuchUnit] Unit dbus-org.bluez.service not found. 206s Traceback (most recent call last): 206s File "/usr/lib/python3/dist-packages/habluetooth/scanner.py", line 344, in _async_start_attempt 206s await self.scanner.start() 206s File "/tmp/autopkgtest.YDvXY4/autopkgtest_tmp/build/tests/test_scanner.py", line 782, in start 206s raise BleakError("org.bluez.Error.InProgress") 206s bleak.exc.BleakError: org.bluez.Error.InProgress 206s 206s During handling of the above exception, another exception occurred: 206s 206s Traceback (most recent call last): 206s File "/usr/lib/python3/dist-packages/bleak_retry_connector/bleak_manager.py", line 46, in get_global_bluez_manager_with_timeout 206s return await get_global_bluez_manager() 206s ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 206s File "/usr/lib/python3/dist-packages/bleak/backends/bluezdbus/manager.py", line 1131, in get_global_bluez_manager 206s await instance.async_init() 206s File "/usr/lib/python3/dist-packages/bleak/backends/bluezdbus/manager.py", line 301, in async_init 206s assert_reply(reply) 206s ~~~~~~~~~~~~^^^^^^^ 206s File "/usr/lib/python3/dist-packages/bleak/backends/bluezdbus/utils.py", line 27, in assert_reply 206s raise BleakDBusError(reply.error_name, reply.body) 206s bleak.exc.BleakDBusError: [org.freedesktop.systemd1.NoSuchUnit] Unit dbus-org.bluez.service not found. 206s ERROR bleak_retry_connector.bluez:bluez.py:324 Failed to stop discovery for hci0 because no manager 206s DEBUG habluetooth.scanner:scanner.py:555 hci0 (AA:BB:CC:DD:EE:FF): adapter stopped responding; executing reset 206s DEBUG habluetooth.scanner:scanner.py:557 hci0 (AA:BB:CC:DD:EE:FF): adapter reset result: True 206s DEBUG habluetooth.scanner:scanner.py:434 hci0 (AA:BB:CC:DD:EE:FF): BleakError while starting bluetooth; attempt: (2/4): org.bluez.Error.InProgress 206s Traceback (most recent call last): 206s File "/usr/lib/python3/dist-packages/habluetooth/scanner.py", line 344, in _async_start_attempt 206s await self.scanner.start() 206s File "/tmp/autopkgtest.YDvXY4/autopkgtest_tmp/build/tests/test_scanner.py", line 782, in start 206s raise BleakError("org.bluez.Error.InProgress") 206s bleak.exc.BleakError: org.bluez.Error.InProgress 206s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 206s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (3/4) 206s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 206s DEBUG habluetooth.scanner:scanner.py:586 hci0 (AA:BB:CC:DD:EE:FF): Force stopping bluetooth discovery 206s ERROR habluetooth.scanner:scanner.py:593 hci0 (AA:BB:CC:DD:EE:FF): Failed to force stop scanner: 'NoneType' object has no attribute 'send' 206s DEBUG habluetooth.scanner:scanner.py:434 hci0 (AA:BB:CC:DD:EE:FF): BleakError while starting bluetooth; attempt: (3/4): org.bluez.Error.InProgress 206s Traceback (most recent call last): 206s File "/usr/lib/python3/dist-packages/habluetooth/scanner.py", line 344, in _async_start_attempt 206s await self.scanner.start() 206s File "/tmp/autopkgtest.YDvXY4/autopkgtest_tmp/build/tests/test_scanner.py", line 784, in start 206s raise BleakError("org.bluez.Error.InProgress") 206s bleak.exc.BleakError: org.bluez.Error.InProgress 206s 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) 206s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'passive', 'adapter': 'hci0', 'bluez': } 206s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (4/4) 206s 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) 206s DEBUG habluetooth.scanner:scanner.py:418 hci0 (AA:BB:CC:DD:EE:FF): Success while starting bluetooth; attempt: (4/4) 206s DEBUG bleak_retry_connector:__init__.py:639 Failed to restore discoveries for hci0 206s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 206s PASSED [ 70%] 206s tests/test_storage.py::test_discovered_device_advertisement_data_to_dict PASSED [ 71%] 206s tests/test_storage.py::test_discovered_device_advertisement_data_from_dict PASSED [ 72%] 206s tests/test_storage.py::test_expire_stale_scanner_discovered_device_advertisement_data 206s -------------------------------- live log call --------------------------------- 206s DEBUG habluetooth.storage:storage.py:122 Loaded 1 fresh discovered devices for myscanner 206s DEBUG habluetooth.storage:storage.py:122 Loaded 0 fresh discovered devices for all_expired 206s PASSED [ 73%] 206s tests/test_storage.py::test_expire_future_discovered_device_advertisement_data 206s -------------------------------- live log call --------------------------------- 206s DEBUG habluetooth.storage:storage.py:122 Loaded 1 fresh discovered devices for myscanner 206s WARNING habluetooth.storage:storage.py:107 Discarding timestamp 1765757499.8027196 for CC:DD:EE:FF:AA:BB on scanner all_future as it is the future (now = 1764757499.802731) 206s DEBUG habluetooth.storage:storage.py:122 Loaded 0 fresh discovered devices for all_future 206s PASSED [ 74%] 206s tests/test_storage.py::test_discovered_device_advertisement_data_from_dict_corrupt 206s -------------------------------- live log call --------------------------------- 206s 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' 206s Traceback (most recent call last): 206s File "/usr/lib/python3/dist-packages/habluetooth/storage.py", line 138, in discovered_device_advertisement_data_from_dict 206s _deserialize_discovered_device_advertisement_datas( 206s ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~^ 206s data[DISCOVERED_DEVICE_ADVERTISEMENT_DATAS] 206s ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 206s ), 206s ^ 206s File "/usr/lib/python3/dist-packages/habluetooth/storage.py", line 198, in _deserialize_discovered_device_advertisement_datas 206s _ble_device_from_dict(device_advertisement_data["device"]), 206s ~~~~~~~~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 206s File "/usr/lib/python3/dist-packages/habluetooth/storage.py", line 215, in _ble_device_from_dict 206s return BLEDevice(**device_data) 206s TypeError: BLEDevice.__init__() missing 1 required positional argument: 'name' 206s PASSED [ 75%] 206s tests/test_storage.py::test_backward_compatibility_rssi_in_device_dict PASSED [ 76%] 206s tests/test_wrappers.py::test_test_switch_adapters_when_out_of_slots 206s -------------------------------- live log setup -------------------------------- 206s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 206s -------------------------------- live log call --------------------------------- 206s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (00:00:00:00:00:01) 206s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci1 (00:00:00:00:00:02) 206s DEBUG habluetooth.wrappers:wrappers.py:279 00:00:00:00:00:01: Looking for backend to connect 206s 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) 206s 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) 206s 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) 206s DEBUG habluetooth.wrappers:wrappers.py:279 00:00:00:00:00:02: Looking for backend to connect 206s 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) 206s DEBUG habluetooth.wrappers:wrappers.py:279 00:00:00:00:00:03: Looking for backend to connect 206s 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) 206s 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) 206s 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) 206s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (00:00:00:00:00:01) 206s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci1 (00:00:00:00:00:02) 206s PASSED [ 77%] 206s tests/test_wrappers.py::test_release_slot_on_connect_failure 206s -------------------------------- live log setup -------------------------------- 206s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 206s -------------------------------- live log call --------------------------------- 206s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (00:00:00:00:00:01) 206s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci1 (00:00:00:00:00:02) 206s DEBUG habluetooth.wrappers:wrappers.py:279 00:00:00:00:00:01: Looking for backend to connect 206s 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) 206s 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) 206s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (00:00:00:00:00:01) 206s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci1 (00:00:00:00:00:02) 206s PASSED [ 78%] 206s tests/test_wrappers.py::test_release_slot_on_connect_exception 206s -------------------------------- live log setup -------------------------------- 206s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 206s -------------------------------- live log call --------------------------------- 206s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (00:00:00:00:00:01) 206s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci1 (00:00:00:00:00:02) 206s DEBUG habluetooth.wrappers:wrappers.py:279 00:00:00:00:00:01: Looking for backend to connect 206s 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) 206s 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) 206s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (00:00:00:00:00:01) 206s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci1 (00:00:00:00:00:02) 206s PASSED [ 80%] 206s tests/test_wrappers.py::test_switch_adapters_on_failure 206s -------------------------------- live log setup -------------------------------- 206s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 206s -------------------------------- live log call --------------------------------- 206s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (00:00:00:00:00:01) 206s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci1 (00:00:00:00:00:02) 206s DEBUG habluetooth.wrappers:wrappers.py:279 00:00:00:00:00:01: Looking for backend to connect 206s 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) 206s 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) 206s DEBUG habluetooth.wrappers:wrappers.py:279 00:00:00:00:00:01: Looking for backend to connect 206s 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) 206s 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) 206s DEBUG habluetooth.wrappers:wrappers.py:279 00:00:00:00:00:01: Looking for backend to connect 206s 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) 206s 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) 206s 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) 206s DEBUG habluetooth.wrappers:wrappers.py:279 00:00:00:00:00:01: Looking for backend to connect 206s 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) 206s 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) 206s 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) 206s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (00:00:00:00:00:01) 206s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci1 (00:00:00:00:00:02) 206s PASSED [ 81%] 206s tests/test_wrappers.py::test_switch_adapters_on_connecting 206s -------------------------------- live log setup -------------------------------- 206s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 206s -------------------------------- live log call --------------------------------- 206s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (00:00:00:00:00:01) 206s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci1 (00:00:00:00:00:02) 206s DEBUG habluetooth.wrappers:wrappers.py:279 00:00:00:00:00:01: Looking for backend to connect 206s 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) 206s 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) 206s DEBUG habluetooth.wrappers:wrappers.py:279 00:00:00:00:00:01: Looking for backend to connect 206s 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) 206s 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) 206s 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) 207s 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) 207s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (00:00:00:00:00:01) 207s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci1 (00:00:00:00:00:02) 207s PASSED [ 82%] 207s tests/test_wrappers.py::test_single_adapter_connection_history 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 hci0 (AA:BB:CC:DD:EE:00) 207s DEBUG habluetooth.wrappers:wrappers.py:279 00:00:00:00:00:11: Looking for backend to connect 207s 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) 207s 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) 207s 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) 207s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (AA:BB:CC:DD:EE:00) 207s PASSED [ 83%] 207s tests/test_wrappers.py::test_passing_subclassed_str_as_address 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 hci0 (00:00:00:00:00:01) 207s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci1 (00:00:00:00:00:02) 207s DEBUG habluetooth.wrappers:wrappers.py:279 00:00:00:00:00:01: Looking for backend to connect 207s 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) 207s 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) 207s 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) 207s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (00:00:00:00:00:01) 207s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci1 (00:00:00:00:00:02) 207s PASSED [ 84%] 207s tests/test_wrappers.py::test_find_device_by_address 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 hci0 (00:00:00:00:00:01) 207s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci1 (00:00:00:00:00:02) 207s PASSED [ 85%] 207s tests/test_wrappers.py::test_discover 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 hci0 (00:00:00:00:00:01) 207s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci1 (00:00:00:00:00:02) 207s PASSED [ 86%] 207s tests/test_wrappers.py::test_raise_after_shutdown 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 hci0 (00:00:00:00:00:01) 207s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci1 (00:00:00:00:00:02) 207s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (00:00:00:00:00:01) 207s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci1 (00:00:00:00:00:02) 207s PASSED [ 87%] 207s tests/test_wrappers.py::test_wrapped_instance_with_filter 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 [ 88%] 207s ------------------------------ live log teardown ------------------------------- 207s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (AA:BB:CC:DD:EE:00) 207s 207s tests/test_wrappers.py::test_wrapped_instance_with_service_uuids 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 [ 90%] 207s ------------------------------ live log teardown ------------------------------- 207s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (AA:BB:CC:DD:EE:00) 207s 207s tests/test_wrappers.py::test_wrapped_instance_with_service_uuids_with_coro_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 PASSED [ 91%] 207s ------------------------------ live log teardown ------------------------------- 207s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (AA:BB:CC:DD:EE:00) 207s 207s tests/test_wrappers.py::test_wrapped_instance_with_broken_callbacks 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 [ 92%] 207s ------------------------------ live log teardown ------------------------------- 207s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (AA:BB:CC:DD:EE:00) 207s 207s tests/test_wrappers.py::test_wrapped_instance_changes_uuids 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 [ 93%] 207s ------------------------------ live log teardown ------------------------------- 207s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (AA:BB:CC:DD:EE:00) 207s 207s tests/test_wrappers.py::test_wrapped_instance_changes_filters 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 [ 94%] 207s ------------------------------ live log teardown ------------------------------- 207s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (AA:BB:CC:DD:EE:00) 207s 207s tests/test_wrappers.py::test_wrapped_instance_unsupported_filter 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 WARNING habluetooth.wrappers:wrappers.py:120 Only UUIDs filters are supported 207s PASSED [ 95%] 207s ------------------------------ live log teardown ------------------------------- 207s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (AA:BB:CC:DD:EE:00) 207s 207s tests/test_wrappers.py::test_client_with_services_parameter 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 hci0 (00:00:00:00:00:01) 207s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci1 (00:00:00:00:00:02) 207s DEBUG habluetooth.wrappers:wrappers.py:279 00:00:00:00:00:01: Looking for backend to connect 207s 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) 207s 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) 207s 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) 207s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (00:00:00:00:00:01) 207s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci1 (00:00:00:00:00:02) 207s PASSED [ 96%] 207s tests/test_wrappers.py::test_client_with_pair_parameter 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 hci0 (00:00:00:00:00:01) 207s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci1 (00:00:00:00:00:02) 207s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (00:00:00:00:00:01) 207s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci1 (00:00:00:00:00:02) 207s PASSED [ 97%] 207s tests/test_wrappers.py::test_client_services_normalization 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 hci0 (00:00:00:00:00:01) 207s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci1 (00:00:00:00:00:02) 207s DEBUG habluetooth.wrappers:wrappers.py:279 00:00:00:00:00:01: Looking for backend to connect 207s 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) 207s 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) 207s 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) 207s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (00:00:00:00:00:01) 207s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci1 (00:00:00:00:00:02) 207s PASSED [ 98%] 207s tests/test_wrappers.py::test_client_with_none_services 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 hci0 (00:00:00:00:00:01) 207s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci1 (00:00:00:00:00:02) 207s DEBUG habluetooth.wrappers:wrappers.py:279 00:00:00:00:00:01: Looking for backend to connect 207s 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) 207s 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) 207s 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) 207s DEBUG habluetooth.wrappers:wrappers.py:279 00:00:00:00:00:01: Looking for backend to connect 207s 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) 207s 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) 207s 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) 207s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (00:00:00:00:00:01) 207s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci1 (00:00:00:00:00:02) 207s PASSED [100%] 207s ------------------------------ live log teardown ------------------------------- 207s DEBUG habluetooth.manager:manager.py:322 Stopping bluetooth manager 207s 207s 207s =============================== warnings summary =============================== 207s tests/test_base_scanner.py: 9 warnings 207s tests/test_manager.py: 3 warnings 207s tests/test_wrappers.py: 14 warnings 207s /tmp/autopkgtest.YDvXY4/autopkgtest_tmp/build/tests/__init__.py:64: DeprecationWarning: Passing additional arguments for BLEDevice is deprecated and has no effect. 207s return BLEDevice(**new) 207s 207s tests/test_base_scanner.py::test_remote_scanner_expires_non_connectable 207s /tmp/autopkgtest.YDvXY4/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 207s assert len(scanner._discovered_device_timestamps) == 1 207s 207s tests/test_base_scanner.py::test_remote_scanner_expires_non_connectable 207s /tmp/autopkgtest.YDvXY4/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 207s assert len(scanner._discovered_device_timestamps) == 0 207s 207s tests/test_base_scanner.py::test_remote_scanner_expires_non_connectable 207s /tmp/autopkgtest.YDvXY4/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 207s assert len(scanner._discovered_device_timestamps) == 0 207s 207s -- Docs: https://docs.pytest.org/en/stable/how-to/capture-warnings.html 207s ================== 89 passed, 1 skipped, 29 warnings in 1.20s ================== 207s autopkgtest [10:25:00]: test pybuild-autopkgtest: -----------------------] 212s pybuild-autopkgtest PASS 212s autopkgtest [10:25:05]: test pybuild-autopkgtest: - - - - - - - - - - results - - - - - - - - - - 217s autopkgtest [10:25:10]: @@@@@@@@@@@@@@@@@@@@ summary 217s pybuild-autopkgtest PASS