Seltsame Syslog-Reihenfolge

26

Haben Sie jemals eine falsche Reihenfolge in / var / log / syslog gesehen? Ich denke, dass es nur Crontab verursacht.

Jun 28 22:20:01 alex CRON[2327]: (root) CMD (something > /dev/null)
Jun 28 22:21:01 alex CRON[2700]: (root) CMD (something > /dev/null)
Jun 21 03:49:01 alex CRON[2753]: (root) CMD (something > /dev/null)
Jun 28 22:23:01 alex CRON[2754]: (root) CMD (something > /dev/null)
Jun 27 05:08:01 alex CRON[2813]: (root) CMD (something > /dev/null)
Jun 26 00:19:01 alex CRON[2816]: (root) CMD (something > /dev/null)
Jun 28 22:25:01 alex CRON[2816]: (munin) CMD (if [ -x /usr/bin/munin-cron ]; then /usr/bin/munin-cron; fi)
Jun 28 22:25:01 alex CRON[2821]: (root) CMD (something > /dev/null)
Jun 28 22:27:01 alex CRON[3225]: (root) CMD (something > /dev/null)
Jun 28 22:28:01 alex CRON[3240]: (root) CMD (something > /dev/null)
Jun 28 22:30:01 alex CRON[3290]: (root) CMD (something > /dev/null)
Jun 26 12:03:01 alex CRON[3291]: message repeated 2 times: [ (root) CMD (something > /dev/null)]
Jun 28 22:30:01 alex CRON[3291]: (munin) CMD (if [ -x /usr/bin/munin-cron ]; then /usr/bin/munin-cron; fi)
Jun 28 22:31:01 alex CRON[3659]: (root) CMD (something > /dev/null)
Jun 28 22:32:01 alex CRON[3754]: (root) CMD (something > /dev/null)
Jun 16 06:41:01 alex CRON[3846]: (root) CMD (something > /dev/null)
Jun 28 22:35:58 alex cron[909]: (CRON) INFO (pidfile fd = 3)
Jun 28 22:35:58 alex cron[944]: (CRON) STARTUP (fork ok)
Jun 28 22:35:58 alex cron[944]: (CRON) INFO (Running @reboot jobs)
Jun 28 22:36:01 alex CRON[1428]: (root) CMD (something > /dev/null)
Jun 28 22:37:01 alex CRON[3050]: (root) CMD (something > /dev/null)
Jun 28 22:38:01 alex CRON[3082]: (root) CMD (something > /dev/null)
Jun 28 22:39:01 alex CRON[3114]: (root) CMD (something > /dev/null)

Wie Sie sehen, gibt es zwischen den Zeilen eine falsche Zeitreihenfolge. Es ist nur ein Crontab-Fehler oder gibt es ein Problem mit der Systemuhr oder so?

Ich verwende den neuesten Ubuntu 14.04-Kernel:

Linux alex 3.13.0-30-generic #54-Ubuntu SMP Mon Jun 9 22:45:01 UTC 2014 x86_64 x86_64 x86_64 GNU/Linux
Tomasz
quelle
1
Ist die Reihenfolge wirklich falsch oder nur die Zeitstempel? Im ersten Fall sollte syslog zuständig sein, ansonsten cron oder deine Uhr.
Wonce
Es sieht so aus, als wären die anderen Protokolle außer cron in Ordnung. Ich habe die Protokollierungszeit durch ein "etwas" -Skript hinzugefügt, nicht nur durch cron und syslog.
Tomasz
1
Dies geschah auf einer meiner Kisten, auf denen Fedora lief. Es stellte sich heraus, dass rsyslog abgestürzt war, aber Journald verwaltete die Protokolle, die es nach dem Start an rsyslog weiterleitete, so dass alte Nachrichten mit neuen gemischt wurden. Dies war allerdings in Fedora 20.
Petter H
2
Ich habe das gleiche Problem bei Ubuntu 14.04 festgestellt. Ungefähr 1 von 20 Cron-Nachrichten hat ein Datum von ungefähr 5 bis 7 Tagen in der Vergangenheit. Nur bei cron-Meldungen bleibt das Problem nach einem Neustart bestehen.
Jasper Krijgsman
1
Ich kann dieses Problem bestätigen, Ubuntu 14.04 passiert mit Cron-Nachrichten.
Thor

Antworten:

28

Dies ist eine heikle Angelegenheit, und es handelt sich tatsächlich um einen Fehler in rsyslog, insbesondere bei RepeatedMsgReduction On, und um eine Verhaltensänderung gegenüber der mit Trusty veröffentlichten Version (im Vergleich zu früheren Versionen).

Weitere Informationen finden Sie unter http://bugzilla.adiscon.com/show_bug.cgi?id=527 .

Kurz gesagt, deaktivieren Sie RepeatedMsgReduction auf Trusty (dh sudo sed -i -r 's/^\$RepeatedMsgReduction.*/$RepeatedMsgReduction off/' /etc/rsyslog.conf). Es ist nicht hilfreich und macht dumme Sachen.

Craig Miskell
quelle
2
Bitte posten Sie nicht genau dieselbe Antwort zweimal. Verwenden Sie stattdessen Kommentare, um auf Ihre erste Antwort zu verweisen. Die beste Option ist, für den Abschluss als Duplikat zu stimmen.
Sven
Die Ironie ... sieht so aus, als ob Sie RepeatedMsgReduction aktivieren möchten! : D
Anonymous Penguin
Warum sagst du das, Anonymous Penguin?
Craig Miskell
1
Scheint, dass Version 7.4.4-1ubuntu2.7 des rsyslog-Pakets dieses Problem behebt: changelogs.ubuntu.com/changelogs/pool/main/r/rsyslog/…
Mauro Molinari
0

Hat Ihre Maschine dann vielleicht gleich neu gestartet? Basierend auf Ihrem Protokoll scheint es, dass cron mindestens neu gestartet wurde. Es ist möglich, dass Sie eine schlechte CMOS-Batterie haben und Ihr Computer beim Ausschalten nicht die genaue Zeit anzeigt.

Ist das eine virtuelle Maschine? Vielleicht wurde es auf einen anderen Host migriert, und die Uhrzeit wurde während der Migration falsch?

Stellt Ihr "etwas" -Skript möglicherweise die Uhrzeit aus Versehen ein?

Wirklich, das kann eine beliebige Anzahl von Dingen sein. Dies ist jedoch kein normales Verhalten.

Gerät
quelle
Es scheint nur ein Syslog-Problem zu sein, da ich eine unabhängige Anmeldung für das Ausführen des Skripts vorgenommen habe und es gut aussieht, ohne Leerzeichen oder in gemischter Reihenfolge.
Tomasz