Dienst startet nicht mehr auf Raspberry

Thunderbolt · 1. Februar 2023 um 07:18

Hi…

Musste heute Morgen feststellen, dass der Symcon Dienst nicht läuft. Dienst wurde heute Nacht für mein automatiches Backup gestoppt und ist dann nicht gestartet. Habe daraufhin den Raspberry PI neu gestartet. Leider startet Dienst immer noch nicht.
Auch mehrfache Versuche den Dienst manuell zu starten oder den Raspberry PI neu zu starten haben keinen Erfolg gebracht.

Wenn ich

sudo symcon

eingebe kommt entweder immer an unterschiedlichen Stellen der Fehler

Sementation fault

oder folgender Fehler

*** IP-Symcon starting
02/01/23 08:14:22 | 00000 | MESSAGE | Kernel               | Creating...
02/01/23 08:14:22 | 00000 | MESSAGE | Kernel               | Detected TimeZone: Europe/Berlin
02/01/23 08:14:22 | 00000 | MESSAGE | Kernel               | Platform: Raspberry Pi
02/01/23 08:14:22 | 00000 | MESSAGE | Kernel               | Version: 6.3
02/01/23 08:14:22 | 00000 | MESSAGE | Kernel               | Revision: 07e763adc3e27a306c2ee06008107db890db5515
02/01/23 08:14:22 | 00000 | MESSAGE | Kernel               | Build: 11/29/22
02/01/23 08:14:22 | 00000 | MESSAGE | Kernel               | WorkingDir: /var/lib/symcon/
02/01/23 08:14:22 | 00000 | MESSAGE | Kernel               | SystemDir: /usr/share/symcon/
02/01/23 08:14:22 | 00000 | MESSAGE | Kernel               | LogDir: /var/log/symcon/
02/01/23 08:14:22 | 00000 | MESSAGE | Kernel               | cURL Version: libcurl/7.79.1 OpenSSL/1.1.1m zlib/1.2.11
02/01/23 08:14:22 | 00000 | MESSAGE | Kernel               | Git2 Version: 1.1.1
02/01/23 08:14:22 | 00000 | MESSAGE | Kernel               | SSH Version: 0.9.6
02/01/23 08:14:22 | 00000 | MESSAGE | Kernel               | FFmpeg Version: Codec/58.134.100 Device/58.13.100 Format/58.76.100 Util/56.70.100
02/01/23 08:14:22 | 00000 | MESSAGE | Kernel               | ZLIB Version: 1.2.11
02/01/23 08:14:22 | 00000 | MESSAGE | Kernel               | OpenSSL Version: OpenSSL 1.1.1m  14 Dec 2021
02/01/23 08:14:22 | 00000 | MESSAGE | Kernel               | OpenSSL CertDir: /usr/share/symcon/
02/01/23 08:14:22 | 00000 | MESSAGE | Kernel               | OpenSSL CertFile: /usr/share/symcon/cacert.pem
02/01/23 08:14:22 | 00000 | MESSAGE | Kernel               | XML2 Version: 20912
02/01/23 08:14:22 | 00000 | MESSAGE | Kernel               | XML2 Threads: Yes
02/01/23 08:14:22 | 00000 | MESSAGE | Kernel               | PHP Version: 7.4.27
02/01/23 08:14:22 | 00000 | MESSAGE | Kernel               | Initializing...
02/01/23 08:14:22 | 00000 | MESSAGE | LocalePool           | Creating...
02/01/23 08:14:22 | 00000 | MESSAGE | LocalePool           | Detected locale: de_DE
01.02.2023 08:14:22 | 00000 | MESSAGE | LicensePool          | Erstelle...
01.02.2023 08:14:22 | 00000 | MESSAGE | Settings             | Erstelle...
01.02.2023 08:14:22 | 00000 | MESSAGE | Settings             | Lade Einstellungen...
01.02.2023 08:14:23 | 00000 | MESSAGE | ObjectManager        | Erstelle...
01.02.2023 08:14:23 | 00000 | MESSAGE | ObjectManager        | Erstelle Root-Kategorie...
01.02.2023 08:14:23 | 00000 | MESSAGE | CategoryManager      | Erstelle...
01.02.2023 08:14:23 | 00000 | MESSAGE | VariableManager      | Erstelle...
01.02.2023 08:14:23 | 00000 | MESSAGE | InstanceManager      | Erstelle...
01.02.2023 08:14:23 | 00000 | MESSAGE | ScriptManager        | Erstelle...
01.02.2023 08:14:23 | 00000 | MESSAGE | EventManager         | Erstelle...
01.02.2023 08:14:23 | 00000 | MESSAGE | MediaManager         | Erstelle...
01.02.2023 08:14:23 | 00000 | MESSAGE | LinkManager          | Erstelle...
01.02.2023 08:14:23 | 00000 | MESSAGE | ModuleLoader         | Erstelle...
01.02.2023 08:14:23 | 00000 | MESSAGE | FlowHandler          | Erstelle...
01.02.2023 08:14:23 | 00000 | MESSAGE | SyncHandler          | Erstelle...
01.02.2023 08:14:23 | 00000 | MESSAGE | ProfilePool          | Erstelle...
01.02.2023 08:14:23 | 00000 | MESSAGE | ScriptEngine         | Erstelle...
01.02.2023 08:14:23 | 00000 | MESSAGE | ScriptEngine         | Starte 25 Skript-Threads...
01.02.2023 08:14:23 | 00000 | MESSAGE | TimerPool            | Erstelle...
01.02.2023 08:14:23 | 00000 | MESSAGE | DataServer           | Erstelle...
01.02.2023 08:14:23 | 00000 | MESSAGE | DataServer           | Starte Server...
02/01/23 08:14:23 | 00000 | MESSAGE | LocalePool           | Removing...
02/01/23 08:14:23 | 00000 | MESSAGE | Kernel               | bind: Address already in use
02/01/23 08:14:23 | 00000 | MESSAGE | Kernel               | Removing...
02/01/23 08:14:23 | 00000 | SUCCESS | Kernel               | *** IPS SHUTDOWN COMPLETE
02/01/23 08:14:23 | 00000 | MESSAGE | TimerPool            | Terminating timer thread...
02/01/23 08:14:23 | 00000 | MESSAGE | TimerPool            | Removing...
02/01/23 08:14:23 | 00000 | MESSAGE | ScriptEngine         | Removing...
02/01/23 08:14:23 | 00000 | MESSAGE | ScriptEngine         | Waiting for all script threads to terminate...
02/01/23 08:14:23 | 00000 | MESSAGE | Settings             | Removing...
02/01/23 08:14:23 | 00000 | MESSAGE | ProfilePool          | Removing...
02/01/23 08:14:23 | 00000 | MESSAGE | SyncHandler          | Removing...
02/01/23 08:14:23 | 00000 | MESSAGE | FlowHandler          | Removing...
02/01/23 08:14:23 | 00000 | MESSAGE | LinkManager          | Removing...
02/01/23 08:14:23 | 00000 | MESSAGE | MediaManager         | Removing...
02/01/23 08:14:23 | 00000 | MESSAGE | EventManager         | Terminating event thread...
02/01/23 08:14:23 | 00000 | MESSAGE | EventManager         | Removing...
02/01/23 08:14:23 | 00000 | MESSAGE | ScriptManager        | Removing...
02/01/23 08:14:23 | 00000 | MESSAGE | VariableManager      | Removing...
02/01/23 08:14:23 | 00000 | MESSAGE | InstanceManager      | Removing...
02/01/23 08:14:23 | 00000 | MESSAGE | CategoryManager      | Removing...
02/01/23 08:14:23 | 00000 | MESSAGE | ObjectManager        | Removing...
02/01/23 08:14:23 | 00000 | MESSAGE | ModuleLoader         | Removing...
02/01/23 08:14:23 | 00000 | MESSAGE | LicensePool          | Removing...
Exception: bind: Address already in use
*** IP-Symcon shutdown

