The OpenNET Project / Index page

[ новости/++ | форум | wiki | теги | ]



"Странные даты в логах"
Вариант для распечатки  
Пред. тема | След. тема 
Форум Открытые системы на сервере (Мониторинг, логи / Linux)
Изначальное сообщение [ Отслеживать ]

"Странные даты в логах"  +/
Сообщение от uber (ok), 09-Окт-18, 08:10 
После внезапной перезагрузки, причем как обрубило, непонятно почему вообще сервер перегружался, в логах появилась врезка с датами Sep 19 22:25:01 и Sep 20 01:25:01, в промежутке между Oct  8 01:55:01 и Oct  8 02:15:01. Это лог cron, периодичность работы - 5 минут.


В логе messages, после обычной работы системы вплоть до Oct  8 01:59:12 и сразу после этого вижу это:
Oct  8 02:00:00 localhost rsyslogd: [origin software="rsyslogd" swVersion="8.8.0" x-pid="703" x-info="http://www.rsyslog.com"] start

потом, после обычной загрузки системы в логах, после Oct  8 02:00:01 вижу это:

Sep 19 22:23:46
то есть врезается кусок старого лога, вплоть до:

Sep 20 01:27:36

и после этого, как ни в чем не бывало:

Oct  8 02:00:10

То есть за девять секунд в лог messages попало двое суток старых записей.
Что это может быть?

если поможет разобраться, в логе cacti
2018/10/08 02:05:04 - POLLER: Poller[1] WARNING: Cron is out of sync with the Poller Interval!  The Poller Interval is '300' seconds, with a maximum of a '300' second Cron, but 603.7 seconds have passed since
the last poll!

Ответить | Правка | Cообщить модератору

Оглавление

Сообщения по теме [Сортировка по времени | RSS]


1. "Странные даты в логах"  +/
Сообщение от Andrey (??), 09-Окт-18, 10:19 
> После внезапной перезагрузки, причем как обрубило, непонятно почему вообще сервер перегружался,
> в логах появилась врезка с датами Sep 19 22:25:01 и Sep
> 20 01:25:01, в промежутке между Oct  8 01:55:01 и Oct
>  8 02:15:01. Это лог cron, периодичность работы - 5 минут.

...
> Что это может быть?

Все что угодно. По имеющейся тут информации можно предположить что лог-файл был заблокирован на запись и процесс, который хотел писать в него, просто копил данные в кеше. Как только файл освободился - сбросил весь кеш на диск.

Такие-же симптомы возможны при перезагрузке, когда NTP не успел получить текущее время и дата-время берется из BIOS.
Посмотрите какому поцессу принадлежат врезанные логи.

Ответить | Правка | ^ к родителю #0 | Наверх | Cообщить модератору

2. "Странные даты в логах"  +/
Сообщение от uber (ok), 09-Окт-18, 14:35 
>[оверквотинг удален]
>>  8 02:15:01. Это лог cron, периодичность работы - 5 минут.
> ...
>> Что это может быть?
> Все что угодно. По имеющейся тут информации можно предположить что лог-файл был
> заблокирован на запись и процесс, который хотел писать в него, просто
> копил данные в кеше. Как только файл освободился - сбросил весь
> кеш на диск.
> Такие-же симптомы возможны при перезагрузке, когда NTP не успел получить текущее время
> и дата-время берется из BIOS.
> Посмотрите какому поцессу принадлежат врезанные логи.

Спасибо за ответ.
Куски скопированы из реальных логов, из более ранних дат, один в один, я проверял. Такие участки copy-past вижу в логе cacti, messages, dhcp-server.log везде родной процесс.

boot.log (Modify time) Oct  8 01:59 подтверждает перегрузку указанное время.
Перегрузка была аварийной, причин не нашел, похоже на потерю питания, но подтверждения найти не смог.

Проверил даты изменения файлов в системе, на всякий случай, - нет ничего подозрительного.
Получается последовательность такая:
1. аварийная перезагрузка
2. в логи попадает информация с более ранней датой, полностью соответствующая уже существующей в архивных логах.
3. далее работа нормализуется

Ответить | Правка | ^ к родителю #1 | Наверх | Cообщить модератору

Архив | Удалить

Рекомендовать для помещения в FAQ | Индекс форумов | Темы | Пред. тема | След. тема


  Закладки на сайте
  Проследить за страницей
Created 1996-2018 by Maxim Chirkov  
ДобавитьПоддержатьВебмастеруГИД  
Hosting by Ihor