Meine DS ist zu schnell für Dovecot

[JudgeDredd]

Hallo Zusammen,

zur Zeit betreibe ich den MailServer nur als Test und Zeitvertreib. Bevor ich mich damit in Produktion begebe, würde ich gerne folgendes Problem beheben.
Die Konfiguration funktioniert soweit alles reibungslos.

Allerdings habe ich das Dovecot Zeitproblem und ich bekomme es einfach nicht in den Griff (habe die anderen Threads schon durchgearbeitet).

Auszug MailLog:

[2013/02/22 17/37/16] Dovecot v1.1.6 starting up
[2013/02/22 18/29/21] Fatal: Time just moved backwards by 28 seconds. This might cause a lot of problems, so I\'ll restart myself now. http://wiki.dovecot.org/TimeMovedBackwards

Auszug Firewall:

[2013/02/22 17/55/43] [FILTER][Pass][LAN/RT/VPN->WAN, 113:28:07.050][@S:R=1:3, <IPmeinerDS>:123->192.53.103.108:123][UDP][HLen=20, TLen=76]
[2013/02/22 18/02/03] [FILTER][Pass][LAN/RT/VPN->WAN, 113:34:27.680][@S:R=1:3, <IPmeinerDS>:123->141.2.22.74:123][UDP][HLen=20, TLen=76]
[2013/02/22 18/27/31] [FILTER][Pass][LAN/RT/VPN->WAN, 113:59:56.060][@S:R=1:3, <IPmeinerDS>:123->141.2.22.74:123][UDP][HLen=20, TLen=76]
[2013/02/22 18/27/31] [FILTER][Pass][LAN/RT/VPN->WAN, 113:59:56.060][@S:R=1:3, <IPmeinerDS>:123->192.53.103.108:123][UDP][HLen=20, TLen=76]
[2013/02/22 18/27/33] [FILTER][Pass][LAN/RT/VPN->WAN, 113:59:58.060][@S:R=1:3, <IPmeinerDS>:123->195.145.119.188:123][UDP][HLen=20, TLen=76]

Wie man sehen kann, wird Dovecot um 17:37 Uhr gestartet. Der NTP-Daemon der DS (ntp - 4.2.6.2-2 ist installiert) aktualisiert nun (hoffe ich )
Nun stellt Dovecot bereits nach weniger als einer Stunde eine Zeitdifferenz von -28 Sekunden fest.
Da frage ich mich doch, ob die DS die Zeit einfach nur grob schätzt. Es kann doch eigentlich nicht sein, das in 50 Minuten der Zeitgeber der DS so eine Abweichung hat.

Die Folge ist, das Dovecot nach der Abweichung nicht mehr startet.

Hat jemand eine Idee, an welcher Stelle ich suchen soll um das Problem zu beheben ?

Gruß,
Andreas

 

[Basalt]

My Syno also runs too fast, but "only" 1...2 secs/hour (depending of the cpu load ?). Dovecot does not die because of that.

Do you use a reliable/stable/local NTP server?
It is also recommended to perform one manual sync after you entered the url.

BR,
Erik

 

[JudgeDredd]

Hi Erik,

Do you use a reliable/stable/local NTP server?

Yes, "ntp - 4.2.6.2-2" via IPKG is installed.

Output of "ntpq -p":

remote refid st t when poll reach delay offset jitter
==============================================================================
LOCAL(0) .LOCL. 10 l 770 64 0 0.000 0.000 0.000
+chaplin.rz.uni- 217.91.28.79 2 u 68 128 377 6.419 2.315 0.263
*ntp1.sda.t-onli .PPS. 1 u 121 128 377 7.226 -1.110 2.856
+ptbtime1.ptb.de .PTB. 1 u 74 128 377 26.671 -0.348 1.695

It is also recommended to perform one manual sync after you entered the url.

How did i perform i manual sync and which URL did you mean ?

Here my Log from this evening: (in the end Dovecot died ...)