Hoffe einer von Euch hat eine Idee hierzu?

Hier noch die Daten der Umgebung:

Raspberry PI 3 mit Bullseye
IPS V6.3

Danke und viele Grüße
Jochen

Update:
Zwischenzeitlich hat der Start des Dienstes funktioniert, ohne eine sonstige Änderung. Unabhängig davon, dass es jetzt wieder funktioniert würe ich das Problem gerne verstehen…

pitti · 2. Februar 2023 um 10:19

Bin da jetzt auch kein großer Experte, aber in dem Log steht doch das die Adresse noch von was anderem gebunden war. Kann es sein das dass stoppen nicht funktioniert hat?

Ich habe das auch schon an anderer Stelle hier gefragt - warum stoppt ihr den Dienst für ein Backup. Das da wirklich korrupte Dateien „gebackup’t“ werden ist doch statistisch sehr gering! Und wenn dann gibt es ja noch die Symcon-eigenen Backups!

Gruß Heiko

Thunderbolt · 3. Februar 2023 um 07:26

Danke Heiko für Dein Feedback!

Ich vermute zwischenzeitlich auch, dass sich die Fehler insofern bedingen, dass der Segmentation fault der eigentliche ursächliche Fehler ist. Wenn ich denn versuche den Dienst neu zu starten, funktioniert (wahrscheinlich auf Grund des ersten Fehlers) das Stoppen nicht richtig und dann kommt der Fehler Exception: bind: Address already in use beim erneuten Startversuch.

