Diff of the two buildlogs: -- --- b1/build.log 2024-12-06 20:43:21.957054941 +0000 +++ b2/build.log 2024-12-06 20:47:05.358928189 +0000 @@ -1,6 +1,6 @@ I: pbuilder: network access will be disabled during build -I: Current time: Fri Dec 6 08:40:58 -12 2024 -I: pbuilder-time-stamp: 1733517658 +I: Current time: Sat Dec 7 10:43:31 +14 2024 +I: pbuilder-time-stamp: 1733517811 I: Building the build Environment I: extracting base tarball [/var/cache/pbuilder/unstable-reproducible-base.tgz] I: copying local configuration @@ -25,52 +25,84 @@ dpkg-source: info: unpacking python-bluetooth-adapters_0.20.2-1.debian.tar.xz I: Not using root during the build. I: Installing the build-deps -I: user script /srv/workspace/pbuilder/22542/tmp/hooks/D02_print_environment starting +I: user script /srv/workspace/pbuilder/23158/tmp/hooks/D01_modify_environment starting +debug: Running on virt64b. +I: Changing host+domainname to test build reproducibility +I: Adding a custom variable just for the fun of it... +I: Changing /bin/sh to bash +'/bin/sh' -> '/bin/bash' +lrwxrwxrwx 1 root root 9 Dec 6 20:43 /bin/sh -> /bin/bash +I: Setting pbuilder2's login shell to /bin/bash +I: Setting pbuilder2's GECOS to second user,second room,second work-phone,second home-phone,second other +I: user script /srv/workspace/pbuilder/23158/tmp/hooks/D01_modify_environment finished +I: user script /srv/workspace/pbuilder/23158/tmp/hooks/D02_print_environment starting I: set - BUILDDIR='/build/reproducible-path' - BUILDUSERGECOS='first user,first room,first work-phone,first home-phone,first other' - BUILDUSERNAME='pbuilder1' - BUILD_ARCH='armhf' - DEBIAN_FRONTEND='noninteractive' - DEB_BUILD_OPTIONS='buildinfo=+all reproducible=+all parallel=3 ' - DISTRIBUTION='unstable' - HOME='/root' - HOST_ARCH='armhf' + BASH=/bin/sh + BASHOPTS=checkwinsize:cmdhist:complete_fullquote:extquote:force_fignore:globasciiranges:globskipdots:hostcomplete:interactive_comments:patsub_replacement:progcomp:promptvars:sourcepath + BASH_ALIASES=() + BASH_ARGC=() + BASH_ARGV=() + BASH_CMDS=() + BASH_LINENO=([0]="12" [1]="0") + BASH_LOADABLES_PATH=/usr/local/lib/bash:/usr/lib/bash:/opt/local/lib/bash:/usr/pkg/lib/bash:/opt/pkg/lib/bash:. + BASH_SOURCE=([0]="/tmp/hooks/D02_print_environment" [1]="/tmp/hooks/D02_print_environment") + BASH_VERSINFO=([0]="5" [1]="2" [2]="37" [3]="1" [4]="release" [5]="arm-unknown-linux-gnueabihf") + BASH_VERSION='5.2.37(1)-release' + BUILDDIR=/build/reproducible-path + BUILDUSERGECOS='second user,second room,second work-phone,second home-phone,second other' + BUILDUSERNAME=pbuilder2 + BUILD_ARCH=armhf + DEBIAN_FRONTEND=noninteractive + DEB_BUILD_OPTIONS='buildinfo=+all reproducible=+all parallel=4 ' + DIRSTACK=() + DISTRIBUTION=unstable + EUID=0 + FUNCNAME=([0]="Echo" [1]="main") + GROUPS=() + HOME=/root + HOSTNAME=i-capture-the-hostname + HOSTTYPE=arm + HOST_ARCH=armhf IFS=' ' - INVOCATION_ID='5b68188df3274e7eb49674492096d913' - LANG='C' - LANGUAGE='en_US:en' - LC_ALL='C' - MAIL='/var/mail/root' - OPTIND='1' - PATH='/usr/sbin:/usr/bin:/sbin:/bin:/usr/games' - PBCURRENTCOMMANDLINEOPERATION='build' - PBUILDER_OPERATION='build' - PBUILDER_PKGDATADIR='/usr/share/pbuilder' - PBUILDER_PKGLIBDIR='/usr/lib/pbuilder' - PBUILDER_SYSCONFDIR='/etc' - PPID='22542' - PS1='# ' - PS2='> ' + INVOCATION_ID=11180471ff2a4bcab3a97b7d9f5ac012 + LANG=C + LANGUAGE=it_CH:it + LC_ALL=C + MACHTYPE=arm-unknown-linux-gnueabihf + MAIL=/var/mail/root + OPTERR=1 + OPTIND=1 + OSTYPE=linux-gnueabihf + PATH=/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/i/capture/the/path + PBCURRENTCOMMANDLINEOPERATION=build + PBUILDER_OPERATION=build + PBUILDER_PKGDATADIR=/usr/share/pbuilder + PBUILDER_PKGLIBDIR=/usr/lib/pbuilder + PBUILDER_SYSCONFDIR=/etc + PIPESTATUS=([0]="0") + POSIXLY_CORRECT=y + PPID=23158 PS4='+ ' - PWD='/' - SHELL='/bin/bash' - SHLVL='2' - SUDO_COMMAND='/usr/bin/timeout -k 18.1h 18h /usr/bin/ionice -c 3 /usr/bin/nice /usr/sbin/pbuilder --build --configfile /srv/reproducible-results/rbuild-debian/r-b-build.5BpEZ3cs/pbuilderrc_fpRn --distribution unstable --hookdir /etc/pbuilder/first-build-hooks --debbuildopts -b --basetgz /var/cache/pbuilder/unstable-reproducible-base.tgz --buildresult /srv/reproducible-results/rbuild-debian/r-b-build.5BpEZ3cs/b1 --logfile b1/build.log python-bluetooth-adapters_0.20.2-1.dsc' - SUDO_GID='112' - SUDO_UID='106' - SUDO_USER='jenkins' - TERM='unknown' - TZ='/usr/share/zoneinfo/Etc/GMT+12' - USER='root' - _='/usr/bin/systemd-run' - http_proxy='http://10.0.0.15:3142/' + PWD=/ + SHELL=/bin/bash + SHELLOPTS=braceexpand:errexit:hashall:interactive-comments:posix + SHLVL=3 + SUDO_COMMAND='/usr/bin/timeout -k 24.1h 24h /usr/bin/ionice -c 3 /usr/bin/nice -n 11 /usr/bin/unshare --uts -- /usr/sbin/pbuilder --build --configfile /srv/reproducible-results/rbuild-debian/r-b-build.5BpEZ3cs/pbuilderrc_QwMO --distribution unstable --hookdir /etc/pbuilder/rebuild-hooks --debbuildopts -b --basetgz /var/cache/pbuilder/unstable-reproducible-base.tgz --buildresult /srv/reproducible-results/rbuild-debian/r-b-build.5BpEZ3cs/b2 --logfile b2/build.log python-bluetooth-adapters_0.20.2-1.dsc' + SUDO_GID=113 + SUDO_UID=107 + SUDO_USER=jenkins + TERM=unknown + TZ=/usr/share/zoneinfo/Etc/GMT-14 + UID=0 + USER=root + _='I: set' + http_proxy=http://10.0.0.15:3142/ I: uname -a - Linux virt32b 6.1.0-28-armmp-lpae #1 SMP Debian 6.1.119-1 (2024-11-22) armv7l GNU/Linux + Linux i-capture-the-hostname 6.1.0-28-arm64 #1 SMP Debian 6.1.119-1 (2024-11-22) aarch64 GNU/Linux I: ls -l /bin lrwxrwxrwx 1 root root 7 Nov 22 14:40 /bin -> usr/bin -I: user script /srv/workspace/pbuilder/22542/tmp/hooks/D02_print_environment finished +I: user script /srv/workspace/pbuilder/23158/tmp/hooks/D02_print_environment finished -> Attempting to satisfy build-dependencies -> Creating pbuilder-satisfydepends-dummy package Package: pbuilder-satisfydepends-dummy @@ -246,7 +278,7 @@ Get: 112 http://deb.debian.org/debian unstable/main armhf python3-pytest-timeout all 2.3.1-1 [21.9 kB] Get: 113 http://deb.debian.org/debian unstable/main armhf python3-uart-devices all 0.1.0-2 [6768 B] Get: 114 http://deb.debian.org/debian unstable/main armhf python3-usb-devices all 0.4.5-2 [9692 B] -Fetched 46.4 MB in 1s (43.4 MB/s) +Fetched 46.4 MB in 2s (24.1 MB/s) debconf: delaying package configuration, since apt-utils is not installed Selecting previously unselected package libapparmor1:armhf. (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 ... 19680 files and directories currently installed.) @@ -625,8 +657,8 @@ Setting up tzdata (2024b-4) ... Current default time zone: 'Etc/UTC' -Local time is now: Fri Dec 6 20:41:56 UTC 2024. -Universal Time is now: Fri Dec 6 20:41:56 UTC 2024. +Local time is now: Fri Dec 6 20:45:03 UTC 2024. +Universal Time is now: Fri Dec 6 20:45:03 UTC 2024. Run 'dpkg-reconfigure tzdata' if you wish to change it. Setting up libpython3.13-minimal:armhf (3.13.1-1) ... @@ -756,7 +788,11 @@ Building tag database... -> Finished parsing the build-deps I: Building the package -I: Running cd /build/reproducible-path/python-bluetooth-adapters-0.20.2/ && env PATH="/usr/sbin:/usr/bin:/sbin:/bin:/usr/games" HOME="/nonexistent/first-build" dpkg-buildpackage -us -uc -b && env PATH="/usr/sbin:/usr/bin:/sbin:/bin:/usr/games" HOME="/nonexistent/first-build" dpkg-genchanges -S > ../python-bluetooth-adapters_0.20.2-1_source.changes +I: user script /srv/workspace/pbuilder/23158/tmp/hooks/A99_set_merged_usr starting +Not re-configuring usrmerge for unstable +I: user script /srv/workspace/pbuilder/23158/tmp/hooks/A99_set_merged_usr finished +hostname: Name or service not known +I: Running cd /build/reproducible-path/python-bluetooth-adapters-0.20.2/ && env PATH="/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/i/capture/the/path" HOME="/nonexistent/second-build" dpkg-buildpackage -us -uc -b && env PATH="/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/i/capture/the/path" HOME="/nonexistent/second-build" dpkg-genchanges -S > ../python-bluetooth-adapters_0.20.2-1_source.changes dpkg-buildpackage: info: source package python-bluetooth-adapters dpkg-buildpackage: info: source version 0.20.2-1 dpkg-buildpackage: info: source distribution unstable @@ -799,183 +835,183 @@ cachedir: .pytest_cache rootdir: /build/reproducible-path/python-bluetooth-adapters-0.20.2/.pybuild/cpython3_3.13_bluetooth-adapters/build configfile: pyproject.toml -plugins: timeout-2.3.1, asyncio-0.24.0a1, cov-5.0.0, typeguard-4.4.1 +plugins: asyncio-0.24.0a1, cov-5.0.0, timeout-2.3.1, typeguard-4.4.1 asyncio: mode=Mode.AUTO, default_loop_scope=None collecting ... collected 29 items / 1 deselected / 28 selected tests/test_init.py::test_get_bluetooth_adapters_file_not_found -------------------------------- live log setup -------------------------------- -2024-12-06 08:43:02.304 DEBUG MainThread asyncio:selector_events.py:64 Using selector: EpollSelector -2024-12-06 08:43:02.305 DEBUG MainThread asyncio:selector_events.py:64 Using selector: EpollSelector +2024-12-07 10:46:42.633 DEBUG MainThread asyncio:selector_events.py:64 Using selector: EpollSelector +2024-12-07 10:46:42.639 DEBUG MainThread asyncio:selector_events.py:64 Using selector: EpollSelector -------------------------------- live log call --------------------------------- -2024-12-06 08:43:02.309 DEBUG MainThread bluetooth_adapters.dbus:dbus.py:123 DBus service not found; make sure the DBus socket is available: +2024-12-07 10:46:42.649 DEBUG MainThread bluetooth_adapters.dbus:dbus.py:123 DBus service not found; make sure the DBus socket is available: PASSED [ 3%] ------------------------------ live log teardown ------------------------------- -2024-12-06 08:43:02.311 DEBUG MainThread asyncio:selector_events.py:64 Using selector: EpollSelector +2024-12-07 10:46:42.657 DEBUG MainThread asyncio:selector_events.py:64 Using selector: EpollSelector tests/test_init.py::test_get_bluetooth_adapters_connection_refused -------------------------------- live log setup -------------------------------- -2024-12-06 08:43:02.315 DEBUG MainThread asyncio:selector_events.py:64 Using selector: EpollSelector +2024-12-07 10:46:42.661 DEBUG MainThread asyncio:selector_events.py:64 Using selector: EpollSelector -------------------------------- live log call --------------------------------- -2024-12-06 08:43:02.317 DEBUG MainThread bluetooth_adapters.dbus:dbus.py:146 DBus connection refused: ; try restarting `bluetooth` and `dbus` +2024-12-07 10:46:42.667 DEBUG MainThread bluetooth_adapters.dbus:dbus.py:146 DBus connection refused: ; try restarting `bluetooth` and `dbus` PASSED [ 7%] ------------------------------ live log teardown ------------------------------- -2024-12-06 08:43:02.319 DEBUG MainThread asyncio:selector_events.py:64 Using selector: EpollSelector +2024-12-07 10:46:42.674 DEBUG MainThread asyncio:selector_events.py:64 Using selector: EpollSelector tests/test_init.py::test_get_bluetooth_adapters_auth_eror -------------------------------- live log setup -------------------------------- -2024-12-06 08:43:02.322 DEBUG MainThread asyncio:selector_events.py:64 Using selector: EpollSelector +2024-12-07 10:46:42.682 DEBUG MainThread asyncio:selector_events.py:64 Using selector: EpollSelector -------------------------------- live log call --------------------------------- -2024-12-06 08:43:02.324 WARNING MainThread bluetooth_adapters.dbus:dbus.py:110 DBus authentication error; make sure the DBus socket is available and the user has the correct permissions: +2024-12-07 10:46:42.684 WARNING MainThread bluetooth_adapters.dbus:dbus.py:110 DBus authentication error; make sure the DBus socket is available and the user has the correct permissions: PASSED [ 10%] ------------------------------ live log teardown ------------------------------- -2024-12-06 08:43:02.327 DEBUG MainThread asyncio:selector_events.py:64 Using selector: EpollSelector +2024-12-07 10:46:42.691 DEBUG MainThread asyncio:selector_events.py:64 Using selector: EpollSelector tests/test_init.py::test_get_bluetooth_adapters_connect_refused_docker -------------------------------- live log setup -------------------------------- -2024-12-06 08:43:02.329 DEBUG MainThread asyncio:selector_events.py:64 Using selector: EpollSelector +2024-12-07 10:46:42.698 DEBUG MainThread asyncio:selector_events.py:64 Using selector: EpollSelector -------------------------------- live log call --------------------------------- -2024-12-06 08:43:02.332 DEBUG MainThread bluetooth_adapters.dbus:dbus.py:141 DBus connection refused: ; try restarting `bluetooth`, `dbus`, and finally the docker container -2024-12-06 08:43:02.333 DEBUG MainThread bluetooth_adapters.dbus:dbus.py:146 DBus connection refused: ; try restarting `bluetooth` and `dbus` +2024-12-07 10:46:42.701 DEBUG MainThread bluetooth_adapters.dbus:dbus.py:141 DBus connection refused: ; try restarting `bluetooth`, `dbus`, and finally the docker container +2024-12-07 10:46:42.706 DEBUG MainThread bluetooth_adapters.dbus:dbus.py:146 DBus connection refused: ; try restarting `bluetooth` and `dbus` PASSED [ 14%] ------------------------------ live log teardown ------------------------------- -2024-12-06 08:43:02.335 DEBUG MainThread asyncio:selector_events.py:64 Using selector: EpollSelector +2024-12-07 10:46:42.714 DEBUG MainThread asyncio:selector_events.py:64 Using selector: EpollSelector tests/test_init.py::test_get_bluetooth_adapters_connect_fails -------------------------------- live log setup -------------------------------- -2024-12-06 08:43:02.338 DEBUG MainThread asyncio:selector_events.py:64 Using selector: EpollSelector +2024-12-07 10:46:42.717 DEBUG MainThread asyncio:selector_events.py:64 Using selector: EpollSelector -------------------------------- live log call --------------------------------- -2024-12-06 08:43:02.340 DEBUG MainThread bluetooth_adapters.dbus:dbus.py:123 DBus service not found; make sure the DBus socket is available: +2024-12-07 10:46:42.719 DEBUG MainThread bluetooth_adapters.dbus:dbus.py:123 DBus service not found; make sure the DBus socket is available: PASSED [ 17%] ------------------------------ live log teardown ------------------------------- -2024-12-06 08:43:02.343 DEBUG MainThread asyncio:selector_events.py:64 Using selector: EpollSelector +2024-12-07 10:46:42.721 DEBUG MainThread asyncio:selector_events.py:64 Using selector: EpollSelector tests/test_init.py::test_get_bluetooth_adapters_connect_fails_docker -------------------------------- live log setup -------------------------------- -2024-12-06 08:43:02.346 DEBUG MainThread asyncio:selector_events.py:64 Using selector: EpollSelector +2024-12-07 10:46:42.724 DEBUG MainThread asyncio:selector_events.py:64 Using selector: EpollSelector -------------------------------- live log call --------------------------------- -2024-12-06 08:43:02.349 DEBUG MainThread bluetooth_adapters.dbus:dbus.py:118 DBus service not found; docker config may be missing `-v /run/dbus:/run/dbus:ro`: -2024-12-06 08:43:02.349 DEBUG MainThread bluetooth_adapters.dbus:dbus.py:123 DBus service not found; make sure the DBus socket is available: +2024-12-07 10:46:42.727 DEBUG MainThread bluetooth_adapters.dbus:dbus.py:118 DBus service not found; docker config may be missing `-v /run/dbus:/run/dbus:ro`: +2024-12-07 10:46:42.728 DEBUG MainThread bluetooth_adapters.dbus:dbus.py:123 DBus service not found; make sure the DBus socket is available: PASSED [ 21%] ------------------------------ live log teardown ------------------------------- -2024-12-06 08:43:02.351 DEBUG MainThread asyncio:selector_events.py:64 Using selector: EpollSelector +2024-12-07 10:46:42.730 DEBUG MainThread asyncio:selector_events.py:64 Using selector: EpollSelector tests/test_init.py::test_get_bluetooth_adapters_connect_broken_pipe -------------------------------- live log setup -------------------------------- -2024-12-06 08:43:02.354 DEBUG MainThread asyncio:selector_events.py:64 Using selector: EpollSelector +2024-12-07 10:46:42.733 DEBUG MainThread asyncio:selector_events.py:64 Using selector: EpollSelector -------------------------------- live log call --------------------------------- -2024-12-06 08:43:02.356 DEBUG MainThread bluetooth_adapters.dbus:dbus.py:135 DBus connection broken: ; try restarting `bluetooth` and `dbus` +2024-12-07 10:46:42.735 DEBUG MainThread bluetooth_adapters.dbus:dbus.py:135 DBus connection broken: ; try restarting `bluetooth` and `dbus` PASSED [ 25%] ------------------------------ live log teardown ------------------------------- -2024-12-06 08:43:02.359 DEBUG MainThread asyncio:selector_events.py:64 Using selector: EpollSelector +2024-12-07 10:46:42.737 DEBUG MainThread asyncio:selector_events.py:64 Using selector: EpollSelector tests/test_init.py::test_get_bluetooth_adapters_connect_broken_pipe_docker -------------------------------- live log setup -------------------------------- -2024-12-06 08:43:02.362 DEBUG MainThread asyncio:selector_events.py:64 Using selector: EpollSelector +2024-12-07 10:46:42.740 DEBUG MainThread asyncio:selector_events.py:64 Using selector: EpollSelector -------------------------------- live log call --------------------------------- -2024-12-06 08:43:02.365 DEBUG MainThread bluetooth_adapters.dbus:dbus.py:130 DBus connection broken: ; try restarting `bluetooth`, `dbus`, and finally the docker container -2024-12-06 08:43:02.365 DEBUG MainThread bluetooth_adapters.dbus:dbus.py:135 DBus connection broken: ; try restarting `bluetooth` and `dbus` +2024-12-07 10:46:42.742 DEBUG MainThread bluetooth_adapters.dbus:dbus.py:130 DBus connection broken: ; try restarting `bluetooth`, `dbus`, and finally the docker container +2024-12-07 10:46:42.743 DEBUG MainThread bluetooth_adapters.dbus:dbus.py:135 DBus connection broken: ; try restarting `bluetooth` and `dbus` PASSED [ 28%] ------------------------------ live log teardown ------------------------------- -2024-12-06 08:43:02.367 DEBUG MainThread asyncio:selector_events.py:64 Using selector: EpollSelector +2024-12-07 10:46:42.745 DEBUG MainThread asyncio:selector_events.py:64 Using selector: EpollSelector tests/test_init.py::test_get_bluetooth_adapters_connect_eof_error -------------------------------- live log setup -------------------------------- -2024-12-06 08:43:02.370 DEBUG MainThread asyncio:selector_events.py:64 Using selector: EpollSelector +2024-12-07 10:46:42.748 DEBUG MainThread asyncio:selector_events.py:64 Using selector: EpollSelector -------------------------------- live log call --------------------------------- -2024-12-06 08:43:02.375 DEBUG MainThread bluetooth_adapters.dbus:dbus.py:160 DBus connection closed: +2024-12-07 10:46:42.752 DEBUG MainThread bluetooth_adapters.dbus:dbus.py:160 DBus connection closed: PASSED [ 32%] ------------------------------ live log teardown ------------------------------- -2024-12-06 08:43:02.377 DEBUG MainThread asyncio:selector_events.py:64 Using selector: EpollSelector +2024-12-07 10:46:42.754 DEBUG MainThread asyncio:selector_events.py:64 Using selector: EpollSelector tests/test_init.py::test_get_bluetooth_adapters_no_call_return -------------------------------- live log setup -------------------------------- -2024-12-06 08:43:02.380 DEBUG MainThread asyncio:selector_events.py:64 Using selector: EpollSelector +2024-12-07 10:46:42.757 DEBUG MainThread asyncio:selector_events.py:64 Using selector: EpollSelector -------------------------------- live log call --------------------------------- -2024-12-06 08:43:02.386 DEBUG MainThread bluetooth_adapters.dbus:dbus.py:170 Received an unexpected reply from Dbus while calling GetManagedObjects on org.bluez: +2024-12-07 10:46:42.763 DEBUG MainThread bluetooth_adapters.dbus:dbus.py:170 Received an unexpected reply from Dbus while calling GetManagedObjects on org.bluez: PASSED [ 35%] ------------------------------ live log teardown ------------------------------- -2024-12-06 08:43:02.391 DEBUG MainThread asyncio:selector_events.py:64 Using selector: EpollSelector +2024-12-07 10:46:42.767 DEBUG MainThread asyncio:selector_events.py:64 Using selector: EpollSelector tests/test_init.py::test_get_bluetooth_adapters_times_out -------------------------------- live log setup -------------------------------- -2024-12-06 08:43:02.394 DEBUG MainThread asyncio:selector_events.py:64 Using selector: EpollSelector +2024-12-07 10:46:42.770 DEBUG MainThread asyncio:selector_events.py:64 Using selector: EpollSelector -------------------------------- live log call --------------------------------- -2024-12-06 08:43:02.398 DEBUG MainThread bluetooth_adapters.dbus:dbus.py:163 Dbus timeout waiting for reply to GetManagedObjects; try restarting `bluetooth` and `dbus` +2024-12-07 10:46:42.774 DEBUG MainThread bluetooth_adapters.dbus:dbus.py:163 Dbus timeout waiting for reply to GetManagedObjects; try restarting `bluetooth` and `dbus` PASSED [ 39%] ------------------------------ live log teardown ------------------------------- -2024-12-06 08:43:02.400 DEBUG MainThread asyncio:selector_events.py:64 Using selector: EpollSelector +2024-12-07 10:46:42.776 DEBUG MainThread asyncio:selector_events.py:64 Using selector: EpollSelector tests/test_init.py::test_get_bluetooth_adapters_no_wrong_return -------------------------------- live log setup -------------------------------- -2024-12-06 08:43:02.403 DEBUG MainThread asyncio:selector_events.py:64 Using selector: EpollSelector +2024-12-07 10:46:42.779 DEBUG MainThread asyncio:selector_events.py:64 Using selector: EpollSelector -------------------------------- live log call --------------------------------- -2024-12-06 08:43:02.409 DEBUG MainThread bluetooth_adapters.dbus:dbus.py:170 Received an unexpected reply from Dbus while calling GetManagedObjects on org.bluez: +2024-12-07 10:46:42.784 DEBUG MainThread bluetooth_adapters.dbus:dbus.py:170 Received an unexpected reply from Dbus while calling GetManagedObjects on org.bluez: PASSED [ 42%] ------------------------------ live log teardown ------------------------------- -2024-12-06 08:43:02.412 DEBUG MainThread asyncio:selector_events.py:64 Using selector: EpollSelector +2024-12-07 10:46:42.787 DEBUG MainThread asyncio:selector_events.py:64 Using selector: EpollSelector tests/test_init.py::test_get_bluetooth_adapters_correct_return_valid_message -------------------------------- live log setup -------------------------------- -2024-12-06 08:43:02.415 DEBUG MainThread asyncio:selector_events.py:64 Using selector: EpollSelector +2024-12-07 10:46:42.790 DEBUG MainThread asyncio:selector_events.py:64 Using selector: EpollSelector PASSED [ 46%] ------------------------------ live log teardown ------------------------------- -2024-12-06 08:43:02.422 DEBUG MainThread asyncio:selector_events.py:64 Using selector: EpollSelector +2024-12-07 10:46:42.797 DEBUG MainThread asyncio:selector_events.py:64 Using selector: EpollSelector tests/test_init.py::test_get_dbus_managed_objects -------------------------------- live log setup -------------------------------- -2024-12-06 08:43:02.425 DEBUG MainThread asyncio:selector_events.py:64 Using selector: EpollSelector +2024-12-07 10:46:42.799 DEBUG MainThread asyncio:selector_events.py:64 Using selector: EpollSelector PASSED [ 50%] ------------------------------ live log teardown ------------------------------- -2024-12-06 08:43:02.433 DEBUG MainThread asyncio:selector_events.py:64 Using selector: EpollSelector +2024-12-07 10:46:42.811 DEBUG MainThread asyncio:selector_events.py:64 Using selector: EpollSelector tests/test_init.py::test_BlueZDBusObjects -------------------------------- live log setup -------------------------------- -2024-12-06 08:43:02.436 DEBUG MainThread asyncio:selector_events.py:64 Using selector: EpollSelector +2024-12-07 10:46:42.814 DEBUG MainThread asyncio:selector_events.py:64 Using selector: EpollSelector PASSED [ 53%] ------------------------------ live log teardown ------------------------------- -2024-12-06 08:43:02.443 DEBUG MainThread asyncio:selector_events.py:64 Using selector: EpollSelector +2024-12-07 10:46:42.834 DEBUG MainThread asyncio:selector_events.py:64 Using selector: EpollSelector tests/test_init.py::test_get_adapters_linux -------------------------------- live log setup -------------------------------- -2024-12-06 08:43:02.448 DEBUG MainThread asyncio:selector_events.py:64 Using selector: EpollSelector +2024-12-07 10:46:42.843 DEBUG MainThread asyncio:selector_events.py:64 Using selector: EpollSelector -------------------------------- live log call --------------------------------- -2024-12-06 08:43:03.202 DEBUG asyncio_0 bluetooth_adapters.systems.linux_hci:linux_hci.py:99 Error while getting HCI devices: [Errno 97] Address family not supported by protocol +2024-12-07 10:46:43.686 DEBUG asyncio_0 bluetooth_adapters.systems.linux_hci:linux_hci.py:99 Error while getting HCI devices: [Errno 97] Address family not supported by protocol PASSED [ 57%] ------------------------------ live log teardown ------------------------------- -2024-12-06 08:43:03.212 DEBUG MainThread asyncio:selector_events.py:64 Using selector: EpollSelector +2024-12-07 10:46:43.692 DEBUG MainThread asyncio:selector_events.py:64 Using selector: EpollSelector tests/test_init.py::test_get_adapters_linux_device_listed_before_adapter -------------------------------- live log setup -------------------------------- -2024-12-06 08:43:03.216 DEBUG MainThread asyncio:selector_events.py:64 Using selector: EpollSelector +2024-12-07 10:46:43.700 DEBUG MainThread asyncio:selector_events.py:64 Using selector: EpollSelector -------------------------------- live log call --------------------------------- -2024-12-06 08:43:03.246 DEBUG asyncio_0 bluetooth_adapters.systems.linux_hci:linux_hci.py:99 Error while getting HCI devices: [Errno 97] Address family not supported by protocol +2024-12-07 10:46:43.717 DEBUG asyncio_0 bluetooth_adapters.systems.linux_hci:linux_hci.py:99 Error while getting HCI devices: [Errno 97] Address family not supported by protocol PASSED [ 60%] ------------------------------ live log teardown ------------------------------- -2024-12-06 08:43:03.249 DEBUG MainThread asyncio:selector_events.py:64 Using selector: EpollSelector +2024-12-07 10:46:43.732 DEBUG MainThread asyncio:selector_events.py:64 Using selector: EpollSelector tests/test_init.py::test_get_adapters_linux_uart -------------------------------- live log setup -------------------------------- -2024-12-06 08:43:03.252 DEBUG MainThread asyncio:selector_events.py:64 Using selector: EpollSelector +2024-12-07 10:46:43.742 DEBUG MainThread asyncio:selector_events.py:64 Using selector: EpollSelector -------------------------------- live log call --------------------------------- -2024-12-06 08:43:03.259 DEBUG asyncio_0 bluetooth_adapters.systems.linux_hci:linux_hci.py:99 Error while getting HCI devices: [Errno 97] Address family not supported by protocol +2024-12-07 10:46:43.753 DEBUG asyncio_0 bluetooth_adapters.systems.linux_hci:linux_hci.py:99 Error while getting HCI devices: [Errno 97] Address family not supported by protocol PASSED [ 64%] ------------------------------ live log teardown ------------------------------- -2024-12-06 08:43:03.266 DEBUG MainThread asyncio:selector_events.py:64 Using selector: EpollSelector +2024-12-07 10:46:43.772 DEBUG MainThread asyncio:selector_events.py:64 Using selector: EpollSelector tests/test_init.py::test_get_adapters_macos -------------------------------- live log setup -------------------------------- -2024-12-06 08:43:03.289 DEBUG MainThread asyncio:selector_events.py:64 Using selector: EpollSelector +2024-12-07 10:46:43.778 DEBUG MainThread asyncio:selector_events.py:64 Using selector: EpollSelector PASSED [ 67%] ------------------------------ live log teardown ------------------------------- -2024-12-06 08:43:03.296 DEBUG MainThread asyncio:selector_events.py:64 Using selector: EpollSelector +2024-12-07 10:46:43.794 DEBUG MainThread asyncio:selector_events.py:64 Using selector: EpollSelector tests/test_init.py::test_get_adapters_windows -------------------------------- live log setup -------------------------------- -2024-12-06 08:43:03.300 DEBUG MainThread asyncio:selector_events.py:64 Using selector: EpollSelector +2024-12-07 10:46:43.800 DEBUG MainThread asyncio:selector_events.py:64 Using selector: EpollSelector PASSED [ 71%] ------------------------------ live log teardown ------------------------------- -2024-12-06 08:43:03.310 DEBUG MainThread asyncio:selector_events.py:64 Using selector: EpollSelector +2024-12-07 10:46:43.822 DEBUG MainThread asyncio:selector_events.py:64 Using selector: EpollSelector tests/test_init.py::test_adapter_human_name PASSED [ 75%] tests/test_init.py::test_adapter_unique_name PASSED [ 78%] @@ -984,18 +1020,18 @@ tests/test_init.py::test_discovered_device_advertisement_data_from_dict PASSED [ 89%] tests/test_init.py::test_expire_stale_scanner_discovered_device_advertisement_data -------------------------------- live log call --------------------------------- -2024-12-06 08:43:03.332 DEBUG MainThread bluetooth_adapters.storage:storage.py:116 Loaded 1 fresh discovered devices for myscanner -2024-12-06 08:43:03.333 DEBUG MainThread bluetooth_adapters.storage:storage.py:116 Loaded 0 fresh discovered devices for all_expired +2024-12-07 10:46:43.870 DEBUG MainThread bluetooth_adapters.storage:storage.py:116 Loaded 1 fresh discovered devices for myscanner +2024-12-07 10:46:43.871 DEBUG MainThread bluetooth_adapters.storage:storage.py:116 Loaded 0 fresh discovered devices for all_expired PASSED [ 92%] tests/test_init.py::test_expire_future_discovered_device_advertisement_data -------------------------------- live log call --------------------------------- -2024-12-06 08:43:03.337 DEBUG MainThread bluetooth_adapters.storage:storage.py:116 Loaded 1 fresh discovered devices for myscanner -2024-12-06 08:43:03.337 WARNING MainThread bluetooth_adapters.storage:storage.py:103 Discarding timestamp 1734517783.3371902 for CC:DD:EE:FF:AA:BB on scanner all_future as it is the future (now = 1733517783.3372214) -2024-12-06 08:43:03.338 DEBUG MainThread bluetooth_adapters.storage:storage.py:116 Loaded 0 fresh discovered devices for all_future +2024-12-07 10:46:43.883 DEBUG MainThread bluetooth_adapters.storage:storage.py:116 Loaded 1 fresh discovered devices for myscanner +2024-12-07 10:46:43.884 WARNING MainThread bluetooth_adapters.storage:storage.py:103 Discarding timestamp 1734518003.8838131 for CC:DD:EE:FF:AA:BB on scanner all_future as it is the future (now = 1733518003.8838463) +2024-12-07 10:46:43.885 DEBUG MainThread bluetooth_adapters.storage:storage.py:116 Loaded 0 fresh discovered devices for all_future PASSED [ 96%] tests/test_init.py::test_discovered_device_advertisement_data_from_dict_corrupt -------------------------------- live log call --------------------------------- -2024-12-06 08:43:03.342 ERROR MainThread bluetooth_adapters.storage:storage.py:140 Error deserializing discovered_device_advertisement_data, adapter startup will be slow: BLEDevice.__init__() missing 1 required positional argument: 'name' +2024-12-07 10:46:43.894 ERROR MainThread bluetooth_adapters.storage:storage.py:140 Error deserializing discovered_device_advertisement_data, adapter startup will be slow: BLEDevice.__init__() missing 1 required positional argument: 'name' Traceback (most recent call last): File "/build/reproducible-path/python-bluetooth-adapters-0.20.2/.pybuild/cpython3_3.13_bluetooth-adapters/build/bluetooth_adapters/storage.py", line 132, in discovered_device_advertisement_data_from_dict _deserialize_discovered_device_advertisement_datas( @@ -1024,7 +1060,7 @@ assert out_ble_device.metadata == expected_ble_device.metadata -- Docs: https://docs.pytest.org/en/stable/how-to/capture-warnings.html -================= 28 passed, 1 deselected, 3 warnings in 1.48s ================= +================= 28 passed, 1 deselected, 3 warnings in 1.77s ================= I: pybuild base:311: cd /build/reproducible-path/python-bluetooth-adapters-0.20.2/.pybuild/cpython3_3.12_bluetooth-adapters/build; python3.12 -m pytest -x --no-cov -k "not test_get_adapters_linux_no_usb_device" /usr/lib/python3/dist-packages/pytest_asyncio/plugin.py:208: PytestDeprecationWarning: The configuration option "asyncio_default_fixture_loop_scope" is unset. 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" @@ -1035,183 +1071,183 @@ cachedir: .pytest_cache rootdir: /build/reproducible-path/python-bluetooth-adapters-0.20.2/.pybuild/cpython3_3.12_bluetooth-adapters/build configfile: pyproject.toml -plugins: timeout-2.3.1, asyncio-0.24.0a1, cov-5.0.0, typeguard-4.4.1 +plugins: asyncio-0.24.0a1, cov-5.0.0, timeout-2.3.1, typeguard-4.4.1 asyncio: mode=Mode.AUTO, default_loop_scope=None collecting ... collected 29 items / 1 deselected / 28 selected tests/test_init.py::test_get_bluetooth_adapters_file_not_found -------------------------------- live log setup -------------------------------- -2024-12-06 08:43:04.957 DEBUG MainThread asyncio:selector_events.py:64 Using selector: EpollSelector -2024-12-06 08:43:04.959 DEBUG MainThread asyncio:selector_events.py:64 Using selector: EpollSelector +2024-12-07 10:46:46.273 DEBUG MainThread asyncio:selector_events.py:64 Using selector: EpollSelector +2024-12-07 10:46:46.275 DEBUG MainThread asyncio:selector_events.py:64 Using selector: EpollSelector -------------------------------- live log call --------------------------------- -2024-12-06 08:43:04.963 DEBUG MainThread bluetooth_adapters.dbus:dbus.py:123 DBus service not found; make sure the DBus socket is available: +2024-12-07 10:46:46.279 DEBUG MainThread bluetooth_adapters.dbus:dbus.py:123 DBus service not found; make sure the DBus socket is available: PASSED [ 3%] ------------------------------ live log teardown ------------------------------- -2024-12-06 08:43:04.966 DEBUG MainThread asyncio:selector_events.py:64 Using selector: EpollSelector +2024-12-07 10:46:46.282 DEBUG MainThread asyncio:selector_events.py:64 Using selector: EpollSelector tests/test_init.py::test_get_bluetooth_adapters_connection_refused -------------------------------- live log setup -------------------------------- -2024-12-06 08:43:04.969 DEBUG MainThread asyncio:selector_events.py:64 Using selector: EpollSelector +2024-12-07 10:46:46.285 DEBUG MainThread asyncio:selector_events.py:64 Using selector: EpollSelector -------------------------------- live log call --------------------------------- -2024-12-06 08:43:04.971 DEBUG MainThread bluetooth_adapters.dbus:dbus.py:146 DBus connection refused: ; try restarting `bluetooth` and `dbus` +2024-12-07 10:46:46.288 DEBUG MainThread bluetooth_adapters.dbus:dbus.py:146 DBus connection refused: ; try restarting `bluetooth` and `dbus` PASSED [ 7%] ------------------------------ live log teardown ------------------------------- -2024-12-06 08:43:04.974 DEBUG MainThread asyncio:selector_events.py:64 Using selector: EpollSelector +2024-12-07 10:46:46.290 DEBUG MainThread asyncio:selector_events.py:64 Using selector: EpollSelector tests/test_init.py::test_get_bluetooth_adapters_auth_eror -------------------------------- live log setup -------------------------------- -2024-12-06 08:43:04.976 DEBUG MainThread asyncio:selector_events.py:64 Using selector: EpollSelector +2024-12-07 10:46:46.293 DEBUG MainThread asyncio:selector_events.py:64 Using selector: EpollSelector -------------------------------- live log call --------------------------------- -2024-12-06 08:43:04.979 WARNING MainThread bluetooth_adapters.dbus:dbus.py:110 DBus authentication error; make sure the DBus socket is available and the user has the correct permissions: +2024-12-07 10:46:46.296 WARNING MainThread bluetooth_adapters.dbus:dbus.py:110 DBus authentication error; make sure the DBus socket is available and the user has the correct permissions: PASSED [ 10%] ------------------------------ live log teardown ------------------------------- -2024-12-06 08:43:04.981 DEBUG MainThread asyncio:selector_events.py:64 Using selector: EpollSelector +2024-12-07 10:46:46.298 DEBUG MainThread asyncio:selector_events.py:64 Using selector: EpollSelector tests/test_init.py::test_get_bluetooth_adapters_connect_refused_docker -------------------------------- live log setup -------------------------------- -2024-12-06 08:43:04.984 DEBUG MainThread asyncio:selector_events.py:64 Using selector: EpollSelector +2024-12-07 10:46:46.301 DEBUG MainThread asyncio:selector_events.py:64 Using selector: EpollSelector -------------------------------- live log call --------------------------------- -2024-12-06 08:43:04.986 DEBUG MainThread bluetooth_adapters.dbus:dbus.py:141 DBus connection refused: ; try restarting `bluetooth`, `dbus`, and finally the docker container -2024-12-06 08:43:04.987 DEBUG MainThread bluetooth_adapters.dbus:dbus.py:146 DBus connection refused: ; try restarting `bluetooth` and `dbus` +2024-12-07 10:46:46.304 DEBUG MainThread bluetooth_adapters.dbus:dbus.py:141 DBus connection refused: ; try restarting `bluetooth`, `dbus`, and finally the docker container +2024-12-07 10:46:46.305 DEBUG MainThread bluetooth_adapters.dbus:dbus.py:146 DBus connection refused: ; try restarting `bluetooth` and `dbus` PASSED [ 14%] ------------------------------ live log teardown ------------------------------- -2024-12-06 08:43:04.989 DEBUG MainThread asyncio:selector_events.py:64 Using selector: EpollSelector +2024-12-07 10:46:46.307 DEBUG MainThread asyncio:selector_events.py:64 Using selector: EpollSelector tests/test_init.py::test_get_bluetooth_adapters_connect_fails -------------------------------- live log setup -------------------------------- -2024-12-06 08:43:04.992 DEBUG MainThread asyncio:selector_events.py:64 Using selector: EpollSelector +2024-12-07 10:46:46.310 DEBUG MainThread asyncio:selector_events.py:64 Using selector: EpollSelector -------------------------------- live log call --------------------------------- -2024-12-06 08:43:04.994 DEBUG MainThread bluetooth_adapters.dbus:dbus.py:123 DBus service not found; make sure the DBus socket is available: +2024-12-07 10:46:46.312 DEBUG MainThread bluetooth_adapters.dbus:dbus.py:123 DBus service not found; make sure the DBus socket is available: PASSED [ 17%] ------------------------------ live log teardown ------------------------------- -2024-12-06 08:43:04.996 DEBUG MainThread asyncio:selector_events.py:64 Using selector: EpollSelector +2024-12-07 10:46:46.315 DEBUG MainThread asyncio:selector_events.py:64 Using selector: EpollSelector tests/test_init.py::test_get_bluetooth_adapters_connect_fails_docker -------------------------------- live log setup -------------------------------- -2024-12-06 08:43:04.999 DEBUG MainThread asyncio:selector_events.py:64 Using selector: EpollSelector +2024-12-07 10:46:46.318 DEBUG MainThread asyncio:selector_events.py:64 Using selector: EpollSelector -------------------------------- live log call --------------------------------- -2024-12-06 08:43:05.001 DEBUG MainThread bluetooth_adapters.dbus:dbus.py:118 DBus service not found; docker config may be missing `-v /run/dbus:/run/dbus:ro`: -2024-12-06 08:43:05.002 DEBUG MainThread bluetooth_adapters.dbus:dbus.py:123 DBus service not found; make sure the DBus socket is available: +2024-12-07 10:46:46.320 DEBUG MainThread bluetooth_adapters.dbus:dbus.py:118 DBus service not found; docker config may be missing `-v /run/dbus:/run/dbus:ro`: +2024-12-07 10:46:46.321 DEBUG MainThread bluetooth_adapters.dbus:dbus.py:123 DBus service not found; make sure the DBus socket is available: PASSED [ 21%] ------------------------------ live log teardown ------------------------------- -2024-12-06 08:43:05.004 DEBUG MainThread asyncio:selector_events.py:64 Using selector: EpollSelector +2024-12-07 10:46:46.323 DEBUG MainThread asyncio:selector_events.py:64 Using selector: EpollSelector tests/test_init.py::test_get_bluetooth_adapters_connect_broken_pipe -------------------------------- live log setup -------------------------------- -2024-12-06 08:43:05.007 DEBUG MainThread asyncio:selector_events.py:64 Using selector: EpollSelector +2024-12-07 10:46:46.326 DEBUG MainThread asyncio:selector_events.py:64 Using selector: EpollSelector -------------------------------- live log call --------------------------------- -2024-12-06 08:43:05.009 DEBUG MainThread bluetooth_adapters.dbus:dbus.py:135 DBus connection broken: ; try restarting `bluetooth` and `dbus` +2024-12-07 10:46:46.329 DEBUG MainThread bluetooth_adapters.dbus:dbus.py:135 DBus connection broken: ; try restarting `bluetooth` and `dbus` PASSED [ 25%] ------------------------------ live log teardown ------------------------------- -2024-12-06 08:43:05.011 DEBUG MainThread asyncio:selector_events.py:64 Using selector: EpollSelector +2024-12-07 10:46:46.331 DEBUG MainThread asyncio:selector_events.py:64 Using selector: EpollSelector tests/test_init.py::test_get_bluetooth_adapters_connect_broken_pipe_docker -------------------------------- live log setup -------------------------------- -2024-12-06 08:43:05.014 DEBUG MainThread asyncio:selector_events.py:64 Using selector: EpollSelector +2024-12-07 10:46:46.334 DEBUG MainThread asyncio:selector_events.py:64 Using selector: EpollSelector -------------------------------- live log call --------------------------------- -2024-12-06 08:43:05.017 DEBUG MainThread bluetooth_adapters.dbus:dbus.py:130 DBus connection broken: ; try restarting `bluetooth`, `dbus`, and finally the docker container -2024-12-06 08:43:05.017 DEBUG MainThread bluetooth_adapters.dbus:dbus.py:135 DBus connection broken: ; try restarting `bluetooth` and `dbus` +2024-12-07 10:46:46.337 DEBUG MainThread bluetooth_adapters.dbus:dbus.py:130 DBus connection broken: ; try restarting `bluetooth`, `dbus`, and finally the docker container +2024-12-07 10:46:46.338 DEBUG MainThread bluetooth_adapters.dbus:dbus.py:135 DBus connection broken: ; try restarting `bluetooth` and `dbus` PASSED [ 28%] ------------------------------ live log teardown ------------------------------- -2024-12-06 08:43:05.019 DEBUG MainThread asyncio:selector_events.py:64 Using selector: EpollSelector +2024-12-07 10:46:46.340 DEBUG MainThread asyncio:selector_events.py:64 Using selector: EpollSelector tests/test_init.py::test_get_bluetooth_adapters_connect_eof_error -------------------------------- live log setup -------------------------------- -2024-12-06 08:43:05.022 DEBUG MainThread asyncio:selector_events.py:64 Using selector: EpollSelector +2024-12-07 10:46:46.343 DEBUG MainThread asyncio:selector_events.py:64 Using selector: EpollSelector -------------------------------- live log call --------------------------------- -2024-12-06 08:43:05.026 DEBUG MainThread bluetooth_adapters.dbus:dbus.py:160 DBus connection closed: +2024-12-07 10:46:46.347 DEBUG MainThread bluetooth_adapters.dbus:dbus.py:160 DBus connection closed: PASSED [ 32%] ------------------------------ live log teardown ------------------------------- -2024-12-06 08:43:05.028 DEBUG MainThread asyncio:selector_events.py:64 Using selector: EpollSelector +2024-12-07 10:46:46.350 DEBUG MainThread asyncio:selector_events.py:64 Using selector: EpollSelector tests/test_init.py::test_get_bluetooth_adapters_no_call_return -------------------------------- live log setup -------------------------------- -2024-12-06 08:43:05.031 DEBUG MainThread asyncio:selector_events.py:64 Using selector: EpollSelector +2024-12-07 10:46:46.353 DEBUG MainThread asyncio:selector_events.py:64 Using selector: EpollSelector -------------------------------- live log call --------------------------------- -2024-12-06 08:43:05.038 DEBUG MainThread bluetooth_adapters.dbus:dbus.py:170 Received an unexpected reply from Dbus while calling GetManagedObjects on org.bluez: +2024-12-07 10:46:46.359 DEBUG MainThread bluetooth_adapters.dbus:dbus.py:170 Received an unexpected reply from Dbus while calling GetManagedObjects on org.bluez: PASSED [ 35%] ------------------------------ live log teardown ------------------------------- -2024-12-06 08:43:05.041 DEBUG MainThread asyncio:selector_events.py:64 Using selector: EpollSelector +2024-12-07 10:46:46.363 DEBUG MainThread asyncio:selector_events.py:64 Using selector: EpollSelector tests/test_init.py::test_get_bluetooth_adapters_times_out -------------------------------- live log setup -------------------------------- -2024-12-06 08:43:05.044 DEBUG MainThread asyncio:selector_events.py:64 Using selector: EpollSelector +2024-12-07 10:46:46.366 DEBUG MainThread asyncio:selector_events.py:64 Using selector: EpollSelector -------------------------------- live log call --------------------------------- -2024-12-06 08:43:05.052 DEBUG MainThread bluetooth_adapters.dbus:dbus.py:163 Dbus timeout waiting for reply to GetManagedObjects; try restarting `bluetooth` and `dbus` +2024-12-07 10:46:46.374 DEBUG MainThread bluetooth_adapters.dbus:dbus.py:163 Dbus timeout waiting for reply to GetManagedObjects; try restarting `bluetooth` and `dbus` PASSED [ 39%] ------------------------------ live log teardown ------------------------------- -2024-12-06 08:43:05.054 DEBUG MainThread asyncio:selector_events.py:64 Using selector: EpollSelector +2024-12-07 10:46:46.376 DEBUG MainThread asyncio:selector_events.py:64 Using selector: EpollSelector tests/test_init.py::test_get_bluetooth_adapters_no_wrong_return -------------------------------- live log setup -------------------------------- -2024-12-06 08:43:05.057 DEBUG MainThread asyncio:selector_events.py:64 Using selector: EpollSelector +2024-12-07 10:46:46.379 DEBUG MainThread asyncio:selector_events.py:64 Using selector: EpollSelector -------------------------------- live log call --------------------------------- -2024-12-06 08:43:05.063 DEBUG MainThread bluetooth_adapters.dbus:dbus.py:170 Received an unexpected reply from Dbus while calling GetManagedObjects on org.bluez: +2024-12-07 10:46:46.385 DEBUG MainThread bluetooth_adapters.dbus:dbus.py:170 Received an unexpected reply from Dbus while calling GetManagedObjects on org.bluez: PASSED [ 42%] ------------------------------ live log teardown ------------------------------- -2024-12-06 08:43:05.066 DEBUG MainThread asyncio:selector_events.py:64 Using selector: EpollSelector +2024-12-07 10:46:46.388 DEBUG MainThread asyncio:selector_events.py:64 Using selector: EpollSelector tests/test_init.py::test_get_bluetooth_adapters_correct_return_valid_message -------------------------------- live log setup -------------------------------- -2024-12-06 08:43:05.068 DEBUG MainThread asyncio:selector_events.py:64 Using selector: EpollSelector +2024-12-07 10:46:46.391 DEBUG MainThread asyncio:selector_events.py:64 Using selector: EpollSelector PASSED [ 46%] ------------------------------ live log teardown ------------------------------- -2024-12-06 08:43:05.075 DEBUG MainThread asyncio:selector_events.py:64 Using selector: EpollSelector +2024-12-07 10:46:46.398 DEBUG MainThread asyncio:selector_events.py:64 Using selector: EpollSelector tests/test_init.py::test_get_dbus_managed_objects -------------------------------- live log setup -------------------------------- -2024-12-06 08:43:05.078 DEBUG MainThread asyncio:selector_events.py:64 Using selector: EpollSelector +2024-12-07 10:46:46.401 DEBUG MainThread asyncio:selector_events.py:64 Using selector: EpollSelector PASSED [ 50%] ------------------------------ live log teardown ------------------------------- -2024-12-06 08:43:05.085 DEBUG MainThread asyncio:selector_events.py:64 Using selector: EpollSelector +2024-12-07 10:46:46.408 DEBUG MainThread asyncio:selector_events.py:64 Using selector: EpollSelector tests/test_init.py::test_BlueZDBusObjects -------------------------------- live log setup -------------------------------- -2024-12-06 08:43:05.088 DEBUG MainThread asyncio:selector_events.py:64 Using selector: EpollSelector +2024-12-07 10:46:46.411 DEBUG MainThread asyncio:selector_events.py:64 Using selector: EpollSelector PASSED [ 53%] ------------------------------ live log teardown ------------------------------- -2024-12-06 08:43:05.095 DEBUG MainThread asyncio:selector_events.py:64 Using selector: EpollSelector +2024-12-07 10:46:46.418 DEBUG MainThread asyncio:selector_events.py:64 Using selector: EpollSelector tests/test_init.py::test_get_adapters_linux -------------------------------- live log setup -------------------------------- -2024-12-06 08:43:05.099 DEBUG MainThread asyncio:selector_events.py:64 Using selector: EpollSelector +2024-12-07 10:46:46.423 DEBUG MainThread asyncio:selector_events.py:64 Using selector: EpollSelector -------------------------------- live log call --------------------------------- -2024-12-06 08:43:05.120 DEBUG asyncio_0 bluetooth_adapters.systems.linux_hci:linux_hci.py:99 Error while getting HCI devices: [Errno 97] Address family not supported by protocol +2024-12-07 10:46:46.437 DEBUG asyncio_0 bluetooth_adapters.systems.linux_hci:linux_hci.py:99 Error while getting HCI devices: [Errno 97] Address family not supported by protocol PASSED [ 57%] ------------------------------ live log teardown ------------------------------- -2024-12-06 08:43:05.191 DEBUG MainThread asyncio:selector_events.py:64 Using selector: EpollSelector +2024-12-07 10:46:46.560 DEBUG MainThread asyncio:selector_events.py:64 Using selector: EpollSelector tests/test_init.py::test_get_adapters_linux_device_listed_before_adapter -------------------------------- live log setup -------------------------------- -2024-12-06 08:43:05.195 DEBUG MainThread asyncio:selector_events.py:64 Using selector: EpollSelector +2024-12-07 10:46:46.572 DEBUG MainThread asyncio:selector_events.py:64 Using selector: EpollSelector -------------------------------- live log call --------------------------------- -2024-12-06 08:43:05.202 DEBUG asyncio_0 bluetooth_adapters.systems.linux_hci:linux_hci.py:99 Error while getting HCI devices: [Errno 97] Address family not supported by protocol +2024-12-07 10:46:46.585 DEBUG asyncio_0 bluetooth_adapters.systems.linux_hci:linux_hci.py:99 Error while getting HCI devices: [Errno 97] Address family not supported by protocol PASSED [ 60%] ------------------------------ live log teardown ------------------------------- -2024-12-06 08:43:05.206 DEBUG MainThread asyncio:selector_events.py:64 Using selector: EpollSelector +2024-12-07 10:46:46.603 DEBUG MainThread asyncio:selector_events.py:64 Using selector: EpollSelector tests/test_init.py::test_get_adapters_linux_uart -------------------------------- live log setup -------------------------------- -2024-12-06 08:43:05.209 DEBUG MainThread asyncio:selector_events.py:64 Using selector: EpollSelector +2024-12-07 10:46:46.610 DEBUG MainThread asyncio:selector_events.py:64 Using selector: EpollSelector -------------------------------- live log call --------------------------------- -2024-12-06 08:43:05.217 DEBUG asyncio_0 bluetooth_adapters.systems.linux_hci:linux_hci.py:99 Error while getting HCI devices: [Errno 97] Address family not supported by protocol +2024-12-07 10:46:46.626 DEBUG asyncio_0 bluetooth_adapters.systems.linux_hci:linux_hci.py:99 Error while getting HCI devices: [Errno 97] Address family not supported by protocol PASSED [ 64%] ------------------------------ live log teardown ------------------------------- -2024-12-06 08:43:05.220 DEBUG MainThread asyncio:selector_events.py:64 Using selector: EpollSelector +2024-12-07 10:46:46.648 DEBUG MainThread asyncio:selector_events.py:64 Using selector: EpollSelector tests/test_init.py::test_get_adapters_macos -------------------------------- live log setup -------------------------------- -2024-12-06 08:43:05.224 DEBUG MainThread asyncio:selector_events.py:64 Using selector: EpollSelector +2024-12-07 10:46:46.656 DEBUG MainThread asyncio:selector_events.py:64 Using selector: EpollSelector PASSED [ 67%] ------------------------------ live log teardown ------------------------------- -2024-12-06 08:43:05.231 DEBUG MainThread asyncio:selector_events.py:64 Using selector: EpollSelector +2024-12-07 10:46:46.678 DEBUG MainThread asyncio:selector_events.py:64 Using selector: EpollSelector tests/test_init.py::test_get_adapters_windows -------------------------------- live log setup -------------------------------- -2024-12-06 08:43:05.234 DEBUG MainThread asyncio:selector_events.py:64 Using selector: EpollSelector +2024-12-07 10:46:46.682 DEBUG MainThread asyncio:selector_events.py:64 Using selector: EpollSelector PASSED [ 71%] ------------------------------ live log teardown ------------------------------- -2024-12-06 08:43:05.241 DEBUG MainThread asyncio:selector_events.py:64 Using selector: EpollSelector +2024-12-07 10:46:46.701 DEBUG MainThread asyncio:selector_events.py:64 Using selector: EpollSelector tests/test_init.py::test_adapter_human_name PASSED [ 75%] tests/test_init.py::test_adapter_unique_name PASSED [ 78%] @@ -1220,18 +1256,18 @@ tests/test_init.py::test_discovered_device_advertisement_data_from_dict PASSED [ 89%] tests/test_init.py::test_expire_stale_scanner_discovered_device_advertisement_data -------------------------------- live log call --------------------------------- -2024-12-06 08:43:05.262 DEBUG MainThread bluetooth_adapters.storage:storage.py:116 Loaded 1 fresh discovered devices for myscanner -2024-12-06 08:43:05.263 DEBUG MainThread bluetooth_adapters.storage:storage.py:116 Loaded 0 fresh discovered devices for all_expired +2024-12-07 10:46:46.756 DEBUG MainThread bluetooth_adapters.storage:storage.py:116 Loaded 1 fresh discovered devices for myscanner +2024-12-07 10:46:46.757 DEBUG MainThread bluetooth_adapters.storage:storage.py:116 Loaded 0 fresh discovered devices for all_expired PASSED [ 92%] tests/test_init.py::test_expire_future_discovered_device_advertisement_data -------------------------------- live log call --------------------------------- -2024-12-06 08:43:05.267 DEBUG MainThread bluetooth_adapters.storage:storage.py:116 Loaded 1 fresh discovered devices for myscanner -2024-12-06 08:43:05.268 WARNING MainThread bluetooth_adapters.storage:storage.py:103 Discarding timestamp 1734517785.2673166 for CC:DD:EE:FF:AA:BB on scanner all_future as it is the future (now = 1733517785.2673457) -2024-12-06 08:43:05.268 DEBUG MainThread bluetooth_adapters.storage:storage.py:116 Loaded 0 fresh discovered devices for all_future +2024-12-07 10:46:46.761 DEBUG MainThread bluetooth_adapters.storage:storage.py:116 Loaded 1 fresh discovered devices for myscanner +2024-12-07 10:46:46.770 WARNING MainThread bluetooth_adapters.storage:storage.py:103 Discarding timestamp 1734518006.761424 for CC:DD:EE:FF:AA:BB on scanner all_future as it is the future (now = 1733518006.7614553) +2024-12-07 10:46:46.770 DEBUG MainThread bluetooth_adapters.storage:storage.py:116 Loaded 0 fresh discovered devices for all_future PASSED [ 96%] tests/test_init.py::test_discovered_device_advertisement_data_from_dict_corrupt -------------------------------- live log call --------------------------------- -2024-12-06 08:43:05.272 ERROR MainThread bluetooth_adapters.storage:storage.py:140 Error deserializing discovered_device_advertisement_data, adapter startup will be slow: BLEDevice.__init__() missing 1 required positional argument: 'name' +2024-12-07 10:46:46.784 ERROR MainThread bluetooth_adapters.storage:storage.py:140 Error deserializing discovered_device_advertisement_data, adapter startup will be slow: BLEDevice.__init__() missing 1 required positional argument: 'name' Traceback (most recent call last): File "/build/reproducible-path/python-bluetooth-adapters-0.20.2/.pybuild/cpython3_3.12_bluetooth-adapters/build/bluetooth_adapters/storage.py", line 132, in discovered_device_advertisement_data_from_dict _deserialize_discovered_device_advertisement_datas( @@ -1255,7 +1291,7 @@ assert out_ble_device.metadata == expected_ble_device.metadata -- Docs: https://docs.pytest.org/en/stable/how-to/capture-warnings.html -================= 28 passed, 1 deselected, 3 warnings in 0.77s ================= +================= 28 passed, 1 deselected, 3 warnings in 1.12s ================= create-stamp debian/debhelper-build-stamp dh_testroot -O--buildsystem=pybuild dh_prep -O--buildsystem=pybuild @@ -1284,12 +1320,14 @@ dpkg-buildpackage: info: binary-only upload (no source included) dpkg-genchanges: info: including full source code in upload I: copying local configuration +I: user script /srv/workspace/pbuilder/23158/tmp/hooks/B01_cleanup starting +I: user script /srv/workspace/pbuilder/23158/tmp/hooks/B01_cleanup finished I: unmounting dev/ptmx filesystem I: unmounting dev/pts filesystem I: unmounting dev/shm filesystem I: unmounting proc filesystem I: unmounting sys filesystem I: cleaning the build env -I: removing directory /srv/workspace/pbuilder/22542 and its subdirectories -I: Current time: Fri Dec 6 08:43:17 -12 2024 -I: pbuilder-time-stamp: 1733517797 +I: removing directory /srv/workspace/pbuilder/23158 and its subdirectories +I: Current time: Sat Dec 7 10:47:01 +14 2024 +I: pbuilder-time-stamp: 1733518021