[2013/02/22 19/31/22] Fatal: Time just moved backwards by 29 seconds. This might cause a lot of problems, so I\'ll restart myself now. http://wiki.dovecot.org/TimeMovedBackwards
[2013/02/22 19/31/24] Dovecot v1.1.6 starting up
[2013/02/22 20/30/06] Fatal: Time just moved backwards by 29 seconds. This might cause a lot of problems, so I\'ll restart myself now. http://wiki.dovecot.org/TimeMovedBackwards
[2013/02/22 20/30/08] Dovecot v1.1.6 starting up
[2013/02/22 21/27/45] Fatal: Time just moved backwards by 29 seconds. This might cause a lot of problems, so I\'ll restart myself now. http://wiki.dovecot.org/TimeMovedBackwards
[2013/02/22 21/27/47] Dovecot v1.1.6 starting up
[2013/02/22 22/35/11] Fatal: Time just moved backwards by 29 seconds. This might cause a lot of problems, so I\'ll restart myself now. http://wiki.dovecot.org/TimeMovedBackwards
[2013/02/22 22/35/13] Dovecot v1.1.6 starting up
[2013/02/22 23/33/47] Fatal: Time just moved backwards by 29 seconds. This might cause a lot of problems, so I\'ll restart myself now. http://wiki.dovecot.org/TimeMovedBackwards

 

[Basalt]

I use the standard NTP from DSM, think it is part of the Local settings (cannot access my Syno from where I am).
On that window, you can enter the url of the NTP (-pool) and a button to perform a manual sync.
Looks like this is something different than the IPKG you are using, do you (still) need that one?

About the log: do you restart Dovecot every time, or is it done automatically?

Erik

 

[jahlives]

@basalt
it depends on if the time moves forward or backwards to kill dovecot. Backwards can make a hell lot of problems ;-)
@Judge
I'm not sure if ntp can run properly on DS nowadays. I tried with ntp a while ago, but ntp was unable to slow down/speed up the internal time signal. So it was not possible to keep the time accurate with NTP-Daemon. Latest after 12hours my dovecot crashed. The only working solution I found was to use ntpdate via cron and execute the job every 30min. To get ntp woreking on a Diskstation the system must support the processing of pps signals. At least when I tried that last time the firmware did not support pps

 

[JudgeDredd]

So, ich bin zwar noch in der Beobachtungsphase, aber zur Zeit kommen keine Dovecot Abstürze/Zeitdiff mehr.

Was habe ich gemacht:
1.) Den IPKG ntp server erstmal deinstalliert.
2.) Im DSM unter "Systemsteuerung->Regionale optionen->Uhrzeit" auf manuell gestellt.
3.) Im DSM unter "Systemsteuerung->Regionale optionen->NTP Dienst" aktiviert
4.) Diese Konfig im groben in die "etc/ntp.conf" übernommen.

Mir ist klar, das die Konfig in etc/ntp.conf leider ein Update nicht übersteht. In diesem Fall muss ich da wohl wieder ran.

In meinem Firewall-LOG und ntp.log sehe ich, das die DS regelmaessig die eingetragenen NTP-Server kontaktiert. Leider kann ich nicht sicher erkennen, ob hier tatsächlich auch die Systemzeit angepasst wird.
Im meiner "mail.log" kommt derzeit keinerlei Meldung von Dovecot, das es zu Zeitsprünge kommt. Allerdings auch keine Meldung, das es keine gibt.
Wie gesagt, bin ich da noch in der Beobachtungsphase ...

 

[Spooky_]

Nur als Zusatzinfo: im Forum auf synology.com gibt es auch einen großen Thread dazu: http://forum.synology.com/enu/viewtopic.php?f=132&t=56081
Hier werden auch diverse Lösungsmöglichkeiten diskutiert, unter anderem zB dieser Zusatz in der NTP config: http://forum.synology.com/enu/viewtopic.php?f=132&t=56081&start=90#p225002 Soweit ich das verstanden habe, soll das verhindern, dass die Zeit wirklich *zurück*gesetzt wird und wenn dann nur "stehen" bleibt.

 

[JudgeDredd]

Nach nun ein paar Wochen Beobachtungsphase, ist es bei mir so, das Dovecot zwar nie mehr abgebrochen hat, aber komischer Weise, entfernt sich Dovecot nach jedem "Time moved Backwards ..." mehr von der Realzeit, startet aber immer nach ca. 2Sekunden neu. So langsam steige ich da nicht mehr durch.

