0s autopkgtest [21:45:26]: starting date and time: 2025-03-15 21:45:26+0000 0s autopkgtest [21:45:26]: git checkout: 325255d2 Merge branch 'pin-any-arch' into 'ubuntu/production' 0s autopkgtest [21:45:26]: host juju-7f2275-prod-proposed-migration-environment-2; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.fi51svon/out --timeout-copy=6000 --setup-commands /home/ubuntu/autopkgtest-cloud/worker-config-production/setup-canonical.sh --apt-pocket=proposed=src:glibc --apt-upgrade upower --timeout-short=1200 --timeout-copy=80000 --timeout-build=80000 --env=ADT_TEST_TRIGGERS=glibc/2.41-1ubuntu2 -- ssh -s /home/ubuntu/autopkgtest/ssh-setup/nova -- --flavor builder-cpu2-ram4-disk20 --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-2@bos03-riscv64-10.secgroup --name adt-plucky-riscv64-upower-20250315-214526-juju-7f2275-prod-proposed-migration-environment-2-d2bf4afd-269b-4352-b4ae-673c0282f86f --image adt/ubuntu-plucky-riscv64-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-2 --net-id=net_prod-proposed-migration-riscv64 -e TERM=linux -e ''"'"'http_proxy=http://squid.internal:3128'"'"'' -e ''"'"'https_proxy=http://squid.internal:3128'"'"'' -e ''"'"'no_proxy=127.0.0.1,127.0.1.1,login.ubuntu.com,localhost,localdomain,novalocal,internal,archive.ubuntu.com,ports.ubuntu.com,security.ubuntu.com,ddebs.ubuntu.com,changelogs.ubuntu.com,keyserver.ubuntu.com,launchpadlibrarian.net,launchpadcontent.net,launchpad.net,10.24.0.0/24,keystone.ps5.canonical.com,objectstorage.prodstack5.canonical.com,radosgw.ps5.canonical.com'"'"'' --mirror=http://ftpmaster.internal/ubuntu/ 305s autopkgtest [21:50:31]: testbed dpkg architecture: riscv64 307s autopkgtest [21:50:33]: testbed apt version: 2.9.33 310s autopkgtest [21:50:36]: @@@@@@@@@@@@@@@@@@@@ test bed setup 311s autopkgtest [21:50:37]: testbed release detected to be: None 318s autopkgtest [21:50:44]: updating testbed package index (apt update) 322s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease [126 kB] 322s Hit:2 http://ftpmaster.internal/ubuntu plucky InRelease 322s Hit:3 http://ftpmaster.internal/ubuntu plucky-updates InRelease 322s Hit:4 http://ftpmaster.internal/ubuntu plucky-security InRelease 323s Get:5 http://ftpmaster.internal/ubuntu plucky-proposed/main Sources [44.1 kB] 324s Get:6 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse Sources [14.5 kB] 324s Get:7 http://ftpmaster.internal/ubuntu plucky-proposed/universe Sources [369 kB] 325s Get:8 http://ftpmaster.internal/ubuntu plucky-proposed/main riscv64 Packages [72.7 kB] 325s Get:9 http://ftpmaster.internal/ubuntu plucky-proposed/main riscv64 c-n-f Metadata [1792 B] 325s Get:10 http://ftpmaster.internal/ubuntu plucky-proposed/restricted riscv64 c-n-f Metadata [120 B] 325s Get:11 http://ftpmaster.internal/ubuntu plucky-proposed/universe riscv64 Packages [319 kB] 325s Get:12 http://ftpmaster.internal/ubuntu plucky-proposed/universe riscv64 c-n-f Metadata [14.3 kB] 325s Get:13 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse riscv64 Packages [3532 B] 325s Get:14 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse riscv64 c-n-f Metadata [272 B] 335s Fetched 965 kB in 5s (182 kB/s) 357s Reading package lists... 364s Reading package lists... 366s Building dependency tree... 366s Reading state information... 369s Calculating upgrade... 371s Calculating upgrade... 372s The following packages will be upgraded: 372s pinentry-curses python3-jinja2 strace 373s 3 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 373s Need to get 615 kB of archives. 373s After this operation, 27.6 kB of additional disk space will be used. 373s Get:1 http://ftpmaster.internal/ubuntu plucky/main riscv64 strace riscv64 6.13+ds-1ubuntu1 [460 kB] 373s Get:2 http://ftpmaster.internal/ubuntu plucky/main riscv64 pinentry-curses riscv64 1.3.1-2ubuntu3 [45.4 kB] 374s Get:3 http://ftpmaster.internal/ubuntu plucky/main riscv64 python3-jinja2 all 3.1.5-2ubuntu1 [109 kB] 381s Fetched 615 kB in 1s (549 kB/s) 384s (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 ... 114110 files and directories currently installed.) 384s Preparing to unpack .../strace_6.13+ds-1ubuntu1_riscv64.deb ... 384s Unpacking strace (6.13+ds-1ubuntu1) over (6.11-0ubuntu1) ... 385s Preparing to unpack .../pinentry-curses_1.3.1-2ubuntu3_riscv64.deb ... 385s Unpacking pinentry-curses (1.3.1-2ubuntu3) over (1.3.1-2ubuntu2) ... 387s Preparing to unpack .../python3-jinja2_3.1.5-2ubuntu1_all.deb ... 388s Unpacking python3-jinja2 (3.1.5-2ubuntu1) over (3.1.5-2) ... 389s Setting up pinentry-curses (1.3.1-2ubuntu3) ... 390s Setting up python3-jinja2 (3.1.5-2ubuntu1) ... 394s Setting up strace (6.13+ds-1ubuntu1) ... 394s Processing triggers for man-db (2.13.0-1) ... 406s Reading package lists... 409s Building dependency tree... 409s Reading state information... 412s Solving dependencies... 413s The following packages will be REMOVED: 413s libnsl2* libpython3.12-minimal* libpython3.12-stdlib* libpython3.12t64* 413s linux-headers-6.11.0-8-generic* linux-image-6.11.0-8-generic* 413s linux-modules-6.11.0-8-generic* linux-riscv-headers-6.11.0-8* 413s linux-riscv-tools-6.11.0-8* linux-tools-6.11.0-8-generic* 420s 0 upgraded, 0 newly installed, 10 to remove and 5 not upgraded. 420s After this operation, 355 MB disk space will be freed. 422s (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 ... 114110 files and directories currently installed.) 422s Removing linux-tools-6.11.0-8-generic (6.11.0-8.8.1) ... 422s Removing linux-riscv-tools-6.11.0-8 (6.11.0-8.8.1) ... 422s Removing libpython3.12t64:riscv64 (3.12.9-1) ... 423s Removing libpython3.12-stdlib:riscv64 (3.12.9-1) ... 425s Removing libnsl2:riscv64 (1.3.0-3build3) ... 425s Removing libpython3.12-minimal:riscv64 (3.12.9-1) ... 426s Removing linux-headers-6.11.0-8-generic (6.11.0-8.8.1) ... 433s Removing linux-image-6.11.0-8-generic (6.11.0-8.8.1) ... 438s I: /boot/vmlinuz.old is now a symlink to vmlinuz-6.14.0-7-generic 438s I: /boot/initrd.img.old is now a symlink to initrd.img-6.14.0-7-generic 438s /etc/kernel/postrm.d/initramfs-tools: 438s update-initramfs: Deleting /boot/initrd.img-6.11.0-8-generic 439s /etc/kernel/postrm.d/zz-u-boot-menu: 439s P: Checking for EXTLINUX directory... found. 440s P: Writing config for vmlinuz-6.14.0-7-generic... 440s P: Updating /boot/extlinux/extlinux.conf... 442s /etc/kernel/postrm.d/zz-update-grub: 442s Sourcing file `/etc/default/grub' 442s Generating grub configuration file ... 450s Found linux image: /boot/vmlinuz-6.14.0-7-generic 450s Found initrd image: /boot/initrd.img-6.14.0-7-generic 458s Warning: os-prober will not be executed to detect other bootable partitions. 458s Systems on them will not be added to the GRUB boot configuration. 458s Check GRUB_DISABLE_OS_PROBER documentation entry. 458s Adding boot menu entry for UEFI Firmware Settings ... 459s done 460s Removing linux-modules-6.11.0-8-generic (6.11.0-8.8.1) ... 468s Removing linux-riscv-headers-6.11.0-8 (6.11.0-8.8.1) ... 487s Processing triggers for libc-bin (2.41-1ubuntu1) ... 489s (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 ... 75587 files and directories currently installed.) 489s Purging configuration files for linux-image-6.11.0-8-generic (6.11.0-8.8.1) ... 491s /etc/kernel/postrm.d/zz-u-boot-menu: 491s P: Checking for EXTLINUX directory... found. 492s P: Writing config for vmlinuz-6.14.0-7-generic... 494s Purging configuration files for libpython3.12-minimal:riscv64 (3.12.9-1) ... 495s Purging configuration files for linux-modules-6.11.0-8-generic (6.11.0-8.8.1) ... 503s autopkgtest [21:53:49]: upgrading testbed (apt dist-upgrade and autopurge) 505s Reading package lists... 507s Building dependency tree... 507s Reading state information... 510s Calculating upgrade...Starting pkgProblemResolver with broken count: 0 510s Starting 2 pkgProblemResolver with broken count: 0 510s Done 516s Entering ResolveByKeep 518s 520s Calculating upgrade... 522s The following packages will be upgraded: 522s libc-bin libc-dev-bin libc6 libc6-dev locales 523s 5 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 523s Need to get 11.3 MB of archives. 523s After this operation, 2048 B of additional disk space will be used. 523s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed/main riscv64 libc6-dev riscv64 2.41-1ubuntu2 [3560 kB] 524s Get:2 http://ftpmaster.internal/ubuntu plucky-proposed/main riscv64 libc-dev-bin riscv64 2.41-1ubuntu2 [24.0 kB] 524s Get:3 http://ftpmaster.internal/ubuntu plucky-proposed/main riscv64 libc6 riscv64 2.41-1ubuntu2 [2845 kB] 525s Get:4 http://ftpmaster.internal/ubuntu plucky-proposed/main riscv64 libc-bin riscv64 2.41-1ubuntu2 [640 kB] 525s Get:5 http://ftpmaster.internal/ubuntu plucky-proposed/main riscv64 locales all 2.41-1ubuntu2 [4246 kB] 534s Preconfiguring packages ... 536s Fetched 11.3 MB in 4s (2701 kB/s) 538s (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 ... 75584 files and directories currently installed.) 538s Preparing to unpack .../libc6-dev_2.41-1ubuntu2_riscv64.deb ... 538s Unpacking libc6-dev:riscv64 (2.41-1ubuntu2) over (2.41-1ubuntu1) ... 547s Preparing to unpack .../libc-dev-bin_2.41-1ubuntu2_riscv64.deb ... 547s Unpacking libc-dev-bin (2.41-1ubuntu2) over (2.41-1ubuntu1) ... 548s Preparing to unpack .../libc6_2.41-1ubuntu2_riscv64.deb ... 553s Unpacking libc6:riscv64 (2.41-1ubuntu2) over (2.41-1ubuntu1) ... 558s Setting up libc6:riscv64 (2.41-1ubuntu2) ... 564s (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 ... 75584 files and directories currently installed.) 564s Preparing to unpack .../libc-bin_2.41-1ubuntu2_riscv64.deb ... 564s Unpacking libc-bin (2.41-1ubuntu2) over (2.41-1ubuntu1) ... 566s Setting up libc-bin (2.41-1ubuntu2) ... 568s (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 ... 75584 files and directories currently installed.) 568s Preparing to unpack .../locales_2.41-1ubuntu2_all.deb ... 568s Unpacking locales (2.41-1ubuntu2) over (2.41-1ubuntu1) ... 577s Setting up locales (2.41-1ubuntu2) ... 622s Generating locales (this might take a while)... 647s en_US.UTF-8... done 647s Generation complete. 648s Setting up libc-dev-bin (2.41-1ubuntu2) ... 648s Setting up libc6-dev:riscv64 (2.41-1ubuntu2) ... 648s Processing triggers for man-db (2.13.0-1) ... 656s Processing triggers for systemd (257.3-1ubuntu3) ... 681s Reading package lists... 683s Building dependency tree... 683s Reading state information... 687s Starting pkgProblemResolver with broken count: 0 688s Starting 2 pkgProblemResolver with broken count: 0 688s Done 692s Solving dependencies... 693s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 695s autopkgtest [21:57:01]: rebooting testbed after setup commands that affected boot 707s autopkgtest-virt-ssh: WARNING: ssh connection failed. Retrying in 3 seconds... 740s autopkgtest-virt-ssh: WARNING: ssh connection failed. Retrying in 3 seconds... 773s autopkgtest-virt-ssh: WARNING: ssh connection failed. Retrying in 3 seconds... 806s autopkgtest-virt-ssh: WARNING: ssh connection failed. Retrying in 3 seconds... 839s autopkgtest-virt-ssh: WARNING: ssh connection failed. Retrying in 3 seconds... 874s autopkgtest [22:00:00]: testbed running kernel: Linux 6.14.0-7-generic #7.1-Ubuntu SMP PREEMPT_DYNAMIC Sat Mar 1 14:36:03 UTC 2025 894s autopkgtest [22:00:20]: @@@@@@@@@@@@@@@@@@@@ apt-source upower 941s Get:1 http://ftpmaster.internal/ubuntu plucky/main upower 1.90.7-1build1 (dsc) [2926 B] 941s Get:2 http://ftpmaster.internal/ubuntu plucky/main upower 1.90.7-1build1 (tar) [153 kB] 941s Get:3 http://ftpmaster.internal/ubuntu plucky/main upower 1.90.7-1build1 (diff) [16.1 kB] 945s gpgv: Signature made Thu Feb 20 20:17:08 2025 UTC 945s gpgv: using RSA key 4D0BE12F0E4776D8AACE9696E66C775AEBFE6C7D 945s gpgv: Can't check signature: No public key 945s dpkg-source: warning: cannot verify inline signature for ./upower_1.90.7-1build1.dsc: no acceptable signature found 948s autopkgtest [22:01:14]: testing package upower version 1.90.7-1build1 950s autopkgtest [22:01:16]: build not needed 954s autopkgtest [22:01:20]: test installed-tests: preparing testbed 959s Reading package lists... 962s Building dependency tree... 962s Reading state information... 964s Starting pkgProblemResolver with broken count: 0 965s Starting 2 pkgProblemResolver with broken count: 0 965s Done 971s The following NEW packages will be installed: 971s gir1.2-umockdev-1.0 gir1.2-upowerglib-1.0 gnome-desktop-testing 971s libduktape207 libimobiledevice-1.0-6 libimobiledevice-glue-1.0-0 971s libplist-2.0-4 libumockdev0 libupower-glib3 libusbmuxd-2.0-7 polkitd 971s python3-dbusmock python3-packaging sgml-base umockdev upower upower-tests 971s xml-core 972s 0 upgraded, 18 newly installed, 0 to remove and 0 not upgraded. 972s Need to get 1043 kB of archives. 972s After this operation, 3806 kB of additional disk space will be used. 972s Get:1 http://ftpmaster.internal/ubuntu plucky/main riscv64 sgml-base all 1.31 [11.4 kB] 972s Get:2 http://ftpmaster.internal/ubuntu plucky/universe riscv64 libumockdev0 riscv64 0.19.1-3 [82.0 kB] 972s Get:3 http://ftpmaster.internal/ubuntu plucky/universe riscv64 gir1.2-umockdev-1.0 riscv64 0.19.1-3 [3764 B] 972s Get:4 http://ftpmaster.internal/ubuntu plucky/main riscv64 libupower-glib3 riscv64 1.90.7-1build1 [41.3 kB] 972s Get:5 http://ftpmaster.internal/ubuntu plucky/main riscv64 gir1.2-upowerglib-1.0 riscv64 1.90.7-1build1 [5432 B] 972s Get:6 http://ftpmaster.internal/ubuntu plucky/universe riscv64 gnome-desktop-testing riscv64 2021.1-4 [16.1 kB] 972s Get:7 http://ftpmaster.internal/ubuntu plucky/main riscv64 libduktape207 riscv64 2.7.0+tests-0ubuntu3 [152 kB] 972s Get:8 http://ftpmaster.internal/ubuntu plucky/main riscv64 libplist-2.0-4 riscv64 2.6.0-2build1 [56.8 kB] 972s Get:9 http://ftpmaster.internal/ubuntu plucky/main riscv64 libimobiledevice-glue-1.0-0 riscv64 1.3.1-1 [34.1 kB] 972s Get:10 http://ftpmaster.internal/ubuntu plucky/main riscv64 libusbmuxd-2.0-7 riscv64 2.1.0-1build1 [18.8 kB] 972s Get:11 http://ftpmaster.internal/ubuntu plucky/main riscv64 libimobiledevice-1.0-6 riscv64 1.3.0+git20240701-4build2 [151 kB] 972s Get:12 http://ftpmaster.internal/ubuntu plucky/main riscv64 xml-core all 0.19 [20.3 kB] 972s Get:13 http://ftpmaster.internal/ubuntu plucky/main riscv64 polkitd riscv64 126-2 [98.8 kB] 972s Get:14 http://ftpmaster.internal/ubuntu plucky/universe riscv64 python3-dbusmock all 0.34.3-1 [92.4 kB] 972s Get:15 http://ftpmaster.internal/ubuntu plucky/main riscv64 python3-packaging all 24.2-1 [51.5 kB] 972s Get:16 http://ftpmaster.internal/ubuntu plucky/universe riscv64 umockdev riscv64 0.19.1-3 [86.1 kB] 972s Get:17 http://ftpmaster.internal/ubuntu plucky/main riscv64 upower riscv64 1.90.7-1build1 [94.6 kB] 973s Get:18 http://ftpmaster.internal/ubuntu plucky/universe riscv64 upower-tests riscv64 1.90.7-1build1 [26.6 kB] 981s Fetched 1043 kB in 1s (741 kB/s) 982s Selecting previously unselected package sgml-base. 984s (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 ... 75584 files and directories currently installed.) 984s Preparing to unpack .../00-sgml-base_1.31_all.deb ... 984s Unpacking sgml-base (1.31) ... 985s Selecting previously unselected package libumockdev0:riscv64. 985s Preparing to unpack .../01-libumockdev0_0.19.1-3_riscv64.deb ... 985s Unpacking libumockdev0:riscv64 (0.19.1-3) ... 986s Selecting previously unselected package gir1.2-umockdev-1.0:riscv64. 986s Preparing to unpack .../02-gir1.2-umockdev-1.0_0.19.1-3_riscv64.deb ... 986s Unpacking gir1.2-umockdev-1.0:riscv64 (0.19.1-3) ... 986s Selecting previously unselected package libupower-glib3:riscv64. 987s Preparing to unpack .../03-libupower-glib3_1.90.7-1build1_riscv64.deb ... 987s Unpacking libupower-glib3:riscv64 (1.90.7-1build1) ... 987s Selecting previously unselected package gir1.2-upowerglib-1.0:riscv64. 987s Preparing to unpack .../04-gir1.2-upowerglib-1.0_1.90.7-1build1_riscv64.deb ... 987s Unpacking gir1.2-upowerglib-1.0:riscv64 (1.90.7-1build1) ... 988s Selecting previously unselected package gnome-desktop-testing. 988s Preparing to unpack .../05-gnome-desktop-testing_2021.1-4_riscv64.deb ... 988s Unpacking gnome-desktop-testing (2021.1-4) ... 988s Selecting previously unselected package libduktape207:riscv64. 989s Preparing to unpack .../06-libduktape207_2.7.0+tests-0ubuntu3_riscv64.deb ... 989s Unpacking libduktape207:riscv64 (2.7.0+tests-0ubuntu3) ... 989s Selecting previously unselected package libplist-2.0-4:riscv64. 989s Preparing to unpack .../07-libplist-2.0-4_2.6.0-2build1_riscv64.deb ... 989s Unpacking libplist-2.0-4:riscv64 (2.6.0-2build1) ... 990s Selecting previously unselected package libimobiledevice-glue-1.0-0. 990s Preparing to unpack .../08-libimobiledevice-glue-1.0-0_1.3.1-1_riscv64.deb ... 990s Unpacking libimobiledevice-glue-1.0-0 (1.3.1-1) ... 991s Selecting previously unselected package libusbmuxd-2.0-7:riscv64. 991s Preparing to unpack .../09-libusbmuxd-2.0-7_2.1.0-1build1_riscv64.deb ... 991s Unpacking libusbmuxd-2.0-7:riscv64 (2.1.0-1build1) ... 992s Selecting previously unselected package libimobiledevice-1.0-6:riscv64. 992s Preparing to unpack .../10-libimobiledevice-1.0-6_1.3.0+git20240701-4build2_riscv64.deb ... 992s Unpacking libimobiledevice-1.0-6:riscv64 (1.3.0+git20240701-4build2) ... 992s Selecting previously unselected package xml-core. 992s Preparing to unpack .../11-xml-core_0.19_all.deb ... 993s Unpacking xml-core (0.19) ... 993s Selecting previously unselected package polkitd. 993s Preparing to unpack .../12-polkitd_126-2_riscv64.deb ... 995s Unpacking polkitd (126-2) ... 995s Selecting previously unselected package python3-dbusmock. 996s Preparing to unpack .../13-python3-dbusmock_0.34.3-1_all.deb ... 996s Unpacking python3-dbusmock (0.34.3-1) ... 996s Selecting previously unselected package python3-packaging. 997s Preparing to unpack .../14-python3-packaging_24.2-1_all.deb ... 997s Unpacking python3-packaging (24.2-1) ... 997s Selecting previously unselected package umockdev. 998s Preparing to unpack .../15-umockdev_0.19.1-3_riscv64.deb ... 998s Unpacking umockdev (0.19.1-3) ... 998s Selecting previously unselected package upower. 998s Preparing to unpack .../16-upower_1.90.7-1build1_riscv64.deb ... 998s Unpacking upower (1.90.7-1build1) ... 999s Selecting previously unselected package upower-tests. 999s Preparing to unpack .../17-upower-tests_1.90.7-1build1_riscv64.deb ... 999s Unpacking upower-tests (1.90.7-1build1) ... 1000s Setting up gnome-desktop-testing (2021.1-4) ... 1000s Setting up python3-dbusmock (0.34.3-1) ... 1005s Setting up python3-packaging (24.2-1) ... 1009s Setting up libumockdev0:riscv64 (0.19.1-3) ... 1009s Setting up libplist-2.0-4:riscv64 (2.6.0-2build1) ... 1009s Setting up libduktape207:riscv64 (2.7.0+tests-0ubuntu3) ... 1009s Setting up libupower-glib3:riscv64 (1.90.7-1build1) ... 1009s Setting up sgml-base (1.31) ... 1010s Setting up umockdev (0.19.1-3) ... 1010s Setting up gir1.2-umockdev-1.0:riscv64 (0.19.1-3) ... 1010s Setting up libimobiledevice-glue-1.0-0 (1.3.1-1) ... 1010s Setting up xml-core (0.19) ... 1020s Setting up gir1.2-upowerglib-1.0:riscv64 (1.90.7-1build1) ... 1020s Setting up libusbmuxd-2.0-7:riscv64 (2.1.0-1build1) ... 1020s Setting up libimobiledevice-1.0-6:riscv64 (1.3.0+git20240701-4build2) ... 1020s Setting up upower (1.90.7-1build1) ... 1030s upower.service is a disabled or a static unit, not starting it. 1030s Setting up upower-tests (1.90.7-1build1) ... 1030s Processing triggers for libc-bin (2.41-1ubuntu2) ... 1031s Processing triggers for man-db (2.13.0-1) ... 1039s Processing triggers for dbus (1.16.2-1ubuntu1) ... 1039s Processing triggers for udev (257.3-1ubuntu3) ... 1048s Processing triggers for sgml-base (1.31) ... 1048s Setting up polkitd (126-2) ... 1077s autopkgtest [22:03:23]: test installed-tests: [----------------------- 1080s Running test: upower/upower-integration.test 1085s Executing: upower/upower-integration.test 1090s Testing installed system binaries 1090s Executing: upower/upower-integration.test 1095s Executing: upower/upower-integration.test 1097s 1742076223.423 GetAll /org/freedesktop/PolicyKit1/Authority org.freedesktop.PolicyKit1.Authority 1097s 1742076223.517 GetAll /org/freedesktop/login1 org.freedesktop.login1.Manager 1097s 1742076223.548 Inhibit "sleep" "UPower" "Pause device polling" "delay" 1097s TI:22:03:43 Acquired inhibitor lock (7, delay) 1097s TI:22:03:43 Starting upowerd version 1.90.7 1097s TI:22:03:43 cannot find a keyboard backlight 1097s TI:22:03:43 Exported UpDevice with path /org/freedesktop/UPower/devices/DisplayDevice 1097s TI:22:03:43 daemon now coldplug 1097s TI:22:03:43 not a switch [/tmp/umockdev.9FSN32/sys/devices/usb1/bluetooth/hci0/hci0:01/input2/mouse3/../capabilities/sw] 1097s TI:22:03:43 not a switch [/tmp/umockdev.9FSN32/sys/devices/usb1/bluetooth/hci0/hci0:01/input2/mouse3/capabilities/sw] 1097s TI:22:03:43 registering subsystem : input 1097s TI:22:03:43 Received uevent add on device /sys/devices/usb1/bluetooth/hci0/hci0:01/input2/mouse3 1097s TI:22:03:43 device /sys/devices/usb1/bluetooth/hci0/hci0:01/input2/mouse3 has parent id: /sys/devices/usb1/bluetooth/hci0/hci0:01 1097s TI:22:03:43 registering subsystem : power_supply 1097s TI:22:03:43 Received uevent add on device /sys/devices/usb1/bluetooth/hci0/hci0:01/1/power_supply/hid-11:22:33:44:aa:bb-battery 1097s TI:22:03:43 failed to coldplug hid-11:22:33:44:aa:bb-battery 1097s TI:22:03:43 using id: generic_id 1097s TI:22:03:43 failed to get data from /tmp/upower-history-xna8luhg/history-rate-generic_id.dat as file does not exist 1097s TI:22:03:43 failed to get data from /tmp/upower-history-xna8luhg/history-charge-generic_id.dat as file does not exist 1097s TI:22:03:43 failed to get data from /tmp/upower-history-xna8luhg/history-time-full-generic_id.dat as file does not exist 1097s TI:22:03:43 failed to get data from /tmp/upower-history-xna8luhg/history-time-empty-generic_id.dat as file does not exist 1097s TI:22:03:43 saving in 600 seconds 1097s TI:22:03:43 device hid-11:22:33:44:aa:bb-battery has unknown state, not saving history 1097s TI:22:03:43 culled 0 of 1 1097s TI:22:03:43 saved /tmp/upower-history-xna8luhg/history-rate-generic_id.dat 1097s TI:22:03:43 culled 0 of 1 1097s TI:22:03:43 saved /tmp/upower-history-xna8luhg/history-charge-generic_id.dat 1097s TI:22:03:43 culled 0 of 1 1097s TI:22:03:43 saved /tmp/upower-history-xna8luhg/history-time-full-generic_id.dat 1097s TI:22:03:43 culled 0 of 1 1097s TI:22:03:43 saved /tmp/upower-history-xna8luhg/history-time-empty-generic_id.dat 1097s TI:22:03:43 capacity_level doesn't exist, skipping 1097s TI:22:03:43 no changes 1097s TI:22:03:43 failed to refresh hid-11:22:33:44:aa:bb-battery 1097s TI:22:03:43 Exported UpDevice with path /org/freedesktop/UPower/devices/battery_hid_11o22o33o44oaaobb_battery 1097s TI:22:03:43 device hid-11:22:33:44:aa:bb-battery has parent id: /sys/devices/usb1/bluetooth/hci0/hci0:01 1097s TI:22:03:43 up_device_supply_sibling_discovered (device: /sys/devices/usb1/bluetooth/hci0/hci0:01/1/power_supply/hid-11:22:33:44:aa:bb-battery, sibling: /sys/devices/usb1/bluetooth/hci0/hci0:01/input2/mouse3) 1097s TI:22:03:43 Type changed from battery to mouse 1097s TI:22:03:43 Got new device from udev enumerator: 0x55571f8959b0 1097s TI:22:03:43 added hid-11:22:33:44:aa:bb-battery 1097s TI:22:03:43 emitting added: /org/freedesktop/UPower/devices/battery_hid_11o22o33o44oaaobb_battery 1097s TI:22:03:43 registering subsystem : usb 1097s TI:22:03:43 registering subsystem : usbmisc 1097s TI:22:03:43 registering subsystem : sound 1097s TI:22:03:43 BlueZ disappeared 1097s TI:22:03:43 on_battery = no 1097s TI:22:03:43 warning_level = none 1097s TI:22:03:43 device (null) has unknown state, not saving history 1097s TI:22:03:43 daemon now not coldplug 1098s TI:22:03:44 Received uevent change on device /sys/devices/usb1/bluetooth/hci0/hci0:01/1/power_supply/hid-11:22:33:44:aa:bb-battery 1098s TI:22:03:44 refreshing device for path /sys/devices/usb1/bluetooth/hci0/hci0:01/1/power_supply/hid-11:22:33:44:aa:bb-battery 1098s TI:22:03:44 using id: Fancy_BT_mouse-11:22:33:44:aa:bb 1098s TI:22:03:44 failed to get data from /tmp/upower-history-xna8luhg/history-rate-Fancy_BT_mouse-11:22:33:44:aa:bb.dat as file does not exist 1098s TI:22:03:44 failed to get data from /tmp/upower-history-xna8luhg/history-charge-Fancy_BT_mouse-11:22:33:44:aa:bb.dat as file does not exist 1098s TI:22:03:44 failed to get data from /tmp/upower-history-xna8luhg/history-time-full-Fancy_BT_mouse-11:22:33:44:aa:bb.dat as file does not exist 1098s TI:22:03:44 failed to get data from /tmp/upower-history-xna8luhg/history-time-empty-Fancy_BT_mouse-11:22:33:44:aa:bb.dat as file does not exist 1098s TI:22:03:44 saving in 600 seconds 1098s TI:22:03:44 saving in 600 seconds 1098s TI:22:03:44 added native-path: hid-11:22:33:44:aa:bb-battery 1098s TI:22:03:44 on_battery = no 1098s TI:22:03:44 Received uevent change on device /sys/devices/usb1/bluetooth/hci0/hci0:01/1/power_supply/hid-11:22:33:44:aa:bb-battery 1098s TI:22:03:44 refreshing device for path /sys/devices/usb1/bluetooth/hci0/hci0:01/1/power_supply/hid-11:22:33:44:aa:bb-battery 1098s TI:22:03:44 capacity_level doesn't exist, skipping 1098s TI:22:03:44 no changes 1098s TI:22:03:44 no changes on /org/freedesktop/UPower/devices/battery_hid_11o22o33o44oaaobb_battery 1098s TI:22:03:44 on_battery = no 1098s TI:22:03:44 Handling SIGTERM 1098s TI:22:03:44 culled 0 of 1 1098s TI:22:03:44 saved /tmp/upower-history-xna8luhg/history-rate-Fancy_BT_mouse-11:22:33:44:aa:bb.dat 1098s TI:22:03:44 culled 0 of 2 1098s TI:22:03:44 saved /tmp/upower-history-xna8luhg/history-charge-Fancy_BT_mouse-11:22:33:44:aa:bb.dat 1098s TI:22:03:44 culled 0 of 1 1098s TI:22:03:44 saved /tmp/upower-history-xna8luhg/history-time-full-Fancy_BT_mouse-11:22:33:44:aa:bb.dat 1098s TI:22:03:44 culled 0 of 1 1098s TI:22:03:44 saved /tmp/upower-history-xna8luhg/history-time-empty-Fancy_BT_mouse-11:22:33:44:aa:bb.dat 1100s .Executing: upower/upower-integration.test 1103s 1742076229.611 GetAll /org/freedesktop/PolicyKit1/Authority org.freedesktop.PolicyKit1.Authority 1103s 1742076229.718 GetAll /org/freedesktop/login1 org.freedesktop.login1.Manager 1103s 1742076229.755 Inhibit "sleep" "UPower" "Pause device polling" "delay" 1103s TI:22:03:49 Acquired inhibitor lock (7, delay) 1103s TI:22:03:49 Starting upowerd version 1.90.7 1103s TI:22:03:49 cannot find a keyboard backlight 1103s TI:22:03:49 Exported UpDevice with path /org/freedesktop/UPower/devices/DisplayDevice 1103s TI:22:03:49 daemon now coldplug 1103s TI:22:03:49 registering subsystem : input 1103s TI:22:03:49 registering subsystem : power_supply 1103s TI:22:03:49 Received uevent add on device /sys/devices/AC 1103s TI:22:03:49 failed to coldplug AC 1103s TI:22:03:49 using id: generic_id 1103s TI:22:03:49 failed to get data from /tmp/upower-history-lk02hwa9/history-rate-generic_id.dat as file does not exist 1103s TI:22:03:49 failed to get data from /tmp/upower-history-lk02hwa9/history-charge-generic_id.dat as file does not exist 1103s TI:22:03:49 failed to get data from /tmp/upower-history-lk02hwa9/history-time-full-generic_id.dat as file does not exist 1103s TI:22:03:49 failed to get data from /tmp/upower-history-lk02hwa9/history-time-empty-generic_id.dat as file does not exist 1103s TI:22:03:49 saving in 600 seconds 1103s TI:22:03:49 device AC has unknown state, not saving history 1103s TI:22:03:49 taking a guess for power supply scope 1103s TI:22:03:49 culled 0 of 1 1103s TI:22:03:49 saved /tmp/upower-history-lk02hwa9/history-rate-generic_id.dat 1103s TI:22:03:49 culled 0 of 1 1103s TI:22:03:49 saved /tmp/upower-history-lk02hwa9/history-charge-generic_id.dat 1103s TI:22:03:49 culled 0 of 1 1103s TI:22:03:49 saved /tmp/upower-history-lk02hwa9/history-time-full-generic_id.dat 1103s TI:22:03:49 culled 0 of 1 1103s TI:22:03:49 saved /tmp/upower-history-lk02hwa9/history-time-empty-generic_id.dat 1103s TI:22:03:49 device AC has unknown state, not saving history 1103s TI:22:03:49 added native-path: AC 1103s TI:22:03:49 Exported UpDevice with path /org/freedesktop/UPower/devices/line_power_AC 1103s TI:22:03:49 device AC has parent id: (null) 1103s TI:22:03:49 Got new device from udev enumerator: 0x555d6b14e970 1103s TI:22:03:49 added AC 1103s TI:22:03:49 emitting added: /org/freedesktop/UPower/devices/line_power_AC 1103s TI:22:03:49 Received uevent add on device /sys/devices/BAT0 1103s TI:22:03:49 using present voltage (alternate) 1103s TI:22:03:49 failed to read battery charge threshold: Failed to open file “/tmp/upower-history-lk02hwa9/charging-threshold-status”: No such file or directory 1103s TI:22:03:49 using id: 80 1103s TI:22:03:49 failed to get data from /tmp/upower-history-lk02hwa9/history-rate-80.dat as file does not exist 1103s TI:22:03:49 failed to get data from /tmp/upower-history-lk02hwa9/history-charge-80.dat as file does not exist 1103s TI:22:03:49 failed to get data from /tmp/upower-history-lk02hwa9/history-time-full-80.dat as file does not exist 1103s TI:22:03:49 failed to get data from /tmp/upower-history-lk02hwa9/history-time-empty-80.dat as file does not exist 1103s TI:22:03:49 saving in 600 seconds 1103s TI:22:03:49 deferring as earlier timeout is already queued 1103s TI:22:03:49 added native-path: BAT0 1103s TI:22:03:49 Exported UpDevice with path /org/freedesktop/UPower/devices/battery_BAT0 1103s TI:22:03:49 device BAT0 has parent id: (null) 1103s TI:22:03:49 Got new device from udev enumerator: 0x555d6b151210 1103s TI:22:03:49 added BAT0 1103s TI:22:03:49 emitting added: /org/freedesktop/UPower/devices/battery_BAT0 1103s TI:22:03:49 registering subsystem : usb 1103s TI:22:03:49 registering subsystem : usbmisc 1103s TI:22:03:49 registering subsystem : sound 1103s TI:22:03:49 on_battery = yes 1103s TI:22:03:49 warning_level = none 1104s TI:22:03:49 daemon now not coldplug 1104s TI:22:03:49 BlueZ disappeared 1104s TI:22:03:50 Handling SIGTERM 1104s TI:22:03:50 culled 0 of 1 1104s TI:22:03:50 saved /tmp/upower-history-lk02hwa9/history-rate-80.dat 1104s TI:22:03:50 culled 0 of 2 1104s TI:22:03:50 saved /tmp/upower-history-lk02hwa9/history-charge-80.dat 1104s TI:22:03:50 culled 0 of 1 1104s TI:22:03:50 saved /tmp/upower-history-lk02hwa9/history-time-full-80.dat 1104s TI:22:03:50 culled 0 of 1 1104s TI:22:03:50 saved /tmp/upower-history-lk02hwa9/history-time-empty-80.dat 1105s 1742076230.984 GetAll /org/freedesktop/PolicyKit1/Authority org.freedesktop.PolicyKit1.Authority 1105s 1742076231.116 GetAll /org/freedesktop/login1 org.freedesktop.login1.Manager 1105s 1742076231.136 Inhibit "sleep" "UPower" "Pause device polling" "delay" 1105s TI:22:03:51 Acquired inhibitor lock (7, delay) 1105s TI:22:03:51 Starting upowerd version 1.90.7 1105s TI:22:03:51 cannot find a keyboard backlight 1105s TI:22:03:51 Exported UpDevice with path /org/freedesktop/UPower/devices/DisplayDevice 1105s TI:22:03:51 daemon now coldplug 1105s TI:22:03:51 registering subsystem : input 1105s TI:22:03:51 registering subsystem : power_supply 1105s TI:22:03:51 Received uevent add on device /sys/devices/AC 1105s TI:22:03:51 failed to coldplug AC 1105s TI:22:03:51 using id: generic_id 1105s TI:22:03:51 failed to get data from /tmp/upower-history-nqlnazv4/history-rate-generic_id.dat as file does not exist 1105s TI:22:03:51 failed to get data from /tmp/upower-history-nqlnazv4/history-charge-generic_id.dat as file does not exist 1105s TI:22:03:51 failed to get data from /tmp/upower-history-nqlnazv4/history-time-full-generic_id.dat as file does not exist 1105s TI:22:03:51 failed to get data from /tmp/upower-history-nqlnazv4/history-time-empty-generic_id.dat as file does not exist 1105s TI:22:03:51 saving in 600 seconds 1105s TI:22:03:51 device AC has unknown state, not saving history 1105s TI:22:03:51 taking a guess for power supply scope 1105s TI:22:03:51 culled 0 of 1 1105s TI:22:03:51 saved /tmp/upower-history-nqlnazv4/history-rate-generic_id.dat 1105s TI:22:03:51 culled 0 of 1 1105s TI:22:03:51 saved /tmp/upower-history-nqlnazv4/history-charge-generic_id.dat 1105s TI:22:03:51 culled 0 of 1 1105s TI:22:03:51 saved /tmp/upower-history-nqlnazv4/history-time-full-generic_id.dat 1105s TI:22:03:51 culled 0 of 1 1105s TI:22:03:51 saved /tmp/upower-history-nqlnazv4/history-time-empty-generic_id.dat 1105s TI:22:03:51 device AC has unknown state, not saving history 1105s TI:22:03:51 added native-path: AC 1105s TI:22:03:51 Exported UpDevice with path /org/freedesktop/UPower/devices/line_power_AC 1105s TI:22:03:51 device AC has parent id: (null) 1105s TI:22:03:51 Got new device from udev enumerator: 0x55612eb3ca40 1105s TI:22:03:51 added AC 1105s TI:22:03:51 emitting added: /org/freedesktop/UPower/devices/line_power_AC 1105s TI:22:03:51 Received uevent add on device /sys/devices/BAT0 1105s TI:22:03:51 using present voltage (alternate) 1105s TI:22:03:51 failed to read battery charge threshold: Failed to open file “/tmp/upower-history-nqlnazv4/charging-threshold-status”: No such file or directory 1105s TI:22:03:51 using id: 80 1105s TI:22:03:51 failed to get data from /tmp/upower-history-nqlnazv4/history-rate-80.dat as file does not exist 1105s TI:22:03:51 failed to get data from /tmp/upower-history-nqlnazv4/history-charge-80.dat as file does not exist 1105s TI:22:03:51 failed to get data from /tmp/upower-history-nqlnazv4/history-time-full-80.dat as file does not exist 1105s TI:22:03:51 failed to get data from /tmp/upower-history-nqlnazv4/history-time-empty-80.dat as file does not exist 1105s TI:22:03:51 saving in 600 seconds 1105s TI:22:03:51 saving to disk earlier due to low power 1105s TI:22:03:51 saving in 5 seconds 1105s TI:22:03:51 added native-path: BAT0 1105s TI:22:03:51 Exported UpDevice with path /org/freedesktop/UPower/devices/battery_BAT0 1105s TI:22:03:51 device BAT0 has parent id: (null) 1105s TI:22:03:51 Got new device from udev enumerator: 0x55612eb3f2e0 1105s TI:22:03:51 added BAT0 1105s TI:22:03:51 emitting added: /org/freedesktop/UPower/devices/battery_BAT0 1105s TI:22:03:51 registering subsystem : usb 1105s TI:22:03:51 registering subsystem : usbmisc 1105s TI:22:03:51 registering subsystem : sound 1105s TI:22:03:51 on_battery = yes 1105s TI:22:03:51 warning_level = critical 1105s TI:22:03:51 daemon now not coldplug 1105s TI:22:03:51 BlueZ disappeared 1105s TI:22:03:51 Handling SIGTERM 1105s TI:22:03:51 culled 0 of 1 1105s TI:22:03:51 saved /tmp/upower-history-nqlnazv4/history-rate-80.dat 1105s TI:22:03:51 culled 0 of 2 1105s TI:22:03:51 saved /tmp/upower-history-nqlnazv4/history-charge-80.dat 1105s TI:22:03:51 culled 0 of 1 1105s TI:22:03:51 saved /tmp/upower-history-nqlnazv4/history-time-full-80.dat 1105s TI:22:03:51 culled 0 of 1 1105s TI:22:03:51 saved /tmp/upower-history-nqlnazv4/history-time-empty-80.dat 1105s Executing: upower/upower-integration.test 1106s 1742076232.118 GetAll /org/freedesktop/PolicyKit1/Authority org.freedesktop.PolicyKit1.Authority 1106s 1742076232.180 GetAll /org/freedesktop/login1 org.freedesktop.login1.Manager 1106s 1742076232.198 Inhibit "sleep" "UPower" "Pause device polling" "delay" 1106s TI:22:03:52 Acquired inhibitor lock (7, delay) 1106s TI:22:03:52 Starting upowerd version 1.90.7 1106s TI:22:03:52 cannot find a keyboard backlight 1106s TI:22:03:52 Exported UpDevice with path /org/freedesktop/UPower/devices/DisplayDevice 1106s TI:22:03:52 daemon now coldplug 1106s TI:22:03:52 registering subsystem : input 1106s TI:22:03:52 registering subsystem : power_supply 1106s TI:22:03:52 Received uevent add on device /sys/devices/AC 1106s TI:22:03:52 failed to coldplug AC 1106s TI:22:03:52 using id: generic_id 1106s TI:22:03:52 failed to get data from /tmp/upower-history-jq65x8mq/history-rate-generic_id.dat as file does not exist 1106s TI:22:03:52 failed to get data from /tmp/upower-history-jq65x8mq/history-charge-generic_id.dat as file does not exist 1106s TI:22:03:52 failed to get data from /tmp/upower-history-jq65x8mq/history-time-full-generic_id.dat as file does not exist 1106s TI:22:03:52 failed to get data from /tmp/upower-history-jq65x8mq/history-time-empty-generic_id.dat as file does not exist 1106s TI:22:03:52 saving in 600 seconds 1106s TI:22:03:52 device AC has unknown state, not saving history 1106s TI:22:03:52 taking a guess for power supply scope 1106s TI:22:03:52 culled 0 of 1 1106s TI:22:03:52 saved /tmp/upower-history-jq65x8mq/history-rate-generic_id.dat 1106s TI:22:03:52 culled 0 of 1 1106s TI:22:03:52 saved /tmp/upower-history-jq65x8mq/history-charge-generic_id.dat 1106s TI:22:03:52 culled 0 of 1 1106s TI:22:03:52 saved /tmp/upower-history-jq65x8mq/history-time-full-generic_id.dat 1106s TI:22:03:52 culled 0 of 1 1106s TI:22:03:52 saved /tmp/upower-history-jq65x8mq/history-time-empty-generic_id.dat 1106s TI:22:03:52 device AC has unknown state, not saving history 1106s TI:22:03:52 added native-path: AC 1106s TI:22:03:52 Exported UpDevice with path /org/freedesktop/UPower/devices/line_power_AC 1106s TI:22:03:52 device AC has parent id: (null) 1106s TI:22:03:52 Got new device from udev enumerator: 0x5559f0d4f980 1106s TI:22:03:52 added AC 1106s TI:22:03:52 emitting added: /org/freedesktop/UPower/devices/line_power_AC 1106s TI:22:03:52 Received uevent add on device /sys/devices/BAT0 1106s TI:22:03:52 using present voltage (alternate) 1106s TI:22:03:52 failed to read battery charge threshold: Failed to open file “/tmp/upower-history-jq65x8mq/charging-threshold-status”: No such file or directory 1106s TI:22:03:52 using id: 80 1106s TI:22:03:52 failed to get data from /tmp/upower-history-jq65x8mq/history-rate-80.dat as file does not exist 1106s TI:22:03:52 failed to get data from /tmp/upower-history-jq65x8mq/history-charge-80.dat as file does not exist 1106s TI:22:03:52 failed to get data from /tmp/upower-history-jq65x8mq/history-time-full-80.dat as file does not exist 1106s TI:22:03:52 failed to get data from /tmp/upower-history-jq65x8mq/history-time-empty-80.dat as file does not exist 1106s TI:22:03:52 saving in 600 seconds 1106s TI:22:03:52 saving to disk earlier due to low power 1106s TI:22:03:52 saving in 5 seconds 1106s TI:22:03:52 added native-path: BAT0 1106s TI:22:03:52 Exported UpDevice with path /org/freedesktop/UPower/devices/battery_BAT0 1106s TI:22:03:52 device BAT0 has parent id: (null) 1106s TI:22:03:52 Got new device from udev enumerator: 0x5559f0d52220 1106s TI:22:03:52 added BAT0 1106s TI:22:03:52 emitting added: /org/freedesktop/UPower/devices/battery_BAT0 1106s TI:22:03:52 registering subsystem : usb 1106s TI:22:03:52 registering subsystem : usbmisc 1106s TI:22:03:52 registering subsystem : sound 1106s TI:22:03:52 on_battery = no 1106s TI:22:03:52 warning_level = none 1106s TI:22:03:52 daemon now not coldplug 1106s TI:22:03:52 BlueZ disappeared 1106s TI:22:03:52 Handling SIGTERM 1106s TI:22:03:52 culled 0 of 1 1106s TI:22:03:52 saved /tmp/upower-history-jq65x8mq/history-rate-80.dat 1106s TI:22:03:52 culled 0 of 2 1106s TI:22:03:52 saved /tmp/upower-history-jq65x8mq/history-charge-80.dat 1106s TI:22:03:52 culled 0 of 1 1106s TI:22:03:52 saved /tmp/upower-history-jq65x8mq/history-time-full-80.dat 1106s TI:22:03:52 culled 0 of 1 1106s TI:22:03:52 saved /tmp/upower-history-jq65x8mq/history-time-empty-80.dat 1110s .Executing: upower/upower-integration.test 1112s 1742076238.012 GetAll /org/freedesktop/PolicyKit1/Authority org.freedesktop.PolicyKit1.Authority 1112s 1742076238.115 GetAll /org/freedesktop/login1 org.freedesktop.login1.Manager 1112s 1742076238.152 Inhibit "sleep" "UPower" "Pause device polling" "delay" 1112s TI:22:03:58 Acquired inhibitor lock (7, delay) 1112s TI:22:03:58 Starting upowerd version 1.90.7 1112s TI:22:03:58 cannot find a keyboard backlight 1112s TI:22:03:58 Exported UpDevice with path /org/freedesktop/UPower/devices/DisplayDevice 1112s TI:22:03:58 daemon now coldplug 1112s TI:22:03:58 registering subsystem : input 1112s TI:22:03:58 registering subsystem : power_supply 1112s TI:22:03:58 Received uevent add on device /sys/devices/bq24735@5-0009 1112s TI:22:03:58 using present voltage (alternate) 1112s TI:22:03:58 failed to read battery charge threshold: Failed to open file “/tmp/upower-history-t9cx7i_8/charging-threshold-status”: No such file or directory 1112s TI:22:03:58 using id: 80 1112s TI:22:03:58 failed to get data from /tmp/upower-history-t9cx7i_8/history-rate-80.dat as file does not exist 1112s TI:22:03:58 failed to get data from /tmp/upower-history-t9cx7i_8/history-charge-80.dat as file does not exist 1112s TI:22:03:58 failed to get data from /tmp/upower-history-t9cx7i_8/history-time-full-80.dat as file does not exist 1112s TI:22:03:58 failed to get data from /tmp/upower-history-t9cx7i_8/history-time-empty-80.dat as file does not exist 1112s TI:22:03:58 saving in 600 seconds 1112s TI:22:03:58 device bq24735@5-0009 has unknown state, not saving history 1112s TI:22:03:58 unknown_poll: setting up fast re-poll 1112s TI:22:03:58 added native-path: bq24735@5-0009 1112s TI:22:03:58 Exported UpDevice with path /org/freedesktop/UPower/devices/battery_bq24735_5_0009 1112s TI:22:03:58 device bq24735@5-0009 has parent id: (null) 1112s TI:22:03:58 Got new device from udev enumerator: 0x556047696480 1112s TI:22:03:58 added bq24735@5-0009 1112s TI:22:03:58 emitting added: /org/freedesktop/UPower/devices/battery_bq24735_5_0009 1112s TI:22:03:58 registering subsystem : usb 1112s TI:22:03:58 registering subsystem : usbmisc 1112s TI:22:03:58 registering subsystem : sound 1112s TI:22:03:58 on_battery = yes 1112s TI:22:03:58 warning_level = none 1112s TI:22:03:58 BlueZ disappeared 1112s TI:22:03:58 daemon now not coldplug 1112s TI:22:03:58 Handling SIGTERM 1112s TI:22:03:58 culled 0 of 1 1112s TI:22:03:58 saved /tmp/upower-history-t9cx7i_8/history-rate-80.dat 1112s TI:22:03:58 culled 0 of 1 1112s TI:22:03:58 saved /tmp/upower-history-t9cx7i_8/history-charge-80.dat 1112s TI:22:03:58 culled 0 of 1 1112s TI:22:03:58 saved /tmp/upower-history-t9cx7i_8/history-time-full-80.dat 1112s TI:22:03:58 culled 0 of 1 1112s TI:22:03:58 saved /tmp/upower-history-t9cx7i_8/history-time-empty-80.dat 1115s .Executing: upower/upower-integration.test 1117s 1742076243.948 GetAll /org/freedesktop/PolicyKit1/Authority org.freedesktop.PolicyKit1.Authority 1118s 1742076244.058 GetAll /org/freedesktop/login1 org.freedesktop.login1.Manager 1118s 1742076244.095 Inhibit "sleep" "UPower" "Pause device polling" "delay" 1118s TI:22:04:04 Acquired inhibitor lock (7, delay) 1118s TI:22:04:04 Starting upowerd version 1.90.7 1118s TI:22:04:04 cannot find a keyboard backlight 1118s TI:22:04:04 Exported UpDevice with path /org/freedesktop/UPower/devices/DisplayDevice 1118s TI:22:04:04 daemon now coldplug 1118s TI:22:04:04 registering subsystem : input 1118s TI:22:04:04 registering subsystem : power_supply 1118s TI:22:04:04 Received uevent add on device /sys/devices/BAT0 1118s TI:22:04:04 using present voltage (alternate) 1118s TI:22:04:04 failed to read battery charge threshold: Failed to open file “/tmp/upower-history-j1n8kyyw/charging-threshold-status”: No such file or directory 1118s TI:22:04:04 using id: 132 1118s TI:22:04:04 failed to get data from /tmp/upower-history-j1n8kyyw/history-rate-132.dat as file does not exist 1118s TI:22:04:04 failed to get data from /tmp/upower-history-j1n8kyyw/history-charge-132.dat as file does not exist 1118s TI:22:04:04 failed to get data from /tmp/upower-history-j1n8kyyw/history-time-full-132.dat as file does not exist 1118s TI:22:04:04 failed to get data from /tmp/upower-history-j1n8kyyw/history-time-empty-132.dat as file does not exist 1118s TI:22:04:04 saving in 600 seconds 1118s TI:22:04:04 saving in 600 seconds 1118s TI:22:04:04 added native-path: BAT0 1118s TI:22:04:04 Exported UpDevice with path /org/freedesktop/UPower/devices/battery_BAT0 1118s TI:22:04:04 device BAT0 has parent id: (null) 1118s TI:22:04:04 Got new device from udev enumerator: 0x5557db9c05e0 1118s TI:22:04:04 added BAT0 1118s TI:22:04:04 emitting added: /org/freedesktop/UPower/devices/battery_BAT0 1118s TI:22:04:04 registering subsystem : usb 1118s TI:22:04:04 registering subsystem : usbmisc 1118s TI:22:04:04 registering subsystem : sound 1118s TI:22:04:04 on_battery = yes 1118s TI:22:04:04 warning_level = none 1118s TI:22:04:04 BlueZ disappeared 1118s TI:22:04:04 daemon now not coldplug 1118s TI:22:04:04 Handling SIGTERM 1118s TI:22:04:04 culled 0 of 1 1118s TI:22:04:04 saved /tmp/upower-history-j1n8kyyw/history-rate-132.dat 1118s TI:22:04:04 culled 0 of 2 1118s TI:22:04:04 saved /tmp/upower-history-j1n8kyyw/history-charge-132.dat 1118s TI:22:04:04 culled 0 of 1 1118s TI:22:04:04 saved /tmp/upower-history-j1n8kyyw/history-time-full-132.dat 1118s TI:22:04:04 culled 0 of 1 1118s TI:22:04:04 saved /tmp/upower-history-j1n8kyyw/history-time-empty-132.dat 1120s .Executing: upower/upower-integration.test 1125s 1742076251.042 GetAll /org/freedesktop/PolicyKit1/Authority org.freedesktop.PolicyKit1.Authority 1125s 1742076251.130 GetAll /org/freedesktop/login1 org.freedesktop.login1.Manager 1125s 1742076251.169 Inhibit "sleep" "UPower" "Pause device polling" "delay" 1125s TI:22:04:11 Acquired inhibitor lock (7, delay) 1125s TI:22:04:11 Starting upowerd version 1.90.7 1125s TI:22:04:11 cannot find a keyboard backlight 1125s TI:22:04:11 Exported UpDevice with path /org/freedesktop/UPower/devices/DisplayDevice 1125s TI:22:04:11 daemon now coldplug 1125s TI:22:04:11 registering subsystem : input 1125s TI:22:04:11 registering subsystem : power_supply 1125s TI:22:04:11 Received uevent add on device /sys/devices/BAT0 1125s TI:22:04:11 using present voltage (alternate) 1125s TI:22:04:11 failed to read battery charge threshold: Failed to open file “/tmp/upower-history-1yfgyjqm/charging-threshold-status”: No such file or directory 1125s TI:22:04:11 using id: 132 1125s TI:22:04:11 failed to get data from /tmp/upower-history-1yfgyjqm/history-rate-132.dat as file does not exist 1125s TI:22:04:11 failed to get data from /tmp/upower-history-1yfgyjqm/history-charge-132.dat as file does not exist 1125s TI:22:04:11 failed to get data from /tmp/upower-history-1yfgyjqm/history-time-full-132.dat as file does not exist 1125s TI:22:04:11 failed to get data from /tmp/upower-history-1yfgyjqm/history-time-empty-132.dat as file does not exist 1125s TI:22:04:11 saving in 600 seconds 1125s TI:22:04:11 saving in 600 seconds 1125s TI:22:04:11 saving in 600 seconds 1125s TI:22:04:11 saving in 600 seconds 1125s TI:22:04:11 added native-path: BAT0 1125s TI:22:04:11 Exported UpDevice with path /org/freedesktop/UPower/devices/battery_BAT0 1125s TI:22:04:11 device BAT0 has parent id: (null) 1125s TI:22:04:11 Got new device from udev enumerator: 0x555ae7175680 1125s TI:22:04:11 added BAT0 1125s TI:22:04:11 emitting added: /org/freedesktop/UPower/devices/battery_BAT0 1125s TI:22:04:11 registering subsystem : usb 1125s TI:22:04:11 registering subsystem : usbmisc 1125s TI:22:04:11 registering subsystem : sound 1125s TI:22:04:11 on_battery = yes 1125s TI:22:04:11 warning_level = none 1125s TI:22:04:11 daemon now not coldplug 1125s TI:22:04:11 BlueZ disappeared 1125s TI:22:04:11 Handling SIGTERM 1125s TI:22:04:11 culled 0 of 2 1125s TI:22:04:11 saved /tmp/upower-history-1yfgyjqm/history-rate-132.dat 1125s TI:22:04:11 culled 0 of 2 1125s TI:22:04:11 saved /tmp/upower-history-1yfgyjqm/history-charge-132.dat 1125s TI:22:04:11 culled 0 of 1 1125s TI:22:04:11 saved /tmp/upower-history-1yfgyjqm/history-time-full-132.dat 1125s TI:22:04:11 culled 0 of 2 1125s TI:22:04:11 saved /tmp/upower-history-1yfgyjqm/history-time-empty-132.dat 1125s Executing: upower/upower-integration.test 1130s .Executing: upower/upower-integration.test 1131s 1742076257.087 SetAllowed ["org.freedesktop.UPower.enable-charging-limit"] 1132s 1742076258.101 GetAll /org/freedesktop/PolicyKit1/Authority org.freedesktop.PolicyKit1.Authority 1132s 1742076258.253 GetAll /org/freedesktop/login1 org.freedesktop.login1.Manager 1132s 1742076258.306 Inhibit "sleep" "UPower" "Pause device polling" "delay" 1132s TI:22:04:18 Acquired inhibitor lock (7, delay) 1132s TI:22:04:18 Starting upowerd version 1.90.7 1132s TI:22:04:18 cannot find a keyboard backlight 1132s TI:22:04:18 Exported UpDevice with path /org/freedesktop/UPower/devices/DisplayDevice 1132s TI:22:04:18 daemon now coldplug 1132s TI:22:04:18 registering subsystem : input 1132s TI:22:04:18 registering subsystem : power_supply 1132s TI:22:04:18 Received uevent add on device /sys/devices/BAT0 1132s TI:22:04:18 using present voltage (alternate) 1132s TI:22:04:18 failed to read battery charge threshold: Failed to open file “/tmp/upower-history-cjx9kfn6/charging-threshold-status”: No such file or directory 1132s TI:22:04:18 using id: 80 1132s TI:22:04:18 failed to get data from /tmp/upower-history-cjx9kfn6/history-rate-80.dat as file does not exist 1132s TI:22:04:18 failed to get data from /tmp/upower-history-cjx9kfn6/history-charge-80.dat as file does not exist 1132s TI:22:04:18 failed to get data from /tmp/upower-history-cjx9kfn6/history-time-full-80.dat as file does not exist 1132s TI:22:04:18 failed to get data from /tmp/upower-history-cjx9kfn6/history-time-empty-80.dat as file does not exist 1132s TI:22:04:18 saving in 600 seconds 1132s TI:22:04:18 device BAT0 has unknown state, not saving history 1132s TI:22:04:18 unknown_poll: setting up fast re-poll 1132s TI:22:04:18 added native-path: BAT0 1132s TI:22:04:18 Exported UpDevice with path /org/freedesktop/UPower/devices/battery_BAT0 1132s TI:22:04:18 device BAT0 has parent id: (null) 1132s TI:22:04:18 Got new device from udev enumerator: 0x5562e4ccfba0 1132s TI:22:04:18 added BAT0 1132s TI:22:04:18 emitting added: /org/freedesktop/UPower/devices/battery_BAT0 1132s TI:22:04:18 Received uevent add on device /sys/devices/BAT1 1132s TI:22:04:18 using present voltage (alternate) 1132s TI:22:04:18 failed to read battery charge threshold: Failed to open file “/tmp/upower-history-cjx9kfn6/charging-threshold-status”: No such file or directory 1132s TI:22:04:18 using id: 80 1132s TI:22:04:18 failed to get data from /tmp/upower-history-cjx9kfn6/history-rate-80.dat as file does not exist 1132s TI:22:04:18 failed to get data from /tmp/upower-history-cjx9kfn6/history-charge-80.dat as file does not exist 1132s TI:22:04:18 failed to get data from /tmp/upower-history-cjx9kfn6/history-time-full-80.dat as file does not exist 1132s TI:22:04:18 failed to get data from /tmp/upower-history-cjx9kfn6/history-time-empty-80.dat as file does not exist 1132s TI:22:04:18 saving in 600 seconds 1132s TI:22:04:18 device BAT1 has unknown state, not saving history 1132s TI:22:04:18 unknown_poll: setting up fast re-poll 1132s TI:22:04:18 added native-path: BAT1 1132s TI:22:04:18 Exported UpDevice with path /org/freedesktop/UPower/devices/battery_BAT1 1132s TI:22:04:18 device BAT1 has parent id: (null) 1132s TI:22:04:18 Got new device from udev enumerator: 0x5562e4cd2560 1132s TI:22:04:18 added BAT1 1132s TI:22:04:18 emitting added: /org/freedesktop/UPower/devices/battery_BAT1 1132s TI:22:04:18 registering subsystem : usb 1132s TI:22:04:18 registering subsystem : usbmisc 1132s TI:22:04:18 registering subsystem : sound 1132s TI:22:04:18 Calculating percentage and time to full/to empty for 2 batteries 1132s TI:22:04:18 on_battery = yes 1132s TI:22:04:18 warning_level = none 1132s TI:22:04:18 daemon now not coldplug 1132s TI:22:04:18 BlueZ disappeared 1133s 1742076259.027 CheckAuthorization dbus.Struct((dbus.String('system-bus-name'), dbus.Dictionary({dbus.String('name'): dbus.String(':1.0', variant_level=1)}, signature=dbus.Signature('sv'))), signature=None) "org.freedesktop.UPower.enable-charging-limit" {} 0 "" 1133s TI:22:04:19 Calculating percentage and time to full/to empty for 2 batteries 1133s TI:22:04:19 on_battery = yes 1133s 1742076259.168 CheckAuthorization dbus.Struct((dbus.String('system-bus-name'), dbus.Dictionary({dbus.String('name'): dbus.String(':1.0', variant_level=1)}, signature=dbus.Signature('sv'))), signature=None) "org.freedesktop.UPower.enable-charging-limit" {} 0 "" 1133s TI:22:04:19 Calculating percentage and time to full/to empty for 2 batteries 1133s TI:22:04:19 on_battery = yes 1133s TI:22:04:19 Handling SIGTERM 1133s TI:22:04:19 culled 0 of 1 1133s TI:22:04:19 saved /tmp/upower-history-cjx9kfn6/history-rate-80.dat 1133s TI:22:04:19 culled 0 of 1 1133s TI:22:04:19 saved /tmp/upower-history-cjx9kfn6/history-charge-80.dat 1133s TI:22:04:19 culled 0 of 1 1133s TI:22:04:19 saved /tmp/upower-history-cjx9kfn6/history-time-full-80.dat 1133s TI:22:04:19 culled 0 of 1 1133s TI:22:04:19 saved /tmp/upower-history-cjx9kfn6/history-time-empty-80.dat 1133s TI:22:04:19 culled 0 of 1 1133s TI:22:04:19 saved /tmp/upower-history-cjx9kfn6/history-rate-80.dat 1133s TI:22:04:19 culled 0 of 1 1133s TI:22:04:19 saved /tmp/upower-history-cjx9kfn6/history-charge-80.dat 1133s TI:22:04:19 culled 0 of 1 1133s TI:22:04:19 saved /tmp/upower-history-cjx9kfn6/history-time-full-80.dat 1133s TI:22:04:19 culled 0 of 1 1133s TI:22:04:19 saved /tmp/upower-history-cjx9kfn6/history-time-empty-80.dat 1135s .Executing: upower/upower-integration.test 1140s 1742076266.119 GetAll /org/freedesktop/PolicyKit1/Authority org.freedesktop.PolicyKit1.Authority 1140s 1742076266.227 GetAll /org/freedesktop/login1 org.freedesktop.login1.Manager 1140s 1742076266.273 Inhibit "sleep" "UPower" "Pause device polling" "delay" 1140s TI:22:04:26 Acquired inhibitor lock (7, delay) 1140s TI:22:04:26 Starting upowerd version 1.90.7 1140s TI:22:04:26 cannot find a keyboard backlight 1140s TI:22:04:26 Exported UpDevice with path /org/freedesktop/UPower/devices/DisplayDevice 1140s TI:22:04:26 daemon now coldplug 1140s TI:22:04:26 registering subsystem : input 1140s TI:22:04:26 registering subsystem : power_supply 1140s TI:22:04:26 Received uevent add on device /sys/devices/BAT0 1140s TI:22:04:26 using present voltage (alternate) 1140s TI:22:04:26 failed to read battery charge threshold: Failed to open file “/tmp/upower-history-e8a982do/charging-threshold-status”: No such file or directory 1140s TI:22:04:26 using id: 80 1140s TI:22:04:26 failed to get data from /tmp/upower-history-e8a982do/history-rate-80.dat as file does not exist 1140s TI:22:04:26 failed to get data from /tmp/upower-history-e8a982do/history-charge-80.dat as file does not exist 1140s TI:22:04:26 failed to get data from /tmp/upower-history-e8a982do/history-time-full-80.dat as file does not exist 1140s TI:22:04:26 failed to get data from /tmp/upower-history-e8a982do/history-time-empty-80.dat as file does not exist 1140s TI:22:04:26 saving in 600 seconds 1140s TI:22:04:26 device BAT0 has unknown state, not saving history 1140s TI:22:04:26 unknown_poll: setting up fast re-poll 1140s TI:22:04:26 added native-path: BAT0 1140s TI:22:04:26 Exported UpDevice with path /org/freedesktop/UPower/devices/battery_BAT0 1140s TI:22:04:26 device BAT0 has parent id: (null) 1140s TI:22:04:26 Got new device from udev enumerator: 0x55609140d900 1140s TI:22:04:26 added BAT0 1140s TI:22:04:26 emitting added: /org/freedesktop/UPower/devices/battery_BAT0 1140s TI:22:04:26 Received uevent add on device /sys/devices/BAT1 1140s TI:22:04:26 using present voltage (alternate) 1140s TI:22:04:26 failed to read battery charge threshold: Failed to open file “/tmp/upower-history-e8a982do/charging-threshold-status”: No such file or directory 1140s TI:22:04:26 using id: 80 1140s TI:22:04:26 failed to get data from /tmp/upower-history-e8a982do/history-rate-80.dat as file does not exist 1140s TI:22:04:26 failed to get data from /tmp/upower-history-e8a982do/history-charge-80.dat as file does not exist 1140s TI:22:04:26 failed to get data from /tmp/upower-history-e8a982do/history-time-full-80.dat as file does not exist 1140s TI:22:04:26 failed to get data from /tmp/upower-history-e8a982do/history-time-empty-80.dat as file does not exist 1140s TI:22:04:26 saving in 600 seconds 1140s TI:22:04:26 device BAT1 has unknown state, not saving history 1140s TI:22:04:26 unknown_poll: setting up fast re-poll 1140s TI:22:04:26 added native-path: BAT1 1140s TI:22:04:26 Exported UpDevice with path /org/freedesktop/UPower/devices/battery_BAT1 1140s TI:22:04:26 device BAT1 has parent id: (null) 1140s TI:22:04:26 Got new device from udev enumerator: 0x5560914102c0 1140s TI:22:04:26 added BAT1 1140s TI:22:04:26 emitting added: /org/freedesktop/UPower/devices/battery_BAT1 1140s TI:22:04:26 registering subsystem : usb 1140s TI:22:04:26 registering subsystem : usbmisc 1140s TI:22:04:26 registering subsystem : sound 1140s TI:22:04:26 BlueZ disappeared 1140s TI:22:04:26 Calculating percentage and time to full/to empty for 2 batteries 1140s TI:22:04:26 on_battery = yes 1140s TI:22:04:26 warning_level = none 1140s TI:22:04:26 daemon now not coldplug 1140s Executing: upower/upower-integration.test 1140s 1742076266.901 CheckAuthorization dbus.Struct((dbus.String('system-bus-name'), dbus.Dictionary({dbus.String('name'): dbus.String(':1.0', variant_level=1)}, signature=dbus.Signature('sv'))), signature=None) "org.freedesktop.UPower.enable-charging-limit" {} 0 "" 1140s 1742076266.940 CheckAuthorization dbus.Struct((dbus.String('system-bus-name'), dbus.Dictionary({dbus.String('name'): dbus.String(':1.0', variant_level=1)}, signature=dbus.Signature('sv'))), signature=None) "org.freedesktop.UPower.enable-charging-limit" {} 0 "" 1141s TI:22:04:27 Handling SIGTERM 1141s TI:22:04:27 culled 0 of 1 1141s TI:22:04:27 saved /tmp/upower-history-e8a982do/history-rate-80.dat 1141s TI:22:04:27 culled 0 of 1 1141s TI:22:04:27 saved /tmp/upower-history-e8a982do/history-charge-80.dat 1141s TI:22:04:27 culled 0 of 1 1141s TI:22:04:27 saved /tmp/upower-history-e8a982do/history-time-full-80.dat 1141s TI:22:04:27 culled 0 of 1 1141s TI:22:04:27 saved /tmp/upower-history-e8a982do/history-time-empty-80.dat 1141s TI:22:04:27 culled 0 of 1 1141s TI:22:04:27 saved /tmp/upower-history-e8a982do/history-rate-80.dat 1141s TI:22:04:27 culled 0 of 1 1141s TI:22:04:27 saved /tmp/upower-history-e8a982do/history-charge-80.dat 1141s TI:22:04:27 culled 0 of 1 1141s TI:22:04:27 saved /tmp/upower-history-e8a982do/history-time-full-80.dat 1141s TI:22:04:27 culled 0 of 1 1141s TI:22:04:27 saved /tmp/upower-history-e8a982do/history-time-empty-80.dat 1145s .Executing: upower/upower-integration.test 1147s 1742076273.033 SetAllowed ["org.freedesktop.UPower.enable-charging-limit"] 1147s 1742076273.519 GetAll /org/freedesktop/PolicyKit1/Authority org.freedesktop.PolicyKit1.Authority 1147s 1742076273.622 GetAll /org/freedesktop/login1 org.freedesktop.login1.Manager 1147s 1742076273.648 Inhibit "sleep" "UPower" "Pause device polling" "delay" 1147s TI:22:04:33 Acquired inhibitor lock (7, delay) 1147s TI:22:04:33 Starting upowerd version 1.90.7 1147s TI:22:04:33 cannot find a keyboard backlight 1147s TI:22:04:33 Exported UpDevice with path /org/freedesktop/UPower/devices/DisplayDevice 1147s TI:22:04:33 daemon now coldplug 1147s TI:22:04:33 registering subsystem : input 1147s TI:22:04:33 registering subsystem : power_supply 1147s TI:22:04:33 Received uevent add on device /sys/devices/BAT0 1147s TI:22:04:33 using present voltage (alternate) 1147s TI:22:04:33 failed to read battery charge threshold: Failed to open file “/tmp/upower-history-dsl2tcc7/charging-threshold-status”: No such file or directory 1147s TI:22:04:33 using id: test-80 1147s TI:22:04:33 failed to get data from /tmp/upower-history-dsl2tcc7/history-rate-test-80.dat as file does not exist 1147s TI:22:04:33 failed to get data from /tmp/upower-history-dsl2tcc7/history-charge-test-80.dat as file does not exist 1147s TI:22:04:33 failed to get data from /tmp/upower-history-dsl2tcc7/history-time-full-test-80.dat as file does not exist 1147s TI:22:04:33 failed to get data from /tmp/upower-history-dsl2tcc7/history-time-empty-test-80.dat as file does not exist 1147s TI:22:04:33 saving in 600 seconds 1147s TI:22:04:33 device BAT0 has unknown state, not saving history 1147s TI:22:04:33 unknown_poll: setting up fast re-poll 1147s TI:22:04:33 added native-path: BAT0 1147s TI:22:04:33 Exported UpDevice with path /org/freedesktop/UPower/devices/battery_BAT0 1147s TI:22:04:33 device BAT0 has parent id: (null) 1147s TI:22:04:33 Got new device from udev enumerator: 0x5559049645a0 1147s TI:22:04:33 added BAT0 1147s TI:22:04:33 emitting added: /org/freedesktop/UPower/devices/battery_BAT0 1147s TI:22:04:33 registering subsystem : usb 1147s TI:22:04:33 registering subsystem : usbmisc 1147s TI:22:04:33 registering subsystem : sound 1147s TI:22:04:33 on_battery = yes 1147s TI:22:04:33 warning_level = none 1147s TI:22:04:33 BlueZ disappeared 1147s TI:22:04:33 daemon now not coldplug 1148s 1742076274.214 CheckAuthorization dbus.Struct((dbus.String('system-bus-name'), dbus.Dictionary({dbus.String('name'): dbus.String(':1.0', variant_level=1)}, signature=dbus.Signature('sv'))), signature=None) "org.freedesktop.UPower.enable-charging-limit" {} 0 "" 1148s TI:22:04:34 on_battery = yes 1148s 1742076274.348 CheckAuthorization dbus.Struct((dbus.String('system-bus-name'), dbus.Dictionary({dbus.String('name'): dbus.String(':1.0', variant_level=1)}, signature=dbus.Signature('sv'))), signature=None) "org.freedesktop.UPower.enable-charging-limit" {} 0 "" 1148s TI:22:04:34 on_battery = yes 1148s TI:22:04:34 Handling SIGTERM 1148s TI:22:04:34 culled 0 of 1 1148s TI:22:04:34 saved /tmp/upower-history-dsl2tcc7/history-rate-test-80.dat 1148s TI:22:04:34 culled 0 of 1 1148s TI:22:04:34 saved /tmp/upower-history-dsl2tcc7/history-charge-test-80.dat 1148s TI:22:04:34 culled 0 of 1 1148s TI:22:04:34 saved /tmp/upower-history-dsl2tcc7/history-time-full-test-80.dat 1148s TI:22:04:34 culled 0 of 1 1148s TI:22:04:34 saved /tmp/upower-history-dsl2tcc7/history-time-empty-test-80.dat 1149s 1742076275.193 GetAll /org/freedesktop/PolicyKit1/Authority org.freedesktop.PolicyKit1.Authority 1149s 1742076275.309 GetAll /org/freedesktop/login1 org.freedesktop.login1.Manager 1149s 1742076275.349 Inhibit "sleep" "UPower" "Pause device polling" "delay" 1149s TI:22:04:35 Acquired inhibitor lock (7, delay) 1149s TI:22:04:35 Starting upowerd version 1.90.7 1149s TI:22:04:35 cannot find a keyboard backlight 1149s TI:22:04:35 Exported UpDevice with path /org/freedesktop/UPower/devices/DisplayDevice 1149s TI:22:04:35 daemon now coldplug 1149s TI:22:04:35 registering subsystem : input 1149s TI:22:04:35 registering subsystem : power_supply 1149s TI:22:04:35 Received uevent add on device /sys/devices/BAT0 1149s TI:22:04:35 using present voltage (alternate) 1149s TI:22:04:35 using id: test-80 1149s TI:22:04:35 failed to get data from /tmp/upower-history-sagmh78_/history-rate-test-80.dat as file does not exist 1149s TI:22:04:35 failed to get data from /tmp/upower-history-sagmh78_/history-charge-test-80.dat as file does not exist 1149s TI:22:04:35 failed to get data from /tmp/upower-history-sagmh78_/history-time-full-test-80.dat as file does not exist 1149s TI:22:04:35 failed to get data from /tmp/upower-history-sagmh78_/history-time-empty-test-80.dat as file does not exist 1149s TI:22:04:35 saving in 600 seconds 1149s TI:22:04:35 device BAT0 has unknown state, not saving history 1149s TI:22:04:35 unknown_poll: setting up fast re-poll 1149s TI:22:04:35 added native-path: BAT0 1149s TI:22:04:35 Exported UpDevice with path /org/freedesktop/UPower/devices/battery_BAT0 1149s TI:22:04:35 device BAT0 has parent id: (null) 1149s TI:22:04:35 Got new device from udev enumerator: 0x556274c3c570 1149s TI:22:04:35 added BAT0 1149s TI:22:04:35 emitting added: /org/freedesktop/UPower/devices/battery_BAT0 1149s TI:22:04:35 registering subsystem : usb 1149s TI:22:04:35 registering subsystem : usbmisc 1149s TI:22:04:35 registering subsystem : sound 1149s TI:22:04:35 BlueZ disappeared 1149s TI:22:04:35 on_battery = yes 1149s TI:22:04:35 warning_level = none 1149s TI:22:04:35 daemon now not coldplug 1149s TI:22:04:35 Handling SIGTERM 1149s TI:22:04:35 culled 0 of 1 1149s TI:22:04:35 saved /tmp/upower-history-sagmh78_/history-rate-test-80.dat 1149s TI:22:04:35 culled 0 of 1 1149s TI:22:04:35 saved /tmp/upower-history-sagmh78_/history-charge-test-80.dat 1149s TI:22:04:35 culled 0 of 1 1149s TI:22:04:35 saved /tmp/upower-history-sagmh78_/history-time-full-test-80.dat 1149s TI:22:04:35 culled 0 of 1 1149s TI:22:04:35 saved /tmp/upower-history-sagmh78_/history-time-empty-test-80.dat 1150s .Executing: upower/upower-integration.test 1155s Executing: upower/upower-integration.test 1156s 1742076281.980 GetAll /org/freedesktop/PolicyKit1/Authority org.freedesktop.PolicyKit1.Authority 1156s 1742076282.071 GetAll /org/freedesktop/login1 org.freedesktop.login1.Manager 1156s 1742076282.096 Inhibit "sleep" "UPower" "Pause device polling" "delay" 1156s TI:22:04:42 Acquired inhibitor lock (7, delay) 1156s TI:22:04:42 Starting upowerd version 1.90.7 1156s TI:22:04:42 cannot find a keyboard backlight 1156s TI:22:04:42 Exported UpDevice with path /org/freedesktop/UPower/devices/DisplayDevice 1156s TI:22:04:42 daemon now coldplug 1156s TI:22:04:42 registering subsystem : input 1156s TI:22:04:42 registering subsystem : power_supply 1156s TI:22:04:42 Received uevent add on device /sys/devices/BAT0 1156s TI:22:04:42 using present voltage (alternate) 1156s TI:22:04:42 failed to read battery charge threshold: Failed to open file “/tmp/upower-history-0nl1unla/charging-threshold-status”: No such file or directory 1156s TI:22:04:42 using id: test-80 1156s TI:22:04:42 failed to get data from /tmp/upower-history-0nl1unla/history-rate-test-80.dat as file does not exist 1156s TI:22:04:42 failed to get data from /tmp/upower-history-0nl1unla/history-charge-test-80.dat as file does not exist 1156s TI:22:04:42 failed to get data from /tmp/upower-history-0nl1unla/history-time-full-test-80.dat as file does not exist 1156s TI:22:04:42 failed to get data from /tmp/upower-history-0nl1unla/history-time-empty-test-80.dat as file does not exist 1156s TI:22:04:42 saving in 600 seconds 1156s TI:22:04:42 device BAT0 has unknown state, not saving history 1156s TI:22:04:42 unknown_poll: setting up fast re-poll 1156s TI:22:04:42 added native-path: BAT0 1156s TI:22:04:42 Exported UpDevice with path /org/freedesktop/UPower/devices/battery_BAT0 1156s TI:22:04:42 device BAT0 has parent id: (null) 1156s TI:22:04:42 Got new device from udev enumerator: 0x555e8c940650 1156s TI:22:04:42 added BAT0 1156s TI:22:04:42 emitting added: /org/freedesktop/UPower/devices/battery_BAT0 1156s TI:22:04:42 registering subsystem : usb 1156s TI:22:04:42 registering subsystem : usbmisc 1156s TI:22:04:42 registering subsystem : sound 1156s TI:22:04:42 on_battery = yes 1156s TI:22:04:42 warning_level = none 1156s TI:22:04:42 BlueZ disappeared 1156s TI:22:04:42 daemon now not coldplug 1156s 1742076282.515 CheckAuthorization dbus.Struct((dbus.String('system-bus-name'), dbus.Dictionary({dbus.String('name'): dbus.String(':1.0', variant_level=1)}, signature=dbus.Signature('sv'))), signature=None) "org.freedesktop.UPower.enable-charging-limit" {} 0 "" 1156s 1742076282.579 CheckAuthorization dbus.Struct((dbus.String('system-bus-name'), dbus.Dictionary({dbus.String('name'): dbus.String(':1.0', variant_level=1)}, signature=dbus.Signature('sv'))), signature=None) "org.freedesktop.UPower.enable-charging-limit" {} 0 "" 1156s TI:22:04:42 Handling SIGTERM 1156s TI:22:04:42 culled 0 of 1 1156s TI:22:04:42 saved /tmp/upower-history-0nl1unla/history-rate-test-80.dat 1156s TI:22:04:42 culled 0 of 1 1156s TI:22:04:42 saved /tmp/upower-history-0nl1unla/history-charge-test-80.dat 1156s TI:22:04:42 culled 0 of 1 1156s TI:22:04:42 saved /tmp/upower-history-0nl1unla/history-time-full-test-80.dat 1156s TI:22:04:42 culled 0 of 1 1156s TI:22:04:42 saved /tmp/upower-history-0nl1unla/history-time-empty-test-80.dat 1157s 1742076283.355 GetAll /org/freedesktop/PolicyKit1/Authority org.freedesktop.PolicyKit1.Authority 1157s 1742076283.436 GetAll /org/freedesktop/login1 org.freedesktop.login1.Manager 1157s 1742076283.461 Inhibit "sleep" "UPower" "Pause device polling" "delay" 1157s TI:22:04:43 Acquired inhibitor lock (7, delay) 1157s TI:22:04:43 Starting upowerd version 1.90.7 1157s TI:22:04:43 cannot find a keyboard backlight 1157s TI:22:04:43 Exported UpDevice with path /org/freedesktop/UPower/devices/DisplayDevice 1157s TI:22:04:43 daemon now coldplug 1157s TI:22:04:43 registering subsystem : input 1157s TI:22:04:43 registering subsystem : power_supply 1157s TI:22:04:43 Received uevent add on device /sys/devices/BAT0 1157s TI:22:04:43 using present voltage (alternate) 1157s TI:22:04:43 using id: test-80 1157s TI:22:04:43 failed to get data from /tmp/upower-history-rn5uo6oq/history-rate-test-80.dat as file does not exist 1157s TI:22:04:43 failed to get data from /tmp/upower-history-rn5uo6oq/history-charge-test-80.dat as file does not exist 1157s TI:22:04:43 failed to get data from /tmp/upower-history-rn5uo6oq/history-time-full-test-80.dat as file does not exist 1157s TI:22:04:43 failed to get data from /tmp/upower-history-rn5uo6oq/history-time-empty-test-80.dat as file does not exist 1157s TI:22:04:43 saving in 600 seconds 1157s TI:22:04:43 device BAT0 has unknown state, not saving history 1157s TI:22:04:43 unknown_poll: setting up fast re-poll 1157s TI:22:04:43 added native-path: BAT0 1157s TI:22:04:43 Exported UpDevice with path /org/freedesktop/UPower/devices/battery_BAT0 1157s TI:22:04:43 device BAT0 has parent id: (null) 1157s TI:22:04:43 Got new device from udev enumerator: 0x55634d3f2660 1157s TI:22:04:43 added BAT0 1157s TI:22:04:43 emitting added: /org/freedesktop/UPower/devices/battery_BAT0 1157s TI:22:04:43 registering subsystem : usb 1157s TI:22:04:43 registering subsystem : usbmisc 1157s TI:22:04:43 registering subsystem : sound 1157s TI:22:04:43 on_battery = yes 1157s TI:22:04:43 warning_level = none 1157s TI:22:04:43 BlueZ disappeared 1157s TI:22:04:43 daemon now not coldplug 1158s TI:22:04:43 Handling SIGTERM 1158s TI:22:04:43 culled 0 of 1 1158s TI:22:04:44 saved /tmp/upower-history-rn5uo6oq/history-rate-test-80.dat 1158s TI:22:04:44 culled 0 of 1 1158s TI:22:04:44 saved /tmp/upower-history-rn5uo6oq/history-charge-test-80.dat 1158s TI:22:04:44 culled 0 of 1 1158s TI:22:04:44 saved /tmp/upower-history-rn5uo6oq/history-time-full-test-80.dat 1158s TI:22:04:44 culled 0 of 1 1158s TI:22:04:44 saved /tmp/upower-history-rn5uo6oq/history-time-empty-test-80.dat 1160s .Executing: upower/upower-integration.test 1163s 1742076289.802 SetAllowed ["org.freedesktop.UPower.enable-charging-limit"] 1164s 1742076290.235 GetAll /org/freedesktop/PolicyKit1/Authority org.freedesktop.PolicyKit1.Authority 1164s 1742076290.333 GetAll /org/freedesktop/login1 org.freedesktop.login1.Manager 1164s 1742076290.368 Inhibit "sleep" "UPower" "Pause device polling" "delay" 1164s TI:22:04:50 Acquired inhibitor lock (7, delay) 1164s TI:22:04:50 Starting upowerd version 1.90.7 1164s TI:22:04:50 cannot find a keyboard backlight 1164s TI:22:04:50 Exported UpDevice with path /org/freedesktop/UPower/devices/DisplayDevice 1164s TI:22:04:50 daemon now coldplug 1164s TI:22:04:50 registering subsystem : input 1164s TI:22:04:50 registering subsystem : power_supply 1164s TI:22:04:50 Received uevent add on device /sys/devices/BAT0 1164s TI:22:04:50 using present voltage (alternate) 1164s TI:22:04:50 failed to read battery charge threshold: Failed to open file “/tmp/upower-history-5s95zntv/charging-threshold-status”: No such file or directory 1164s TI:22:04:50 using id: test-80 1164s TI:22:04:50 failed to get data from /tmp/upower-history-5s95zntv/history-rate-test-80.dat as file does not exist 1164s TI:22:04:50 failed to get data from /tmp/upower-history-5s95zntv/history-charge-test-80.dat as file does not exist 1164s TI:22:04:50 failed to get data from /tmp/upower-history-5s95zntv/history-time-full-test-80.dat as file does not exist 1164s TI:22:04:50 failed to get data from /tmp/upower-history-5s95zntv/history-time-empty-test-80.dat as file does not exist 1164s TI:22:04:50 saving in 600 seconds 1164s TI:22:04:50 device BAT0 has unknown state, not saving history 1164s TI:22:04:50 unknown_poll: setting up fast re-poll 1164s TI:22:04:50 added native-path: BAT0 1164s TI:22:04:50 Exported UpDevice with path /org/freedesktop/UPower/devices/battery_BAT0 1164s TI:22:04:50 device BAT0 has parent id: (null) 1164s TI:22:04:50 Got new device from udev enumerator: 0x55619d441360 1164s TI:22:04:50 added BAT0 1164s TI:22:04:50 emitting added: /org/freedesktop/UPower/devices/battery_BAT0 1164s TI:22:04:50 registering subsystem : usb 1164s TI:22:04:50 registering subsystem : usbmisc 1164s TI:22:04:50 registering subsystem : sound 1164s TI:22:04:50 on_battery = yes 1164s TI:22:04:50 warning_level = none 1164s TI:22:04:50 BlueZ disappeared 1164s TI:22:04:50 daemon now not coldplug 1164s 1742076290.770 CheckAuthorization dbus.Struct((dbus.String('system-bus-name'), dbus.Dictionary({dbus.String('name'): dbus.String(':1.0', variant_level=1)}, signature=dbus.Signature('sv'))), signature=None) "org.freedesktop.UPower.enable-charging-limit" {} 0 "" 1164s TI:22:04:50 Handling SIGTERM 1164s TI:22:04:50 culled 0 of 1 1164s TI:22:04:50 saved /tmp/upower-history-5s95zntv/history-rate-test-80.dat 1164s TI:22:04:50 culled 0 of 1 1164s TI:22:04:50 saved /tmp/upower-history-5s95zntv/history-charge-test-80.dat 1164s TI:22:04:50 culled 0 of 1 1164s TI:22:04:50 saved /tmp/upower-history-5s95zntv/history-time-full-test-80.dat 1164s TI:22:04:50 culled 0 of 1 1164s TI:22:04:50 saved /tmp/upower-history-5s95zntv/history-time-empty-test-80.dat 1165s .Executing: upower/upower-integration.test 1170s 1742076296.716 GetAll /org/freedesktop/PolicyKit1/Authority org.freedesktop.PolicyKit1.Authority 1170s Executing: upower/upower-integration.test 1170s 1742076296.840 GetAll /org/freedesktop/login1 org.freedesktop.login1.Manager 1170s 1742076296.869 Inhibit "sleep" "UPower" "Pause device polling" "delay" 1170s TI:22:04:56 Acquired inhibitor lock (7, delay) 1170s TI:22:04:56 Starting upowerd version 1.90.7 1171s TI:22:04:56 cannot find a keyboard backlight 1171s TI:22:04:56 Exported UpDevice with path /org/freedesktop/UPower/devices/DisplayDevice 1171s TI:22:04:56 daemon now coldplug 1171s TI:22:04:57 registering subsystem : input 1171s TI:22:04:57 registering subsystem : power_supply 1171s TI:22:04:57 Received uevent add on device /sys/devices/BAT0 1171s TI:22:04:57 using present voltage (alternate) 1171s TI:22:04:57 failed to read battery charge threshold: Failed to open file “/tmp/upower-history-s6zpzezl/charging-threshold-status”: No such file or directory 1171s TI:22:04:57 using id: 132 1171s TI:22:04:57 failed to get data from /tmp/upower-history-s6zpzezl/history-rate-132.dat as file does not exist 1171s TI:22:04:57 failed to get data from /tmp/upower-history-s6zpzezl/history-charge-132.dat as file does not exist 1171s TI:22:04:57 failed to get data from /tmp/upower-history-s6zpzezl/history-time-full-132.dat as file does not exist 1171s TI:22:04:57 failed to get data from /tmp/upower-history-s6zpzezl/history-time-empty-132.dat as file does not exist 1171s TI:22:04:57 saving in 600 seconds 1171s TI:22:04:57 saving in 600 seconds 1171s TI:22:04:57 added native-path: BAT0 1171s TI:22:04:57 Exported UpDevice with path /org/freedesktop/UPower/devices/battery_BAT0 1171s TI:22:04:57 device BAT0 has parent id: (null) 1171s TI:22:04:57 Got new device from udev enumerator: 0x555cfd1a6420 1171s TI:22:04:57 added BAT0 1171s TI:22:04:57 emitting added: /org/freedesktop/UPower/devices/battery_BAT0 1171s TI:22:04:57 registering subsystem : usb 1171s TI:22:04:57 registering subsystem : usbmisc 1171s TI:22:04:57 registering subsystem : sound 1171s TI:22:04:57 on_battery = yes 1171s TI:22:04:57 warning_level = none 1171s TI:22:04:57 BlueZ disappeared 1171s TI:22:04:57 daemon now not coldplug 1171s TI:22:04:57 Handling SIGTERM 1171s TI:22:04:57 culled 0 of 1 1171s TI:22:04:57 saved /tmp/upower-history-s6zpzezl/history-rate-132.dat 1171s TI:22:04:57 culled 0 of 2 1171s TI:22:04:57 saved /tmp/upower-history-s6zpzezl/history-charge-132.dat 1171s TI:22:04:57 culled 0 of 1 1171s TI:22:04:57 saved /tmp/upower-history-s6zpzezl/history-time-full-132.dat 1171s TI:22:04:57 culled 0 of 1 1171s TI:22:04:57 saved /tmp/upower-history-s6zpzezl/history-time-empty-132.dat 1175s .Executing: upower/upower-integration.test 1177s 1742076303.592 GetAll /org/freedesktop/PolicyKit1/Authority org.freedesktop.PolicyKit1.Authority 1177s 1742076303.715 GetAll /org/freedesktop/login1 org.freedesktop.login1.Manager 1177s 1742076303.783 Inhibit "sleep" "UPower" "Pause device polling" "delay" 1177s TI:22:05:03 Acquired inhibitor lock (7, delay) 1177s TI:22:05:03 Starting upowerd version 1.90.7 1177s TI:22:05:03 cannot find a keyboard backlight 1177s TI:22:05:03 Exported UpDevice with path /org/freedesktop/UPower/devices/DisplayDevice 1177s TI:22:05:03 daemon now coldplug 1178s TI:22:05:04 registering subsystem : input 1178s TI:22:05:04 registering subsystem : power_supply 1178s TI:22:05:04 Received uevent add on device /sys/devices/BAT0 1178s TI:22:05:04 using present voltage (alternate) 1178s TI:22:05:04 failed to read battery charge threshold: Failed to open file “/tmp/upower-history-rbpb9li0/charging-threshold-status”: No such file or directory 1178s TI:22:05:04 using id: Fake_Battery-80-001 1178s TI:22:05:04 failed to get data from /tmp/upower-history-rbpb9li0/history-rate-Fake_Battery-80-001.dat as file does not exist 1178s TI:22:05:04 failed to get data from /tmp/upower-history-rbpb9li0/history-charge-Fake_Battery-80-001.dat as file does not exist 1178s TI:22:05:04 failed to get data from /tmp/upower-history-rbpb9li0/history-time-full-Fake_Battery-80-001.dat as file does not exist 1178s TI:22:05:04 failed to get data from /tmp/upower-history-rbpb9li0/history-time-empty-Fake_Battery-80-001.dat as file does not exist 1178s TI:22:05:04 saving in 600 seconds 1178s TI:22:05:04 saving in 600 seconds 1178s TI:22:05:04 added native-path: BAT0 1178s TI:22:05:04 Exported UpDevice with path /org/freedesktop/UPower/devices/battery_BAT0 1178s TI:22:05:04 device BAT0 has parent id: (null) 1178s TI:22:05:04 Got new device from udev enumerator: 0x55601fe405b0 1178s TI:22:05:04 added BAT0 1178s TI:22:05:04 emitting added: /org/freedesktop/UPower/devices/battery_BAT0 1178s TI:22:05:04 registering subsystem : usb 1178s TI:22:05:04 registering subsystem : usbmisc 1178s TI:22:05:04 registering subsystem : sound 1178s TI:22:05:04 on_battery = yes 1178s TI:22:05:04 warning_level = none 1178s TI:22:05:04 BlueZ disappeared 1178s TI:22:05:04 daemon now not coldplug 1178s TI:22:05:04 Received uevent change on device /sys/devices/BAT0 1178s TI:22:05:04 refreshing device for path /sys/devices/BAT0 1178s TI:22:05:04 using present voltage (alternate) 1178s TI:22:05:04 culled 0 of 1 1178s TI:22:05:04 saved /tmp/upower-history-rbpb9li0/history-rate-Fake_Battery-80-001.dat 1178s TI:22:05:04 culled 0 of 2 1178s TI:22:05:04 saved /tmp/upower-history-rbpb9li0/history-charge-Fake_Battery-80-001.dat 1178s TI:22:05:04 culled 0 of 1 1178s TI:22:05:04 saved /tmp/upower-history-rbpb9li0/history-time-full-Fake_Battery-80-001.dat 1178s TI:22:05:04 culled 0 of 1 1178s TI:22:05:04 saved /tmp/upower-history-rbpb9li0/history-time-empty-Fake_Battery-80-001.dat 1178s TI:22:05:04 failed to read battery charge threshold: Failed to open file “/tmp/upower-history-rbpb9li0/charging-threshold-status”: No such file or directory 1178s TI:22:05:04 using id: Fake_Battery-90-002 1178s TI:22:05:04 failed to get data from /tmp/upower-history-rbpb9li0/history-rate-Fake_Battery-90-002.dat as file does not exist 1178s TI:22:05:04 failed to get data from /tmp/upower-history-rbpb9li0/history-charge-Fake_Battery-90-002.dat as file does not exist 1178s TI:22:05:04 failed to get data from /tmp/upower-history-rbpb9li0/history-time-full-Fake_Battery-90-002.dat as file does not exist 1178s TI:22:05:04 failed to get data from /tmp/upower-history-rbpb9li0/history-time-empty-Fake_Battery-90-002.dat as file does not exist 1178s TI:22:05:04 saving in 600 seconds 1178s TI:22:05:04 saving in 600 seconds 1178s TI:22:05:04 on_battery = yes 1179s TI:22:05:05 Received uevent change on device /sys/devices/BAT0 1179s TI:22:05:05 refreshing device for path /sys/devices/BAT0 1179s TI:22:05:05 culled 0 of 1 1179s TI:22:05:05 saved /tmp/upower-history-rbpb9li0/history-rate-Fake_Battery-90-002.dat 1179s TI:22:05:05 culled 0 of 2 1179s TI:22:05:05 saved /tmp/upower-history-rbpb9li0/history-charge-Fake_Battery-90-002.dat 1179s TI:22:05:05 culled 0 of 1 1179s TI:22:05:05 saved /tmp/upower-history-rbpb9li0/history-time-full-Fake_Battery-90-002.dat 1179s TI:22:05:05 culled 0 of 1 1179s TI:22:05:05 saved /tmp/upower-history-rbpb9li0/history-time-empty-Fake_Battery-90-002.dat 1179s TI:22:05:05 on_battery = yes 1180s TI:22:05:06 Handling SIGTERM 1180s .Executing: upower/upower-integration.test 1185s Executing: upower/upower-integration.test 1186s 1742076312.116 GetAll /org/freedesktop/PolicyKit1/Authority org.freedesktop.PolicyKit1.Authority 1186s 1742076312.223 GetAll /org/freedesktop/login1 org.freedesktop.login1.Manager 1186s 1742076312.277 Inhibit "sleep" "UPower" "Pause device polling" "delay" 1186s TI:22:05:12 Acquired inhibitor lock (9, delay) 1186s TI:22:05:12 Starting upowerd version 1.90.7 1186s TI:22:05:12 cannot find a keyboard backlight 1186s TI:22:05:12 Exported UpDevice with path /org/freedesktop/UPower/devices/DisplayDevice 1186s TI:22:05:12 daemon now coldplug 1186s TI:22:05:12 registering subsystem : input 1186s TI:22:05:12 registering subsystem : power_supply 1186s TI:22:05:12 Received uevent add on device /sys/devices/BAT0 1186s TI:22:05:12 using present voltage (alternate) 1186s TI:22:05:12 failed to read battery charge threshold: Failed to open file “/tmp/upower-history-o9ouoilh/charging-threshold-status”: No such file or directory 1186s TI:22:05:12 using id: 132 1186s TI:22:05:12 failed to get data from /tmp/upower-history-o9ouoilh/history-rate-132.dat as file does not exist 1186s TI:22:05:12 failed to get data from /tmp/upower-history-o9ouoilh/history-charge-132.dat as file does not exist 1186s TI:22:05:12 failed to get data from /tmp/upower-history-o9ouoilh/history-time-full-132.dat as file does not exist 1186s TI:22:05:12 failed to get data from /tmp/upower-history-o9ouoilh/history-time-empty-132.dat as file does not exist 1186s TI:22:05:12 saving in 600 seconds 1186s TI:22:05:12 saving in 600 seconds 1186s TI:22:05:12 saving in 600 seconds 1186s TI:22:05:12 saving in 600 seconds 1186s TI:22:05:12 added native-path: BAT0 1186s TI:22:05:12 Exported UpDevice with path /org/freedesktop/UPower/devices/battery_BAT0 1186s TI:22:05:12 device BAT0 has parent id: (null) 1186s TI:22:05:12 Got new device from udev enumerator: 0x5561a64ce8c0 1186s TI:22:05:12 added BAT0 1186s TI:22:05:12 emitting added: /org/freedesktop/UPower/devices/battery_BAT0 1186s TI:22:05:12 registering subsystem : usb 1186s TI:22:05:12 registering subsystem : usbmisc 1186s TI:22:05:12 registering subsystem : sound 1186s TI:22:05:12 on_battery = no 1186s TI:22:05:12 warning_level = none 1186s TI:22:05:12 daemon now not coldplug 1186s TI:22:05:12 BlueZ disappeared 1186s TI:22:05:12 Handling SIGTERM 1186s TI:22:05:12 culled 0 of 2 1186s TI:22:05:12 saved /tmp/upower-history-o9ouoilh/history-rate-132.dat 1186s TI:22:05:12 culled 0 of 2 1186s TI:22:05:12 saved /tmp/upower-history-o9ouoilh/history-charge-132.dat 1186s TI:22:05:12 culled 0 of 1 1187s TI:22:05:12 saved /tmp/upower-history-o9ouoilh/history-time-full-132.dat 1187s TI:22:05:12 culled 0 of 2 1187s TI:22:05:12 saved /tmp/upower-history-o9ouoilh/history-time-empty-132.dat 1190s .Executing: upower/upower-integration.test 1192s 1742076318.515 GetAll /org/freedesktop/PolicyKit1/Authority org.freedesktop.PolicyKit1.Authority 1192s 1742076318.642 GetAll /org/freedesktop/login1 org.freedesktop.login1.Manager 1192s 1742076318.677 Inhibit "sleep" "UPower" "Pause device polling" "delay" 1192s TI:22:05:18 Acquired inhibitor lock (7, delay) 1192s TI:22:05:18 Starting upowerd version 1.90.7 1192s TI:22:05:18 cannot find a keyboard backlight 1192s TI:22:05:18 Exported UpDevice with path /org/freedesktop/UPower/devices/DisplayDevice 1192s TI:22:05:18 daemon now coldplug 1192s TI:22:05:18 registering subsystem : input 1192s TI:22:05:18 registering subsystem : power_supply 1192s TI:22:05:18 Received uevent add on device /sys/devices/AC 1192s TI:22:05:18 failed to coldplug AC 1192s TI:22:05:18 using id: generic_id 1192s TI:22:05:18 failed to get data from /tmp/upower-history-qgymnkv4/history-rate-generic_id.dat as file does not exist 1192s TI:22:05:18 failed to get data from /tmp/upower-history-qgymnkv4/history-charge-generic_id.dat as file does not exist 1192s TI:22:05:18 failed to get data from /tmp/upower-history-qgymnkv4/history-time-full-generic_id.dat as file does not exist 1192s TI:22:05:18 failed to get data from /tmp/upower-history-qgymnkv4/history-time-empty-generic_id.dat as file does not exist 1192s TI:22:05:18 saving in 600 seconds 1192s TI:22:05:18 device AC has unknown state, not saving history 1192s TI:22:05:18 taking a guess for power supply scope 1192s TI:22:05:18 culled 0 of 1 1192s TI:22:05:18 saved /tmp/upower-history-qgymnkv4/history-rate-generic_id.dat 1192s TI:22:05:18 culled 0 of 1 1192s TI:22:05:18 saved /tmp/upower-history-qgymnkv4/history-charge-generic_id.dat 1192s TI:22:05:18 culled 0 of 1 1192s TI:22:05:18 saved /tmp/upower-history-qgymnkv4/history-time-full-generic_id.dat 1192s TI:22:05:18 culled 0 of 1 1192s TI:22:05:18 saved /tmp/upower-history-qgymnkv4/history-time-empty-generic_id.dat 1192s TI:22:05:18 device AC has unknown state, not saving history 1192s TI:22:05:18 added native-path: AC 1192s TI:22:05:18 Exported UpDevice with path /org/freedesktop/UPower/devices/line_power_AC 1192s TI:22:05:18 device AC has parent id: (null) 1192s TI:22:05:18 Got new device from udev enumerator: 0x5558cb8df960 1192s TI:22:05:18 added AC 1192s TI:22:05:18 emitting added: /org/freedesktop/UPower/devices/line_power_AC 1192s TI:22:05:18 Received uevent add on device /sys/devices/BAT0 1192s TI:22:05:18 using present voltage (alternate) 1192s TI:22:05:18 failed to read battery charge threshold: Failed to open file “/tmp/upower-history-qgymnkv4/charging-threshold-status”: No such file or directory 1192s TI:22:05:18 using id: 80 1193s TI:22:05:18 failed to get data from /tmp/upower-history-qgymnkv4/history-rate-80.dat as file does not exist 1193s TI:22:05:18 failed to get data from /tmp/upower-history-qgymnkv4/history-charge-80.dat as file does not exist 1193s TI:22:05:18 failed to get data from /tmp/upower-history-qgymnkv4/history-time-full-80.dat as file does not exist 1193s TI:22:05:18 failed to get data from /tmp/upower-history-qgymnkv4/history-time-empty-80.dat as file does not exist 1193s TI:22:05:18 saving in 600 seconds 1193s TI:22:05:18 device BAT0 has unknown state, not saving history 1193s TI:22:05:18 unknown_poll: setting up fast re-poll 1193s TI:22:05:18 added native-path: BAT0 1193s TI:22:05:18 Exported UpDevice with path /org/freedesktop/UPower/devices/battery_BAT0 1193s TI:22:05:18 device BAT0 has parent id: (null) 1193s TI:22:05:18 Got new device from udev enumerator: 0x5558cb8e2240 1193s TI:22:05:18 added BAT0 1193s TI:22:05:18 emitting added: /org/freedesktop/UPower/devices/battery_BAT0 1193s TI:22:05:18 registering subsystem : usb 1193s TI:22:05:18 registering subsystem : usbmisc 1193s TI:22:05:18 registering subsystem : sound 1193s TI:22:05:19 on_battery = yes 1193s TI:22:05:19 warning_level = none 1193s TI:22:05:19 BlueZ disappeared 1193s TI:22:05:19 daemon now not coldplug 1193s TI:22:05:19 up_daemon_poll_dispatch: refreshing BAT0 1193s TI:22:05:19 using present voltage (alternate) 1193s TI:22:05:19 device BAT0 has unknown state, not saving history 1194s TI:22:05:19 on_battery = yes 1194s TI:22:05:20 up_daemon_poll_dispatch: refreshing BAT0 1194s TI:22:05:20 using present voltage (alternate) 1195s TI:22:05:20 device BAT0 has unknown state, not saving history 1195s TI:22:05:20 on_battery = yes 1195s Executing: upower/upower-integration.test 1196s TI:22:05:21 up_daemon_poll_dispatch: refreshing BAT0 1196s TI:22:05:21 using present voltage (alternate) 1196s TI:22:05:21 device BAT0 has unknown state, not saving history 1196s TI:22:05:21 on_battery = yes 1197s TI:22:05:22 up_daemon_poll_dispatch: refreshing BAT0 1197s TI:22:05:22 using present voltage (alternate) 1197s TI:22:05:22 device BAT0 has unknown state, not saving history 1197s TI:22:05:23 on_battery = yes 1198s TI:22:05:24 up_daemon_poll_dispatch: refreshing BAT0 1198s TI:22:05:24 using present voltage (alternate) 1198s TI:22:05:24 device BAT0 has unknown state, not saving history 1198s TI:22:05:24 unknown_poll: stopping fast repoll (giving up) 1198s TI:22:05:24 on_battery = yes 1200s Executing: upower/upower-integration.test 1203s TI:22:05:29 up_daemon_poll_dispatch: refreshing BAT0 1203s TI:22:05:29 using present voltage (alternate) 1203s TI:22:05:29 device BAT0 has unknown state, not saving history 1203s TI:22:05:29 on_battery = yes 1205s Executing: upower/upower-integration.test 1208s TI:22:05:34 up_daemon_poll_dispatch: refreshing BAT0 1208s TI:22:05:34 using present voltage (alternate) 1208s TI:22:05:34 deferring as earlier timeout is already queued 1208s TI:22:05:34 deferring as earlier timeout is already queued 1208s TI:22:05:34 deferring as earlier timeout is already queued 1208s TI:22:05:34 on_battery = yes 1210s Executing: upower/upower-integration.test 1215s Executing: upower/upower-integration.test 1219s TI:22:05:45 Received uevent change on device /sys/devices/AC 1219s TI:22:05:45 refreshing device for path /sys/devices/AC 1219s TI:22:05:45 device AC has unknown state, not saving history 1219s TI:22:05:45 on_battery = yes 1220s Executing: upower/upower-integration.test 1225s Executing: upower/upower-integration.test 1230s Executing: upower/upower-integration.test 1235s Executing: upower/upower-integration.test 1238s TI:22:06:04 up_daemon_poll_dispatch: refreshing BAT0 1238s TI:22:06:04 using present voltage (alternate) 1238s TI:22:06:04 device BAT0 has unknown state, not saving history 1238s TI:22:06:04 on_battery = yes 1240s Executing: upower/upower-integration.test 1243s TI:22:06:09 up_daemon_poll_dispatch: refreshing BAT0 1243s TI:22:06:09 using present voltage (alternate) 1243s TI:22:06:09 device BAT0 has unknown state, not saving history 1243s TI:22:06:09 on_battery = yes 1245s Executing: upower/upower-integration.test 1248s TI:22:06:14 up_daemon_poll_dispatch: refreshing BAT0 1248s TI:22:06:14 using present voltage (alternate) 1248s TI:22:06:14 device BAT0 has unknown state, not saving history 1248s TI:22:06:14 on_battery = yes 1250s Executing: upower/upower-integration.test 1253s TI:22:06:19 up_daemon_poll_dispatch: refreshing BAT0 1253s TI:22:06:19 using present voltage (alternate) 1253s TI:22:06:19 deferring as earlier timeout is already queued 1253s TI:22:06:19 deferring as earlier timeout is already queued 1253s TI:22:06:19 deferring as earlier timeout is already queued 1253s TI:22:06:19 deferring as earlier timeout is already queued 1253s TI:22:06:19 on_battery = no 1255s Executing: upower/upower-integration.test 1260s Executing: upower/upower-integration.test 1260s TI:22:06:26 Handling SIGTERM 1261s TI:22:06:26 culled 0 of 3 1261s TI:22:06:26 saved /tmp/upower-history-qgymnkv4/history-rate-80.dat 1261s TI:22:06:26 culled 0 of 3 1261s TI:22:06:26 saved /tmp/upower-history-qgymnkv4/history-charge-80.dat 1261s TI:22:06:26 culled 0 of 2 1261s TI:22:06:26 saved /tmp/upower-history-qgymnkv4/history-time-full-80.dat 1261s TI:22:06:26 culled 0 of 3 1261s TI:22:06:26 saved /tmp/upower-history-qgymnkv4/history-time-empty-80.dat 1265s .Executing: upower/upower-integration.test 1266s 1742076392.294 GetAll /org/freedesktop/PolicyKit1/Authority org.freedesktop.PolicyKit1.Authority 1266s 1742076392.367 GetAll /org/freedesktop/login1 org.freedesktop.login1.Manager 1266s 1742076392.430 Inhibit "sleep" "UPower" "Pause device polling" "delay" 1266s TI:22:06:32 Acquired inhibitor lock (7, delay) 1266s TI:22:06:32 Starting upowerd version 1.90.7 1266s TI:22:06:32 cannot find a keyboard backlight 1266s TI:22:06:32 Exported UpDevice with path /org/freedesktop/UPower/devices/DisplayDevice 1266s TI:22:06:32 daemon now coldplug 1266s TI:22:06:32 registering subsystem : input 1266s TI:22:06:32 registering subsystem : power_supply 1266s TI:22:06:32 Received uevent add on device /sys/devices/BAT0 1266s TI:22:06:32 using present voltage (alternate) 1266s TI:22:06:32 failed to read battery charge threshold: Failed to open file “/tmp/upower-history-gw7wi72m/charging-threshold-status”: No such file or directory 1266s TI:22:06:32 using id: 80 1266s TI:22:06:32 failed to get data from /tmp/upower-history-gw7wi72m/history-rate-80.dat as file does not exist 1266s TI:22:06:32 failed to get data from /tmp/upower-history-gw7wi72m/history-charge-80.dat as file does not exist 1266s TI:22:06:32 failed to get data from /tmp/upower-history-gw7wi72m/history-time-full-80.dat as file does not exist 1266s TI:22:06:32 failed to get data from /tmp/upower-history-gw7wi72m/history-time-empty-80.dat as file does not exist 1266s TI:22:06:32 saving in 600 seconds 1266s TI:22:06:32 saving to disk earlier due to low power 1266s TI:22:06:32 saving in 5 seconds 1266s TI:22:06:32 added native-path: BAT0 1266s TI:22:06:32 Exported UpDevice with path /org/freedesktop/UPower/devices/battery_BAT0 1266s TI:22:06:32 device BAT0 has parent id: (null) 1266s TI:22:06:32 Got new device from udev enumerator: 0x555c0fccb5f0 1266s TI:22:06:32 added BAT0 1266s TI:22:06:32 emitting added: /org/freedesktop/UPower/devices/battery_BAT0 1266s TI:22:06:32 registering subsystem : usb 1266s TI:22:06:32 registering subsystem : usbmisc 1266s TI:22:06:32 registering subsystem : sound 1266s TI:22:06:32 on_battery = yes 1266s TI:22:06:32 warning_level = critical 1266s TI:22:06:32 daemon now not coldplug 1266s TI:22:06:32 BlueZ disappeared 1266s TI:22:06:32 Handling SIGTERM 1266s TI:22:06:32 culled 0 of 1 1266s TI:22:06:32 saved /tmp/upower-history-gw7wi72m/history-rate-80.dat 1266s TI:22:06:32 culled 0 of 2 1266s TI:22:06:32 saved /tmp/upower-history-gw7wi72m/history-charge-80.dat 1266s TI:22:06:32 culled 0 of 1 1266s TI:22:06:32 saved /tmp/upower-history-gw7wi72m/history-time-full-80.dat 1266s TI:22:06:32 culled 0 of 1 1266s TI:22:06:32 saved /tmp/upower-history-gw7wi72m/history-time-empty-80.dat 1270s .Executing: upower/upower-integration.test 1272s 1742076398.188 GetAll /org/freedesktop/PolicyKit1/Authority org.freedesktop.PolicyKit1.Authority 1272s 1742076398.267 GetAll /org/freedesktop/login1 org.freedesktop.login1.Manager 1272s 1742076398.296 Inhibit "sleep" "UPower" "Pause device polling" "delay" 1272s TI:22:06:38 Acquired inhibitor lock (7, delay) 1272s TI:22:06:38 Starting upowerd version 1.90.7 1272s TI:22:06:38 cannot find a keyboard backlight 1272s TI:22:06:38 Exported UpDevice with path /org/freedesktop/UPower/devices/DisplayDevice 1272s TI:22:06:38 daemon now coldplug 1272s TI:22:06:38 registering subsystem : input 1272s TI:22:06:38 registering subsystem : power_supply 1272s TI:22:06:38 Received uevent add on device /sys/devices/BAT0 1272s TI:22:06:38 using present voltage (alternate) 1272s TI:22:06:38 failed to read battery charge threshold: Failed to open file “/tmp/upower-history-lon2a5n_/charging-threshold-status”: No such file or directory 1272s TI:22:06:38 using id: 80 1272s TI:22:06:38 failed to get data from /tmp/upower-history-lon2a5n_/history-rate-80.dat as file does not exist 1272s TI:22:06:38 failed to get data from /tmp/upower-history-lon2a5n_/history-charge-80.dat as file does not exist 1272s TI:22:06:38 failed to get data from /tmp/upower-history-lon2a5n_/history-time-full-80.dat as file does not exist 1272s TI:22:06:38 failed to get data from /tmp/upower-history-lon2a5n_/history-time-empty-80.dat as file does not exist 1272s TI:22:06:38 saving in 600 seconds 1272s TI:22:06:38 saving in 600 seconds 1272s TI:22:06:38 added native-path: BAT0 1272s TI:22:06:38 Exported UpDevice with path /org/freedesktop/UPower/devices/battery_BAT0 1272s TI:22:06:38 device BAT0 has parent id: (null) 1272s TI:22:06:38 Got new device from udev enumerator: 0x555884357670 1272s TI:22:06:38 added BAT0 1272s TI:22:06:38 emitting added: /org/freedesktop/UPower/devices/battery_BAT0 1272s TI:22:06:38 registering subsystem : usb 1272s TI:22:06:38 registering subsystem : usbmisc 1272s TI:22:06:38 registering subsystem : sound 1272s TI:22:06:38 on_battery = no 1272s TI:22:06:38 warning_level = none 1272s TI:22:06:38 BlueZ disappeared 1272s TI:22:06:38 daemon now not coldplug 1272s TI:22:06:38 Handling SIGTERM 1272s TI:22:06:38 culled 0 of 1 1272s TI:22:06:38 saved /tmp/upower-history-lon2a5n_/history-rate-80.dat 1272s TI:22:06:38 culled 0 of 2 1272s TI:22:06:38 saved /tmp/upower-history-lon2a5n_/history-charge-80.dat 1272s TI:22:06:38 culled 0 of 1 1272s TI:22:06:38 saved /tmp/upower-history-lon2a5n_/history-time-full-80.dat 1272s TI:22:06:38 culled 0 of 1 1272s TI:22:06:38 saved /tmp/upower-history-lon2a5n_/history-time-empty-80.dat 1275s .Executing: upower/upower-integration.test 1280s Executing: upower/upower-integration.test 1281s 1742076407.513 AddAdapter "hci0" "my-computer" 1281s 1742076407.564 emit / org.freedesktop.DBus.ObjectManager.InterfacesAdded "/org/bluez/hci0" {"org.bluez.Adapter1": {"UUIDs": ["00001200-0000-1000-8000-00805f9b34fb", "00001800-0000-1000-8000-00805f9b34fb", "00001801-0000-1000-8000-00805f9b34fb", "0000110e-0000-1000-8000-00805f9b34fb", "0000110c-0000-1000-8000-00805f9b34fb"], "Discoverable": False, "Discovering": False, "Pairable": True, "Powered": True, "Address": "00:01:02:03:04:05", "AddressType": "public", "Alias": "my-computer", "Modalias": "usb:v1D6Bp0245d050A", "Name": "my-computer", "Class": 268, "DiscoverableTimeout": 180, "PairableTimeout": 0, "Roles": ["central", "peripheral"]}} 1281s 1742076407.641 AddDevice "hci0" "11:22:33:44:AA:BB" "Computer" 1281s 1742076407.658 emit / org.freedesktop.DBus.ObjectManager.InterfacesAdded "/org/bluez/hci0/dev_11_22_33_44_AA_BB" {"org.bluez.Device1": {"Address": "11:22:33:44:AA:BB", "AddressType": "public", "Name": "Computer", "Icon": "phone", "Class": 5898764, "Appearance": 0, "UUIDs": [], "Paired": False, "Connected": False, "Trusted": False, "Blocked": False, "WakeAllowed": False, "Alias": "Computer", "Adapter": "/org/bluez/hci0", "LegacyPairing": False, "Modalias": "bluetooth:v000Fp1200d1436", "RSSI": -79, "TxPower": 0, "ManufacturerData": [], "ServiceData": [], "ServicesResolved": False, "AdvertisingFlags": [], "AdvertisingData": []}} 1281s 1742076407.723 emit /org/bluez/hci0/dev_11_22_33_44_AA_BB org.freedesktop.DBus.Properties.PropertiesChanged "org.bluez.Device1" {"Class": 7078156} [] 1281s 1742076407.790 emit / org.freedesktop.DBus.ObjectManager.InterfacesAdded "/org/bluez/hci0/dev_11_22_33_44_AA_BB" {"org.bluez.Battery1": {"Percentage": 99}} 1282s 1742076408.227 GetAll /org/freedesktop/PolicyKit1/Authority org.freedesktop.PolicyKit1.Authority 1282s 1742076408.328 GetAll /org/freedesktop/login1 org.freedesktop.login1.Manager 1282s 1742076408.414 Inhibit "sleep" "UPower" "Pause device polling" "delay" 1282s TI:22:06:48 Acquired inhibitor lock (7, delay) 1282s TI:22:06:48 Starting upowerd version 1.90.7 1282s TI:22:06:48 cannot find a keyboard backlight 1282s TI:22:06:48 Exported UpDevice with path /org/freedesktop/UPower/devices/DisplayDevice 1282s TI:22:06:48 daemon now coldplug 1282s TI:22:06:48 registering subsystem : input 1282s TI:22:06:48 registering subsystem : power_supply 1282s TI:22:06:48 registering subsystem : usb 1282s TI:22:06:48 registering subsystem : usbmisc 1282s TI:22:06:48 registering subsystem : sound 1282s TI:22:06:48 on_battery = no 1282s TI:22:06:48 warning_level = none 1282s TI:22:06:48 device (null) has unknown state, not saving history 1282s 1742076408.597 GetManagedObjects 1282s TI:22:06:48 BlueZ appeared 1282s 1742076408.734 GetAll /org/bluez/hci0/dev_11_22_33_44_AA_BB org.bluez.Device1 1282s 1742076408.763 GetAll /org/bluez/hci0/dev_11_22_33_44_AA_BB org.bluez.Battery1 1282s TI:22:06:48 using id: Computer-11:22:33:44:AA:BB 1282s TI:22:06:48 failed to get data from /tmp/upower-history-be0dn1dn/history-rate-Computer-11:22:33:44:AA:BB.dat as file does not exist 1282s TI:22:06:48 failed to get data from /tmp/upower-history-be0dn1dn/history-charge-Computer-11:22:33:44:AA:BB.dat as file does not exist 1282s TI:22:06:48 failed to get data from /tmp/upower-history-be0dn1dn/history-time-full-Computer-11:22:33:44:AA:BB.dat as file does not exist 1282s TI:22:06:48 failed to get data from /tmp/upower-history-be0dn1dn/history-time-empty-Computer-11:22:33:44:AA:BB.dat as file does not exist 1282s TI:22:06:48 saving in 600 seconds 1282s TI:22:06:48 device /org/bluez/hci0/dev_11_22_33_44_AA_BB has unknown state, not saving history 1282s TI:22:06:48 failed to refresh /org/bluez/hci0/dev_11_22_33_44_AA_BB 1282s TI:22:06:48 Exported UpDevice with path /org/freedesktop/UPower/devices/computer_dev_11_22_33_44_AA_BB 1282s TI:22:06:48 emitting device-added: /org/bluez/hci0/dev_11_22_33_44_AA_BB 1282s TI:22:06:48 added /org/bluez/hci0/dev_11_22_33_44_AA_BB 1282s TI:22:06:48 emitting added: /org/freedesktop/UPower/devices/computer_dev_11_22_33_44_AA_BB 1282s TI:22:06:48 on_battery = no 1282s TI:22:06:48 daemon now not coldplug 1283s TI:22:06:49 Handling SIGTERM 1283s TI:22:06:49 culled 0 of 1 1283s TI:22:06:49 saved /tmp/upower-history-be0dn1dn/history-rate-Computer-11:22:33:44:AA:BB.dat 1283s TI:22:06:49 culled 0 of 1 1283s TI:22:06:49 saved /tmp/upower-history-be0dn1dn/history-charge-Computer-11:22:33:44:AA:BB.dat 1283s TI:22:06:49 culled 0 of 1 1283s TI:22:06:49 saved /tmp/upower-history-be0dn1dn/history-time-full-Computer-11:22:33:44:AA:BB.dat 1283s TI:22:06:49 culled 0 of 1 1283s TI:22:06:49 saved /tmp/upower-history-be0dn1dn/history-time-empty-Computer-11:22:33:44:AA:BB.dat 1285s .Executing: upower/upower-integration.test 1290s Executing: upower/upower-integration.test 1291s 1742076417.612 AddAdapter "hci0" "my-computer" 1291s 1742076417.642 emit / org.freedesktop.DBus.ObjectManager.InterfacesAdded "/org/bluez/hci0" {"org.bluez.Adapter1": {"UUIDs": ["00001200-0000-1000-8000-00805f9b34fb", "00001800-0000-1000-8000-00805f9b34fb", "00001801-0000-1000-8000-00805f9b34fb", "0000110e-0000-1000-8000-00805f9b34fb", "0000110c-0000-1000-8000-00805f9b34fb"], "Discoverable": False, "Discovering": False, "Pairable": True, "Powered": True, "Address": "00:01:02:03:04:05", "AddressType": "public", "Alias": "my-computer", "Modalias": "usb:v1D6Bp0245d050A", "Name": "my-computer", "Class": 268, "DiscoverableTimeout": 180, "PairableTimeout": 0, "Roles": ["central", "peripheral"]}} 1291s 1742076417.662 AddDevice "hci0" "11:22:33:44:AA:BB" "WH-1000XM3" 1291s 1742076417.686 emit / org.freedesktop.DBus.ObjectManager.InterfacesAdded "/org/bluez/hci0/dev_11_22_33_44_AA_BB" {"org.bluez.Device1": {"Address": "11:22:33:44:AA:BB", "AddressType": "public", "Name": "WH-1000XM3", "Icon": "phone", "Class": 5898764, "Appearance": 0, "UUIDs": [], "Paired": False, "Connected": False, "Trusted": False, "Blocked": False, "WakeAllowed": False, "Alias": "WH-1000XM3", "Adapter": "/org/bluez/hci0", "LegacyPairing": False, "Modalias": "bluetooth:v000Fp1200d1436", "RSSI": -79, "TxPower": 0, "ManufacturerData": [], "ServiceData": [], "ServicesResolved": False, "AdvertisingFlags": [], "AdvertisingData": []}} 1291s 1742076417.734 emit /org/bluez/hci0/dev_11_22_33_44_AA_BB org.freedesktop.DBus.Properties.PropertiesChanged "org.bluez.Device1" {"Class": 2360324} [] 1291s 1742076417.784 emit / org.freedesktop.DBus.ObjectManager.InterfacesAdded "/org/bluez/hci0/dev_11_22_33_44_AA_BB" {"org.bluez.Battery1": {"Percentage": 99}} 1292s 1742076418.304 GetAll /org/freedesktop/PolicyKit1/Authority org.freedesktop.PolicyKit1.Authority 1292s 1742076418.436 GetAll /org/freedesktop/login1 org.freedesktop.login1.Manager 1292s 1742076418.470 Inhibit "sleep" "UPower" "Pause device polling" "delay" 1292s TI:22:06:58 Acquired inhibitor lock (7, delay) 1292s TI:22:06:58 Starting upowerd version 1.90.7 1292s TI:22:06:58 cannot find a keyboard backlight 1292s TI:22:06:58 Exported UpDevice with path /org/freedesktop/UPower/devices/DisplayDevice 1292s TI:22:06:58 daemon now coldplug 1292s TI:22:06:58 registering subsystem : input 1292s TI:22:06:58 registering subsystem : power_supply 1292s TI:22:06:58 registering subsystem : usb 1292s TI:22:06:58 registering subsystem : usbmisc 1292s TI:22:06:58 registering subsystem : sound 1292s TI:22:06:58 on_battery = no 1292s TI:22:06:58 warning_level = none 1292s TI:22:06:58 device (null) has unknown state, not saving history 1292s 1742076418.668 GetManagedObjects 1292s TI:22:06:58 BlueZ appeared 1292s 1742076418.798 GetAll /org/bluez/hci0/dev_11_22_33_44_AA_BB org.bluez.Device1 1292s 1742076418.843 GetAll /org/bluez/hci0/dev_11_22_33_44_AA_BB org.bluez.Battery1 1292s TI:22:06:58 using id: WH-1000XM3-11:22:33:44:AA:BB 1292s TI:22:06:58 failed to get data from /tmp/upower-history-kd6gwokv/history-rate-WH-1000XM3-11:22:33:44:AA:BB.dat as file does not exist 1292s TI:22:06:58 failed to get data from /tmp/upower-history-kd6gwokv/history-charge-WH-1000XM3-11:22:33:44:AA:BB.dat as file does not exist 1292s TI:22:06:58 failed to get data from /tmp/upower-history-kd6gwokv/history-time-full-WH-1000XM3-11:22:33:44:AA:BB.dat as file does not exist 1292s TI:22:06:58 failed to get data from /tmp/upower-history-kd6gwokv/history-time-empty-WH-1000XM3-11:22:33:44:AA:BB.dat as file does not exist 1292s TI:22:06:58 saving in 600 seconds 1292s TI:22:06:58 device /org/bluez/hci0/dev_11_22_33_44_AA_BB has unknown state, not saving history 1292s TI:22:06:58 failed to refresh /org/bluez/hci0/dev_11_22_33_44_AA_BB 1292s TI:22:06:58 Exported UpDevice with path /org/freedesktop/UPower/devices/headset_dev_11_22_33_44_AA_BB 1292s TI:22:06:58 emitting device-added: /org/bluez/hci0/dev_11_22_33_44_AA_BB 1292s TI:22:06:58 added /org/bluez/hci0/dev_11_22_33_44_AA_BB 1292s TI:22:06:58 emitting added: /org/freedesktop/UPower/devices/headset_dev_11_22_33_44_AA_BB 1292s TI:22:06:58 on_battery = no 1292s TI:22:06:58 daemon now not coldplug 1293s TI:22:06:59 Handling SIGTERM 1293s TI:22:06:59 culled 0 of 1 1293s TI:22:06:59 saved /tmp/upower-history-kd6gwokv/history-rate-WH-1000XM3-11:22:33:44:AA:BB.dat 1293s TI:22:06:59 culled 0 of 1 1293s TI:22:06:59 saved /tmp/upower-history-kd6gwokv/history-charge-WH-1000XM3-11:22:33:44:AA:BB.dat 1293s TI:22:06:59 culled 0 of 1 1293s TI:22:06:59 saved /tmp/upower-history-kd6gwokv/history-time-full-WH-1000XM3-11:22:33:44:AA:BB.dat 1293s TI:22:06:59 culled 0 of 1 1293s TI:22:06:59 saved /tmp/upower-history-kd6gwokv/history-time-empty-WH-1000XM3-11:22:33:44:AA:BB.dat 1295s .Executing: upower/upower-integration.test 1300s Executing: upower/upower-integration.test 1301s 1742076427.399 AddAdapter "hci0" "my-computer" 1301s 1742076427.420 emit / org.freedesktop.DBus.ObjectManager.InterfacesAdded "/org/bluez/hci0" {"org.bluez.Adapter1": {"UUIDs": ["00001200-0000-1000-8000-00805f9b34fb", "00001800-0000-1000-8000-00805f9b34fb", "00001801-0000-1000-8000-00805f9b34fb", "0000110e-0000-1000-8000-00805f9b34fb", "0000110c-0000-1000-8000-00805f9b34fb"], "Discoverable": False, "Discovering": False, "Pairable": True, "Powered": True, "Address": "00:01:02:03:04:05", "AddressType": "public", "Alias": "my-computer", "Modalias": "usb:v1D6Bp0245d050A", "Name": "my-computer", "Class": 268, "DiscoverableTimeout": 180, "PairableTimeout": 0, "Roles": ["central", "peripheral"]}} 1301s 1742076427.444 AddDevice "hci0" "11:22:33:44:AA:BB" "Polar H7" 1301s 1742076427.465 emit / org.freedesktop.DBus.ObjectManager.InterfacesAdded "/org/bluez/hci0/dev_11_22_33_44_AA_BB" {"org.bluez.Device1": {"Address": "11:22:33:44:AA:BB", "AddressType": "public", "Name": "Polar H7", "Icon": "phone", "Class": 5898764, "Appearance": 0, "UUIDs": [], "Paired": False, "Connected": False, "Trusted": False, "Blocked": False, "WakeAllowed": False, "Alias": "Polar H7", "Adapter": "/org/bluez/hci0", "LegacyPairing": False, "Modalias": "bluetooth:v000Fp1200d1436", "RSSI": -79, "TxPower": 0, "ManufacturerData": [], "ServiceData": [], "ServicesResolved": False, "AdvertisingFlags": [], "AdvertisingData": []}} 1301s 1742076427.518 emit /org/bluez/hci0/dev_11_22_33_44_AA_BB org.freedesktop.DBus.Properties.PropertiesChanged "org.bluez.Device1" {"Appearance": 833} [] 1301s 1742076427.624 emit / org.freedesktop.DBus.ObjectManager.InterfacesAdded "/org/bluez/hci0/dev_11_22_33_44_AA_BB" {"org.bluez.Battery1": {"Percentage": 99}} 1302s 1742076428.047 GetAll /org/freedesktop/PolicyKit1/Authority org.freedesktop.PolicyKit1.Authority 1302s 1742076428.156 GetAll /org/freedesktop/login1 org.freedesktop.login1.Manager 1302s 1742076428.190 Inhibit "sleep" "UPower" "Pause device polling" "delay" 1302s TI:22:07:08 Acquired inhibitor lock (7, delay) 1302s TI:22:07:08 Starting upowerd version 1.90.7 1302s TI:22:07:08 cannot find a keyboard backlight 1302s TI:22:07:08 Exported UpDevice with path /org/freedesktop/UPower/devices/DisplayDevice 1302s TI:22:07:08 daemon now coldplug 1302s TI:22:07:08 registering subsystem : input 1302s TI:22:07:08 registering subsystem : power_supply 1302s TI:22:07:08 registering subsystem : usb 1302s TI:22:07:08 registering subsystem : usbmisc 1302s TI:22:07:08 registering subsystem : sound 1302s TI:22:07:08 on_battery = no 1302s TI:22:07:08 warning_level = none 1302s TI:22:07:08 device (null) has unknown state, not saving history 1302s TI:22:07:08 daemon now not coldplug 1302s 1742076428.369 GetManagedObjects 1302s TI:22:07:08 BlueZ appeared 1302s 1742076428.473 GetAll /org/bluez/hci0/dev_11_22_33_44_AA_BB org.bluez.Device1 1302s 1742076428.503 GetAll /org/bluez/hci0/dev_11_22_33_44_AA_BB org.bluez.Battery1 1302s TI:22:07:08 using id: Polar_H7-11:22:33:44:AA:BB 1302s TI:22:07:08 failed to get data from /tmp/upower-history-r1amislv/history-rate-Polar_H7-11:22:33:44:AA:BB.dat as file does not exist 1302s TI:22:07:08 failed to get data from /tmp/upower-history-r1amislv/history-charge-Polar_H7-11:22:33:44:AA:BB.dat as file does not exist 1302s TI:22:07:08 failed to get data from /tmp/upower-history-r1amislv/history-time-full-Polar_H7-11:22:33:44:AA:BB.dat as file does not exist 1302s TI:22:07:08 failed to get data from /tmp/upower-history-r1amislv/history-time-empty-Polar_H7-11:22:33:44:AA:BB.dat as file does not exist 1302s TI:22:07:08 saving in 600 seconds 1302s TI:22:07:08 device /org/bluez/hci0/dev_11_22_33_44_AA_BB has unknown state, not saving history 1302s TI:22:07:08 failed to refresh /org/bluez/hci0/dev_11_22_33_44_AA_BB 1302s TI:22:07:08 Exported UpDevice with path /org/freedesktop/UPower/devices/bluetooth_generic_dev_11_22_33_44_AA_BB 1302s TI:22:07:08 emitting device-added: /org/bluez/hci0/dev_11_22_33_44_AA_BB 1302s TI:22:07:08 added /org/bluez/hci0/dev_11_22_33_44_AA_BB 1302s TI:22:07:08 emitting added: /org/freedesktop/UPower/devices/bluetooth_generic_dev_11_22_33_44_AA_BB 1302s TI:22:07:08 on_battery = no 1303s TI:22:07:09 Handling SIGTERM 1303s TI:22:07:09 culled 0 of 1 1303s TI:22:07:09 saved /tmp/upower-history-r1amislv/history-rate-Polar_H7-11:22:33:44:AA:BB.dat 1303s TI:22:07:09 culled 0 of 1 1303s TI:22:07:09 saved /tmp/upower-history-r1amislv/history-charge-Polar_H7-11:22:33:44:AA:BB.dat 1303s TI:22:07:09 culled 0 of 1 1303s TI:22:07:09 saved /tmp/upower-history-r1amislv/history-time-full-Polar_H7-11:22:33:44:AA:BB.dat 1303s TI:22:07:09 culled 0 of 1 1303s TI:22:07:09 saved /tmp/upower-history-r1amislv/history-time-empty-Polar_H7-11:22:33:44:AA:BB.dat 1305s .Executing: upower/upower-integration.test 1308s 1742076434.218 GetAll /org/freedesktop/PolicyKit1/Authority org.freedesktop.PolicyKit1.Authority 1308s 1742076434.307 GetAll /org/freedesktop/login1 org.freedesktop.login1.Manager 1308s 1742076434.348 Inhibit "sleep" "UPower" "Pause device polling" "delay" 1308s TI:22:07:14 Acquired inhibitor lock (9, delay) 1308s TI:22:07:14 Starting upowerd version 1.90.7 1308s TI:22:07:14 cannot find a keyboard backlight 1308s TI:22:07:14 Exported UpDevice with path /org/freedesktop/UPower/devices/DisplayDevice 1308s TI:22:07:14 daemon now coldplug 1308s TI:22:07:14 not a switch [/tmp/umockdev.FIZ922/sys/devices/devices/pci0000:00/0000:00:14.0/usb2/2-7/2-7:1.0/bluetooth/hci0/hci0:256/0005:046D:B00D.0002/input/input22/../capabilities/sw] 1308s TI:22:07:14 not a switch [/tmp/umockdev.FIZ922/sys/devices/devices/pci0000:00/0000:00:14.0/usb2/2-7/2-7:1.0/bluetooth/hci0/hci0:256/0005:046D:B00D.0002/input/input22/capabilities/sw] 1308s TI:22:07:14 not a switch [/tmp/umockdev.FIZ922/sys/devices/devices/pci0000:00/0000:00:14.0/usb2/2-7/2-7:1.0/bluetooth/hci0/hci0:256/0005:046D:B00D.0002/input/input22/mouse1/../capabilities/sw] 1308s TI:22:07:14 not a switch [/tmp/umockdev.FIZ922/sys/devices/devices/pci0000:00/0000:00:14.0/usb2/2-7/2-7:1.0/bluetooth/hci0/hci0:256/0005:046D:B00D.0002/input/input22/mouse1/capabilities/sw] 1308s TI:22:07:14 registering subsystem : input 1308s TI:22:07:14 Received uevent add on device /sys/devices/devices/pci0000:00/0000:00:14.0/usb2/2-7/2-7:1.0/bluetooth/hci0/hci0:256/0005:046D:B00D.0002/input/input22 1308s TI:22:07:14 device /sys/devices/devices/pci0000:00/0000:00:14.0/usb2/2-7/2-7:1.0/bluetooth/hci0/hci0:256/0005:046D:B00D.0002/input/input22 has parent id: /sys/devices/devices/pci0000:00/0000:00:14.0/usb2/2-7/2-7:1.0/bluetooth/hci0/hci0:256 1308s TI:22:07:14 Received uevent add on device /sys/devices/devices/pci0000:00/0000:00:14.0/usb2/2-7/2-7:1.0/bluetooth/hci0/hci0:256/0005:046D:B00D.0002/input/input22/mouse1 1308s TI:22:07:14 device /sys/devices/devices/pci0000:00/0000:00:14.0/usb2/2-7/2-7:1.0/bluetooth/hci0/hci0:256/0005:046D:B00D.0002/input/input22/mouse1 has parent id: (null) 1308s TI:22:07:14 registering subsystem : power_supply 1308s TI:22:07:14 Received uevent add on device /sys/devices/devices/pci0000:00/0000:00:14.0/usb2/2-7/2-7:1.0/bluetooth/hci0/hci0:256/0005:046D:B00D.0002/power_supply/hid-00:1f:20:96:33:47-battery 1308s TI:22:07:14 failed to coldplug hid-00:1f:20:96:33:47-battery 1308s TI:22:07:14 using id: generic_id 1308s TI:22:07:14 failed to get data from /tmp/upower-history-6qb_r53i/history-rate-generic_id.dat as file does not exist 1308s TI:22:07:14 failed to get data from /tmp/upower-history-6qb_r53i/history-charge-generic_id.dat as file does not exist 1308s TI:22:07:14 failed to get data from /tmp/upower-history-6qb_r53i/history-time-full-generic_id.dat as file does not exist 1308s TI:22:07:14 failed to get data from /tmp/upower-history-6qb_r53i/history-time-empty-generic_id.dat as file does not exist 1308s TI:22:07:14 saving in 600 seconds 1308s TI:22:07:14 device hid-00:1f:20:96:33:47-battery has unknown state, not saving history 1308s TI:22:07:14 culled 0 of 1 1308s TI:22:07:14 saved /tmp/upower-history-6qb_r53i/history-rate-generic_id.dat 1308s TI:22:07:14 culled 0 of 1 1308s TI:22:07:14 saved /tmp/upower-history-6qb_r53i/history-charge-generic_id.dat 1308s TI:22:07:14 culled 0 of 1 1308s TI:22:07:14 saved /tmp/upower-history-6qb_r53i/history-time-full-generic_id.dat 1308s TI:22:07:14 culled 0 of 1 1308s TI:22:07:14 saved /tmp/upower-history-6qb_r53i/history-time-empty-generic_id.dat 1308s TI:22:07:14 using id: Fancy_BT_mouse 1308s TI:22:07:14 failed to get data from /tmp/upower-history-6qb_r53i/history-rate-Fancy_BT_mouse.dat as file does not exist 1308s TI:22:07:14 failed to get data from /tmp/upower-history-6qb_r53i/history-charge-Fancy_BT_mouse.dat as file does not exist 1308s TI:22:07:14 failed to get data from /tmp/upower-history-6qb_r53i/history-time-full-Fancy_BT_mouse.dat as file does not exist 1308s TI:22:07:14 failed to get data from /tmp/upower-history-6qb_r53i/history-time-empty-Fancy_BT_mouse.dat as file does not exist 1308s TI:22:07:14 saving in 600 seconds 1308s TI:22:07:14 saving in 600 seconds 1308s TI:22:07:14 added native-path: hid-00:1f:20:96:33:47-battery 1308s TI:22:07:14 Exported UpDevice with path /org/freedesktop/UPower/devices/battery_hid_00o1fo20o96o33o47_battery 1308s TI:22:07:14 device hid-00:1f:20:96:33:47-battery has parent id: /sys/devices/devices/pci0000:00/0000:00:14.0/usb2/2-7/2-7:1.0/bluetooth/hci0/hci0:256 1308s TI:22:07:14 up_device_supply_sibling_discovered (device: /sys/devices/devices/pci0000:00/0000:00:14.0/usb2/2-7/2-7:1.0/bluetooth/hci0/hci0:256/0005:046D:B00D.0002/power_supply/hid-00:1f:20:96:33:47-battery, sibling: /sys/devices/devices/pci0000:00/0000:00:14.0/usb2/2-7/2-7:1.0/bluetooth/hci0/hci0:256/0005:046D:B00D.0002/input/input22) 1308s TI:22:07:14 Type changed from battery to mouse 1308s TI:22:07:14 Got new device from udev enumerator: 0x556146451ca0 1308s TI:22:07:14 added hid-00:1f:20:96:33:47-battery 1308s TI:22:07:14 emitting added: /org/freedesktop/UPower/devices/battery_hid_00o1fo20o96o33o47_battery 1308s TI:22:07:14 registering subsystem : usb 1308s TI:22:07:14 registering subsystem : usbmisc 1308s TI:22:07:14 registering subsystem : sound 1308s TI:22:07:14 on_battery = no 1308s TI:22:07:14 warning_level = none 1308s TI:22:07:14 device (null) has unknown state, not saving history 1308s TI:22:07:14 daemon now not coldplug 1308s TI:22:07:14 BlueZ disappeared 1308s TI:22:07:14 Handling SIGTERM 1308s TI:22:07:14 culled 0 of 1 1308s TI:22:07:14 saved /tmp/upower-history-6qb_r53i/history-rate-Fancy_BT_mouse.dat 1308s TI:22:07:14 culled 0 of 2 1308s TI:22:07:14 saved /tmp/upower-history-6qb_r53i/history-charge-Fancy_BT_mouse.dat 1308s TI:22:07:14 culled 0 of 1 1308s TI:22:07:14 saved /tmp/upower-history-6qb_r53i/history-time-full-Fancy_BT_mouse.dat 1308s TI:22:07:14 culled 0 of 1 1308s TI:22:07:14 saved /tmp/upower-history-6qb_r53i/history-time-empty-Fancy_BT_mouse.dat 1310s .Executing: upower/upower-integration.test 1315s Executing: upower/upower-integration.test 1316s 1742076442.004 GetAll /org/freedesktop/PolicyKit1/Authority org.freedesktop.PolicyKit1.Authority 1316s 1742076442.093 GetAll /org/freedesktop/login1 org.freedesktop.login1.Manager 1316s 1742076442.130 Inhibit "sleep" "UPower" "Pause device polling" "delay" 1316s TI:22:07:22 Acquired inhibitor lock (7, delay) 1316s TI:22:07:22 Starting upowerd version 1.90.7 1316s TI:22:07:22 cannot find a keyboard backlight 1316s TI:22:07:22 Exported UpDevice with path /org/freedesktop/UPower/devices/DisplayDevice 1316s TI:22:07:22 daemon now coldplug 1316s TI:22:07:22 not a switch [/tmp/umockdev.XDWN32/sys/devices/devices/pci0000:00/0000:00:14.0/usb2/2-7/2-7:1.0/bluetooth/hci0/hci0:256/0005:046D:B00D.0002/input/input22/../capabilities/sw] 1316s TI:22:07:22 not a switch [/tmp/umockdev.XDWN32/sys/devices/devices/pci0000:00/0000:00:14.0/usb2/2-7/2-7:1.0/bluetooth/hci0/hci0:256/0005:046D:B00D.0002/input/input22/capabilities/sw] 1316s TI:22:07:22 registering subsystem : input 1316s TI:22:07:22 Received uevent add on device /sys/devices/devices/pci0000:00/0000:00:14.0/usb2/2-7/2-7:1.0/bluetooth/hci0/hci0:256/0005:046D:B00D.0002/input/input22 1316s TI:22:07:22 device /sys/devices/devices/pci0000:00/0000:00:14.0/usb2/2-7/2-7:1.0/bluetooth/hci0/hci0:256/0005:046D:B00D.0002/input/input22 has parent id: /sys/devices/devices/pci0000:00/0000:00:14.0/usb2/2-7/2-7:1.0/bluetooth/hci0/hci0:256 1316s TI:22:07:22 registering subsystem : power_supply 1316s TI:22:07:22 Received uevent add on device /sys/devices/devices/pci0000:00/0000:00:14.0/usb2/2-7/2-7:1.0/bluetooth/hci0/hci0:256/0005:046D:B00D.0002/power_supply/hid-00:1f:20:96:33:47-battery 1316s TI:22:07:22 failed to coldplug hid-00:1f:20:96:33:47-battery 1316s TI:22:07:22 using id: generic_id 1316s TI:22:07:22 failed to get data from /tmp/upower-history-gy4njzzw/history-rate-generic_id.dat as file does not exist 1316s TI:22:07:22 failed to get data from /tmp/upower-history-gy4njzzw/history-charge-generic_id.dat as file does not exist 1316s TI:22:07:22 failed to get data from /tmp/upower-history-gy4njzzw/history-time-full-generic_id.dat as file does not exist 1316s TI:22:07:22 failed to get data from /tmp/upower-history-gy4njzzw/history-time-empty-generic_id.dat as file does not exist 1316s TI:22:07:22 saving in 600 seconds 1316s TI:22:07:22 device hid-00:1f:20:96:33:47-battery has unknown state, not saving history 1316s TI:22:07:22 culled 0 of 1 1316s TI:22:07:22 saved /tmp/upower-history-gy4njzzw/history-rate-generic_id.dat 1316s TI:22:07:22 culled 0 of 1 1316s TI:22:07:22 saved /tmp/upower-history-gy4njzzw/history-charge-generic_id.dat 1316s TI:22:07:22 culled 0 of 1 1316s TI:22:07:22 saved /tmp/upower-history-gy4njzzw/history-time-full-generic_id.dat 1316s TI:22:07:22 culled 0 of 1 1316s TI:22:07:22 saved /tmp/upower-history-gy4njzzw/history-time-empty-generic_id.dat 1316s TI:22:07:22 using id: Fancy_BT_mouse 1316s TI:22:07:22 failed to get data from /tmp/upower-history-gy4njzzw/history-rate-Fancy_BT_mouse.dat as file does not exist 1316s TI:22:07:22 failed to get data from /tmp/upower-history-gy4njzzw/history-charge-Fancy_BT_mouse.dat as file does not exist 1316s TI:22:07:22 failed to get data from /tmp/upower-history-gy4njzzw/history-time-full-Fancy_BT_mouse.dat as file does not exist 1316s TI:22:07:22 failed to get data from /tmp/upower-history-gy4njzzw/history-time-empty-Fancy_BT_mouse.dat as file does not exist 1316s TI:22:07:22 saving in 600 seconds 1316s TI:22:07:22 saving in 600 seconds 1316s TI:22:07:22 added native-path: hid-00:1f:20:96:33:47-battery 1316s TI:22:07:22 Exported UpDevice with path /org/freedesktop/UPower/devices/battery_hid_00o1fo20o96o33o47_battery 1316s TI:22:07:22 device hid-00:1f:20:96:33:47-battery has parent id: /sys/devices/devices/pci0000:00/0000:00:14.0/usb2/2-7/2-7:1.0/bluetooth/hci0/hci0:256 1316s TI:22:07:22 up_device_supply_sibling_discovered (device: /sys/devices/devices/pci0000:00/0000:00:14.0/usb2/2-7/2-7:1.0/bluetooth/hci0/hci0:256/0005:046D:B00D.0002/power_supply/hid-00:1f:20:96:33:47-battery, sibling: /sys/devices/devices/pci0000:00/0000:00:14.0/usb2/2-7/2-7:1.0/bluetooth/hci0/hci0:256/0005:046D:B00D.0002/input/input22) 1316s TI:22:07:22 Type changed from battery to mouse 1316s TI:22:07:22 Got new device from udev enumerator: 0x5563e1722f00 1316s TI:22:07:22 added hid-00:1f:20:96:33:47-battery 1316s TI:22:07:22 emitting added: /org/freedesktop/UPower/devices/battery_hid_00o1fo20o96o33o47_battery 1316s TI:22:07:22 registering subsystem : usb 1316s TI:22:07:22 registering subsystem : usbmisc 1316s TI:22:07:22 registering subsystem : sound 1316s TI:22:07:22 BlueZ disappeared 1316s TI:22:07:22 on_battery = no 1316s TI:22:07:22 warning_level = none 1316s TI:22:07:22 device (null) has unknown state, not saving history 1316s TI:22:07:22 daemon now not coldplug 1316s TI:22:07:22 Handling SIGTERM 1316s TI:22:07:22 culled 0 of 1 1316s TI:22:07:22 saved /tmp/upower-history-gy4njzzw/history-rate-Fancy_BT_mouse.dat 1316s TI:22:07:22 culled 0 of 2 1316s TI:22:07:22 saved /tmp/upower-history-gy4njzzw/history-charge-Fancy_BT_mouse.dat 1316s TI:22:07:22 culled 0 of 1 1316s TI:22:07:22 saved /tmp/upower-history-gy4njzzw/history-time-full-Fancy_BT_mouse.dat 1316s TI:22:07:22 culled 0 of 1 1316s TI:22:07:22 saved /tmp/upower-history-gy4njzzw/history-time-empty-Fancy_BT_mouse.dat 1320s .Executing: upower/upower-integration.test 1323s 1742076449.953 GetAll /org/freedesktop/PolicyKit1/Authority org.freedesktop.PolicyKit1.Authority 1324s 1742076450.036 GetAll /org/freedesktop/login1 org.freedesktop.login1.Manager 1324s 1742076450.073 Inhibit "sleep" "UPower" "Pause device polling" "delay" 1324s TI:22:07:30 Acquired inhibitor lock (7, delay) 1324s TI:22:07:30 Starting upowerd version 1.90.7 1324s TI:22:07:30 cannot find a keyboard backlight 1324s TI:22:07:30 Exported UpDevice with path /org/freedesktop/UPower/devices/DisplayDevice 1324s TI:22:07:30 daemon now coldplug 1324s TI:22:07:30 registering subsystem : input 1324s TI:22:07:30 registering subsystem : power_supply 1324s TI:22:07:30 registering subsystem : usb 1324s TI:22:07:30 registering subsystem : usbmisc 1324s TI:22:07:30 registering subsystem : sound 1324s TI:22:07:30 on_battery = no 1324s TI:22:07:30 warning_level = none 1324s TI:22:07:30 device (null) has unknown state, not saving history 1324s TI:22:07:30 daemon now not coldplug 1324s 1742076450.236 GetManagedObjects 1324s TI:22:07:30 BlueZ appeared 1324s 1742076450.323 AddAdapter "hci0" "my-computer" 1324s 1742076450.362 emit / org.freedesktop.DBus.ObjectManager.InterfacesAdded "/org/bluez/hci0" {"org.bluez.Adapter1": {"UUIDs": ["00001200-0000-1000-8000-00805f9b34fb", "00001800-0000-1000-8000-00805f9b34fb", "00001801-0000-1000-8000-00805f9b34fb", "0000110e-0000-1000-8000-00805f9b34fb", "0000110c-0000-1000-8000-00805f9b34fb"], "Discoverable": False, "Discovering": False, "Pairable": True, "Powered": True, "Address": "00:01:02:03:04:05", "AddressType": "public", "Alias": "my-computer", "Modalias": "usb:v1D6Bp0245d050A", "Name": "my-computer", "Class": 268, "DiscoverableTimeout": 180, "PairableTimeout": 0, "Roles": ["central", "peripheral"]}} 1324s 1742076450.400 AddDevice "hci0" "11:22:33:44:AA:BB" "Logitech Bluetooth Name" 1324s 1742076450.415 emit / org.freedesktop.DBus.ObjectManager.InterfacesAdded "/org/bluez/hci0/dev_11_22_33_44_AA_BB" {"org.bluez.Device1": {"Address": "11:22:33:44:AA:BB", "AddressType": "public", "Name": "Logitech Bluetooth Name", "Icon": "phone", "Class": 5898764, "Appearance": 0, "UUIDs": [], "Paired": False, "Connected": False, "Trusted": False, "Blocked": False, "WakeAllowed": False, "Alias": "Logitech Bluetooth Name", "Adapter": "/org/bluez/hci0", "LegacyPairing": False, "Modalias": "bluetooth:v000Fp1200d1436", "RSSI": -79, "TxPower": 0, "ManufacturerData": [], "ServiceData": [], "ServicesResolved": False, "AdvertisingFlags": [], "AdvertisingData": []}} 1324s 1742076450.470 emit /org/bluez/hci0/dev_11_22_33_44_AA_BB org.freedesktop.DBus.Properties.PropertiesChanged "org.bluez.Device1" {"Appearance": 962} [] 1324s 1742076450.528 emit / org.freedesktop.DBus.ObjectManager.InterfacesAdded "/org/bluez/hci0/dev_11_22_33_44_AA_BB" {"org.bluez.Battery1": {"Percentage": 99}} 1324s 1742076450.558 GetAll /org/bluez/hci0/dev_11_22_33_44_AA_BB org.bluez.Device1 1324s 1742076450.592 GetAll /org/bluez/hci0/dev_11_22_33_44_AA_BB org.bluez.Battery1 1324s TI:22:07:30 using id: Logitech_Bluetooth_Name-11:22:33:44:AA:BB 1324s TI:22:07:30 failed to get data from /tmp/upower-history-iwkjtlka/history-rate-Logitech_Bluetooth_Name-11:22:33:44:AA:BB.dat as file does not exist 1324s TI:22:07:30 failed to get data from /tmp/upower-history-iwkjtlka/history-charge-Logitech_Bluetooth_Name-11:22:33:44:AA:BB.dat as file does not exist 1324s TI:22:07:30 failed to get data from /tmp/upower-history-iwkjtlka/history-time-full-Logitech_Bluetooth_Name-11:22:33:44:AA:BB.dat as file does not exist 1324s TI:22:07:30 failed to get data from /tmp/upower-history-iwkjtlka/history-time-empty-Logitech_Bluetooth_Name-11:22:33:44:AA:BB.dat as file does not exist 1324s TI:22:07:30 saving in 600 seconds 1324s TI:22:07:30 device /org/bluez/hci0/dev_11_22_33_44_AA_BB has unknown state, not saving history 1324s TI:22:07:30 failed to refresh /org/bluez/hci0/dev_11_22_33_44_AA_BB 1324s TI:22:07:30 Exported UpDevice with path /org/freedesktop/UPower/devices/mouse_dev_11_22_33_44_AA_BB 1324s TI:22:07:30 emitting device-added: /org/bluez/hci0/dev_11_22_33_44_AA_BB 1324s TI:22:07:30 added /org/bluez/hci0/dev_11_22_33_44_AA_BB 1324s TI:22:07:30 emitting added: /org/freedesktop/UPower/devices/mouse_dev_11_22_33_44_AA_BB 1324s TI:22:07:30 on_battery = no 1325s TI:22:07:31 Received uevent add on device /sys/devices/pci0000:00/0000:00:14.0/usb3/3-10/3-10:1.2 1325s TI:22:07:31 failed to coldplug /sys/devices/pci0000:00/0000:00:14.0/usb3/3-10/3-10:1.2 1325s TI:22:07:31 device /sys/devices/pci0000:00/0000:00:14.0/usb3/3-10/3-10:1.2 has parent id: (null) 1325s TI:22:07:31 not a switch [/tmp/umockdev.ZDKO32/sys/devices/pci0000:00/0000:00:14.0/usb3/3-10/3-10:1.2/0003:046D:C52B.0009/0003:046D:4101.000A/input/input22/../capabilities/sw] 1325s TI:22:07:31 not a switch [/tmp/umockdev.ZDKO32/sys/devices/pci0000:00/0000:00:14.0/usb3/3-10/3-10:1.2/0003:046D:C52B.0009/0003:046D:4101.000A/input/input22/capabilities/sw] 1325s TI:22:07:31 Received uevent add on device /sys/devices/pci0000:00/0000:00:14.0/usb3/3-10/3-10:1.2/0003:046D:C52B.0009/0003:046D:4101.000A/input/input22 1325s TI:22:07:31 device /sys/devices/pci0000:00/0000:00:14.0/usb3/3-10/3-10:1.2/0003:046D:C52B.0009/0003:046D:4101.000A/input/input22 has parent id: /sys/devices/pci0000:00/0000:00:14.0/usb3/3-10/3-10:1.2 1325s TI:22:07:31 Received uevent add on device /sys/devices/pci0000:00/0000:00:14.0/usb3/3-10/3-10:1.2/0003:046D:C52B.0009/0003:046D:4101.000A/power_supply/hidpp_battery_3 1325s TI:22:07:31 failed to coldplug hidpp_battery_3 1325s TI:22:07:31 using id: generic_id 1325s TI:22:07:31 failed to get data from /tmp/upower-history-iwkjtlka/history-rate-generic_id.dat as file does not exist 1325s TI:22:07:31 failed to get data from /tmp/upower-history-iwkjtlka/history-charge-generic_id.dat as file does not exist 1325s TI:22:07:31 failed to get data from /tmp/upower-history-iwkjtlka/history-time-full-generic_id.dat as file does not exist 1325s TI:22:07:31 failed to get data from /tmp/upower-history-iwkjtlka/history-time-empty-generic_id.dat as file does not exist 1325s TI:22:07:31 saving in 600 seconds 1325s TI:22:07:31 device hidpp_battery_3 has unknown state, not saving history 1325s TI:22:07:31 culled 0 of 1 1325s TI:22:07:31 saved /tmp/upower-history-iwkjtlka/history-rate-generic_id.dat 1325s TI:22:07:31 culled 0 of 1 1325s TI:22:07:31 saved /tmp/upower-history-iwkjtlka/history-charge-generic_id.dat 1325s TI:22:07:31 culled 0 of 1 1325s TI:22:07:31 saved /tmp/upower-history-iwkjtlka/history-time-full-generic_id.dat 1325s TI:22:07:31 culled 0 of 1 1325s TI:22:07:31 saved /tmp/upower-history-iwkjtlka/history-time-empty-generic_id.dat 1325s TI:22:07:31 using id: Logitech_HID++_name-11:22:33:44:aa:bb 1325s TI:22:07:31 failed to get data from /tmp/upower-history-iwkjtlka/history-rate-Logitech_HID++_name-11:22:33:44:aa:bb.dat as file does not exist 1325s TI:22:07:31 failed to get data from /tmp/upower-history-iwkjtlka/history-charge-Logitech_HID++_name-11:22:33:44:aa:bb.dat as file does not exist 1325s TI:22:07:31 failed to get data from /tmp/upower-history-iwkjtlka/history-time-full-Logitech_HID++_name-11:22:33:44:aa:bb.dat as file does not exist 1325s TI:22:07:31 failed to get data from /tmp/upower-history-iwkjtlka/history-time-empty-Logitech_HID++_name-11:22:33:44:aa:bb.dat as file does not exist 1325s TI:22:07:31 saving in 600 seconds 1325s TI:22:07:31 saving in 600 seconds 1325s TI:22:07:31 added native-path: hidpp_battery_3 1325s TI:22:07:31 Exported UpDevice with path /org/freedesktop/UPower/devices/battery_hidpp_battery_3 1325s TI:22:07:31 device hidpp_battery_3 has parent id: /sys/devices/pci0000:00/0000:00:14.0/usb3/3-10/3-10:1.2 1325s TI:22:07:31 up_device_supply_sibling_discovered (device: /sys/devices/pci0000:00/0000:00:14.0/usb3/3-10/3-10:1.2/0003:046D:C52B.0009/0003:046D:4101.000A/power_supply/hidpp_battery_3, sibling: /sys/devices/pci0000:00/0000:00:14.0/usb3/3-10/3-10:1.2/0003:046D:C52B.0009/0003:046D:4101.000A/input/input22) 1325s TI:22:07:31 Type changed from battery to mouse 1325s TI:22:07:31 Got new device from udev enumerator: 0x55599158e390 1325s TI:22:07:31 culled 0 of 1 1325s TI:22:07:31 saved /tmp/upower-history-iwkjtlka/history-rate-Logitech_HID++_name-11:22:33:44:aa:bb.dat 1325s TI:22:07:31 culled 0 of 2 1325s TI:22:07:31 saved /tmp/upower-history-iwkjtlka/history-charge-Logitech_HID++_name-11:22:33:44:aa:bb.dat 1325s TI:22:07:31 culled 0 of 1 1325s TI:22:07:31 saved /tmp/upower-history-iwkjtlka/history-time-full-Logitech_HID++_name-11:22:33:44:aa:bb.dat 1325s TI:22:07:31 culled 0 of 1 1325s TI:22:07:31 saved /tmp/upower-history-iwkjtlka/history-time-empty-Logitech_HID++_name-11:22:33:44:aa:bb.dat 1325s TI:22:07:31 removed /org/bluez/hci0/dev_11_22_33_44_AA_BB 1325s TI:22:07:31 emitting device-removed: /org/freedesktop/UPower/devices/mouse_dev_11_22_33_44_AA_BB 1325s TI:22:07:31 Unexported UpDevice with path /org/freedesktop/UPower/devices/mouse_dev_11_22_33_44_AA_BB 1325s TI:22:07:31 Hiding duplicate device 11:22:33:44:AA:BB 1325s TI:22:07:31 culled 0 of 1 1325s TI:22:07:31 saved /tmp/upower-history-iwkjtlka/history-rate-Logitech_Bluetooth_Name-11:22:33:44:AA:BB.dat 1325s TI:22:07:31 culled 0 of 1 1325s TI:22:07:31 saved /tmp/upower-history-iwkjtlka/history-charge-Logitech_Bluetooth_Name-11:22:33:44:AA:BB.dat 1325s TI:22:07:31 culled 0 of 1 1325s TI:22:07:31 saved /tmp/upower-history-iwkjtlka/history-time-full-Logitech_Bluetooth_Name-11:22:33:44:AA:BB.dat 1325s TI:22:07:31 culled 0 of 1 1325s TI:22:07:31 saved /tmp/upower-history-iwkjtlka/history-time-empty-Logitech_Bluetooth_Name-11:22:33:44:AA:BB.dat 1325s TI:22:07:31 added hidpp_battery_3 1325s TI:22:07:31 emitting added: /org/freedesktop/UPower/devices/battery_hidpp_battery_3 1325s TI:22:07:31 on_battery = no 1325s ETI:22:07:31 Handling SIGTERM 1325s Executing: upower/upower-integration.test 1330s Executing: upower/upower-integration.test 1331s 1742076457.315 GetAll /org/freedesktop/PolicyKit1/Authority org.freedesktop.PolicyKit1.Authority 1331s 1742076457.459 GetAll /org/freedesktop/login1 org.freedesktop.login1.Manager 1331s 1742076457.503 Inhibit "sleep" "UPower" "Pause device polling" "delay" 1331s TI:22:07:37 Acquired inhibitor lock (7, delay) 1331s TI:22:07:37 Starting upowerd version 1.90.7 1331s TI:22:07:37 cannot find a keyboard backlight 1331s TI:22:07:37 Exported UpDevice with path /org/freedesktop/UPower/devices/DisplayDevice 1331s TI:22:07:37 daemon now coldplug 1331s TI:22:07:37 not a switch [/tmp/umockdev.TAUU32/sys/devices/usb2/bluetooth/hci0/hci0:1/input3/event4/../capabilities/sw] 1331s TI:22:07:37 not a switch [/tmp/umockdev.TAUU32/sys/devices/usb2/bluetooth/hci0/hci0:1/input3/event4/capabilities/sw] 1331s TI:22:07:37 registering subsystem : input 1331s TI:22:07:37 Received uevent add on device /sys/devices/usb2/bluetooth/hci0/hci0:1/input3/event4 1331s TI:22:07:37 device /sys/devices/usb2/bluetooth/hci0/hci0:1/input3/event4 has parent id: /sys/devices/usb2/bluetooth/hci0/hci0:1 1331s TI:22:07:37 registering subsystem : power_supply 1331s TI:22:07:37 Received uevent add on device /sys/devices/usb2/bluetooth/hci0/hci0:1/power_supply/hid-00:22:33:44:55:66-battery 1331s TI:22:07:37 failed to coldplug hid-00:22:33:44:55:66-battery 1331s TI:22:07:37 using id: generic_id 1331s TI:22:07:37 failed to get data from /tmp/upower-history-1wce5eks/history-rate-generic_id.dat as file does not exist 1331s TI:22:07:37 failed to get data from /tmp/upower-history-1wce5eks/history-charge-generic_id.dat as file does not exist 1331s TI:22:07:37 failed to get data from /tmp/upower-history-1wce5eks/history-time-full-generic_id.dat as file does not exist 1331s TI:22:07:37 failed to get data from /tmp/upower-history-1wce5eks/history-time-empty-generic_id.dat as file does not exist 1331s TI:22:07:37 saving in 600 seconds 1331s TI:22:07:37 device hid-00:22:33:44:55:66-battery has unknown state, not saving history 1331s TI:22:07:37 culled 0 of 1 1331s TI:22:07:37 saved /tmp/upower-history-1wce5eks/history-rate-generic_id.dat 1331s TI:22:07:37 culled 0 of 1 1331s TI:22:07:37 saved /tmp/upower-history-1wce5eks/history-charge-generic_id.dat 1331s TI:22:07:37 culled 0 of 1 1331s TI:22:07:37 saved /tmp/upower-history-1wce5eks/history-time-full-generic_id.dat 1331s TI:22:07:37 culled 0 of 1 1331s TI:22:07:37 saved /tmp/upower-history-1wce5eks/history-time-empty-generic_id.dat 1331s TI:22:07:37 using id: Monster_Typist 1331s TI:22:07:37 failed to get data from /tmp/upower-history-1wce5eks/history-rate-Monster_Typist.dat as file does not exist 1331s TI:22:07:37 failed to get data from /tmp/upower-history-1wce5eks/history-charge-Monster_Typist.dat as file does not exist 1331s TI:22:07:37 failed to get data from /tmp/upower-history-1wce5eks/history-time-full-Monster_Typist.dat as file does not exist 1331s TI:22:07:37 failed to get data from /tmp/upower-history-1wce5eks/history-time-empty-Monster_Typist.dat as file does not exist 1331s TI:22:07:37 saving in 600 seconds 1331s TI:22:07:37 saving in 600 seconds 1331s TI:22:07:37 added native-path: hid-00:22:33:44:55:66-battery 1331s TI:22:07:37 Exported UpDevice with path /org/freedesktop/UPower/devices/battery_hid_00o22o33o44o55o66_battery 1331s TI:22:07:37 device hid-00:22:33:44:55:66-battery has parent id: /sys/devices/usb2/bluetooth/hci0/hci0:1 1331s TI:22:07:37 up_device_supply_sibling_discovered (device: /sys/devices/usb2/bluetooth/hci0/hci0:1/power_supply/hid-00:22:33:44:55:66-battery, sibling: /sys/devices/usb2/bluetooth/hci0/hci0:1/input3/event4) 1331s TI:22:07:37 Type changed from battery to keyboard 1331s TI:22:07:37 Got new device from udev enumerator: 0x5563d89d8a80 1331s TI:22:07:37 added hid-00:22:33:44:55:66-battery 1331s TI:22:07:37 emitting added: /org/freedesktop/UPower/devices/battery_hid_00o22o33o44o55o66_battery 1331s TI:22:07:37 registering subsystem : usb 1331s TI:22:07:37 registering subsystem : usbmisc 1331s TI:22:07:37 registering subsystem : sound 1331s TI:22:07:37 BlueZ disappeared 1331s TI:22:07:37 on_battery = no 1331s TI:22:07:37 warning_level = none 1331s TI:22:07:37 device (null) has unknown state, not saving history 1331s TI:22:07:37 daemon now not coldplug 1332s TI:22:07:38 Handling SIGTERM 1332s TI:22:07:38 culled 0 of 1 1332s TI:22:07:38 saved /tmp/upower-history-1wce5eks/history-rate-Monster_Typist.dat 1332s TI:22:07:38 culled 0 of 2 1332s TI:22:07:38 saved /tmp/upower-history-1wce5eks/history-charge-Monster_Typist.dat 1332s TI:22:07:38 culled 0 of 1 1332s TI:22:07:38 saved /tmp/upower-history-1wce5eks/history-time-full-Monster_Typist.dat 1332s TI:22:07:38 culled 0 of 1 1332s TI:22:07:38 saved /tmp/upower-history-1wce5eks/history-time-empty-Monster_Typist.dat 1335s .Executing: upower/upower-integration.test 1340s 1742076466.211 AddAdapter "hci0" "my-computer" 1340s 1742076466.231 emit / org.freedesktop.DBus.ObjectManager.InterfacesAdded "/org/bluez/hci0" {"org.bluez.Adapter1": {"UUIDs": ["00001200-0000-1000-8000-00805f9b34fb", "00001800-0000-1000-8000-00805f9b34fb", "00001801-0000-1000-8000-00805f9b34fb", "0000110e-0000-1000-8000-00805f9b34fb", "0000110c-0000-1000-8000-00805f9b34fb"], "Discoverable": False, "Discovering": False, "Pairable": True, "Powered": True, "Address": "00:01:02:03:04:05", "AddressType": "public", "Alias": "my-computer", "Modalias": "usb:v1D6Bp0245d050A", "Name": "my-computer", "Class": 268, "DiscoverableTimeout": 180, "PairableTimeout": 0, "Roles": ["central", "peripheral"]}} 1340s 1742076466.263 AddDevice "hci0" "11:22:33:44:AA:BB" "Satechi M1 Mouse" 1340s 1742076466.286 emit / org.freedesktop.DBus.ObjectManager.InterfacesAdded "/org/bluez/hci0/dev_11_22_33_44_AA_BB" {"org.bluez.Device1": {"Address": "11:22:33:44:AA:BB", "AddressType": "public", "Name": "Satechi M1 Mouse", "Icon": "phone", "Class": 5898764, "Appearance": 0, "UUIDs": [], "Paired": False, "Connected": False, "Trusted": False, "Blocked": False, "WakeAllowed": False, "Alias": "Satechi M1 Mouse", "Adapter": "/org/bluez/hci0", "LegacyPairing": False, "Modalias": "bluetooth:v000Fp1200d1436", "RSSI": -79, "TxPower": 0, "ManufacturerData": [], "ServiceData": [], "ServicesResolved": False, "AdvertisingFlags": [], "AdvertisingData": []}} 1340s 1742076466.369 emit /org/bluez/hci0/dev_11_22_33_44_AA_BB org.freedesktop.DBus.Properties.PropertiesChanged "org.bluez.Device1" {"Class": 0} [] 1340s 1742076466.491 emit / org.freedesktop.DBus.ObjectManager.InterfacesAdded "/org/bluez/hci0/dev_11_22_33_44_AA_BB" {"org.bluez.Battery1": {"Percentage": 99}} 1340s Executing: upower/upower-integration.test 1341s 1742076467.065 GetAll /org/freedesktop/PolicyKit1/Authority org.freedesktop.PolicyKit1.Authority 1341s 1742076467.155 GetAll /org/freedesktop/login1 org.freedesktop.login1.Manager 1341s 1742076467.190 Inhibit "sleep" "UPower" "Pause device polling" "delay" 1341s TI:22:07:47 Acquired inhibitor lock (7, delay) 1341s TI:22:07:47 Starting upowerd version 1.90.7 1341s TI:22:07:47 cannot find a keyboard backlight 1341s TI:22:07:47 Exported UpDevice with path /org/freedesktop/UPower/devices/DisplayDevice 1341s TI:22:07:47 daemon now coldplug 1341s TI:22:07:47 registering subsystem : input 1341s TI:22:07:47 registering subsystem : power_supply 1341s TI:22:07:47 registering subsystem : usb 1341s TI:22:07:47 registering subsystem : usbmisc 1341s TI:22:07:47 registering subsystem : sound 1341s TI:22:07:47 on_battery = no 1341s TI:22:07:47 warning_level = none 1341s TI:22:07:47 device (null) has unknown state, not saving history 1341s 1742076467.331 GetManagedObjects 1341s TI:22:07:47 BlueZ appeared 1341s 1742076467.456 GetAll /org/bluez/hci0/dev_11_22_33_44_AA_BB org.bluez.Device1 1341s 1742076467.505 GetAll /org/bluez/hci0/dev_11_22_33_44_AA_BB org.bluez.Battery1 1341s TI:22:07:47 using id: Satechi_M1_Mouse-11:22:33:44:AA:BB 1341s TI:22:07:47 failed to get data from /tmp/upower-history-4as6dfn_/history-rate-Satechi_M1_Mouse-11:22:33:44:AA:BB.dat as file does not exist 1341s TI:22:07:47 failed to get data from /tmp/upower-history-4as6dfn_/history-charge-Satechi_M1_Mouse-11:22:33:44:AA:BB.dat as file does not exist 1341s TI:22:07:47 failed to get data from /tmp/upower-history-4as6dfn_/history-time-full-Satechi_M1_Mouse-11:22:33:44:AA:BB.dat as file does not exist 1341s TI:22:07:47 failed to get data from /tmp/upower-history-4as6dfn_/history-time-empty-Satechi_M1_Mouse-11:22:33:44:AA:BB.dat as file does not exist 1341s TI:22:07:47 saving in 600 seconds 1341s TI:22:07:47 device /org/bluez/hci0/dev_11_22_33_44_AA_BB has unknown state, not saving history 1341s TI:22:07:47 failed to refresh /org/bluez/hci0/dev_11_22_33_44_AA_BB 1341s TI:22:07:47 Exported UpDevice with path /org/freedesktop/UPower/devices/bluetooth_generic_dev_11_22_33_44_AA_BB 1341s TI:22:07:47 emitting device-added: /org/bluez/hci0/dev_11_22_33_44_AA_BB 1341s TI:22:07:47 added /org/bluez/hci0/dev_11_22_33_44_AA_BB 1341s TI:22:07:47 emitting added: /org/freedesktop/UPower/devices/bluetooth_generic_dev_11_22_33_44_AA_BB 1341s TI:22:07:47 on_battery = no 1341s TI:22:07:47 daemon now not coldplug 1342s TI:22:07:48 Handling SIGTERM 1342s TI:22:07:48 culled 0 of 1 1342s TI:22:07:48 saved /tmp/upower-history-4as6dfn_/history-rate-Satechi_M1_Mouse-11:22:33:44:AA:BB.dat 1342s TI:22:07:48 culled 0 of 1 1342s TI:22:07:48 saved /tmp/upower-history-4as6dfn_/history-charge-Satechi_M1_Mouse-11:22:33:44:AA:BB.dat 1342s TI:22:07:48 culled 0 of 1 1342s TI:22:07:48 saved /tmp/upower-history-4as6dfn_/history-time-full-Satechi_M1_Mouse-11:22:33:44:AA:BB.dat 1342s TI:22:07:48 culled 0 of 1 1342s TI:22:07:48 saved /tmp/upower-history-4as6dfn_/history-time-empty-Satechi_M1_Mouse-11:22:33:44:AA:BB.dat 1345s .Executing: upower/upower-integration.test 1350s 1742076476.072 AddAdapter "hci0" "my-computer" 1350s 1742076476.092 emit / org.freedesktop.DBus.ObjectManager.InterfacesAdded "/org/bluez/hci0" {"org.bluez.Adapter1": {"UUIDs": ["00001200-0000-1000-8000-00805f9b34fb", "00001800-0000-1000-8000-00805f9b34fb", "00001801-0000-1000-8000-00805f9b34fb", "0000110e-0000-1000-8000-00805f9b34fb", "0000110c-0000-1000-8000-00805f9b34fb"], "Discoverable": False, "Discovering": False, "Pairable": True, "Powered": True, "Address": "00:01:02:03:04:05", "AddressType": "public", "Alias": "my-computer", "Modalias": "usb:v1D6Bp0245d050A", "Name": "my-computer", "Class": 268, "DiscoverableTimeout": 180, "PairableTimeout": 0, "Roles": ["central", "peripheral"]}} 1350s 1742076476.114 AddDevice "hci0" "11:22:33:44:AA:BB" "Arc Touch Mouse SE" 1350s 1742076476.134 emit / org.freedesktop.DBus.ObjectManager.InterfacesAdded "/org/bluez/hci0/dev_11_22_33_44_AA_BB" {"org.bluez.Device1": {"Address": "11:22:33:44:AA:BB", "AddressType": "public", "Name": "Arc Touch Mouse SE", "Icon": "phone", "Class": 5898764, "Appearance": 0, "UUIDs": [], "Paired": False, "Connected": False, "Trusted": False, "Blocked": False, "WakeAllowed": False, "Alias": "Arc Touch Mouse SE", "Adapter": "/org/bluez/hci0", "LegacyPairing": False, "Modalias": "bluetooth:v000Fp1200d1436", "RSSI": -79, "TxPower": 0, "ManufacturerData": [], "ServiceData": [], "ServicesResolved": False, "AdvertisingFlags": [], "AdvertisingData": []}} 1350s 1742076476.184 emit /org/bluez/hci0/dev_11_22_33_44_AA_BB org.freedesktop.DBus.Properties.PropertiesChanged "org.bluez.Device1" {"Appearance": 962} [] 1350s 1742076476.245 emit / org.freedesktop.DBus.ObjectManager.InterfacesAdded "/org/bluez/hci0/dev_11_22_33_44_AA_BB" {"org.bluez.Battery1": {"Percentage": 99}} 1350s 1742076476.594 GetAll /org/freedesktop/PolicyKit1/Authority org.freedesktop.PolicyKit1.Authority 1350s 1742076476.681 GetAll /org/freedesktop/login1 org.freedesktop.login1.Manager 1350s 1742076476.709 Inhibit "sleep" "UPower" "Pause device polling" "delay" 1350s Executing: upower/upower-integration.test 1350s TI:22:07:56 Acquired inhibitor lock (7, delay) 1350s TI:22:07:56 Starting upowerd version 1.90.7 1350s TI:22:07:56 cannot find a keyboard backlight 1350s TI:22:07:56 Exported UpDevice with path /org/freedesktop/UPower/devices/DisplayDevice 1350s TI:22:07:56 daemon now coldplug 1350s TI:22:07:56 registering subsystem : input 1350s TI:22:07:56 registering subsystem : power_supply 1350s TI:22:07:56 registering subsystem : usb 1350s TI:22:07:56 registering subsystem : usbmisc 1350s TI:22:07:56 registering subsystem : sound 1350s TI:22:07:56 on_battery = no 1350s TI:22:07:56 warning_level = none 1350s TI:22:07:56 device (null) has unknown state, not saving history 1350s 1742076476.892 GetManagedObjects 1351s TI:22:07:56 BlueZ appeared 1351s 1742076477.011 GetAll /org/bluez/hci0/dev_11_22_33_44_AA_BB org.bluez.Device1 1351s 1742076477.048 GetAll /org/bluez/hci0/dev_11_22_33_44_AA_BB org.bluez.Battery1 1351s TI:22:07:57 using id: Arc_Touch_Mouse_SE-11:22:33:44:AA:BB 1351s TI:22:07:57 failed to get data from /tmp/upower-history-a29fhwor/history-rate-Arc_Touch_Mouse_SE-11:22:33:44:AA:BB.dat as file does not exist 1351s TI:22:07:57 failed to get data from /tmp/upower-history-a29fhwor/history-charge-Arc_Touch_Mouse_SE-11:22:33:44:AA:BB.dat as file does not exist 1351s TI:22:07:57 failed to get data from /tmp/upower-history-a29fhwor/history-time-full-Arc_Touch_Mouse_SE-11:22:33:44:AA:BB.dat as file does not exist 1351s TI:22:07:57 failed to get data from /tmp/upower-history-a29fhwor/history-time-empty-Arc_Touch_Mouse_SE-11:22:33:44:AA:BB.dat as file does not exist 1351s TI:22:07:57 saving in 600 seconds 1351s TI:22:07:57 device /org/bluez/hci0/dev_11_22_33_44_AA_BB has unknown state, not saving history 1351s TI:22:07:57 failed to refresh /org/bluez/hci0/dev_11_22_33_44_AA_BB 1351s TI:22:07:57 Exported UpDevice with path /org/freedesktop/UPower/devices/mouse_dev_11_22_33_44_AA_BB 1351s TI:22:07:57 emitting device-added: /org/bluez/hci0/dev_11_22_33_44_AA_BB 1351s TI:22:07:57 added /org/bluez/hci0/dev_11_22_33_44_AA_BB 1351s TI:22:07:57 emitting added: /org/freedesktop/UPower/devices/mouse_dev_11_22_33_44_AA_BB 1351s TI:22:07:57 on_battery = no 1351s TI:22:07:57 daemon now not coldplug 1351s TI:22:07:57 Handling SIGTERM 1351s TI:22:07:57 culled 0 of 1 1351s TI:22:07:57 saved /tmp/upower-history-a29fhwor/history-rate-Arc_Touch_Mouse_SE-11:22:33:44:AA:BB.dat 1351s TI:22:07:57 culled 0 of 1 1351s TI:22:07:57 saved /tmp/upower-history-a29fhwor/history-charge-Arc_Touch_Mouse_SE-11:22:33:44:AA:BB.dat 1351s TI:22:07:57 culled 0 of 1 1351s TI:22:07:57 saved /tmp/upower-history-a29fhwor/history-time-full-Arc_Touch_Mouse_SE-11:22:33:44:AA:BB.dat 1351s TI:22:07:57 culled 0 of 1 1351s TI:22:07:57 saved /tmp/upower-history-a29fhwor/history-time-empty-Arc_Touch_Mouse_SE-11:22:33:44:AA:BB.dat 1355s .Executing: upower/upower-integration.test 1357s 1742076483.207 GetAll /org/freedesktop/PolicyKit1/Authority org.freedesktop.PolicyKit1.Authority 1357s 1742076483.298 GetAll /org/freedesktop/login1 org.freedesktop.login1.Manager 1357s 1742076483.331 Inhibit "sleep" "UPower" "Pause device polling" "delay" 1357s TI:22:08:03 Acquired inhibitor lock (7, delay) 1357s TI:22:08:03 Starting upowerd version 1.90.7 1357s TI:22:08:03 cannot find a keyboard backlight 1357s TI:22:08:03 Exported UpDevice with path /org/freedesktop/UPower/devices/DisplayDevice 1357s TI:22:08:03 daemon now coldplug 1357s TI:22:08:03 not a switch [/tmp/umockdev.1AZF32/sys/devices/usb1/bluetooth/hci0/hci0:01/input2/mouse3/../capabilities/sw] 1357s TI:22:08:03 not a switch [/tmp/umockdev.1AZF32/sys/devices/usb1/bluetooth/hci0/hci0:01/input2/mouse3/capabilities/sw] 1357s TI:22:08:03 registering subsystem : input 1357s TI:22:08:03 Received uevent add on device /sys/devices/usb1/bluetooth/hci0/hci0:01/input2/mouse3 1357s TI:22:08:03 device /sys/devices/usb1/bluetooth/hci0/hci0:01/input2/mouse3 has parent id: /sys/devices/usb1/bluetooth/hci0/hci0:01 1357s TI:22:08:03 registering subsystem : power_supply 1357s TI:22:08:03 Received uevent add on device /sys/devices/usb1/bluetooth/hci0/hci0:01/1/power_supply/hid-11:22:33:44:aa:bb-battery 1357s TI:22:08:03 failed to coldplug hid-11:22:33:44:aa:bb-battery 1357s TI:22:08:03 using id: generic_id 1357s TI:22:08:03 failed to get data from /tmp/upower-history-_n5ambk2/history-rate-generic_id.dat as file does not exist 1357s TI:22:08:03 failed to get data from /tmp/upower-history-_n5ambk2/history-charge-generic_id.dat as file does not exist 1357s TI:22:08:03 failed to get data from /tmp/upower-history-_n5ambk2/history-time-full-generic_id.dat as file does not exist 1357s TI:22:08:03 failed to get data from /tmp/upower-history-_n5ambk2/history-time-empty-generic_id.dat as file does not exist 1357s TI:22:08:03 saving in 600 seconds 1357s TI:22:08:03 device hid-11:22:33:44:aa:bb-battery has unknown state, not saving history 1357s TI:22:08:03 culled 0 of 1 1357s TI:22:08:03 saved /tmp/upower-history-_n5ambk2/history-rate-generic_id.dat 1357s TI:22:08:03 culled 0 of 1 1357s TI:22:08:03 saved /tmp/upower-history-_n5ambk2/history-charge-generic_id.dat 1357s TI:22:08:03 culled 0 of 1 1357s TI:22:08:03 saved /tmp/upower-history-_n5ambk2/history-time-full-generic_id.dat 1357s TI:22:08:03 culled 0 of 1 1357s TI:22:08:03 saved /tmp/upower-history-_n5ambk2/history-time-empty-generic_id.dat 1357s TI:22:08:03 using id: Fancy_BT_mouse 1357s TI:22:08:03 failed to get data from /tmp/upower-history-_n5ambk2/history-rate-Fancy_BT_mouse.dat as file does not exist 1357s TI:22:08:03 failed to get data from /tmp/upower-history-_n5ambk2/history-charge-Fancy_BT_mouse.dat as file does not exist 1357s TI:22:08:03 failed to get data from /tmp/upower-history-_n5ambk2/history-time-full-Fancy_BT_mouse.dat as file does not exist 1357s TI:22:08:03 failed to get data from /tmp/upower-history-_n5ambk2/history-time-empty-Fancy_BT_mouse.dat as file does not exist 1357s TI:22:08:03 saving in 600 seconds 1357s TI:22:08:03 saving in 600 seconds 1357s TI:22:08:03 added native-path: hid-11:22:33:44:aa:bb-battery 1357s TI:22:08:03 Exported UpDevice with path /org/freedesktop/UPower/devices/battery_hid_11o22o33o44oaaobb_battery 1357s TI:22:08:03 device hid-11:22:33:44:aa:bb-battery has parent id: /sys/devices/usb1/bluetooth/hci0/hci0:01 1357s TI:22:08:03 up_device_supply_sibling_discovered (device: /sys/devices/usb1/bluetooth/hci0/hci0:01/1/power_supply/hid-11:22:33:44:aa:bb-battery, sibling: /sys/devices/usb1/bluetooth/hci0/hci0:01/input2/mouse3) 1357s TI:22:08:03 culled 0 of 1 1357s TI:22:08:03 saved /tmp/upower-history-_n5ambk2/history-rate-Fancy_BT_mouse.dat 1357s TI:22:08:03 culled 0 of 2 1357s TI:22:08:03 saved /tmp/upower-history-_n5ambk2/history-charge-Fancy_BT_mouse.dat 1357s TI:22:08:03 culled 0 of 1 1357s TI:22:08:03 saved /tmp/upower-history-_n5ambk2/history-time-full-Fancy_BT_mouse.dat 1357s TI:22:08:03 culled 0 of 1 1357s TI:22:08:03 saved /tmp/upower-history-_n5ambk2/history-time-empty-Fancy_BT_mouse.dat 1357s TI:22:08:03 Type changed from battery to mouse 1357s TI:22:08:03 Got new device from udev enumerator: 0x55572ea27a30 1357s TI:22:08:03 added hid-11:22:33:44:aa:bb-battery 1357s TI:22:08:03 emitting added: /org/freedesktop/UPower/devices/battery_hid_11o22o33o44oaaobb_battery 1357s TI:22:08:03 registering subsystem : usb 1357s TI:22:08:03 registering subsystem : usbmisc 1357s TI:22:08:03 registering subsystem : sound 1357s TI:22:08:03 on_battery = no 1357s TI:22:08:03 warning_level = none 1357s TI:22:08:03 device (null) has unknown state, not saving history 1357s TI:22:08:03 BlueZ disappeared 1357s TI:22:08:03 daemon now not coldplug 1357s TI:22:08:03 Handling SIGTERM 1360s .Executing: upower/upower-integration.test 1365s 1742076491.213 GetAll /org/freedesktop/PolicyKit1/Authority org.freedesktop.PolicyKit1.Authority 1365s 1742076491.316 GetAll /org/freedesktop/login1 org.freedesktop.login1.Manager 1365s 1742076491.356 Inhibit "sleep" "UPower" "Pause device polling" "delay" 1365s TI:22:08:11 Acquired inhibitor lock (7, delay) 1365s TI:22:08:11 Starting upowerd version 1.90.7 1365s TI:22:08:11 cannot find a keyboard backlight 1365s TI:22:08:11 Exported UpDevice with path /org/freedesktop/UPower/devices/DisplayDevice 1365s TI:22:08:11 daemon now coldplug 1365s TI:22:08:11 not a switch [/tmp/umockdev.XCPE32/sys/devices/usb2/bluetooth/hci0/hci0:1/input3/event3/../capabilities/sw] 1365s TI:22:08:11 not a switch [/tmp/umockdev.XCPE32/sys/devices/usb2/bluetooth/hci0/hci0:1/input3/event3/capabilities/sw] 1365s TI:22:08:11 not a switch [/tmp/umockdev.XCPE32/sys/devices/usb2/bluetooth/hci0/hci0:1/input3/event4/../capabilities/sw] 1365s TI:22:08:11 not a switch [/tmp/umockdev.XCPE32/sys/devices/usb2/bluetooth/hci0/hci0:1/input3/event4/capabilities/sw] 1365s TI:22:08:11 registering subsystem : input 1365s TI:22:08:11 Received uevent add on device /sys/devices/usb2/bluetooth/hci0/hci0:1/input3/event3 1365s TI:22:08:11 device /sys/devices/usb2/bluetooth/hci0/hci0:1/input3/event3 has parent id: /sys/devices/usb2/bluetooth/hci0/hci0:1 1365s TI:22:08:11 Received uevent add on device /sys/devices/usb2/bluetooth/hci0/hci0:1/input3/event4 1365s TI:22:08:11 device /sys/devices/usb2/bluetooth/hci0/hci0:1/input3/event4 has parent id: /sys/devices/usb2/bluetooth/hci0/hci0:1 1365s TI:22:08:11 registering subsystem : power_supply 1365s TI:22:08:11 Received uevent add on device /sys/devices/usb2/bluetooth/hci0/hci0:1/power_supply/hid-00:22:33:44:55:66-battery 1365s TI:22:08:11 failed to coldplug hid-00:22:33:44:55:66-battery 1365s TI:22:08:11 using id: generic_id 1365s TI:22:08:11 failed to get data from /tmp/upower-history-0fkcu5iy/history-rate-generic_id.dat as file does not exist 1365s TI:22:08:11 failed to get data from /tmp/upower-history-0fkcu5iy/history-charge-generic_id.dat as file does not exist 1365s TI:22:08:11 failed to get data from /tmp/upower-history-0fkcu5iy/history-time-full-generic_id.dat as file does not exist 1365s TI:22:08:11 failed to get data from /tmp/upower-history-0fkcu5iy/history-time-empty-generic_id.dat as file does not exist 1365s TI:22:08:11 saving in 600 seconds 1365s TI:22:08:11 device hid-00:22:33:44:55:66-battery has unknown state, not saving history 1365s TI:22:08:11 culled 0 of 1 1365s TI:22:08:11 saved /tmp/upower-history-0fkcu5iy/history-rate-generic_id.dat 1365s TI:22:08:11 culled 0 of 1 1365s TI:22:08:11 saved /tmp/upower-history-0fkcu5iy/history-charge-generic_id.dat 1365s TI:22:08:11 culled 0 of 1 1365s TI:22:08:11 saved /tmp/upower-history-0fkcu5iy/history-time-full-generic_id.dat 1365s TI:22:08:11 culled 0 of 1 1365s TI:22:08:11 saved /tmp/upower-history-0fkcu5iy/history-time-empty-generic_id.dat 1365s TI:22:08:11 using id: Monster_Typist_Mouse_Keyboard_Combo 1365s TI:22:08:11 failed to get data from /tmp/upower-history-0fkcu5iy/history-rate-Monster_Typist_Mouse_Keyboard_Combo.dat as file does not exist 1365s TI:22:08:11 failed to get data from /tmp/upower-history-0fkcu5iy/history-charge-Monster_Typist_Mouse_Keyboard_Combo.dat as file does not exist 1365s TI:22:08:11 failed to get data from /tmp/upower-history-0fkcu5iy/history-time-full-Monster_Typist_Mouse_Keyboard_Combo.dat as file does not exist 1365s TI:22:08:11 failed to get data from /tmp/upower-history-0fkcu5iy/history-time-empty-Monster_Typist_Mouse_Keyboard_Combo.dat as file does not exist 1365s TI:22:08:11 saving in 600 seconds 1365s TI:22:08:11 saving in 600 seconds 1365s TI:22:08:11 added native-path: hid-00:22:33:44:55:66-battery 1365s TI:22:08:11 Exported UpDevice with path /org/freedesktop/UPower/devices/battery_hid_00o22o33o44o55o66_battery 1365s TI:22:08:11 device hid-00:22:33:44:55:66-battery has parent id: /sys/devices/usb2/bluetooth/hci0/hci0:1 1365s TI:22:08:11 up_device_supply_sibling_discovered (device: /sys/devices/usb2/bluetooth/hci0/hci0:1/power_supply/hid-00:22:33:44:55:66-battery, sibling: /sys/devices/usb2/bluetooth/hci0/hci0:1/input3/event3) 1365s TI:22:08:11 Type changed from battery to mouse 1365s TI:22:08:11 up_device_supply_sibling_discovered (device: /sys/devices/usb2/bluetooth/hci0/hci0:1/power_supply/hid-00:22:33:44:55:66-battery, sibling: /sys/devices/usb2/bluetooth/hci0/hci0:1/input3/event4) 1365s TI:22:08:11 Type changed from mouse to keyboard 1365s TI:22:08:11 Got new device from udev enumerator: 0x555e658ccf70 1365s TI:22:08:11 added hid-00:22:33:44:55:66-battery 1365s TI:22:08:11 emitting added: /org/freedesktop/UPower/devices/battery_hid_00o22o33o44o55o66_battery 1365s TI:22:08:11 registering subsystem : usb 1365s TI:22:08:11 registering subsystem : usbmisc 1365s TI:22:08:11 registering subsystem : sound 1365s TI:22:08:11 BlueZ disappeared 1365s TI:22:08:11 on_battery = no 1365s TI:22:08:11 warning_level = none 1365s TI:22:08:11 device (null) has unknown state, not saving history 1365s TI:22:08:11 daemon now not coldplug 1365s Executing: upower/upower-integration.test 1366s TI:22:08:12 Handling SIGTERM 1366s TI:22:08:12 culled 0 of 1 1366s TI:22:08:12 saved /tmp/upower-history-0fkcu5iy/history-rate-Monster_Typist_Mouse_Keyboard_Combo.dat 1366s TI:22:08:12 culled 0 of 2 1366s TI:22:08:12 saved /tmp/upower-history-0fkcu5iy/history-charge-Monster_Typist_Mouse_Keyboard_Combo.dat 1366s TI:22:08:12 culled 0 of 1 1366s TI:22:08:12 saved /tmp/upower-history-0fkcu5iy/history-time-full-Monster_Typist_Mouse_Keyboard_Combo.dat 1366s TI:22:08:12 culled 0 of 1 1366s TI:22:08:12 saved /tmp/upower-history-0fkcu5iy/history-time-empty-Monster_Typist_Mouse_Keyboard_Combo.dat 1370s .Executing: upower/upower-integration.test 1372s 1742076498.093 GetAll /org/freedesktop/PolicyKit1/Authority org.freedesktop.PolicyKit1.Authority 1372s 1742076498.237 GetAll /org/freedesktop/login1 org.freedesktop.login1.Manager 1372s 1742076498.269 Inhibit "sleep" "UPower" "Pause device polling" "delay" 1372s TI:22:08:18 Acquired inhibitor lock (7, delay) 1372s TI:22:08:18 Starting upowerd version 1.90.7 1372s TI:22:08:18 cannot find a keyboard backlight 1372s TI:22:08:18 Exported UpDevice with path /org/freedesktop/UPower/devices/DisplayDevice 1372s TI:22:08:18 daemon now coldplug 1372s TI:22:08:18 not a switch [/tmp/umockdev.VTGD32/sys/devices/usb1/bluetooth/hci0/hci0:01/input2/mouse3/../capabilities/sw] 1372s TI:22:08:18 not a switch [/tmp/umockdev.VTGD32/sys/devices/usb1/bluetooth/hci0/hci0:01/input2/mouse3/capabilities/sw] 1372s TI:22:08:18 registering subsystem : input 1372s TI:22:08:18 Received uevent add on device /sys/devices/usb1/bluetooth/hci0/hci0:01/input2/mouse3 1372s TI:22:08:18 device /sys/devices/usb1/bluetooth/hci0/hci0:01/input2/mouse3 has parent id: /sys/devices/usb1/bluetooth/hci0/hci0:01 1372s TI:22:08:18 registering subsystem : power_supply 1372s TI:22:08:18 Received uevent add on device /sys/devices/usb1/bluetooth/hci0/hci0:01/1/power_supply/hid-11:22:33:44:aa:bb-battery 1372s TI:22:08:18 failed to coldplug hid-11:22:33:44:aa:bb-battery 1372s TI:22:08:18 using id: generic_id 1372s TI:22:08:18 failed to get data from /tmp/upower-history-fbt8m634/history-rate-generic_id.dat as file does not exist 1372s TI:22:08:18 failed to get data from /tmp/upower-history-fbt8m634/history-charge-generic_id.dat as file does not exist 1372s TI:22:08:18 failed to get data from /tmp/upower-history-fbt8m634/history-time-full-generic_id.dat as file does not exist 1372s TI:22:08:18 failed to get data from /tmp/upower-history-fbt8m634/history-time-empty-generic_id.dat as file does not exist 1372s TI:22:08:18 saving in 600 seconds 1372s TI:22:08:18 device hid-11:22:33:44:aa:bb-battery has unknown state, not saving history 1372s TI:22:08:18 culled 0 of 1 1372s TI:22:08:18 saved /tmp/upower-history-fbt8m634/history-rate-generic_id.dat 1372s TI:22:08:18 culled 0 of 1 1372s TI:22:08:18 saved /tmp/upower-history-fbt8m634/history-charge-generic_id.dat 1372s TI:22:08:18 culled 0 of 1 1372s TI:22:08:18 saved /tmp/upower-history-fbt8m634/history-time-full-generic_id.dat 1372s TI:22:08:18 culled 0 of 1 1372s TI:22:08:18 saved /tmp/upower-history-fbt8m634/history-time-empty-generic_id.dat 1372s TI:22:08:18 using id: Fancy_BT_mouse 1372s TI:22:08:18 failed to get data from /tmp/upower-history-fbt8m634/history-rate-Fancy_BT_mouse.dat as file does not exist 1372s TI:22:08:18 failed to get data from /tmp/upower-history-fbt8m634/history-charge-Fancy_BT_mouse.dat as file does not exist 1372s TI:22:08:18 failed to get data from /tmp/upower-history-fbt8m634/history-time-full-Fancy_BT_mouse.dat as file does not exist 1372s TI:22:08:18 failed to get data from /tmp/upower-history-fbt8m634/history-time-empty-Fancy_BT_mouse.dat as file does not exist 1372s TI:22:08:18 saving in 600 seconds 1372s TI:22:08:18 saving in 600 seconds 1372s TI:22:08:18 added native-path: hid-11:22:33:44:aa:bb-battery 1372s TI:22:08:18 Exported UpDevice with path /org/freedesktop/UPower/devices/battery_hid_11o22o33o44oaaobb_battery 1372s TI:22:08:18 device hid-11:22:33:44:aa:bb-battery has parent id: /sys/devices/usb1/bluetooth/hci0/hci0:01 1372s TI:22:08:18 up_device_supply_sibling_discovered (device: /sys/devices/usb1/bluetooth/hci0/hci0:01/1/power_supply/hid-11:22:33:44:aa:bb-battery, sibling: /sys/devices/usb1/bluetooth/hci0/hci0:01/input2/mouse3) 1372s TI:22:08:18 culled 0 of 1 1372s TI:22:08:18 saved /tmp/upower-history-fbt8m634/history-rate-Fancy_BT_mouse.dat 1372s TI:22:08:18 culled 0 of 2 1372s TI:22:08:18 saved /tmp/upower-history-fbt8m634/history-charge-Fancy_BT_mouse.dat 1372s TI:22:08:18 culled 0 of 1 1372s TI:22:08:18 saved /tmp/upower-history-fbt8m634/history-time-full-Fancy_BT_mouse.dat 1372s TI:22:08:18 culled 0 of 1 1372s TI:22:08:18 saved /tmp/upower-history-fbt8m634/history-time-empty-Fancy_BT_mouse.dat 1372s TI:22:08:18 Type changed from battery to mouse 1372s TI:22:08:18 Got new device from udev enumerator: 0x556344e2ca40 1372s TI:22:08:18 added hid-11:22:33:44:aa:bb-battery 1372s TI:22:08:18 emitting added: /org/freedesktop/UPower/devices/battery_hid_11o22o33o44oaaobb_battery 1372s TI:22:08:18 registering subsystem : usb 1372s TI:22:08:18 registering subsystem : usbmisc 1372s TI:22:08:18 registering subsystem : sound 1372s TI:22:08:18 on_battery = no 1372s TI:22:08:18 warning_level = none 1372s TI:22:08:18 device (null) has unknown state, not saving history 1372s TI:22:08:18 daemon now not coldplug 1372s TI:22:08:18 BlueZ disappeared 1372s TI:22:08:18 Received uevent remove on device /sys/devices/usb1/bluetooth/hci0/hci0:01/1/power_supply/hid-11:22:33:44:aa:bb-battery 1372s TI:22:08:18 removing device for path /sys/devices/usb1/bluetooth/hci0/hci0:01/1/power_supply/hid-11:22:33:44:aa:bb-battery 1372s TI:22:08:18 Removing device from udev enumerator: 0x556344e2ca40 1372s TI:22:08:18 removed hid-11:22:33:44:aa:bb-battery 1372s TI:22:08:18 emitting device-removed: /org/freedesktop/UPower/devices/battery_hid_11o22o33o44oaaobb_battery 1372s TI:22:08:18 on_battery = no 1373s TI:22:08:19 Received uevent add on device /sys/devices/usb1/bluetooth/hci0/hci0:01/1/power_supply/hid-11:22:33:44:aa:bb-battery 1373s TI:22:08:19 failed to coldplug hid-11:22:33:44:aa:bb-battery 1373s TI:22:08:19 using id: generic_id 1373s TI:22:08:19 loading 2 items of data from /tmp/upower-history-fbt8m634/history-rate-generic_id.dat 1373s TI:22:08:19 loading 2 items of data from /tmp/upower-history-fbt8m634/history-charge-generic_id.dat 1373s TI:22:08:19 loading 2 items of data from /tmp/upower-history-fbt8m634/history-time-full-generic_id.dat 1373s TI:22:08:19 loading 2 items of data from /tmp/upower-history-fbt8m634/history-time-empty-generic_id.dat 1373s TI:22:08:19 saving in 600 seconds 1373s TI:22:08:19 device hid-11:22:33:44:aa:bb-battery has unknown state, not saving history 1373s TI:22:08:19 culled 0 of 2 1373s TI:22:08:19 saved /tmp/upower-history-fbt8m634/history-rate-generic_id.dat 1373s TI:22:08:19 culled 0 of 2 1373s TI:22:08:19 saved /tmp/upower-history-fbt8m634/history-charge-generic_id.dat 1373s TI:22:08:19 culled 0 of 2 1373s TI:22:08:19 saved /tmp/upower-history-fbt8m634/history-time-full-generic_id.dat 1373s TI:22:08:19 culled 0 of 2 1373s TI:22:08:19 saved /tmp/upower-history-fbt8m634/history-time-empty-generic_id.dat 1373s TI:22:08:19 using id: Fancy_BT_mouse 1373s TI:22:08:19 loading 2 items of data from /tmp/upower-history-fbt8m634/history-rate-Fancy_BT_mouse.dat 1373s TI:22:08:19 loading 3 items of data from /tmp/upower-history-fbt8m634/history-charge-Fancy_BT_mouse.dat 1373s TI:22:08:19 loading 2 items of data from /tmp/upower-history-fbt8m634/history-time-full-Fancy_BT_mouse.dat 1373s TI:22:08:19 loading 2 items of data from /tmp/upower-history-fbt8m634/history-time-empty-Fancy_BT_mouse.dat 1373s TI:22:08:19 saving in 600 seconds 1373s TI:22:08:19 saving in 600 seconds 1373s TI:22:08:19 added native-path: hid-11:22:33:44:aa:bb-battery 1373s TI:22:08:19 Exported UpDevice with path /org/freedesktop/UPower/devices/battery_hid_11o22o33o44oaaobb_battery 1373s TI:22:08:19 device hid-11:22:33:44:aa:bb-battery has parent id: /sys/devices/usb1/bluetooth/hci0/hci0:01 1373s TI:22:08:19 up_device_supply_sibling_discovered (device: /sys/devices/usb1/bluetooth/hci0/hci0:01/1/power_supply/hid-11:22:33:44:aa:bb-battery, sibling: /sys/devices/usb1/bluetooth/hci0/hci0:01/input2/mouse3) 1373s TI:22:08:19 culled 0 of 2 1373s TI:22:08:19 saved /tmp/upower-history-fbt8m634/history-rate-Fancy_BT_mouse.dat 1373s TI:22:08:19 culled 0 of 4 1373s TI:22:08:19 saved /tmp/upower-history-fbt8m634/history-charge-Fancy_BT_mouse.dat 1373s TI:22:08:19 culled 0 of 2 1373s TI:22:08:19 saved /tmp/upower-history-fbt8m634/history-time-full-Fancy_BT_mouse.dat 1373s TI:22:08:19 culled 0 of 2 1373s TI:22:08:19 saved /tmp/upower-history-fbt8m634/history-time-empty-Fancy_BT_mouse.dat 1373s TI:22:08:19 Type changed from battery to mouse 1373s TI:22:08:19 Got new device from udev enumerator: 0x556344e2ca40 1373s TI:22:08:19 added hid-11:22:33:44:aa:bb-battery 1373s TI:22:08:19 emitting added: /org/freedesktop/UPower/devices/battery_hid_11o22o33o44oaaobb_battery 1373s TI:22:08:19 on_battery = no 1374s TI:22:08:20 Received uevent add on device /sys/devices/usb1/bluetooth/hci0/hci0:01/1/power_supply/hid-11:22:33:44:aa:bb-battery 1374s TI:22:08:20 refreshing device for path /sys/devices/usb1/bluetooth/hci0/hci0:01/1/power_supply/hid-11:22:33:44:aa:bb-battery 1374s TI:22:08:20 using id: Fancy_BT_mouse-11:22:33:44:aa:bb 1374s TI:22:08:20 failed to get data from /tmp/upower-history-fbt8m634/history-rate-Fancy_BT_mouse-11:22:33:44:aa:bb.dat as file does not exist 1374s TI:22:08:20 failed to get data from /tmp/upower-history-fbt8m634/history-charge-Fancy_BT_mouse-11:22:33:44:aa:bb.dat as file does not exist 1374s TI:22:08:20 failed to get data from /tmp/upower-history-fbt8m634/history-time-full-Fancy_BT_mouse-11:22:33:44:aa:bb.dat as file does not exist 1374s TI:22:08:20 failed to get data from /tmp/upower-history-fbt8m634/history-time-empty-Fancy_BT_mouse-11:22:33:44:aa:bb.dat as file does not exist 1374s TI:22:08:20 saving in 600 seconds 1374s TI:22:08:20 saving in 600 seconds 1374s TI:22:08:20 on_battery = no 1374s TI:22:08:20 Received uevent add on device /sys/devices/usb1/bluetooth/hci0/hci0:01/2/power_supply/hid-11:22:33:44:aa:bb-battery 1374s TI:22:08:20 Received uevent remove on device /sys/devices/usb1/bluetooth/hci0/hci0:01/2/power_supply/hid-11:22:33:44:aa:bb-battery 1374s TI:22:08:20 removing device for path /sys/devices/usb1/bluetooth/hci0/hci0:01/2/power_supply/hid-11:22:33:44:aa:bb-battery 1374s TI:22:08:20 Removing device from udev enumerator: 0x556344e2ca40 1374s TI:22:08:20 removed hid-11:22:33:44:aa:bb-battery 1374s TI:22:08:20 emitting device-removed: /org/freedesktop/UPower/devices/battery_hid_11o22o33o44oaaobb_battery 1374s TI:22:08:20 culled 0 of 1 1374s TI:22:08:20 saved /tmp/upower-history-fbt8m634/history-rate-Fancy_BT_mouse-11:22:33:44:aa:bb.dat 1374s TI:22:08:20 culled 0 of 2 1374s TI:22:08:20 saved /tmp/upower-history-fbt8m634/history-charge-Fancy_BT_mouse-11:22:33:44:aa:bb.dat 1374s TI:22:08:20 culled 0 of 1 1374s TI:22:08:20 saved /tmp/upower-history-fbt8m634/history-time-full-Fancy_BT_mouse-11:22:33:44:aa:bb.dat 1374s TI:22:08:20 culled 0 of 1 1374s TI:22:08:20 saved /tmp/upower-history-fbt8m634/history-time-empty-Fancy_BT_mouse-11:22:33:44:aa:bb.dat 1374s TI:22:08:20 failed to coldplug hid-11:22:33:44:aa:bb-battery 1374s TI:22:08:20 using id: generic_id 1374s TI:22:08:20 loading 3 items of data from /tmp/upower-history-fbt8m634/history-rate-generic_id.dat 1374s TI:22:08:20 loading 3 items of data from /tmp/upower-history-fbt8m634/history-charge-generic_id.dat 1374s TI:22:08:20 loading 3 items of data from /tmp/upower-history-fbt8m634/history-time-full-generic_id.dat 1374s TI:22:08:20 loading 3 items of data from /tmp/upower-history-fbt8m634/history-time-empty-generic_id.dat 1374s TI:22:08:20 saving in 600 seconds 1374s TI:22:08:20 device hid-11:22:33:44:aa:bb-battery has unknown state, not saving history 1374s TI:22:08:20 culled 0 of 3 1374s TI:22:08:20 saved /tmp/upower-history-fbt8m634/history-rate-generic_id.dat 1374s TI:22:08:20 culled 0 of 3 1374s TI:22:08:20 saved /tmp/upower-history-fbt8m634/history-charge-generic_id.dat 1374s TI:22:08:20 culled 0 of 3 1374s TI:22:08:20 saved /tmp/upower-history-fbt8m634/history-time-full-generic_id.dat 1374s TI:22:08:20 culled 0 of 3 1374s TI:22:08:20 saved /tmp/upower-history-fbt8m634/history-time-empty-generic_id.dat 1374s TI:22:08:20 using id: Fancy_BT_mouse 1374s TI:22:08:20 loading 3 items of data from /tmp/upower-history-fbt8m634/history-rate-Fancy_BT_mouse.dat 1374s TI:22:08:20 loading 5 items of data from /tmp/upower-history-fbt8m634/history-charge-Fancy_BT_mouse.dat 1374s TI:22:08:20 loading 3 items of data from /tmp/upower-history-fbt8m634/history-time-full-Fancy_BT_mouse.dat 1374s TI:22:08:20 loading 3 items of data from /tmp/upower-history-fbt8m634/history-time-empty-Fancy_BT_mouse.dat 1374s TI:22:08:20 saving in 600 seconds 1374s TI:22:08:20 deferring as earlier timeout is already queued 1374s TI:22:08:20 added native-path: hid-11:22:33:44:aa:bb-battery 1374s TI:22:08:20 Exported UpDevice with path /org/freedesktop/UPower/devices/battery_hid_11o22o33o44oaaobb_battery 1374s TI:22:08:20 device hid-11:22:33:44:aa:bb-battery has parent id: /sys/devices/usb1/bluetooth/hci0/hci0:01 1374s TI:22:08:20 up_device_supply_sibling_discovered (device: /sys/devices/usb1/bluetooth/hci0/hci0:01/2/power_supply/hid-11:22:33:44:aa:bb-battery, sibling: /sys/devices/usb1/bluetooth/hci0/hci0:01/input2/mouse3) 1374s TI:22:08:20 culled 0 of 3 1374s TI:22:08:20 saved /tmp/upower-history-fbt8m634/history-rate-Fancy_BT_mouse.dat 1374s TI:22:08:20 culled 0 of 6 1374s TI:22:08:20 saved /tmp/upower-history-fbt8m634/history-charge-Fancy_BT_mouse.dat 1374s TI:22:08:20 culled 0 of 3 1374s TI:22:08:20 saved /tmp/upower-history-fbt8m634/history-time-full-Fancy_BT_mouse.dat 1374s TI:22:08:20 culled 0 of 3 1374s TI:22:08:20 saved /tmp/upower-history-fbt8m634/history-time-empty-Fancy_BT_mouse.dat 1374s TI:22:08:20 Type changed from battery to mouse 1374s TI:22:08:20 Got new device from udev enumerator: 0x556344e2ca40 1374s TI:22:08:20 added hid-11:22:33:44:aa:bb-battery 1374s TI:22:08:20 emitting added: /org/freedesktop/UPower/devices/battery_hid_11o22o33o44oaaobb_battery 1374s TI:22:08:20 Received uevent add on device /sys/devices/usb1/bluetooth/hci0/hci0:01/2/power_supply/hid-11:22:33:44:aa:bb-battery 1374s TI:22:08:20 refreshing device for path /sys/devices/usb1/bluetooth/hci0/hci0:01/2/power_supply/hid-11:22:33:44:aa:bb-battery 1374s TI:22:08:20 using id: Fancy_BT_mouse-11:22:33:44:aa:bb 1374s TI:22:08:20 loading 2 items of data from /tmp/upower-history-fbt8m634/history-rate-Fancy_BT_mouse-11:22:33:44:aa:bb.dat 1374s TI:22:08:20 loading 3 items of data from /tmp/upower-history-fbt8m634/history-charge-Fancy_BT_mouse-11:22:33:44:aa:bb.dat 1374s TI:22:08:20 loading 2 items of data from /tmp/upower-history-fbt8m634/history-time-full-Fancy_BT_mouse-11:22:33:44:aa:bb.dat 1374s TI:22:08:20 loading 2 items of data from /tmp/upower-history-fbt8m634/history-time-empty-Fancy_BT_mouse-11:22:33:44:aa:bb.dat 1374s TI:22:08:20 saving in 600 seconds 1374s TI:22:08:20 deferring as earlier timeout is already queued 1374s TI:22:08:20 on_battery = no 1375s TI:22:08:21 Handling SIGTERM 1375s TI:22:08:21 culled 0 of 2 1375s TI:22:08:21 saved /tmp/upower-history-fbt8m634/history-rate-Fancy_BT_mouse-11:22:33:44:aa:bb.dat 1375s TI:22:08:21 culled 0 of 4 1375s TI:22:08:21 saved /tmp/upower-history-fbt8m634/history-charge-Fancy_BT_mouse-11:22:33:44:aa:bb.dat 1375s TI:22:08:21 culled 0 of 2 1375s TI:22:08:21 saved /tmp/upower-history-fbt8m634/history-time-full-Fancy_BT_mouse-11:22:33:44:aa:bb.dat 1375s TI:22:08:21 culled 0 of 2 1375s TI:22:08:21 saved /tmp/upower-history-fbt8m634/history-time-empty-Fancy_BT_mouse-11:22:33:44:aa:bb.dat 1375s .Executing: upower/upower-integration.test 1380s Test timed out after 300 seconds 1380s Executing: upower/upower-integration.test 1380s cleaning up pid 1896 1380s FAIL: upower/upower-integration.test (Child process killed by signal 9) 1381s SUMMARY: total=1; passed=0; skipped=0; failed=1; user=180.4s; system=62.4s; maxrss=37372 1381s FAIL: upower/upower-integration.test (Child process killed by signal 9) 1381s /tmp/autopkgtest.MTNuF4/wrapper.sh: Killing leaked background processes: 2190 1382s PID TTY STAT TIME COMMAND 1382s 2190 ? R 0:03 /usr/bin/python3 -m dbusmock --system org.freedesktop.PolicyKit1 /org/freedesktop/PolicyKit1/Authority org.freedesktop.PolicyKit1.Authority 1383s autopkgtest [22:08:29]: test installed-tests: -----------------------] 1386s autopkgtest [22:08:32]: test installed-tests: - - - - - - - - - - results - - - - - - - - - - 1386s installed-tests FAIL non-zero exit status 2 1389s autopkgtest [22:08:35]: test build: preparing testbed 1795s autopkgtest [22:15:21]: testbed dpkg architecture: riscv64 1798s autopkgtest [22:15:24]: testbed apt version: 2.9.33 1801s autopkgtest [22:15:27]: @@@@@@@@@@@@@@@@@@@@ test bed setup 1803s autopkgtest [22:15:29]: testbed release detected to be: plucky 1811s autopkgtest [22:15:37]: updating testbed package index (apt update) 1815s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease [126 kB] 1816s Hit:2 http://ftpmaster.internal/ubuntu plucky InRelease 1816s Hit:3 http://ftpmaster.internal/ubuntu plucky-updates InRelease 1816s Hit:4 http://ftpmaster.internal/ubuntu plucky-security InRelease 1817s Get:5 http://ftpmaster.internal/ubuntu plucky-proposed/universe Sources [369 kB] 1818s Get:6 http://ftpmaster.internal/ubuntu plucky-proposed/main Sources [44.1 kB] 1818s Get:7 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse Sources [14.5 kB] 1819s Get:8 http://ftpmaster.internal/ubuntu plucky-proposed/main riscv64 Packages [72.7 kB] 1819s Get:9 http://ftpmaster.internal/ubuntu plucky-proposed/main riscv64 c-n-f Metadata [1792 B] 1819s Get:10 http://ftpmaster.internal/ubuntu plucky-proposed/restricted riscv64 c-n-f Metadata [120 B] 1819s Get:11 http://ftpmaster.internal/ubuntu plucky-proposed/universe riscv64 Packages [319 kB] 1819s Get:12 http://ftpmaster.internal/ubuntu plucky-proposed/universe riscv64 c-n-f Metadata [14.3 kB] 1819s Get:13 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse riscv64 Packages [3532 B] 1819s Get:14 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse riscv64 c-n-f Metadata [272 B] 1835s Fetched 965 kB in 7s (135 kB/s) 1866s Reading package lists... 1876s Reading package lists... 1880s Building dependency tree... 1880s Reading state information... 1885s Calculating upgrade... 1887s Calculating upgrade... 1889s The following packages will be upgraded: 1889s pinentry-curses python3-jinja2 strace 1890s 3 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 1890s Need to get 615 kB of archives. 1890s After this operation, 27.6 kB of additional disk space will be used. 1890s Get:1 http://ftpmaster.internal/ubuntu plucky/main riscv64 strace riscv64 6.13+ds-1ubuntu1 [460 kB] 1891s Get:2 http://ftpmaster.internal/ubuntu plucky/main riscv64 pinentry-curses riscv64 1.3.1-2ubuntu3 [45.4 kB] 1891s Get:3 http://ftpmaster.internal/ubuntu plucky/main riscv64 python3-jinja2 all 3.1.5-2ubuntu1 [109 kB] 1903s Fetched 615 kB in 1s (493 kB/s) 1907s (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 ... 114110 files and directories currently installed.) 1907s Preparing to unpack .../strace_6.13+ds-1ubuntu1_riscv64.deb ... 1908s Unpacking strace (6.13+ds-1ubuntu1) over (6.11-0ubuntu1) ... 1910s Preparing to unpack .../pinentry-curses_1.3.1-2ubuntu3_riscv64.deb ... 1910s Unpacking pinentry-curses (1.3.1-2ubuntu3) over (1.3.1-2ubuntu2) ... 1911s Preparing to unpack .../python3-jinja2_3.1.5-2ubuntu1_all.deb ... 1915s Unpacking python3-jinja2 (3.1.5-2ubuntu1) over (3.1.5-2) ... 1917s Setting up pinentry-curses (1.3.1-2ubuntu3) ... 1917s Setting up python3-jinja2 (3.1.5-2ubuntu1) ... 1925s Setting up strace (6.13+ds-1ubuntu1) ... 1925s Processing triggers for man-db (2.13.0-1) ... 1945s Reading package lists... 1948s Building dependency tree... 1948s Reading state information... 1953s Solving dependencies... 1955s The following packages will be REMOVED: 1955s libnsl2* libpython3.12-minimal* libpython3.12-stdlib* libpython3.12t64* 1955s linux-headers-6.11.0-8-generic* linux-image-6.11.0-8-generic* 1955s linux-modules-6.11.0-8-generic* linux-riscv-headers-6.11.0-8* 1955s linux-riscv-tools-6.11.0-8* linux-tools-6.11.0-8-generic* 1965s 0 upgraded, 0 newly installed, 10 to remove and 5 not upgraded. 1965s After this operation, 355 MB disk space will be freed. 1967s (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 ... 114110 files and directories currently installed.) 1967s Removing linux-tools-6.11.0-8-generic (6.11.0-8.8.1) ... 1967s Removing linux-riscv-tools-6.11.0-8 (6.11.0-8.8.1) ... 1967s Removing libpython3.12t64:riscv64 (3.12.9-1) ... 1967s Removing libpython3.12-stdlib:riscv64 (3.12.9-1) ... 1970s Removing libnsl2:riscv64 (1.3.0-3build3) ... 1971s Removing libpython3.12-minimal:riscv64 (3.12.9-1) ... 1973s Removing linux-headers-6.11.0-8-generic (6.11.0-8.8.1) ... 1984s Removing linux-image-6.11.0-8-generic (6.11.0-8.8.1) ... 1993s I: /boot/vmlinuz.old is now a symlink to vmlinuz-6.14.0-7-generic 1993s I: /boot/initrd.img.old is now a symlink to initrd.img-6.14.0-7-generic 1993s /etc/kernel/postrm.d/initramfs-tools: 1993s update-initramfs: Deleting /boot/initrd.img-6.11.0-8-generic 1994s /etc/kernel/postrm.d/zz-u-boot-menu: 1994s P: Checking for EXTLINUX directory... found. 1996s P: Writing config for vmlinuz-6.14.0-7-generic... 1997s P: Updating /boot/extlinux/extlinux.conf... 2000s /etc/kernel/postrm.d/zz-update-grub: 2000s Sourcing file `/etc/default/grub' 2000s Generating grub configuration file ... 2011s Found linux image: /boot/vmlinuz-6.14.0-7-generic 2012s Found initrd image: /boot/initrd.img-6.14.0-7-generic 2023s Warning: os-prober will not be executed to detect other bootable partitions. 2023s Systems on them will not be added to the GRUB boot configuration. 2023s Check GRUB_DISABLE_OS_PROBER documentation entry. 2024s Adding boot menu entry for UEFI Firmware Settings ... 2025s done 2025s Removing linux-modules-6.11.0-8-generic (6.11.0-8.8.1) ... 2037s Removing linux-riscv-headers-6.11.0-8 (6.11.0-8.8.1) ... 2067s Processing triggers for libc-bin (2.41-1ubuntu1) ... 2070s (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 ... 75587 files and directories currently installed.) 2070s Purging configuration files for linux-image-6.11.0-8-generic (6.11.0-8.8.1) ... 2073s /etc/kernel/postrm.d/zz-u-boot-menu: 2073s P: Checking for EXTLINUX directory... found. 2075s P: Writing config for vmlinuz-6.14.0-7-generic... 2078s Purging configuration files for libpython3.12-minimal:riscv64 (3.12.9-1) ... 2079s Purging configuration files for linux-modules-6.11.0-8-generic (6.11.0-8.8.1) ... 2092s autopkgtest [22:20:18]: upgrading testbed (apt dist-upgrade and autopurge) 2095s Reading package lists... 2098s Building dependency tree... 2098s Reading state information... 2103s Calculating upgrade...Starting pkgProblemResolver with broken count: 0 2105s Starting 2 pkgProblemResolver with broken count: 0 2105s Done 2112s Entering ResolveByKeep 2114s 2115s Calculating upgrade... 2117s The following packages will be upgraded: 2117s libc-bin libc-dev-bin libc6 libc6-dev locales 2118s 5 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 2118s Need to get 11.3 MB of archives. 2118s After this operation, 2048 B of additional disk space will be used. 2118s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed/main riscv64 libc6-dev riscv64 2.41-1ubuntu2 [3560 kB] 2119s Get:2 http://ftpmaster.internal/ubuntu plucky-proposed/main riscv64 libc-dev-bin riscv64 2.41-1ubuntu2 [24.0 kB] 2119s Get:3 http://ftpmaster.internal/ubuntu plucky-proposed/main riscv64 libc6 riscv64 2.41-1ubuntu2 [2845 kB] 2120s Get:4 http://ftpmaster.internal/ubuntu plucky-proposed/main riscv64 libc-bin riscv64 2.41-1ubuntu2 [640 kB] 2120s Get:5 http://ftpmaster.internal/ubuntu plucky-proposed/main riscv64 locales all 2.41-1ubuntu2 [4246 kB] 2132s Preconfiguring packages ... 2135s Fetched 11.3 MB in 5s (2493 kB/s) 2138s (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 ... 75584 files and directories currently installed.) 2138s Preparing to unpack .../libc6-dev_2.41-1ubuntu2_riscv64.deb ... 2138s Unpacking libc6-dev:riscv64 (2.41-1ubuntu2) over (2.41-1ubuntu1) ... 2148s Preparing to unpack .../libc-dev-bin_2.41-1ubuntu2_riscv64.deb ... 2148s Unpacking libc-dev-bin (2.41-1ubuntu2) over (2.41-1ubuntu1) ... 2149s Preparing to unpack .../libc6_2.41-1ubuntu2_riscv64.deb ... 2156s Unpacking libc6:riscv64 (2.41-1ubuntu2) over (2.41-1ubuntu1) ... 2161s Setting up libc6:riscv64 (2.41-1ubuntu2) ... 2169s (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 ... 75584 files and directories currently installed.) 2169s Preparing to unpack .../libc-bin_2.41-1ubuntu2_riscv64.deb ... 2169s Unpacking libc-bin (2.41-1ubuntu2) over (2.41-1ubuntu1) ... 2171s Setting up libc-bin (2.41-1ubuntu2) ... 2174s (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 ... 75584 files and directories currently installed.) 2174s Preparing to unpack .../locales_2.41-1ubuntu2_all.deb ... 2174s Unpacking locales (2.41-1ubuntu2) over (2.41-1ubuntu1) ... 2183s Setting up locales (2.41-1ubuntu2) ... 2243s Generating locales (this might take a while)... 2273s en_US.UTF-8... done 2273s Generation complete. 2274s Setting up libc-dev-bin (2.41-1ubuntu2) ... 2274s Setting up libc6-dev:riscv64 (2.41-1ubuntu2) ... 2274s Processing triggers for man-db (2.13.0-1) ... 2284s Processing triggers for systemd (257.3-1ubuntu3) ... 2316s Reading package lists... 2320s Building dependency tree... 2320s Reading state information... 2325s Starting pkgProblemResolver with broken count: 0 2327s Starting 2 pkgProblemResolver with broken count: 0 2327s Done 2332s Solving dependencies... 2335s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 2338s autopkgtest [22:24:24]: rebooting testbed after setup commands that affected boot 2375s autopkgtest-virt-ssh: WARNING: ssh connection failed. Retrying in 3 seconds... 2408s autopkgtest-virt-ssh: WARNING: ssh connection failed. Retrying in 3 seconds... 2441s autopkgtest-virt-ssh: WARNING: ssh connection failed. Retrying in 3 seconds... 2474s autopkgtest-virt-ssh: WARNING: ssh connection failed. Retrying in 3 seconds... 2507s autopkgtest-virt-ssh: WARNING: ssh connection failed. Retrying in 3 seconds... 2540s autopkgtest-virt-ssh: WARNING: ssh connection failed. Retrying in 3 seconds... 2599s Reading package lists... 2602s Building dependency tree... 2602s Reading state information... 2604s Starting pkgProblemResolver with broken count: 0 2604s Starting 2 pkgProblemResolver with broken count: 0 2604s Done 2610s The following NEW packages will be installed: 2610s build-essential cpp cpp-14 cpp-14-riscv64-linux-gnu cpp-riscv64-linux-gnu 2610s g++ g++-14 g++-14-riscv64-linux-gnu g++-riscv64-linux-gnu gcc gcc-14 2610s gcc-14-riscv64-linux-gnu gcc-riscv64-linux-gnu gir1.2-glib-2.0-dev 2610s gir1.2-polkit-1.0 gir1.2-upowerglib-1.0 girepository-tools libasan8 2610s libblkid-dev libcc1-0 libffi-dev libgcc-14-dev libgio-2.0-dev 2610s libgio-2.0-dev-bin libgirepository-2.0-0 libglib2.0-bin libglib2.0-dev 2610s libglib2.0-dev-bin libgomp1 libimobiledevice-1.0-6 2610s libimobiledevice-glue-1.0-0 libisl23 libitm1 liblsan0 libmount-dev libmpc3 2610s libpcre2-16-0 libpcre2-32-0 libpcre2-dev libpcre2-posix3 libpkgconf3 2610s libplist-2.0-4 libpolkit-gobject-1-dev libselinux1-dev libsepol-dev 2610s libstdc++-14-dev libsysprof-capture-4-dev libtsan2 libubsan1 2610s libupower-glib-dev libupower-glib3 libusbmuxd-2.0-7 native-architecture 2610s pkgconf pkgconf-bin python3-packaging upower uuid-dev zlib1g-dev 2611s 0 upgraded, 59 newly installed, 0 to remove and 0 not upgraded. 2611s Need to get 97.6 MB of archives. 2611s After this operation, 474 MB of additional disk space will be used. 2611s Get:1 http://ftpmaster.internal/ubuntu plucky/main riscv64 libisl23 riscv64 0.27-1 [722 kB] 2612s Get:2 http://ftpmaster.internal/ubuntu plucky/main riscv64 libmpc3 riscv64 1.3.1-1build2 [61.5 kB] 2612s Get:3 http://ftpmaster.internal/ubuntu plucky/main riscv64 cpp-14-riscv64-linux-gnu riscv64 14.2.0-17ubuntu3 [15.2 MB] 2616s Get:4 http://ftpmaster.internal/ubuntu plucky/main riscv64 cpp-14 riscv64 14.2.0-17ubuntu3 [1028 B] 2616s Get:5 http://ftpmaster.internal/ubuntu plucky/main riscv64 cpp-riscv64-linux-gnu riscv64 4:14.2.0-1ubuntu1 [5554 B] 2616s Get:6 http://ftpmaster.internal/ubuntu plucky/main riscv64 cpp riscv64 4:14.2.0-1ubuntu1 [22.4 kB] 2616s Get:7 http://ftpmaster.internal/ubuntu plucky/main riscv64 libcc1-0 riscv64 15-20250222-0ubuntu1 [46.0 kB] 2616s Get:8 http://ftpmaster.internal/ubuntu plucky/main riscv64 libgomp1 riscv64 15-20250222-0ubuntu1 [148 kB] 2616s Get:9 http://ftpmaster.internal/ubuntu plucky/main riscv64 libitm1 riscv64 15-20250222-0ubuntu1 [29.7 kB] 2616s Get:10 http://ftpmaster.internal/ubuntu plucky/main riscv64 libasan8 riscv64 15-20250222-0ubuntu1 [3306 kB] 2617s Get:11 http://ftpmaster.internal/ubuntu plucky/main riscv64 liblsan0 riscv64 15-20250222-0ubuntu1 [1474 kB] 2617s Get:12 http://ftpmaster.internal/ubuntu plucky/main riscv64 libtsan2 riscv64 15-20250222-0ubuntu1 [2988 kB] 2618s Get:13 http://ftpmaster.internal/ubuntu plucky/main riscv64 libubsan1 riscv64 15-20250222-0ubuntu1 [1313 kB] 2618s Get:14 http://ftpmaster.internal/ubuntu plucky/main riscv64 libgcc-14-dev riscv64 14.2.0-17ubuntu3 [5982 kB] 2619s Get:15 http://ftpmaster.internal/ubuntu plucky/main riscv64 gcc-14-riscv64-linux-gnu riscv64 14.2.0-17ubuntu3 [29.9 MB] 2626s Get:16 http://ftpmaster.internal/ubuntu plucky/main riscv64 gcc-14 riscv64 14.2.0-17ubuntu3 [526 kB] 2626s Get:17 http://ftpmaster.internal/ubuntu plucky/main riscv64 gcc-riscv64-linux-gnu riscv64 4:14.2.0-1ubuntu1 [1198 B] 2626s Get:18 http://ftpmaster.internal/ubuntu plucky/main riscv64 gcc riscv64 4:14.2.0-1ubuntu1 [4998 B] 2626s Get:19 http://ftpmaster.internal/ubuntu plucky/main riscv64 libstdc++-14-dev riscv64 14.2.0-17ubuntu3 [6135 kB] 2628s Get:20 http://ftpmaster.internal/ubuntu plucky/main riscv64 g++-14-riscv64-linux-gnu riscv64 14.2.0-17ubuntu3 [16.8 MB] 2632s Get:21 http://ftpmaster.internal/ubuntu plucky/main riscv64 g++-14 riscv64 14.2.0-17ubuntu3 [21.8 kB] 2632s Get:22 http://ftpmaster.internal/ubuntu plucky/main riscv64 g++-riscv64-linux-gnu riscv64 4:14.2.0-1ubuntu1 [960 B] 2632s Get:23 http://ftpmaster.internal/ubuntu plucky/main riscv64 g++ riscv64 4:14.2.0-1ubuntu1 [1080 B] 2632s Get:24 http://ftpmaster.internal/ubuntu plucky/main riscv64 build-essential riscv64 12.10ubuntu1 [4934 B] 2632s Get:25 http://ftpmaster.internal/ubuntu plucky/main riscv64 gir1.2-glib-2.0-dev riscv64 2.84.0-1 [861 kB] 2633s Get:26 http://ftpmaster.internal/ubuntu plucky/main riscv64 gir1.2-polkit-1.0 riscv64 126-2 [8614 B] 2633s Get:27 http://ftpmaster.internal/ubuntu plucky/main riscv64 libupower-glib3 riscv64 1.90.7-1build1 [41.3 kB] 2633s Get:28 http://ftpmaster.internal/ubuntu plucky/main riscv64 gir1.2-upowerglib-1.0 riscv64 1.90.7-1build1 [5432 B] 2633s Get:29 http://ftpmaster.internal/ubuntu plucky/main riscv64 native-architecture all 0.2.6 [2140 B] 2633s Get:30 http://ftpmaster.internal/ubuntu plucky/main riscv64 libgirepository-2.0-0 riscv64 2.84.0-1 [118 kB] 2633s Get:31 http://ftpmaster.internal/ubuntu plucky/main riscv64 girepository-tools riscv64 2.84.0-1 [121 kB] 2633s Get:32 http://ftpmaster.internal/ubuntu plucky/main riscv64 libffi-dev riscv64 3.4.7-1 [88.8 kB] 2633s Get:33 http://ftpmaster.internal/ubuntu plucky/main riscv64 uuid-dev riscv64 2.40.2-14ubuntu1 [73.6 kB] 2633s Get:34 http://ftpmaster.internal/ubuntu plucky/main riscv64 libblkid-dev riscv64 2.40.2-14ubuntu1 [586 kB] 2633s Get:35 http://ftpmaster.internal/ubuntu plucky/main riscv64 libsepol-dev riscv64 3.7-1 [1216 kB] 2633s Get:36 http://ftpmaster.internal/ubuntu plucky/main riscv64 libpcre2-16-0 riscv64 10.45-1 [246 kB] 2633s Get:37 http://ftpmaster.internal/ubuntu plucky/main riscv64 libpcre2-32-0 riscv64 10.45-1 [232 kB] 2633s Get:38 http://ftpmaster.internal/ubuntu plucky/main riscv64 libpcre2-posix3 riscv64 10.45-1 [6820 B] 2633s Get:39 http://ftpmaster.internal/ubuntu plucky/main riscv64 libpcre2-dev riscv64 10.45-1 [2204 kB] 2634s Get:40 http://ftpmaster.internal/ubuntu plucky/main riscv64 libselinux1-dev riscv64 3.7-3ubuntu3 [321 kB] 2634s Get:41 http://ftpmaster.internal/ubuntu plucky/main riscv64 libmount-dev riscv64 2.40.2-14ubuntu1 [34.6 kB] 2634s Get:42 http://ftpmaster.internal/ubuntu plucky/main riscv64 libsysprof-capture-4-dev riscv64 48~beta-2 [131 kB] 2634s Get:43 http://ftpmaster.internal/ubuntu plucky/main riscv64 libpkgconf3 riscv64 1.8.1-4 [31.6 kB] 2634s Get:44 http://ftpmaster.internal/ubuntu plucky/main riscv64 pkgconf-bin riscv64 1.8.1-4 [21.8 kB] 2634s Get:45 http://ftpmaster.internal/ubuntu plucky/main riscv64 pkgconf riscv64 1.8.1-4 [16.7 kB] 2634s Get:46 http://ftpmaster.internal/ubuntu plucky/main riscv64 zlib1g-dev riscv64 1:1.3.dfsg+really1.3.1-1ubuntu1 [968 kB] 2635s Get:47 http://ftpmaster.internal/ubuntu plucky/main riscv64 libgio-2.0-dev riscv64 2.84.0-1 [4885 kB] 2636s Get:48 http://ftpmaster.internal/ubuntu plucky/main riscv64 python3-packaging all 24.2-1 [51.5 kB] 2636s Get:49 http://ftpmaster.internal/ubuntu plucky/main riscv64 libgio-2.0-dev-bin riscv64 2.84.0-1 [133 kB] 2636s Get:50 http://ftpmaster.internal/ubuntu plucky/main riscv64 libglib2.0-bin riscv64 2.84.0-1 [97.7 kB] 2636s Get:51 http://ftpmaster.internal/ubuntu plucky/main riscv64 libglib2.0-dev-bin riscv64 2.84.0-1 [18.1 kB] 2636s Get:52 http://ftpmaster.internal/ubuntu plucky/main riscv64 libglib2.0-dev riscv64 2.84.0-1 [18.9 kB] 2636s Get:53 http://ftpmaster.internal/ubuntu plucky/main riscv64 libplist-2.0-4 riscv64 2.6.0-2build1 [56.8 kB] 2636s Get:54 http://ftpmaster.internal/ubuntu plucky/main riscv64 libimobiledevice-glue-1.0-0 riscv64 1.3.1-1 [34.1 kB] 2636s Get:55 http://ftpmaster.internal/ubuntu plucky/main riscv64 libusbmuxd-2.0-7 riscv64 2.1.0-1build1 [18.8 kB] 2636s Get:56 http://ftpmaster.internal/ubuntu plucky/main riscv64 libimobiledevice-1.0-6 riscv64 1.3.0+git20240701-4build2 [151 kB] 2636s Get:57 http://ftpmaster.internal/ubuntu plucky/main riscv64 libpolkit-gobject-1-dev riscv64 126-2 [26.3 kB] 2636s Get:58 http://ftpmaster.internal/ubuntu plucky/main riscv64 libupower-glib-dev riscv64 1.90.7-1build1 [13.2 kB] 2636s Get:59 http://ftpmaster.internal/ubuntu plucky/main riscv64 upower riscv64 1.90.7-1build1 [94.6 kB] 2648s Fetched 97.6 MB in 26s (3770 kB/s) 2649s Selecting previously unselected package libisl23:riscv64. 2651s (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 ... 75584 files and directories currently installed.) 2651s Preparing to unpack .../00-libisl23_0.27-1_riscv64.deb ... 2651s Unpacking libisl23:riscv64 (0.27-1) ... 2652s Selecting previously unselected package libmpc3:riscv64. 2652s Preparing to unpack .../01-libmpc3_1.3.1-1build2_riscv64.deb ... 2652s Unpacking libmpc3:riscv64 (1.3.1-1build2) ... 2653s Selecting previously unselected package cpp-14-riscv64-linux-gnu. 2654s Preparing to unpack .../02-cpp-14-riscv64-linux-gnu_14.2.0-17ubuntu3_riscv64.deb ... 2654s Unpacking cpp-14-riscv64-linux-gnu (14.2.0-17ubuntu3) ... 2670s Selecting previously unselected package cpp-14. 2670s Preparing to unpack .../03-cpp-14_14.2.0-17ubuntu3_riscv64.deb ... 2670s Unpacking cpp-14 (14.2.0-17ubuntu3) ... 2671s Selecting previously unselected package cpp-riscv64-linux-gnu. 2671s Preparing to unpack .../04-cpp-riscv64-linux-gnu_4%3a14.2.0-1ubuntu1_riscv64.deb ... 2671s Unpacking cpp-riscv64-linux-gnu (4:14.2.0-1ubuntu1) ... 2672s Selecting previously unselected package cpp. 2672s Preparing to unpack .../05-cpp_4%3a14.2.0-1ubuntu1_riscv64.deb ... 2672s Unpacking cpp (4:14.2.0-1ubuntu1) ... 2673s Selecting previously unselected package libcc1-0:riscv64. 2673s Preparing to unpack .../06-libcc1-0_15-20250222-0ubuntu1_riscv64.deb ... 2673s Unpacking libcc1-0:riscv64 (15-20250222-0ubuntu1) ... 2674s Selecting previously unselected package libgomp1:riscv64. 2674s Preparing to unpack .../07-libgomp1_15-20250222-0ubuntu1_riscv64.deb ... 2674s Unpacking libgomp1:riscv64 (15-20250222-0ubuntu1) ... 2675s Selecting previously unselected package libitm1:riscv64. 2675s Preparing to unpack .../08-libitm1_15-20250222-0ubuntu1_riscv64.deb ... 2675s Unpacking libitm1:riscv64 (15-20250222-0ubuntu1) ... 2676s Selecting previously unselected package libasan8:riscv64. 2676s Preparing to unpack .../09-libasan8_15-20250222-0ubuntu1_riscv64.deb ... 2676s Unpacking libasan8:riscv64 (15-20250222-0ubuntu1) ... 2681s Selecting previously unselected package liblsan0:riscv64. 2681s Preparing to unpack .../10-liblsan0_15-20250222-0ubuntu1_riscv64.deb ... 2681s Unpacking liblsan0:riscv64 (15-20250222-0ubuntu1) ... 2683s Selecting previously unselected package libtsan2:riscv64. 2683s Preparing to unpack .../11-libtsan2_15-20250222-0ubuntu1_riscv64.deb ... 2683s Unpacking libtsan2:riscv64 (15-20250222-0ubuntu1) ... 2687s Selecting previously unselected package libubsan1:riscv64. 2687s Preparing to unpack .../12-libubsan1_15-20250222-0ubuntu1_riscv64.deb ... 2687s Unpacking libubsan1:riscv64 (15-20250222-0ubuntu1) ... 2689s Selecting previously unselected package libgcc-14-dev:riscv64. 2690s Preparing to unpack .../13-libgcc-14-dev_14.2.0-17ubuntu3_riscv64.deb ... 2690s Unpacking libgcc-14-dev:riscv64 (14.2.0-17ubuntu3) ... 2702s Selecting previously unselected package gcc-14-riscv64-linux-gnu. 2703s Preparing to unpack .../14-gcc-14-riscv64-linux-gnu_14.2.0-17ubuntu3_riscv64.deb ... 2703s Unpacking gcc-14-riscv64-linux-gnu (14.2.0-17ubuntu3) ... 2732s Selecting previously unselected package gcc-14. 2732s Preparing to unpack .../15-gcc-14_14.2.0-17ubuntu3_riscv64.deb ... 2732s Unpacking gcc-14 (14.2.0-17ubuntu3) ... 2733s Selecting previously unselected package gcc-riscv64-linux-gnu. 2733s Preparing to unpack .../16-gcc-riscv64-linux-gnu_4%3a14.2.0-1ubuntu1_riscv64.deb ... 2733s Unpacking gcc-riscv64-linux-gnu (4:14.2.0-1ubuntu1) ... 2734s Selecting previously unselected package gcc. 2734s Preparing to unpack .../17-gcc_4%3a14.2.0-1ubuntu1_riscv64.deb ... 2734s Unpacking gcc (4:14.2.0-1ubuntu1) ... 2735s Selecting previously unselected package libstdc++-14-dev:riscv64. 2735s Preparing to unpack .../18-libstdc++-14-dev_14.2.0-17ubuntu3_riscv64.deb ... 2735s Unpacking libstdc++-14-dev:riscv64 (14.2.0-17ubuntu3) ... 2748s Selecting previously unselected package g++-14-riscv64-linux-gnu. 2749s Preparing to unpack .../19-g++-14-riscv64-linux-gnu_14.2.0-17ubuntu3_riscv64.deb ... 2749s Unpacking g++-14-riscv64-linux-gnu (14.2.0-17ubuntu3) ... 2765s Selecting previously unselected package g++-14. 2765s Preparing to unpack .../20-g++-14_14.2.0-17ubuntu3_riscv64.deb ... 2765s Unpacking g++-14 (14.2.0-17ubuntu3) ... 2766s Selecting previously unselected package g++-riscv64-linux-gnu. 2766s Preparing to unpack .../21-g++-riscv64-linux-gnu_4%3a14.2.0-1ubuntu1_riscv64.deb ... 2766s Unpacking g++-riscv64-linux-gnu (4:14.2.0-1ubuntu1) ... 2767s Selecting previously unselected package g++. 2767s Preparing to unpack .../22-g++_4%3a14.2.0-1ubuntu1_riscv64.deb ... 2767s Unpacking g++ (4:14.2.0-1ubuntu1) ... 2768s Selecting previously unselected package build-essential. 2768s Preparing to unpack .../23-build-essential_12.10ubuntu1_riscv64.deb ... 2768s Unpacking build-essential (12.10ubuntu1) ... 2769s Selecting previously unselected package gir1.2-glib-2.0-dev:riscv64. 2769s Preparing to unpack .../24-gir1.2-glib-2.0-dev_2.84.0-1_riscv64.deb ... 2769s Unpacking gir1.2-glib-2.0-dev:riscv64 (2.84.0-1) ... 2772s Selecting previously unselected package gir1.2-polkit-1.0. 2772s Preparing to unpack .../25-gir1.2-polkit-1.0_126-2_riscv64.deb ... 2772s Unpacking gir1.2-polkit-1.0 (126-2) ... 2773s Selecting previously unselected package libupower-glib3:riscv64. 2773s Preparing to unpack .../26-libupower-glib3_1.90.7-1build1_riscv64.deb ... 2773s Unpacking libupower-glib3:riscv64 (1.90.7-1build1) ... 2774s Selecting previously unselected package gir1.2-upowerglib-1.0:riscv64. 2774s Preparing to unpack .../27-gir1.2-upowerglib-1.0_1.90.7-1build1_riscv64.deb ... 2774s Unpacking gir1.2-upowerglib-1.0:riscv64 (1.90.7-1build1) ... 2775s Selecting previously unselected package native-architecture. 2775s Preparing to unpack .../28-native-architecture_0.2.6_all.deb ... 2775s Unpacking native-architecture (0.2.6) ... 2776s Selecting previously unselected package libgirepository-2.0-0:riscv64. 2776s Preparing to unpack .../29-libgirepository-2.0-0_2.84.0-1_riscv64.deb ... 2776s Unpacking libgirepository-2.0-0:riscv64 (2.84.0-1) ... 2777s Selecting previously unselected package girepository-tools:riscv64. 2777s Preparing to unpack .../30-girepository-tools_2.84.0-1_riscv64.deb ... 2777s Unpacking girepository-tools:riscv64 (2.84.0-1) ... 2778s Selecting previously unselected package libffi-dev:riscv64. 2778s Preparing to unpack .../31-libffi-dev_3.4.7-1_riscv64.deb ... 2778s Unpacking libffi-dev:riscv64 (3.4.7-1) ... 2779s Selecting previously unselected package uuid-dev:riscv64. 2779s Preparing to unpack .../32-uuid-dev_2.40.2-14ubuntu1_riscv64.deb ... 2779s Unpacking uuid-dev:riscv64 (2.40.2-14ubuntu1) ... 2780s Selecting previously unselected package libblkid-dev:riscv64. 2781s Preparing to unpack .../33-libblkid-dev_2.40.2-14ubuntu1_riscv64.deb ... 2781s Unpacking libblkid-dev:riscv64 (2.40.2-14ubuntu1) ... 2782s Selecting previously unselected package libsepol-dev:riscv64. 2782s Preparing to unpack .../34-libsepol-dev_3.7-1_riscv64.deb ... 2782s Unpacking libsepol-dev:riscv64 (3.7-1) ... 2786s Selecting previously unselected package libpcre2-16-0:riscv64. 2786s Preparing to unpack .../35-libpcre2-16-0_10.45-1_riscv64.deb ... 2786s Unpacking libpcre2-16-0:riscv64 (10.45-1) ... 2787s Selecting previously unselected package libpcre2-32-0:riscv64. 2787s Preparing to unpack .../36-libpcre2-32-0_10.45-1_riscv64.deb ... 2787s Unpacking libpcre2-32-0:riscv64 (10.45-1) ... 2788s Selecting previously unselected package libpcre2-posix3:riscv64. 2788s Preparing to unpack .../37-libpcre2-posix3_10.45-1_riscv64.deb ... 2788s Unpacking libpcre2-posix3:riscv64 (10.45-1) ... 2789s Selecting previously unselected package libpcre2-dev:riscv64. 2789s Preparing to unpack .../38-libpcre2-dev_10.45-1_riscv64.deb ... 2789s Unpacking libpcre2-dev:riscv64 (10.45-1) ... 2794s Selecting previously unselected package libselinux1-dev:riscv64. 2794s Preparing to unpack .../39-libselinux1-dev_3.7-3ubuntu3_riscv64.deb ... 2794s Unpacking libselinux1-dev:riscv64 (3.7-3ubuntu3) ... 2796s Selecting previously unselected package libmount-dev:riscv64. 2796s Preparing to unpack .../40-libmount-dev_2.40.2-14ubuntu1_riscv64.deb ... 2796s Unpacking libmount-dev:riscv64 (2.40.2-14ubuntu1) ... 2797s Selecting previously unselected package libsysprof-capture-4-dev:riscv64. 2797s Preparing to unpack .../41-libsysprof-capture-4-dev_48~beta-2_riscv64.deb ... 2797s Unpacking libsysprof-capture-4-dev:riscv64 (48~beta-2) ... 2798s Selecting previously unselected package libpkgconf3:riscv64. 2798s Preparing to unpack .../42-libpkgconf3_1.8.1-4_riscv64.deb ... 2798s Unpacking libpkgconf3:riscv64 (1.8.1-4) ... 2799s Selecting previously unselected package pkgconf-bin. 2799s Preparing to unpack .../43-pkgconf-bin_1.8.1-4_riscv64.deb ... 2799s Unpacking pkgconf-bin (1.8.1-4) ... 2800s Selecting previously unselected package pkgconf:riscv64. 2800s Preparing to unpack .../44-pkgconf_1.8.1-4_riscv64.deb ... 2800s Unpacking pkgconf:riscv64 (1.8.1-4) ... 2801s Selecting previously unselected package zlib1g-dev:riscv64. 2801s Preparing to unpack .../45-zlib1g-dev_1%3a1.3.dfsg+really1.3.1-1ubuntu1_riscv64.deb ... 2801s Unpacking zlib1g-dev:riscv64 (1:1.3.dfsg+really1.3.1-1ubuntu1) ... 2802s Selecting previously unselected package libgio-2.0-dev:riscv64. 2803s Preparing to unpack .../46-libgio-2.0-dev_2.84.0-1_riscv64.deb ... 2803s Unpacking libgio-2.0-dev:riscv64 (2.84.0-1) ... 2814s Selecting previously unselected package python3-packaging. 2814s Preparing to unpack .../47-python3-packaging_24.2-1_all.deb ... 2814s Unpacking python3-packaging (24.2-1) ... 2815s Selecting previously unselected package libgio-2.0-dev-bin. 2816s Preparing to unpack .../48-libgio-2.0-dev-bin_2.84.0-1_riscv64.deb ... 2816s Unpacking libgio-2.0-dev-bin (2.84.0-1) ... 2817s Selecting previously unselected package libglib2.0-bin. 2817s Preparing to unpack .../49-libglib2.0-bin_2.84.0-1_riscv64.deb ... 2817s Unpacking libglib2.0-bin (2.84.0-1) ... 2818s Selecting previously unselected package libglib2.0-dev-bin. 2818s Preparing to unpack .../50-libglib2.0-dev-bin_2.84.0-1_riscv64.deb ... 2818s Unpacking libglib2.0-dev-bin (2.84.0-1) ... 2818s Selecting previously unselected package libglib2.0-dev:riscv64. 2819s Preparing to unpack .../51-libglib2.0-dev_2.84.0-1_riscv64.deb ... 2819s Unpacking libglib2.0-dev:riscv64 (2.84.0-1) ... 2819s Selecting previously unselected package libplist-2.0-4:riscv64. 2820s Preparing to unpack .../52-libplist-2.0-4_2.6.0-2build1_riscv64.deb ... 2820s Unpacking libplist-2.0-4:riscv64 (2.6.0-2build1) ... 2820s Selecting previously unselected package libimobiledevice-glue-1.0-0. 2820s Preparing to unpack .../53-libimobiledevice-glue-1.0-0_1.3.1-1_riscv64.deb ... 2820s Unpacking libimobiledevice-glue-1.0-0 (1.3.1-1) ... 2821s Selecting previously unselected package libusbmuxd-2.0-7:riscv64. 2821s Preparing to unpack .../54-libusbmuxd-2.0-7_2.1.0-1build1_riscv64.deb ... 2821s Unpacking libusbmuxd-2.0-7:riscv64 (2.1.0-1build1) ... 2822s Selecting previously unselected package libimobiledevice-1.0-6:riscv64. 2822s Preparing to unpack .../55-libimobiledevice-1.0-6_1.3.0+git20240701-4build2_riscv64.deb ... 2822s Unpacking libimobiledevice-1.0-6:riscv64 (1.3.0+git20240701-4build2) ... 2823s Selecting previously unselected package libpolkit-gobject-1-dev. 2823s Preparing to unpack .../56-libpolkit-gobject-1-dev_126-2_riscv64.deb ... 2823s Unpacking libpolkit-gobject-1-dev (126-2) ... 2824s Selecting previously unselected package libupower-glib-dev:riscv64. 2824s Preparing to unpack .../57-libupower-glib-dev_1.90.7-1build1_riscv64.deb ... 2824s Unpacking libupower-glib-dev:riscv64 (1.90.7-1build1) ... 2825s Selecting previously unselected package upower. 2825s Preparing to unpack .../58-upower_1.90.7-1build1_riscv64.deb ... 2825s Unpacking upower (1.90.7-1build1) ... 2827s Setting up native-architecture (0.2.6) ... 2827s Setting up libgirepository-2.0-0:riscv64 (2.84.0-1) ... 2827s Setting up libglib2.0-bin (2.84.0-1) ... 2827s Setting up libgomp1:riscv64 (15-20250222-0ubuntu1) ... 2827s Setting up libffi-dev:riscv64 (3.4.7-1) ... 2827s Setting up libpcre2-16-0:riscv64 (10.45-1) ... 2827s Setting up libsysprof-capture-4-dev:riscv64 (48~beta-2) ... 2827s Setting up libpcre2-32-0:riscv64 (10.45-1) ... 2827s Setting up python3-packaging (24.2-1) ... 2833s Setting up libpkgconf3:riscv64 (1.8.1-4) ... 2833s Setting up uuid-dev:riscv64 (2.40.2-14ubuntu1) ... 2833s Setting up libmpc3:riscv64 (1.3.1-1build2) ... 2833s Setting up libsepol-dev:riscv64 (3.7-1) ... 2833s Setting up pkgconf-bin (1.8.1-4) ... 2834s Setting up libubsan1:riscv64 (15-20250222-0ubuntu1) ... 2834s Setting up zlib1g-dev:riscv64 (1:1.3.dfsg+really1.3.1-1ubuntu1) ... 2834s Setting up libpcre2-posix3:riscv64 (10.45-1) ... 2834s Setting up libplist-2.0-4:riscv64 (2.6.0-2build1) ... 2834s Setting up gir1.2-glib-2.0-dev:riscv64 (2.84.0-1) ... 2834s Setting up gir1.2-polkit-1.0 (126-2) ... 2834s Setting up libasan8:riscv64 (15-20250222-0ubuntu1) ... 2834s Setting up libupower-glib3:riscv64 (1.90.7-1build1) ... 2834s Setting up libtsan2:riscv64 (15-20250222-0ubuntu1) ... 2834s Setting up libisl23:riscv64 (0.27-1) ... 2834s Setting up libcc1-0:riscv64 (15-20250222-0ubuntu1) ... 2834s Setting up liblsan0:riscv64 (15-20250222-0ubuntu1) ... 2834s Setting up libitm1:riscv64 (15-20250222-0ubuntu1) ... 2834s Setting up libblkid-dev:riscv64 (2.40.2-14ubuntu1) ... 2834s Setting up libgio-2.0-dev-bin (2.84.0-1) ... 2843s Setting up girepository-tools:riscv64 (2.84.0-1) ... 2843s Setting up libpcre2-dev:riscv64 (10.45-1) ... 2843s Setting up libselinux1-dev:riscv64 (3.7-3ubuntu3) ... 2843s Setting up cpp-14-riscv64-linux-gnu (14.2.0-17ubuntu3) ... 2843s Setting up pkgconf:riscv64 (1.8.1-4) ... 2843s Setting up libimobiledevice-glue-1.0-0 (1.3.1-1) ... 2843s Setting up cpp-14 (14.2.0-17ubuntu3) ... 2843s Setting up gir1.2-upowerglib-1.0:riscv64 (1.90.7-1build1) ... 2843s Setting up libgcc-14-dev:riscv64 (14.2.0-17ubuntu3) ... 2843s Setting up cpp-riscv64-linux-gnu (4:14.2.0-1ubuntu1) ... 2843s Setting up libstdc++-14-dev:riscv64 (14.2.0-17ubuntu3) ... 2843s Setting up libmount-dev:riscv64 (2.40.2-14ubuntu1) ... 2843s Setting up libglib2.0-dev-bin (2.84.0-1) ... 2843s Setting up libgio-2.0-dev:riscv64 (2.84.0-1) ... 2843s Setting up libusbmuxd-2.0-7:riscv64 (2.1.0-1build1) ... 2843s Setting up gcc-14-riscv64-linux-gnu (14.2.0-17ubuntu3) ... 2843s Setting up cpp (4:14.2.0-1ubuntu1) ... 2843s Setting up libimobiledevice-1.0-6:riscv64 (1.3.0+git20240701-4build2) ... 2843s Setting up g++-14-riscv64-linux-gnu (14.2.0-17ubuntu3) ... 2843s Setting up upower (1.90.7-1build1) ... 2856s upower.service is a disabled or a static unit, not starting it. 2856s Setting up gcc-14 (14.2.0-17ubuntu3) ... 2856s Setting up gcc-riscv64-linux-gnu (4:14.2.0-1ubuntu1) ... 2856s Setting up g++-riscv64-linux-gnu (4:14.2.0-1ubuntu1) ... 2856s Setting up g++-14 (14.2.0-17ubuntu3) ... 2856s Setting up gcc (4:14.2.0-1ubuntu1) ... 2857s Setting up g++ (4:14.2.0-1ubuntu1) ... 2857s update-alternatives: using /usr/bin/g++ to provide /usr/bin/c++ (c++) in auto mode 2857s Setting up build-essential (12.10ubuntu1) ... 2857s Processing triggers for dbus (1.16.2-1ubuntu1) ... 2857s Processing triggers for udev (257.3-1ubuntu3) ... 2870s Processing triggers for install-info (7.1.1-1) ... 2875s Processing triggers for libc-bin (2.41-1ubuntu2) ... 2875s Processing triggers for man-db (2.13.0-1) ... 2890s Processing triggers for libglib2.0-0t64:riscv64 (2.84.0-1) ... 2891s No schema files found: doing nothing. 2891s Setting up libglib2.0-dev:riscv64 (2.84.0-1) ... 2891s Setting up libpolkit-gobject-1-dev (126-2) ... 2891s Setting up libupower-glib-dev:riscv64 (1.90.7-1build1) ... 2916s autopkgtest [22:34:02]: test build: [----------------------- 2927s build: OK 2927s run: OK 2929s autopkgtest [22:34:15]: test build: -----------------------] 2932s build PASS 2932s autopkgtest [22:34:18]: test build: - - - - - - - - - - results - - - - - - - - - - 2935s autopkgtest [22:34:21]: @@@@@@@@@@@@@@@@@@@@ summary 2935s installed-tests FAIL non-zero exit status 2 2935s build PASS 2955s nova [W] Using flock in prodstack6-riscv64 2955s Creating nova instance adt-plucky-riscv64-upower-20250315-214526-juju-7f2275-prod-proposed-migration-environment-2-d2bf4afd-269b-4352-b4ae-673c0282f86f from image adt/ubuntu-plucky-riscv64-server-20250315.img (UUID 306bcabb-e549-4f8c-9dd8-573d52716ea6)... 2955s nova [W] Timed out waiting for 8a4adc97-848f-4f09-8185-75f947749f96 to get deleted. 2955s nova [W] Using flock in prodstack6-riscv64 2955s Creating nova instance adt-plucky-riscv64-upower-20250315-214526-juju-7f2275-prod-proposed-migration-environment-2-d2bf4afd-269b-4352-b4ae-673c0282f86f from image adt/ubuntu-plucky-riscv64-server-20250315.img (UUID 306bcabb-e549-4f8c-9dd8-573d52716ea6)... 2955s nova [W] Timed out waiting for f724b9d9-5605-46cc-8e7e-bf2ed0edf525 to get deleted.