Wenn die Theorie stimmt wäre es interessant herauszufinden woher das Segmentation fault kommen könnte…
Hat dazu noch einer von Euch eine Idee?

Übrigens die letzten Tage hat das Stoppen und Starten des Dienstes im Rahmen des nächtlichen Backups ohne Probleme funktioniert.
Kann technisch schwer beurteilen, ob das wirklich notwendig ist. Wobei ich das seit vielen Jahren mache und bis jetzt noch nie Probleme damit hatte.

Grüße Jochen

Thunderbolt · 6. Februar 2023 um 09:29

Hi…

Nachdem der Dienst einige Tage ohne Probleme lief und sich auch natchts für das Backup automatisch stoppen und starten lies, ist das Problem wieder da.

Wenn ich sudo symcon eingebe, kommt am Ende ein Abbruch mit Segmentation fault.

Noch jemand eine Idee?

Schon mal vielen Dank!!!

Grüße Jochen

Nall-chan · 6. Februar 2023 um 12:06

Vermutlich hängt der process vom Dienst noch.
Und mit sudo Symcon startest du keinen Dienst, sondern Symcon auf der Kommandozeile.
Und dann läuft es halt mehrfach.
Michael

Thunderbolt · 6. Februar 2023 um 12:21

Danke Michael für die Erklärung!

Wo schaue ich denn am besten ob und warum der Start des Dienstes noch hängt?

Danke und Grüße
Jochen

Nall-chan · 6. Februar 2023 um 13:22

Vielleicht im Logfile?
Michael

Thunderbolt · 6. Februar 2023 um 14:04

Okay hätte ich auch selbst darauf kommen können…

Der letzte Teil des Logs sieht wie folgt aus:

06.02.2023 04:03:06 | 00000 | MESSAGE | ModuleLoader         | # IMAP geladen
06.02.2023 04:03:06 | 00000 | MESSAGE | ModuleLoader         | # POP3 geladen
06.02.2023 04:03:06 | 00000 | MESSAGE | ModuleLoader         | # SMS geladen
06.02.2023 04:03:06 | 00000 | MESSAGE | ModuleLoader         | # SMS REST geladen
06.02.2023 04:03:06 | 00000 | MESSAGE | ModuleLoader         | # SMTP geladen
06.02.2023 04:03:06 | 00000 | MESSAGE | ModuleLoader         | # VoIP geladen
06.02.2023 04:03:07 | 00000 | MESSAGE | BuildInLibrary       | Registriere Funktion...

Bei der letzten Zeile schein er sich dann aufzuhängen.

Noch mal Danke für Euren Input!

Grüße Jochen

paresy · 6. Februar 2023 um 21:50

Passiert das immer?

Traust du dich an diese Anleitung? Debugging für Experten (Raspberry Pi, Linux, SymBox)

paresy

Thunderbolt · 7. Februar 2023 um 06:34

Hi paresy…

Heute Nacht ist es beim Stoppen und Starten des Dienstes für das Backup wieder passiert. Log sieht wie folgt aus:

07.02.2023 04:02:50 | 00000 | MESSAGE | ModuleLoader         | # xComfort Room Control geladen
07.02.2023 04:02:50 | 00000 | MESSAGE | ModuleLoader         | # xComfort Shutter geladen
07.02.2023 04:02:50 | 00000 | MESSAGE | ModuleLoader         | # xComfort Switch geladen
07.02.2023 04:02:50 | 00000 | MESSAGE | ModuleLoader         | # xComfort Temperature geladen
07.02.2023 04:02:50 | 00000 | MESSAGE | ModuleLoader         | # xComfort Value RX geladen
07.02.2023 04:02:50 | 00000 | MESSAGE | ModuleLoader         | # xComfort Value TX geladen
07.02.2023 04:02:50 | 00000 | MESSAGE | ModuleLoader         | # Z-Wave Configurator geladen
07.02.2023 04:02:50 | 00000 | MESSAGE | ModuleLoader         | # Z-Wave Module geladen
07.02.2023 04:02:50 | 00000 | MESSAGE | ModuleLoader         | # Z-Wave Discovery geladen
07.02.2023 04:02:50 | 00000 | MESSAGE | ModuleLoader         | # Z-Wave Gateway geladen
07.02.2023 04:02:50 | 00000 | MESSAGE | ModuleLoader         | # IMAP geladen
07.02.2023 04:02:50 | 00000 | MESSAGE | ModuleLoader         | # POP3 geladen
07.02.2023 04:02:50 | 00000 | MESSAGE | ModuleLoader         | # SMS geladen
07.02.2023 04:02:50 | 00000 | MESSAGE | ModuleLoader         | # SMS REST geladen
07.02.2023 04:02:50 | 00000 | MESSAGE | ModuleLoader         | # SMTP geladen
07.02.2023 04:02:50 | 00000 | MESSAGE | ModuleLoader         | # VoIP geladen
07.02.2023 04:02:50 | 00000 | MESSAGE | BuildInLibrary       | Registriere Funktion...

Bin dann nach Deiner Anleitung vorgegangen. Allerdings bekomme ich beim ausführen von

sudo gdb /usr/bin/symcon

den Fehler

Segmentation fault

Danke und Grüße
Jochen

paresy · 7. Februar 2023 um 11:51

Ja, aber dann sollte gdb dir anbieten den Backtrace vom Segfault anzeigen zu lassen?

paresy

Thunderbolt · 7. Februar 2023 um 11:59

Nicht wirklich… oder ich stehe auf dem Schlauch…

So sieht es im Terminal aus:

Bildschirmfoto 2023-02-07 um 12.57.35

Grüße Jochen

tissenm · 7. Februar 2023 um 14:03

Bei mir heute Nacht auch dasselbe. Komischerweise ist das Backup aber durchgelaufen und dann kein Neustart mit denselben Meldungen. Erst nach Reboot lief wieder alles.

Werde das weiter beobachten und beim nächsten Mal auch Killall probieren.

Gruß Michael

paresy · 7. Februar 2023 um 14:33

Das würde bedeuten, dass gdb direkt beim Starten abstürzt. Sind auf dem Pi alle Updates installiert? Welche Version habt ihr da genau in Benutzung?

paresy

Thunderbolt · 7. Februar 2023 um 14:47

PI ist aktuell. Habe als das Problem los ging extra noch mal ein sudo apt-get update und sudo apt-get upgrade laufen lassen.

Symcon Paket hat die Version 6.3-276.

Danke und Grüße
Jochen

tissenm · 7. Februar 2023 um 14:54

Pi ist aktuell und Symcon wohl auch.

IP-Symcon 6.3, Raspberry Pi (arm64), 29.11.2022, 07e763adc3e2

Gruß Michael

paresy · 7. Februar 2023 um 15:05

Ist es bei dir auch die 64 Bit Version oder noch die 32 Bit Version?

paresy

Thunderbolt · 7. Februar 2023 um 15:14

Bei mir ist es noch 32 Bit.

Grüße Jochen

Thunderbolt · 8. Februar 2023 um 11:57

Hi…

Nachdem ich so ja nicht mehr wirklich weitergekommen bin, habe ich beschlossen den Raspberry PI neu aufzusetzen und das Backup einzuspielen.

Bis jetzt läuft es stabil. Werde berichten, falls der Fehler doch wieder auftritt…

Danke noch mal für die Hilfe!

Viele Grüße
Jochen