[2013/03/18 01/23/56] Fatal: Time just moved backwards by 76 seconds. This might cause a lot of problems, so I\'ll restart myself now. http://wiki.dovecot.org/TimeMovedBackwards
[2013/03/18 01/23/58] Dovecot v1.1.6 starting up
[2013/03/18 02/25/55] Fatal: Time just moved backwards by 77 seconds. This might cause a lot of problems, so I\'ll restart myself now. http://wiki.dovecot.org/TimeMovedBackwards
[2013/03/18 02/25/58] Dovecot v1.1.6 starting up
[2013/03/18 03/32/04] Fatal: Time just moved backwards by 76 seconds. This might cause a lot of problems, so I\'ll restart myself now. http://wiki.dovecot.org/TimeMovedBackwards
[2013/03/18 03/32/06] Dovecot v1.1.6 starting up
[2013/03/18 04/29/45] Fatal: Time just moved backwards by 76 seconds. This might cause a lot of problems, so I\'ll restart myself now. http://wiki.dovecot.org/TimeMovedBackwards
[2013/03/18 04/29/47] Dovecot v1.1.6 starting up
[2013/03/18 05/32/25] Fatal: Time just moved backwards by 77 seconds. This might cause a lot of problems, so I\'ll restart myself now. http://wiki.dovecot.org/TimeMovedBackwards
[2013/03/18 05/32/27] Dovecot v1.1.6 starting up
[2013/03/18 06/27/55] Fatal: Time just moved backwards by 78 seconds. This might cause a lot of problems, so I\'ll restart myself now. http://wiki.dovecot.org/TimeMovedBackwards
[2013/03/18 06/27/58] Dovecot v1.1.6 starting up
[2013/03/18 07/29/46] Fatal: Time just moved backwards by 77 seconds. This might cause a lot of problems, so I\'ll restart myself now. http://wiki.dovecot.org/TimeMovedBackwards
[2013/03/18 07/29/48] Dovecot v1.1.6 starting up
[2013/03/18 08/27/11] Fatal: Time just moved backwards by 78 seconds. This might cause a lot of problems, so I\'ll restart myself now. http://wiki.dovecot.org/TimeMovedBackwards
[2013/03/18 08/30/51] Dovecot v1.1.6 starting up

 

[jahlives]

~75 Sekunden ist aber sehr sehr viel. Wie oft machst du denn den Sync mit einem ntp Server? Da du scheinbar den ntp Daemon und nicht ntpdate verwendest kannst du z.B. mal mit

ntpq -pn

die Details zur sync anschauen. Sind dort grosse Offsets zu den ntp Servern?

 

[JudgeDredd]

Richtig, früher ist Dovecot auch spätestens bei ~20 Sekunden Abweichung "gestorben". Da Dovecot aber jetzt immer nach ~3 Sekunden neustartet traue ich der Differenz von >70 Sek. im Log nicht so recht.

ntpq -pn liefert:

remote refid st t when poll reach delay offset jitter
==============================================================================
-141.2.22.74 217.91.28.79 2 u 61 64 377 6.950 -78631. 1.489
*195.145.119.188 .PPS. 1 u 42 64 377 7.052 -78618. 1.220
+88.198.9.164 81.94.123.17 2 u 40 64 377 15.728 -78616. 1.732
-87.139.126.233 217.91.44.17 2 u 35 64 377 112.774 -78621. 6.350
+213.95.21.43 62.128.1.18 3 u 39 64 377 8.933 -78618. 2.801
-141.82.25.202 130.149.17.8 2 u 43 64 377 25.398 -78625. 1.370
127.127.1.0 .LOCL. 12 l 394 64 300 0.000 0.000 0.004

 

[jahlives]

also diese offsets sprechen für sich Wenn du nicht sicher bist ob die 78 Sekunden stimmen, dann stoppe den ntp Daemon und mach manuell ein ntpdate -b 0.ch.pool.ntp.org das sollte dir anzeigen um wieviel die Zeit verändert werden musste.
So langsam habe ich dasw Gefühl Synology hat hier etwas nicht fertig gedacht: es war früher so, dass ein ntp Daemon auf einer DS nie ging, weil die Module fehlten, welche das Taktsignal der Uhr ändern konnten. Scheinbar haben sie das nachgerüstet, nur frage ich mich ob sie das für alle Modelle (Prozessorarchitekturen) auch gemacht haben. Der ntp Daemon versuucht durch Anpassung des Taktsignals die Zeit konstant zu halten. Kann er das Signal nicht ändern, dann kann er auch die Zeit nicht halten

 

[JudgeDredd]

Da ich nicht so der Offset Experte bin sind die Werte für mich nicht sehr aussagekräftig.
Ich habe den Daemon mal gestoppt und ein "ntpdate -b 0.ch.pool.ntp.org" ausgeführt.
Hier das Ergebnis:

