org.bluez.Adapter1: StopDiscovery: Error: Operation already in progress

[alzuno]

Hi @wcbonner,

I've been experiencing recurring service interruptions. Over the past month, the system hasn't remained up for more than 24 hours. Previously, it would sometimes run for a month without any issues.

From the logs, I noticed this recurring error:

/org/bluez/hci0: org.bluez.Adapter1: StopDiscovery: Error: Operation already in progress /home/pi/GoveeBTTempLogger/goveebttemplogger.cpp(3918)

After this error appears, the logging stops entirely. The only way to get the system working again is to reboot the Raspberry Pi.

Here a piece of logs from the system:

Nov 14 09:39:41 rasp3 goveebttemplogger[33743]: /org/bluez/hci0: org.bluez.Adapter1: StartDiscovery
Nov 14 09:39:41 rasp3 goveebttemplogger[33743]: /org/bluez/hci0: org.bluez.Adapter1: SetDiscoveryFilter
Nov 14 09:39:41 rasp3 goveebttemplogger[33743]: /org/bluez/hci0: org.freedesktop.DBus.Properties: SetPowered: true
Nov 14 09:39:41 rasp3 goveebttemplogger[33743]: Host Controller Address: B8:27:EB:FF:AD:6A BlueZ Adapter Path: /org/bluez/hci0
Nov 14 09:39:41 rasp3 goveebttemplogger[33743]: Connected to D-Bus as ":1.21"
Nov 14 09:39:41 rasp3 goveebttemplogger[33743]: Reading LastDownload: /var/log/goveebttemplogger/gvh-lastdownload.txt
Nov 14 09:39:40 rasp3 goveebttemplogger[33743]: Reading: /var/log/goveebttemplogger/gvh-A4C13856DF3C-2024-11.txt
Nov 14 09:39:40 rasp3 goveebttemplogger[33743]: Reading: /var/log/goveebttemplogger/gvh-A4C138043FDE-2024-11.txt
Nov 14 09:39:40 rasp3 goveebttemplogger[33743]: Reading: /var/cache/goveebttemplogger/gvh-A4C13856DF3C-cache.txt
Nov 14 09:39:40 rasp3 goveebttemplogger[33743]: Reading: /var/cache/goveebttemplogger/gvh-A4C138043FDE-cache.txt
Nov 14 09:39:40 rasp3 goveebttemplogger[33743]: Reading: /var/log/goveebttemplogger/gvh-thermometer-types.txt
Nov 14 09:39:40 rasp3 goveebttemplogger[33743]: Reading: /var/www/html/goveebttemplogger/gvh-titlemap.txt
Nov 14 09:39:40 rasp3 goveebttemplogger[33743]: GoveeBTTempLogger Version 3.20241023.1 Built on: Nov 13 2024 at 09:24:30 (starting)
Nov 14 09:39:40 rasp3 systemd[1]: Started GoveeBTTempLogger service.
Nov 14 09:39:33 rasp3 systemd[1]: goveebttemplogger.service: Consumed 17min 4.071s CPU time.
Nov 14 09:39:33 rasp3 systemd[1]: Stopped GoveeBTTempLogger service.
Nov 14 09:39:33 rasp3 systemd[1]: goveebttemplogger.service: Succeeded.
Nov 14 09:39:33 rasp3 goveebttemplogger[674]: GoveeBTTempLogger Version 3.20241023.1 Built on: Nov 13 2024 at 09:24:30 (exiting)
Nov 14 09:39:33 rasp3 goveebttemplogger[674]: Writing: /var/log/goveebttemplogger/gvh-thermometer-types.txt
Nov 14 09:39:33 rasp3 goveebttemplogger[674]: /org/bluez/hci0: org.bluez.Adapter1: StopDiscovery: Error: Operation already in progress /home/pi/GoveeBTTempLogger/goveebttemplogger.cpp(3918)
Nov 14 09:39:32 rasp3 systemd[1]: Stopping GoveeBTTempLogger service...
Nov 14 09:39:32 rasp3 goveebttemplogger[674]: ***************** SIGINT: Caught Ctrl-C, finishing loop and quitting. *****************
Nov 14 09:30:56 rasp3 goveebttemplogger[674]: /org/bluez/hci0: org.bluez.Adapter1: StartDiscovery
Nov 14 09:30:56 rasp3 goveebttemplogger[674]: /org/bluez/hci0: org.bluez.Adapter1: SetDiscoveryFilter
Nov 14 09:30:56 rasp3 goveebttemplogger[674]: /org/bluez/hci0: org.bluez.Adapter1: StopDiscovery: Error: Operation already in progress /home/pi/GoveeBTTempLogger/goveebttemplogger.cpp(3918)
Nov 14 09:00:55 rasp3 goveebttemplogger[674]: /org/bluez/hci0: org.bluez.Adapter1: StartDiscovery
Nov 14 09:00:55 rasp3 goveebttemplogger[674]: /org/bluez/hci0: org.bluez.Adapter1: SetDiscoveryFilter
Nov 14 09:00:55 rasp3 goveebttemplogger[674]: /org/bluez/hci0: org.bluez.Adapter1: StopDiscovery: Error: Operation already in progress /home/pi/GoveeBTTempLogger/goveebttemplogger.cpp(3918)
Nov 14 08:30:54 rasp3 goveebttemplogger[674]: /org/bluez/hci0: org.bluez.Adapter1: StartDiscovery
Nov 14 08:30:54 rasp3 goveebttemplogger[674]: /org/bluez/hci0: org.bluez.Adapter1: SetDiscoveryFilter
Nov 14 08:30:54 rasp3 goveebttemplogger[674]: /org/bluez/hci0: org.bluez.Adapter1: StopDiscovery: Error: Operation already in progress /home/pi/GoveeBTTempLogger/goveebttemplogger.cpp(3918)
Nov 14 08:00:53 rasp3 goveebttemplogger[674]: /org/bluez/hci0: org.bluez.Adapter1: StartDiscovery
Nov 14 08:00:53 rasp3 goveebttemplogger[674]: /org/bluez/hci0: org.bluez.Adapter1: SetDiscoveryFilter
Nov 14 08:00:53 rasp3 goveebttemplogger[674]: /org/bluez/hci0: org.bluez.Adapter1: StopDiscovery: Error: Operation already in progress /home/pi/GoveeBTTempLogger/goveebttemplogger.cpp(3918)
Nov 14 07:30:52 rasp3 goveebttemplogger[674]: /org/bluez/hci0: org.bluez.Adapter1: StartDiscovery
Nov 14 07:30:52 rasp3 goveebttemplogger[674]: /org/bluez/hci0: org.bluez.Adapter1: SetDiscoveryFilter
Nov 14 07:30:52 rasp3 goveebttemplogger[674]: /org/bluez/hci0: org.bluez.Adapter1: StopDiscovery: Error: Operation already in progress /home/pi/GoveeBTTempLogger/goveebttemplogger.cpp(3918)
Nov 14 07:00:51 rasp3 goveebttemplogger[674]: /org/bluez/hci0: org.bluez.Adapter1: StartDiscovery
Nov 14 07:00:51 rasp3 goveebttemplogger[674]: /org/bluez/hci0: org.bluez.Adapter1: SetDiscoveryFilter
Nov 14 07:00:51 rasp3 goveebttemplogger[674]: /org/bluez/hci0: org.bluez.Adapter1: StopDiscovery: Error: Operation already in progress /home/pi/GoveeBTTempLogger/goveebttemplogger.cpp(3918)
Nov 14 06:30:50 rasp3 goveebttemplogger[674]: /org/bluez/hci0: org.bluez.Adapter1: StartDiscovery
Nov 14 06:30:50 rasp3 goveebttemplogger[674]: /org/bluez/hci0: org.bluez.Adapter1: SetDiscoveryFilter
Nov 14 06:30:50 rasp3 goveebttemplogger[674]: /org/bluez/hci0: org.bluez.Adapter1: StopDiscovery: Error: Operation already in progress /home/pi/GoveeBTTempLogger/goveebttemplogger.cpp(3918)
Nov 14 06:00:49 rasp3 goveebttemplogger[674]: /org/bluez/hci0: org.bluez.Adapter1: StartDiscovery
Nov 14 06:00:49 rasp3 goveebttemplogger[674]: /org/bluez/hci0: org.bluez.Adapter1: SetDiscoveryFilter
Nov 14 06:00:49 rasp3 goveebttemplogger[674]: /org/bluez/hci0: org.bluez.Adapter1: StopDiscovery: Error: Operation already in progress /home/pi/GoveeBTTempLogger/goveebttemplogger.cpp(3918)
Nov 14 05:30:48 rasp3 goveebttemplogger[674]: /org/bluez/hci0: org.bluez.Adapter1: StartDiscovery
Nov 14 05:30:48 rasp3 goveebttemplogger[674]: /org/bluez/hci0: org.bluez.Adapter1: SetDiscoveryFilter
Nov 14 05:30:48 rasp3 goveebttemplogger[674]: /org/bluez/hci0: org.bluez.Adapter1: StopDiscovery: Error: Operation already in progress /home/pi/GoveeBTTempLogger/goveebttemplogger.cpp(3918)
Nov 14 05:00:47 rasp3 goveebttemplogger[674]: /org/bluez/hci0: org.bluez.Adapter1: StartDiscovery
Nov 14 05:00:47 rasp3 goveebttemplogger[674]: /org/bluez/hci0: org.bluez.Adapter1: SetDiscoveryFilter
Nov 14 05:00:47 rasp3 goveebttemplogger[674]: /org/bluez/hci0: org.bluez.Adapter1: StopDiscovery: Error: Operation already in progress /home/pi/GoveeBTTempLogger/goveebttemplogger.cpp(3918)
Nov 14 04:30:46 rasp3 goveebttemplogger[674]: /org/bluez/hci0: org.bluez.Adapter1: StartDiscovery
Nov 14 04:30:46 rasp3 goveebttemplogger[674]: /org/bluez/hci0: org.bluez.Adapter1: SetDiscoveryFilter
Nov 14 04:30:46 rasp3 goveebttemplogger[674]: /org/bluez/hci0: org.bluez.Adapter1: StopDiscovery: Error: Operation already in progress /home/pi/GoveeBTTempLogger/goveebttemplogger.cpp(3918)
Nov 14 04:00:45 rasp3 goveebttemplogger[674]: /org/bluez/hci0: org.bluez.Adapter1: StartDiscovery
Nov 14 04:00:45 rasp3 goveebttemplogger[674]: /org/bluez/hci0: org.bluez.Adapter1: SetDiscoveryFilter
Nov 14 04:00:45 rasp3 goveebttemplogger[674]: /org/bluez/hci0: org.bluez.Adapter1: StopDiscovery: Error: Operation already in progress /home/pi/GoveeBTTempLogger/goveebttemplogger.cpp(3918)
Nov 14 03:30:44 rasp3 goveebttemplogger[674]: /org/bluez/hci0: org.bluez.Adapter1: StartDiscovery
Nov 14 03:30:44 rasp3 goveebttemplogger[674]: /org/bluez/hci0: org.bluez.Adapter1: SetDiscoveryFilter
Nov 14 03:30:44 rasp3 goveebttemplogger[674]: /org/bluez/hci0: org.bluez.Adapter1: StopDiscovery: Error: Operation already in progress /home/pi/GoveeBTTempLogger/goveebttemplogger.cpp(3918)
Nov 14 03:00:43 rasp3 goveebttemplogger[674]: /org/bluez/hci0: org.bluez.Adapter1: StartDiscovery
Nov 14 03:00:43 rasp3 goveebttemplogger[674]: /org/bluez/hci0: org.bluez.Adapter1: SetDiscoveryFilter
Nov 14 03:00:43 rasp3 goveebttemplogger[674]: /org/bluez/hci0: org.bluez.Adapter1: StopDiscovery: Error: Operation already in progress /home/pi/GoveeBTTempLogger/goveebttemplogger.cpp(3918)
Nov 14 02:30:42 rasp3 goveebttemplogger[674]: /org/bluez/hci0: org.bluez.Adapter1: StartDiscovery
Nov 14 02:30:42 rasp3 goveebttemplogger[674]: /org/bluez/hci0: org.bluez.Adapter1: SetDiscoveryFilter
Nov 14 02:30:42 rasp3 goveebttemplogger[674]: /org/bluez/hci0: org.bluez.Adapter1: StopDiscovery: Error: Operation already in progress /home/pi/GoveeBTTempLogger/goveebttemplogger.cpp(3918)
Nov 14 02:00:41 rasp3 goveebttemplogger[674]: /org/bluez/hci0: org.bluez.Adapter1: StartDiscovery
Nov 14 02:00:41 rasp3 goveebttemplogger[674]: /org/bluez/hci0: org.bluez.Adapter1: SetDiscoveryFilter
Nov 14 02:00:41 rasp3 goveebttemplogger[674]: /org/bluez/hci0: org.bluez.Adapter1: StopDiscovery: Error: Operation already in progress /home/pi/GoveeBTTempLogger/goveebttemplogger.cpp(3918)
Nov 14 01:30:40 rasp3 goveebttemplogger[674]: /org/bluez/hci0: org.bluez.Adapter1: StartDiscovery
Nov 14 01:30:40 rasp3 goveebttemplogger[674]: /org/bluez/hci0: org.bluez.Adapter1: SetDiscoveryFilter
Nov 14 01:30:40 rasp3 goveebttemplogger[674]: /org/bluez/hci0: org.bluez.Adapter1: StopDiscovery: Error: Operation already in progress /home/pi/GoveeBTTempLogger/goveebttemplogger.cpp(3918)
Nov 14 01:00:39 rasp3 goveebttemplogger[674]: /org/bluez/hci0: org.bluez.Adapter1: StartDiscovery
Nov 14 01:00:39 rasp3 goveebttemplogger[674]: /org/bluez/hci0: org.bluez.Adapter1: SetDiscoveryFilter
Nov 14 01:00:39 rasp3 goveebttemplogger[674]: /org/bluez/hci0: org.bluez.Adapter1: StopDiscovery: Error: Operation already in progress /home/pi/GoveeBTTempLogger/goveebttemplogger.cpp(3918)
Nov 14 00:40:01 rasp3 goveebttemplogger[674]: Writing: /var/www/html/goveebttemplogger/gvh-A4C13856DF3C-day.svg With Title: Office (A4:C1:38:56:DF:3C)
Nov 14 00:40:01 rasp3 goveebttemplogger[674]: Writing: /var/www/html/goveebttemplogger/gvh-A4C138043FDE-day.svg With Title: Room (A4:C1:38:04:3F:DE)
Nov 14 00:35:01 rasp3 goveebttemplogger[674]: Writing: /var/www/html/goveebttemplogger/gvh-A4C13856DF3C-week.svg With Title: Office (A4:C1:38:56:DF:3C)
Nov 14 00:35:01 rasp3 goveebttemplogger[674]: Writing: /var/www/html/goveebttemplogger/gvh-A4C13856DF3C-day.svg With Title: Office (A4:C1:38:56:DF:3C)
Nov 14 00:35:01 rasp3 goveebttemplogger[674]: Writing: /var/www/html/goveebttemplogger/gvh-A4C138043FDE-week.svg With Title: Room (A4:C1:38:04:3F:DE)
Nov 14 00:35:01 rasp3 goveebttemplogger[674]: Writing: /var/www/html/goveebttemplogger/gvh-A4C138043FDE-day.svg With Title: Room (A4:C1:38:04:3F:DE)
Nov 14 00:30:39 rasp3 goveebttemplogger[674]: /org/bluez/hci0: org.bluez.Adapter1: StartDiscovery
Nov 14 00:30:39 rasp3 goveebttemplogger[674]: /org/bluez/hci0: org.bluez.Adapter1: SetDiscoveryFilter
Nov 14 00:30:39 rasp3 goveebttemplogger[674]: /org/bluez/hci0: org.bluez.Adapter1: StopDiscovery: Error: Operation already in progress /home/pi/GoveeBTTempLogger/goveebttemplogger.cpp(3918)
Nov 14 00:30:01 rasp3 goveebttemplogger[674]: Writing: /var/www/html/goveebttemplogger/gvh-A4C13856DF3C-day.svg With Title: Office (A4:C1:38:56:DF:3C)
Nov 14 00:30:01 rasp3 goveebttemplogger[674]: Writing: /var/www/html/goveebttemplogger/gvh-A4C138043FDE-day.svg With Title: Room (A4:C1:38:04:3F:DE)
Nov 14 00:25:01 rasp3 goveebttemplogger[674]: Writing: /var/www/html/goveebttemplogger/gvh-A4C13856DF3C-day.svg With Title: Office (A4:C1:38:56:DF:3C)
Nov 14 00:25:01 rasp3 goveebttemplogger[674]: Writing: /var/www/html/goveebttemplogger/gvh-A4C138043FDE-day.svg With Title: Room (A4:C1:38:04:3F:DE)
Nov 14 00:20:01 rasp3 goveebttemplogger[674]: Writing: /var/www/html/goveebttemplogger/gvh-A4C13856DF3C-day.svg With Title: Office (A4:C1:38:56:DF:3C)
Nov 14 00:20:01 rasp3 goveebttemplogger[674]: Writing: /var/www/html/goveebttemplogger/gvh-A4C138043FDE-day.svg With Title: Room (A4:C1:38:04:3F:DE)
Nov 14 00:15:01 rasp3 goveebttemplogger[674]: Writing: /var/www/html/goveebttemplogger/gvh-A4C13856DF3C-day.svg With Title: Office (A4:C1:38:56:DF:3C)
Nov 14 00:15:01 rasp3 goveebttemplogger[674]: Writing: /var/www/html/goveebttemplogger/gvh-A4C138043FDE-day.svg With Title: Room (A4:C1:38:04:3F:DE)
Nov 14 00:10:01 rasp3 goveebttemplogger[674]: Writing: /var/www/html/goveebttemplogger/gvh-A4C13856DF3C-day.svg With Title: Office (A4:C1:38:56:DF:3C)
Nov 14 00:10:01 rasp3 goveebttemplogger[674]: Writing: /var/www/html/goveebttemplogger/gvh-A4C138043FDE-day.svg With Title: Room (A4:C1:38:04:3F:DE)
Nov 14 00:05:01 rasp3 goveebttemplogger[674]: Writing: /var/www/html/goveebttemplogger/gvh-A4C13856DF3C-year.svg With Title: Office (A4:C1:38:56:DF:3C)
Nov 14 00:05:01 rasp3 goveebttemplogger[674]: Writing: /var/www/html/goveebttemplogger/gvh-A4C13856DF3C-month.svg With Title: Office (A4:C1:38:56:DF:3C)
Nov 14 00:05:01 rasp3 goveebttemplogger[674]: Writing: /var/www/html/goveebttemplogger/gvh-A4C13856DF3C-week.svg With Title: Office (A4:C1:38:56:DF:3C)
Nov 14 00:05:01 rasp3 goveebttemplogger[674]: Writing: /var/www/html/goveebttemplogger/gvh-A4C13856DF3C-day.svg With Title: Office (A4:C1:38:56:DF:3C)
Nov 14 00:05:01 rasp3 goveebttemplogger[674]: Writing: /var/www/html/goveebttemplogger/gvh-A4C138043FDE-year.svg With Title: Room (A4:C1:38:04:3F:DE)
Nov 14 00:05:01 rasp3 goveebttemplogger[674]: Writing: /var/www/html/goveebttemplogger/gvh-A4C138043FDE-month.svg With Title: Room (A4:C1:38:04:3F:DE)
Nov 14 00:05:01 rasp3 goveebttemplogger[674]: Writing: /var/www/html/goveebttemplogger/gvh-A4C138043FDE-week.svg With Title: Room (A4:C1:38:04:3F:DE)
Nov 14 00:05:01 rasp3 goveebttemplogger[674]: Writing: /var/www/html/goveebttemplogger/gvh-A4C138043FDE-day.svg With Title: Room (A4:C1:38:04:3F:DE)
Nov 14 00:00:37 rasp3 goveebttemplogger[674]: /org/bluez/hci0: org.bluez.Adapter1: StartDiscovery
Nov 14 00:00:37 rasp3 goveebttemplogger[674]: /org/bluez/hci0: org.bluez.Adapter1: SetDiscoveryFilter
Nov 14 00:00:37 rasp3 goveebttemplogger[674]: /org/bluez/hci0: org.bluez.Adapter1: StopDiscovery: Error: Operation already in progress /home/pi/GoveeBTTempLogger/goveebttemplogger.cpp(3918)

