0s autopkgtest [21:46:28]: starting date and time: 2025-12-05 21:46:28+0000 0s autopkgtest [21:46:28]: git checkout: 4b346b80 nova: make wait_reboot return success even when a no-op 0s autopkgtest [21:46:28]: host juju-7f2275-prod-proposed-migration-environment-2; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work._0gsgjbm/out --timeout-copy=6000 --setup-commands /home/ubuntu/autopkgtest-cloud/worker-config-production/setup-canonical.sh --apt-pocket=proposed=src:python3-defaults --apt-upgrade habluetooth --timeout-short=300 --timeout-copy=20000 --timeout-build=20000 --env=ADT_TEST_TRIGGERS=python3-defaults/3.13.9-2 -- ssh -s /home/ubuntu/autopkgtest/ssh-setup/nova -- --flavor autopkgtest-cpu2-ram4-disk20-s390x --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-2@sto01-s390x-20.secgroup --name adt-resolute-s390x-habluetooth-20251205-214628-juju-7f2275-prod-proposed-migration-environment-2-ddf998ab-47a0-4eea-9634-f1475e6ab456 --image adt/ubuntu-resolute-s390x-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-2 --net-id=net_prod-autopkgtest-workers-s390x -e TERM=linux --mirror=http://ftpmaster.internal/ubuntu/ 4s Creating nova instance adt-resolute-s390x-habluetooth-20251205-214628-juju-7f2275-prod-proposed-migration-environment-2-ddf998ab-47a0-4eea-9634-f1475e6ab456 from image adt/ubuntu-resolute-s390x-server-20251205.img (UUID e0f6ccba-b422-4dad-b382-fbc5a99524c4)... 86s autopkgtest [21:47:54]: testbed dpkg architecture: s390x 86s autopkgtest [21:47:54]: testbed apt version: 3.1.12 87s autopkgtest [21:47:55]: @@@@@@@@@@@@@@@@@@@@ test bed setup 87s autopkgtest [21:47:55]: testbed release detected to be: None 88s autopkgtest [21:47:56]: updating testbed package index (apt update) 88s Get:1 http://ftpmaster.internal/ubuntu resolute-proposed InRelease [124 kB] 88s Hit:2 http://ftpmaster.internal/ubuntu resolute InRelease 88s Hit:3 http://ftpmaster.internal/ubuntu resolute-updates InRelease 88s Hit:4 http://ftpmaster.internal/ubuntu resolute-security InRelease 88s Get:5 http://ftpmaster.internal/ubuntu resolute-proposed/main Sources [143 kB] 88s Get:6 http://ftpmaster.internal/ubuntu resolute-proposed/universe Sources [847 kB] 89s Get:7 http://ftpmaster.internal/ubuntu resolute-proposed/multiverse Sources [23.4 kB] 89s Get:8 http://ftpmaster.internal/ubuntu resolute-proposed/main s390x Packages [224 kB] 89s Get:9 http://ftpmaster.internal/ubuntu resolute-proposed/universe s390x Packages [694 kB] 89s Get:10 http://ftpmaster.internal/ubuntu resolute-proposed/multiverse s390x Packages [6484 B] 91s Fetched 2062 kB in 2s (922 kB/s) 94s Reading package lists... 96s Hit:1 http://ftpmaster.internal/ubuntu resolute-proposed InRelease 96s Hit:2 http://ftpmaster.internal/ubuntu resolute InRelease 96s Hit:3 http://ftpmaster.internal/ubuntu resolute-updates InRelease 96s Hit:4 http://ftpmaster.internal/ubuntu resolute-security InRelease 99s Reading package lists... 100s Reading package lists... 101s Building dependency tree... 101s Reading state information... 102s Calculating upgrade... 103s The following packages will be upgraded: 103s libnftnl11 libpython3-stdlib lxd-installer python3 python3-attr python3-gdbm 103s python3-minimal 103s 7 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 103s Need to get 201 kB of archives. 103s After this operation, 20.5 kB of additional disk space will be used. 103s Get:1 http://ftpmaster.internal/ubuntu resolute-proposed/main s390x python3-minimal s390x 3.13.9-2 [28.1 kB] 103s Get:2 http://ftpmaster.internal/ubuntu resolute-proposed/main s390x python3 s390x 3.13.9-2 [23.0 kB] 103s Get:3 http://ftpmaster.internal/ubuntu resolute-proposed/main s390x libpython3-stdlib s390x 3.13.9-2 [10.8 kB] 103s Get:4 http://ftpmaster.internal/ubuntu resolute/main s390x libnftnl11 s390x 1.3.1-1 [68.7 kB] 103s Get:5 http://ftpmaster.internal/ubuntu resolute-proposed/main s390x python3-gdbm s390x 3.13.9-2 [11.0 kB] 103s Get:6 http://ftpmaster.internal/ubuntu resolute/main s390x lxd-installer all 14ubuntu0 [5428 B] 103s Get:7 http://ftpmaster.internal/ubuntu resolute/main s390x python3-attr all 25.4.0-1 [53.6 kB] 105s dpkg-preconfigure: unable to re-open stdin: No such file or directory 105s Fetched 201 kB in 0s (1159 kB/s) 106s (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 ... 61321 files and directories currently installed.) 106s Preparing to unpack .../python3-minimal_3.13.9-2_s390x.deb ... 106s Unpacking python3-minimal (3.13.9-2) over (3.13.7-1) ... 107s Setting up python3-minimal (3.13.9-2) ... 109s (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 ... 61321 files and directories currently installed.) 109s Preparing to unpack .../0-python3_3.13.9-2_s390x.deb ... 109s running python pre-rtupdate hooks for python3.13... 109s Unpacking python3 (3.13.9-2) over (3.13.7-1) ... 110s Preparing to unpack .../1-libpython3-stdlib_3.13.9-2_s390x.deb ... 110s Unpacking libpython3-stdlib:s390x (3.13.9-2) over (3.13.7-1) ... 110s Preparing to unpack .../2-libnftnl11_1.3.1-1_s390x.deb ... 110s Unpacking libnftnl11:s390x (1.3.1-1) over (1.3.0-1) ... 110s Preparing to unpack .../3-python3-gdbm_3.13.9-2_s390x.deb ... 110s Unpacking python3-gdbm (3.13.9-2) over (3.13.9-1) ... 110s Preparing to unpack .../4-lxd-installer_14ubuntu0_all.deb ... 111s Unpacking lxd-installer (14ubuntu0) over (13ubuntu0) ... 111s Preparing to unpack .../5-python3-attr_25.4.0-1_all.deb ... 111s Unpacking python3-attr (25.4.0-1) over (25.3.0-1) ... 112s Setting up python3-gdbm (3.13.9-2) ... 112s Setting up libnftnl11:s390x (1.3.1-1) ... 112s Setting up lxd-installer (14ubuntu0) ... 114s Setting up libpython3-stdlib:s390x (3.13.9-2) ... 114s Setting up python3 (3.13.9-2) ... 114s running python rtupdate hooks for python3.13... 114s running python post-rtupdate hooks for python3.13... 115s Setting up python3-attr (25.4.0-1) ... 116s Processing triggers for man-db (2.13.1-1) ... 119s Processing triggers for libc-bin (2.42-2ubuntu2) ... 120s autopkgtest [21:48:28]: upgrading testbed (apt dist-upgrade and autopurge) 120s Reading package lists... 121s Building dependency tree... 121s Reading state information... 121s Calculating upgrade... 122s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 123s Reading package lists... 124s Building dependency tree... 124s Reading state information... 125s Solving dependencies... 125s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 126s autopkgtest [21:48:34]: rebooting testbed after setup commands that affected boot 173s autopkgtest [21:49:21]: testbed running kernel: Linux 6.17.0-6-generic #6-Ubuntu SMP Tue Oct 7 12:36:22 UTC 2025 177s autopkgtest [21:49:25]: @@@@@@@@@@@@@@@@@@@@ apt-source habluetooth 181s Get:1 http://ftpmaster.internal/ubuntu resolute/universe habluetooth 4.0.1-1 (dsc) [2584 B] 181s Get:2 http://ftpmaster.internal/ubuntu resolute/universe habluetooth 4.0.1-1 (tar) [133 kB] 181s Get:3 http://ftpmaster.internal/ubuntu resolute/universe habluetooth 4.0.1-1 (diff) [4508 B] 182s gpgv: Signature made Mon Jul 7 19:49:22 2025 UTC 182s gpgv: using RSA key FB8ACFA78C726089C38AD0269605A1098C63B92A 182s gpgv: Can't check signature: No public key 182s dpkg-source: warning: cannot verify inline signature for ./habluetooth_4.0.1-1.dsc: no acceptable signature found 182s autopkgtest [21:49:30]: testing package habluetooth version 4.0.1-1 183s autopkgtest [21:49:31]: build not needed 183s autopkgtest [21:49:31]: test pybuild-autopkgtest: preparing testbed 184s Reading package lists... 186s Building dependency tree... 186s Reading state information... 186s Solving dependencies... 188s The following NEW packages will be installed: 188s autoconf automake autopoint autotools-dev bluez build-essential cpp cpp-15 188s cpp-15-s390x-linux-gnu cpp-s390x-linux-gnu debhelper debugedit dh-autoreconf 188s dh-python dh-strip-nondeterminism docutils-common dwz fonts-font-awesome 188s fonts-lato furo g++ g++-15 g++-15-s390x-linux-gnu g++-s390x-linux-gnu gcc 188s gcc-15 gcc-15-s390x-linux-gnu gcc-s390x-linux-gnu gettext intltool-debian 188s libarchive-zip-perl libasan8 libasound2-data libasound2t64 libcc1-0 188s libdebhelper-perl libfile-stripnondeterminism-perl libgcc-15-dev libgomp1 188s libisl23 libitm1 libjs-jquery libjs-jquery-hotkeys libjs-jquery-isonscreen 188s libjs-jquery-metadata libjs-jquery-tablesorter 188s libjs-jquery-throttle-debounce libjs-sphinxdoc libjs-underscore libjson-perl 188s libmpc3 libpython3.14-minimal libpython3.14-stdlib libstdc++-15-dev libtool 188s libubsan1 m4 po-debconf pybuild-plugin-autopkgtest pybuild-plugin-pyproject 188s python3-accessible-pygments python3-aiooui python3-alabaster python3-all 188s python3-async-generator python3-async-interrupt python3-bleak 188s python3-bleak-retry-connector python3-bluetooth-adapters 188s python3-bluetooth-auto-recovery python3-bluetooth-data-tools python3-bs4 188s python3-btsocket python3-build python3-coverage python3-dbus-fast 188s python3-defusedxml python3-docutils python3-freezegun python3-habluetooth 188s python3-imagesize python3-iniconfig python3-installer 188s python3-mdit-py-plugins python3-myst-parser python3-pluggy 188s python3-poetry-core python3-pyproject-hooks python3-pyric python3-pytest 188s python3-pytest-asyncio python3-pytest-cov python3-roman 188s python3-roman-numerals python3-snowballstemmer python3-soupsieve 188s python3-sphinx python3-uart-devices python3-usb-devices python3-wheel 188s python3.14 python3.14-minimal sgml-base sphinx-basic-ng sphinx-common 188s sphinx-rtd-theme-common xml-core 188s 0 upgraded, 107 newly installed, 0 to remove and 0 not upgraded. 188s Need to get 75.2 MB of archives. 188s After this operation, 252 MB of additional disk space will be used. 188s Get:1 http://ftpmaster.internal/ubuntu resolute/main s390x fonts-lato all 2.015-1build1 [2779 kB] 188s Get:2 http://ftpmaster.internal/ubuntu resolute/main s390x libpython3.14-minimal s390x 3.14.0-4 [904 kB] 188s Get:3 http://ftpmaster.internal/ubuntu resolute/main s390x python3.14-minimal s390x 3.14.0-4 [2509 kB] 188s Get:4 http://ftpmaster.internal/ubuntu resolute/main s390x m4 s390x 1.4.20-2 [223 kB] 189s Get:5 http://ftpmaster.internal/ubuntu resolute/main s390x autoconf all 2.72-3.1ubuntu1 [384 kB] 189s Get:6 http://ftpmaster.internal/ubuntu resolute/main s390x autotools-dev all 20240727.1 [43.4 kB] 189s Get:7 http://ftpmaster.internal/ubuntu resolute/main s390x automake all 1:1.18.1-3 [582 kB] 189s Get:8 http://ftpmaster.internal/ubuntu resolute/main s390x autopoint all 0.23.2-1 [620 kB] 189s Get:9 http://ftpmaster.internal/ubuntu resolute/main s390x libasound2-data all 1.2.14-2ubuntu1 [21.3 kB] 189s Get:10 http://ftpmaster.internal/ubuntu resolute/main s390x libasound2t64 s390x 1.2.14-2ubuntu1 [414 kB] 189s Get:11 http://ftpmaster.internal/ubuntu resolute/main s390x bluez s390x 5.84-1 [1508 kB] 189s Get:12 http://ftpmaster.internal/ubuntu resolute/main s390x libisl23 s390x 0.27-1 [704 kB] 189s Get:13 http://ftpmaster.internal/ubuntu resolute/main s390x libmpc3 s390x 1.3.1-2 [57.4 kB] 189s Get:14 http://ftpmaster.internal/ubuntu resolute/main s390x cpp-15-s390x-linux-gnu s390x 15.2.0-9ubuntu1 [10.2 MB] 189s Get:15 http://ftpmaster.internal/ubuntu resolute/main s390x cpp-15 s390x 15.2.0-9ubuntu1 [1024 B] 189s Get:16 http://ftpmaster.internal/ubuntu resolute/main s390x cpp-s390x-linux-gnu s390x 4:15.2.0-4ubuntu1 [5746 B] 189s Get:17 http://ftpmaster.internal/ubuntu resolute/main s390x cpp s390x 4:15.2.0-4ubuntu1 [22.4 kB] 189s Get:18 http://ftpmaster.internal/ubuntu resolute/main s390x libcc1-0 s390x 15.2.0-9ubuntu1 [50.0 kB] 189s Get:19 http://ftpmaster.internal/ubuntu resolute/main s390x libgomp1 s390x 15.2.0-9ubuntu1 [154 kB] 189s Get:20 http://ftpmaster.internal/ubuntu resolute/main s390x libitm1 s390x 15.2.0-9ubuntu1 [30.9 kB] 189s Get:21 http://ftpmaster.internal/ubuntu resolute/main s390x libasan8 s390x 15.2.0-9ubuntu1 [2969 kB] 190s Get:22 http://ftpmaster.internal/ubuntu resolute/main s390x libubsan1 s390x 15.2.0-9ubuntu1 [1211 kB] 190s Get:23 http://ftpmaster.internal/ubuntu resolute/main s390x libgcc-15-dev s390x 15.2.0-9ubuntu1 [1045 kB] 190s Get:24 http://ftpmaster.internal/ubuntu resolute/main s390x gcc-15-s390x-linux-gnu s390x 15.2.0-9ubuntu1 [19.9 MB] 190s Get:25 http://ftpmaster.internal/ubuntu resolute/main s390x gcc-15 s390x 15.2.0-9ubuntu1 [516 kB] 190s Get:26 http://ftpmaster.internal/ubuntu resolute/main s390x gcc-s390x-linux-gnu s390x 4:15.2.0-4ubuntu1 [1208 B] 190s Get:27 http://ftpmaster.internal/ubuntu resolute/main s390x gcc s390x 4:15.2.0-4ubuntu1 [5018 B] 190s Get:28 http://ftpmaster.internal/ubuntu resolute/main s390x libstdc++-15-dev s390x 15.2.0-9ubuntu1 [2659 kB] 190s Get:29 http://ftpmaster.internal/ubuntu resolute/main s390x g++-15-s390x-linux-gnu s390x 15.2.0-9ubuntu1 [11.7 MB] 190s Get:30 http://ftpmaster.internal/ubuntu resolute/main s390x g++-15 s390x 15.2.0-9ubuntu1 [24.3 kB] 190s Get:31 http://ftpmaster.internal/ubuntu resolute/main s390x g++-s390x-linux-gnu s390x 4:15.2.0-4ubuntu1 [956 B] 190s Get:32 http://ftpmaster.internal/ubuntu resolute/main s390x g++ s390x 4:15.2.0-4ubuntu1 [1078 B] 190s Get:33 http://ftpmaster.internal/ubuntu resolute/main s390x build-essential s390x 12.12ubuntu2 [5258 B] 190s Get:34 http://ftpmaster.internal/ubuntu resolute/main s390x libdebhelper-perl all 13.24.2ubuntu1 [95.7 kB] 190s Get:35 http://ftpmaster.internal/ubuntu resolute/main s390x libtool all 2.5.4-7 [169 kB] 190s Get:36 http://ftpmaster.internal/ubuntu resolute/main s390x dh-autoreconf all 21 [12.5 kB] 190s Get:37 http://ftpmaster.internal/ubuntu resolute/main s390x libarchive-zip-perl all 1.68-1 [90.2 kB] 191s Get:38 http://ftpmaster.internal/ubuntu resolute/main s390x libfile-stripnondeterminism-perl all 1.15.0-1 [20.5 kB] 191s Get:39 http://ftpmaster.internal/ubuntu resolute/main s390x dh-strip-nondeterminism all 1.15.0-1 [5090 B] 191s Get:40 http://ftpmaster.internal/ubuntu resolute/main s390x debugedit s390x 1:5.2-3 [52.8 kB] 191s Get:41 http://ftpmaster.internal/ubuntu resolute/main s390x dwz s390x 0.16-2 [121 kB] 191s Get:42 http://ftpmaster.internal/ubuntu resolute/main s390x gettext s390x 0.23.2-1 [1062 kB] 191s Get:43 http://ftpmaster.internal/ubuntu resolute/main s390x intltool-debian all 0.35.0+20060710.6build1 [24.1 kB] 191s Get:44 http://ftpmaster.internal/ubuntu resolute/main s390x po-debconf all 1.0.21+nmu1 [233 kB] 191s Get:45 http://ftpmaster.internal/ubuntu resolute/main s390x debhelper all 13.24.2ubuntu1 [896 kB] 191s Get:46 http://ftpmaster.internal/ubuntu resolute/universe s390x dh-python all 6.20251029 [120 kB] 191s Get:47 http://ftpmaster.internal/ubuntu resolute/main s390x sgml-base all 1.31+nmu1 [11.0 kB] 191s Get:48 http://ftpmaster.internal/ubuntu resolute/main s390x xml-core all 0.19 [20.3 kB] 191s Get:49 http://ftpmaster.internal/ubuntu resolute/main s390x docutils-common all 0.22.3+dfsg-1 [130 kB] 191s Get:50 http://ftpmaster.internal/ubuntu resolute/main s390x fonts-font-awesome all 5.0.10+really4.7.0~dfsg-4.1build1 [531 kB] 191s Get:51 http://ftpmaster.internal/ubuntu resolute/universe s390x python3-accessible-pygments all 0.0.5-2 [790 kB] 191s Get:52 http://ftpmaster.internal/ubuntu resolute/main s390x python3-soupsieve all 2.7-2 [33.6 kB] 191s Get:53 http://ftpmaster.internal/ubuntu resolute/main s390x python3-bs4 all 4.14.2-1 [82.8 kB] 191s Get:54 http://ftpmaster.internal/ubuntu resolute/main s390x python3-defusedxml all 0.7.1-3 [42.2 kB] 191s Get:55 http://ftpmaster.internal/ubuntu resolute/main s390x libjs-jquery all 3.7.1+dfsg+~3.5.33-1build1 [321 kB] 191s Get:56 http://ftpmaster.internal/ubuntu resolute/main s390x libjs-underscore all 1.13.4~dfsg+~1.11.4-3 [118 kB] 191s Get:57 http://ftpmaster.internal/ubuntu resolute/main s390x libjs-sphinxdoc all 8.2.3-1ubuntu2 [28.0 kB] 191s Get:58 http://ftpmaster.internal/ubuntu resolute/main s390x libjson-perl all 4.10000-1 [81.9 kB] 191s Get:59 http://ftpmaster.internal/ubuntu resolute/main s390x sphinx-common all 8.2.3-1ubuntu2 [656 kB] 191s Get:60 http://ftpmaster.internal/ubuntu resolute/main s390x python3-alabaster all 0.7.16-0.1 [18.5 kB] 191s Get:61 http://ftpmaster.internal/ubuntu resolute/main s390x python3-roman-numerals all 3.1.0-2 [8470 B] 191s Get:62 http://ftpmaster.internal/ubuntu resolute/main s390x python3-docutils all 0.22.3+dfsg-1 [438 kB] 191s Get:63 http://ftpmaster.internal/ubuntu resolute/main s390x python3-imagesize all 1.4.1-1build1 [6900 B] 191s Get:64 http://ftpmaster.internal/ubuntu resolute/main s390x python3-roman all 5.2-1 [10.8 kB] 191s Get:65 http://ftpmaster.internal/ubuntu resolute/main s390x python3-snowballstemmer all 3.0.1-1 [65.9 kB] 191s Get:66 http://ftpmaster.internal/ubuntu resolute/main s390x python3-sphinx all 8.2.3-1ubuntu2 [482 kB] 191s Get:67 http://ftpmaster.internal/ubuntu resolute/universe s390x sphinx-basic-ng all 1.0.0~beta2-1 [12.0 kB] 191s Get:68 http://ftpmaster.internal/ubuntu resolute/universe s390x furo all 2025.09.25+dfsg-2 [53.7 kB] 191s Get:69 http://ftpmaster.internal/ubuntu resolute/universe s390x libjs-jquery-metadata all 12-4 [6582 B] 191s Get:70 http://ftpmaster.internal/ubuntu resolute/universe s390x libjs-jquery-tablesorter all 1:2.31.3+dfsg1-4 [192 kB] 191s Get:71 http://ftpmaster.internal/ubuntu resolute/universe s390x libjs-jquery-throttle-debounce all 1.1+dfsg.1-2 [12.5 kB] 191s Get:72 http://ftpmaster.internal/ubuntu resolute/main s390x libpython3.14-stdlib s390x 3.14.0-4 [2373 kB] 191s Get:73 http://ftpmaster.internal/ubuntu resolute/universe s390x pybuild-plugin-autopkgtest all 6.20251029 [1746 B] 191s Get:74 http://ftpmaster.internal/ubuntu resolute/universe s390x python3-pyproject-hooks all 1.2.0-1 [10.2 kB] 191s Get:75 http://ftpmaster.internal/ubuntu resolute/universe s390x python3-wheel all 0.46.1-2 [22.1 kB] 191s Get:76 http://ftpmaster.internal/ubuntu resolute/universe s390x python3-build all 1.2.2-4 [31.0 kB] 191s Get:77 http://ftpmaster.internal/ubuntu resolute/universe s390x python3-installer all 0.7.0+dfsg1-3 [17.4 kB] 191s Get:78 http://ftpmaster.internal/ubuntu resolute/universe s390x pybuild-plugin-pyproject all 6.20251029 [1732 B] 191s Get:79 http://ftpmaster.internal/ubuntu resolute/universe s390x python3-aiooui all 0.1.9-1 [312 kB] 191s Get:80 http://ftpmaster.internal/ubuntu resolute/main s390x python3.14 s390x 3.14.0-4 [805 kB] 191s Get:81 http://ftpmaster.internal/ubuntu resolute-proposed/main s390x python3-all s390x 3.13.9-2 [892 B] 191s Get:82 http://ftpmaster.internal/ubuntu resolute/universe s390x python3-async-generator all 1.10-7 [18.1 kB] 191s Get:83 http://ftpmaster.internal/ubuntu resolute/universe s390x python3-async-interrupt all 1.2.2-1 [6604 B] 191s Get:84 http://ftpmaster.internal/ubuntu resolute/universe s390x python3-dbus-fast s390x 3.1.2-1 [1090 kB] 191s Get:85 http://ftpmaster.internal/ubuntu resolute/universe s390x python3-bleak all 1.1.1-1 [95.0 kB] 191s Get:86 http://ftpmaster.internal/ubuntu resolute/universe s390x python3-uart-devices all 0.1.1-1 [6742 B] 191s Get:87 http://ftpmaster.internal/ubuntu resolute/universe s390x python3-usb-devices all 0.4.5-2 [9014 B] 191s Get:88 http://ftpmaster.internal/ubuntu resolute/universe s390x python3-bluetooth-adapters all 2.1.1-1 [13.7 kB] 191s Get:89 http://ftpmaster.internal/ubuntu resolute/universe s390x python3-bleak-retry-connector all 4.4.4-1 [20.1 kB] 191s Get:90 http://ftpmaster.internal/ubuntu resolute/universe s390x python3-btsocket all 0.3.0-1 [19.0 kB] 191s Get:91 http://ftpmaster.internal/ubuntu resolute/universe s390x python3-pyric all 0.1.6.4+git20170421.0562b17-1 [114 kB] 191s Get:92 http://ftpmaster.internal/ubuntu resolute/main s390x sphinx-rtd-theme-common all 3.0.2+dfsg-3 [1013 kB] 191s Get:93 http://ftpmaster.internal/ubuntu resolute/universe s390x python3-bluetooth-auto-recovery all 1.5.3-1 [29.4 kB] 191s Get:94 http://ftpmaster.internal/ubuntu resolute/universe s390x python3-bluetooth-data-tools s390x 1.28.4-1 [11.7 kB] 191s Get:95 http://ftpmaster.internal/ubuntu resolute/universe s390x python3-coverage s390x 7.8.2+dfsg1-1 [156 kB] 191s Get:96 http://ftpmaster.internal/ubuntu resolute/universe s390x python3-freezegun all 1.5.1-1.2 [15.9 kB] 191s Get:97 http://ftpmaster.internal/ubuntu resolute/universe s390x python3-habluetooth s390x 4.0.1-1 [65.3 kB] 191s Get:98 http://ftpmaster.internal/ubuntu resolute/universe s390x python3-iniconfig all 2.1.0-1 [6840 B] 192s Get:99 http://ftpmaster.internal/ubuntu resolute/universe s390x python3-mdit-py-plugins all 0.5.0-1 [31.5 kB] 192s Get:100 http://ftpmaster.internal/ubuntu resolute/universe s390x python3-myst-parser all 4.0.1-1 [64.9 kB] 192s Get:101 http://ftpmaster.internal/ubuntu resolute/universe s390x python3-pluggy all 1.6.0-1 [21.0 kB] 192s Get:102 http://ftpmaster.internal/ubuntu resolute/universe s390x python3-poetry-core all 2.2.1-2 [226 kB] 192s Get:103 http://ftpmaster.internal/ubuntu resolute/universe s390x python3-pytest all 8.3.5-2 [252 kB] 192s Get:104 http://ftpmaster.internal/ubuntu resolute/universe s390x python3-pytest-asyncio all 0.25.1-1 [17.0 kB] 192s Get:105 http://ftpmaster.internal/ubuntu resolute/universe s390x libjs-jquery-hotkeys all 0.2.0-1 [13.3 kB] 192s Get:106 http://ftpmaster.internal/ubuntu resolute/universe s390x libjs-jquery-isonscreen all 1.2.0-2 [3814 B] 192s Get:107 http://ftpmaster.internal/ubuntu resolute/universe s390x python3-pytest-cov all 5.0.0-1 [21.3 kB] 194s Fetched 75.2 MB in 4s (18.6 MB/s) 194s Selecting previously unselected package fonts-lato. 195s (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 ... 61321 files and directories currently installed.) 195s Preparing to unpack .../000-fonts-lato_2.015-1build1_all.deb ... 195s Unpacking fonts-lato (2.015-1build1) ... 196s Selecting previously unselected package libpython3.14-minimal:s390x. 196s Preparing to unpack .../001-libpython3.14-minimal_3.14.0-4_s390x.deb ... 196s Unpacking libpython3.14-minimal:s390x (3.14.0-4) ... 196s Selecting previously unselected package python3.14-minimal. 196s Preparing to unpack .../002-python3.14-minimal_3.14.0-4_s390x.deb ... 196s Unpacking python3.14-minimal (3.14.0-4) ... 196s Selecting previously unselected package m4. 196s Preparing to unpack .../003-m4_1.4.20-2_s390x.deb ... 196s Unpacking m4 (1.4.20-2) ... 196s Selecting previously unselected package autoconf. 196s Preparing to unpack .../004-autoconf_2.72-3.1ubuntu1_all.deb ... 196s Unpacking autoconf (2.72-3.1ubuntu1) ... 196s Selecting previously unselected package autotools-dev. 196s Preparing to unpack .../005-autotools-dev_20240727.1_all.deb ... 196s Unpacking autotools-dev (20240727.1) ... 197s Selecting previously unselected package automake. 197s Preparing to unpack .../006-automake_1%3a1.18.1-3_all.deb ... 197s Unpacking automake (1:1.18.1-3) ... 197s Selecting previously unselected package autopoint. 197s Preparing to unpack .../007-autopoint_0.23.2-1_all.deb ... 197s Unpacking autopoint (0.23.2-1) ... 197s Selecting previously unselected package libasound2-data. 197s Preparing to unpack .../008-libasound2-data_1.2.14-2ubuntu1_all.deb ... 197s Unpacking libasound2-data (1.2.14-2ubuntu1) ... 197s Selecting previously unselected package libasound2t64:s390x. 197s Preparing to unpack .../009-libasound2t64_1.2.14-2ubuntu1_s390x.deb ... 197s Unpacking libasound2t64:s390x (1.2.14-2ubuntu1) ... 197s Selecting previously unselected package bluez. 197s Preparing to unpack .../010-bluez_5.84-1_s390x.deb ... 197s Unpacking bluez (5.84-1) ... 197s Selecting previously unselected package libisl23:s390x. 197s Preparing to unpack .../011-libisl23_0.27-1_s390x.deb ... 197s Unpacking libisl23:s390x (0.27-1) ... 198s Selecting previously unselected package libmpc3:s390x. 198s Preparing to unpack .../012-libmpc3_1.3.1-2_s390x.deb ... 198s Unpacking libmpc3:s390x (1.3.1-2) ... 198s Selecting previously unselected package cpp-15-s390x-linux-gnu. 198s Preparing to unpack .../013-cpp-15-s390x-linux-gnu_15.2.0-9ubuntu1_s390x.deb ... 198s Unpacking cpp-15-s390x-linux-gnu (15.2.0-9ubuntu1) ... 199s Selecting previously unselected package cpp-15. 199s Preparing to unpack .../014-cpp-15_15.2.0-9ubuntu1_s390x.deb ... 199s Unpacking cpp-15 (15.2.0-9ubuntu1) ... 199s Selecting previously unselected package cpp-s390x-linux-gnu. 199s Preparing to unpack .../015-cpp-s390x-linux-gnu_4%3a15.2.0-4ubuntu1_s390x.deb ... 199s Unpacking cpp-s390x-linux-gnu (4:15.2.0-4ubuntu1) ... 199s Selecting previously unselected package cpp. 199s Preparing to unpack .../016-cpp_4%3a15.2.0-4ubuntu1_s390x.deb ... 199s Unpacking cpp (4:15.2.0-4ubuntu1) ... 199s Selecting previously unselected package libcc1-0:s390x. 199s Preparing to unpack .../017-libcc1-0_15.2.0-9ubuntu1_s390x.deb ... 199s Unpacking libcc1-0:s390x (15.2.0-9ubuntu1) ... 199s Selecting previously unselected package libgomp1:s390x. 199s Preparing to unpack .../018-libgomp1_15.2.0-9ubuntu1_s390x.deb ... 199s Unpacking libgomp1:s390x (15.2.0-9ubuntu1) ... 199s Selecting previously unselected package libitm1:s390x. 199s Preparing to unpack .../019-libitm1_15.2.0-9ubuntu1_s390x.deb ... 199s Unpacking libitm1:s390x (15.2.0-9ubuntu1) ... 200s Selecting previously unselected package libasan8:s390x. 200s Preparing to unpack .../020-libasan8_15.2.0-9ubuntu1_s390x.deb ... 200s Unpacking libasan8:s390x (15.2.0-9ubuntu1) ... 200s Selecting previously unselected package libubsan1:s390x. 200s Preparing to unpack .../021-libubsan1_15.2.0-9ubuntu1_s390x.deb ... 200s Unpacking libubsan1:s390x (15.2.0-9ubuntu1) ... 200s Selecting previously unselected package libgcc-15-dev:s390x. 200s Preparing to unpack .../022-libgcc-15-dev_15.2.0-9ubuntu1_s390x.deb ... 200s Unpacking libgcc-15-dev:s390x (15.2.0-9ubuntu1) ... 200s Selecting previously unselected package gcc-15-s390x-linux-gnu. 200s Preparing to unpack .../023-gcc-15-s390x-linux-gnu_15.2.0-9ubuntu1_s390x.deb ... 200s Unpacking gcc-15-s390x-linux-gnu (15.2.0-9ubuntu1) ... 203s Selecting previously unselected package gcc-15. 203s Preparing to unpack .../024-gcc-15_15.2.0-9ubuntu1_s390x.deb ... 203s Unpacking gcc-15 (15.2.0-9ubuntu1) ... 203s Selecting previously unselected package gcc-s390x-linux-gnu. 203s Preparing to unpack .../025-gcc-s390x-linux-gnu_4%3a15.2.0-4ubuntu1_s390x.deb ... 203s Unpacking gcc-s390x-linux-gnu (4:15.2.0-4ubuntu1) ... 204s Selecting previously unselected package gcc. 204s Preparing to unpack .../026-gcc_4%3a15.2.0-4ubuntu1_s390x.deb ... 204s Unpacking gcc (4:15.2.0-4ubuntu1) ... 204s Selecting previously unselected package libstdc++-15-dev:s390x. 204s Preparing to unpack .../027-libstdc++-15-dev_15.2.0-9ubuntu1_s390x.deb ... 204s Unpacking libstdc++-15-dev:s390x (15.2.0-9ubuntu1) ... 205s Selecting previously unselected package g++-15-s390x-linux-gnu. 205s Preparing to unpack .../028-g++-15-s390x-linux-gnu_15.2.0-9ubuntu1_s390x.deb ... 205s Unpacking g++-15-s390x-linux-gnu (15.2.0-9ubuntu1) ... 207s Selecting previously unselected package g++-15. 207s Preparing to unpack .../029-g++-15_15.2.0-9ubuntu1_s390x.deb ... 207s Unpacking g++-15 (15.2.0-9ubuntu1) ... 207s Selecting previously unselected package g++-s390x-linux-gnu. 207s Preparing to unpack .../030-g++-s390x-linux-gnu_4%3a15.2.0-4ubuntu1_s390x.deb ... 207s Unpacking g++-s390x-linux-gnu (4:15.2.0-4ubuntu1) ... 207s Selecting previously unselected package g++. 207s Preparing to unpack .../031-g++_4%3a15.2.0-4ubuntu1_s390x.deb ... 207s Unpacking g++ (4:15.2.0-4ubuntu1) ... 207s Selecting previously unselected package build-essential. 207s Preparing to unpack .../032-build-essential_12.12ubuntu2_s390x.deb ... 207s Unpacking build-essential (12.12ubuntu2) ... 207s Selecting previously unselected package libdebhelper-perl. 207s Preparing to unpack .../033-libdebhelper-perl_13.24.2ubuntu1_all.deb ... 207s Unpacking libdebhelper-perl (13.24.2ubuntu1) ... 207s Selecting previously unselected package libtool. 207s Preparing to unpack .../034-libtool_2.5.4-7_all.deb ... 207s Unpacking libtool (2.5.4-7) ... 207s Selecting previously unselected package dh-autoreconf. 207s Preparing to unpack .../035-dh-autoreconf_21_all.deb ... 207s Unpacking dh-autoreconf (21) ... 207s Selecting previously unselected package libarchive-zip-perl. 207s Preparing to unpack .../036-libarchive-zip-perl_1.68-1_all.deb ... 207s Unpacking libarchive-zip-perl (1.68-1) ... 207s Selecting previously unselected package libfile-stripnondeterminism-perl. 207s Preparing to unpack .../037-libfile-stripnondeterminism-perl_1.15.0-1_all.deb ... 207s Unpacking libfile-stripnondeterminism-perl (1.15.0-1) ... 207s Selecting previously unselected package dh-strip-nondeterminism. 207s Preparing to unpack .../038-dh-strip-nondeterminism_1.15.0-1_all.deb ... 207s Unpacking dh-strip-nondeterminism (1.15.0-1) ... 207s Selecting previously unselected package debugedit. 208s Preparing to unpack .../039-debugedit_1%3a5.2-3_s390x.deb ... 208s Unpacking debugedit (1:5.2-3) ... 208s Selecting previously unselected package dwz. 208s Preparing to unpack .../040-dwz_0.16-2_s390x.deb ... 208s Unpacking dwz (0.16-2) ... 208s Selecting previously unselected package gettext. 208s Preparing to unpack .../041-gettext_0.23.2-1_s390x.deb ... 208s Unpacking gettext (0.23.2-1) ... 208s Selecting previously unselected package intltool-debian. 208s Preparing to unpack .../042-intltool-debian_0.35.0+20060710.6build1_all.deb ... 208s Unpacking intltool-debian (0.35.0+20060710.6build1) ... 208s Selecting previously unselected package po-debconf. 208s Preparing to unpack .../043-po-debconf_1.0.21+nmu1_all.deb ... 208s Unpacking po-debconf (1.0.21+nmu1) ... 208s Selecting previously unselected package debhelper. 208s Preparing to unpack .../044-debhelper_13.24.2ubuntu1_all.deb ... 208s Unpacking debhelper (13.24.2ubuntu1) ... 208s Selecting previously unselected package dh-python. 208s Preparing to unpack .../045-dh-python_6.20251029_all.deb ... 208s Unpacking dh-python (6.20251029) ... 208s Selecting previously unselected package sgml-base. 209s Preparing to unpack .../046-sgml-base_1.31+nmu1_all.deb ... 209s Unpacking sgml-base (1.31+nmu1) ... 209s Selecting previously unselected package xml-core. 209s Preparing to unpack .../047-xml-core_0.19_all.deb ... 209s Unpacking xml-core (0.19) ... 209s Selecting previously unselected package docutils-common. 209s Preparing to unpack .../048-docutils-common_0.22.3+dfsg-1_all.deb ... 209s Unpacking docutils-common (0.22.3+dfsg-1) ... 209s Selecting previously unselected package fonts-font-awesome. 209s Preparing to unpack .../049-fonts-font-awesome_5.0.10+really4.7.0~dfsg-4.1build1_all.deb ... 209s Unpacking fonts-font-awesome (5.0.10+really4.7.0~dfsg-4.1build1) ... 210s Selecting previously unselected package python3-accessible-pygments. 210s Preparing to unpack .../050-python3-accessible-pygments_0.0.5-2_all.deb ... 210s Unpacking python3-accessible-pygments (0.0.5-2) ... 210s Selecting previously unselected package python3-soupsieve. 210s Preparing to unpack .../051-python3-soupsieve_2.7-2_all.deb ... 210s Unpacking python3-soupsieve (2.7-2) ... 210s Selecting previously unselected package python3-bs4. 210s Preparing to unpack .../052-python3-bs4_4.14.2-1_all.deb ... 210s Unpacking python3-bs4 (4.14.2-1) ... 210s Selecting previously unselected package python3-defusedxml. 210s Preparing to unpack .../053-python3-defusedxml_0.7.1-3_all.deb ... 210s Unpacking python3-defusedxml (0.7.1-3) ... 210s Selecting previously unselected package libjs-jquery. 210s Preparing to unpack .../054-libjs-jquery_3.7.1+dfsg+~3.5.33-1build1_all.deb ... 210s Unpacking libjs-jquery (3.7.1+dfsg+~3.5.33-1build1) ... 210s Selecting previously unselected package libjs-underscore. 210s Preparing to unpack .../055-libjs-underscore_1.13.4~dfsg+~1.11.4-3_all.deb ... 210s Unpacking libjs-underscore (1.13.4~dfsg+~1.11.4-3) ... 210s Selecting previously unselected package libjs-sphinxdoc. 210s Preparing to unpack .../056-libjs-sphinxdoc_8.2.3-1ubuntu2_all.deb ... 210s Unpacking libjs-sphinxdoc (8.2.3-1ubuntu2) ... 210s Selecting previously unselected package libjson-perl. 210s Preparing to unpack .../057-libjson-perl_4.10000-1_all.deb ... 210s Unpacking libjson-perl (4.10000-1) ... 211s Selecting previously unselected package sphinx-common. 211s Preparing to unpack .../058-sphinx-common_8.2.3-1ubuntu2_all.deb ... 211s Unpacking sphinx-common (8.2.3-1ubuntu2) ... 211s Selecting previously unselected package python3-alabaster. 211s Preparing to unpack .../059-python3-alabaster_0.7.16-0.1_all.deb ... 211s Unpacking python3-alabaster (0.7.16-0.1) ... 211s Selecting previously unselected package python3-roman-numerals. 211s Preparing to unpack .../060-python3-roman-numerals_3.1.0-2_all.deb ... 211s Unpacking python3-roman-numerals (3.1.0-2) ... 211s Selecting previously unselected package python3-docutils. 211s Preparing to unpack .../061-python3-docutils_0.22.3+dfsg-1_all.deb ... 211s Unpacking python3-docutils (0.22.3+dfsg-1) ... 211s Selecting previously unselected package python3-imagesize. 212s Preparing to unpack .../062-python3-imagesize_1.4.1-1build1_all.deb ... 212s Unpacking python3-imagesize (1.4.1-1build1) ... 212s Selecting previously unselected package python3-roman. 212s Preparing to unpack .../063-python3-roman_5.2-1_all.deb ... 212s Unpacking python3-roman (5.2-1) ... 212s Selecting previously unselected package python3-snowballstemmer. 212s Preparing to unpack .../064-python3-snowballstemmer_3.0.1-1_all.deb ... 212s Unpacking python3-snowballstemmer (3.0.1-1) ... 212s Selecting previously unselected package python3-sphinx. 212s Preparing to unpack .../065-python3-sphinx_8.2.3-1ubuntu2_all.deb ... 212s Unpacking python3-sphinx (8.2.3-1ubuntu2) ... 212s Selecting previously unselected package sphinx-basic-ng. 212s Preparing to unpack .../066-sphinx-basic-ng_1.0.0~beta2-1_all.deb ... 212s Unpacking sphinx-basic-ng (1.0.0~beta2-1) ... 212s Selecting previously unselected package furo. 212s Preparing to unpack .../067-furo_2025.09.25+dfsg-2_all.deb ... 212s Unpacking furo (2025.09.25+dfsg-2) ... 212s Selecting previously unselected package libjs-jquery-metadata. 212s Preparing to unpack .../068-libjs-jquery-metadata_12-4_all.deb ... 212s Unpacking libjs-jquery-metadata (12-4) ... 212s Selecting previously unselected package libjs-jquery-tablesorter. 213s Preparing to unpack .../069-libjs-jquery-tablesorter_1%3a2.31.3+dfsg1-4_all.deb ... 213s Unpacking libjs-jquery-tablesorter (1:2.31.3+dfsg1-4) ... 213s Selecting previously unselected package libjs-jquery-throttle-debounce. 213s Preparing to unpack .../070-libjs-jquery-throttle-debounce_1.1+dfsg.1-2_all.deb ... 213s Unpacking libjs-jquery-throttle-debounce (1.1+dfsg.1-2) ... 213s Selecting previously unselected package libpython3.14-stdlib:s390x. 213s Preparing to unpack .../071-libpython3.14-stdlib_3.14.0-4_s390x.deb ... 213s Unpacking libpython3.14-stdlib:s390x (3.14.0-4) ... 213s Selecting previously unselected package pybuild-plugin-autopkgtest. 213s Preparing to unpack .../072-pybuild-plugin-autopkgtest_6.20251029_all.deb ... 213s Unpacking pybuild-plugin-autopkgtest (6.20251029) ... 213s Selecting previously unselected package python3-pyproject-hooks. 213s Preparing to unpack .../073-python3-pyproject-hooks_1.2.0-1_all.deb ... 213s Unpacking python3-pyproject-hooks (1.2.0-1) ... 213s Selecting previously unselected package python3-wheel. 213s Preparing to unpack .../074-python3-wheel_0.46.1-2_all.deb ... 213s Unpacking python3-wheel (0.46.1-2) ... 213s Selecting previously unselected package python3-build. 213s Preparing to unpack .../075-python3-build_1.2.2-4_all.deb ... 213s Unpacking python3-build (1.2.2-4) ... 213s Selecting previously unselected package python3-installer. 213s Preparing to unpack .../076-python3-installer_0.7.0+dfsg1-3_all.deb ... 213s Unpacking python3-installer (0.7.0+dfsg1-3) ... 213s Selecting previously unselected package pybuild-plugin-pyproject. 213s Preparing to unpack .../077-pybuild-plugin-pyproject_6.20251029_all.deb ... 213s Unpacking pybuild-plugin-pyproject (6.20251029) ... 213s Selecting previously unselected package python3-aiooui. 213s Preparing to unpack .../078-python3-aiooui_0.1.9-1_all.deb ... 214s Unpacking python3-aiooui (0.1.9-1) ... 214s Selecting previously unselected package python3.14. 214s Preparing to unpack .../079-python3.14_3.14.0-4_s390x.deb ... 214s Unpacking python3.14 (3.14.0-4) ... 214s Selecting previously unselected package python3-all. 214s Preparing to unpack .../080-python3-all_3.13.9-2_s390x.deb ... 214s Unpacking python3-all (3.13.9-2) ... 214s Selecting previously unselected package python3-async-generator. 214s Preparing to unpack .../081-python3-async-generator_1.10-7_all.deb ... 214s Unpacking python3-async-generator (1.10-7) ... 214s Selecting previously unselected package python3-async-interrupt. 214s Preparing to unpack .../082-python3-async-interrupt_1.2.2-1_all.deb ... 214s Unpacking python3-async-interrupt (1.2.2-1) ... 214s Selecting previously unselected package python3-dbus-fast. 214s Preparing to unpack .../083-python3-dbus-fast_3.1.2-1_s390x.deb ... 214s Unpacking python3-dbus-fast (3.1.2-1) ... 214s Selecting previously unselected package python3-bleak. 214s Preparing to unpack .../084-python3-bleak_1.1.1-1_all.deb ... 214s Unpacking python3-bleak (1.1.1-1) ... 215s Selecting previously unselected package python3-uart-devices. 215s Preparing to unpack .../085-python3-uart-devices_0.1.1-1_all.deb ... 215s Unpacking python3-uart-devices (0.1.1-1) ... 215s Selecting previously unselected package python3-usb-devices. 215s Preparing to unpack .../086-python3-usb-devices_0.4.5-2_all.deb ... 215s Unpacking python3-usb-devices (0.4.5-2) ... 215s Selecting previously unselected package python3-bluetooth-adapters. 215s Preparing to unpack .../087-python3-bluetooth-adapters_2.1.1-1_all.deb ... 215s Unpacking python3-bluetooth-adapters (2.1.1-1) ... 215s Selecting previously unselected package python3-bleak-retry-connector. 215s Preparing to unpack .../088-python3-bleak-retry-connector_4.4.4-1_all.deb ... 215s Unpacking python3-bleak-retry-connector (4.4.4-1) ... 215s Selecting previously unselected package python3-btsocket. 215s Preparing to unpack .../089-python3-btsocket_0.3.0-1_all.deb ... 215s Unpacking python3-btsocket (0.3.0-1) ... 215s Selecting previously unselected package python3-pyric. 215s Preparing to unpack .../090-python3-pyric_0.1.6.4+git20170421.0562b17-1_all.deb ... 215s Unpacking python3-pyric (0.1.6.4+git20170421.0562b17-1) ... 215s Selecting previously unselected package sphinx-rtd-theme-common. 215s Preparing to unpack .../091-sphinx-rtd-theme-common_3.0.2+dfsg-3_all.deb ... 215s Unpacking sphinx-rtd-theme-common (3.0.2+dfsg-3) ... 215s Selecting previously unselected package python3-bluetooth-auto-recovery. 215s Preparing to unpack .../092-python3-bluetooth-auto-recovery_1.5.3-1_all.deb ... 215s Unpacking python3-bluetooth-auto-recovery (1.5.3-1) ... 215s Selecting previously unselected package python3-bluetooth-data-tools. 215s Preparing to unpack .../093-python3-bluetooth-data-tools_1.28.4-1_s390x.deb ... 215s Unpacking python3-bluetooth-data-tools (1.28.4-1) ... 215s Selecting previously unselected package python3-coverage. 215s Preparing to unpack .../094-python3-coverage_7.8.2+dfsg1-1_s390x.deb ... 215s Unpacking python3-coverage (7.8.2+dfsg1-1) ... 215s Selecting previously unselected package python3-freezegun. 215s Preparing to unpack .../095-python3-freezegun_1.5.1-1.2_all.deb ... 215s Unpacking python3-freezegun (1.5.1-1.2) ... 215s Selecting previously unselected package python3-habluetooth. 215s Preparing to unpack .../096-python3-habluetooth_4.0.1-1_s390x.deb ... 215s Unpacking python3-habluetooth (4.0.1-1) ... 215s Selecting previously unselected package python3-iniconfig. 215s Preparing to unpack .../097-python3-iniconfig_2.1.0-1_all.deb ... 215s Unpacking python3-iniconfig (2.1.0-1) ... 215s Selecting previously unselected package python3-mdit-py-plugins. 215s Preparing to unpack .../098-python3-mdit-py-plugins_0.5.0-1_all.deb ... 215s Unpacking python3-mdit-py-plugins (0.5.0-1) ... 215s Selecting previously unselected package python3-myst-parser. 215s Preparing to unpack .../099-python3-myst-parser_4.0.1-1_all.deb ... 215s Unpacking python3-myst-parser (4.0.1-1) ... 216s Selecting previously unselected package python3-pluggy. 216s Preparing to unpack .../100-python3-pluggy_1.6.0-1_all.deb ... 216s Unpacking python3-pluggy (1.6.0-1) ... 216s Selecting previously unselected package python3-poetry-core. 216s Preparing to unpack .../101-python3-poetry-core_2.2.1-2_all.deb ... 216s Unpacking python3-poetry-core (2.2.1-2) ... 216s Selecting previously unselected package python3-pytest. 216s Preparing to unpack .../102-python3-pytest_8.3.5-2_all.deb ... 216s Unpacking python3-pytest (8.3.5-2) ... 216s Selecting previously unselected package python3-pytest-asyncio. 216s Preparing to unpack .../103-python3-pytest-asyncio_0.25.1-1_all.deb ... 216s Unpacking python3-pytest-asyncio (0.25.1-1) ... 216s Selecting previously unselected package libjs-jquery-hotkeys. 216s Preparing to unpack .../104-libjs-jquery-hotkeys_0.2.0-1_all.deb ... 216s Unpacking libjs-jquery-hotkeys (0.2.0-1) ... 216s Selecting previously unselected package libjs-jquery-isonscreen. 216s Preparing to unpack .../105-libjs-jquery-isonscreen_1.2.0-2_all.deb ... 216s Unpacking libjs-jquery-isonscreen (1.2.0-2) ... 216s Selecting previously unselected package python3-pytest-cov. 216s Preparing to unpack .../106-python3-pytest-cov_5.0.0-1_all.deb ... 216s Unpacking python3-pytest-cov (5.0.0-1) ... 216s Setting up dh-python (6.20251029) ... 217s Setting up python3-pyric (0.1.6.4+git20170421.0562b17-1) ... 219s Setting up python3-iniconfig (2.1.0-1) ... 219s Setting up python3-aiooui (0.1.9-1) ... 220s Setting up python3-uart-devices (0.1.1-1) ... 221s Setting up fonts-lato (2.015-1build1) ... 221s Setting up python3-usb-devices (0.4.5-2) ... 221s Setting up python3-async-generator (1.10-7) ... 222s Setting up python3-bluetooth-data-tools (1.28.4-1) ... 222s Setting up python3-defusedxml (0.7.1-3) ... 223s Setting up libarchive-zip-perl (1.68-1) ... 223s Setting up python3-alabaster (0.7.16-0.1) ... 224s Setting up libdebhelper-perl (13.24.2ubuntu1) ... 224s Setting up libpython3.14-minimal:s390x (3.14.0-4) ... 224s Setting up python3-mdit-py-plugins (0.5.0-1) ... 224s Setting up m4 (1.4.20-2) ... 224s Setting up python3-coverage (7.8.2+dfsg1-1) ... 226s Setting up libjs-jquery-throttle-debounce (1.1+dfsg.1-2) ... 226s Setting up libgomp1:s390x (15.2.0-9ubuntu1) ... 226s Setting up python3-wheel (0.46.1-2) ... 227s Setting up python3-btsocket (0.3.0-1) ... 228s Setting up libasound2-data (1.2.14-2ubuntu1) ... 228s Setting up python3-roman (5.2-1) ... 229s Setting up autotools-dev (20240727.1) ... 229s Setting up libasound2t64:s390x (1.2.14-2ubuntu1) ... 229s Setting up python3-pyproject-hooks (1.2.0-1) ... 230s Setting up python3-accessible-pygments (0.0.5-2) ... 231s Setting up python3-snowballstemmer (3.0.1-1) ... 233s Setting up python3-poetry-core (2.2.1-2) ... 235s Setting up libmpc3:s390x (1.3.1-2) ... 235s Setting up autopoint (0.23.2-1) ... 235s Setting up python3-installer (0.7.0+dfsg1-3) ... 236s Setting up autoconf (2.72-3.1ubuntu1) ... 236s Setting up python3-pluggy (1.6.0-1) ... 237s Setting up python3-async-interrupt (1.2.2-1) ... 238s Setting up libubsan1:s390x (15.2.0-9ubuntu1) ... 238s Setting up dwz (0.16-2) ... 238s Setting up libasan8:s390x (15.2.0-9ubuntu1) ... 238s Setting up libjson-perl (4.10000-1) ... 238s Setting up debugedit (1:5.2-3) ... 238s Setting up python3-roman-numerals (3.1.0-2) ... 238s Setting up sgml-base (1.31+nmu1) ... 239s Setting up libjs-jquery (3.7.1+dfsg+~3.5.33-1build1) ... 239s Setting up libjs-jquery-hotkeys (0.2.0-1) ... 239s Setting up libisl23:s390x (0.27-1) ... 239s Setting up python3-build (1.2.2-4) ... 239s Setting up python3-soupsieve (2.7-2) ... 240s Setting up fonts-font-awesome (5.0.10+really4.7.0~dfsg-4.1build1) ... 240s Setting up sphinx-rtd-theme-common (3.0.2+dfsg-3) ... 240s Setting up python3.14-minimal (3.14.0-4) ... 243s Setting up libcc1-0:s390x (15.2.0-9ubuntu1) ... 243s Setting up python3-freezegun (1.5.1-1.2) ... 245s Setting up libitm1:s390x (15.2.0-9ubuntu1) ... 245s Setting up libjs-underscore (1.13.4~dfsg+~1.11.4-3) ... 245s Setting up python3-imagesize (1.4.1-1build1) ... 245s Setting up automake (1:1.18.1-3) ... 245s update-alternatives: using /usr/bin/automake-1.18 to provide /usr/bin/automake (automake) in auto mode 245s Setting up libfile-stripnondeterminism-perl (1.15.0-1) ... 245s Setting up gettext (0.23.2-1) ... 245s Setting up libgcc-15-dev:s390x (15.2.0-9ubuntu1) ... 245s Setting up libpython3.14-stdlib:s390x (3.14.0-4) ... 245s Setting up pybuild-plugin-pyproject (6.20251029) ... 245s Setting up python3-pytest (8.3.5-2) ... 247s Setting up python3-bs4 (4.14.2-1) ... 248s Setting up intltool-debian (0.35.0+20060710.6build1) ... 248s Setting up bluez (5.84-1) ... 249s Created symlink '/etc/systemd/user/default.target.wants/mpris-proxy.service' → '/usr/lib/systemd/user/mpris-proxy.service'. 251s Created symlink '/etc/systemd/system/dbus-org.bluez.service' → '/usr/lib/systemd/system/bluetooth.service'. 251s Created symlink '/etc/systemd/system/bluetooth.target.wants/bluetooth.service' → '/usr/lib/systemd/system/bluetooth.service'. 253s Setting up libstdc++-15-dev:s390x (15.2.0-9ubuntu1) ... 253s Setting up libjs-jquery-metadata (12-4) ... 253s Setting up libjs-jquery-isonscreen (1.2.0-2) ... 253s Setting up libjs-sphinxdoc (8.2.3-1ubuntu2) ... 253s Setting up python3-pytest-asyncio (0.25.1-1) ... 253s Setting up dh-strip-nondeterminism (1.15.0-1) ... 253s Setting up libjs-jquery-tablesorter (1:2.31.3+dfsg1-4) ... 253s Setting up xml-core (0.19) ... 254s Setting up cpp-15-s390x-linux-gnu (15.2.0-9ubuntu1) ... 254s Setting up python3.14 (3.14.0-4) ... 259s Setting up gcc-15-s390x-linux-gnu (15.2.0-9ubuntu1) ... 259s Setting up g++-15-s390x-linux-gnu (15.2.0-9ubuntu1) ... 259s Setting up python3-dbus-fast (3.1.2-1) ... 260s Setting up cpp-s390x-linux-gnu (4:15.2.0-4ubuntu1) ... 260s Setting up po-debconf (1.0.21+nmu1) ... 260s Setting up python3-all (3.13.9-2) ... 260s Setting up python3-pytest-cov (5.0.0-1) ... 261s Setting up gcc-s390x-linux-gnu (4:15.2.0-4ubuntu1) ... 261s Setting up sphinx-common (8.2.3-1ubuntu2) ... 261s Setting up cpp-15 (15.2.0-9ubuntu1) ... 261s Setting up cpp (4:15.2.0-4ubuntu1) ... 261s Setting up python3-bleak (1.1.1-1) ... 262s Setting up python3-bluetooth-adapters (2.1.1-1) ... 262s Setting up python3-bleak-retry-connector (4.4.4-1) ... 262s Setting up g++-s390x-linux-gnu (4:15.2.0-4ubuntu1) ... 262s Setting up gcc-15 (15.2.0-9ubuntu1) ... 262s Setting up python3-bluetooth-auto-recovery (1.5.3-1) ... 263s Setting up python3-habluetooth (4.0.1-1) ... 265s Setting up g++-15 (15.2.0-9ubuntu1) ... 265s Setting up libtool (2.5.4-7) ... 265s Setting up gcc (4:15.2.0-4ubuntu1) ... 265s Setting up dh-autoreconf (21) ... 265s Setting up g++ (4:15.2.0-4ubuntu1) ... 265s update-alternatives: using /usr/bin/g++ to provide /usr/bin/c++ (c++) in auto mode 265s Setting up build-essential (12.12ubuntu2) ... 265s Setting up debhelper (13.24.2ubuntu1) ... 265s Setting up pybuild-plugin-autopkgtest (6.20251029) ... 265s Processing triggers for install-info (7.2-5) ... 266s Processing triggers for libc-bin (2.42-2ubuntu2) ... 266s Processing triggers for systemd (257.9-0ubuntu2) ... 266s Processing triggers for man-db (2.13.1-1) ... 277s Processing triggers for dbus (1.16.2-2ubuntu2) ... 277s Processing triggers for sgml-base (1.31+nmu1) ... 277s Setting up docutils-common (0.22.3+dfsg-1) ... 278s Processing triggers for sgml-base (1.31+nmu1) ... 278s Setting up python3-docutils (0.22.3+dfsg-1) ... 281s Setting up python3-sphinx (8.2.3-1ubuntu2) ... 288s Setting up python3-myst-parser (4.0.1-1) ... 289s Setting up sphinx-basic-ng (1.0.0~beta2-1) ... 290s Setting up furo (2025.09.25+dfsg-2) ... 293s autopkgtest [21:51:21]: test pybuild-autopkgtest: pybuild-autopkgtest 293s autopkgtest [21:51:21]: test pybuild-autopkgtest: [----------------------- 293s pybuild-autopkgtest 294s I: pybuild base:317: cd /tmp/autopkgtest.ZVzfcl/autopkgtest_tmp/build; python3.14 -m pytest --no-cov --ignore=tests/test_benchmark_base_scanner.py 296s /usr/lib/python3/dist-packages/pytest_asyncio/plugin.py:207: PytestDeprecationWarning: The configuration option "asyncio_default_fixture_loop_scope" is unset. 296s 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" 296s 296s warnings.warn(PytestDeprecationWarning(_DEFAULT_FIXTURE_LOOP_SCOPE_UNSET)) 296s ============================= test session starts ============================== 296s platform linux -- Python 3.14.0, pytest-8.3.5, pluggy-1.6.0 -- /usr/bin/python3.14 296s cachedir: .pytest_cache 296s rootdir: /tmp/autopkgtest.ZVzfcl/autopkgtest_tmp/build 296s configfile: pyproject.toml 296s plugins: asyncio-0.25.1, typeguard-4.4.2, cov-5.0.0 296s asyncio: mode=Mode.STRICT, asyncio_default_fixture_loop_scope=None 298s collecting ... collected 90 items 298s 298s tests/test_base_scanner.py::test_remote_scanner[None] 298s -------------------------------- live log setup -------------------------------- 298s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 298s WARNING habluetooth.manager:manager.py:189 BluetoothManager: does not implement _discover_service_info, subclasses must implement this method to consume discovery data 298s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 298s -------------------------------- live log call --------------------------------- 298s DEBUG habluetooth.manager:manager.py:765 Registering scanner esp32 298s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner esp32 298s PASSED [ 1%] 298s tests/test_base_scanner.py::test_remote_scanner[w] 298s -------------------------------- live log setup -------------------------------- 298s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 298s -------------------------------- live log call --------------------------------- 298s DEBUG habluetooth.manager:manager.py:765 Registering scanner esp32 298s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner esp32 298s PASSED [ 2%] 298s tests/test_base_scanner.py::test_remote_scanner_expires_connectable 298s -------------------------------- live log setup -------------------------------- 298s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 298s -------------------------------- live log call --------------------------------- 298s DEBUG habluetooth.manager:manager.py:765 Registering scanner esp32 298s DEBUG habluetooth.base_scanner:base_scanner.py:210 esp32: Scanner watchdog time_since_last_detection: 196.009207088 298s DEBUG habluetooth.base_scanner:base_scanner.py:225 esp32: Bluetooth scanner has gone quiet for 196.009207088s, check logs on the scanner device for more information 298s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner esp32 298s PASSED [ 3%] 298s tests/test_base_scanner.py::test_remote_scanner_expires_non_connectable 298s -------------------------------- live log setup -------------------------------- 298s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 298s -------------------------------- live log call --------------------------------- 298s DEBUG habluetooth.manager:manager.py:765 Registering scanner esp32 298s DEBUG habluetooth.base_scanner:base_scanner.py:210 esp32: Scanner watchdog time_since_last_detection: 196.013689901 298s DEBUG habluetooth.base_scanner:base_scanner.py:225 esp32: Bluetooth scanner has gone quiet for 196.013689901s, check logs on the scanner device for more information 298s DEBUG habluetooth.base_scanner:base_scanner.py:210 esp32: Scanner watchdog time_since_last_detection: 901.0136899009999 298s DEBUG habluetooth.base_scanner:base_scanner.py:225 esp32: Bluetooth scanner has gone quiet for 901.0136899009999s, check logs on the scanner device for more information 298s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner esp32 298s PASSED [ 4%] 298s tests/test_base_scanner.py::test_base_scanner_connecting_behavior 298s -------------------------------- live log setup -------------------------------- 298s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 298s -------------------------------- live log call --------------------------------- 298s DEBUG habluetooth.manager:manager.py:765 Registering scanner esp32 298s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner esp32 298s PASSED [ 5%] 298s tests/test_base_scanner.py::test_scanner_stops_responding 298s -------------------------------- live log setup -------------------------------- 298s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 298s -------------------------------- live log call --------------------------------- 298s DEBUG habluetooth.manager:manager.py:765 Registering scanner esp32 298s DEBUG habluetooth.base_scanner:base_scanner.py:210 esp32: Scanner watchdog time_since_last_detection: 120.01127674900002 298s DEBUG habluetooth.base_scanner:base_scanner.py:225 esp32: Bluetooth scanner has gone quiet for 120.01127674900002s, check logs on the scanner device for more information 298s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner esp32 298s PASSED [ 6%] 298s tests/test_base_scanner.py::test_merge_manufacturer_data_history_existing 298s -------------------------------- live log setup -------------------------------- 298s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 298s -------------------------------- live log call --------------------------------- 298s DEBUG habluetooth.manager:manager.py:765 Registering scanner esp32 298s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner esp32 298s PASSED [ 7%] 298s tests/test_base_scanner.py::test_merge_manufacturer_data_history_new 298s -------------------------------- live log setup -------------------------------- 298s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 298s -------------------------------- live log call --------------------------------- 298s DEBUG habluetooth.manager:manager.py:765 Registering scanner esp32 298s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner esp32 298s PASSED [ 8%] 298s tests/test_base_scanner.py::test_filter_apple_data 298s -------------------------------- live log setup -------------------------------- 298s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 298s -------------------------------- live log call --------------------------------- 298s DEBUG habluetooth.manager:manager.py:765 Registering scanner esp32 298s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner esp32 298s PASSED [ 10%] 298s tests/test_base_scanner.py::test_connection_history_count_in_progress 298s -------------------------------- live log setup -------------------------------- 298s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (AA:BB:CC:DD:EE:00) 298s PASSED [ 11%] 298s ------------------------------ live log teardown ------------------------------- 298s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (AA:BB:CC:DD:EE:00) 298s 298s tests/test_base_scanner.py::test_connection_history_failure_count 298s -------------------------------- live log setup -------------------------------- 298s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (AA:BB:CC:DD:EE:00) 298s -------------------------------- live log call --------------------------------- 298s 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 298s PASSED [ 12%] 298s ------------------------------ live log teardown ------------------------------- 298s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (AA:BB:CC:DD:EE:00) 298s 298s tests/test_init.py::test_create_scanner 298s -------------------------------- live log setup -------------------------------- 298s WARNING habluetooth.manager:manager.py:189 BluetoothManager: does not implement _discover_service_info, subclasses must implement this method to consume discovery data 298s PASSED [ 13%] 298s tests/test_init.py::test_create_remote_scanner PASSED [ 14%] 298s tests/test_init.py::test__async_on_advertisement PASSED [ 15%] 298s tests/test_init.py::test__async_on_advertisement_first PASSED [ 16%] 298s tests/test_init.py::test__async_on_advertisement_prefers_longest_local_name PASSED [ 17%] 298s tests/test_init.py::test_create_ha_scanner PASSED [ 18%] 298s tests/test_manager.py::test_async_recover_failed_adapters 298s -------------------------------- live log setup -------------------------------- 298s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 298s -------------------------------- live log call --------------------------------- 298s WARNING habluetooth.manager:manager.py:189 BluetoothManager: does not implement _discover_service_info, subclasses must implement this method to consume discovery data 298s PASSED [ 20%] 298s tests/test_manager.py::test_create_manager 298s -------------------------------- live log setup -------------------------------- 298s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 298s -------------------------------- live log call --------------------------------- 298s WARNING habluetooth.manager:manager.py:189 BluetoothManager: does not implement _discover_service_info, subclasses must implement this method to consume discovery data 298s PASSED [ 21%] 298s tests/test_manager.py::test_async_register_disappeared_callback 298s -------------------------------- live log setup -------------------------------- 298s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 298s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (AA:BB:CC:DD:EE:00) 298s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci1 (AA:BB:CC:DD:EE:11) 299s -------------------------------- live log call --------------------------------- 299s ERROR habluetooth.manager:manager.py:425 Error in disappeared callback 299s Traceback (most recent call last): 299s File "/usr/lib/python3/dist-packages/habluetooth/manager.py", line 423, in _async_check_unavailable 299s disappear_callback(address) 299s ~~~~~~~~~~~~~~~~~~^^^^^^^^^ 299s File "/tmp/autopkgtest.ZVzfcl/autopkgtest_tmp/build/tests/test_manager.py", line 188, in _failing_callback 299s raise ValueError("This is a test") 299s ValueError: This is a test 299s PASSED [ 22%] 299s ------------------------------ live log teardown ------------------------------- 299s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci1 (AA:BB:CC:DD:EE:11) 299s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (AA:BB:CC:DD:EE:00) 299s 299s tests/test_manager.py::test_async_register_allocation_callback 299s -------------------------------- live log setup -------------------------------- 299s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 299s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (AA:BB:CC:DD:EE:00) 299s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci1 (AA:BB:CC:DD:EE:11) 299s -------------------------------- live log call --------------------------------- 299s ERROR habluetooth.manager:manager.py:850 Error in allocation callback 299s Traceback (most recent call last): 299s File "/usr/lib/python3/dist-packages/habluetooth/manager.py", line 848, in async_on_allocation_changed 299s callback_(ha_slot_allocations) 299s ~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^ 299s File "/tmp/autopkgtest.ZVzfcl/autopkgtest_tmp/build/tests/test_manager.py", line 259, in _failing_callback 299s raise ValueError("This is a test") 299s ValueError: This is a test 299s ERROR habluetooth.manager:manager.py:850 Error in allocation callback 299s Traceback (most recent call last): 299s File "/usr/lib/python3/dist-packages/habluetooth/manager.py", line 848, in async_on_allocation_changed 299s callback_(ha_slot_allocations) 299s ~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^ 299s File "/tmp/autopkgtest.ZVzfcl/autopkgtest_tmp/build/tests/test_manager.py", line 259, in _failing_callback 299s raise ValueError("This is a test") 299s ValueError: This is a test 299s PASSED [ 23%] 299s ------------------------------ live log teardown ------------------------------- 299s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci1 (AA:BB:CC:DD:EE:11) 299s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (AA:BB:CC:DD:EE:00) 299s 299s tests/test_manager.py::test_async_register_allocation_callback_non_connectable 299s -------------------------------- live log setup -------------------------------- 299s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 299s DEBUG habluetooth.manager:manager.py:765 Registering scanner non connectable (AA:BB:CC:DD:EE:FF) 299s PASSED [ 24%] 299s ------------------------------ live log teardown ------------------------------- 299s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner non connectable (AA:BB:CC:DD:EE:FF) 299s 299s tests/test_manager.py::test_async_register_scanner_registration_callback 299s -------------------------------- live log setup -------------------------------- 299s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 299s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (AA:BB:CC:DD:EE:00) 299s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci1 (AA:BB:CC:DD:EE:11) 299s -------------------------------- live log call --------------------------------- 299s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci3 (AA:BB:CC:DD:EE:33) 299s ERROR habluetooth.manager:manager.py:867 Error in scanner callback 299s Traceback (most recent call last): 299s File "/usr/lib/python3/dist-packages/habluetooth/manager.py", line 865, in _async_on_scanner_registration 299s callback_(HaScannerRegistration(event, scanner)) 299s ~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 299s File "/tmp/autopkgtest.ZVzfcl/autopkgtest_tmp/build/tests/test_manager.py", line 381, in _failing_callback 299s raise ValueError("This is a test") 299s ValueError: This is a test 299s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci3 (AA:BB:CC:DD:EE:33) 299s ERROR habluetooth.manager:manager.py:867 Error in scanner callback 299s Traceback (most recent call last): 299s File "/usr/lib/python3/dist-packages/habluetooth/manager.py", line 865, in _async_on_scanner_registration 299s callback_(HaScannerRegistration(event, scanner)) 299s ~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 299s File "/tmp/autopkgtest.ZVzfcl/autopkgtest_tmp/build/tests/test_manager.py", line 381, in _failing_callback 299s raise ValueError("This is a test") 299s ValueError: This is a test 299s PASSED [ 25%] 299s ------------------------------ live log teardown ------------------------------- 299s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci1 (AA:BB:CC:DD:EE:11) 299s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (AA:BB:CC:DD:EE:00) 299s 299s tests/test_manager.py::test_async_register_scanner_with_connection_slots 299s -------------------------------- live log setup -------------------------------- 299s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 299s -------------------------------- live log call --------------------------------- 299s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci3 (AA:BB:CC:DD:EE:33) 299s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci3 (AA:BB:CC:DD:EE:33) 299s PASSED [ 26%] 299s tests/test_manager.py::test_diagnostics 299s -------------------------------- live log setup -------------------------------- 299s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 299s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (AA:BB:CC:DD:EE:00) 299s PASSED [ 27%] 299s ------------------------------ live log teardown ------------------------------- 299s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (AA:BB:CC:DD:EE:00) 299s 299s tests/test_manager.py::test_advertisements_do_not_switch_adapters_for_no_reason 299s -------------------------------- live log setup -------------------------------- 299s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 299s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (AA:BB:CC:DD:EE:00) 299s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci1 (AA:BB:CC:DD:EE:11) 299s PASSED [ 28%] 299s ------------------------------ live log teardown ------------------------------- 299s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci1 (AA:BB:CC:DD:EE:11) 299s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (AA:BB:CC:DD:EE:00) 299s 299s tests/test_manager.py::test_switching_adapters_based_on_rssi 299s -------------------------------- live log setup -------------------------------- 299s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 299s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (AA:BB:CC:DD:EE:00) 299s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci1 (AA:BB:CC:DD:EE:11) 299s -------------------------------- live log call --------------------------------- 299s 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) 299s PASSED [ 30%] 299s ------------------------------ live log teardown ------------------------------- 299s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci1 (AA:BB:CC:DD:EE:11) 299s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (AA:BB:CC:DD:EE:00) 299s 299s tests/test_manager.py::test_switching_adapters_based_on_zero_rssi 299s -------------------------------- live log setup -------------------------------- 299s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 299s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (AA:BB:CC:DD:EE:00) 299s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci1 (AA:BB:CC:DD:EE:11) 299s -------------------------------- live log call --------------------------------- 299s 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) 299s PASSED [ 31%] 299s ------------------------------ live log teardown ------------------------------- 299s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci1 (AA:BB:CC:DD:EE:11) 299s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (AA:BB:CC:DD:EE:00) 299s 299s tests/test_manager.py::test_switching_adapters_based_on_stale 299s -------------------------------- live log setup -------------------------------- 299s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 299s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (AA:BB:CC:DD:EE:00) 299s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci1 (AA:BB:CC:DD:EE:11) 299s -------------------------------- live log call --------------------------------- 299s 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) 299s PASSED [ 32%] 299s ------------------------------ live log teardown ------------------------------- 299s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci1 (AA:BB:CC:DD:EE:11) 299s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (AA:BB:CC:DD:EE:00) 299s 299s tests/test_manager.py::test_switching_adapters_based_on_stale_with_discovered_interval 299s -------------------------------- live log setup -------------------------------- 299s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 299s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (AA:BB:CC:DD:EE:00) 299s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci1 (AA:BB:CC:DD:EE:11) 299s -------------------------------- live log call --------------------------------- 299s 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) 299s PASSED [ 33%] 299s ------------------------------ live log teardown ------------------------------- 299s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci1 (AA:BB:CC:DD:EE:11) 299s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (AA:BB:CC:DD:EE:00) 299s 299s tests/test_manager.py::test_switching_adapters_based_on_rssi_connectable_to_non_connectable 299s -------------------------------- live log setup -------------------------------- 299s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 299s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (AA:BB:CC:DD:EE:00) 299s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci1 (AA:BB:CC:DD:EE:11) 299s -------------------------------- live log call --------------------------------- 299s 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) 299s PASSED [ 34%] 299s ------------------------------ live log teardown ------------------------------- 299s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci1 (AA:BB:CC:DD:EE:11) 299s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (AA:BB:CC:DD:EE:00) 299s 299s tests/test_manager.py::test_connectable_advertisement_can_be_retrieved_best_path_is_non_connectable 299s -------------------------------- live log setup -------------------------------- 299s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 299s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (AA:BB:CC:DD:EE:00) 299s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci1 (AA:BB:CC:DD:EE:11) 299s PASSED [ 35%] 299s ------------------------------ live log teardown ------------------------------- 299s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci1 (AA:BB:CC:DD:EE:11) 299s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (AA:BB:CC:DD:EE:00) 299s 299s tests/test_manager.py::test_switching_adapters_when_one_goes_away 299s -------------------------------- live log setup -------------------------------- 299s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 299s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (AA:BB:CC:DD:EE:00) 299s -------------------------------- live log call --------------------------------- 299s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci2 299s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci2 299s PASSED [ 36%] 299s ------------------------------ live log teardown ------------------------------- 299s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (AA:BB:CC:DD:EE:00) 299s 299s tests/test_manager.py::test_switching_adapters_when_one_stop_scanning 299s -------------------------------- live log setup -------------------------------- 299s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 299s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (AA:BB:CC:DD:EE:00) 299s -------------------------------- live log call --------------------------------- 299s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci2 299s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci2 299s PASSED [ 37%] 299s ------------------------------ live log teardown ------------------------------- 299s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (AA:BB:CC:DD:EE:00) 299s 299s tests/test_manager.py::test_set_fallback_interval_small 299s -------------------------------- live log setup -------------------------------- 299s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 299s PASSED [ 38%] 299s tests/test_manager.py::test_set_fallback_interval_big 299s -------------------------------- live log setup -------------------------------- 299s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 299s PASSED [ 40%] 299s tests/test_manager.py::test_subclassing_bluetooth_manager 299s -------------------------------- live log setup -------------------------------- 299s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 299s -------------------------------- live log call --------------------------------- 299s WARNING habluetooth.manager:manager.py:189 TestBluetoothManager2: does not implement _discover_service_info, subclasses must implement this method to consume discovery data 299s PASSED [ 41%] 299s tests/test_models.py::test_model PASSED [ 42%] 299s tests/test_models.py::test_model_from_bleak PASSED [ 43%] 299s tests/test_models.py::test_model_from_scanner PASSED [ 44%] 299s tests/test_models.py::test_construct_service_info_bleak PASSED [ 45%] 299s tests/test_models.py::test_from_device_and_advertisement_data PASSED [ 46%] 299s tests/test_models.py::test_pyobjc_compat PASSED [ 47%] 299s tests/test_models.py::test_as_connectable PASSED [ 48%] 299s tests/test_scanner.py::test_empty_data_no_scanner 299s -------------------------------- live log setup -------------------------------- 299s WARNING habluetooth.manager:manager.py:189 BluetoothManager: does not implement _discover_service_info, subclasses must implement this method to consume discovery data 299s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 299s PASSED [ 50%] 299s tests/test_scanner.py::test_dbus_socket_missing_in_container 299s -------------------------------- live log setup -------------------------------- 299s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 299s -------------------------------- live log call --------------------------------- 299s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 299s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (1/4) 299s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 299s DEBUG habluetooth.scanner:scanner.py:462 hci0 (AA:BB:CC:DD:EE:FF): FileNotFoundError while starting bluetooth: 299s Traceback (most recent call last): 299s File "/usr/lib/python3/dist-packages/habluetooth/scanner.py", line 344, in _async_start_attempt 299s await self.scanner.start() 299s File "/usr/lib/python3.14/unittest/mock.py", line 2326, in _execute_mock_call 299s raise effect 299s FileNotFoundError 299s PASSED [ 51%] 299s tests/test_scanner.py::test_dbus_socket_missing 299s -------------------------------- live log setup -------------------------------- 299s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 299s -------------------------------- live log call --------------------------------- 299s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 299s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (1/4) 299s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 299s DEBUG habluetooth.scanner:scanner.py:462 hci0 (AA:BB:CC:DD:EE:FF): FileNotFoundError while starting bluetooth: 299s Traceback (most recent call last): 299s File "/usr/lib/python3/dist-packages/habluetooth/scanner.py", line 344, in _async_start_attempt 299s await self.scanner.start() 299s File "/usr/lib/python3.14/unittest/mock.py", line 2326, in _execute_mock_call 299s raise effect 299s FileNotFoundError 299s PASSED [ 52%] 299s tests/test_scanner.py::test_handle_cancellation 299s -------------------------------- live log setup -------------------------------- 299s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 299s -------------------------------- live log call --------------------------------- 299s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 299s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (1/4) 299s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 299s PASSED [ 53%] 299s tests/test_scanner.py::test_handle_stop_while_starting 299s -------------------------------- live log setup -------------------------------- 299s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 299s -------------------------------- live log call --------------------------------- 299s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 299s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (1/4) 299s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 299s DEBUG habluetooth.scanner:scanner.py:452 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth scanner aborted: 299s Traceback (most recent call last): 299s File "/usr/lib/python3/dist-packages/habluetooth/scanner.py", line 344, in _async_start_attempt 299s await self.scanner.start() 299s File "/tmp/autopkgtest.ZVzfcl/autopkgtest_tmp/build/tests/test_scanner.py", line 149, in _start 299s await asyncio.sleep(1000) 299s File "/usr/lib/python3.14/asyncio/tasks.py", line 702, in sleep 299s return await future 299s ^^^^^^^^^^^^ 299s asyncio.exceptions.CancelledError: Interrupted by interrupt context manager 299s 299s The above exception was the direct cause of the following exception: 299s 299s Traceback (most recent call last): 299s File "/usr/lib/python3/dist-packages/habluetooth/scanner.py", line 342, in _async_start_attempt 299s async_interrupt.interrupt(self._start_future, _AbortStartError, None), 299s ~~~~~~~~~~~~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 299s File "/usr/lib/python3/dist-packages/async_interrupt/__init__.py", line 90, in __aexit__ 299s raise self._exception from exc_val 299s habluetooth.scanner._AbortStartError 299s DEBUG habluetooth.scanner:scanner.py:571 hci0 (AA:BB:CC:DD:EE:FF): Scanner is already stopped 299s PASSED [ 54%] 299s tests/test_scanner.py::test_dbus_broken_pipe_in_container 299s -------------------------------- live log setup -------------------------------- 299s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 299s -------------------------------- live log call --------------------------------- 299s ERROR asyncio:base_events.py:1875 Future exception was never retrieved 299s future: 299s habluetooth.scanner._AbortStartError 299s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 299s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (1/4) 299s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 299s DEBUG habluetooth.scanner:scanner.py:480 hci0 (AA:BB:CC:DD:EE:FF): DBus connection broken: 299s Traceback (most recent call last): 299s File "/usr/lib/python3/dist-packages/habluetooth/scanner.py", line 344, in _async_start_attempt 299s await self.scanner.start() 299s File "/usr/lib/python3.14/unittest/mock.py", line 2326, in _execute_mock_call 299s raise effect 299s BrokenPipeError 299s PASSED [ 55%] 299s tests/test_scanner.py::test_dbus_broken_pipe 299s -------------------------------- live log setup -------------------------------- 299s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 299s -------------------------------- live log call --------------------------------- 299s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 299s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (1/4) 299s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 299s DEBUG habluetooth.scanner:scanner.py:480 hci0 (AA:BB:CC:DD:EE:FF): DBus connection broken: 299s Traceback (most recent call last): 299s File "/usr/lib/python3/dist-packages/habluetooth/scanner.py", line 344, in _async_start_attempt 299s await self.scanner.start() 299s File "/usr/lib/python3.14/unittest/mock.py", line 2326, in _execute_mock_call 299s raise effect 299s BrokenPipeError 299s PASSED [ 56%] 299s tests/test_scanner.py::test_invalid_dbus_message 299s -------------------------------- live log setup -------------------------------- 299s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 299s -------------------------------- live log call --------------------------------- 299s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 299s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (1/4) 299s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 299s DEBUG habluetooth.scanner:scanner.py:495 hci0 (AA:BB:CC:DD:EE:FF): Invalid DBus message received: 299s Traceback (most recent call last): 299s File "/usr/lib/python3/dist-packages/habluetooth/scanner.py", line 344, in _async_start_attempt 299s await self.scanner.start() 299s File "/usr/lib/python3.14/unittest/mock.py", line 2326, in _execute_mock_call 299s raise effect 299s dbus_fast.errors.InvalidMessageError 299s PASSED [ 57%] 299s tests/test_scanner.py::test_adapter_needs_reset_at_start[org.bluez.Error.Failed] 299s -------------------------------- live log setup -------------------------------- 299s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 299s -------------------------------- live log call --------------------------------- 299s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 299s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (1/4) 299s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 299s DEBUG habluetooth.scanner:scanner.py:434 hci0 (AA:BB:CC:DD:EE:FF): BleakError while starting bluetooth; attempt: (1/4): org.bluez.Error.Failed 299s Traceback (most recent call last): 299s File "/usr/lib/python3/dist-packages/habluetooth/scanner.py", line 344, in _async_start_attempt 299s await self.scanner.start() 299s File "/tmp/autopkgtest.ZVzfcl/autopkgtest_tmp/build/tests/test_scanner.py", line 249, in start 299s raise BleakError(error) 299s bleak.exc.BleakError: org.bluez.Error.Failed 299s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 299s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (2/4) 299s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 299s DEBUG habluetooth.scanner:scanner.py:555 hci0 (AA:BB:CC:DD:EE:FF): adapter stopped responding; executing reset 299s DEBUG habluetooth.scanner:scanner.py:557 hci0 (AA:BB:CC:DD:EE:FF): adapter reset result: True 299s DEBUG habluetooth.scanner:scanner.py:434 hci0 (AA:BB:CC:DD:EE:FF): BleakError while starting bluetooth; attempt: (2/4): org.bluez.Error.Failed 299s Traceback (most recent call last): 299s File "/usr/lib/python3/dist-packages/habluetooth/scanner.py", line 344, in _async_start_attempt 299s await self.scanner.start() 299s File "/tmp/autopkgtest.ZVzfcl/autopkgtest_tmp/build/tests/test_scanner.py", line 249, in start 299s raise BleakError(error) 299s bleak.exc.BleakError: org.bluez.Error.Failed 299s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 299s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (3/4) 299s DEBUG habluetooth.scanner:scanner.py:418 hci0 (AA:BB:CC:DD:EE:FF): Success while starting bluetooth; attempt: (3/4) 308s DEBUG bleak_retry_connector.bleak_manager:bleak_manager.py:56 Timed out trying to connect to DBus; will not try again until next restart 308s DEBUG bleak_retry_connector:__init__.py:639 Failed to restore discoveries for hci0 308s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 308s PASSED [ 58%] 308s tests/test_scanner.py::test_adapter_needs_reset_at_start[org.bluez.Error.InProgress] 308s -------------------------------- live log setup -------------------------------- 308s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 308s -------------------------------- live log call --------------------------------- 308s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 308s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (1/4) 308s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 308s DEBUG habluetooth.scanner:scanner.py:586 hci0 (AA:BB:CC:DD:EE:FF): Force stopping bluetooth discovery 308s ERROR bleak_retry_connector.bluez:bluez.py:324 Failed to stop discovery for hci0 because no manager 308s DEBUG habluetooth.scanner:scanner.py:434 hci0 (AA:BB:CC:DD:EE:FF): BleakError while starting bluetooth; attempt: (1/4): org.bluez.Error.InProgress 308s Traceback (most recent call last): 308s File "/usr/lib/python3/dist-packages/habluetooth/scanner.py", line 344, in _async_start_attempt 308s await self.scanner.start() 308s File "/tmp/autopkgtest.ZVzfcl/autopkgtest_tmp/build/tests/test_scanner.py", line 249, in start 308s raise BleakError(error) 308s bleak.exc.BleakError: org.bluez.Error.InProgress 308s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 308s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (2/4) 308s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 308s DEBUG habluetooth.scanner:scanner.py:586 hci0 (AA:BB:CC:DD:EE:FF): Force stopping bluetooth discovery 308s ERROR bleak_retry_connector.bluez:bluez.py:324 Failed to stop discovery for hci0 because no manager 308s DEBUG habluetooth.scanner:scanner.py:555 hci0 (AA:BB:CC:DD:EE:FF): adapter stopped responding; executing reset 308s DEBUG habluetooth.scanner:scanner.py:557 hci0 (AA:BB:CC:DD:EE:FF): adapter reset result: True 308s DEBUG habluetooth.scanner:scanner.py:434 hci0 (AA:BB:CC:DD:EE:FF): BleakError while starting bluetooth; attempt: (2/4): org.bluez.Error.InProgress 308s Traceback (most recent call last): 308s File "/usr/lib/python3/dist-packages/habluetooth/scanner.py", line 344, in _async_start_attempt 308s await self.scanner.start() 308s File "/tmp/autopkgtest.ZVzfcl/autopkgtest_tmp/build/tests/test_scanner.py", line 249, in start 308s raise BleakError(error) 308s bleak.exc.BleakError: org.bluez.Error.InProgress 308s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 308s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (3/4) 308s DEBUG habluetooth.scanner:scanner.py:418 hci0 (AA:BB:CC:DD:EE:FF): Success while starting bluetooth; attempt: (3/4) 308s DEBUG bleak_retry_connector:__init__.py:639 Failed to restore discoveries for hci0 308s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 308s PASSED [ 60%] 308s tests/test_scanner.py::test_adapter_needs_reset_at_start[org.bluez.Error.NotReady] 308s -------------------------------- live log setup -------------------------------- 308s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 308s -------------------------------- live log call --------------------------------- 308s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 308s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (1/4) 308s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 308s DEBUG habluetooth.scanner:scanner.py:434 hci0 (AA:BB:CC:DD:EE:FF): BleakError while starting bluetooth; attempt: (1/4): org.bluez.Error.NotReady 308s Traceback (most recent call last): 308s File "/usr/lib/python3/dist-packages/habluetooth/scanner.py", line 344, in _async_start_attempt 308s await self.scanner.start() 308s File "/tmp/autopkgtest.ZVzfcl/autopkgtest_tmp/build/tests/test_scanner.py", line 249, in start 308s raise BleakError(error) 308s bleak.exc.BleakError: org.bluez.Error.NotReady 308s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 308s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (2/4) 308s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 308s DEBUG habluetooth.scanner:scanner.py:555 hci0 (AA:BB:CC:DD:EE:FF): adapter stopped responding; executing reset 308s DEBUG habluetooth.scanner:scanner.py:557 hci0 (AA:BB:CC:DD:EE:FF): adapter reset result: True 308s DEBUG habluetooth.scanner:scanner.py:434 hci0 (AA:BB:CC:DD:EE:FF): BleakError while starting bluetooth; attempt: (2/4): org.bluez.Error.NotReady 308s Traceback (most recent call last): 308s File "/usr/lib/python3/dist-packages/habluetooth/scanner.py", line 344, in _async_start_attempt 308s await self.scanner.start() 308s File "/tmp/autopkgtest.ZVzfcl/autopkgtest_tmp/build/tests/test_scanner.py", line 249, in start 308s raise BleakError(error) 308s bleak.exc.BleakError: org.bluez.Error.NotReady 308s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 308s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (3/4) 308s DEBUG habluetooth.scanner:scanner.py:418 hci0 (AA:BB:CC:DD:EE:FF): Success while starting bluetooth; attempt: (3/4) 308s DEBUG bleak_retry_connector:__init__.py:639 Failed to restore discoveries for hci0 308s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 308s PASSED [ 61%] 308s tests/test_scanner.py::test_adapter_needs_reset_at_start[not found] 308s -------------------------------- live log setup -------------------------------- 308s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 308s -------------------------------- live log call --------------------------------- 308s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 308s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (1/4) 308s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 308s DEBUG habluetooth.scanner:scanner.py:434 hci0 (AA:BB:CC:DD:EE:FF): BleakError while starting bluetooth; attempt: (1/4): not found 308s Traceback (most recent call last): 308s File "/usr/lib/python3/dist-packages/habluetooth/scanner.py", line 344, in _async_start_attempt 308s await self.scanner.start() 308s File "/tmp/autopkgtest.ZVzfcl/autopkgtest_tmp/build/tests/test_scanner.py", line 249, in start 308s raise BleakError(error) 308s bleak.exc.BleakError: not found 308s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 308s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (2/4) 308s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 308s DEBUG habluetooth.scanner:scanner.py:555 hci0 (AA:BB:CC:DD:EE:FF): adapter stopped responding; executing reset 308s DEBUG habluetooth.scanner:scanner.py:557 hci0 (AA:BB:CC:DD:EE:FF): adapter reset result: True 308s DEBUG habluetooth.scanner:scanner.py:434 hci0 (AA:BB:CC:DD:EE:FF): BleakError while starting bluetooth; attempt: (2/4): not found 308s Traceback (most recent call last): 308s File "/usr/lib/python3/dist-packages/habluetooth/scanner.py", line 344, in _async_start_attempt 308s await self.scanner.start() 308s File "/tmp/autopkgtest.ZVzfcl/autopkgtest_tmp/build/tests/test_scanner.py", line 249, in start 308s raise BleakError(error) 308s bleak.exc.BleakError: not found 308s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 308s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (3/4) 308s DEBUG habluetooth.scanner:scanner.py:418 hci0 (AA:BB:CC:DD:EE:FF): Success while starting bluetooth; attempt: (3/4) 308s DEBUG bleak_retry_connector:__init__.py:639 Failed to restore discoveries for hci0 308s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 308s PASSED [ 62%] 308s tests/test_scanner.py::test_recovery_from_dbus_restart 308s -------------------------------- live log setup -------------------------------- 308s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 308s -------------------------------- live log call --------------------------------- 308s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 308s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (1/4) 308s DEBUG habluetooth.scanner:scanner.py:418 hci0 (AA:BB:CC:DD:EE:FF): Success while starting bluetooth; attempt: (1/4) 308s DEBUG bleak_retry_connector:__init__.py:639 Failed to restore discoveries for hci0 308s DEBUG habluetooth.base_scanner:base_scanner.py:210 hci0 (AA:BB:CC:DD:EE:FF): Scanner watchdog time_since_last_detection: 10.002838955999977 308s DEBUG habluetooth.base_scanner:base_scanner.py:210 hci0 (AA:BB:CC:DD:EE:FF): Scanner watchdog time_since_last_detection: 20.0 308s DEBUG habluetooth.base_scanner:base_scanner.py:210 hci0 (AA:BB:CC:DD:EE:FF): Scanner watchdog time_since_last_detection: 110.00000000000003 308s DEBUG habluetooth.scanner:scanner.py:517 hci0 (AA:BB:CC:DD:EE:FF): Bluetooth scanner has gone quiet for 110.00000000000003s, restarting 308s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 308s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 308s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (1/4) 308s DEBUG habluetooth.scanner:scanner.py:418 hci0 (AA:BB:CC:DD:EE:FF): Success while starting bluetooth; attempt: (1/4) 308s DEBUG bleak_retry_connector:__init__.py:639 Failed to restore discoveries for hci0 308s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 308s PASSED [ 63%] 308s tests/test_scanner.py::test_adapter_recovery 308s -------------------------------- live log setup -------------------------------- 308s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 308s -------------------------------- live log call --------------------------------- 308s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 308s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (1/4) 308s DEBUG habluetooth.scanner:scanner.py:418 hci0 (AA:BB:CC:DD:EE:FF): Success while starting bluetooth; attempt: (1/4) 308s DEBUG bleak_retry_connector:__init__.py:639 Failed to restore discoveries for hci0 308s DEBUG habluetooth.base_scanner:base_scanner.py:210 hci0 (AA:BB:CC:DD:EE:FF): Scanner watchdog time_since_last_detection: 10.0 308s DEBUG habluetooth.base_scanner:base_scanner.py:210 hci0 (AA:BB:CC:DD:EE:FF): Scanner watchdog time_since_last_detection: 20.0 308s DEBUG habluetooth.base_scanner:base_scanner.py:210 hci0 (AA:BB:CC:DD:EE:FF): Scanner watchdog time_since_last_detection: 120.00000000000003 308s DEBUG habluetooth.scanner:scanner.py:517 hci0 (AA:BB:CC:DD:EE:FF): Bluetooth scanner has gone quiet for 120.00000000000003s, restarting 308s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 308s DEBUG habluetooth.scanner:scanner.py:555 hci0 (AA:BB:CC:DD:EE:FF): adapter stopped responding; executing reset 308s DEBUG habluetooth.scanner:scanner.py:557 hci0 (AA:BB:CC:DD:EE:FF): adapter reset result: True 308s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 308s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (1/4) 308s DEBUG habluetooth.scanner:scanner.py:418 hci0 (AA:BB:CC:DD:EE:FF): Success while starting bluetooth; attempt: (1/4) 308s DEBUG bleak_retry_connector:__init__.py:639 Failed to restore discoveries for hci0 308s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 308s PASSED [ 64%] 308s tests/test_scanner.py::test_adapter_scanner_fails_to_start_first_time 308s -------------------------------- live log setup -------------------------------- 308s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 308s -------------------------------- live log call --------------------------------- 308s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 308s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (1/4) 308s DEBUG habluetooth.scanner:scanner.py:418 hci0 (AA:BB:CC:DD:EE:FF): Success while starting bluetooth; attempt: (1/4) 308s DEBUG bleak_retry_connector:__init__.py:639 Failed to restore discoveries for hci0 308s DEBUG habluetooth.base_scanner:base_scanner.py:210 hci0 (AA:BB:CC:DD:EE:FF): Scanner watchdog time_since_last_detection: 10.0 308s DEBUG habluetooth.base_scanner:base_scanner.py:210 hci0 (AA:BB:CC:DD:EE:FF): Scanner watchdog time_since_last_detection: 20.0 308s DEBUG habluetooth.base_scanner:base_scanner.py:210 hci0 (AA:BB:CC:DD:EE:FF): Scanner watchdog time_since_last_detection: 120.0 308s DEBUG habluetooth.scanner:scanner.py:517 hci0 (AA:BB:CC:DD:EE:FF): Bluetooth scanner has gone quiet for 120.0s, restarting 308s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 308s DEBUG habluetooth.scanner:scanner.py:555 hci0 (AA:BB:CC:DD:EE:FF): adapter stopped responding; executing reset 308s DEBUG habluetooth.scanner:scanner.py:557 hci0 (AA:BB:CC:DD:EE:FF): adapter reset result: True 308s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 308s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (1/4) 308s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 308s DEBUG habluetooth.scanner:scanner.py:434 hci0 (AA:BB:CC:DD:EE:FF): BleakError while starting bluetooth; attempt: (1/4): Failed to start 308s Traceback (most recent call last): 308s File "/usr/lib/python3/dist-packages/habluetooth/scanner.py", line 344, in _async_start_attempt 308s await self.scanner.start() 308s File "/tmp/autopkgtest.ZVzfcl/autopkgtest_tmp/build/tests/test_scanner.py", line 485, in start 308s raise BleakError("Failed to start") 308s bleak.exc.BleakError: Failed to start 308s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 308s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (2/4) 308s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 308s DEBUG habluetooth.scanner:scanner.py:434 hci0 (AA:BB:CC:DD:EE:FF): BleakError while starting bluetooth; attempt: (2/4): Failed to start 308s Traceback (most recent call last): 308s File "/usr/lib/python3/dist-packages/habluetooth/scanner.py", line 344, in _async_start_attempt 308s await self.scanner.start() 308s File "/tmp/autopkgtest.ZVzfcl/autopkgtest_tmp/build/tests/test_scanner.py", line 485, in start 308s raise BleakError("Failed to start") 308s bleak.exc.BleakError: Failed to start 308s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 308s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (3/4) 308s DEBUG habluetooth.scanner:scanner.py:418 hci0 (AA:BB:CC:DD:EE:FF): Success while starting bluetooth; attempt: (3/4) 308s DEBUG bleak_retry_connector:__init__.py:639 Failed to restore discoveries for hci0 308s DEBUG habluetooth.base_scanner:base_scanner.py:210 hci0 (AA:BB:CC:DD:EE:FF): Scanner watchdog time_since_last_detection: 90.00559267599999 308s DEBUG habluetooth.scanner:scanner.py:517 hci0 (AA:BB:CC:DD:EE:FF): Bluetooth scanner has gone quiet for 90.00559267599999s, restarting 308s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 308s DEBUG habluetooth.scanner:scanner.py:555 hci0 (AA:BB:CC:DD:EE:FF): adapter stopped responding; executing reset 308s DEBUG habluetooth.scanner:scanner.py:557 hci0 (AA:BB:CC:DD:EE:FF): adapter reset result: True 308s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 308s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (1/4) 308s DEBUG habluetooth.scanner:scanner.py:418 hci0 (AA:BB:CC:DD:EE:FF): Success while starting bluetooth; attempt: (1/4) 308s DEBUG bleak_retry_connector:__init__.py:639 Failed to restore discoveries for hci0 308s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 308s PASSED [ 65%] 308s tests/test_scanner.py::test_adapter_fails_to_start_and_takes_a_bit_to_init 308s -------------------------------- live log setup -------------------------------- 308s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 308s -------------------------------- live log call --------------------------------- 308s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 308s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (1/4) 308s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 308s DEBUG habluetooth.scanner:scanner.py:402 hci0 (AA:BB:CC:DD:EE:FF): Waiting for adapter to initialize; attempt (1/4) 308s 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 308s Traceback (most recent call last): 308s File "/usr/lib/python3/dist-packages/habluetooth/scanner.py", line 344, in _async_start_attempt 308s await self.scanner.start() 308s File "/tmp/autopkgtest.ZVzfcl/autopkgtest_tmp/build/tests/test_scanner.py", line 599, in start 308s raise BleakError("org.freedesktop.DBus.Error.UnknownObject") 308s bleak.exc.BleakError: org.freedesktop.DBus.Error.UnknownObject 308s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 308s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (2/4) 308s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 308s DEBUG habluetooth.scanner:scanner.py:586 hci0 (AA:BB:CC:DD:EE:FF): Force stopping bluetooth discovery 308s ERROR bleak_retry_connector.bluez:bluez.py:324 Failed to stop discovery for hci0 because no manager 308s DEBUG habluetooth.scanner:scanner.py:555 hci0 (AA:BB:CC:DD:EE:FF): adapter stopped responding; executing reset 308s DEBUG habluetooth.scanner:scanner.py:557 hci0 (AA:BB:CC:DD:EE:FF): adapter reset result: True 308s DEBUG habluetooth.scanner:scanner.py:434 hci0 (AA:BB:CC:DD:EE:FF): BleakError while starting bluetooth; attempt: (2/4): org.bluez.Error.InProgress 308s Traceback (most recent call last): 308s File "/usr/lib/python3/dist-packages/habluetooth/scanner.py", line 344, in _async_start_attempt 308s await self.scanner.start() 308s File "/tmp/autopkgtest.ZVzfcl/autopkgtest_tmp/build/tests/test_scanner.py", line 601, in start 308s raise BleakError("org.bluez.Error.InProgress") 308s bleak.exc.BleakError: org.bluez.Error.InProgress 308s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 308s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (3/4) 308s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 308s DEBUG habluetooth.scanner:scanner.py:586 hci0 (AA:BB:CC:DD:EE:FF): Force stopping bluetooth discovery 308s ERROR bleak_retry_connector.bluez:bluez.py:324 Failed to stop discovery for hci0 because no manager 308s DEBUG habluetooth.scanner:scanner.py:434 hci0 (AA:BB:CC:DD:EE:FF): BleakError while starting bluetooth; attempt: (3/4): org.bluez.Error.InProgress 308s Traceback (most recent call last): 308s File "/usr/lib/python3/dist-packages/habluetooth/scanner.py", line 344, in _async_start_attempt 308s await self.scanner.start() 308s File "/tmp/autopkgtest.ZVzfcl/autopkgtest_tmp/build/tests/test_scanner.py", line 603, in start 308s raise BleakError("org.bluez.Error.InProgress") 308s bleak.exc.BleakError: org.bluez.Error.InProgress 308s 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) 308s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'passive', 'adapter': 'hci0', 'bluez': } 308s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (4/4) 308s 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) 308s DEBUG habluetooth.scanner:scanner.py:418 hci0 (AA:BB:CC:DD:EE:FF): Success while starting bluetooth; attempt: (4/4) 308s DEBUG bleak_retry_connector:__init__.py:639 Failed to restore discoveries for hci0 308s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 308s PASSED [ 66%] 308s tests/test_scanner.py::test_restart_takes_longer_than_watchdog_time 308s -------------------------------- live log setup -------------------------------- 308s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 308s -------------------------------- live log call --------------------------------- 308s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 308s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (1/4) 308s DEBUG habluetooth.scanner:scanner.py:418 hci0 (AA:BB:CC:DD:EE:FF): Success while starting bluetooth; attempt: (1/4) 308s DEBUG bleak_retry_connector:__init__.py:639 Failed to restore discoveries for hci0 308s DEBUG habluetooth.base_scanner:base_scanner.py:210 hci0 (AA:BB:CC:DD:EE:FF): Scanner watchdog time_since_last_detection: 120.00000000000003 308s DEBUG habluetooth.scanner:scanner.py:517 hci0 (AA:BB:CC:DD:EE:FF): Bluetooth scanner has gone quiet for 120.00000000000003s, restarting 308s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 308s DEBUG habluetooth.scanner:scanner.py:555 hci0 (AA:BB:CC:DD:EE:FF): adapter stopped responding; executing reset 308s DEBUG habluetooth.scanner:scanner.py:557 hci0 (AA:BB:CC:DD:EE:FF): adapter reset result: True 308s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 308s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (1/4) 308s DEBUG habluetooth.base_scanner:base_scanner.py:210 hci0 (AA:BB:CC:DD:EE:FF): Scanner watchdog time_since_last_detection: 120.00000000000003 308s DEBUG habluetooth.scanner:scanner.py:512 hci0 (AA:BB:CC:DD:EE:FF): Scanner is already restarting, deferring restart 308s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 308s DEBUG habluetooth.scanner:scanner.py:426 hci0 (AA:BB:CC:DD:EE:FF): TimeoutError while starting bluetooth; attempt: (1/4) 308s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 308s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (2/4) 308s DEBUG habluetooth.scanner:scanner.py:418 hci0 (AA:BB:CC:DD:EE:FF): Success while starting bluetooth; attempt: (2/4) 308s DEBUG bleak_retry_connector:__init__.py:639 Failed to restore discoveries for hci0 308s ERROR asyncio:base_events.py:1875 Future exception was never retrieved 308s future: 308s habluetooth.scanner._AbortStartError 308s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 308s PASSED [ 67%] 308s tests/test_scanner.py::test_setup_and_stop_macos SKIPPED (condition:...) [ 68%] 308s tests/test_scanner.py::test_adapter_init_fails_fallback_to_passive 308s -------------------------------- live log setup -------------------------------- 308s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 308s -------------------------------- live log call --------------------------------- 308s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 308s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (1/4) 308s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 308s DEBUG habluetooth.scanner:scanner.py:402 hci0 (AA:BB:CC:DD:EE:FF): Waiting for adapter to initialize; attempt (1/4) 308s 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 308s Traceback (most recent call last): 308s File "/usr/lib/python3/dist-packages/habluetooth/scanner.py", line 344, in _async_start_attempt 308s await self.scanner.start() 308s File "/tmp/autopkgtest.ZVzfcl/autopkgtest_tmp/build/tests/test_scanner.py", line 780, in start 308s raise BleakError("org.freedesktop.DBus.Error.UnknownObject") 308s bleak.exc.BleakError: org.freedesktop.DBus.Error.UnknownObject 308s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 308s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (2/4) 308s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 308s DEBUG habluetooth.scanner:scanner.py:586 hci0 (AA:BB:CC:DD:EE:FF): Force stopping bluetooth discovery 308s ERROR bleak_retry_connector.bluez:bluez.py:324 Failed to stop discovery for hci0 because no manager 308s DEBUG habluetooth.scanner:scanner.py:555 hci0 (AA:BB:CC:DD:EE:FF): adapter stopped responding; executing reset 308s DEBUG habluetooth.scanner:scanner.py:557 hci0 (AA:BB:CC:DD:EE:FF): adapter reset result: True 308s DEBUG habluetooth.scanner:scanner.py:434 hci0 (AA:BB:CC:DD:EE:FF): BleakError while starting bluetooth; attempt: (2/4): org.bluez.Error.InProgress 308s Traceback (most recent call last): 308s File "/usr/lib/python3/dist-packages/habluetooth/scanner.py", line 344, in _async_start_attempt 308s await self.scanner.start() 308s File "/tmp/autopkgtest.ZVzfcl/autopkgtest_tmp/build/tests/test_scanner.py", line 782, in start 308s raise BleakError("org.bluez.Error.InProgress") 308s bleak.exc.BleakError: org.bluez.Error.InProgress 308s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 308s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (3/4) 308s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 308s DEBUG habluetooth.scanner:scanner.py:586 hci0 (AA:BB:CC:DD:EE:FF): Force stopping bluetooth discovery 308s ERROR bleak_retry_connector.bluez:bluez.py:324 Failed to stop discovery for hci0 because no manager 308s DEBUG habluetooth.scanner:scanner.py:434 hci0 (AA:BB:CC:DD:EE:FF): BleakError while starting bluetooth; attempt: (3/4): org.bluez.Error.InProgress 308s Traceback (most recent call last): 308s File "/usr/lib/python3/dist-packages/habluetooth/scanner.py", line 344, in _async_start_attempt 308s await self.scanner.start() 308s File "/tmp/autopkgtest.ZVzfcl/autopkgtest_tmp/build/tests/test_scanner.py", line 784, in start 308s raise BleakError("org.bluez.Error.InProgress") 308s bleak.exc.BleakError: org.bluez.Error.InProgress 308s 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) 308s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'passive', 'adapter': 'hci0', 'bluez': } 308s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (4/4) 308s 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) 308s DEBUG habluetooth.scanner:scanner.py:418 hci0 (AA:BB:CC:DD:EE:FF): Success while starting bluetooth; attempt: (4/4) 308s DEBUG bleak_retry_connector:__init__.py:639 Failed to restore discoveries for hci0 308s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 308s PASSED [ 70%] 308s tests/test_storage.py::test_discovered_device_advertisement_data_to_dict PASSED [ 71%] 308s tests/test_storage.py::test_discovered_device_advertisement_data_from_dict PASSED [ 72%] 308s tests/test_storage.py::test_expire_stale_scanner_discovered_device_advertisement_data 308s -------------------------------- live log call --------------------------------- 308s DEBUG habluetooth.storage:storage.py:122 Loaded 1 fresh discovered devices for myscanner 308s DEBUG habluetooth.storage:storage.py:122 Loaded 0 fresh discovered devices for all_expired 308s PASSED [ 73%] 308s tests/test_storage.py::test_expire_future_discovered_device_advertisement_data 308s -------------------------------- live log call --------------------------------- 308s DEBUG habluetooth.storage:storage.py:122 Loaded 1 fresh discovered devices for myscanner 308s WARNING habluetooth.storage:storage.py:107 Discarding timestamp 1765971577.2357433 for CC:DD:EE:FF:AA:BB on scanner all_future as it is the future (now = 1764971577.2357516) 308s DEBUG habluetooth.storage:storage.py:122 Loaded 0 fresh discovered devices for all_future 308s PASSED [ 74%] 308s tests/test_storage.py::test_discovered_device_advertisement_data_from_dict_corrupt 308s -------------------------------- live log call --------------------------------- 308s 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' 308s Traceback (most recent call last): 308s File "/usr/lib/python3/dist-packages/habluetooth/storage.py", line 138, in discovered_device_advertisement_data_from_dict 308s _deserialize_discovered_device_advertisement_datas( 308s ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~^ 308s data[DISCOVERED_DEVICE_ADVERTISEMENT_DATAS] 308s ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 308s ), 308s ^ 308s File "/usr/lib/python3/dist-packages/habluetooth/storage.py", line 198, in _deserialize_discovered_device_advertisement_datas 308s _ble_device_from_dict(device_advertisement_data["device"]), 308s ~~~~~~~~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 308s File "/usr/lib/python3/dist-packages/habluetooth/storage.py", line 215, in _ble_device_from_dict 308s return BLEDevice(**device_data) 308s TypeError: BLEDevice.__init__() missing 1 required positional argument: 'name' 308s PASSED [ 75%] 308s tests/test_storage.py::test_backward_compatibility_rssi_in_device_dict PASSED [ 76%] 308s tests/test_wrappers.py::test_test_switch_adapters_when_out_of_slots 308s -------------------------------- live log setup -------------------------------- 308s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 308s -------------------------------- live log call --------------------------------- 308s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (00:00:00:00:00:01) 308s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci1 (00:00:00:00:00:02) 308s DEBUG habluetooth.wrappers:wrappers.py:279 00:00:00:00:00:01: Looking for backend to connect 308s 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) 308s 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) 308s 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) 308s DEBUG habluetooth.wrappers:wrappers.py:279 00:00:00:00:00:02: Looking for backend to connect 308s 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) 308s DEBUG habluetooth.wrappers:wrappers.py:279 00:00:00:00:00:03: Looking for backend to connect 308s 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) 308s 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) 308s 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) 308s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (00:00:00:00:00:01) 308s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci1 (00:00:00:00:00:02) 308s PASSED [ 77%] 308s tests/test_wrappers.py::test_release_slot_on_connect_failure 308s -------------------------------- live log setup -------------------------------- 308s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 308s -------------------------------- live log call --------------------------------- 308s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (00:00:00:00:00:01) 308s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci1 (00:00:00:00:00:02) 308s DEBUG habluetooth.wrappers:wrappers.py:279 00:00:00:00:00:01: Looking for backend to connect 308s 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) 308s 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) 308s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (00:00:00:00:00:01) 308s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci1 (00:00:00:00:00:02) 308s PASSED [ 78%] 308s tests/test_wrappers.py::test_release_slot_on_connect_exception 308s -------------------------------- live log setup -------------------------------- 308s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 308s -------------------------------- live log call --------------------------------- 308s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (00:00:00:00:00:01) 308s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci1 (00:00:00:00:00:02) 308s DEBUG habluetooth.wrappers:wrappers.py:279 00:00:00:00:00:01: Looking for backend to connect 308s 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) 308s 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) 308s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (00:00:00:00:00:01) 308s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci1 (00:00:00:00:00:02) 308s PASSED [ 80%] 308s tests/test_wrappers.py::test_switch_adapters_on_failure 308s -------------------------------- live log setup -------------------------------- 308s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 308s -------------------------------- live log call --------------------------------- 308s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (00:00:00:00:00:01) 308s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci1 (00:00:00:00:00:02) 308s DEBUG habluetooth.wrappers:wrappers.py:279 00:00:00:00:00:01: Looking for backend to connect 308s 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) 308s 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) 308s DEBUG habluetooth.wrappers:wrappers.py:279 00:00:00:00:00:01: Looking for backend to connect 308s 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) 308s 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) 308s DEBUG habluetooth.wrappers:wrappers.py:279 00:00:00:00:00:01: Looking for backend to connect 308s 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) 308s 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) 308s 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) 308s DEBUG habluetooth.wrappers:wrappers.py:279 00:00:00:00:00:01: Looking for backend to connect 308s 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) 308s 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) 308s 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) 308s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (00:00:00:00:00:01) 308s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci1 (00:00:00:00:00:02) 308s PASSED [ 81%] 308s tests/test_wrappers.py::test_switch_adapters_on_connecting 308s -------------------------------- live log setup -------------------------------- 308s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 308s -------------------------------- live log call --------------------------------- 308s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (00:00:00:00:00:01) 308s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci1 (00:00:00:00:00:02) 308s DEBUG habluetooth.wrappers:wrappers.py:279 00:00:00:00:00:01: Looking for backend to connect 308s 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) 308s 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) 308s DEBUG habluetooth.wrappers:wrappers.py:279 00:00:00:00:00:01: Looking for backend to connect 308s 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) 308s 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) 308s 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) 308s 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) 308s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (00:00:00:00:00:01) 308s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci1 (00:00:00:00:00:02) 308s PASSED [ 82%] 308s tests/test_wrappers.py::test_single_adapter_connection_history 308s -------------------------------- live log setup -------------------------------- 308s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 308s -------------------------------- live log call --------------------------------- 308s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (AA:BB:CC:DD:EE:00) 308s DEBUG habluetooth.wrappers:wrappers.py:279 00:00:00:00:00:11: Looking for backend to connect 308s 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) 308s 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) 308s 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) 308s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (AA:BB:CC:DD:EE:00) 308s PASSED [ 83%] 308s tests/test_wrappers.py::test_passing_subclassed_str_as_address 308s -------------------------------- live log setup -------------------------------- 308s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 308s -------------------------------- live log call --------------------------------- 308s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (00:00:00:00:00:01) 308s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci1 (00:00:00:00:00:02) 308s DEBUG habluetooth.wrappers:wrappers.py:279 00:00:00:00:00:01: Looking for backend to connect 308s 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) 308s 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) 308s 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) 308s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (00:00:00:00:00:01) 308s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci1 (00:00:00:00:00:02) 308s PASSED [ 84%] 308s tests/test_wrappers.py::test_find_device_by_address 308s -------------------------------- live log setup -------------------------------- 308s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 308s -------------------------------- live log call --------------------------------- 308s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (00:00:00:00:00:01) 308s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci1 (00:00:00:00:00:02) 308s PASSED [ 85%] 308s tests/test_wrappers.py::test_discover 308s -------------------------------- live log setup -------------------------------- 308s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 308s -------------------------------- live log call --------------------------------- 308s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (00:00:00:00:00:01) 308s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci1 (00:00:00:00:00:02) 308s PASSED [ 86%] 308s tests/test_wrappers.py::test_raise_after_shutdown 308s -------------------------------- live log setup -------------------------------- 308s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 308s -------------------------------- live log call --------------------------------- 308s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (00:00:00:00:00:01) 308s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci1 (00:00:00:00:00:02) 308s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (00:00:00:00:00:01) 308s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci1 (00:00:00:00:00:02) 308s PASSED [ 87%] 308s tests/test_wrappers.py::test_wrapped_instance_with_filter 308s -------------------------------- live log setup -------------------------------- 308s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 308s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (AA:BB:CC:DD:EE:00) 308s PASSED [ 88%] 308s ------------------------------ live log teardown ------------------------------- 308s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (AA:BB:CC:DD:EE:00) 308s 308s tests/test_wrappers.py::test_wrapped_instance_with_service_uuids 308s -------------------------------- live log setup -------------------------------- 308s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 308s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (AA:BB:CC:DD:EE:00) 308s PASSED [ 90%] 308s ------------------------------ live log teardown ------------------------------- 308s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (AA:BB:CC:DD:EE:00) 308s 308s tests/test_wrappers.py::test_wrapped_instance_with_service_uuids_with_coro_callback 308s -------------------------------- live log setup -------------------------------- 308s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 308s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (AA:BB:CC:DD:EE:00) 308s PASSED [ 91%] 308s ------------------------------ live log teardown ------------------------------- 308s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (AA:BB:CC:DD:EE:00) 308s 308s tests/test_wrappers.py::test_wrapped_instance_with_broken_callbacks 308s -------------------------------- live log setup -------------------------------- 308s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 308s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (AA:BB:CC:DD:EE:00) 308s PASSED [ 92%] 308s ------------------------------ live log teardown ------------------------------- 308s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (AA:BB:CC:DD:EE:00) 308s 308s tests/test_wrappers.py::test_wrapped_instance_changes_uuids 308s -------------------------------- live log setup -------------------------------- 308s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 308s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (AA:BB:CC:DD:EE:00) 308s PASSED [ 93%] 308s ------------------------------ live log teardown ------------------------------- 308s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (AA:BB:CC:DD:EE:00) 308s 308s tests/test_wrappers.py::test_wrapped_instance_changes_filters 308s -------------------------------- live log setup -------------------------------- 308s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 308s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (AA:BB:CC:DD:EE:00) 308s PASSED [ 94%] 308s ------------------------------ live log teardown ------------------------------- 308s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (AA:BB:CC:DD:EE:00) 308s 308s tests/test_wrappers.py::test_wrapped_instance_unsupported_filter 308s -------------------------------- live log setup -------------------------------- 308s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 308s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (AA:BB:CC:DD:EE:00) 308s -------------------------------- live log call --------------------------------- 308s WARNING habluetooth.wrappers:wrappers.py:120 Only UUIDs filters are supported 308s PASSED [ 95%] 308s ------------------------------ live log teardown ------------------------------- 308s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (AA:BB:CC:DD:EE:00) 308s 308s tests/test_wrappers.py::test_client_with_services_parameter 308s -------------------------------- live log setup -------------------------------- 308s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 308s -------------------------------- live log call --------------------------------- 308s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (00:00:00:00:00:01) 308s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci1 (00:00:00:00:00:02) 308s DEBUG habluetooth.wrappers:wrappers.py:279 00:00:00:00:00:01: Looking for backend to connect 308s 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) 308s 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) 308s 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) 308s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (00:00:00:00:00:01) 308s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci1 (00:00:00:00:00:02) 309s PASSED [ 96%] 309s tests/test_wrappers.py::test_client_with_pair_parameter 309s -------------------------------- live log setup -------------------------------- 309s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 309s -------------------------------- live log call --------------------------------- 309s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (00:00:00:00:00:01) 309s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci1 (00:00:00:00:00:02) 309s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (00:00:00:00:00:01) 309s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci1 (00:00:00:00:00:02) 309s PASSED [ 97%] 309s tests/test_wrappers.py::test_client_services_normalization 309s -------------------------------- live log setup -------------------------------- 309s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 309s -------------------------------- live log call --------------------------------- 309s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (00:00:00:00:00:01) 309s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci1 (00:00:00:00:00:02) 309s DEBUG habluetooth.wrappers:wrappers.py:279 00:00:00:00:00:01: Looking for backend to connect 309s 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) 309s 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) 309s 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) 309s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (00:00:00:00:00:01) 309s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci1 (00:00:00:00:00:02) 309s PASSED [ 98%] 309s tests/test_wrappers.py::test_client_with_none_services 309s -------------------------------- live log setup -------------------------------- 309s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 309s -------------------------------- live log call --------------------------------- 309s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (00:00:00:00:00:01) 309s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci1 (00:00:00:00:00:02) 309s DEBUG habluetooth.wrappers:wrappers.py:279 00:00:00:00:00:01: Looking for backend to connect 309s 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) 309s 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) 309s 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) 309s DEBUG habluetooth.wrappers:wrappers.py:279 00:00:00:00:00:01: Looking for backend to connect 309s 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) 309s 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) 309s 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) 309s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (00:00:00:00:00:01) 309s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci1 (00:00:00:00:00:02) 309s PASSED [100%] 309s ------------------------------ live log teardown ------------------------------- 309s DEBUG habluetooth.manager:manager.py:322 Stopping bluetooth manager 309s 309s 309s =============================== warnings summary =============================== 309s tests/test_base_scanner.py::test_remote_scanner[None] 309s /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 309s return asyncio.get_event_loop_policy() 309s 309s tests/test_base_scanner.py: 10 warnings 309s tests/test_manager.py: 20 warnings 309s tests/test_scanner.py: 18 warnings 309s tests/test_wrappers.py: 21 warnings 309s /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 309s old_loop_policy = asyncio.get_event_loop_policy() 309s 309s tests/test_base_scanner.py: 10 warnings 309s tests/test_manager.py: 20 warnings 309s tests/test_scanner.py: 18 warnings 309s tests/test_wrappers.py: 21 warnings 309s /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 309s asyncio.set_event_loop_policy(policy) 309s 309s tests/test_base_scanner.py: 9 warnings 309s tests/test_manager.py: 20 warnings 309s tests/test_scanner.py: 18 warnings 309s tests/test_wrappers.py: 21 warnings 309s /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 309s _restore_event_loop_policy(asyncio.get_event_loop_policy()), 309s 309s tests/test_base_scanner.py: 9 warnings 309s tests/test_manager.py: 20 warnings 309s tests/test_scanner.py: 18 warnings 309s tests/test_wrappers.py: 21 warnings 309s /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 309s loop = asyncio.get_event_loop_policy().new_event_loop() 309s 309s tests/test_base_scanner.py: 9 warnings 309s tests/test_manager.py: 20 warnings 309s tests/test_scanner.py: 18 warnings 309s tests/test_wrappers.py: 21 warnings 309s /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 309s policy = asyncio.get_event_loop_policy() 309s 309s tests/test_base_scanner.py: 9 warnings 309s tests/test_manager.py: 3 warnings 309s tests/test_wrappers.py: 14 warnings 309s /tmp/autopkgtest.ZVzfcl/autopkgtest_tmp/build/tests/__init__.py:64: DeprecationWarning: Passing additional arguments for BLEDevice is deprecated and has no effect. 309s return BLEDevice(**new) 309s 309s tests/test_base_scanner.py: 9 warnings 309s tests/test_manager.py: 20 warnings 309s tests/test_scanner.py: 18 warnings 309s tests/test_wrappers.py: 22 warnings 309s /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 309s asyncio.set_event_loop_policy(old_loop_policy) 309s 309s tests/test_base_scanner.py: 9 warnings 309s tests/test_manager.py: 20 warnings 309s tests/test_scanner.py: 18 warnings 309s tests/test_wrappers.py: 21 warnings 309s /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 309s policy = asyncio.get_event_loop_policy() 309s 309s tests/test_base_scanner.py: 9 warnings 309s tests/test_manager.py: 20 warnings 309s tests/test_scanner.py: 18 warnings 309s tests/test_wrappers.py: 21 warnings 309s /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 309s asyncio.set_event_loop_policy(previous_policy) 309s 309s tests/test_base_scanner.py: 9 warnings 309s tests/test_manager.py: 20 warnings 309s tests/test_scanner.py: 18 warnings 309s tests/test_wrappers.py: 21 warnings 309s /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 309s policy = asyncio.get_event_loop_policy() 309s 309s tests/test_base_scanner.py::test_remote_scanner_expires_non_connectable 309s /tmp/autopkgtest.ZVzfcl/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 309s assert len(scanner._discovered_device_timestamps) == 1 309s 309s tests/test_base_scanner.py::test_remote_scanner_expires_non_connectable 309s /tmp/autopkgtest.ZVzfcl/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 309s assert len(scanner._discovered_device_timestamps) == 0 309s 309s tests/test_base_scanner.py::test_remote_scanner_expires_non_connectable 309s /tmp/autopkgtest.ZVzfcl/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 309s assert len(scanner._discovered_device_timestamps) == 0 309s 309s -- Docs: https://docs.pytest.org/en/stable/how-to/capture-warnings.html 309s ================= 89 passed, 1 skipped, 645 warnings in 12.76s ================= 309s I: pybuild base:317: cd /tmp/autopkgtest.ZVzfcl/autopkgtest_tmp/build; python3.13 -m pytest --no-cov --ignore=tests/test_benchmark_base_scanner.py 311s /usr/lib/python3/dist-packages/pytest_asyncio/plugin.py:207: PytestDeprecationWarning: The configuration option "asyncio_default_fixture_loop_scope" is unset. 311s 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" 311s 311s warnings.warn(PytestDeprecationWarning(_DEFAULT_FIXTURE_LOOP_SCOPE_UNSET)) 311s ============================= test session starts ============================== 311s platform linux -- Python 3.13.9, pytest-8.3.5, pluggy-1.6.0 -- /usr/bin/python3.13 311s cachedir: .pytest_cache 311s rootdir: /tmp/autopkgtest.ZVzfcl/autopkgtest_tmp/build 311s configfile: pyproject.toml 311s plugins: asyncio-0.25.1, typeguard-4.4.2, cov-5.0.0 311s asyncio: mode=Mode.STRICT, asyncio_default_fixture_loop_scope=None 312s collecting ... collected 90 items 312s 312s tests/test_base_scanner.py::test_remote_scanner[None] 312s -------------------------------- live log setup -------------------------------- 312s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 312s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 312s WARNING habluetooth.manager:manager.py:189 BluetoothManager: does not implement _discover_service_info, subclasses must implement this method to consume discovery data 312s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 312s -------------------------------- live log call --------------------------------- 312s DEBUG habluetooth.manager:manager.py:765 Registering scanner esp32 312s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner esp32 312s PASSED [ 1%] 312s tests/test_base_scanner.py::test_remote_scanner[w] 312s -------------------------------- live log setup -------------------------------- 312s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 312s -------------------------------- live log call --------------------------------- 312s DEBUG habluetooth.manager:manager.py:765 Registering scanner esp32 312s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner esp32 312s PASSED [ 2%] 312s tests/test_base_scanner.py::test_remote_scanner_expires_connectable 312s -------------------------------- live log setup -------------------------------- 312s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 312s -------------------------------- live log call --------------------------------- 312s DEBUG habluetooth.manager:manager.py:765 Registering scanner esp32 312s DEBUG habluetooth.base_scanner:base_scanner.py:210 esp32: Scanner watchdog time_since_last_detection: 196.01185229899997 312s DEBUG habluetooth.base_scanner:base_scanner.py:225 esp32: Bluetooth scanner has gone quiet for 196.01185229899997s, check logs on the scanner device for more information 312s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner esp32 312s PASSED [ 3%] 312s tests/test_base_scanner.py::test_remote_scanner_expires_non_connectable 312s -------------------------------- live log setup -------------------------------- 312s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 312s -------------------------------- live log call --------------------------------- 312s DEBUG habluetooth.manager:manager.py:765 Registering scanner esp32 312s DEBUG habluetooth.base_scanner:base_scanner.py:210 esp32: Scanner watchdog time_since_last_detection: 196.01400457600002 312s DEBUG habluetooth.base_scanner:base_scanner.py:225 esp32: Bluetooth scanner has gone quiet for 196.01400457600002s, check logs on the scanner device for more information 312s DEBUG habluetooth.base_scanner:base_scanner.py:210 esp32: Scanner watchdog time_since_last_detection: 901.0140045759999 312s DEBUG habluetooth.base_scanner:base_scanner.py:225 esp32: Bluetooth scanner has gone quiet for 901.0140045759999s, check logs on the scanner device for more information 312s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner esp32 312s PASSED [ 4%] 312s tests/test_base_scanner.py::test_base_scanner_connecting_behavior 312s -------------------------------- live log setup -------------------------------- 312s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 312s -------------------------------- live log call --------------------------------- 312s DEBUG habluetooth.manager:manager.py:765 Registering scanner esp32 312s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner esp32 312s PASSED [ 5%] 312s tests/test_base_scanner.py::test_scanner_stops_responding 312s -------------------------------- live log setup -------------------------------- 312s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 312s -------------------------------- live log call --------------------------------- 312s DEBUG habluetooth.manager:manager.py:765 Registering scanner esp32 312s DEBUG habluetooth.base_scanner:base_scanner.py:210 esp32: Scanner watchdog time_since_last_detection: 120.01089642899998 312s DEBUG habluetooth.base_scanner:base_scanner.py:225 esp32: Bluetooth scanner has gone quiet for 120.01089642899998s, check logs on the scanner device for more information 312s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner esp32 312s PASSED [ 6%] 312s tests/test_base_scanner.py::test_merge_manufacturer_data_history_existing 312s -------------------------------- live log setup -------------------------------- 312s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 312s -------------------------------- live log call --------------------------------- 312s DEBUG habluetooth.manager:manager.py:765 Registering scanner esp32 312s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner esp32 312s PASSED [ 7%] 312s tests/test_base_scanner.py::test_merge_manufacturer_data_history_new 312s -------------------------------- live log setup -------------------------------- 312s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 312s -------------------------------- live log call --------------------------------- 312s DEBUG habluetooth.manager:manager.py:765 Registering scanner esp32 312s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner esp32 312s PASSED [ 8%] 313s tests/test_base_scanner.py::test_filter_apple_data 313s -------------------------------- live log setup -------------------------------- 313s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 313s -------------------------------- live log call --------------------------------- 313s DEBUG habluetooth.manager:manager.py:765 Registering scanner esp32 313s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner esp32 313s PASSED [ 10%] 313s tests/test_base_scanner.py::test_connection_history_count_in_progress 313s -------------------------------- live log setup -------------------------------- 313s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (AA:BB:CC:DD:EE:00) 313s PASSED [ 11%] 313s ------------------------------ live log teardown ------------------------------- 313s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (AA:BB:CC:DD:EE:00) 313s 313s tests/test_base_scanner.py::test_connection_history_failure_count 313s -------------------------------- live log setup -------------------------------- 313s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (AA:BB:CC:DD:EE:00) 313s -------------------------------- live log call --------------------------------- 313s 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 313s PASSED [ 12%] 313s ------------------------------ live log teardown ------------------------------- 313s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (AA:BB:CC:DD:EE:00) 313s 313s tests/test_init.py::test_create_scanner 313s -------------------------------- live log setup -------------------------------- 313s WARNING habluetooth.manager:manager.py:189 BluetoothManager: does not implement _discover_service_info, subclasses must implement this method to consume discovery data 313s PASSED [ 13%] 313s tests/test_init.py::test_create_remote_scanner PASSED [ 14%] 313s tests/test_init.py::test__async_on_advertisement PASSED [ 15%] 313s tests/test_init.py::test__async_on_advertisement_first PASSED [ 16%] 313s tests/test_init.py::test__async_on_advertisement_prefers_longest_local_name PASSED [ 17%] 313s tests/test_init.py::test_create_ha_scanner PASSED [ 18%] 313s tests/test_manager.py::test_async_recover_failed_adapters 313s -------------------------------- live log setup -------------------------------- 313s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 313s -------------------------------- live log call --------------------------------- 313s WARNING habluetooth.manager:manager.py:189 BluetoothManager: does not implement _discover_service_info, subclasses must implement this method to consume discovery data 313s PASSED [ 20%] 313s tests/test_manager.py::test_create_manager 313s -------------------------------- live log setup -------------------------------- 313s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 313s -------------------------------- live log call --------------------------------- 313s WARNING habluetooth.manager:manager.py:189 BluetoothManager: does not implement _discover_service_info, subclasses must implement this method to consume discovery data 313s PASSED [ 21%] 313s tests/test_manager.py::test_async_register_disappeared_callback 313s -------------------------------- live log setup -------------------------------- 313s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 313s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (AA:BB:CC:DD:EE:00) 313s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci1 (AA:BB:CC:DD:EE:11) 313s -------------------------------- live log call --------------------------------- 313s ERROR habluetooth.manager:manager.py:425 Error in disappeared callback 313s Traceback (most recent call last): 313s File "/usr/lib/python3/dist-packages/habluetooth/manager.py", line 423, in _async_check_unavailable 313s disappear_callback(address) 313s ~~~~~~~~~~~~~~~~~~^^^^^^^^^ 313s File "/tmp/autopkgtest.ZVzfcl/autopkgtest_tmp/build/tests/test_manager.py", line 188, in _failing_callback 313s raise ValueError("This is a test") 313s ValueError: This is a test 313s PASSED [ 22%] 313s ------------------------------ live log teardown ------------------------------- 313s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci1 (AA:BB:CC:DD:EE:11) 313s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (AA:BB:CC:DD:EE:00) 313s 313s tests/test_manager.py::test_async_register_allocation_callback 313s -------------------------------- live log setup -------------------------------- 313s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 313s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (AA:BB:CC:DD:EE:00) 313s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci1 (AA:BB:CC:DD:EE:11) 313s -------------------------------- live log call --------------------------------- 313s ERROR habluetooth.manager:manager.py:850 Error in allocation callback 313s Traceback (most recent call last): 313s File "/usr/lib/python3/dist-packages/habluetooth/manager.py", line 848, in async_on_allocation_changed 313s callback_(ha_slot_allocations) 313s ~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^ 313s File "/tmp/autopkgtest.ZVzfcl/autopkgtest_tmp/build/tests/test_manager.py", line 259, in _failing_callback 313s raise ValueError("This is a test") 313s ValueError: This is a test 313s ERROR habluetooth.manager:manager.py:850 Error in allocation callback 313s Traceback (most recent call last): 313s File "/usr/lib/python3/dist-packages/habluetooth/manager.py", line 848, in async_on_allocation_changed 313s callback_(ha_slot_allocations) 313s ~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^ 313s File "/tmp/autopkgtest.ZVzfcl/autopkgtest_tmp/build/tests/test_manager.py", line 259, in _failing_callback 313s raise ValueError("This is a test") 313s ValueError: This is a test 313s PASSED [ 23%] 313s ------------------------------ live log teardown ------------------------------- 313s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci1 (AA:BB:CC:DD:EE:11) 313s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (AA:BB:CC:DD:EE:00) 313s 313s tests/test_manager.py::test_async_register_allocation_callback_non_connectable 313s -------------------------------- live log setup -------------------------------- 313s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 313s DEBUG habluetooth.manager:manager.py:765 Registering scanner non connectable (AA:BB:CC:DD:EE:FF) 313s PASSED [ 24%] 313s ------------------------------ live log teardown ------------------------------- 313s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner non connectable (AA:BB:CC:DD:EE:FF) 313s 313s tests/test_manager.py::test_async_register_scanner_registration_callback 313s -------------------------------- live log setup -------------------------------- 313s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 313s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (AA:BB:CC:DD:EE:00) 313s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci1 (AA:BB:CC:DD:EE:11) 313s -------------------------------- live log call --------------------------------- 313s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci3 (AA:BB:CC:DD:EE:33) 313s ERROR habluetooth.manager:manager.py:867 Error in scanner callback 313s Traceback (most recent call last): 313s File "/usr/lib/python3/dist-packages/habluetooth/manager.py", line 865, in _async_on_scanner_registration 313s callback_(HaScannerRegistration(event, scanner)) 313s ~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 313s File "/tmp/autopkgtest.ZVzfcl/autopkgtest_tmp/build/tests/test_manager.py", line 381, in _failing_callback 313s raise ValueError("This is a test") 313s ValueError: This is a test 313s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci3 (AA:BB:CC:DD:EE:33) 313s ERROR habluetooth.manager:manager.py:867 Error in scanner callback 313s Traceback (most recent call last): 313s File "/usr/lib/python3/dist-packages/habluetooth/manager.py", line 865, in _async_on_scanner_registration 313s callback_(HaScannerRegistration(event, scanner)) 313s ~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 313s File "/tmp/autopkgtest.ZVzfcl/autopkgtest_tmp/build/tests/test_manager.py", line 381, in _failing_callback 313s raise ValueError("This is a test") 313s ValueError: This is a test 313s PASSED [ 25%] 313s ------------------------------ live log teardown ------------------------------- 313s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci1 (AA:BB:CC:DD:EE:11) 313s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (AA:BB:CC:DD:EE:00) 313s 313s tests/test_manager.py::test_async_register_scanner_with_connection_slots 313s -------------------------------- live log setup -------------------------------- 313s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 313s -------------------------------- live log call --------------------------------- 313s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci3 (AA:BB:CC:DD:EE:33) 313s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci3 (AA:BB:CC:DD:EE:33) 313s PASSED [ 26%] 313s tests/test_manager.py::test_diagnostics 313s -------------------------------- live log setup -------------------------------- 313s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 313s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (AA:BB:CC:DD:EE:00) 313s PASSED [ 27%] 313s ------------------------------ live log teardown ------------------------------- 313s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (AA:BB:CC:DD:EE:00) 313s 313s tests/test_manager.py::test_advertisements_do_not_switch_adapters_for_no_reason 313s -------------------------------- live log setup -------------------------------- 313s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 313s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (AA:BB:CC:DD:EE:00) 313s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci1 (AA:BB:CC:DD:EE:11) 313s PASSED [ 28%] 313s ------------------------------ live log teardown ------------------------------- 313s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci1 (AA:BB:CC:DD:EE:11) 313s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (AA:BB:CC:DD:EE:00) 313s 313s tests/test_manager.py::test_switching_adapters_based_on_rssi 313s -------------------------------- live log setup -------------------------------- 313s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 313s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (AA:BB:CC:DD:EE:00) 313s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci1 (AA:BB:CC:DD:EE:11) 313s -------------------------------- live log call --------------------------------- 313s 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) 313s PASSED [ 30%] 313s ------------------------------ live log teardown ------------------------------- 313s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci1 (AA:BB:CC:DD:EE:11) 313s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (AA:BB:CC:DD:EE:00) 313s 313s tests/test_manager.py::test_switching_adapters_based_on_zero_rssi 313s -------------------------------- live log setup -------------------------------- 313s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 313s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (AA:BB:CC:DD:EE:00) 313s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci1 (AA:BB:CC:DD:EE:11) 313s -------------------------------- live log call --------------------------------- 313s 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) 313s PASSED [ 31%] 313s ------------------------------ live log teardown ------------------------------- 313s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci1 (AA:BB:CC:DD:EE:11) 313s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (AA:BB:CC:DD:EE:00) 313s 313s tests/test_manager.py::test_switching_adapters_based_on_stale 313s -------------------------------- live log setup -------------------------------- 313s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 313s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (AA:BB:CC:DD:EE:00) 313s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci1 (AA:BB:CC:DD:EE:11) 313s -------------------------------- live log call --------------------------------- 313s 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) 313s PASSED [ 32%] 313s ------------------------------ live log teardown ------------------------------- 313s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci1 (AA:BB:CC:DD:EE:11) 313s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (AA:BB:CC:DD:EE:00) 313s 313s tests/test_manager.py::test_switching_adapters_based_on_stale_with_discovered_interval 313s -------------------------------- live log setup -------------------------------- 313s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 313s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (AA:BB:CC:DD:EE:00) 313s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci1 (AA:BB:CC:DD:EE:11) 313s -------------------------------- live log call --------------------------------- 313s 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) 313s PASSED [ 33%] 313s ------------------------------ live log teardown ------------------------------- 313s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci1 (AA:BB:CC:DD:EE:11) 313s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (AA:BB:CC:DD:EE:00) 313s 313s tests/test_manager.py::test_switching_adapters_based_on_rssi_connectable_to_non_connectable 313s -------------------------------- live log setup -------------------------------- 313s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 313s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (AA:BB:CC:DD:EE:00) 313s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci1 (AA:BB:CC:DD:EE:11) 313s -------------------------------- live log call --------------------------------- 313s 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) 313s PASSED [ 34%] 313s ------------------------------ live log teardown ------------------------------- 313s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci1 (AA:BB:CC:DD:EE:11) 313s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (AA:BB:CC:DD:EE:00) 313s 313s tests/test_manager.py::test_connectable_advertisement_can_be_retrieved_best_path_is_non_connectable 313s -------------------------------- live log setup -------------------------------- 313s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 313s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (AA:BB:CC:DD:EE:00) 313s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci1 (AA:BB:CC:DD:EE:11) 313s PASSED [ 35%] 313s ------------------------------ live log teardown ------------------------------- 313s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci1 (AA:BB:CC:DD:EE:11) 313s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (AA:BB:CC:DD:EE:00) 313s 313s tests/test_manager.py::test_switching_adapters_when_one_goes_away 313s -------------------------------- live log setup -------------------------------- 313s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 313s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (AA:BB:CC:DD:EE:00) 313s -------------------------------- live log call --------------------------------- 313s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci2 313s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci2 313s PASSED [ 36%] 313s ------------------------------ live log teardown ------------------------------- 313s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (AA:BB:CC:DD:EE:00) 313s 313s tests/test_manager.py::test_switching_adapters_when_one_stop_scanning 313s -------------------------------- live log setup -------------------------------- 313s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 313s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (AA:BB:CC:DD:EE:00) 313s -------------------------------- live log call --------------------------------- 313s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci2 313s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci2 313s PASSED [ 37%] 313s ------------------------------ live log teardown ------------------------------- 313s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (AA:BB:CC:DD:EE:00) 313s 313s tests/test_manager.py::test_set_fallback_interval_small 313s -------------------------------- live log setup -------------------------------- 313s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 313s PASSED [ 38%] 313s tests/test_manager.py::test_set_fallback_interval_big 313s -------------------------------- live log setup -------------------------------- 313s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 313s PASSED [ 40%] 313s tests/test_manager.py::test_subclassing_bluetooth_manager 313s -------------------------------- live log setup -------------------------------- 313s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 313s -------------------------------- live log call --------------------------------- 313s WARNING habluetooth.manager:manager.py:189 TestBluetoothManager2: does not implement _discover_service_info, subclasses must implement this method to consume discovery data 313s PASSED [ 41%] 313s tests/test_models.py::test_model PASSED [ 42%] 313s tests/test_models.py::test_model_from_bleak PASSED [ 43%] 313s tests/test_models.py::test_model_from_scanner PASSED [ 44%] 313s tests/test_models.py::test_construct_service_info_bleak PASSED [ 45%] 313s tests/test_models.py::test_from_device_and_advertisement_data PASSED [ 46%] 313s tests/test_models.py::test_pyobjc_compat PASSED [ 47%] 313s tests/test_models.py::test_as_connectable PASSED [ 48%] 313s tests/test_scanner.py::test_empty_data_no_scanner 313s -------------------------------- live log setup -------------------------------- 313s WARNING habluetooth.manager:manager.py:189 BluetoothManager: does not implement _discover_service_info, subclasses must implement this method to consume discovery data 313s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 313s PASSED [ 50%] 313s tests/test_scanner.py::test_dbus_socket_missing_in_container 313s -------------------------------- live log setup -------------------------------- 313s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 313s -------------------------------- live log call --------------------------------- 313s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 313s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (1/4) 313s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 313s DEBUG habluetooth.scanner:scanner.py:462 hci0 (AA:BB:CC:DD:EE:FF): FileNotFoundError while starting bluetooth: 313s Traceback (most recent call last): 313s File "/usr/lib/python3/dist-packages/habluetooth/scanner.py", line 344, in _async_start_attempt 313s await self.scanner.start() 313s File "/usr/lib/python3.13/unittest/mock.py", line 2321, in _execute_mock_call 313s raise effect 313s FileNotFoundError 313s PASSED [ 51%] 313s tests/test_scanner.py::test_dbus_socket_missing 313s -------------------------------- live log setup -------------------------------- 313s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 313s -------------------------------- live log call --------------------------------- 313s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 313s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (1/4) 313s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 313s DEBUG habluetooth.scanner:scanner.py:462 hci0 (AA:BB:CC:DD:EE:FF): FileNotFoundError while starting bluetooth: 313s Traceback (most recent call last): 313s File "/usr/lib/python3/dist-packages/habluetooth/scanner.py", line 344, in _async_start_attempt 313s await self.scanner.start() 313s File "/usr/lib/python3.13/unittest/mock.py", line 2321, in _execute_mock_call 313s raise effect 313s FileNotFoundError 313s PASSED [ 52%] 313s tests/test_scanner.py::test_handle_cancellation 313s -------------------------------- live log setup -------------------------------- 313s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 313s -------------------------------- live log call --------------------------------- 313s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 313s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (1/4) 313s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 313s PASSED [ 53%] 313s tests/test_scanner.py::test_handle_stop_while_starting 313s -------------------------------- live log setup -------------------------------- 313s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 313s -------------------------------- live log call --------------------------------- 313s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 313s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (1/4) 313s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 313s DEBUG habluetooth.scanner:scanner.py:452 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth scanner aborted: 313s Traceback (most recent call last): 313s File "/usr/lib/python3/dist-packages/habluetooth/scanner.py", line 344, in _async_start_attempt 313s await self.scanner.start() 313s File "/tmp/autopkgtest.ZVzfcl/autopkgtest_tmp/build/tests/test_scanner.py", line 149, in _start 313s await asyncio.sleep(1000) 313s File "/usr/lib/python3.13/asyncio/tasks.py", line 718, in sleep 313s return await future 313s ^^^^^^^^^^^^ 313s asyncio.exceptions.CancelledError: Interrupted by interrupt context manager 313s 313s The above exception was the direct cause of the following exception: 313s 313s Traceback (most recent call last): 313s File "/usr/lib/python3/dist-packages/habluetooth/scanner.py", line 342, in _async_start_attempt 313s async_interrupt.interrupt(self._start_future, _AbortStartError, None), 313s ~~~~~~~~~~~~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 313s File "/usr/lib/python3/dist-packages/async_interrupt/__init__.py", line 90, in __aexit__ 313s raise self._exception from exc_val 313s habluetooth.scanner._AbortStartError 313s DEBUG habluetooth.scanner:scanner.py:571 hci0 (AA:BB:CC:DD:EE:FF): Scanner is already stopped 313s PASSED [ 54%] 313s tests/test_scanner.py::test_dbus_broken_pipe_in_container 313s -------------------------------- live log setup -------------------------------- 313s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 313s -------------------------------- live log call --------------------------------- 313s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 313s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (1/4) 313s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 313s DEBUG habluetooth.scanner:scanner.py:480 hci0 (AA:BB:CC:DD:EE:FF): DBus connection broken: 313s Traceback (most recent call last): 313s File "/usr/lib/python3/dist-packages/habluetooth/scanner.py", line 344, in _async_start_attempt 313s await self.scanner.start() 313s File "/usr/lib/python3.13/unittest/mock.py", line 2321, in _execute_mock_call 313s raise effect 313s BrokenPipeError 313s PASSED [ 55%] 313s tests/test_scanner.py::test_dbus_broken_pipe 313s -------------------------------- live log setup -------------------------------- 313s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 313s -------------------------------- live log call --------------------------------- 313s ERROR asyncio:base_events.py:1879 Future exception was never retrieved 313s future: 313s habluetooth.scanner._AbortStartError 313s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 313s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (1/4) 313s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 313s DEBUG habluetooth.scanner:scanner.py:480 hci0 (AA:BB:CC:DD:EE:FF): DBus connection broken: 313s Traceback (most recent call last): 313s File "/usr/lib/python3/dist-packages/habluetooth/scanner.py", line 344, in _async_start_attempt 313s await self.scanner.start() 313s File "/usr/lib/python3.13/unittest/mock.py", line 2321, in _execute_mock_call 313s raise effect 313s BrokenPipeError 313s PASSED [ 56%] 313s tests/test_scanner.py::test_invalid_dbus_message 313s -------------------------------- live log setup -------------------------------- 313s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 313s -------------------------------- live log call --------------------------------- 313s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 313s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (1/4) 313s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 313s DEBUG habluetooth.scanner:scanner.py:495 hci0 (AA:BB:CC:DD:EE:FF): Invalid DBus message received: 313s Traceback (most recent call last): 313s File "/usr/lib/python3/dist-packages/habluetooth/scanner.py", line 344, in _async_start_attempt 313s await self.scanner.start() 313s File "/usr/lib/python3.13/unittest/mock.py", line 2321, in _execute_mock_call 313s raise effect 313s dbus_fast.errors.InvalidMessageError 313s PASSED [ 57%] 313s tests/test_scanner.py::test_adapter_needs_reset_at_start[org.bluez.Error.Failed] 313s -------------------------------- live log setup -------------------------------- 313s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 313s -------------------------------- live log call --------------------------------- 313s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 313s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (1/4) 313s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 313s DEBUG habluetooth.scanner:scanner.py:434 hci0 (AA:BB:CC:DD:EE:FF): BleakError while starting bluetooth; attempt: (1/4): org.bluez.Error.Failed 313s Traceback (most recent call last): 313s File "/usr/lib/python3/dist-packages/habluetooth/scanner.py", line 344, in _async_start_attempt 313s await self.scanner.start() 313s File "/tmp/autopkgtest.ZVzfcl/autopkgtest_tmp/build/tests/test_scanner.py", line 249, in start 313s raise BleakError(error) 313s bleak.exc.BleakError: org.bluez.Error.Failed 313s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 313s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (2/4) 313s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 313s DEBUG habluetooth.scanner:scanner.py:555 hci0 (AA:BB:CC:DD:EE:FF): adapter stopped responding; executing reset 313s DEBUG habluetooth.scanner:scanner.py:557 hci0 (AA:BB:CC:DD:EE:FF): adapter reset result: True 313s DEBUG habluetooth.scanner:scanner.py:434 hci0 (AA:BB:CC:DD:EE:FF): BleakError while starting bluetooth; attempt: (2/4): org.bluez.Error.Failed 313s Traceback (most recent call last): 313s File "/usr/lib/python3/dist-packages/habluetooth/scanner.py", line 344, in _async_start_attempt 313s await self.scanner.start() 313s File "/tmp/autopkgtest.ZVzfcl/autopkgtest_tmp/build/tests/test_scanner.py", line 249, in start 313s raise BleakError(error) 313s bleak.exc.BleakError: org.bluez.Error.Failed 313s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 313s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (3/4) 313s DEBUG habluetooth.scanner:scanner.py:418 hci0 (AA:BB:CC:DD:EE:FF): Success while starting bluetooth; attempt: (3/4) 313s DEBUG bleak.backends.bluezdbus.manager:manager.py:938 received D-Bus signal: org.freedesktop.DBus.NameAcquired (/org/freedesktop/DBus): [':1.20'] 321s DEBUG bleak_retry_connector.bleak_manager:bleak_manager.py:56 Timed out trying to connect to DBus; will not try again until next restart 321s DEBUG bleak_retry_connector:__init__.py:639 Failed to restore discoveries for hci0 321s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 321s PASSED [ 58%] 321s tests/test_scanner.py::test_adapter_needs_reset_at_start[org.bluez.Error.InProgress] 321s -------------------------------- live log setup -------------------------------- 321s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 321s -------------------------------- live log call --------------------------------- 321s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 321s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (1/4) 321s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 321s DEBUG habluetooth.scanner:scanner.py:586 hci0 (AA:BB:CC:DD:EE:FF): Force stopping bluetooth discovery 321s ERROR bleak_retry_connector.bluez:bluez.py:324 Failed to stop discovery for hci0 because no manager 321s DEBUG habluetooth.scanner:scanner.py:434 hci0 (AA:BB:CC:DD:EE:FF): BleakError while starting bluetooth; attempt: (1/4): org.bluez.Error.InProgress 321s Traceback (most recent call last): 321s File "/usr/lib/python3/dist-packages/habluetooth/scanner.py", line 344, in _async_start_attempt 321s await self.scanner.start() 321s File "/tmp/autopkgtest.ZVzfcl/autopkgtest_tmp/build/tests/test_scanner.py", line 249, in start 321s raise BleakError(error) 321s bleak.exc.BleakError: org.bluez.Error.InProgress 321s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 321s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (2/4) 321s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 321s DEBUG habluetooth.scanner:scanner.py:586 hci0 (AA:BB:CC:DD:EE:FF): Force stopping bluetooth discovery 321s ERROR bleak_retry_connector.bluez:bluez.py:324 Failed to stop discovery for hci0 because no manager 321s DEBUG habluetooth.scanner:scanner.py:555 hci0 (AA:BB:CC:DD:EE:FF): adapter stopped responding; executing reset 321s DEBUG habluetooth.scanner:scanner.py:557 hci0 (AA:BB:CC:DD:EE:FF): adapter reset result: True 321s DEBUG habluetooth.scanner:scanner.py:434 hci0 (AA:BB:CC:DD:EE:FF): BleakError while starting bluetooth; attempt: (2/4): org.bluez.Error.InProgress 321s Traceback (most recent call last): 321s File "/usr/lib/python3/dist-packages/habluetooth/scanner.py", line 344, in _async_start_attempt 321s await self.scanner.start() 321s File "/tmp/autopkgtest.ZVzfcl/autopkgtest_tmp/build/tests/test_scanner.py", line 249, in start 321s raise BleakError(error) 321s bleak.exc.BleakError: org.bluez.Error.InProgress 321s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 321s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (3/4) 321s DEBUG habluetooth.scanner:scanner.py:418 hci0 (AA:BB:CC:DD:EE:FF): Success while starting bluetooth; attempt: (3/4) 321s DEBUG bleak_retry_connector:__init__.py:639 Failed to restore discoveries for hci0 321s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 321s PASSED [ 60%] 321s tests/test_scanner.py::test_adapter_needs_reset_at_start[org.bluez.Error.NotReady] 321s -------------------------------- live log setup -------------------------------- 321s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 321s -------------------------------- live log call --------------------------------- 321s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 321s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (1/4) 321s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 321s DEBUG habluetooth.scanner:scanner.py:434 hci0 (AA:BB:CC:DD:EE:FF): BleakError while starting bluetooth; attempt: (1/4): org.bluez.Error.NotReady 321s Traceback (most recent call last): 321s File "/usr/lib/python3/dist-packages/habluetooth/scanner.py", line 344, in _async_start_attempt 321s await self.scanner.start() 321s File "/tmp/autopkgtest.ZVzfcl/autopkgtest_tmp/build/tests/test_scanner.py", line 249, in start 321s raise BleakError(error) 321s bleak.exc.BleakError: org.bluez.Error.NotReady 321s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 321s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (2/4) 321s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 321s DEBUG habluetooth.scanner:scanner.py:555 hci0 (AA:BB:CC:DD:EE:FF): adapter stopped responding; executing reset 321s DEBUG habluetooth.scanner:scanner.py:557 hci0 (AA:BB:CC:DD:EE:FF): adapter reset result: True 321s DEBUG habluetooth.scanner:scanner.py:434 hci0 (AA:BB:CC:DD:EE:FF): BleakError while starting bluetooth; attempt: (2/4): org.bluez.Error.NotReady 321s Traceback (most recent call last): 321s File "/usr/lib/python3/dist-packages/habluetooth/scanner.py", line 344, in _async_start_attempt 321s await self.scanner.start() 321s File "/tmp/autopkgtest.ZVzfcl/autopkgtest_tmp/build/tests/test_scanner.py", line 249, in start 321s raise BleakError(error) 321s bleak.exc.BleakError: org.bluez.Error.NotReady 321s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 321s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (3/4) 321s DEBUG habluetooth.scanner:scanner.py:418 hci0 (AA:BB:CC:DD:EE:FF): Success while starting bluetooth; attempt: (3/4) 321s DEBUG bleak_retry_connector:__init__.py:639 Failed to restore discoveries for hci0 321s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 321s PASSED [ 61%] 321s tests/test_scanner.py::test_adapter_needs_reset_at_start[not found] 321s -------------------------------- live log setup -------------------------------- 321s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 321s -------------------------------- live log call --------------------------------- 321s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 321s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (1/4) 321s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 321s DEBUG habluetooth.scanner:scanner.py:434 hci0 (AA:BB:CC:DD:EE:FF): BleakError while starting bluetooth; attempt: (1/4): not found 321s Traceback (most recent call last): 321s File "/usr/lib/python3/dist-packages/habluetooth/scanner.py", line 344, in _async_start_attempt 321s await self.scanner.start() 321s File "/tmp/autopkgtest.ZVzfcl/autopkgtest_tmp/build/tests/test_scanner.py", line 249, in start 321s raise BleakError(error) 321s bleak.exc.BleakError: not found 321s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 321s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (2/4) 321s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 321s DEBUG habluetooth.scanner:scanner.py:555 hci0 (AA:BB:CC:DD:EE:FF): adapter stopped responding; executing reset 321s DEBUG habluetooth.scanner:scanner.py:557 hci0 (AA:BB:CC:DD:EE:FF): adapter reset result: True 321s DEBUG habluetooth.scanner:scanner.py:434 hci0 (AA:BB:CC:DD:EE:FF): BleakError while starting bluetooth; attempt: (2/4): not found 321s Traceback (most recent call last): 321s File "/usr/lib/python3/dist-packages/habluetooth/scanner.py", line 344, in _async_start_attempt 321s await self.scanner.start() 321s File "/tmp/autopkgtest.ZVzfcl/autopkgtest_tmp/build/tests/test_scanner.py", line 249, in start 321s raise BleakError(error) 321s bleak.exc.BleakError: not found 321s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 321s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (3/4) 321s DEBUG habluetooth.scanner:scanner.py:418 hci0 (AA:BB:CC:DD:EE:FF): Success while starting bluetooth; attempt: (3/4) 321s DEBUG bleak_retry_connector:__init__.py:639 Failed to restore discoveries for hci0 321s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 321s PASSED [ 62%] 321s tests/test_scanner.py::test_recovery_from_dbus_restart 321s -------------------------------- live log setup -------------------------------- 321s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 321s -------------------------------- live log call --------------------------------- 321s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 321s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (1/4) 321s DEBUG habluetooth.scanner:scanner.py:418 hci0 (AA:BB:CC:DD:EE:FF): Success while starting bluetooth; attempt: (1/4) 321s DEBUG bleak_retry_connector:__init__.py:639 Failed to restore discoveries for hci0 321s DEBUG habluetooth.base_scanner:base_scanner.py:210 hci0 (AA:BB:CC:DD:EE:FF): Scanner watchdog time_since_last_detection: 10.016681925 321s DEBUG habluetooth.base_scanner:base_scanner.py:210 hci0 (AA:BB:CC:DD:EE:FF): Scanner watchdog time_since_last_detection: 20.0 321s DEBUG habluetooth.base_scanner:base_scanner.py:210 hci0 (AA:BB:CC:DD:EE:FF): Scanner watchdog time_since_last_detection: 110.0 321s DEBUG habluetooth.scanner:scanner.py:517 hci0 (AA:BB:CC:DD:EE:FF): Bluetooth scanner has gone quiet for 110.0s, restarting 321s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 321s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 321s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (1/4) 321s DEBUG habluetooth.scanner:scanner.py:418 hci0 (AA:BB:CC:DD:EE:FF): Success while starting bluetooth; attempt: (1/4) 321s DEBUG bleak_retry_connector:__init__.py:639 Failed to restore discoveries for hci0 321s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 321s PASSED [ 63%] 321s tests/test_scanner.py::test_adapter_recovery 321s -------------------------------- live log setup -------------------------------- 321s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 321s -------------------------------- live log call --------------------------------- 321s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 321s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (1/4) 321s DEBUG habluetooth.scanner:scanner.py:418 hci0 (AA:BB:CC:DD:EE:FF): Success while starting bluetooth; attempt: (1/4) 321s DEBUG bleak_retry_connector:__init__.py:639 Failed to restore discoveries for hci0 321s DEBUG habluetooth.base_scanner:base_scanner.py:210 hci0 (AA:BB:CC:DD:EE:FF): Scanner watchdog time_since_last_detection: 10.0 321s DEBUG habluetooth.base_scanner:base_scanner.py:210 hci0 (AA:BB:CC:DD:EE:FF): Scanner watchdog time_since_last_detection: 20.0 321s DEBUG habluetooth.base_scanner:base_scanner.py:210 hci0 (AA:BB:CC:DD:EE:FF): Scanner watchdog time_since_last_detection: 120.0 321s DEBUG habluetooth.scanner:scanner.py:517 hci0 (AA:BB:CC:DD:EE:FF): Bluetooth scanner has gone quiet for 120.0s, restarting 321s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 322s DEBUG habluetooth.scanner:scanner.py:555 hci0 (AA:BB:CC:DD:EE:FF): adapter stopped responding; executing reset 322s DEBUG habluetooth.scanner:scanner.py:557 hci0 (AA:BB:CC:DD:EE:FF): adapter reset result: True 322s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 322s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (1/4) 322s DEBUG habluetooth.scanner:scanner.py:418 hci0 (AA:BB:CC:DD:EE:FF): Success while starting bluetooth; attempt: (1/4) 322s DEBUG bleak_retry_connector:__init__.py:639 Failed to restore discoveries for hci0 322s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 322s PASSED [ 64%] 322s tests/test_scanner.py::test_adapter_scanner_fails_to_start_first_time 322s -------------------------------- live log setup -------------------------------- 322s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 322s -------------------------------- live log call --------------------------------- 322s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 322s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (1/4) 322s DEBUG habluetooth.scanner:scanner.py:418 hci0 (AA:BB:CC:DD:EE:FF): Success while starting bluetooth; attempt: (1/4) 322s DEBUG bleak_retry_connector:__init__.py:639 Failed to restore discoveries for hci0 322s DEBUG habluetooth.base_scanner:base_scanner.py:210 hci0 (AA:BB:CC:DD:EE:FF): Scanner watchdog time_since_last_detection: 10.0 322s DEBUG habluetooth.base_scanner:base_scanner.py:210 hci0 (AA:BB:CC:DD:EE:FF): Scanner watchdog time_since_last_detection: 20.0 322s DEBUG habluetooth.base_scanner:base_scanner.py:210 hci0 (AA:BB:CC:DD:EE:FF): Scanner watchdog time_since_last_detection: 120.00000000000003 322s DEBUG habluetooth.scanner:scanner.py:517 hci0 (AA:BB:CC:DD:EE:FF): Bluetooth scanner has gone quiet for 120.00000000000003s, restarting 322s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 322s DEBUG habluetooth.scanner:scanner.py:555 hci0 (AA:BB:CC:DD:EE:FF): adapter stopped responding; executing reset 322s DEBUG habluetooth.scanner:scanner.py:557 hci0 (AA:BB:CC:DD:EE:FF): adapter reset result: True 322s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 322s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (1/4) 322s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 322s DEBUG habluetooth.scanner:scanner.py:434 hci0 (AA:BB:CC:DD:EE:FF): BleakError while starting bluetooth; attempt: (1/4): Failed to start 322s Traceback (most recent call last): 322s File "/usr/lib/python3/dist-packages/habluetooth/scanner.py", line 344, in _async_start_attempt 322s await self.scanner.start() 322s File "/tmp/autopkgtest.ZVzfcl/autopkgtest_tmp/build/tests/test_scanner.py", line 485, in start 322s raise BleakError("Failed to start") 322s bleak.exc.BleakError: Failed to start 322s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 322s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (2/4) 322s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 322s DEBUG habluetooth.scanner:scanner.py:434 hci0 (AA:BB:CC:DD:EE:FF): BleakError while starting bluetooth; attempt: (2/4): Failed to start 322s Traceback (most recent call last): 322s File "/usr/lib/python3/dist-packages/habluetooth/scanner.py", line 344, in _async_start_attempt 322s await self.scanner.start() 322s File "/tmp/autopkgtest.ZVzfcl/autopkgtest_tmp/build/tests/test_scanner.py", line 485, in start 322s raise BleakError("Failed to start") 322s bleak.exc.BleakError: Failed to start 322s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 322s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (3/4) 322s DEBUG habluetooth.scanner:scanner.py:418 hci0 (AA:BB:CC:DD:EE:FF): Success while starting bluetooth; attempt: (3/4) 322s DEBUG bleak_retry_connector:__init__.py:639 Failed to restore discoveries for hci0 322s DEBUG habluetooth.base_scanner:base_scanner.py:210 hci0 (AA:BB:CC:DD:EE:FF): Scanner watchdog time_since_last_detection: 90.00308335799997 322s DEBUG habluetooth.scanner:scanner.py:517 hci0 (AA:BB:CC:DD:EE:FF): Bluetooth scanner has gone quiet for 90.00308335799997s, restarting 322s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 322s DEBUG habluetooth.scanner:scanner.py:555 hci0 (AA:BB:CC:DD:EE:FF): adapter stopped responding; executing reset 322s DEBUG habluetooth.scanner:scanner.py:557 hci0 (AA:BB:CC:DD:EE:FF): adapter reset result: True 322s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 322s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (1/4) 322s DEBUG habluetooth.scanner:scanner.py:418 hci0 (AA:BB:CC:DD:EE:FF): Success while starting bluetooth; attempt: (1/4) 322s DEBUG bleak_retry_connector:__init__.py:639 Failed to restore discoveries for hci0 322s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 322s PASSED [ 65%] 322s tests/test_scanner.py::test_adapter_fails_to_start_and_takes_a_bit_to_init 322s -------------------------------- live log setup -------------------------------- 322s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 322s -------------------------------- live log call --------------------------------- 322s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 322s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (1/4) 322s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 322s DEBUG habluetooth.scanner:scanner.py:402 hci0 (AA:BB:CC:DD:EE:FF): Waiting for adapter to initialize; attempt (1/4) 322s 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 322s Traceback (most recent call last): 322s File "/usr/lib/python3/dist-packages/habluetooth/scanner.py", line 344, in _async_start_attempt 322s await self.scanner.start() 322s File "/tmp/autopkgtest.ZVzfcl/autopkgtest_tmp/build/tests/test_scanner.py", line 599, in start 322s raise BleakError("org.freedesktop.DBus.Error.UnknownObject") 322s bleak.exc.BleakError: org.freedesktop.DBus.Error.UnknownObject 322s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 322s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (2/4) 322s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 322s DEBUG habluetooth.scanner:scanner.py:586 hci0 (AA:BB:CC:DD:EE:FF): Force stopping bluetooth discovery 322s ERROR bleak_retry_connector.bluez:bluez.py:324 Failed to stop discovery for hci0 because no manager 322s DEBUG habluetooth.scanner:scanner.py:555 hci0 (AA:BB:CC:DD:EE:FF): adapter stopped responding; executing reset 322s DEBUG habluetooth.scanner:scanner.py:557 hci0 (AA:BB:CC:DD:EE:FF): adapter reset result: True 322s DEBUG habluetooth.scanner:scanner.py:434 hci0 (AA:BB:CC:DD:EE:FF): BleakError while starting bluetooth; attempt: (2/4): org.bluez.Error.InProgress 322s Traceback (most recent call last): 322s File "/usr/lib/python3/dist-packages/habluetooth/scanner.py", line 344, in _async_start_attempt 322s await self.scanner.start() 322s File "/tmp/autopkgtest.ZVzfcl/autopkgtest_tmp/build/tests/test_scanner.py", line 601, in start 322s raise BleakError("org.bluez.Error.InProgress") 322s bleak.exc.BleakError: org.bluez.Error.InProgress 322s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 322s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (3/4) 322s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 322s DEBUG habluetooth.scanner:scanner.py:586 hci0 (AA:BB:CC:DD:EE:FF): Force stopping bluetooth discovery 322s ERROR bleak_retry_connector.bluez:bluez.py:324 Failed to stop discovery for hci0 because no manager 322s DEBUG habluetooth.scanner:scanner.py:434 hci0 (AA:BB:CC:DD:EE:FF): BleakError while starting bluetooth; attempt: (3/4): org.bluez.Error.InProgress 322s Traceback (most recent call last): 322s File "/usr/lib/python3/dist-packages/habluetooth/scanner.py", line 344, in _async_start_attempt 322s await self.scanner.start() 322s File "/tmp/autopkgtest.ZVzfcl/autopkgtest_tmp/build/tests/test_scanner.py", line 603, in start 322s raise BleakError("org.bluez.Error.InProgress") 322s bleak.exc.BleakError: org.bluez.Error.InProgress 322s 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) 322s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'passive', 'adapter': 'hci0', 'bluez': } 322s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (4/4) 322s 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) 322s DEBUG habluetooth.scanner:scanner.py:418 hci0 (AA:BB:CC:DD:EE:FF): Success while starting bluetooth; attempt: (4/4) 322s DEBUG bleak_retry_connector:__init__.py:639 Failed to restore discoveries for hci0 322s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 322s PASSED [ 66%] 322s tests/test_scanner.py::test_restart_takes_longer_than_watchdog_time 322s -------------------------------- live log setup -------------------------------- 322s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 322s -------------------------------- live log call --------------------------------- 322s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 322s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (1/4) 322s DEBUG habluetooth.scanner:scanner.py:418 hci0 (AA:BB:CC:DD:EE:FF): Success while starting bluetooth; attempt: (1/4) 322s DEBUG bleak_retry_connector:__init__.py:639 Failed to restore discoveries for hci0 322s DEBUG habluetooth.base_scanner:base_scanner.py:210 hci0 (AA:BB:CC:DD:EE:FF): Scanner watchdog time_since_last_detection: 120.0 322s DEBUG habluetooth.scanner:scanner.py:517 hci0 (AA:BB:CC:DD:EE:FF): Bluetooth scanner has gone quiet for 120.0s, restarting 322s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 322s DEBUG habluetooth.scanner:scanner.py:555 hci0 (AA:BB:CC:DD:EE:FF): adapter stopped responding; executing reset 322s DEBUG habluetooth.scanner:scanner.py:557 hci0 (AA:BB:CC:DD:EE:FF): adapter reset result: True 322s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 322s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (1/4) 322s DEBUG habluetooth.base_scanner:base_scanner.py:210 hci0 (AA:BB:CC:DD:EE:FF): Scanner watchdog time_since_last_detection: 120.0 322s DEBUG habluetooth.scanner:scanner.py:512 hci0 (AA:BB:CC:DD:EE:FF): Scanner is already restarting, deferring restart 322s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 322s DEBUG habluetooth.scanner:scanner.py:426 hci0 (AA:BB:CC:DD:EE:FF): TimeoutError while starting bluetooth; attempt: (1/4) 322s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 322s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (2/4) 322s DEBUG habluetooth.scanner:scanner.py:418 hci0 (AA:BB:CC:DD:EE:FF): Success while starting bluetooth; attempt: (2/4) 322s DEBUG bleak_retry_connector:__init__.py:639 Failed to restore discoveries for hci0 322s ERROR asyncio:base_events.py:1879 Future exception was never retrieved 322s future: 322s habluetooth.scanner._AbortStartError 322s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 322s PASSED [ 67%] 322s tests/test_scanner.py::test_setup_and_stop_macos SKIPPED (condition:...) [ 68%] 322s tests/test_scanner.py::test_adapter_init_fails_fallback_to_passive 322s -------------------------------- live log setup -------------------------------- 322s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 322s -------------------------------- live log call --------------------------------- 322s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 322s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (1/4) 322s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 322s DEBUG habluetooth.scanner:scanner.py:402 hci0 (AA:BB:CC:DD:EE:FF): Waiting for adapter to initialize; attempt (1/4) 322s 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 322s Traceback (most recent call last): 322s File "/usr/lib/python3/dist-packages/habluetooth/scanner.py", line 344, in _async_start_attempt 322s await self.scanner.start() 322s File "/tmp/autopkgtest.ZVzfcl/autopkgtest_tmp/build/tests/test_scanner.py", line 780, in start 322s raise BleakError("org.freedesktop.DBus.Error.UnknownObject") 322s bleak.exc.BleakError: org.freedesktop.DBus.Error.UnknownObject 322s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 322s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (2/4) 322s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 322s DEBUG habluetooth.scanner:scanner.py:586 hci0 (AA:BB:CC:DD:EE:FF): Force stopping bluetooth discovery 322s ERROR bleak_retry_connector.bluez:bluez.py:324 Failed to stop discovery for hci0 because no manager 322s DEBUG habluetooth.scanner:scanner.py:555 hci0 (AA:BB:CC:DD:EE:FF): adapter stopped responding; executing reset 322s DEBUG habluetooth.scanner:scanner.py:557 hci0 (AA:BB:CC:DD:EE:FF): adapter reset result: True 322s DEBUG habluetooth.scanner:scanner.py:434 hci0 (AA:BB:CC:DD:EE:FF): BleakError while starting bluetooth; attempt: (2/4): org.bluez.Error.InProgress 322s Traceback (most recent call last): 322s File "/usr/lib/python3/dist-packages/habluetooth/scanner.py", line 344, in _async_start_attempt 322s await self.scanner.start() 322s File "/tmp/autopkgtest.ZVzfcl/autopkgtest_tmp/build/tests/test_scanner.py", line 782, in start 322s raise BleakError("org.bluez.Error.InProgress") 322s bleak.exc.BleakError: org.bluez.Error.InProgress 322s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 322s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (3/4) 322s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 322s DEBUG habluetooth.scanner:scanner.py:586 hci0 (AA:BB:CC:DD:EE:FF): Force stopping bluetooth discovery 322s ERROR bleak_retry_connector.bluez:bluez.py:324 Failed to stop discovery for hci0 because no manager 322s DEBUG habluetooth.scanner:scanner.py:434 hci0 (AA:BB:CC:DD:EE:FF): BleakError while starting bluetooth; attempt: (3/4): org.bluez.Error.InProgress 322s Traceback (most recent call last): 322s File "/usr/lib/python3/dist-packages/habluetooth/scanner.py", line 344, in _async_start_attempt 322s await self.scanner.start() 322s File "/tmp/autopkgtest.ZVzfcl/autopkgtest_tmp/build/tests/test_scanner.py", line 784, in start 322s raise BleakError("org.bluez.Error.InProgress") 322s bleak.exc.BleakError: org.bluez.Error.InProgress 322s 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) 322s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'passive', 'adapter': 'hci0', 'bluez': } 322s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (4/4) 322s 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) 322s DEBUG habluetooth.scanner:scanner.py:418 hci0 (AA:BB:CC:DD:EE:FF): Success while starting bluetooth; attempt: (4/4) 322s DEBUG bleak_retry_connector:__init__.py:639 Failed to restore discoveries for hci0 322s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 322s PASSED [ 70%] 322s tests/test_storage.py::test_discovered_device_advertisement_data_to_dict PASSED [ 71%] 322s tests/test_storage.py::test_discovered_device_advertisement_data_from_dict PASSED [ 72%] 322s tests/test_storage.py::test_expire_stale_scanner_discovered_device_advertisement_data 322s -------------------------------- live log call --------------------------------- 322s DEBUG habluetooth.storage:storage.py:122 Loaded 1 fresh discovered devices for myscanner 322s DEBUG habluetooth.storage:storage.py:122 Loaded 0 fresh discovered devices for all_expired 322s PASSED [ 73%] 322s tests/test_storage.py::test_expire_future_discovered_device_advertisement_data 322s -------------------------------- live log call --------------------------------- 322s DEBUG habluetooth.storage:storage.py:122 Loaded 1 fresh discovered devices for myscanner 322s WARNING habluetooth.storage:storage.py:107 Discarding timestamp 1765971590.7536242 for CC:DD:EE:FF:AA:BB on scanner all_future as it is the future (now = 1764971590.7536297) 322s DEBUG habluetooth.storage:storage.py:122 Loaded 0 fresh discovered devices for all_future 322s PASSED [ 74%] 322s tests/test_storage.py::test_discovered_device_advertisement_data_from_dict_corrupt 322s -------------------------------- live log call --------------------------------- 322s 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' 322s Traceback (most recent call last): 322s File "/usr/lib/python3/dist-packages/habluetooth/storage.py", line 138, in discovered_device_advertisement_data_from_dict 322s _deserialize_discovered_device_advertisement_datas( 322s ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~^ 322s data[DISCOVERED_DEVICE_ADVERTISEMENT_DATAS] 322s ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 322s ), 322s ^ 322s File "/usr/lib/python3/dist-packages/habluetooth/storage.py", line 198, in _deserialize_discovered_device_advertisement_datas 322s _ble_device_from_dict(device_advertisement_data["device"]), 322s ~~~~~~~~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 322s File "/usr/lib/python3/dist-packages/habluetooth/storage.py", line 215, in _ble_device_from_dict 322s return BLEDevice(**device_data) 322s TypeError: BLEDevice.__init__() missing 1 required positional argument: 'name' 322s PASSED [ 75%] 322s tests/test_storage.py::test_backward_compatibility_rssi_in_device_dict PASSED [ 76%] 322s tests/test_wrappers.py::test_test_switch_adapters_when_out_of_slots 322s -------------------------------- live log setup -------------------------------- 322s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 322s -------------------------------- live log call --------------------------------- 322s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (00:00:00:00:00:01) 322s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci1 (00:00:00:00:00:02) 322s DEBUG habluetooth.wrappers:wrappers.py:279 00:00:00:00:00:01: Looking for backend to connect 322s 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) 322s 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) 322s 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) 322s DEBUG habluetooth.wrappers:wrappers.py:279 00:00:00:00:00:02: Looking for backend to connect 322s 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) 322s DEBUG habluetooth.wrappers:wrappers.py:279 00:00:00:00:00:03: Looking for backend to connect 322s 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) 322s 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) 322s 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) 322s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (00:00:00:00:00:01) 322s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci1 (00:00:00:00:00:02) 322s PASSED [ 77%] 322s tests/test_wrappers.py::test_release_slot_on_connect_failure 322s -------------------------------- live log setup -------------------------------- 322s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 322s -------------------------------- live log call --------------------------------- 322s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (00:00:00:00:00:01) 322s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci1 (00:00:00:00:00:02) 322s DEBUG habluetooth.wrappers:wrappers.py:279 00:00:00:00:00:01: Looking for backend to connect 322s 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) 322s 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) 322s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (00:00:00:00:00:01) 322s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci1 (00:00:00:00:00:02) 322s PASSED [ 78%] 322s tests/test_wrappers.py::test_release_slot_on_connect_exception 322s -------------------------------- live log setup -------------------------------- 322s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 322s -------------------------------- live log call --------------------------------- 322s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (00:00:00:00:00:01) 322s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci1 (00:00:00:00:00:02) 322s DEBUG habluetooth.wrappers:wrappers.py:279 00:00:00:00:00:01: Looking for backend to connect 322s 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) 322s 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) 322s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (00:00:00:00:00:01) 322s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci1 (00:00:00:00:00:02) 322s PASSED [ 80%] 322s tests/test_wrappers.py::test_switch_adapters_on_failure 322s -------------------------------- live log setup -------------------------------- 322s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 322s -------------------------------- live log call --------------------------------- 322s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (00:00:00:00:00:01) 322s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci1 (00:00:00:00:00:02) 322s DEBUG habluetooth.wrappers:wrappers.py:279 00:00:00:00:00:01: Looking for backend to connect 322s 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) 322s 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) 322s DEBUG habluetooth.wrappers:wrappers.py:279 00:00:00:00:00:01: Looking for backend to connect 322s 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) 322s 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) 322s DEBUG habluetooth.wrappers:wrappers.py:279 00:00:00:00:00:01: Looking for backend to connect 322s 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) 322s 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) 322s 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) 322s DEBUG habluetooth.wrappers:wrappers.py:279 00:00:00:00:00:01: Looking for backend to connect 322s 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) 322s 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) 322s 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) 322s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (00:00:00:00:00:01) 322s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci1 (00:00:00:00:00:02) 322s PASSED [ 81%] 322s tests/test_wrappers.py::test_switch_adapters_on_connecting 322s -------------------------------- live log setup -------------------------------- 322s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 322s -------------------------------- live log call --------------------------------- 322s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (00:00:00:00:00:01) 322s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci1 (00:00:00:00:00:02) 322s DEBUG habluetooth.wrappers:wrappers.py:279 00:00:00:00:00:01: Looking for backend to connect 322s 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) 322s 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) 322s DEBUG habluetooth.wrappers:wrappers.py:279 00:00:00:00:00:01: Looking for backend to connect 322s 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) 322s 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) 322s 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) 322s 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) 322s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (00:00:00:00:00:01) 322s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci1 (00:00:00:00:00:02) 322s PASSED [ 82%] 322s tests/test_wrappers.py::test_single_adapter_connection_history 322s -------------------------------- live log setup -------------------------------- 322s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 322s -------------------------------- live log call --------------------------------- 322s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (AA:BB:CC:DD:EE:00) 322s DEBUG habluetooth.wrappers:wrappers.py:279 00:00:00:00:00:11: Looking for backend to connect 322s 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) 322s 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) 322s 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) 322s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (AA:BB:CC:DD:EE:00) 322s PASSED [ 83%] 322s tests/test_wrappers.py::test_passing_subclassed_str_as_address 322s -------------------------------- live log setup -------------------------------- 322s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 322s -------------------------------- live log call --------------------------------- 322s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (00:00:00:00:00:01) 322s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci1 (00:00:00:00:00:02) 322s DEBUG habluetooth.wrappers:wrappers.py:279 00:00:00:00:00:01: Looking for backend to connect 322s 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) 322s 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) 322s 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) 322s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (00:00:00:00:00:01) 322s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci1 (00:00:00:00:00:02) 322s PASSED [ 84%] 322s tests/test_wrappers.py::test_find_device_by_address 322s -------------------------------- live log setup -------------------------------- 322s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 322s -------------------------------- live log call --------------------------------- 322s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (00:00:00:00:00:01) 322s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci1 (00:00:00:00:00:02) 322s PASSED [ 85%] 322s tests/test_wrappers.py::test_discover 322s -------------------------------- live log setup -------------------------------- 322s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 322s -------------------------------- live log call --------------------------------- 322s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (00:00:00:00:00:01) 322s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci1 (00:00:00:00:00:02) 322s PASSED [ 86%] 322s tests/test_wrappers.py::test_raise_after_shutdown 322s -------------------------------- live log setup -------------------------------- 322s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 322s -------------------------------- live log call --------------------------------- 322s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (00:00:00:00:00:01) 322s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci1 (00:00:00:00:00:02) 322s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (00:00:00:00:00:01) 322s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci1 (00:00:00:00:00:02) 322s PASSED [ 87%] 322s tests/test_wrappers.py::test_wrapped_instance_with_filter 322s -------------------------------- live log setup -------------------------------- 322s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 322s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (AA:BB:CC:DD:EE:00) 322s PASSED [ 88%] 322s ------------------------------ live log teardown ------------------------------- 322s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (AA:BB:CC:DD:EE:00) 322s 322s tests/test_wrappers.py::test_wrapped_instance_with_service_uuids 322s -------------------------------- live log setup -------------------------------- 322s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 322s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (AA:BB:CC:DD:EE:00) 322s PASSED [ 90%] 322s ------------------------------ live log teardown ------------------------------- 322s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (AA:BB:CC:DD:EE:00) 322s 322s tests/test_wrappers.py::test_wrapped_instance_with_service_uuids_with_coro_callback 322s -------------------------------- live log setup -------------------------------- 322s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 322s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (AA:BB:CC:DD:EE:00) 322s PASSED [ 91%] 322s ------------------------------ live log teardown ------------------------------- 322s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (AA:BB:CC:DD:EE:00) 322s 322s tests/test_wrappers.py::test_wrapped_instance_with_broken_callbacks 322s -------------------------------- live log setup -------------------------------- 322s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 322s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (AA:BB:CC:DD:EE:00) 322s PASSED [ 92%] 322s ------------------------------ live log teardown ------------------------------- 322s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (AA:BB:CC:DD:EE:00) 322s 322s tests/test_wrappers.py::test_wrapped_instance_changes_uuids 322s -------------------------------- live log setup -------------------------------- 322s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 322s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (AA:BB:CC:DD:EE:00) 322s PASSED [ 93%] 322s ------------------------------ live log teardown ------------------------------- 322s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (AA:BB:CC:DD:EE:00) 322s 322s tests/test_wrappers.py::test_wrapped_instance_changes_filters 322s -------------------------------- live log setup -------------------------------- 322s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 322s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (AA:BB:CC:DD:EE:00) 322s PASSED [ 94%] 322s ------------------------------ live log teardown ------------------------------- 322s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (AA:BB:CC:DD:EE:00) 322s 322s tests/test_wrappers.py::test_wrapped_instance_unsupported_filter 322s -------------------------------- live log setup -------------------------------- 322s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 322s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (AA:BB:CC:DD:EE:00) 322s -------------------------------- live log call --------------------------------- 322s WARNING habluetooth.wrappers:wrappers.py:120 Only UUIDs filters are supported 322s PASSED [ 95%] 322s ------------------------------ live log teardown ------------------------------- 322s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (AA:BB:CC:DD:EE:00) 322s 322s tests/test_wrappers.py::test_client_with_services_parameter 322s -------------------------------- live log setup -------------------------------- 322s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 322s -------------------------------- live log call --------------------------------- 322s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (00:00:00:00:00:01) 322s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci1 (00:00:00:00:00:02) 322s DEBUG habluetooth.wrappers:wrappers.py:279 00:00:00:00:00:01: Looking for backend to connect 322s 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) 322s 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) 322s 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) 322s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (00:00:00:00:00:01) 322s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci1 (00:00:00:00:00:02) 322s PASSED [ 96%] 322s tests/test_wrappers.py::test_client_with_pair_parameter 322s -------------------------------- live log setup -------------------------------- 322s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 322s -------------------------------- live log call --------------------------------- 322s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (00:00:00:00:00:01) 322s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci1 (00:00:00:00:00:02) 322s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (00:00:00:00:00:01) 322s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci1 (00:00:00:00:00:02) 322s PASSED [ 97%] 322s tests/test_wrappers.py::test_client_services_normalization 322s -------------------------------- live log setup -------------------------------- 322s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 322s -------------------------------- live log call --------------------------------- 322s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (00:00:00:00:00:01) 322s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci1 (00:00:00:00:00:02) 322s DEBUG habluetooth.wrappers:wrappers.py:279 00:00:00:00:00:01: Looking for backend to connect 322s 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) 322s 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) 322s 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) 322s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (00:00:00:00:00:01) 322s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci1 (00:00:00:00:00:02) 322s PASSED [ 98%] 322s tests/test_wrappers.py::test_client_with_none_services 322s -------------------------------- live log setup -------------------------------- 322s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 322s -------------------------------- live log call --------------------------------- 322s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (00:00:00:00:00:01) 322s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci1 (00:00:00:00:00:02) 322s DEBUG habluetooth.wrappers:wrappers.py:279 00:00:00:00:00:01: Looking for backend to connect 322s 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) 322s 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) 322s 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) 322s DEBUG habluetooth.wrappers:wrappers.py:279 00:00:00:00:00:01: Looking for backend to connect 322s 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) 322s 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) 322s 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) 322s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (00:00:00:00:00:01) 322s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci1 (00:00:00:00:00:02) 322s PASSED [100%] 322s ------------------------------ live log teardown ------------------------------- 322s DEBUG habluetooth.manager:manager.py:322 Stopping bluetooth manager 322s 322s 322s =============================== warnings summary =============================== 322s tests/test_base_scanner.py: 9 warnings 322s tests/test_manager.py: 3 warnings 322s tests/test_wrappers.py: 14 warnings 322s /tmp/autopkgtest.ZVzfcl/autopkgtest_tmp/build/tests/__init__.py:64: DeprecationWarning: Passing additional arguments for BLEDevice is deprecated and has no effect. 322s return BLEDevice(**new) 322s 322s tests/test_base_scanner.py::test_remote_scanner_expires_non_connectable 322s /tmp/autopkgtest.ZVzfcl/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 322s assert len(scanner._discovered_device_timestamps) == 1 322s 322s tests/test_base_scanner.py::test_remote_scanner_expires_non_connectable 322s /tmp/autopkgtest.ZVzfcl/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 322s assert len(scanner._discovered_device_timestamps) == 0 322s 322s tests/test_base_scanner.py::test_remote_scanner_expires_non_connectable 322s /tmp/autopkgtest.ZVzfcl/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 322s assert len(scanner._discovered_device_timestamps) == 0 322s 322s -- Docs: https://docs.pytest.org/en/stable/how-to/capture-warnings.html 322s ================= 89 passed, 1 skipped, 29 warnings in 11.47s ================== 323s autopkgtest [21:51:51]: test pybuild-autopkgtest: -----------------------] 324s pybuild-autopkgtest PASS 324s autopkgtest [21:51:52]: test pybuild-autopkgtest: - - - - - - - - - - results - - - - - - - - - - 324s autopkgtest [21:51:52]: @@@@@@@@@@@@@@@@@@@@ summary 324s pybuild-autopkgtest PASS