[18 Mar 13:58:50 ntpdate[30923]: step time server 82.220.2.2 offset 0.002206 sec

Was liest man denn nun aus den Daten raus ?

 

[jahlives]

aus diesen Daten würde ich lesen, dass der ntp Daemon wohl einen Schuss in der Socke hat. K.A. wieso der von einem so grossen Offset ausgeht. Spricht denn für dich etwas dagegen den ntpdate zu nutzen und einfach sehr oft die Zeit zu syncen? z.B. alle 10 Minuten oder so, dann können die Offsets nie so gross werden, dass es Dovecot totschiessen würde

 

[JudgeDredd]

Nunja, die Entscheidung für den Daemon war eigentlich, das ich gerne meine IP-Geräte mit der DS syncen will und nicht jedes Gerät für den externen NTP Zugriff freischalten wollte.

 

[jahlives]

und du willst deine Clients wirklich an einem ntp syncen, der glaubt 78 Sekunden daneben zu liegen?? ;-)

 

[JudgeDredd]

So, nachdem ich nun auf das letzte DSM Release migriert habe, scheinen die NTP Differenzen konstant zu bleiben.

[2013/04/06 07/46/18] Fatal: Time just moved backwards by 149 seconds. This might cause a lot of problems, so I\'ll restart myself now. http://wiki.dovecot.org/TimeMovedBackwards
[2013/04/06 07/46/20] Dovecot v1.1.6 starting up
[2013/04/06 08/17/31] Fatal: Time just moved backwards by 149 seconds. This might cause a lot of problems, so I\'ll restart myself now. http://wiki.dovecot.org/TimeMovedBackwards
[2013/04/06 08/17/33] Dovecot v1.1.6 starting up
[2013/04/06 09/17/09] Fatal: Time just moved backwards by 149 seconds. This might cause a lot of problems, so I\'ll restart myself now. http://wiki.dovecot.org/TimeMovedBackwards
[2013/04/06 09/17/11] Dovecot v1.1.6 starting up
[2013/04/06 09/49/30] Fatal: Time just moved backwards by 149 seconds. This might cause a lot of problems, so I\'ll restart myself now. http://wiki.dovecot.org/TimeMovedBackwards
[2013/04/06 09/49/32] Dovecot v1.1.6 starting up

Allerdings hat es sich jetzt bei 149 Sekunden "eingependelt" obwohl Dovecot ja nach ~2 Sek. neu startet. (vorher hat er sich nach und nach von ~1 Sek. auf ~149 Sek. hochgearbeitet)
Kann mir jemand sagen, wie ich die "149 seconds" wieder "reseten" kann, so dass Dovercot wieder bei ~1 Sek. anfängt ?

 

[JudgeDredd]

Leider muss ich nochmal um Unterstützung bitten. Ich hatte heute ein wenig Zeit nochmal bzgl. meines Zeitproblems zu forschen.

Zuerst habe ich im DSM:
- Die Zeiteinstellung auf manuell gestellt
- Den NTP Dienst deaktiviert

Dann in der Konsole:
- mit "killall ntp" den Daemon beendet.
- mit ntpdate <externer ntp-server> die Zeit gesynct. (Ergebnis war korrekt)

Nach ca. 1 Minute mit "date" wieder die Zeit gecheckt. (Ergebnis war korrekt)
Nach ca. 10 Minuten mit "date" wieder die Zeit gecheckt. (Ergebnis war wieder eine Differenz von ~150 Sekunden) !!!

Jetzt frage ich mich, obwohl kein Crontab und kein Daemon die Zeit synct, wer oder was in der DS ändert da die Zeit ?
Hat hierzu vielleicht irgendjemand noch eine Stelle wo ich suchen könnte ?

Gruß Andreas

 

[Basalt]

- mit "killall ntp" den Daemon beendet.

I assume you mean "killall ntpd" ?
Did you check with "ps|grep ntp" if all processes were killed indeed ?
...and did not came alive after some time

Erik.

 

[JudgeDredd]

Hi Erik,

of course "ntpd". Sorry the "d" must be lost in subspace

I already did it like you said ... ("ps|grep ntp" every time before and after i checked the DS-Time with "date")
There is no remaining process for ntp service ...

Any other ideas ?

 

[Basalt]

Looks like a bug to me, maybe Synology uses an alogrithm to adjust clock speed based on (older?) ntp info ?
I would submit a support ticket to Synology.
They don't reply very fast, by hey, what is time when you gain 149 seconds that many times a day

Best regards,
Erik.