Pesanan syslog yang aneh

26

Pernahkah Anda melihat urutan yang salah di / var / log / syslog? Saya pikir itu hanya menyebabkan crontab.

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)

Seperti yang Anda lihat, ada urutan waktu yang buruk antar baris. Itu hanya kesalahan crontab, atau ada beberapa masalah dengan jam sistem, atau sesuatu?

Saya menjalankan kernel Ubuntu 14.04 terbaru:

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
sumber
1
Apakah pesanannya salah atau hanya cap waktu? Dalam kasus pertama syslog harus bertanggung jawab, jika tidak cron atau jam Anda.
Menang
Sepertinya log lain kecuali cron OK. Saya menambahkan waktu logging dengan skrip "sesuatu", bukan hanya cron dan syslog.
Tomasz
1
Ini terjadi pada sebuah kotak milik saya yang menjalankan fedora, ternyata rsyslog telah macet, tetapi journald menyimpan log yang memang diteruskan ke rsyslog setelah dimulai, mengakibatkan pesan lama tercampur dengan yang baru. Ini berada di fedora 20 sekalipun.
Petter H
2
Saya mengalami masalah yang sama pada Ubuntu 14.04. Sekitar 1 dari setiap 20 pesan cron memiliki tanggal sekitar 5-7 hari di masa lalu. Hanya dengan pesan cron dan masalah berlanjut setelah reboot.
Jasper Krijgsman
1
Saya dapat mengkonfirmasi masalah ini, Ubuntu 14.04, yang terjadi dengan pesan cron.
thor

Jawaban:

28

Ini adalah trik yang rumit, dan ini sebenarnya bug di rsyslog, khususnya RepeatMsgReduction On, dan perubahan perilaku dengan versi yang dirilis dengan Trusty (dibandingkan dengan versi sebelumnya)

Lihat http://bugzilla.adiscon.com/show_bug.cgi?id=527 untuk detail berdarah.

Singkatnya, matikan RepucedMsgReduction on Trusty (ie sudo sed -i -r 's/^\$RepeatedMsgReduction.*/$RepeatedMsgReduction off/' /etc/rsyslog.conf). Itu tidak membantu, dan melakukan hal-hal bodoh.

Craig Miskell
sumber
2
Tolong jangan posting jawaban yang sama persis dua kali. Gunakan komentar sebagai gantinya untuk merujuk ke jawaban pertama Anda. Opsi terbaik adalah memilih yang hampir sama.
Sven
Ironi ... sepertinya Anda ingin RepeatMsgReduction diaktifkan! : D
Penguin Anonim
Apa yang membuat Anda mengatakan itu, Penguin Anonim?
Craig Miskell
1
Tampaknya versi 7.4.4-1ubuntu2.7 dari paket rsyslog memperbaiki masalah ini: changelogs.ubuntu.com/changelogs/pool/main/r/rsyslog/…
Mauro Molinari
0

Apakah mesin Anda mungkin restart tepat saat itu? Berdasarkan log Anda, nampaknya cron dihidupkan ulang setidaknya. Mungkin saja Anda memiliki baterai CMOS yang buruk, dan mesin Anda tidak menjaga waktu yang akurat saat dimatikan.

Apakah ini mesin virtual? Mungkin dimigrasi ke host yang berbeda, dan jam menjadi salah selama migrasi?

Apakah skrip 'sesuatu' Anda mungkin mengatur waktu secara tidak sengaja?

Sungguh, ini bisa sejumlah hal. Ini bukan perilaku normal.

devicenull
sumber
sepertinya hanya masalah syslog, karena saya membuat hak masuk independen untuk menjalankan script semut itu terlihat baik-baik saja, tanpa spasi, atau urutan campuran.
Tomasz