I ran the system on the console and observed the same issue:

[2024-11-15T13:53:55] [A4:C1:38:56:DF:3C] (Temp) 21.9°C (Humidity)  58.2% (Battery)  85% (GVH5075)
[2024-11-15T13:53:55] [A4:C1:38:04:3F:DE] (Temp) 21.2°C (Humidity)  60.8% (Battery)  47% (GVH5075)
[2024-11-15T13:53:57] [A4:C1:38:56:DF:3C] (Temp) 21.9°C (Humidity)  58.2% (Battery)  85% (GVH5075)
[2024-11-15T13:53:57] [A4:C1:38:04:3F:DE] (Temp) 21.2°C (Humidity)  60.8% (Battery)  47% (GVH5075)
[2024-11-15T13:53:59] [A4:C1:38:56:DF:3C] (Temp) 21.9°C (Humidity)  58.2% (Battery)  85% (GVH5075)
[2024-11-15T13:53:59] [A4:C1:38:04:3F:DE] (Temp) 21.2°C (Humidity)  60.8% (Battery)  47% (GVH5075)
[2024-11-15T13:54:11] [A4:C1:38:56:DF:3C] (Temp) 21.9°C (Humidity)  58.2% (Battery)  86% (GVH5075)
[2024-11-15T13:54:11] [A4:C1:38:04:3F:DE] (Temp) 21.1°C (Humidity)  60.8% (Battery)  47% (GVH5075)
[2024-11-15T13:54:14] [A4:C1:38:56:DF:3C] (Temp) 21.9°C (Humidity)  58.2% (Battery)  86% (GVH5075)
[2024-11-15T13:54:14] [A4:C1:38:04:3F:DE] (Temp) 21.2°C (Humidity)  60.7% (Battery)  47% (GVH5075)
[2024-11-15T13:54:17] [A4:C1:38:04:3F:DE] (Temp) 21.1°C (Humidity)  60.8% (Battery)  47% (GVH5075)
[2024-11-15T13:54:18] [A4:C1:38:56:DF:3C] (Temp) 21.9°C (Humidity)  58.2% (Battery)  86% (GVH5075)
[2024-11-15T13:54:22] [A4:C1:38:04:3F:DE] (Temp) 21.1°C (Humidity)  60.8% (Battery)  47% (GVH5075)
[-------------------] [A4:C1:38:04:3F:DE] 00:00:00
[-------------------] [A4:C1:38:56:DF:3C] 00:00:00
[2024-11-15T13:54:26] [A4:C1:38:04:3F:DE] (Temp) 21.1°C (Humidity)  60.8% (Battery)  47% (GVH5075)
[2024-11-15T13:54:29] [A4:C1:38:56:DF:3C] (Temp) 21.9°C (Humidity)  58.2% (Battery)  86% (GVH5075)
[2024-11-15T13:54:32] [A4:C1:38:04:3F:DE] (Temp) 21.2°C (Humidity)  60.8% (Battery)  47% (GVH5075)
[2024-11-15T13:54:33] [A4:C1:38:56:DF:3C] (Temp) 21.9°C (Humidity)  58.2% (Battery)  86% (GVH5075)
[2024-11-15T13:54:35] [A4:C1:38:04:3F:DE] (Temp) 21.2°C (Humidity)  60.8% (Battery)  47% (GVH5075)
[2024-11-15T13:54:40] [A4:C1:38:04:3F:DE] (Temp) 21.1°C (Humidity)  60.8% (Battery)  47% (GVH5075)
[2024-11-15T13:54:43] [A4:C1:38:04:3F:DE] (Temp) 21.2°C (Humidity)  60.8% (Battery)  47% (GVH5075)
[2024-11-15T13:54:45] [A4:C1:38:56:DF:3C] (Temp) 22.0°C (Humidity)  58.2% (Battery)  85% (GVH5075)
[2024-11-15T13:54:45] [A4:C1:38:04:3F:DE] (Temp) 21.2°C (Humidity)  60.8% (Battery)  47% (GVH5075)
[2024-11-15T13:54:53] [A4:C1:38:04:3F:DE] (Temp) 21.1°C (Humidity)  60.8% (Battery)  47% (GVH5075)
[2024-11-15T13:54:58] [A4:C1:38:04:3F:DE] (Temp) 21.2°C (Humidity)  60.8% (Battery)  47% (GVH5075)
[2024-11-15T13:54:59] [A4:C1:38:56:DF:3C] (Temp) 21.9°C (Humidity)  58.2% (Battery)  85% (GVH5075)
[2024-11-15T13:55:00] [A4:C1:38:56:DF:3C] (Temp) 21.9°C (Humidity)  58.2% (Battery)  85% (GVH5075)
[2024-11-15T13:55:00] [A4:C1:38:04:3F:DE] (Temp) 21.1°C (Humidity)  60.8% (Battery)  47% (GVH5075)
[2024-11-15T13:55:01] 300 seconds or more have passed. Writing SVG Files
[2024-11-15T13:55:01] Writing: /var/www/html/goveebttemplogger/gvh-A4C138043FDE-day.svg With Title: Room (A4:C1:38:04:3F:DE)
[2024-11-15T13:55:01] Writing: /var/www/html/goveebttemplogger/gvh-A4C13856DF3C-day.svg With Title: Office (A4:C1:38:56:DF:3C)
[2024-11-15T13:55:06] [A4:C1:38:56:DF:3C] (Temp) 21.9°C (Humidity)  58.2% (Battery)  85% (GVH5075)
[2024-11-15T13:55:18] [A4:C1:38:56:DF:3C] (Temp) 21.9°C (Humidity)  58.2% (Battery)  85% (GVH5075)
[2024-11-15T13:55:18] [A4:C1:38:04:3F:DE] (Temp) 21.1°C (Humidity)  60.8% (Battery)  47% (GVH5075)
[2024-11-15T13:55:23] [A4:C1:38:56:DF:3C] (Temp) 21.9°C (Humidity)  58.2% (Battery)  85% (GVH5075)
[2024-11-15T13:55:23] [A4:C1:38:04:3F:DE] (Temp) 21.2°C (Humidity)  60.8% (Battery)  47% (GVH5075)
[-------------------] [A4:C1:38:04:3F:DE] 00:00:00
[-------------------] [A4:C1:38:56:DF:3C] 00:00:00
[2024-11-15T13:55:25] [A4:C1:38:04:3F:DE] (Temp) 21.2°C (Humidity)  60.8% (Battery)  47% (GVH5075)
[2024-11-15T13:55:26] [A4:C1:38:56:DF:3C] (Temp) 22.0°C (Humidity)  58.2% (Battery)  85% (GVH5075)
[2024-11-15T13:55:28] [A4:C1:38:56:DF:3C] (Temp) 21.9°C (Humidity)  58.2% (Battery)  85% (GVH5075)
[2024-11-15T13:55:37] [A4:C1:38:04:3F:DE] (Temp) 21.2°C (Humidity)  60.8% (Battery)  47% (GVH5075)
[2024-11-15T13:55:41] [A4:C1:38:56:DF:3C] (Temp) 21.9°C (Humidity)  58.2% (Battery)  85% (GVH5075)
[2024-11-15T13:55:45] [A4:C1:38:04:3F:DE] (Temp) 21.1°C (Humidity)  60.8% (Battery)  47% (GVH5075)
[-------------------] [A4:C1:38:04:3F:DE] 00:00:00
[-------------------] [A4:C1:38:56:DF:3C] 00:00:00
[-------------------] [A4:C1:38:04:3F:DE] 00:00:00
[-------------------] [A4:C1:38:56:DF:3C] 00:00:00
[2024-11-15T13:57:35] [A4:C1:38:56:DF:3C] (Temp) 21.9°C (Humidity)  58.2% (Battery)  85% (GVH5075)
[                   ] /org/bluez/hci0: org.bluez.Adapter1: StopDiscovery: Error: Operation already in progress /home/pi/GoveeBTTempLogger/goveebttemplogger.cpp(3918)
[                   ] /org/bluez/hci0: org.bluez.Adapter1: SetDiscoveryFilter
[                   ] /org/bluez/hci0: org.bluez.Adapter1: StartDiscovery
[-------------------] [A4:C1:38:04:3F:DE] 00:00:00
[-------------------] [A4:C1:38:56:DF:3C] 00:00:00
[-------------------] [A4:C1:38:04:3F:DE] 00:00:00
[-------------------] [A4:C1:38:56:DF:3C] 00:00:00
[2024-11-15T14:00:01] 300 seconds or more have passed. Writing SVG Files
[2024-11-15T14:00:01] Writing: /var/www/html/goveebttemplogger/gvh-A4C138043FDE-day.svg With Title: Room (A4:C1:38:04:3F:DE)
[2024-11-15T14:00:01] Writing: /var/www/html/goveebttemplogger/gvh-A4C13856DF3C-day.svg With Title: Office (A4:C1:38:56:DF:3C)
[2024-11-15T14:00:17] [A4:C1:38:04:3F:DE] (Temp) 21.1°C (Humidity)  60.8% (Battery)  47% (GVH5075)
[-------------------] [A4:C1:38:04:3F:DE] 00:00:00
[-------------------] [A4:C1:38:56:DF:3C] 00:00:00
[-------------------] [A4:C1:38:04:3F:DE] 00:00:00
[-------------------] [A4:C1:38:56:DF:3C] 00:00:00
[-------------------] [A4:C1:38:04:3F:DE] 00:00:00
[-------------------] [A4:C1:38:56:DF:3C] 00:00:00

Additional Information:

Distributor ID:	Debian
Description:	Debian GNU/Linux 11 (bullseye)
Release:	11
Codename:	bullseye

Thanks for your help!

[wcbonner]

@alzuno I'd seen some problems like this but was hoping that I'd fixed it.

Are you running on a Raspberry Pi or another platform? (I'm guessing by your machine name in the logs that it's a Raspberry Pi3.)

Is the software running as the user goveebttemplogger instead of root? (one of my recent changes was to avoid running as root)

Are you running anything else that uses Bluetooth? (Keyboard, Mouse, or any other program?)

Am I correct that the Bluetooth appears to not work at all after this problem occurs, and the only way to recover it is to reboot the entire machine?

I've seen this issue and am not sure what's happening. There may be something I'm not cleaning up in the DBus communication that is causing BlueZ to leak memory and stop responding. It seems wrong that a user mode program can crash a piece of system software..

You should be able to revert to the old hardware Bluetooth Hardware Control Interface by adding the --HCI option to the end of the command line, but that probably won't recover the Bluetooth after it's locked up. Using HCI took exclusive control of the Bluetooth interface and has been deprecated for several years.

[wcbonner]

@alzuno Commits I made today c8e2cd1 and then 6b895c5 bring the version up to 3.20241115.1 and I'm hoping will squash the issue you have for good.

I'm going to run this on my primary machines for a couple of days before creating a new release, but I'm interested if this fixes the issue on your platform.

[alzuno]

Hi @wcbonner,

Thanks for your reply, and sorry for the delayed response (I’m on the "other side of the pond").

Are you running on a Raspberry Pi or another platform? (I'm guessing by your machine name in the logs that it's a Raspberry Pi3.)

Yes, it's a Raspberry Pi3:

Hardware	: BCM2835
Revision	: a020d3
Model		: Raspberry Pi 3 Model B Plus Rev 1.3

And here’s the OS information:

Distributor ID:	Debian
Description:	Debian GNU/Linux 11 (bullseye)
Release:	11
Codename:	bullseye

Is the software running as the user goveebttemplogger instead of root? (one of my recent changes was to avoid running as root)

It is running as the goveebttemplogger user. Here's the ps aux output:

goveebt+ 675 0.0 0.6 10480 5628 ? Ss Nov15 0:09 /usr/local/bin/goveebttemplogger --verbose 0 --log /var/log/goveebttemplogger --time 60 --svg /var/www/html/gove

Are you running anything else that uses Bluetooth? (Keyboard, Mouse, or any other program?)

No, it’s a headless setup. Nothing but electricity is connected to the Raspberry Pi, and no other program is using Bluetooth.

Am I correct that the Bluetooth appears to not work at all after this problem occurs, and the only way to recover it is to reboot the entire machine?

Yes, that’s exactly the behavior. The only way to recover is to reboot the machine.

@alzuno Commits I made today c8e2cd1 and then 6b895c5 bring the version up to 3.20241115.1 and I'm hoping will squash the issue you have for good.

I'm going to run this on my primary machines for a couple of days before creating a new release, but I'm interested if this fixes the issue on your platform.

Great! I’m updating to the latest version now and will run it over the weekend. I’ll let you know the results.
Thanks!

[alzuno]

Hi @wcbonner,

I updated the system to 3.20241115.1 version, and unfortunately it seems is not working.
As soon as the service is started, is just /org/bluez/hci0: org.bluez.Adapter1: StartDiscovery and then nothing else is happening.

Here's a journalctl output:

