chronyd restarts FAIL

[Dirk539]

Hallo,
ich habe heute, eher durch Zufall, im Log der RM (3.73.9.20231130) gesehen, dass der chronyd service bei mir nicht erfolgreich gestartet wird. 'monit' versucht den chronyd dann zu restarten, was aber wiederum mit einem 'fail' aus der Prozedur /etc/init.d/S46chrony quittiert wird.
Dann wiederholt sich das ganze:

Code: Alles auswählen

Dec  6 11:58:17 raspi-homematic user.err monit[1950]: 'chronyd' process is not running
Dec  6 11:58:17 raspi-homematic user.info monit[1950]: 'chronyd' trying to restart
Dec  6 11:58:17 raspi-homematic user.info monit[1950]: 'chronyd' restart: '/etc/init.d/S46chrony restart'
Dec  6 11:58:24 raspi-homematic daemon.info chronyd[8609]: chronyd version 4.3 starting (+CMDMON +NTP +REFCLOCK +RTC +PRIVDROP -SCFILTER -SIGND +ASYNCDNS -NTS -SECHASH +IPV6 -DEBUG)
Dec  6 11:58:24 raspi-homematic daemon.info chronyd[8609]: Frequency 0.000 +/- 1000000.000 ppm read from /var/lib/chrony/chrony.drift
Dec  6 11:58:24 raspi-homematic daemon.info chronyd[8609]: chronyd exiting
Dec  6 11:58:47 raspi-homematic user.err monit[1950]: 'chronyd' failed to restart (exit status 0) -- '/etc/init.d/S46chrony restart': Stopping chrony: FAIL Starting chrony: FAIL

als NTP-Server ist bei mir ein Linuxserver (in meinem Netz) definiert, zudem auch erfolgtreich eine Verbindung hergestellt werden:

Code: Alles auswählen

root@raspi-homematic:/etc/config# cat ntpclient
NTPSERVERS='192.168.0.1'
root@raspi-homematic:/etc/config# /usr/bin/ntpdate 192.168.0.1
 6 Dec 12:08:14 ntpdate[9632]: adjust time server 192.168.0.1 offset +0.000079 sec

Mir ist überhaupt nicht klar, welche Schmerzen die chrony-Startprozedur (siehe oben) hat, da sie, außer der FAIL-Meldung, keine weiteren Informationen ausgibt.
Habt Ihr einen Tipp, wie ich weiter vorgehen kann, um das Problem zu beheben?

Danke und Grüße!

[jmaus]

Ruf einfach mal folgende Dinge auf:

Code: Alles auswählen

monit stop chronyd
chronyd -d

Und dann sollte der hoffentlich etwas mehr infos ausspucken warum chrony bei dir sofort sich nach dem start wieder beendet.

[Dirk539]

Hallo,

wenn ich den chronyd "zu Fuß" starte, scheint er glücklich zu sein und läuft:

Code: Alles auswählen

root@raspi-homematic:/var/log# chronyd -d
2023-12-06T14:03:55Z chronyd version 4.3 starting (+CMDMON +NTP +REFCLOCK +RTC +PRIVDROP -SCFILTER -SIGND +ASYNCDNS -NTS -SECHASH +IPV6 -DEBUG)
2023-12-06T14:03:55Z Frequency -1.198 +/- 84.665 ppm read from /var/lib/chrony/chrony.drift
2023-12-06T14:03:59Z Selected source 192.168.0.1

und dazu die Logmeldungen:

Code: Alles auswählen

Dec  6 15:03:55 raspi-homematic user.info monit[1950]: 'chronyd' restarted
Dec  6 15:04:11 raspi-homematic user.err monit[1950]: 'chronyd' service restarted 1 times within 1 cycles(s) - exec
Dec  6 15:04:11 raspi-homematic user.info monit[1950]: 'chronyd' process is running with pid 28462
Dec  6 15:04:11 raspi-homematic user.info monit[1950]: 'chronyd' process is running after previous restart timeout (manually recovered?)

Die restarts (STOP und START) aus der Prozedur funkionierten aber nicht !?

Code: Alles auswählen

