Sporadische FHEM-Restarts

[JWRu]

Bei mir läuft FHEM unter Debian 11 (Bullseye) auf einer ZBox.
Seit ca. einem halben Jahr beobachte ich sporadische Restarts von FHEM (alle paar Wochen).
Heute morgen ist es gegen 6:00 Uhr wieder passiert und ich habe mir mal den syslog angeschaut.
Der FHEM Log ist vor dem Restart völlig unauffällig, man sieht erst die Meldungen, wenn FHEM wieder startet.
Hier ist mal der Ausschnitt aus dem syslog:

Code Auswählen Erweitern

Feb 19 05:54:13 ZBox-LAN1 lepresenced[1304223]: [tid:2] main::bluetooth_dump_thread: Received '< HCI Command: Remote Name Request (0x01|0x0019) plen 10', telling hcidump and hcitool to restart...
Feb 19 05:54:13 ZBox-LAN1 lepresenced[1304223]: [tid:2] main::bluetooth_dump_thread: restarting hcidump...
Feb 19 05:54:19 ZBox-LAN1 lepresenced[1304223]: [tid:1] main::bluetooth_scan_thread: restarting hcitool...
Feb 19 05:55:01 ZBox-LAN1 CRON[1308109]: (root) CMD (command -v debian-sa1 > /dev/null && debian-sa1 1 1)
Feb 19 05:55:51 ZBox-LAN1 lepresenced[1304223]: [tid:2] main::bluetooth_dump_thread: Received '< HCI Command: Remote Name Request (0x01|0x0019) plen 10', telling hcidump and hcitool to restart...
Feb 19 05:55:51 ZBox-LAN1 lepresenced[1304223]: [tid:2] main::bluetooth_dump_thread: restarting hcidump...
Feb 19 05:55:56 ZBox-LAN1 lepresenced[1304223]: [tid:1] main::bluetooth_scan_thread: restarting hcitool...
Feb 19 05:55:59 ZBox-LAN1 lepresenced[1304223]: [tid:2] main::bluetooth_dump_thread: Received '< HCI Command: Remote Name Request (0x01|0x0019) plen 10', telling hcidump and hcitool to restart...
Feb 19 05:55:59 ZBox-LAN1 lepresenced[1304223]: [tid:2] main::bluetooth_dump_thread: restarting hcidump...
Feb 19 05:56:04 ZBox-LAN1 lepresenced[1304223]: [tid:1] main::bluetooth_scan_thread: restarting hcitool...
Feb 19 05:56:39 ZBox-LAN1 lepresenced[1304223]: [tid:2] main::bluetooth_dump_thread: Received '< HCI Command: Remote Name Request (0x01|0x0019) plen 10', telling hcidump and hcitool to restart...
Feb 19 05:56:39 ZBox-LAN1 lepresenced[1304223]: [tid:2] main::bluetooth_dump_thread: restarting hcidump...
Feb 19 05:56:39 ZBox-LAN1 lepresenced[1304223]: [tid:1] main::bluetooth_scan_thread: restarting hcitool...
Feb 19 05:56:52 ZBox-LAN1 lepresenced[1304223]: [tid:2] main::bluetooth_dump_thread: Received '< HCI Command: Remote Name Request (0x01|0x0019) plen 10', telling hcidump and hcitool to restart...
Feb 19 05:56:52 ZBox-LAN1 lepresenced[1304223]: [tid:2] main::bluetooth_dump_thread: restarting hcidump...
Feb 19 05:56:53 ZBox-LAN1 lepresenced[1304223]: [tid:1] main::bluetooth_scan_thread: restarting hcitool...
Feb 19 05:57:19 ZBox-LAN1 lepresenced[1304223]: [tid:2] main::bluetooth_dump_thread: Received '< HCI Command: Remote Name Request (0x01|0x0019) plen 10', telling hcidump and hcitool to restart...
Feb 19 05:57:19 ZBox-LAN1 lepresenced[1304223]: [tid:2] main::bluetooth_dump_thread: restarting hcidump...
Feb 19 05:57:24 ZBox-LAN1 lepresenced[1304223]: [tid:1] main::bluetooth_scan_thread: restarting hcitool...
Feb 19 05:58:56 ZBox-LAN1 lepresenced[1304223]: [tid:2] main::bluetooth_dump_thread: Received '< HCI Command: Remote Name Request (0x01|0x0019) plen 10', telling hcidump and hcitool to restart...
Feb 19 05:58:56 ZBox-LAN1 lepresenced[1304223]: [tid:2] main::bluetooth_dump_thread: restarting hcidump...
Feb 19 05:59:01 ZBox-LAN1 lepresenced[1304223]: [tid:1] main::bluetooth_scan_thread: restarting hcitool...
Feb 19 05:59:04 ZBox-LAN1 lepresenced[1304223]: [tid:2] main::bluetooth_dump_thread: Received '< HCI Command: Remote Name Request (0x01|0x0019) plen 10', telling hcidump and hcitool to restart...
Feb 19 05:59:04 ZBox-LAN1 lepresenced[1304223]: [tid:2] main::bluetooth_dump_thread: restarting hcidump...
Feb 19 05:59:10 ZBox-LAN1 lepresenced[1304223]: [tid:1] main::bluetooth_scan_thread: restarting hcitool...
Feb 19 05:59:44 ZBox-LAN1 lepresenced[1304223]: [tid:2] main::bluetooth_dump_thread: Received '< HCI Command: Remote Name Request (0x01|0x0019) plen 10', telling hcidump and hcitool to restart...
Feb 19 05:59:44 ZBox-LAN1 lepresenced[1304223]: [tid:2] main::bluetooth_dump_thread: restarting hcidump...
Feb 19 05:59:49 ZBox-LAN1 lepresenced[1304223]: [tid:1] main::bluetooth_scan_thread: restarting hcitool...
Feb 19 05:59:50 ZBox-LAN1 kernel: [1086923.218585] traps: perl[322086] trap invalid opcode ip:565245eb265b sp:7ffe1bb5f7d0 error:0 in perl[565245ea2000+185000]
Feb 19 05:59:50 ZBox-LAN1 collectord[769]: #015closed connection from 127.0.0.1
Feb 19 05:59:50 ZBox-LAN1 collectord[769]: #015closed connection from 127.0.0.1
Feb 19 05:59:50 ZBox-LAN1 collectord[769]: #015closed connection from 127.0.0.1
Feb 19 05:59:50 ZBox-LAN1 collectord[769]: #015closed connection from 127.0.0.1
Feb 19 05:59:50 ZBox-LAN1 collectord[769]: #015closed connection from 127.0.0.1
Feb 19 05:59:50 ZBox-LAN1 collectord[769]: #015closed connection from 127.0.0.1
Feb 19 05:59:50 ZBox-LAN1 presenced[775]: #015closed connection from 127.0.0.1:60090
Feb 19 05:59:50 ZBox-LAN1 presenced[775]: #015closed successfully all threads
Feb 19 05:59:50 ZBox-LAN1 presenced[775]: #015closed connection from 127.0.0.1:60056
Feb 19 05:59:50 ZBox-LAN1 presenced[775]: #015closed successfully all threads
Feb 19 05:59:50 ZBox-LAN1 systemd[1]: fhem.service: Main process exited, code=killed, status=4/ILL
Feb 19 05:59:50 ZBox-LAN1 presenced[775]: #015closed connection from 127.0.0.1:60064
Feb 19 05:59:50 ZBox-LAN1 presenced[775]: #015closed successfully all threads
Feb 19 05:59:50 ZBox-LAN1 presenced[775]: #015closed connection from 127.0.0.1:60078
Feb 19 05:59:50 ZBox-LAN1 presenced[775]: #015closed successfully all threads
Feb 19 05:59:51 ZBox-LAN1 presenced[775]: #015closed connection from 127.0.0.1:60050
Feb 19 05:59:51 ZBox-LAN1 presenced[775]: #015closed successfully all threads
Feb 19 06:00:01 ZBox-LAN1 CRON[1308437]: (admin) CMD (/opt/fhem/contrib/fhem_watchdog_jr.sh)
Feb 19 06:00:01 ZBox-LAN1 systemd[1]: Starting system activity accounting tool...
Feb 19 06:00:01 ZBox-LAN1 systemd[1]: sysstat-collect.service: Succeeded.
Feb 19 06:00:01 ZBox-LAN1 systemd[1]: Finished system activity accounting tool.
Feb 19 06:01:20 ZBox-LAN1 systemd[1]: fhem.service: State 'stop-sigterm' timed out. Killing.
Feb 19 06:01:20 ZBox-LAN1 systemd[1]: fhem.service: Killing process 322087 (perl) with signal SIGKILL.
Feb 19 06:01:20 ZBox-LAN1 mariadbd[833]: 2025-02-19  6:01:20 45047 [Warning] Aborted connection 45047 to db: 'fhem' user: 'fhemuser' host: 'localhost' (Got an error reading communication packets)
Feb 19 06:01:20 ZBox-LAN1 systemd[1]: fhem.service: Failed with result 'signal'.
Feb 19 06:01:20 ZBox-LAN1 systemd[1]: fhem.service: Consumed 1d 51min 57.545s CPU time.
Feb 19 06:01:20 ZBox-LAN1 systemd[1]: fhem.service: Scheduled restart job, restart counter is at 4.
Feb 19 06:01:20 ZBox-LAN1 systemd[1]: Stopped FHEM Home Automation.
Feb 19 06:01:20 ZBox-LAN1 systemd[1]: fhem.service: Consumed 1d 51min 57.545s CPU time.
Feb 19 06:01:20 ZBox-LAN1 systemd[1]: Starting FHEM Home Automation...
Feb 19 06:01:21 ZBox-LAN1 systemd[1]: Started FHEM Home Automation.
Feb 19 06:01:28 ZBox-LAN1 collectord[769]: #015new connection from 127.0.0.1:34178
Feb 19 06:01:28 ZBox-LAN1 collectord[769]: #015created thread 85 for processing device 7C:2F:80:EA:FB:F3 in room RasPi-Zero_BT for peer 127.0.0.1 (UUID: 94d885943683c0a6e2b8c1b393d312d7)
Feb 19 06:01:28 ZBox-LAN1 collectord[769]: #015created thread 86 for processing device 7C:2F:80:EA:FB:F3 in room RasPi-Zero_BTLE for peer 127.0.0.1 (UUID: 94d885943683c0a6e2b8c1b393d312d7)
Feb 19 06:01:28 ZBox-LAN1 collectord[769]: #015created thread 87 for processing device 7C:2F:80:EA:FB:F3 in room ZBox_BT for peer 127.0.0.1 (UUID: 94d885943683c0a6e2b8c1b393d312d7)
Feb 19 06:01:28 ZBox-LAN1 presenced[775]: #015new connection from 127.0.0.1:56148
Feb 19 06:01:28 ZBox-LAN1 collectord[769]: #015created thread 88 for processing device 7C:2F:80:EA:FB:F3 in room ZBox_BTLE for peer 127.0.0.1 (UUID: 94d885943683c0a6e2b8c1b393d312d7)
Feb 19 06:01:28 ZBox-LAN1 lepresenced[1304223]: [tid:2] main::bluetooth_dump_thread: Received '< HCI Command: Remote Name Request (0x01|0x0019) plen 10', telling hcidump and hcitool to restart...
Feb 19 06:01:28 ZBox-LAN1 lepresenced[1304223]: [tid:2] main::bluetooth_dump_thread: restarting hcidump...
Feb 19 06:01:29 ZBox-LAN1 lepresenced[1304223]: [tid:1] main::bluetooth_scan_thread: restarting hcitool...
Feb 19 06:01:29 ZBox-LAN1 collectord[769]: #015new connection from 127.0.0.1:34192
Feb 19 06:01:29 ZBox-LAN1 collectord[769]: #015created thread 89 for processing device 7C:2F:80:EA:FB:93 in room RasPi-Zero_BT for peer 127.0.0.1 (UUID: 36f43b148c2208b80efab61fb28d5375)
Feb 19 06:01:29 ZBox-LAN1 collectord[769]: #015created thread 90 for processing device 7C:2F:80:EA:FB:93 in room RasPi-Zero_BTLE for peer 127.0.0.1 (UUID: 36f43b148c2208b80efab61fb28d5375)
Feb 19 06:01:29 ZBox-LAN1 collectord[769]: #015created thread 91 for processing device 7C:2F:80:EA:FB:93 in room ZBox_BT for peer 127.0.0.1 (UUID: 36f43b148c2208b80efab61fb28d5375)
Feb 19 06:01:29 ZBox-LAN1 presenced[775]: #015new connection from 127.0.0.1:56162
Feb 19 06:01:29 ZBox-LAN1 collectord[769]: #015created thread 92 for processing device 7C:2F:80:EA:FB:93 in room ZBox_BTLE for peer 127.0.0.1 (UUID: 36f43b148c2208b80efab61fb28d5375)
Feb 19 06:01:35 ZBox-LAN1 lepresenced[1304223]: [tid:2] main::bluetooth_dump_thread: Received '< HCI Command: Remote Name Request (0x01|0x0019) plen 10', telling hcidump and hcitool to restart...
Feb 19 06:01:35 ZBox-LAN1 lepresenced[1304223]: [tid:2] main::bluetooth_dump_thread: restarting hcidump...
Feb 19 06:01:35 ZBox-LAN1 lepresenced[1304223]: [tid:1] main::bluetooth_scan_thread: restarting hcitool...
Feb 19 06:01:35 ZBox-LAN1 collectord[769]: #015new connection from 127.0.0.1:34194
Feb 19 06:01:35 ZBox-LAN1 collectord[769]: #015new connection from 127.0.0.1:34202
Feb 19 06:01:35 ZBox-LAN1 collectord[769]: #015created thread 93 for processing device 7C:2F:80:EA:FC:2D in room RasPi-Zero_BT for peer 127.0.0.1 (UUID: 30b4d6f6ee210480aee12817da6c4b99)
Feb 19 06:01:35 ZBox-LAN1 collectord[769]: #015created thread 94 for processing device 7C:2F:80:EA:FC:2D in room RasPi-Zero_BTLE for peer 127.0.0.1 (UUID: 30b4d6f6ee210480aee12817da6c4b99)
Feb 19 06:01:35 ZBox-LAN1 collectord[769]: #015created thread 95 for processing device 7C:2F:80:EA:FC:2D in room ZBox_BT for peer 127.0.0.1 (UUID: 30b4d6f6ee210480aee12817da6c4b99)
Feb 19 06:01:35 ZBox-LAN1 presenced[775]: #015new connection from 127.0.0.1:56178
Feb 19 06:01:35 ZBox-LAN1 collectord[769]: #015created thread 96 for processing device 7C:2F:80:EA:FC:2D in room ZBox_BTLE for peer 127.0.0.1 (UUID: 30b4d6f6ee210480aee12817da6c4b99)
Feb 19 06:01:35 ZBox-LAN1 collectord[769]: #015new connection from 127.0.0.1:34218
Feb 19 06:01:35 ZBox-LAN1 collectord[769]: #015closed connection from 127.0.0.1
Feb 19 06:01:35 ZBox-LAN1 collectord[769]: #015new connection from 127.0.0.1:34226
Feb 19 06:01:35 ZBox-LAN1 collectord[769]: #015created thread 97 for processing device 38:E1:3D:B2:C1:B2 in room RasPi-Zero_BT for peer 127.0.0.1 (UUID: 9ffdb58939e36c991036ba1df5417dcc)
Feb 19 06:01:35 ZBox-LAN1 collectord[769]: #015created thread 98 for processing device 38:E1:3D:B2:C1:B2 in room RasPi-Zero_BTLE for peer 127.0.0.1 (UUID: 9ffdb58939e36c991036ba1df5417dcc)
Feb 19 06:01:35 ZBox-LAN1 collectord[769]: #015created thread 99 for processing device 38:E1:3D:B2:C1:B2 in room ZBox_BT for peer 127.0.0.1 (UUID: 9ffdb58939e36c991036ba1df5417dcc)
Feb 19 06:01:35 ZBox-LAN1 presenced[775]: #015new connection from 127.0.0.1:56186
Feb 19 06:01:35 ZBox-LAN1 collectord[769]: #015created thread 100 for processing device 38:E1:3D:B2:C1:B2 in room ZBox_BTLE for peer 127.0.0.1 (UUID: 9ffdb58939e36c991036ba1df5417dcc)
Feb 19 06:01:35 ZBox-LAN1 collectord[769]: #015new connection from 127.0.0.1:34242
Feb 19 06:01:35 ZBox-LAN1 collectord[769]: #015created thread 101 for processing device 7C:2F:80:EA:FB:F3 in room RasPi-Zero_BT for peer 127.0.0.1 (UUID: 4105236b61d0c61ab3165612e80f3451)
Feb 19 06:01:35 ZBox-LAN1 collectord[769]: #015created thread 102 for processing device 7C:2F:80:EA:FB:F3 in room RasPi-Zero_BTLE for peer 127.0.0.1 (UUID: 4105236b61d0c61ab3165612e80f3451)
Feb 19 06:01:35 ZBox-LAN1 collectord[769]: #015created thread 103 for processing device 7C:2F:80:EA:FB:F3 in room ZBox_BT for peer 127.0.0.1 (UUID: 4105236b61d0c61ab3165612e80f3451)
Feb 19 06:01:35 ZBox-LAN1 presenced[775]: #015new connection from 127.0.0.1:56194
Feb 19 06:01:35 ZBox-LAN1 collectord[769]: #015created thread 104 for processing device 7C:2F:80:EA:FB:F3 in room ZBox_BTLE for peer 127.0.0.1 (UUID: 4105236b61d0c61ab3165612e80f3451)
Feb 19 06:01:35 ZBox-LAN1 collectord[769]: #015new connection from 127.0.0.1:34256
Feb 19 06:01:35 ZBox-LAN1 collectord[769]: #015closed connection from 127.0.0.1
Feb 19 06:01:35 ZBox-LAN1 collectord[769]: #015created thread 105 for processing device 14:60:CB:28:71:A5 in room RasPi-Zero_BT for peer 127.0.0.1 (UUID: 9bfa18d6ed929cbefbbfc728b27eb85c)
Feb 19 06:01:35 ZBox-LAN1 collectord[769]: #015created thread 106 for processing device 14:60:CB:28:71:A5 in room RasPi-Zero_BTLE for peer 127.0.0.1 (UUID: 9bfa18d6ed929cbefbbfc728b27eb85c)
Feb 19 06:01:35 ZBox-LAN1 collectord[769]: #015created thread 107 for processing device 14:60:CB:28:71:A5 in room ZBox_BT for peer 127.0.0.1 (UUID: 9bfa18d6ed929cbefbbfc728b27eb85c)
Feb 19 06:01:35 ZBox-LAN1 presenced[775]: #015new connection from 127.0.0.1:56200
Feb 19 06:01:35 ZBox-LAN1 collectord[769]: #015created thread 108 for processing device 14:60:CB:28:71:A5 in room ZBox_BTLE for peer 127.0.0.1 (UUID: 9bfa18d6ed929cbefbbfc728b27eb85c)
Feb 19 06:01:35 ZBox-LAN1 collectord[769]: #015created thread 109 for processing device 7C:2F:80:EA:FB:93 in room RasPi-Zero_BT for peer 127.0.0.1 (UUID: 07e7d279299b22879f0ca1b1de700315)
Feb 19 06:01:35 ZBox-LAN1 collectord[769]: #015created thread 110 for processing device 7C:2F:80:EA:FB:93 in room RasPi-Zero_BTLE for peer 127.0.0.1 (UUID: 07e7d279299b22879f0ca1b1de700315)
Feb 19 06:01:35 ZBox-LAN1 collectord[769]: #015created thread 111 for processing device 7C:2F:80:EA:FB:93 in room ZBox_BT for peer 127.0.0.1 (UUID: 07e7d279299b22879f0ca1b1de700315)
Feb 19 06:01:35 ZBox-LAN1 presenced[775]: #015new connection from 127.0.0.1:56210
Feb 19 06:01:35 ZBox-LAN1 collectord[769]: #015created thread 112 for processing device 7C:2F:80:EA:FB:93 in room ZBox_BTLE for peer 127.0.0.1 (UUID: 07e7d279299b22879f0ca1b1de700315)
Feb 19 06:01:36 ZBox-LAN1 presenced[775]: #015closed connection from 127.0.0.1:56148
Feb 19 06:01:36 ZBox-LAN1 presenced[775]: #015closed successfully all threads
Feb 19 06:01:36 ZBox-LAN1 presenced[775]: #015closed connection from 127.0.0.1:56162
Feb 19 06:01:36 ZBox-LAN1 presenced[775]: #015closed successfully all threads
Feb 19 06:01:41 ZBox-LAN1 lepresenced[1304223]: [tid:2] main::bluetooth_dump_thread: Received '< HCI Command: Remote Name Request (0x01|0x0019) plen 10', telling hcidump and hcitool to restart...
Mir ist nur diese Kernel-Fehler aufgefallen:

Code Auswählen Erweitern

Feb 19 05:59:50 ZBox-LAN1 kernel: [1086923.218585] traps: perl[322086] trap invalid opcode ip:565245eb265b sp:7ffe1bb5f7d0 error:0 in perl[565245ea2000+185000]Was könnte die Ursache sein?