pi@rasp3:~ $ journalctl -r -u goveebttemplogger
-- Journal begins at Thu 2024-07-04 02:14:43 CEST, ends at Sat 2024-11-16 11:53:49 CET. --
Nov 16 11:53:49 rasp3 goveebttemplogger[1478]: /org/bluez/hci0: org.bluez.Adapter1: StartDiscovery
Nov 16 11:53:49 rasp3 goveebttemplogger[1478]: /org/bluez/hci0: org.bluez.Adapter1: SetDiscoveryFilter
Nov 16 11:53:49 rasp3 goveebttemplogger[1478]: /org/bluez/hci0: org.freedesktop.DBus.Properties: SetPowered: true
Nov 16 11:53:49 rasp3 goveebttemplogger[1478]: Host Controller Address: B8:27:EB:FF:AD:6A BlueZ Adapter Path: /org/bluez/h>
Nov 16 11:53:49 rasp3 goveebttemplogger[1478]: D-Bus connection ":1.18" Opened
Nov 16 11:53:49 rasp3 goveebttemplogger[1478]: Reading LastDownload: /var/log/goveebttemplogger/gvh-lastdownload.txt
Nov 16 11:53:48 rasp3 goveebttemplogger[1478]: Reading: /var/log/goveebttemplogger/gvh-A4C13856DF3C-2024-11.txt
Nov 16 11:53:48 rasp3 goveebttemplogger[1478]: Reading: /var/log/goveebttemplogger/gvh-A4C138043FDE-2024-11.txt
Nov 16 11:53:48 rasp3 goveebttemplogger[1478]: Reading: /var/cache/goveebttemplogger/gvh-A4C13856DF3C-cache.txt
Nov 16 11:53:48 rasp3 goveebttemplogger[1478]: Reading: /var/cache/goveebttemplogger/gvh-A4C138043FDE-cache.txt
Nov 16 11:53:48 rasp3 goveebttemplogger[1478]: Reading: /var/log/goveebttemplogger/gvh-thermometer-types.txt
Nov 16 11:53:48 rasp3 goveebttemplogger[1478]: Reading: /var/www/html/goveebttemplogger/gvh-titlemap.txt
Nov 16 11:53:48 rasp3 goveebttemplogger[1478]: GoveeBTTempLogger Version 3.20241115.1 Built on: Nov 16 2024 at 10:20:24 (s>
Nov 16 11:53:47 rasp3 systemd[1]: Started GoveeBTTempLogger service.
Nov 16 11:53:45 rasp3 systemd[1]: goveebttemplogger.service: Consumed 2.769s CPU time.
Nov 16 11:53:45 rasp3 systemd[1]: Stopped GoveeBTTempLogger service.
Nov 16 11:53:45 rasp3 systemd[1]: goveebttemplogger.service: Succeeded.
Nov 16 11:53:45 rasp3 goveebttemplogger[680]: GoveeBTTempLogger Version 3.20241115.1 Built on: Nov 16 2024 at 10:20:24 (ex>
Nov 16 11:53:45 rasp3 goveebttemplogger[680]: Writing: /var/log/goveebttemplogger/gvh-thermometer-types.txt
Nov 16 11:53:45 rasp3 goveebttemplogger[680]: D-Bus connection ":1.14" Closed
Nov 16 11:53:45 rasp3 goveebttemplogger[680]: /org/bluez/hci0: org.bluez.Adapter1: StopDiscovery: Error: Operation already>
Nov 16 11:53:44 rasp3 systemd[1]: Stopping GoveeBTTempLogger service...
Nov 16 11:53:44 rasp3 goveebttemplogger[680]: ***************** SIGINT: Caught Ctrl-C, finishing loop and quitting. ******>
Nov 16 11:29:37 rasp3 goveebttemplogger[680]: /org/bluez/hci0: org.bluez.Adapter1: StartDiscovery
Nov 16 11:29:37 rasp3 goveebttemplogger[680]: /org/bluez/hci0: org.bluez.Adapter1: SetDiscoveryFilter
Nov 16 11:29:37 rasp3 goveebttemplogger[680]: /org/bluez/hci0: org.freedesktop.DBus.Properties: SetPowered: true
Nov 16 11:29:37 rasp3 goveebttemplogger[680]: Host Controller Address: B8:27:EB:FF:AD:6A BlueZ Adapter Path: /org/bluez/hc>
Nov 16 11:29:37 rasp3 goveebttemplogger[680]: D-Bus connection ":1.14" Opened
Nov 16 11:29:37 rasp3 goveebttemplogger[680]: D-Bus connection ":1.12" Closed
Nov 16 11:29:37 rasp3 goveebttemplogger[680]: /org/bluez/hci0: org.bluez.Adapter1: StopDiscovery: Error: Operation already>
Nov 16 11:10:01 rasp3 goveebttemplogger[680]: Writing: /var/www/html/goveebttemplogger/gvh-A4C13856DF3C-week.svg With Titl>
Nov 16 11:10:01 rasp3 goveebttemplogger[680]: Writing: /var/www/html/goveebttemplogger/gvh-A4C13856DF3C-day.svg With Title>
Nov 16 11:10:01 rasp3 goveebttemplogger[680]: Writing: /var/www/html/goveebttemplogger/gvh-A4C138043FDE-week.svg With Titl>
Nov 16 11:10:01 rasp3 goveebttemplogger[680]: Writing: /var/www/html/goveebttemplogger/gvh-A4C138043FDE-day.svg With Title>
-- Boot 83550d0c219841dfb643e11c2caa6fa9 --
Nov 16 11:00:02 rasp3 goveebttemplogger[680]: Writing: /var/www/html/goveebttemplogger/gvh-A4C13856DF3C-day.svg With Title>
Nov 16 11:00:02 rasp3 goveebttemplogger[680]: Writing: /var/www/html/goveebttemplogger/gvh-A4C138043FDE-day.svg With Title>
-- Boot da42c8b1f2c14a3abec5b8d2526e0ba5 --
Nov 16 10:59:38 rasp3 goveebttemplogger[680]: /org/bluez/hci0: org.bluez.Adapter1: StartDiscovery
Nov 16 10:59:37 rasp3 goveebttemplogger[680]: /org/bluez/hci0: org.bluez.Adapter1: SetDiscoveryFilter
Nov 16 10:59:37 rasp3 goveebttemplogger[680]: /org/bluez/hci0: org.freedesktop.DBus.Properties: SetPowered: true
Nov 16 10:59:37 rasp3 goveebttemplogger[680]: Host Controller Address: B8:27:EB:FF:AD:6A BlueZ Adapter Path: /org/bluez/hc>
Nov 16 10:59:37 rasp3 goveebttemplogger[680]: D-Bus connection ":1.12" Opened
Nov 16 10:59:37 rasp3 goveebttemplogger[680]: Reading LastDownload: /var/log/goveebttemplogger/gvh-lastdownload.txt
Nov 16 10:59:37 rasp3 goveebttemplogger[680]: Reading: /var/log/goveebttemplogger/gvh-A4C13856DF3C-2024-11.txt
Nov 16 10:59:36 rasp3 goveebttemplogger[680]: Reading: /var/log/goveebttemplogger/gvh-A4C138043FDE-2024-11.txt
Nov 16 10:59:36 rasp3 goveebttemplogger[680]: Reading: /var/cache/goveebttemplogger/gvh-A4C13856DF3C-cache.txt
Nov 16 10:59:35 rasp3 goveebttemplogger[680]: Reading: /var/cache/goveebttemplogger/gvh-A4C138043FDE-cache.txt
Nov 16 10:59:35 rasp3 goveebttemplogger[680]: Reading: /var/log/goveebttemplogger/gvh-thermometer-types.txt
Nov 16 10:59:35 rasp3 goveebttemplogger[680]: Reading: /var/www/html/goveebttemplogger/gvh-titlemap.txt
Nov 16 10:59:35 rasp3 goveebttemplogger[680]: GoveeBTTempLogger Version 3.20241115.1 Built on: Nov 16 2024 at 10:20:24 (st>
Nov 16 10:59:35 rasp3 systemd[1]: Started GoveeBTTempLogger service.
-- Boot 83550d0c219841dfb643e11c2caa6fa9 --

And here's running on console with verbose 1:

[2024-11-16T12:08:06] GoveeBTTempLogger Version 3.20241115.1 Built on: Nov 16 2024 at 10:20:24
[2024-11-16T12:08:06] Reading: /var/www/html/goveebttemplogger/gvh-titlemap.txt
[2024-11-16T12:08:06] Reading: /var/log/goveebttemplogger/gvh-thermometer-types.txt
[2024-11-16T12:08:06] Reading: /var/cache/goveebttemplogger/gvh-A4C138043FDE-cache.txt
[2024-11-16T12:08:06] Reading: /var/cache/goveebttemplogger/gvh-A4C13856DF3C-cache.txt
[2024-11-16T12:08:07] Reading: /var/log/goveebttemplogger/gvh-A4C138043FDE-2024-11.txt
[2024-11-16T12:08:07] Reading: /var/log/goveebttemplogger/gvh-A4C13856DF3C-2024-11.txt
[2024-11-16T12:08:08] Writing: /var/www/html/goveebttemplogger/gvh-A4C138043FDE-day.svg With Title: Room (A4:C1:38:04:3F:DE)
[2024-11-16T12:08:08] Writing: /var/www/html/goveebttemplogger/gvh-A4C138043FDE-week.svg With Title: Room (A4:C1:38:04:3F:DE)
[2024-11-16T12:08:08] Writing: /var/www/html/goveebttemplogger/gvh-A4C138043FDE-month.svg With Title: Room (A4:C1:38:04:3F:DE)
[2024-11-16T12:08:08] Writing: /var/www/html/goveebttemplogger/gvh-A4C13856DF3C-day.svg With Title: Office (A4:C1:38:56:DF:3C)
[2024-11-16T12:08:08] Writing: /var/www/html/goveebttemplogger/gvh-A4C13856DF3C-week.svg With Title: Office (A4:C1:38:56:DF:3C)
[2024-11-16T12:08:08] Writing: /var/www/html/goveebttemplogger/gvh-A4C13856DF3C-month.svg With Title: Office (A4:C1:38:56:DF:3C)
[2024-11-16T12:08:08] Reading LastDownload: /var/log/goveebttemplogger/gvh-lastdownload.txt
[2024-11-16T12:08:08] D-Bus connection ":1.17" Opened
[                   ] Host Controller Address: B8:27:EB:FF:AD:6A BlueZ Adapter Path: /org/bluez/hci0
[                   ] /org/bluez/hci0: org.freedesktop.DBus.Properties: SetPowered: true
[                   ] /org/bluez/hci0: org.bluez.Adapter1: SetDiscoveryFilter
[                   ] /: org.freedesktop.DBus.ObjectManager: GetManagedObjects
[                   ] /org/bluez/hci0: org.bluez.Adapter1: StartDiscovery
[2024-11-16T12:08:09] 300 seconds or more have passed. Writing SVG Files
[-------------------] [A4:C1:38:04:3F:DE] 00:00:00
[-------------------] [A4:C1:38:56:DF:3C] 00:00:00
[-------------------] [A4:C1:38:04:3F:DE] 00:00:00
[-------------------] [A4:C1:38:56:DF:3C] 00:00:00
[2024-11-16T12:10:01] 300 seconds or more have passed. Writing SVG Files
[-------------------] [A4:C1:38:04:3F:DE] 00:00:00
[-------------------] [A4:C1:38:56:DF:3C] 00:00:00
[-------------------] [A4:C1:38:04:3F:DE] 00:00:00
[-------------------] [A4:C1:38:56:DF:3C] 00:00:00

Please let me know if you need anything else from me.
Thanks

[wcbonner]

@alzuno I'm not sure why it's not working for you.

My test would be to disable the service and reboot the machine. preferably power cycled, but sometimes doing that on a headless machine is a pain.

sudo systemctl stop goveebttemplogger.service
sudo systemctl disable goveebttemplogger.service
sudo systemctl reboot

then at the command line run these commands, replacing "wim" with the name of your local logged in user.

I made a minor change this morning, bumping the version to 3.20241116.0 but it shouldn't have changed the main logic.

pushd ~/GoveeBTTempLogger/ && git pull && popd && cmake -S ~/GoveeBTTempLogger/ -B ~/GoveeBTTempLogger/build && cmake --build ~/GoveeBTTempLogger/build && pushd ~/GoveeBTTempLogger/build && cpack . && popd
mkdir --parents /home/wim/gvh/log/ /home/wim/gvh/svg/ /home/wim/gvh/cache/

Run the program in default DBus mode. If it reports govee devices and temperatures, that's good. If not, I'm interested if you let it run for over half an hour if it closes the DBus connection and opens another with a different name successfully.

/home/wim/GoveeBTTempLogger/build/goveebttemplogger --verbose 1 --log /home/wim/gvh/log/ --time 60 --svg /home/wim/gvh/svg/ --battery 8 --minmax 8 --cache /home/wim/gvh/cache/

If the DBus communication didn't work, try the same by adding the --HCI option to see if it gets data that way.

/home/wim/GoveeBTTempLogger/build/goveebttemplogger --verbose 1 --log /home/wim/gvh/log/ --time 60 --svg /home/wim/gvh/svg/ --battery 8 --minmax 8 --cache /home/wim/gvh/cache/ --HCI

[alzuno]

Hi @wcbonner,
I ran the tests you requested, and here are the results:

1. I disabled the service using the provided commands.
2. I then powered off the Raspberry Pi, physically disconnecting it from the power supply, and plugged it back in.
3. After restarting, I ran the provided commands.
4. The system immediately detected the Govee devices and reported their temperatures without any issues.

pi@rasp3:~ $ pwd
/home/pi
pi@rasp3:~ $ git clone https://github.com/wcbonner/GoveeBTTempLogger.git
Cloning into 'GoveeBTTempLogger'...
remote: Enumerating objects: 2265, done.
remote: Counting objects: 100% (983/983), done.
remote: Compressing objects: 100% (320/320), done.
remote: Total 2265 (delta 711), reused 891 (delta 643), pack-reused 1282 (from 1)
Receiving objects: 100% (2265/2265), 3.73 MiB | 6.74 MiB/s, done.
Resolving deltas: 100% (1264/1264), done.
pi@rasp3:~ $ pushd ~/GoveeBTTempLogger/ && git pull && popd && cmake -S ~/GoveeBTTempLogger/ -B ~/GoveeBTTempLogger/build && cmake --build ~/GoveeBTTempLogger/build && pushd ~/GoveeBTTempLogger/build && cpack . && popd
~/GoveeBTTempLogger ~
hint: Pulling without specifying how to reconcile divergent branches is
hint: discouraged. You can squelch this message by running one of the following
hint: commands sometime before your next pull:
hint: 
hint:   git config pull.rebase false  # merge (the default strategy)
hint:   git config pull.rebase true   # rebase
hint:   git config pull.ff only       # fast-forward only
hint: 
hint: You can replace "git config" with "git config --global" to set a default
hint: preference for all repositories. You can also pass --rebase, --no-rebase,
hint: or --ff-only on the command line to override the configured default per
hint: invocation.
Already up to date.
~
-- The C compiler identification is GNU 10.2.1
-- The CXX compiler identification is GNU 10.2.1
-- Detecting C compiler ABI info
-- Detecting C compiler ABI info - done
-- Check for working C compiler: /usr/bin/cc - skipped
-- Detecting C compile features
-- Detecting C compile features - done
-- Detecting CXX compiler ABI info
-- Detecting CXX compiler ABI info - done
-- Check for working CXX compiler: /usr/bin/c++ - skipped
-- Detecting CXX compile features
-- Detecting CXX compile features - done
-- Found PkgConfig: /usr/bin/pkg-config (found version "0.29.2") 
-- Checking for module 'dbus-1'
--   Found dbus-1, version 1.12.28
-- Components to pack: 
-- Configuring done
-- Generating done
-- Build files have been written to: /home/pi/GoveeBTTempLogger/build
Scanning dependencies of target goveebttemplogger
[ 14%] Building CXX object CMakeFiles/goveebttemplogger.dir/goveebttemplogger.cpp.o
[ 28%] Building C object CMakeFiles/goveebttemplogger.dir/uuid.c.o
[ 42%] Building CXX object CMakeFiles/goveebttemplogger.dir/wimiso8601.cpp.o
[ 57%] Linking CXX executable goveebttemplogger
Setting Raw Priveleges on $<TARGET_FILE:goveebttemplogger>
[ 57%] Built target goveebttemplogger
Scanning dependencies of target gvh-organizelogs
[ 71%] Building CXX object CMakeFiles/gvh-organizelogs.dir/gvh-organizelogs.cpp.o
[ 85%] Building CXX object CMakeFiles/gvh-organizelogs.dir/wimiso8601.cpp.o
[100%] Linking CXX executable gvh-organizelogs
[100%] Built target gvh-organizelogs
~/GoveeBTTempLogger/build ~
CPack: Create package using DEB
CPack: Install projects
CPack: - Run preinstall target for: GoveeBTTempLogger
CPack: - Install project: GoveeBTTempLogger []
CPack: Create package
CPackDeb: - Generating dependency list
CPack: - package: /home/pi/GoveeBTTempLogger/build/goveebttemplogger_3.20241116.0_arm64.deb generated.

pi@rasp3:~ $ /home/pi/GoveeBTTempLogger/build/goveebttemplogger --verbose 1 --log /home/pi/gvh/log/ --time 60 --svg /home/pi/gvh/svg/ --battery 8 --minmax 8 --cache /home/pi/gvh/cache/
[2024-11-16T21:22:06] GoveeBTTempLogger Version 3.20241116.0 Built on: Nov 16 2024 at 21:18:24
[2024-11-16T21:22:06] D-Bus connection ":1.16" Opened
[                   ] Host Controller Address: B8:27:EB:FF:AD:6A BlueZ Adapter Path: /org/bluez/hci0
[                   ] /org/bluez/hci0: org.freedesktop.DBus.Properties: SetPowered: true
[                   ] /org/bluez/hci0: org.bluez.Adapter1: SetDiscoveryFilter
[                   ] /: org.freedesktop.DBus.ObjectManager: GetManagedObjects
[                   ] /org/bluez/hci0: org.bluez.Adapter1: StartDiscovery
[2024-11-16T21:22:06] 300 seconds or more have passed. Writing SVG Files
[2024-11-16T21:22:07] [A4:C1:38:04:3F:DE] (Temp) 21.0°C (Humidity)  60.2% (Battery)  47% (GVH5075)
[2024-11-16T21:22:10] [A4:C1:38:56:DF:3C] (Temp) 20.7°C (Humidity)  56.9% (Battery)  85% (GVH5075)
[2024-11-16T21:22:12] [A4:C1:38:56:DF:3C] (Temp) 20.7°C (Humidity)  56.9% (Battery)  85% (GVH5075)
[2024-11-16T21:22:13] [A4:C1:38:04:3F:DE] (Temp) 21.0°C (Humidity)  60.2% (Battery)  47% (GVH5075)
[2024-11-16T21:22:14] [A4:C1:38:56:DF:3C] (Temp) 20.7°C (Humidity)  56.9% (Battery)  85% (GVH5075)
[2024-11-16T21:22:17] [A4:C1:38:56:DF:3C] (Temp) 20.7°C (Humidity)  56.9% (Battery)  85% (GVH5075)
[2024-11-16T21:22:23] [A4:C1:38:04:3F:DE] (Temp) 21.0°C (Humidity)  60.2% (Battery)  47% (GVH5075)
[2024-11-16T21:22:27] [A4:C1:38:04:3F:DE] (Temp) 21.0°C (Humidity)  60.2% (Battery)  47% (GVH5075)
[2024-11-16T21:22:35] [A4:C1:38:04:3F:DE] (Temp) 21.0°C (Humidity)  60.2% (Battery)  47% (GVH5075)
[2024-11-16T21:22:36] [A4:C1:38:56:DF:3C] (Temp) 20.7°C (Humidity)  56.9% (Battery)  85% (GVH5075)
[2024-11-16T21:22:40] [A4:C1:38:04:3F:DE] (Temp) 21.0°C (Humidity)  60.3% (Battery)  47% (GVH5075)
[2024-11-16T21:22:42] [A4:C1:38:04:3F:DE] (Temp) 21.0°C (Humidity)  60.3% (Battery)  47% (GVH5075)
[2024-11-16T21:22:46] [A4:C1:38:04:3F:DE] (Temp) 21.0°C (Humidity)  60.3% (Battery)  47% (GVH5075)
[2024-11-16T21:22:47] [A4:C1:38:56:DF:3C] (Temp) 20.7°C (Humidity)  56.9% (Battery)  85% (GVH5075)
[2024-11-16T21:22:48] [A4:C1:38:04:3F:DE] (Temp) 21.0°C (Humidity)  60.3% (Battery)  47% (GVH5075)
[2024-11-16T21:22:49] [A4:C1:38:56:DF:3C] (Temp) 20.7°C (Humidity)  56.9% (Battery)  85% (GVH5075)
[2024-11-16T21:22:55] [A4:C1:38:04:3F:DE] (Temp) 21.0°C (Humidity)  60.2% (Battery)  47% (GVH5075)
[2024-11-16T21:22:56] [A4:C1:38:56:DF:3C] (Temp) 20.7°C (Humidity)  57.0% (Battery)  85% (GVH5075)
[2024-11-16T21:22:57] [A4:C1:38:04:3F:DE] (Temp) 21.0°C (Humidity)  60.2% (Battery)  47% (GVH5075)
[2024-11-16T21:22:59] [A4:C1:38:04:3F:DE] (Temp) 21.0°C (Humidity)  60.2% (Battery)  47% (GVH5075)
[2024-11-16T21:23:01] [A4:C1:38:04:3F:DE] (Temp) 21.0°C (Humidity)  60.3% (Battery)  47% (GVH5075)
[2024-11-16T21:23:02] [A4:C1:38:56:DF:3C] (Temp) 20.7°C (Humidity)  57.0% (Battery)  85% (GVH5075)
[2024-11-16T21:23:05] [A4:C1:38:56:DF:3C] (Temp) 20.7°C (Humidity)  56.9% (Battery)  85% (GVH5075)
[-------------------] [A4:C1:38:04:3F:DE] 00:00:00
[-------------------] [A4:C1:38:56:DF:3C] 00:00:00
[2024-11-16T21:23:07] Writing: /home/pi/gvh/cache/gvh-A4C138043FDE-cache.txt
[2024-11-16T21:23:07] Writing: /home/pi/gvh/cache/gvh-A4C13856DF3C-cache.txt
[2024-11-16T21:23:09] [A4:C1:38:56:DF:3C] (Temp) 20.7°C (Humidity)  56.9% (Battery)  85% (GVH5075)
[2024-11-16T21:23:12] [A4:C1:38:04:3F:DE] (Temp) 21.0°C (Humidity)  60.3% (Battery)  47% (GVH5075)
[2024-11-16T21:23:13] [A4:C1:38:56:DF:3C] (Temp) 20.7°C (Humidity)  56.9% (Battery)  85% (GVH5075)
[2024-11-16T21:23:14] [A4:C1:38:04:3F:DE] (Temp) 20.9°C (Humidity)  60.2% (Battery)  47% (GVH5075)
[2024-11-16T21:23:15] [A4:C1:38:56:DF:3C] (Temp) 20.7°C (Humidity)  56.9% (Battery)  85% (GVH5075)
[2024-11-16T21:23:16] [A4:C1:38:04:3F:DE] (Temp) 21.0°C (Humidity)  60.3% (Battery)  47% (GVH5075)
[2024-11-16T21:23:18] [A4:C1:38:56:DF:3C] (Temp) 20.7°C (Humidity)  56.9% (Battery)  85% (GVH5075)
[2024-11-16T21:23:24] [A4:C1:38:04:3F:DE] (Temp) 21.0°C (Humidity)  60.3% (Battery)  47% (GVH5075)
[2024-11-16T21:23:26] [A4:C1:38:56:DF:3C] (Temp) 20.7°C (Humidity)  56.9% (Battery)  85% (GVH5075)
[2024-11-16T21:23:29] [A4:C1:38:04:3F:DE] (Temp) 21.0°C (Humidity)  60.3% (Battery)  47% (GVH5075)
[2024-11-16T21:23:33] [A4:C1:38:56:DF:3C] (Temp) 20.7°C (Humidity)  57.0% (Battery)  85% (GVH5075)
[2024-11-16T21:23:34] [A4:C1:38:04:3F:DE] (Temp) 21.0°C (Humidity)  60.2% (Battery)  47% (GVH5075)
[2024-11-16T21:23:45] [A4:C1:38:56:DF:3C] (Temp) 20.7°C (Humidity)  57.0% (Battery)  85% (GVH5075)
[2024-11-16T21:23:47] [A4:C1:38:04:3F:DE] (Temp) 21.0°C (Humidity)  60.2% (Battery)  47% (GVH5075)
[2024-11-16T21:23:51] [A4:C1:38:04:3F:DE] (Temp) 21.0°C (Humidity)  60.2% (Battery)  47% (GVH5075)
[2024-11-16T21:23:52] [A4:C1:38:04:3F:DE] (Temp) 21.0°C (Humidity)  60.2% (Battery)  47% (GVH5075)
[2024-11-16T21:24:00] [A4:C1:38:04:3F:DE] (Temp) 20.9°C (Humidity)  60.2% (Battery)  47% (GVH5075)
[2024-11-16T21:24:03] [A4:C1:38:04:3F:DE] (Temp) 21.0°C (Humidity)  60.2% (Battery)  47% (GVH5075)
[2024-11-16T21:24:04] [A4:C1:38:56:DF:3C] (Temp) 20.7°C (Humidity)  56.9% (Battery)  85% (GVH5075)
[2024-11-16T21:24:06] [A4:C1:38:56:DF:3C] (Temp) 20.7°C (Humidity)  57.0% (Battery)  85% (GVH5075)
[2024-11-16T21:24:07] [A4:C1:38:04:3F:DE] (Temp) 21.0°C (Humidity)  60.2% (Battery)  47% (GVH5075)
[-------------------] [A4:C1:38:04:3F:DE] 00:00:00
[-------------------] [A4:C1:38:56:DF:3C] 00:00:00
[2024-11-16T21:24:08] [A4:C1:38:56:DF:3C] (Temp) 20.7°C (Humidity)  57.0% (Battery)  85% (GVH5075)
[2024-11-16T21:24:10] [A4:C1:38:04:3F:DE] (Temp) 21.0°C (Humidity)  60.2% (Battery)  47% (GVH5075)
[2024-11-16T21:24:19] [A4:C1:38:04:3F:DE] (Temp) 21.0°C (Humidity)  60.2% (Battery)  47% (GVH5075)
[2024-11-16T21:24:22] [A4:C1:38:04:3F:DE] (Temp) 21.0°C (Humidity)  60.2% (Battery)  47% (GVH5075)
[2024-11-16T21:24:25] [A4:C1:38:56:DF:3C] (Temp) 20.7°C (Humidity)  57.0% (Battery)  85% (GVH5075)
[2024-11-16T21:24:28] [A4:C1:38:04:3F:DE] (Temp) 21.0°C (Humidity)  60.2% (Battery)  47% (GVH5075)
[2024-11-16T21:24:32] [A4:C1:38:04:3F:DE] (Temp) 21.0°C (Humidity)  60.2% (Battery)  47% (GVH5075)
[2024-11-16T21:24:43] [A4:C1:38:04:3F:DE] (Temp) 21.0°C (Humidity)  60.2% (Battery)  47% (GVH5075)
[2024-11-16T21:24:44] [A4:C1:38:56:DF:3C] (Temp) 20.7°C (Humidity)  56.9% (Battery)  85% (GVH5075)
[2024-11-16T21:24:53] [A4:C1:38:04:3F:DE] (Temp) 21.0°C (Humidity)  60.2% (Battery)  47% (GVH5075)
[2024-11-16T21:24:57] [A4:C1:38:04:3F:DE] (Temp) 21.0°C (Humidity)  60.2% (Battery)  47% (GVH5075)
[2024-11-16T21:24:58] [A4:C1:38:56:DF:3C] (Temp) 20.7°C (Humidity)  57.0% (Battery)  85% (GVH5075)
[2024-11-16T21:25:01] 300 seconds or more have passed. Writing SVG Files
[2024-11-16T21:25:01] Writing: /home/pi/gvh/svg/gvh-A4C138043FDE-day.svg With Title: A4C138043FDE (GVH5075)
[2024-11-16T21:25:01] Writing: /home/pi/gvh/svg/gvh-A4C13856DF3C-day.svg With Title: A4C13856DF3C (GVH5075)
[2024-11-16T21:25:01] [A4:C1:38:56:DF:3C] (Temp) 20.7°C (Humidity)  57.0% (Battery)  85% (GVH5075)
[2024-11-16T21:25:02] [A4:C1:38:56:DF:3C] (Temp) 20.7°C (Humidity)  56.9% (Battery)  85% (GVH5075)
[2024-11-16T21:25:03] [A4:C1:38:04:3F:DE] (Temp) 21.0°C (Humidity)  60.2% (Battery)  47% (GVH5075)
[2024-11-16T21:25:06] [A4:C1:38:56:DF:3C] (Temp) 20.7°C (Humidity)  57.0% (Battery)  85% (GVH5075)
[2024-11-16T21:25:07] [A4:C1:38:04:3F:DE] (Temp) 21.0°C (Humidity)  60.2% (Battery)  47% (GVH5075)
[2024-11-16T21:25:08] [A4:C1:38:56:DF:3C] (Temp) 20.7°C (Humidity)  57.0% (Battery)  85% (GVH5075)
[-------------------] [A4:C1:38:04:3F:DE] 00:00:00
[-------------------] [A4:C1:38:56:DF:3C] 00:00:00
[2024-11-16T21:25:10] [A4:C1:38:56:DF:3C] (Temp) 20.7°C (Humidity)  57.0% (Battery)  85% (GVH5075)
[2024-11-16T21:25:11] [A4:C1:38:04:3F:DE] (Temp) 21.0°C (Humidity)  60.2% (Battery)  47% (GVH5075)
[2024-11-16T21:25:16] [A4:C1:38:04:3F:DE] (Temp) 21.0°C (Humidity)  60.2% (Battery)  47% (GVH5075)
[2024-11-16T21:25:19] [A4:C1:38:56:DF:3C] (Temp) 20.7°C (Humidity)  57.0% (Battery)  85% (GVH5075)
[2024-11-16T21:25:24] [A4:C1:38:56:DF:3C] (Temp) 20.7°C (Humidity)  57.0% (Battery)  85% (GVH5075)
[2024-11-16T21:25:33] [A4:C1:38:04:3F:DE] (Temp) 21.0°C (Humidity)  60.2% (Battery)  47% (GVH5075)
[2024-11-16T21:25:39] [A4:C1:38:04:3F:DE] (Temp) 21.0°C (Humidity)  60.2% (Battery)  47% (GVH5075)
[2024-11-16T21:25:46] [A4:C1:38:04:3F:DE] (Temp) 21.0°C (Humidity)  60.2% (Battery)  47% (GVH5075)
[2024-11-16T21:25:49] [A4:C1:38:04:3F:DE] (Temp) 20.9°C (Humidity)  60.2% (Battery)  47% (GVH5075)
[2024-11-16T21:25:51] [A4:C1:38:56:DF:3C] (Temp) 20.7°C (Humidity)  57.0% (Battery)  85% (GVH5075)
[2024-11-16T21:25:58] [A4:C1:38:04:3F:DE] (Temp) 20.9°C (Humidity)  60.2% (Battery)  47% (GVH5075)
[2024-11-16T21:26:03] [A4:C1:38:56:DF:3C] (Temp) 20.7°C (Humidity)  57.0% (Battery)  85% (GVH5075)
[2024-11-16T21:26:05] [A4:C1:38:04:3F:DE] (Temp) 21.0°C (Humidity)  60.2% (Battery)  47% (GVH5075)
[2024-11-16T21:26:08] [A4:C1:38:56:DF:3C] (Temp) 20.7°C (Humidity)  57.0% (Battery)  85% (GVH5075)
[2024-11-16T21:26:08] [A4:C1:38:04:3F:DE] (Temp) 21.0°C (Humidity)  60.2% (Battery)  47% (GVH5075)
[-------------------] [A4:C1:38:04:3F:DE] 00:00:00
[-------------------] [A4:C1:38:56:DF:3C] 00:00:00
[2024-11-16T21:26:10] [A4:C1:38:04:3F:DE] (Temp) 20.9°C (Humidity)  60.2% (Battery)  47% (GVH5075)
[2024-11-16T21:26:11] [A4:C1:38:04:3F:DE] (Temp) 21.0°C (Humidity)  60.3% (Battery)  47% (GVH5075)
[2024-11-16T21:26:12] [A4:C1:38:56:DF:3C] (Temp) 20.7°C (Humidity)  57.0% (Battery)  85% (GVH5075)
[2024-11-16T21:26:13] [A4:C1:38:04:3F:DE] (Temp) 21.0°C (Humidity)  60.3% (Battery)  47% (GVH5075)
^C***************** SIGINT: Caught Ctrl-C, finishing loop and quitting. *****************
[                   ] /org/bluez/hci0: org.bluez.Adapter1: StopDiscovery
[                   ] /org/bluez/hci0: org.bluez.Adapter1: RemoveDevice /org/bluez/hci0/dev_A4_C1_38_56_DF_3C
[                   ] /org/bluez/hci0: org.bluez.Adapter1: RemoveDevice /org/bluez/hci0/dev_A4_C1_38_04_3F_DE
[2024-11-16T21:26:16] D-Bus connection ":1.16" Closed
[-------------------] [A4:C1:38:04:3F:DE] 00:00:00
[-------------------] [A4:C1:38:56:DF:3C] 00:00:00
[-------------------] [A4:C1:38:04:3F:DE] 00:00:00
[-------------------] [A4:C1:38:56:DF:3C] 00:00:00
[2024-11-16T21:26:16] Writing: /home/pi/gvh/log/gvh-thermometer-types.txt
GoveeBTTempLogger Version 3.20241116.0 Built on: Nov 16 2024 at 21:18:24 (exiting)

After this, I updated the system and rebooted it again. It's now been running for approximately 24 hours without any problems.

I'm not sure what was causing the previous issues, but for now, everything seems to be working correctly. I'll continue monitoring and will let you know if the issue reappears.

Thank you so much for your help! Let me know if there’s anything else you’d like me to test.

[alzuno]

Additionally, in the running service logs, I’ve noticed that every 30 minutes the system closes a D-Bus connection and opens a new one with a different code.

Nov 17 16:32:39 rasp3 goveebttemplogger[680]: D-Bus connection ":1.54" Opened
Nov 17 16:32:39 rasp3 goveebttemplogger[680]: D-Bus connection ":1.50" Closed

Here’s a snippet from the logs:

-- Journal begins at Thu 2024-07-04 02:14:43 CEST, ends at Sun 2024-11-17 16:35:02 CET. --
Nov 17 16:35:01 rasp3 goveebttemplogger[680]: Writing: /var/www/html/goveebttemplogger/gvh-A4C13856DF3C-week.svg With Title: Office (A4:C1:38:56:DF:3C)
Nov 17 16:35:01 rasp3 goveebttemplogger[680]: Writing: /var/www/html/goveebttemplogger/gvh-A4C13856DF3C-day.svg With Title: Office (A4:C1:38:56:DF:3C)
Nov 17 16:35:01 rasp3 goveebttemplogger[680]: Writing: /var/www/html/goveebttemplogger/gvh-A4C138043FDE-week.svg With Title: Room (A4:C1:38:04:3F:DE)
Nov 17 16:35:01 rasp3 goveebttemplogger[680]: Writing: /var/www/html/goveebttemplogger/gvh-A4C138043FDE-day.svg With Title: Room (A4:C1:38:04:3F:DE)
Nov 17 16:32:39 rasp3 goveebttemplogger[680]: /org/bluez/hci0: org.bluez.Adapter1: StartDiscovery
Nov 17 16:32:39 rasp3 goveebttemplogger[680]: /org/bluez/hci0: org.bluez.Adapter1: SetDiscoveryFilter
Nov 17 16:32:39 rasp3 goveebttemplogger[680]: /org/bluez/hci0: org.freedesktop.DBus.Properties: SetPowered: true
Nov 17 16:32:39 rasp3 goveebttemplogger[680]: Host Controller Address: B8:27:EB:FF:AD:6A BlueZ Adapter Path: /org/bluez/hci0
Nov 17 16:32:39 rasp3 goveebttemplogger[680]: D-Bus connection ":1.54" Opened
Nov 17 16:32:39 rasp3 goveebttemplogger[680]: D-Bus connection ":1.50" Closed
Nov 17 16:32:39 rasp3 goveebttemplogger[680]: /org/bluez/hci0: org.bluez.Adapter1: RemoveDevice /org/bluez/hci0/dev_A4_C1_38_56_DF_3C
Nov 17 16:32:39 rasp3 goveebttemplogger[680]: /org/bluez/hci0: org.bluez.Adapter1: RemoveDevice /org/bluez/hci0/dev_A4_C1_38_04_3F_DE
Nov 17 16:32:39 rasp3 goveebttemplogger[680]: /org/bluez/hci0: org.bluez.Adapter1: StopDiscovery
Nov 17 16:30:01 rasp3 goveebttemplogger[680]: Writing: /var/www/html/goveebttemplogger/gvh-A4C13856DF3C-day.svg With Title: Office (A4:C1:38:56:DF:3C)
Nov 17 16:30:01 rasp3 goveebttemplogger[680]: Writing: /var/www/html/goveebttemplogger/gvh-A4C138043FDE-day.svg With Title: Room (A4:C1:38:04:3F:DE)
Nov 17 16:25:01 rasp3 goveebttemplogger[680]: Writing: /var/www/html/goveebttemplogger/gvh-A4C13856DF3C-day.svg With Title: Office (A4:C1:38:56:DF:3C)
Nov 17 16:25:01 rasp3 goveebttemplogger[680]: Writing: /var/www/html/goveebttemplogger/gvh-A4C138043FDE-day.svg With Title: Room (A4:C1:38:04:3F:DE)
Nov 17 16:20:01 rasp3 goveebttemplogger[680]: Writing: /var/www/html/goveebttemplogger/gvh-A4C13856DF3C-day.svg With Title: Office (A4:C1:38:56:DF:3C)
Nov 17 16:20:01 rasp3 goveebttemplogger[680]: Writing: /var/www/html/goveebttemplogger/gvh-A4C138043FDE-day.svg With Title: Room (A4:C1:38:04:3F:DE)
Nov 17 16:15:01 rasp3 goveebttemplogger[680]: Writing: /var/www/html/goveebttemplogger/gvh-A4C13856DF3C-day.svg With Title: Office (A4:C1:38:56:DF:3C)
Nov 17 16:15:01 rasp3 goveebttemplogger[680]: Writing: /var/www/html/goveebttemplogger/gvh-A4C138043FDE-day.svg With Title: Room (A4:C1:38:04:3F:DE)
Nov 17 16:10:01 rasp3 goveebttemplogger[680]: Writing: /var/www/html/goveebttemplogger/gvh-A4C13856DF3C-day.svg With Title: Office (A4:C1:38:56:DF:3C)
Nov 17 16:10:01 rasp3 goveebttemplogger[680]: Writing: /var/www/html/goveebttemplogger/gvh-A4C138043FDE-day.svg With Title: Room (A4:C1:38:04:3F:DE)
Nov 17 16:05:01 rasp3 goveebttemplogger[680]: Writing: /var/www/html/goveebttemplogger/gvh-A4C13856DF3C-month.svg With Title: Office (A4:C1:38:56:DF:3C)
Nov 17 16:05:01 rasp3 goveebttemplogger[680]: Writing: /var/www/html/goveebttemplogger/gvh-A4C13856DF3C-week.svg With Title: Office (A4:C1:38:56:DF:3C)
Nov 17 16:05:01 rasp3 goveebttemplogger[680]: Writing: /var/www/html/goveebttemplogger/gvh-A4C13856DF3C-day.svg With Title: Office (A4:C1:38:56:DF:3C)
Nov 17 16:05:01 rasp3 goveebttemplogger[680]: Writing: /var/www/html/goveebttemplogger/gvh-A4C138043FDE-month.svg With Title: Room (A4:C1:38:04:3F:DE)
Nov 17 16:05:01 rasp3 goveebttemplogger[680]: Writing: /var/www/html/goveebttemplogger/gvh-A4C138043FDE-week.svg With Title: Room (A4:C1:38:04:3F:DE)
Nov 17 16:05:01 rasp3 goveebttemplogger[680]: Writing: /var/www/html/goveebttemplogger/gvh-A4C138043FDE-day.svg With Title: Room (A4:C1:38:04:3F:DE)
Nov 17 16:02:40 rasp3 goveebttemplogger[680]: /org/bluez/hci0: org.bluez.Adapter1: StartDiscovery
Nov 17 16:02:39 rasp3 goveebttemplogger[680]: /org/bluez/hci0: org.bluez.Adapter1: SetDiscoveryFilter
Nov 17 16:02:39 rasp3 goveebttemplogger[680]: /org/bluez/hci0: org.freedesktop.DBus.Properties: SetPowered: true
Nov 17 16:02:39 rasp3 goveebttemplogger[680]: Host Controller Address: B8:27:EB:FF:AD:6A BlueZ Adapter Path: /org/bluez/hci0
Nov 17 16:02:39 rasp3 goveebttemplogger[680]: D-Bus connection ":1.50" Opened
Nov 17 16:02:39 rasp3 goveebttemplogger[680]: D-Bus connection ":1.49" Closed
Nov 17 16:02:39 rasp3 goveebttemplogger[680]: /org/bluez/hci0: org.bluez.Adapter1: RemoveDevice /org/bluez/hci0/dev_A4_C1_38_04_3F_DE
Nov 17 16:02:39 rasp3 goveebttemplogger[680]: /org/bluez/hci0: org.bluez.Adapter1: RemoveDevice /org/bluez/hci0/dev_A4_C1_38_56_DF_3C
Nov 17 16:02:38 rasp3 goveebttemplogger[680]: /org/bluez/hci0: org.bluez.Adapter1: StopDiscovery
Nov 17 16:00:01 rasp3 goveebttemplogger[680]: Writing: /var/www/html/goveebttemplogger/gvh-A4C13856DF3C-day.svg With Title: Office (A4:C1:38:56:DF:3C)
Nov 17 16:00:01 rasp3 goveebttemplogger[680]: Writing: /var/www/html/goveebttemplogger/gvh-A4C138043FDE-day.svg With Title: Room (A4:C1:38:04:3F:DE)
Nov 17 15:55:01 rasp3 goveebttemplogger[680]: Writing: /var/www/html/goveebttemplogger/gvh-A4C13856DF3C-day.svg With Title: Office (A4:C1:38:56:DF:3C)
Nov 17 15:55:01 rasp3 goveebttemplogger[680]: Writing: /var/www/html/goveebttemplogger/gvh-A4C138043FDE-day.svg With Title: Room (A4:C1:38:04:3F:DE)
Nov 17 15:50:01 rasp3 goveebttemplogger[680]: Writing: /var/www/html/goveebttemplogger/gvh-A4C13856DF3C-day.svg With Title: Office (A4:C1:38:56:DF:3C)
Nov 17 15:50:01 rasp3 goveebttemplogger[680]: Writing: /var/www/html/goveebttemplogger/gvh-A4C138043FDE-day.svg With Title: Room (A4:C1:38:04:3F:DE)
Nov 17 15:45:01 rasp3 goveebttemplogger[680]: Writing: /var/www/html/goveebttemplogger/gvh-A4C13856DF3C-day.svg With Title: Office (A4:C1:38:56:DF:3C)
Nov 17 15:45:01 rasp3 goveebttemplogger[680]: Writing: /var/www/html/goveebttemplogger/gvh-A4C138043FDE-day.svg With Title: Room (A4:C1:38:04:3F:DE)
Nov 17 15:42:12 rasp3 goveebttemplogger[680]: Writing: /var/cache/goveebttemplogger/gvh-A4C138043FDE-cache.txt
Nov 17 15:41:11 rasp3 goveebttemplogger[680]: Writing: /var/cache/goveebttemplogger/gvh-A4C13856DF3C-cache.txt
Nov 17 15:40:03 rasp3 goveebttemplogger[680]: Writing: /var/www/html/goveebttemplogger/gvh-A4C13856DF3C-day.svg With Title: Office (A4:C1:38:56:DF:3C)
Nov 17 15:40:01 rasp3 goveebttemplogger[680]: Writing: /var/www/html/goveebttemplogger/gvh-A4C138043FDE-day.svg With Title: Room (A4:C1:38:04:3F:DE)
Nov 17 15:35:01 rasp3 goveebttemplogger[680]: Writing: /var/www/html/goveebttemplogger/gvh-A4C13856DF3C-week.svg With Title: Office (A4:C1:38:56:DF:3C)
Nov 17 15:35:01 rasp3 goveebttemplogger[680]: Writing: /var/www/html/goveebttemplogger/gvh-A4C13856DF3C-day.svg With Title: Office (A4:C1:38:56:DF:3C)
Nov 17 15:35:01 rasp3 goveebttemplogger[680]: Writing: /var/www/html/goveebttemplogger/gvh-A4C138043FDE-week.svg With Title: Room (A4:C1:38:04:3F:DE)
Nov 17 15:35:01 rasp3 goveebttemplogger[680]: Writing: /var/www/html/goveebttemplogger/gvh-A4C138043FDE-day.svg With Title: Room (A4:C1:38:04:3F:DE)
Nov 17 15:32:36 rasp3 goveebttemplogger[680]: /org/bluez/hci0: org.bluez.Adapter1: StartDiscovery
Nov 17 15:32:36 rasp3 goveebttemplogger[680]: /org/bluez/hci0: org.bluez.Adapter1: SetDiscoveryFilter
Nov 17 15:32:36 rasp3 goveebttemplogger[680]: /org/bluez/hci0: org.freedesktop.DBus.Properties: SetPowered: true
Nov 17 15:32:36 rasp3 goveebttemplogger[680]: Host Controller Address: B8:27:EB:FF:AD:6A BlueZ Adapter Path: /org/bluez/hci0
Nov 17 15:32:36 rasp3 goveebttemplogger[680]: D-Bus connection ":1.49" Opened
Nov 17 15:32:36 rasp3 goveebttemplogger[680]: D-Bus connection ":1.48" Closed
Nov 17 15:32:36 rasp3 goveebttemplogger[680]: /org/bluez/hci0: org.bluez.Adapter1: RemoveDevice /org/bluez/hci0/dev_A4_C1_38_56_DF_3C
Nov 17 15:32:36 rasp3 goveebttemplogger[680]: /org/bluez/hci0: org.bluez.Adapter1: RemoveDevice /org/bluez/hci0/dev_A4_C1_38_04_3F_DE
Nov 17 15:32:36 rasp3 goveebttemplogger[680]: /org/bluez/hci0: org.bluez.Adapter1: StopDiscovery
Nov 17 15:30:01 rasp3 goveebttemplogger[680]: Writing: /var/www/html/goveebttemplogger/gvh-A4C13856DF3C-day.svg With Title: Office (A4:C1:38:56:DF:3C)
Nov 17 15:30:01 rasp3 goveebttemplogger[680]: Writing: /var/www/html/goveebttemplogger/gvh-A4C138043FDE-day.svg With Title: Room (A4:C1:38:04:3F:DE)
Nov 17 15:25:01 rasp3 goveebttemplogger[680]: Writing: /var/www/html/goveebttemplogger/gvh-A4C13856DF3C-day.svg With Title: Office (A4:C1:38:56:DF:3C)
Nov 17 15:25:01 rasp3 goveebttemplogger[680]: Writing: /var/www/html/goveebttemplogger/gvh-A4C138043FDE-day.svg With Title: Room (A4:C1:38:04:3F:DE)
Nov 17 15:20:01 rasp3 goveebttemplogger[680]: Writing: /var/www/html/goveebttemplogger/gvh-A4C13856DF3C-day.svg With Title: Office (A4:C1:38:56:DF:3C)
Nov 17 15:20:01 rasp3 goveebttemplogger[680]: Writing: /var/www/html/goveebttemplogger/gvh-A4C138043FDE-day.svg With Title: Room (A4:C1:38:04:3F:DE)
Nov 17 15:15:01 rasp3 goveebttemplogger[680]: Writing: /var/www/html/goveebttemplogger/gvh-A4C13856DF3C-day.svg With Title: Office (A4:C1:38:56:DF:3C)
Nov 17 15:15:01 rasp3 goveebttemplogger[680]: Writing: /var/www/html/goveebttemplogger/gvh-A4C138043FDE-day.svg With Title: Room (A4:C1:38:04:3F:DE)
Nov 17 15:10:01 rasp3 goveebttemplogger[680]: Writing: /var/www/html/goveebttemplogger/gvh-A4C13856DF3C-day.svg With Title: Office (A4:C1:38:56:DF:3C)
Nov 17 15:10:01 rasp3 goveebttemplogger[680]: Writing: /var/www/html/goveebttemplogger/gvh-A4C138043FDE-day.svg With Title: Room (A4:C1:38:04:3F:DE)
Nov 17 15:05:01 rasp3 goveebttemplogger[680]: Writing: /var/www/html/goveebttemplogger/gvh-A4C13856DF3C-week.svg With Title: Office (A4:C1:38:56:DF:3C)
Nov 17 15:05:01 rasp3 goveebttemplogger[680]: Writing: /var/www/html/goveebttemplogger/gvh-A4C13856DF3C-day.svg With Title: Office (A4:C1:38:56:DF:3C)
Nov 17 15:05:01 rasp3 goveebttemplogger[680]: Writing: /var/www/html/goveebttemplogger/gvh-A4C138043FDE-week.svg With Title: Room (A4:C1:38:04:3F:DE)
Nov 17 15:05:01 rasp3 goveebttemplogger[680]: Writing: /var/www/html/goveebttemplogger/gvh-A4C138043FDE-day.svg With Title: Room (A4:C1:38:04:3F:DE)
Nov 17 15:02:36 rasp3 goveebttemplogger[680]: /org/bluez/hci0: org.bluez.Adapter1: StartDiscovery
Nov 17 15:02:36 rasp3 goveebttemplogger[680]: /org/bluez/hci0: org.bluez.Adapter1: SetDiscoveryFilter
Nov 17 15:02:36 rasp3 goveebttemplogger[680]: /org/bluez/hci0: org.freedesktop.DBus.Properties: SetPowered: true
Nov 17 15:02:36 rasp3 goveebttemplogger[680]: Host Controller Address: B8:27:EB:FF:AD:6A BlueZ Adapter Path: /org/bluez/hci0
Nov 17 15:02:36 rasp3 goveebttemplogger[680]: D-Bus connection ":1.48" Opened
Nov 17 15:02:36 rasp3 goveebttemplogger[680]: D-Bus connection ":1.47" Closed
Nov 17 15:02:36 rasp3 goveebttemplogger[680]: /org/bluez/hci0: org.bluez.Adapter1: RemoveDevice /org/bluez/hci0/dev_A4_C1_38_56_DF_3C
Nov 17 15:02:36 rasp3 goveebttemplogger[680]: /org/bluez/hci0: org.bluez.Adapter1: RemoveDevice /org/bluez/hci0/dev_A4_C1_38_04_3F_DE
Nov 17 15:02:36 rasp3 goveebttemplogger[680]: /org/bluez/hci0: org.bluez.Adapter1: StopDiscovery
Nov 17 15:00:01 rasp3 goveebttemplogger[680]: Writing: /var/www/html/goveebttemplogger/gvh-A4C13856DF3C-day.svg With Title: Office (A4:C1:38:56:DF:3C)

[wcbonner]

After this, I updated the system and rebooted it again. It's now been running for approximately 24 hours without any problems.

I'm not sure what was causing the previous issues, but for now, everything seems to be working correctly. I'll continue monitoring and will let you know if the issue reappears.

Thank you so much for your help! Let me know if there’s anything else you’d like me to test.

@alzuno Glad to know that it seems to be working properly now. I still don't know what could have been causing the problems you saw. The changes I made should make things more robust long term.

I imaged a Raspberry Pi Zero W Rev 1.1 I had sitting around yesterday to make sure the code still worked on a 32 bit platform and everything worked as expected. I wasn't sure if you were running the 32 bit or 64 bit OS on your platform and wanted to eliminate that is a reason for the code to fail.

I'm going to close this issue. If it recurs, please reopen it instead of creating a new one.

[alzuno]

Hi @wcbonner,

This morning, I noticed the system exhibiting the same behavior as before.

While it appears to be running longer than it did previously, it’s still encountering the Error: Operation already in progress.

-- Journal begins at Thu 2024-07-04 02:14:43 CEST, ends at Mon 2024-11-18 14:55:02 CET. --
Nov 18 14:38:45 rasp3 goveebttemplogger[678]: /org/bluez/hci0: org.bluez.Adapter1: StartDiscovery
Nov 18 14:38:45 rasp3 goveebttemplogger[678]: /org/bluez/hci0: org.bluez.Adapter1: SetDiscoveryFilter
Nov 18 14:38:45 rasp3 goveebttemplogger[678]: /org/bluez/hci0: org.freedesktop.DBus.Properties: SetPowered: true
Nov 18 14:38:45 rasp3 goveebttemplogger[678]: Host Controller Address: B8:27:EB:FF:AD:6A BlueZ Adapter Path: /org/bluez/hci0
Nov 18 14:38:45 rasp3 goveebttemplogger[678]: D-Bus connection ":1.17" Opened
Nov 18 14:38:45 rasp3 goveebttemplogger[678]: D-Bus connection ":1.12" Closed
Nov 18 14:38:45 rasp3 goveebttemplogger[678]: /org/bluez/hci0: org.bluez.Adapter1: StopDiscovery: Error: Operation already in progress /home/pi/GoveeBTTempLogger/goveebttemplo>
Nov 18 14:15:01 rasp3 goveebttemplogger[678]: Writing: /var/www/html/goveebttemplogger/gvh-A4C13856DF3C-day.svg With Title: Office (A4:C1:38:56:DF:3C)
Nov 18 14:15:01 rasp3 goveebttemplogger[678]: Writing: /var/www/html/goveebttemplogger/gvh-A4C138043FDE-month.svg With Title: Room (A4:C1:38:04:3F:DE)
Nov 18 14:15:01 rasp3 goveebttemplogger[678]: Writing: /var/www/html/goveebttemplogger/gvh-A4C138043FDE-week.svg With Title: Room (A4:C1:38:04:3F:DE)
Nov 18 14:15:01 rasp3 goveebttemplogger[678]: Writing: /var/www/html/goveebttemplogger/gvh-A4C138043FDE-day.svg With Title: Room (A4:C1:38:04:3F:DE)
Nov 18 14:10:47 rasp3 goveebttemplogger[678]: Writing: /var/cache/goveebttemplogger/gvh-A4C138043FDE-cache.txt
Nov 18 14:10:01 rasp3 goveebttemplogger[678]: Writing: /var/www/html/goveebttemplogger/gvh-A4C13856DF3C-month.svg With Title: Office (A4:C1:38:56:DF:3C)
Nov 18 14:10:01 rasp3 goveebttemplogger[678]: Writing: /var/www/html/goveebttemplogger/gvh-A4C13856DF3C-week.svg With Title: Office (A4:C1:38:56:DF:3C)
Nov 18 14:10:01 rasp3 goveebttemplogger[678]: Writing: /var/www/html/goveebttemplogger/gvh-A4C13856DF3C-day.svg With Title: Office (A4:C1:38:56:DF:3C)
Nov 18 14:09:46 rasp3 goveebttemplogger[678]: Writing: /var/cache/goveebttemplogger/gvh-A4C13856DF3C-cache.txt
Nov 18 14:08:45 rasp3 goveebttemplogger[678]: /org/bluez/hci0: org.bluez.Adapter1: StartDiscovery
Nov 18 14:08:45 rasp3 goveebttemplogger[678]: /org/bluez/hci0: org.bluez.Adapter1: SetDiscoveryFilter
Nov 18 14:08:45 rasp3 goveebttemplogger[678]: /org/bluez/hci0: org.freedesktop.DBus.Properties: SetPowered: true
Nov 18 14:08:45 rasp3 goveebttemplogger[678]: Host Controller Address: B8:27:EB:FF:AD:6A BlueZ Adapter Path: /org/bluez/hci0
Nov 18 14:08:45 rasp3 goveebttemplogger[678]: D-Bus connection ":1.12" Opened
Nov 18 14:08:45 rasp3 goveebttemplogger[678]: Reading LastDownload: /var/log/goveebttemplogger/gvh-lastdownload.txt
Nov 18 14:08:44 rasp3 goveebttemplogger[678]: Reading: /var/log/goveebttemplogger/gvh-A4C13856DF3C-2024-11.txt
Nov 18 14:08:43 rasp3 goveebttemplogger[678]: Reading: /var/log/goveebttemplogger/gvh-A4C138043FDE-2024-11.txt
Nov 18 14:08:43 rasp3 goveebttemplogger[678]: Reading: /var/cache/goveebttemplogger/gvh-A4C13856DF3C-cache.txt
Nov 18 14:08:43 rasp3 goveebttemplogger[678]: Reading: /var/cache/goveebttemplogger/gvh-A4C138043FDE-cache.txt
Nov 18 14:08:42 rasp3 goveebttemplogger[678]: Reading: /var/log/goveebttemplogger/gvh-thermometer-types.txt
Nov 18 14:08:42 rasp3 goveebttemplogger[678]: Reading: /var/www/html/goveebttemplogger/gvh-titlemap.txt
Nov 18 14:08:42 rasp3 goveebttemplogger[678]: GoveeBTTempLogger Version 3.20241116.0 Built on: Nov 16 2024 at 21:18:24 (starting)
Nov 18 14:08:42 rasp3 systemd[1]: Started GoveeBTTempLogger service.
-- Boot 1bb0052e0a74460a8032306bcc2d89c3 --
Nov 18 09:56:09 rasp3 systemd[1]: goveebttemplogger.service: Consumed 1min 4.351s CPU time.
Nov 18 09:56:09 rasp3 systemd[1]: Stopped GoveeBTTempLogger service.
Nov 18 09:56:09 rasp3 systemd[1]: goveebttemplogger.service: Succeeded.
Nov 18 09:56:09 rasp3 goveebttemplogger[673]: GoveeBTTempLogger Version 3.20241116.0 Built on: Nov 16 2024 at 21:18:24 (exiting)
Nov 18 09:56:09 rasp3 goveebttemplogger[673]: Writing: /var/log/goveebttemplogger/gvh-thermometer-types.txt
Nov 18 09:56:09 rasp3 goveebttemplogger[673]: D-Bus connection ":1.14" Closed
Nov 18 09:56:09 rasp3 goveebttemplogger[673]: /org/bluez/hci0: org.bluez.Adapter1: RemoveDevice /org/bluez/hci0/dev_A4_C1_38_56_DF_3C
Nov 18 09:56:09 rasp3 goveebttemplogger[673]: /org/bluez/hci0: org.bluez.Adapter1: RemoveDevice /org/bluez/hci0/dev_A4_C1_38_04_3F_DE
Nov 18 09:56:09 rasp3 goveebttemplogger[673]: /org/bluez/hci0: org.bluez.Adapter1: StopDiscovery
Nov 18 09:56:09 rasp3 systemd[1]: Stopping GoveeBTTempLogger service...
Nov 18 09:56:09 rasp3 goveebttemplogger[673]: ***************** SIGINT: Caught Ctrl-C, finishing loop and quitting. *****************
Nov 18 09:55:02 rasp3 goveebttemplogger[673]: Writing: /var/www/html/goveebttemplogger/gvh-A4C13856DF3C-day.svg With Title: Office (A4:C1:38:56:DF:3C)
Nov 18 09:55:01 rasp3 goveebttemplogger[673]: Writing: /var/www/html/goveebttemplogger/gvh-A4C138043FDE-day.svg With Title: Room (A4:C1:38:04:3F:DE)
Nov 18 09:51:05 rasp3 goveebttemplogger[673]: /org/bluez/hci0: org.bluez.Adapter1: StartDiscovery
Nov 18 09:51:04 rasp3 goveebttemplogger[673]: /org/bluez/hci0: org.bluez.Adapter1: SetDiscoveryFilter
Nov 18 09:51:04 rasp3 goveebttemplogger[673]: /org/bluez/hci0: org.freedesktop.DBus.Properties: SetPowered: true
Nov 18 09:51:04 rasp3 goveebttemplogger[673]: Host Controller Address: B8:27:EB:FF:AD:6A BlueZ Adapter Path: /org/bluez/hci0
Nov 18 09:51:04 rasp3 goveebttemplogger[673]: D-Bus connection ":1.14" Opened
Nov 18 09:51:04 rasp3 goveebttemplogger[673]: D-Bus connection ":1.12" Closed
Nov 18 09:51:04 rasp3 goveebttemplogger[673]: /org/bluez/hci0: org.bluez.Adapter1: RemoveDevice /org/bluez/hci0/dev_A4_C1_38_56_DF_3C
Nov 18 09:51:04 rasp3 goveebttemplogger[673]: /org/bluez/hci0: org.bluez.Adapter1: RemoveDevice /org/bluez/hci0/dev_A4_C1_38_04_3F_DE
Nov 18 09:51:04 rasp3 goveebttemplogger[673]: /org/bluez/hci0: org.bluez.Adapter1: StopDiscovery
Nov 18 09:50:01 rasp3 goveebttemplogger[673]: Writing: /var/www/html/goveebttemplogger/gvh-A4C13856DF3C-day.svg With Title: Office (A4:C1:38:56:DF:3C)
Nov 18 09:50:01 rasp3 goveebttemplogger[673]: Writing: /var/www/html/goveebttemplogger/gvh-A4C138043FDE-day.svg With Title: Room (A4:C1:38:04:3F:DE)
Nov 18 09:48:33 rasp3 goveebttemplogger[673]: Writing: /var/cache/goveebttemplogger/gvh-A4C13856DF3C-cache.txt
Nov 18 09:48:33 rasp3 goveebttemplogger[673]: Writing: /var/cache/goveebttemplogger/gvh-A4C138043FDE-cache.txt
Nov 18 09:45:02 rasp3 goveebttemplogger[673]: Writing: /var/www/html/goveebttemplogger/gvh-A4C13856DF3C-week.svg With Title: Office (A4:C1:38:56:DF:3C)
Nov 18 09:45:02 rasp3 goveebttemplogger[673]: Writing: /var/www/html/goveebttemplogger/gvh-A4C13856DF3C-day.svg With Title: Office (A4:C1:38:56:DF:3C)
Nov 18 09:45:01 rasp3 goveebttemplogger[673]: Writing: /var/www/html/goveebttemplogger/gvh-A4C138043FDE-week.svg With Title: Room (A4:C1:38:04:3F:DE)
Nov 18 09:45:01 rasp3 goveebttemplogger[673]: Writing: /var/www/html/goveebttemplogger/gvh-A4C138043FDE-day.svg With Title: Room (A4:C1:38:04:3F:DE)
Nov 18 09:44:29 rasp3 goveebttemplogger[673]: Writing: /var/www/html/goveebttemplogger/gvh-A4C13856DF3C-week.svg With Title: Office (A4:C1:38:56:DF:3C)
Nov 18 09:44:29 rasp3 goveebttemplogger[673]: Writing: /var/www/html/goveebttemplogger/gvh-A4C13856DF3C-day.svg With Title: Office (A4:C1:38:56:DF:3C)
Nov 18 09:21:04 rasp3 goveebttemplogger[673]: /org/bluez/hci0: org.bluez.Adapter1: StartDiscovery
Nov 18 09:21:04 rasp3 goveebttemplogger[673]: /org/bluez/hci0: org.bluez.Adapter1: SetDiscoveryFilter
Nov 18 09:21:04 rasp3 goveebttemplogger[673]: /org/bluez/hci0: org.freedesktop.DBus.Properties: SetPowered: true
Nov 18 09:21:04 rasp3 goveebttemplogger[673]: Host Controller Address: B8:27:EB:FF:AD:6A BlueZ Adapter Path: /org/bluez/hci0
Nov 18 09:21:04 rasp3 goveebttemplogger[673]: D-Bus connection ":1.12" Opened
Nov 18 09:21:04 rasp3 goveebttemplogger[673]: Reading LastDownload: /var/log/goveebttemplogger/gvh-lastdownload.txt
Nov 18 09:21:03 rasp3 goveebttemplogger[673]: Reading: /var/log/goveebttemplogger/gvh-A4C13856DF3C-2024-11.txt
Nov 18 09:21:02 rasp3 goveebttemplogger[673]: Reading: /var/log/goveebttemplogger/gvh-A4C138043FDE-2024-11.txt
Nov 18 09:21:02 rasp3 goveebttemplogger[673]: Reading: /var/cache/goveebttemplogger/gvh-A4C13856DF3C-cache.txt
Nov 18 09:21:01 rasp3 goveebttemplogger[673]: Reading: /var/cache/goveebttemplogger/gvh-A4C138043FDE-cache.txt
Nov 18 09:21:01 rasp3 goveebttemplogger[673]: Reading: /var/log/goveebttemplogger/gvh-thermometer-types.txt
Nov 18 09:21:01 rasp3 goveebttemplogger[673]: Reading: /var/www/html/goveebttemplogger/gvh-titlemap.txt
Nov 18 09:21:01 rasp3 goveebttemplogger[673]: GoveeBTTempLogger Version 3.20241116.0 Built on: Nov 16 2024 at 21:18:24 (starting)
Nov 18 09:21:01 rasp3 systemd[1]: Started GoveeBTTempLogger service.
-- Boot 31c0639d345b401ba7a4b7372ca4802d --
Nov 18 09:16:30 rasp3 goveebttemplogger[677]: /org/bluez/hci0: org.bluez.Adapter1: StartDiscovery
Nov 18 09:16:30 rasp3 goveebttemplogger[677]: /org/bluez/hci0: org.bluez.Adapter1: SetDiscoveryFilter
Nov 18 09:16:30 rasp3 goveebttemplogger[677]: /org/bluez/hci0: org.freedesktop.DBus.Properties: SetPowered: true
Nov 18 09:16:30 rasp3 goveebttemplogger[677]: Host Controller Address: B8:27:EB:FF:AD:6A BlueZ Adapter Path: /org/bluez/hci0
Nov 18 09:16:30 rasp3 goveebttemplogger[677]: D-Bus connection ":1.17" Opened
Nov 18 09:16:30 rasp3 goveebttemplogger[677]: D-Bus connection ":1.12" Closed
Nov 18 09:16:30 rasp3 goveebttemplogger[677]: /org/bluez/hci0: org.bluez.Adapter1: StopDiscovery: Error: Operation already in progress /home/pi/GoveeBTTempLogger/goveebttemplo>
Nov 18 08:50:01 rasp3 goveebttemplogger[677]: Writing: /var/www/html/goveebttemplogger/gvh-A4C13856DF3C-year.svg With Title: Office (A4:C1:38:56:DF:3C)
Nov 18 08:50:01 rasp3 goveebttemplogger[677]: Writing: /var/www/html/goveebttemplogger/gvh-A4C13856DF3C-month.svg With Title: Office (A4:C1:38:56:DF:3C)
Nov 18 08:50:01 rasp3 goveebttemplogger[677]: Writing: /var/www/html/goveebttemplogger/gvh-A4C13856DF3C-week.svg With Title: Office (A4:C1:38:56:DF:3C)
Nov 18 08:50:01 rasp3 goveebttemplogger[677]: Writing: /var/www/html/goveebttemplogger/gvh-A4C13856DF3C-day.svg With Title: Office (A4:C1:38:56:DF:3C)
Nov 18 08:50:01 rasp3 goveebttemplogger[677]: Writing: /var/www/html/goveebttemplogger/gvh-A4C138043FDE-year.svg With Title: Room (A4:C1:38:04:3F:DE)
Nov 18 08:50:01 rasp3 goveebttemplogger[677]: Writing: /var/www/html/goveebttemplogger/gvh-A4C138043FDE-month.svg With Title: Room (A4:C1:38:04:3F:DE)
Nov 18 08:50:01 rasp3 goveebttemplogger[677]: Writing: /var/www/html/goveebttemplogger/gvh-A4C138043FDE-week.svg With Title: Room (A4:C1:38:04:3F:DE)
Nov 18 08:50:01 rasp3 goveebttemplogger[677]: Writing: /var/www/html/goveebttemplogger/gvh-A4C138043FDE-day.svg With Title: Room (A4:C1:38:04:3F:DE)
Nov 18 08:48:33 rasp3 goveebttemplogger[677]: Writing: /var/cache/goveebttemplogger/gvh-A4C13856DF3C-cache.txt
Nov 18 08:48:33 rasp3 goveebttemplogger[677]: Writing: /var/cache/goveebttemplogger/gvh-A4C138043FDE-cache.txt
Nov 18 08:46:31 rasp3 goveebttemplogger[677]: /org/bluez/hci0: org.bluez.Adapter1: StartDiscovery
Nov 18 08:46:30 rasp3 goveebttemplogger[677]: /org/bluez/hci0: org.bluez.Adapter1: SetDiscoveryFilter
Nov 18 08:46:30 rasp3 goveebttemplogger[677]: /org/bluez/hci0: org.freedesktop.DBus.Properties: SetPowered: true
Nov 18 08:46:30 rasp3 goveebttemplogger[677]: Host Controller Address: B8:27:EB:FF:AD:6A BlueZ Adapter Path: /org/bluez/hci0
Nov 18 08:46:30 rasp3 goveebttemplogger[677]: D-Bus connection ":1.12" Opened
Nov 18 08:46:30 rasp3 goveebttemplogger[677]: Reading LastDownload: /var/log/goveebttemplogger/gvh-lastdownload.txt
Nov 18 08:46:29 rasp3 goveebttemplogger[677]: Reading: /var/log/goveebttemplogger/gvh-A4C13856DF3C-2024-11.txt
Nov 18 08:46:28 rasp3 goveebttemplogger[677]: Reading: /var/log/goveebttemplogger/gvh-A4C138043FDE-2024-11.txt
Nov 18 08:46:28 rasp3 goveebttemplogger[677]: Reading: /var/cache/goveebttemplogger/gvh-A4C13856DF3C-cache.txt
Nov 18 08:46:28 rasp3 goveebttemplogger[677]: Reading: /var/cache/goveebttemplogger/gvh-A4C138043FDE-cache.txt
Nov 18 08:46:28 rasp3 goveebttemplogger[677]: Reading: /var/log/goveebttemplogger/gvh-thermometer-types.txt
Nov 18 08:46:28 rasp3 goveebttemplogger[677]: Reading: /var/www/html/goveebttemplogger/gvh-titlemap.txt
Nov 18 08:46:28 rasp3 goveebttemplogger[677]: GoveeBTTempLogger Version 3.20241116.0 Built on: Nov 16 2024 at 21:18:24 (starting)
Nov 18 08:46:28 rasp3 systemd[1]: Started GoveeBTTempLogger service.
-- Boot 226a8378705f4bd3a3cf07131b38b3c5 --
Nov 18 08:46:03 rasp3 systemd[1]: goveebttemplogger.service: Consumed 1h 3min 20.379s CPU time.
Nov 18 08:46:03 rasp3 systemd[1]: Stopped GoveeBTTempLogger service.
Nov 18 08:46:03 rasp3 systemd[1]: goveebttemplogger.service: Succeeded.
Nov 18 08:46:03 rasp3 goveebttemplogger[680]: GoveeBTTempLogger Version 3.20241116.0 Built on: Nov 16 2024 at 21:18:24 (exiting)
Nov 18 08:46:03 rasp3 goveebttemplogger[680]: Writing: /var/log/goveebttemplogger/gvh-thermometer-types.txt
Nov 18 08:46:03 rasp3 goveebttemplogger[680]: D-Bus connection ":1.87" Closed
Nov 18 08:46:03 rasp3 goveebttemplogger[680]: /org/bluez/hci0: org.bluez.Adapter1: StopDiscovery: Error: Operation already in progress /home/pi/GoveeBTTempLogger/goveebttemplo>
Nov 18 08:46:02 rasp3 systemd[1]: Stopping GoveeBTTempLogger service...
Nov 18 08:46:02 rasp3 goveebttemplogger[680]: ***************** SIGINT: Caught Ctrl-C, finishing loop and quitting. *****************
Nov 18 08:32:39 rasp3 goveebttemplogger[680]: /org/bluez/hci0: org.bluez.Adapter1: StartDiscovery
Nov 18 08:32:39 rasp3 goveebttemplogger[680]: /org/bluez/hci0: org.bluez.Adapter1: SetDiscoveryFilter
Nov 18 08:32:39 rasp3 goveebttemplogger[680]: /org/bluez/hci0: org.freedesktop.DBus.Properties: SetPowered: true

Let me know if you’d like to add more details or logs!

P.S: I will try adding the --HCI option to see if it gets data that way and doesn't halt.
Will let you know the results.

[wcbonner]

@alzuno Can you remind me of your exact system:

cat /proc/cpuinfo
lsb_release -a
cat /proc/version

Also check the logs if there are any bluetooth messages interspersed with the goveebttemplogger messages?

journalctl --boot 0 --unit=bluetooth --unit=goveebttemplogger

On my Pi Zero I get a bunch of bluetooth messages as the system boots but then it recovers and works as I expect. I updated a bit more error checking yesterday so updating your cade may be helpful.

pushd ~/GoveeBTTempLogger/ && git pull && popd && cmake -S ~/GoveeBTTempLogger/ -B ~/GoveeBTTempLogger/build && cmake --build ~/GoveeBTTempLogger/build && pushd ~/GoveeBTTempLogger/build && cpack . && popd

wim@WimPiZeroW-Sola:~ $ cat /proc/cpuinfo
processor       : 0
model name      : ARMv6-compatible processor rev 7 (v6l)
BogoMIPS        : 997.08
Features        : half thumb fastmult vfp edsp java tls
CPU implementer : 0x41
CPU architecture: 7
CPU variant     : 0x0
CPU part        : 0xb76
CPU revision    : 7

Hardware        : BCM2835
Revision        : 9000c1
Serial          : 000000009cd77483
Model           : Raspberry Pi Zero W Rev 1.1
wim@WimPiZeroW-Sola:~ $ lsb_release -a
No LSB modules are available.
Distributor ID: Raspbian
Description:    Raspbian GNU/Linux 12 (bookworm)
Release:        12
Codename:       bookworm
wim@WimPiZeroW-Sola:~ $ cat /proc/version
Linux version 6.6.51+rpt-rpi-v6 (serge@raspberrypi.com) (gcc-12 (Raspbian 12.2.0-14+rpi1) 12.2.0, GNU ld (GNU Binutils for Raspbian) 2.40) #1 Raspbian 1:6.6.51-1+rpt3 (2024-10-08)
wim@WimPiZeroW-Sola:~ $ journalctl --boot 0 --unit=bluetooth --unit=goveebttemplogger
Nov 17 17:17:53 WimPiZeroW-Sola systemd[1]: bluetooth.service - Bluetooth service was skipped because of an unmet condition check (ConditionPathIsDirectory=/sys/class/bluetooth).
Nov 17 17:18:06 WimPiZeroW-Sola systemd[1]: Started goveebttemplogger.service - GoveeBTTempLogger service.
Nov 17 17:18:07 WimPiZeroW-Sola goveebttemplogger[435]: GoveeBTTempLogger Version 3.20241117.0 Built on: Nov 17 2024 at 15:51:29 (starting)
Nov 17 17:18:07 WimPiZeroW-Sola goveebttemplogger[435]: Reading: /var/www/html/goveebttemplogger/gvh-titlemap.txt
Nov 17 17:18:07 WimPiZeroW-Sola goveebttemplogger[435]: Reading: /var/log/goveebttemplogger/gvh-thermometer-types.txt
Nov 17 17:18:08 WimPiZeroW-Sola goveebttemplogger[435]: Reading: /var/cache/goveebttemplogger/gvh-A4C1380D3B10-cache.txt
Nov 17 17:18:10 WimPiZeroW-Sola goveebttemplogger[435]: Reading: /var/cache/goveebttemplogger/gvh-A4C13837BCAE-cache.txt
Nov 17 17:18:12 WimPiZeroW-Sola goveebttemplogger[435]: Reading: /var/cache/goveebttemplogger/gvh-A4C138D5A33B-cache.txt
Nov 17 17:18:13 WimPiZeroW-Sola goveebttemplogger[435]: Reading: /var/cache/goveebttemplogger/gvh-A4C138DCCC3D-cache.txt
Nov 17 17:18:15 WimPiZeroW-Sola goveebttemplogger[435]: Reading: /var/cache/goveebttemplogger/gvh-C23533302550-cache.txt
Nov 17 17:18:17 WimPiZeroW-Sola goveebttemplogger[435]: Reading: /var/cache/goveebttemplogger/gvh-C33635306177-cache.txt
Nov 17 17:18:19 WimPiZeroW-Sola goveebttemplogger[435]: Reading: /var/cache/goveebttemplogger/gvh-CF3533352891-cache.txt
Nov 17 17:18:21 WimPiZeroW-Sola goveebttemplogger[435]: Reading: /var/cache/goveebttemplogger/gvh-D03533334403-cache.txt
Nov 17 17:18:23 WimPiZeroW-Sola goveebttemplogger[435]: Reading: /var/cache/goveebttemplogger/gvh-E35ECC215C0F-cache.txt
Nov 17 17:18:25 WimPiZeroW-Sola goveebttemplogger[435]: Reading: /var/cache/goveebttemplogger/gvh-E36059218065-cache.txt
Nov 17 17:18:26 WimPiZeroW-Sola goveebttemplogger[435]: Reading: /var/cache/goveebttemplogger/gvh-E3605923147D-cache.txt
Nov 17 17:18:28 WimPiZeroW-Sola goveebttemplogger[435]: Reading: /var/cache/goveebttemplogger/gvh-E38EC8C1989A-cache.txt
Nov 17 17:18:29 WimPiZeroW-Sola goveebttemplogger[435]: Reading: /var/log/goveebttemplogger/gvh-A4C1380D3B10-2024-11.txt
Nov 17 17:18:31 WimPiZeroW-Sola goveebttemplogger[435]: Reading: /var/log/goveebttemplogger/gvh-A4C13837BCAE-2024-11.txt
Nov 17 17:18:34 WimPiZeroW-Sola goveebttemplogger[435]: Reading: /var/log/goveebttemplogger/gvh-A4C138D5A33B-2024-11.txt
Nov 17 17:18:35 WimPiZeroW-Sola goveebttemplogger[435]: Reading: /var/log/goveebttemplogger/gvh-A4C138DCCC3D-2024-11.txt
Nov 17 17:18:37 WimPiZeroW-Sola goveebttemplogger[435]: Reading: /var/log/goveebttemplogger/gvh-C23533302550-2024-11.txt
Nov 17 17:18:41 WimPiZeroW-Sola goveebttemplogger[435]: Reading: /var/log/goveebttemplogger/gvh-C33635306177-2024-11.txt
Nov 17 17:18:45 WimPiZeroW-Sola goveebttemplogger[435]: Reading: /var/log/goveebttemplogger/gvh-CF3533352891-2024-11.txt
Nov 17 17:18:45 WimPiZeroW-Sola goveebttemplogger[435]: Reading: /var/log/goveebttemplogger/gvh-D03533334403-2024-11.txt
Nov 17 17:18:48 WimPiZeroW-Sola goveebttemplogger[435]: Reading: /var/log/goveebttemplogger/gvh-E35ECC215C0F-2024-11.txt
Nov 17 17:18:51 WimPiZeroW-Sola goveebttemplogger[435]: Reading: /var/log/goveebttemplogger/gvh-E36059218065-2024-11.txt
Nov 17 17:18:54 WimPiZeroW-Sola goveebttemplogger[435]: Reading: /var/log/goveebttemplogger/gvh-E3605923147D-2024-11.txt
Nov 17 17:18:56 WimPiZeroW-Sola goveebttemplogger[435]: Reading: /var/log/goveebttemplogger/gvh-E38EC8C1989A-2024-11.txt
Nov 17 17:18:58 WimPiZeroW-Sola goveebttemplogger[435]: Writing: /var/www/html/goveebttemplogger/gvh-A4C1380D3B10-day.svg With Title: H5177 (A4:C1:38:0D:3B:10)
Nov 17 17:18:58 WimPiZeroW-Sola goveebttemplogger[435]: Writing: /var/www/html/goveebttemplogger/gvh-A4C13837BCAE-day.svg With Title: Sola Display H5075 (A4:C1:38:37:BC:AE)
Nov 17 17:18:58 WimPiZeroW-Sola goveebttemplogger[435]: Writing: /var/www/html/goveebttemplogger/gvh-A4C138D5A33B-day.svg With Title: Sola V-Berth H5074 (A4:C1:38:D5:A3:3B)
Nov 17 17:18:58 WimPiZeroW-Sola goveebttemplogger[435]: Writing: /var/www/html/goveebttemplogger/gvh-A4C138DCCC3D-day.svg With Title: H5174 (A4:C1:38:DC:CC:3D)
Nov 17 17:18:58 WimPiZeroW-Sola goveebttemplogger[435]: Writing: /var/www/html/goveebttemplogger/gvh-C23533302550-day.svg With Title: H5100 (C2:35:33:30:25:50)
Nov 17 17:18:58 WimPiZeroW-Sola goveebttemplogger[435]: Writing: /var/www/html/goveebttemplogger/gvh-C33635306177-day.svg With Title: H5104 (C3:36:35:30:61:77)
Nov 17 17:18:58 WimPiZeroW-Sola goveebttemplogger[435]: Writing: /var/www/html/goveebttemplogger/gvh-D03533334403-day.svg With Title: H5105 (D0:35:33:33:44:03)
Nov 17 17:18:58 WimPiZeroW-Sola goveebttemplogger[435]: Writing: /var/www/html/goveebttemplogger/gvh-E35ECC215C0F-day.svg With Title: Outside H5074 (E3:5E:CC:21:5C:0F)
Nov 17 17:18:58 WimPiZeroW-Sola goveebttemplogger[435]: Writing: /var/www/html/goveebttemplogger/gvh-E36059218065-day.svg With Title: Sola Icebox H5074 (E3:60:59:21:80:65)
Nov 17 17:18:58 WimPiZeroW-Sola goveebttemplogger[435]: Writing: /var/www/html/goveebttemplogger/gvh-E3605923147D-day.svg With Title: Sola Refrigerator H5074 (E3:60:59:23:14:7D)
Nov 17 17:18:59 WimPiZeroW-Sola goveebttemplogger[435]: Writing: /var/www/html/goveebttemplogger/gvh-E38EC8C1989A-day.svg With Title: Sola H5074 (E3:8E:C8:C1:98:9A)
Nov 17 17:18:59 WimPiZeroW-Sola goveebttemplogger[435]: Reading LastDownload: /var/log/goveebttemplogger/gvh-lastdownload.txt
Nov 17 17:18:59 WimPiZeroW-Sola goveebttemplogger[435]: D-Bus connection ":1.11" Opened
Nov 17 17:18:59 WimPiZeroW-Sola (uetoothd)[613]: ConfigurationDirectory 'bluetooth' already exists but the mode is different. (File system: 755 ConfigurationDirectoryMode: 555)
Nov 17 17:18:59 WimPiZeroW-Sola systemd[1]: Starting bluetooth.service - Bluetooth service...
Nov 17 17:19:01 WimPiZeroW-Sola bluetoothd[613]: Bluetooth daemon 5.66
Nov 17 17:19:01 WimPiZeroW-Sola systemd[1]: Started bluetooth.service - Bluetooth service.
Nov 17 17:19:01 WimPiZeroW-Sola bluetoothd[613]: Starting SDP server
Nov 17 17:19:01 WimPiZeroW-Sola bluetoothd[613]: profiles/audio/vcp.c:vcp_init() D-Bus experimental not enabled
Nov 17 17:19:01 WimPiZeroW-Sola bluetoothd[613]: src/plugin.c:plugin_init() Failed to init vcp plugin
Nov 17 17:19:01 WimPiZeroW-Sola bluetoothd[613]: profiles/audio/mcp.c:mcp_init() D-Bus experimental not enabled
Nov 17 17:19:01 WimPiZeroW-Sola bluetoothd[613]: src/plugin.c:plugin_init() Failed to init mcp plugin
Nov 17 17:19:01 WimPiZeroW-Sola bluetoothd[613]: profiles/audio/bap.c:bap_init() D-Bus experimental not enabled
Nov 17 17:19:01 WimPiZeroW-Sola bluetoothd[613]: src/plugin.c:plugin_init() Failed to init bap plugin
Nov 17 17:19:01 WimPiZeroW-Sola bluetoothd[613]: Bluetooth management interface 1.22 initialized
Nov 17 17:19:02 WimPiZeroW-Sola bluetoothd[613]: profiles/sap/server.c:sap_server_register() Sap driver initialization failed.
Nov 17 17:19:02 WimPiZeroW-Sola bluetoothd[613]: sap-server: Operation not permitted (1)
Nov 17 17:19:02 WimPiZeroW-Sola goveebttemplogger[435]: Host Controller Address: B8:27:EB:7D:DE:29 BlueZ Adapter Path: /org/bluez/hci0
Nov 17 17:19:02 WimPiZeroW-Sola bluetoothd[613]: Failed to set mode: Failed (0x03)
Nov 17 17:19:02 WimPiZeroW-Sola bluetoothd[613]: Failed to set mode: Failed (0x03)
Nov 17 17:19:02 WimPiZeroW-Sola goveebttemplogger[435]: /org/bluez/hci0: org.freedesktop.DBus.Properties: SetPowered: true Error: Failed /home/wim/GoveeBTTempLogger/goveebttemplogger.cpp(3813)
Nov 17 17:19:03 WimPiZeroW-Sola goveebttemplogger[435]: D-Bus connection ":1.11" Closed
Nov 17 17:19:03 WimPiZeroW-Sola goveebttemplogger[435]: D-Bus connection ":1.13" Opened

[alzuno]

Thanks @wcbonner

Can you remind me of your exact system:

pi@rasp3:~ $ cat /proc/cpuinfo
processor	: 0
BogoMIPS	: 38.40
Features	: fp asimd evtstrm crc32 cpuid
CPU implementer	: 0x41
CPU architecture: 8
CPU variant	: 0x0
CPU part	: 0xd03
CPU revision	: 4

processor	: 1
BogoMIPS	: 38.40
Features	: fp asimd evtstrm crc32 cpuid
CPU implementer	: 0x41
CPU architecture: 8
CPU variant	: 0x0
CPU part	: 0xd03
CPU revision	: 4

processor	: 2
BogoMIPS	: 38.40
Features	: fp asimd evtstrm crc32 cpuid
CPU implementer	: 0x41
CPU architecture: 8
CPU variant	: 0x0
CPU part	: 0xd03
CPU revision	: 4

processor	: 3
BogoMIPS	: 38.40
Features	: fp asimd evtstrm crc32 cpuid
CPU implementer	: 0x41
CPU architecture: 8
CPU variant	: 0x0
CPU part	: 0xd03
CPU revision	: 4

Hardware	: BCM2835
Revision	: a020d3
Serial		: 00000000f15507c0
Model		: Raspberry Pi 3 Model B Plus Rev 1.3
pi@rasp3:~ $ lsb_release -a
No LSB modules are available.
Distributor ID:	Debian
Description:	Debian GNU/Linux 11 (bullseye)
Release:	11
Codename:	bullseye
pi@rasp3:~ $ cat /proc/version
Linux version 6.1.21-v8+ (dom@buildbot) (aarch64-linux-gnu-gcc-8 (Ubuntu/Linaro 8.4.0-3ubuntu1) 8.4.0, GNU ld (GNU Binutils for Ubuntu) 2.34) #1642 SMP PREEMPT Mon Apr  3 17:24:16 BST 2023

Also check the logs if there are any bluetooth messages interspersed with the goveebttemplogger messages?

Nov 18 15:02:21 rasp3 systemd[1]: Stopping GoveeBTTempLogger service...
Nov 18 15:02:21 rasp3 goveebttemplogger[1719]: ***************** SIGINT: Caught Ctrl-C, finishing loop and quitting. *****************
Nov 18 15:01:48 rasp3 goveebttemplogger[1719]: Download from device: [A4:C1:38:04:3F:DE] 2024-10-29 15:01:00 2024-11-18 15:01:00 (28800)
Nov 18 15:01:21 rasp3 goveebttemplogger[1719]: Using Controller Address: B8:27:EB:FF:AD:6A
Nov 18 15:01:21 rasp3 goveebttemplogger[1719]: Host Controller Address: B8:27:EB:FF:AD:6A BlueTooth Device ID: 0 HCI Name: hci0
Nov 18 15:01:21 rasp3 goveebttemplogger[1719]: Reading LastDownload: /var/log/goveebttemplogger/gvh-lastdownload.txt
Nov 18 15:01:20 rasp3 goveebttemplogger[1719]: Reading: /var/log/goveebttemplogger/gvh-A4C13856DF3C-2024-11.txt
Nov 18 15:01:19 rasp3 goveebttemplogger[1719]: Reading: /var/log/goveebttemplogger/gvh-A4C138043FDE-2024-11.txt
Nov 18 15:01:19 rasp3 goveebttemplogger[1719]: Reading: /var/cache/goveebttemplogger/gvh-A4C13856DF3C-cache.txt
Nov 18 15:01:19 rasp3 goveebttemplogger[1719]: Reading: /var/cache/goveebttemplogger/gvh-A4C138043FDE-cache.txt
Nov 18 15:01:19 rasp3 goveebttemplogger[1719]: Reading: /var/log/goveebttemplogger/gvh-thermometer-types.txt
Nov 18 15:01:19 rasp3 goveebttemplogger[1719]: Reading: /var/www/html/goveebttemplogger/gvh-titlemap.txt
Nov 18 15:01:19 rasp3 goveebttemplogger[1719]: GoveeBTTempLogger Version 3.20241116.0 Built on: Nov 16 2024 at 21:18:24 (starting)
Nov 18 15:01:19 rasp3 systemd[1]: Started GoveeBTTempLogger service.
Nov 18 15:01:19 rasp3 systemd[1]: goveebttemplogger.service: Consumed 3.551s CPU time.
Nov 18 15:01:19 rasp3 systemd[1]: Stopped GoveeBTTempLogger service.
Nov 18 15:01:19 rasp3 systemd[1]: goveebttemplogger.service: Succeeded.
Nov 18 15:01:19 rasp3 goveebttemplogger[678]: GoveeBTTempLogger Version 3.20241116.0 Built on: Nov 16 2024 at 21:18:24 (exiting)
Nov 18 15:01:19 rasp3 goveebttemplogger[678]: Writing: /var/log/goveebttemplogger/gvh-thermometer-types.txt
Nov 18 15:01:19 rasp3 goveebttemplogger[678]: D-Bus connection ":1.17" Closed
Nov 18 15:01:19 rasp3 goveebttemplogger[678]: /org/bluez/hci0: org.bluez.Adapter1: StopDiscovery: Error: Operation already in progress /home/pi/GoveeBTTempLogger/goveebttemplo>
Nov 18 15:01:18 rasp3 systemd[1]: Stopping GoveeBTTempLogger service...
Nov 18 15:01:18 rasp3 goveebttemplogger[678]: ***************** SIGINT: Caught Ctrl-C, finishing loop and quitting. *****************
Nov 18 15:01:10 rasp3 systemd[1]: goveebttemplogger.service: Current command vanished from the unit file, execution of the command list won't be resumed.
Nov 18 14:38:45 rasp3 goveebttemplogger[678]: /org/bluez/hci0: org.bluez.Adapter1: StartDiscovery
Nov 18 14:38:45 rasp3 goveebttemplogger[678]: /org/bluez/hci0: org.bluez.Adapter1: SetDiscoveryFilter
Nov 18 14:38:45 rasp3 goveebttemplogger[678]: /org/bluez/hci0: org.freedesktop.DBus.Properties: SetPowered: true
Nov 18 14:38:45 rasp3 goveebttemplogger[678]: Host Controller Address: B8:27:EB:FF:AD:6A BlueZ Adapter Path: /org/bluez/hci0
Nov 18 14:38:45 rasp3 goveebttemplogger[678]: D-Bus connection ":1.17" Opened
Nov 18 14:38:45 rasp3 goveebttemplogger[678]: D-Bus connection ":1.12" Closed
Nov 18 14:38:45 rasp3 goveebttemplogger[678]: /org/bluez/hci0: org.bluez.Adapter1: StopDiscovery: Error: Operation already in progress /home/pi/GoveeBTTempLogger/goveebttemplo>
Nov 18 14:15:01 rasp3 goveebttemplogger[678]: Writing: /var/www/html/goveebttemplogger/gvh-A4C13856DF3C-day.svg With Title: Office (A4:C1:38:56:DF:3C)
Nov 18 14:15:01 rasp3 goveebttemplogger[678]: Writing: /var/www/html/goveebttemplogger/gvh-A4C138043FDE-month.svg With Title: Room (A4:C1:38:04:3F:DE)
Nov 18 14:15:01 rasp3 goveebttemplogger[678]: Writing: /var/www/html/goveebttemplogger/gvh-A4C138043FDE-week.svg With Title: Room (A4:C1:38:04:3F:DE)
Nov 18 14:15:01 rasp3 goveebttemplogger[678]: Writing: /var/www/html/goveebttemplogger/gvh-A4C138043FDE-day.svg With Title: Room (A4:C1:38:04:3F:DE)
Nov 18 14:10:47 rasp3 goveebttemplogger[678]: Writing: /var/cache/goveebttemplogger/gvh-A4C138043FDE-cache.txt
Nov 18 14:10:01 rasp3 goveebttemplogger[678]: Writing: /var/www/html/goveebttemplogger/gvh-A4C13856DF3C-month.svg With Title: Office (A4:C1:38:56:DF:3C)
Nov 18 14:10:01 rasp3 goveebttemplogger[678]: Writing: /var/www/html/goveebttemplogger/gvh-A4C13856DF3C-week.svg With Title: Office (A4:C1:38:56:DF:3C)
Nov 18 14:10:01 rasp3 goveebttemplogger[678]: Writing: /var/www/html/goveebttemplogger/gvh-A4C13856DF3C-day.svg With Title: Office (A4:C1:38:56:DF:3C)
Nov 18 14:09:46 rasp3 goveebttemplogger[678]: Writing: /var/cache/goveebttemplogger/gvh-A4C13856DF3C-cache.txt
Nov 18 14:08:45 rasp3 goveebttemplogger[678]: /org/bluez/hci0: org.bluez.Adapter1: StartDiscovery
Nov 18 14:08:45 rasp3 goveebttemplogger[678]: /org/bluez/hci0: org.bluez.Adapter1: SetDiscoveryFilter
Nov 18 14:08:45 rasp3 goveebttemplogger[678]: /org/bluez/hci0: org.freedesktop.DBus.Properties: SetPowered: true
Nov 18 14:08:45 rasp3 goveebttemplogger[678]: Host Controller Address: B8:27:EB:FF:AD:6A BlueZ Adapter Path: /org/bluez/hci0
Nov 18 14:08:45 rasp3 goveebttemplogger[678]: D-Bus connection ":1.12" Opened
Nov 18 14:08:45 rasp3 goveebttemplogger[678]: Reading LastDownload: /var/log/goveebttemplogger/gvh-lastdownload.txt
Nov 18 14:08:44 rasp3 goveebttemplogger[678]: Reading: /var/log/goveebttemplogger/gvh-A4C13856DF3C-2024-11.txt
Nov 18 14:08:43 rasp3 goveebttemplogger[678]: Reading: /var/log/goveebttemplogger/gvh-A4C138043FDE-2024-11.txt
Nov 18 14:08:43 rasp3 goveebttemplogger[678]: Reading: /var/cache/goveebttemplogger/gvh-A4C13856DF3C-cache.txt
Nov 18 14:08:43 rasp3 goveebttemplogger[678]: Reading: /var/cache/goveebttemplogger/gvh-A4C138043FDE-cache.txt
Nov 18 14:08:42 rasp3 goveebttemplogger[678]: Reading: /var/log/goveebttemplogger/gvh-thermometer-types.txt
Nov 18 14:08:42 rasp3 goveebttemplogger[678]: Reading: /var/www/html/goveebttemplogger/gvh-titlemap.txt
Nov 18 14:08:42 rasp3 goveebttemplogger[678]: GoveeBTTempLogger Version 3.20241116.0 Built on: Nov 16 2024 at 21:18:24 (starting)
Nov 18 14:08:42 rasp3 systemd[1]: Started GoveeBTTempLogger service.
Nov 18 14:08:40 rasp3 bluetoothd[608]: Failed to set privacy: Rejected (0x0b)
Nov 18 14:08:40 rasp3 bluetoothd[608]: sap-server: Operation not permitted (1)
Nov 18 14:08:40 rasp3 bluetoothd[608]: profiles/sap/server.c:sap_server_register() Sap driver initialization failed.
Nov 18 14:08:40 rasp3 bluetoothd[608]: Bluetooth management interface 1.22 initialized
Nov 18 14:08:40 rasp3 bluetoothd[608]: Starting SDP server
Nov 18 14:08:40 rasp3 systemd[1]: Started Bluetooth service.
Nov 18 14:08:40 rasp3 bluetoothd[608]: Bluetooth daemon 5.55
Nov 18 14:08:39 rasp3 systemd[1]: Starting Bluetooth service...
Nov 18 14:08:30 rasp3 systemd[1]: Condition check resulted in Bluetooth service being skipped.
-- Boot 1bb0052e0a74460a8032306bcc2d89c3 --
Nov 18 14:08:21 rasp3 systemd[1]: bluetooth.service: Consumed 4min 39.353s CPU time.
Nov 18 14:08:21 rasp3 systemd[1]: Stopped Bluetooth service.
Nov 18 14:08:21 rasp3 systemd[1]: bluetooth.service: Succeeded.
Nov 18 14:08:21 rasp3 bluetoothd[603]: Exit
Nov 18 14:08:21 rasp3 bluetoothd[603]: Stopping SDP server
Nov 18 14:08:21 rasp3 bluetoothd[603]: Terminating
Nov 18 14:08:21 rasp3 systemd[1]: Stopping Bluetooth service...
Nov 18 09:56:09 rasp3 systemd[1]: goveebttemplogger.service: Consumed 1min 4.351s CPU time.
Nov 18 09:56:09 rasp3 systemd[1]: Stopped GoveeBTTempLogger service.
Nov 18 09:56:09 rasp3 systemd[1]: goveebttemplogger.service: Succeeded.
Nov 18 09:56:09 rasp3 goveebttemplogger[673]: GoveeBTTempLogger Version 3.20241116.0 Built on: Nov 16 2024 at 21:18:24 (exiting)
Nov 18 09:56:09 rasp3 goveebttemplogger[673]: Writing: /var/log/goveebttemplogger/gvh-thermometer-types.txt
Nov 18 09:56:09 rasp3 goveebttemplogger[673]: D-Bus connection ":1.14" Closed
Nov 18 09:56:09 rasp3 goveebttemplogger[673]: /org/bluez/hci0: org.bluez.Adapter1: RemoveDevice /org/bluez/hci0/dev_A4_C1_38_56_DF_3C
Nov 18 09:56:09 rasp3 goveebttemplogger[673]: /org/bluez/hci0: org.bluez.Adapter1: RemoveDevice /org/bluez/hci0/dev_A4_C1_38_04_3F_DE
Nov 18 09:56:09 rasp3 goveebttemplogger[673]: /org/bluez/hci0: org.bluez.Adapter1: StopDiscovery
Nov 18 09:56:09 rasp3 systemd[1]: Stopping GoveeBTTempLogger service...
Nov 18 09:56:09 rasp3 goveebttemplogger[673]: ***************** SIGINT: Caught Ctrl-C, finishing loop and quitting. *****************
Nov 18 09:55:02 rasp3 goveebttemplogger[673]: Writing: /var/www/html/goveebttemplogger/gvh-A4C13856DF3C-day.svg With Title: Office (A4:C1:38:56:DF:3C)
Nov 18 09:55:01 rasp3 goveebttemplogger[673]: Writing: /var/www/html/goveebttemplogger/gvh-A4C138043FDE-day.svg With Title: Room (A4:C1:38:04:3F:DE)
Nov 18 09:51:05 rasp3 goveebttemplogger[673]: /org/bluez/hci0: org.bluez.Adapter1: StartDiscovery
Nov 18 09:51:04 rasp3 goveebttemplogger[673]: /org/bluez/hci0: org.bluez.Adapter1: SetDiscoveryFilter
Nov 18 09:51:04 rasp3 goveebttemplogger[673]: /org/bluez/hci0: org.freedesktop.DBus.Properties: SetPowered: true
Nov 18 09:51:04 rasp3 goveebttemplogger[673]: Host Controller Address: B8:27:EB:FF:AD:6A BlueZ Adapter Path: /org/bluez/hci0
Nov 18 09:51:04 rasp3 goveebttemplogger[673]: D-Bus connection ":1.14" Opened
Nov 18 09:51:04 rasp3 goveebttemplogger[673]: D-Bus connection ":1.12" Closed
Nov 18 09:51:04 rasp3 goveebttemplogger[673]: /org/bluez/hci0: org.bluez.Adapter1: RemoveDevice /org/bluez/hci0/dev_A4_C1_38_56_DF_3C
Nov 18 09:51:04 rasp3 goveebttemplogger[673]: /org/bluez/hci0: org.bluez.Adapter1: RemoveDevice /org/bluez/hci0/dev_A4_C1_38_04_3F_DE
Nov 18 09:51:04 rasp3 goveebttemplogger[673]: /org/bluez/hci0: org.bluez.Adapter1: StopDiscovery
Nov 18 09:50:01 rasp3 goveebttemplogger[673]: Writing: /var/www/html/goveebttemplogger/gvh-A4C13856DF3C-day.svg With Title: Office (A4:C1:38:56:DF:3C)
Nov 18 09:50:01 rasp3 goveebttemplogger[673]: Writing: /var/www/html/goveebttemplogger/gvh-A4C138043FDE-day.svg With Title: Room (A4:C1:38:04:3F:DE)
Nov 18 09:48:33 rasp3 goveebttemplogger[673]: Writing: /var/cache/goveebttemplogger/gvh-A4C13856DF3C-cache.txt
Nov 18 09:48:33 rasp3 goveebttemplogger[673]: Writing: /var/cache/goveebttemplogger/gvh-A4C138043FDE-cache.txt
Nov 18 09:45:02 rasp3 goveebttemplogger[673]: Writing: /var/www/html/goveebttemplogger/gvh-A4C13856DF3C-week.svg With Title: Office (A4:C1:38:56:DF:3C)
Nov 18 09:45:02 rasp3 goveebttemplogger[673]: Writing: /var/www/html/goveebttemplogger/gvh-A4C13856DF3C-day.svg With Title: Office (A4:C1:38:56:DF:3C)
Nov 18 09:45:01 rasp3 goveebttemplogger[673]: Writing: /var/www/html/goveebttemplogger/gvh-A4C138043FDE-week.svg With Title: Room (A4:C1:38:04:3F:DE)
Nov 18 09:45:01 rasp3 goveebttemplogger[673]: Writing: /var/www/html/goveebttemplogger/gvh-A4C138043FDE-day.svg With Title: Room (A4:C1:38:04:3F:DE)
Nov 18 09:44:29 rasp3 goveebttemplogger[673]: Writing: /var/www/html/goveebttemplogger/gvh-A4C13856DF3C-week.svg With Title: Office (A4:C1:38:56:DF:3C)
Nov 18 09:44:29 rasp3 goveebttemplogger[673]: Writing: /var/www/html/goveebttemplogger/gvh-A4C13856DF3C-day.svg With Title: Office (A4:C1:38:56:DF:3C)
Nov 18 09:21:04 rasp3 goveebttemplogger[673]: /org/bluez/hci0: org.bluez.Adapter1: StartDiscovery
Nov 18 09:21:04 rasp3 goveebttemplogger[673]: /org/bluez/hci0: org.bluez.Adapter1: SetDiscoveryFilter
Nov 18 09:21:04 rasp3 goveebttemplogger[673]: /org/bluez/hci0: org.freedesktop.DBus.Properties: SetPowered: true
Nov 18 09:21:04 rasp3 goveebttemplogger[673]: Host Controller Address: B8:27:EB:FF:AD:6A BlueZ Adapter Path: /org/bluez/hci0
Nov 18 09:21:04 rasp3 goveebttemplogger[673]: D-Bus connection ":1.12" Opened
Nov 18 09:21:04 rasp3 goveebttemplogger[673]: Reading LastDownload: /var/log/goveebttemplogger/gvh-lastdownload.txt
Nov 18 09:21:03 rasp3 goveebttemplogger[673]: Reading: /var/log/goveebttemplogger/gvh-A4C13856DF3C-2024-11.txt
Nov 18 09:21:02 rasp3 goveebttemplogger[673]: Reading: /var/log/goveebttemplogger/gvh-A4C138043FDE-2024-11.txt
Nov 18 09:21:02 rasp3 goveebttemplogger[673]: Reading: /var/cache/goveebttemplogger/gvh-A4C13856DF3C-cache.txt
Nov 18 09:21:01 rasp3 goveebttemplogger[673]: Reading: /var/cache/goveebttemplogger/gvh-A4C138043FDE-cache.txt
Nov 18 09:21:01 rasp3 goveebttemplogger[673]: Reading: /var/log/goveebttemplogger/gvh-thermometer-types.txt
Nov 18 09:21:01 rasp3 goveebttemplogger[673]: Reading: /var/www/html/goveebttemplogger/gvh-titlemap.txt
Nov 18 09:21:01 rasp3 goveebttemplogger[673]: GoveeBTTempLogger Version 3.20241116.0 Built on: Nov 16 2024 at 21:18:24 (starting)
Nov 18 09:21:01 rasp3 systemd[1]: Started GoveeBTTempLogger service.
Nov 18 09:20:59 rasp3 bluetoothd[603]: Failed to set privacy: Rejected (0x0b)
Nov 18 09:20:59 rasp3 bluetoothd[603]: sap-server: Operation not permitted (1)
Nov 18 09:20:59 rasp3 bluetoothd[603]: profiles/sap/server.c:sap_server_register() Sap driver initialization failed.
Nov 18 09:20:58 rasp3 bluetoothd[603]: Bluetooth management interface 1.22 initialized
Nov 18 09:20:58 rasp3 bluetoothd[603]: Starting SDP server
Nov 18 09:20:58 rasp3 systemd[1]: Started Bluetooth service.
Nov 18 09:20:58 rasp3 bluetoothd[603]: Bluetooth daemon 5.55
Nov 18 09:20:58 rasp3 systemd[1]: Starting Bluetooth service...
Nov 18 09:20:49 rasp3 systemd[1]: Condition check resulted in Bluetooth service being skipped.
-- Boot 31c0639d345b401ba7a4b7372ca4802d --
Nov 18 09:16:30 rasp3 goveebttemplogger[677]: /org/bluez/hci0: org.bluez.Adapter1: StartDiscovery
Nov 18 09:16:30 rasp3 goveebttemplogger[677]: /org/bluez/hci0: org.bluez.Adapter1: SetDiscoveryFilter
Nov 18 09:16:30 rasp3 goveebttemplogger[677]: /org/bluez/hci0: org.freedesktop.DBus.Properties: SetPowered: true
Nov 18 09:16:30 rasp3 goveebttemplogger[677]: Host Controller Address: B8:27:EB:FF:AD:6A BlueZ Adapter Path: /org/bluez/hci0
Nov 18 09:16:30 rasp3 goveebttemplogger[677]: D-Bus connection ":1.17" Opened
Nov 18 09:16:30 rasp3 goveebttemplogger[677]: D-Bus connection ":1.12" Closed
Nov 18 09:16:30 rasp3 goveebttemplogger[677]: /org/bluez/hci0: org.bluez.Adapter1: StopDiscovery: Error: Operation already in progress /home/pi/GoveeBTTempLogger/goveebttemplo>
Nov 18 08:50:01 rasp3 goveebttemplogger[677]: Writing: /var/www/html/goveebttemplogger/gvh-A4C13856DF3C-year.svg With Title: Office (A4:C1:38:56:DF:3C)
Nov 18 08:50:01 rasp3 goveebttemplogger[677]: Writing: /var/www/html/goveebttemplogger/gvh-A4C13856DF3C-month.svg With Title: Office (A4:C1:38:56:DF:3C)
Nov 18 08:50:01 rasp3 goveebttemplogger[677]: Writing: /var/www/html/goveebttemplogger/gvh-A4C13856DF3C-week.svg With Title: Office (A4:C1:38:56:DF:3C)
Nov 18 08:50:01 rasp3 goveebttemplogger[677]: Writing: /var/www/html/goveebttemplogger/gvh-A4C13856DF3C-day.svg With Title: Office (A4:C1:38:56:DF:3C)
Nov 18 08:50:01 rasp3 goveebttemplogger[677]: Writing: /var/www/html/goveebttemplogger/gvh-A4C138043FDE-year.svg With Title: Room (A4:C1:38:04:3F:DE)
Nov 18 08:50:01 rasp3 goveebttemplogger[677]: Writing: /var/www/html/goveebttemplogger/gvh-A4C138043FDE-month.svg With Title: Room (A4:C1:38:04:3F:DE)
Nov 18 08:50:01 rasp3 goveebttemplogger[677]: Writing: /var/www/html/goveebttemplogger/gvh-A4C138043FDE-week.svg With Title: Room (A4:C1:38:04:3F:DE)
Nov 18 08:50:01 rasp3 goveebttemplogger[677]: Writing: /var/www/html/goveebttemplogger/gvh-A4C138043FDE-day.svg With Title: Room (A4:C1:38:04:3F:DE)
Nov 18 08:48:33 rasp3 goveebttemplogger[677]: Writing: /var/cache/goveebttemplogger/gvh-A4C13856DF3C-cache.txt
Nov 18 08:48:33 rasp3 goveebttemplogger[677]: Writing: /var/cache/goveebttemplogger/gvh-A4C138043FDE-cache.txt
Nov 18 08:46:31 rasp3 goveebttemplogger[677]: /org/bluez/hci0: org.bluez.Adapter1: StartDiscovery
Nov 18 08:46:30 rasp3 goveebttemplogger[677]: /org/bluez/hci0: org.bluez.Adapter1: SetDiscoveryFilter
Nov 18 08:46:30 rasp3 goveebttemplogger[677]: /org/bluez/hci0: org.freedesktop.DBus.Properties: SetPowered: true
Nov 18 08:46:30 rasp3 goveebttemplogger[677]: Host Controller Address: B8:27:EB:FF:AD:6A BlueZ Adapter Path: /org/bluez/hci0
Nov 18 08:46:30 rasp3 goveebttemplogger[677]: D-Bus connection ":1.12" Opened
Nov 18 08:46:30 rasp3 goveebttemplogger[677]: Reading LastDownload: /var/log/goveebttemplogger/gvh-lastdownload.txt
Nov 18 08:46:29 rasp3 goveebttemplogger[677]: Reading: /var/log/goveebttemplogger/gvh-A4C13856DF3C-2024-11.txt
Nov 18 08:46:28 rasp3 goveebttemplogger[677]: Reading: /var/log/goveebttemplogger/gvh-A4C138043FDE-2024-11.txt
Nov 18 08:46:28 rasp3 goveebttemplogger[677]: Reading: /var/cache/goveebttemplogger/gvh-A4C13856DF3C-cache.txt
Nov 18 08:46:28 rasp3 goveebttemplogger[677]: Reading: /var/cache/goveebttemplogger/gvh-A4C138043FDE-cache.txt
Nov 18 08:46:28 rasp3 goveebttemplogger[677]: Reading: /var/log/goveebttemplogger/gvh-thermometer-types.txt
Nov 18 08:46:28 rasp3 goveebttemplogger[677]: Reading: /var/www/html/goveebttemplogger/gvh-titlemap.txt
Nov 18 08:46:28 rasp3 goveebttemplogger[677]: GoveeBTTempLogger Version 3.20241116.0 Built on: Nov 16 2024 at 21:18:24 (starting)
Nov 18 08:46:28 rasp3 systemd[1]: Started GoveeBTTempLogger service.
Nov 18 08:46:25 rasp3 bluetoothd[593]: Failed to set privacy: Rejected (0x0b)
Nov 18 08:46:25 rasp3 bluetoothd[593]: sap-server: Operation not permitted (1)
Nov 18 08:46:25 rasp3 bluetoothd[593]: profiles/sap/server.c:sap_server_register() Sap driver initialization failed.
Nov 18 08:46:25 rasp3 bluetoothd[593]: Bluetooth management interface 1.22 initialized
Nov 18 08:46:25 rasp3 bluetoothd[593]: Starting SDP server
Nov 18 08:46:25 rasp3 systemd[1]: Started Bluetooth service.
Nov 18 08:46:25 rasp3 bluetoothd[593]: Bluetooth daemon 5.55
Nov 18 08:46:24 rasp3 systemd[1]: Starting Bluetooth service...
Nov 18 08:46:16 rasp3 systemd[1]: Condition check resulted in Bluetooth service being skipped.
-- Boot 226a8378705f4bd3a3cf07131b38b3c5 --

I will update and let you know the results after that.
Thanks,