Dec  6 15:01:51 raspi-homematic user.err monit[1950]: 'chronyd' failed to restart (exit status 0) -- '/etc/init.d/S46chrony restart': Stopping chrony: FAIL Starting chrony: FAIL
Dec  6 15:02:06 raspi-homematic user.err monit[1950]: 'chronyd' process is not running
Dec  6 15:02:06 raspi-homematic user.info monit[1950]: 'chronyd' trying to restart
Dec  6 15:02:06 raspi-homematic user.info monit[1950]: 'chronyd' restart: '/etc/init.d/S46chrony restart'
Dec  6 15:02:14 raspi-homematic daemon.info chronyd[28291]: chronyd version 4.3 starting (+CMDMON +NTP +REFCLOCK +RTC +PRIVDROP -SCFILTER -SIGND +ASYNCDNS -NTS -SECHASH +IPV6 -DEBUG)
Dec  6 15:02:14 raspi-homematic daemon.info chronyd[28291]: Frequency -1.198 +/- 84.665 ppm read from /var/lib/chrony/chrony.drift
Dec  6 15:02:14 raspi-homematic daemon.info chronyd[28291]: chronyd exiting

Es sieht so aus, als habe chronyd hier kein grundsätzliches Problem sondern vielleicht die Prozedur?

[jmaus]

Und was ist wenn du direkt

Code: Alles auswählen

monit stop chronyd
/etc/init.d/S46chronyd start

machst?

[Dirk539]

Du meintest wahrscheinlich S46chrony und nicht S46chronyd, oder?

Dieser Startversuch schlägt leider auch fehl:

Code: Alles auswählen

root@raspi-homematic:/etc/init.d# /etc/init.d/S46chronyd start
-sh: /etc/init.d/S46chronyd: not found

root@raspi-homematic:/etc/init.d# /etc/init.d/S46chrony start
Starting chrony: FAIL

[jmaus]

Du meintest wahrscheinlich S46chrony und nicht S46chronyd, oder?

Dieser Startversuch schlägt leider auch fehl:

Code: Alles auswählen

root@raspi-homematic:/etc/init.d# /etc/init.d/S46chrony start
Starting chrony: FAIL

Komisch. Dann zeig mal bitte den Inhalt deiner /etc/config/ntpclient Datei. Auch bitte mal schauen ob du eine /var/etc/ntp-dhcp.conf Datei hast und dann den Inhalt hier zeigen. Auch bitte mal nach dem manuellen start von "chronyd" schauen was der folgende Befehl für ausgaben zurück gibt. D.h. also sowas hier:

Code: Alles auswählen

monit stop chronyd
chronyd
chronyc add server 192.168.0.1

[Dirk539]

Die /etc/config/ntpclient enthält:

Code: Alles auswählen

NTPSERVERS='192.168.0.1'

/var/etc/ntp-dhcp.conf ist nicht vorhanden.

Wenn ich den chronyd manuell starte:

Code: Alles auswählen

monit stop chronyd
chronyd

sehe ich Meldung
chronyc add server 192.168.0.1
nicht.

Im Messages-Log lese ich diese Meldungen:

Code: Alles auswählen

Dec  7 10:01:09 raspi-homematic daemon.info chronyd[32406]: chronyd version 4.3 starting (+CMDMON +NTP +REFCLOCK +RTC +PRIVDROP -SCFILTER -SIGND +ASYNCDNS -NTS -SECHASH +IPV6 -DEBUG)
Dec  7 10:01:09 raspi-homematic daemon.info chronyd[32406]: Frequency 0.754 +/- 0.026 ppm read from /var/lib/chrony/chrony.drift
Dec  7 10:01:14 raspi-homematic daemon.info chronyd[32406]: Selected source 192.168.0.1

[jmaus]

Das ist ja auch keine Meldung. Du sollst das chronyc Kommando ausführen wenn der chrony Dämon läuft und schauen was es zurückgibt..

[Dirk539]

Sorry, hatte ich falsch verstanden.
Hier das Kommando und der Output:

Code: Alles auswählen

root@raspi-homematic:/etc/init.d# chronyd
root@raspi-homematic:/etc/init.d# chronyc add server 192.168.0.1
511 Source already present

[jmaus]

Sorry, hatte ich falsch verstanden.
Hier das Kommando und der Output:

Code: Alles auswählen

root@raspi-homematic:/etc/init.d# chronyd
root@raspi-homematic:/etc/init.d# chronyc add server 192.168.0.1
511 Source already present

Hmm, komisch. wo kommt denn das her das er denkt 192.168.0.1 ist bereits vorhanden?!?

Was kommt denn wenn du

Code: Alles auswählen

chronyd -p

aufrufst? Das sollte die Konfiguration dumpen bevor der chronyd seinen job aufnimmt. Und wenn du "chronyd -dd" aufrufst loggt er vielleicht noch mehr? Irgendwoher muss chrony ja bei dem plain start bereits die info bekommen. Vielleicht schaust du noch in das /var/run/chrony/ und /var/lib/chrony/ verzeichnis. Kann sein da liegen noch irgendwelche infos über die NTP server die er nutzt bei dir...