себя. Неприлично долго. И по логу есть такое ощущение, что он не с WAL-ы накатывает/откатывает, а занимается хз чем. Кто может дать направление куда копать?
2021-11-25 09:26:27 MSK [3948] СООБЩЕНИЕ: работа системы БД была прервана; последний момент работы: 2021-11-25 09:01:02 MSK
2021-11-25 09:26:27 MSK [3100] [user=wuser,host=::1,db=wcache2,app=[н/д]] ВАЖНО: система баз данных запускается
2021-11-25 09:26:27 MSK [3988] [user=wuser,host=::1,db=wcache2,app=[н/д]] ВАЖНО: система баз данных запускается
...
2021-11-25 10:28:32 MSK [7060] [user=Администратор,host=::1,db=Администратор,app=[н/д]] ВАЖНО: система баз данных запускается
2021-11-25 10:34:36 MSK [3948] СООБЩЕНИЕ: система БД была остановлена нештатно; производится автоматическое восстановление
2021-11-25 10:35:23 MSK [3948] СООБЩЕНИЕ: запись REDO начинается со смещения 78/AE10C870
2021-11-25 10:39:06 MSK [3948] СООБЩЕНИЕ: неверная длина записи по смещению 78/F1CD15F8: ожидалось 24, получено 0
2021-11-25 10:39:06 MSK [3948] СООБЩЕНИЕ: записи REDO обработаны до смещения 78/F1CD15D0
2021-11-25 10:42:46 MSK [3948] СООБЩЕНИЕ: checkpoint starting: end-of-recovery immediate
2021-11-25 10:43:43 MSK [3948] СООБЩЕНИЕ: checkpoint complete: wrote 85038 buffers (64.9%); 0 WAL file(s) added, 4 removed, 63 recycled; write=1.708 s, sync=54.029 s, total=57.228 s; sync files=9714, longest=5.444 s, average=0.006 s; distance=1109779 kB, estimate=1109779 kB
2021-11-25 10:43:45 MSK [1644] СООБЩЕНИЕ: система БД готова принимать подключения
Вот с 9:26 до 10:34 чем он занимался?
Думаю что информации мало чем он занимался. Логи за это время есть? Какая версия? антивир?
Как бы это и есть лог. Или ты Виндовый журнал имеешь ввиду? В месте многоточия одни и те же сообщения., как те что выше и ниже многоточия. ПГ 12.4. Есть Kaspersky Endpoint Security.
прям одни и теже? PID процесса вроде ж меняется. То есть скорее это попытка запустить сервис и потом она отваливает. Снова попытка и снова отваливается.
Строки между 9:26 и 10:34 это попытки начать сессию. Сообщения выдаются клиентам, ну и логируются. Разные pid - очевидно pid-ы запускающихся backend-ов клиентов, пытающихся подключиться к БД. 3948 - видимо что-то стартовое, его сейчас нет. А 1644 видимо родительский процесс, он и сейчас есть. Первая запись - это первая запись в логе, без сокращений. Сокращены лишь единообразные записи попыток клиентов подключиться.
Тут даже два интересных пропуска, кажется: 09:26:27 MSK [3948] СООБЩЕНИЕ: работа системы БД была прервана; последний момент работы: 2021-11-25 09:01:02 MSK <первый> 10:34:36 MSK [3948] СООБЩЕНИЕ: система БД была остановлена нештатно; производится автоматическое восстановление 10:35:23 MSK [3948] СООБЩЕНИЕ: запись REDO начинается со смещения 78/AE10C870 10:39:06 MSK [3948] СООБЩЕНИЕ: неверная длина записи по смещению 78/F1CD15F8: ожидалось 24, получено 0 10:39:06 MSK [3948] СООБЩЕНИЕ: записи REDO обработаны до смещения 78/F1CD15D0 <второй> 10:42:46 MSK [3948] СООБЩЕНИЕ: checkpoint starting: end-of-recovery immediate 10:43:43 MSK [3948] СООБЩЕНИЕ: checkpoint complete: wrote 85038 buffers (64.9%); 0 WAL file(s) added, 4 removed, 63 recycled; write=1.708 s, sync=54.029 s, total=57.228 s; sync files=9714, longest=5.444 s, average=0.006 s; distance=1109779 kB, estimate=1109779 kB 10:43:45 MSK [1644] СООБЩЕНИЕ: система БД готова принимать подключения В первый, казалось бы, из длительных процессов может происходить fsync data directory, а во второй — всякие "нестандартные" вещи (вроде реинициализации unlogged tables, обработки prepared transactions и т.п.). Может, там просто в кластере баз (data directory) очень много файлов?
Если бы это был не постгрес, а что другое — я бы первым делом пошёл проверять, не ломится ли он в dns... Но тут... Шансы есть и на это, но скорее — сразу брать трейсер/профайлер и выяснять чем он конкретно занимается.
Ну и ещё вариант — windows же "умеет" блокировать файлы при попытке одновременного доступа (и даже, если не вру, делает это по умолчанию), так? Т.е. если какая-то другая программа (антивирус, например) "лезет" в файлы PostgreSQL — могут происходить всякие "странные" вещи.
Эм... Лет так... С 95й венды.
Тогда была бы ошыбка открытия файла.
Может быть, и не было бы — я код postgres не читал (может, он повторяет попытки открытия на windows вообще, или только при fsync-ах). Если кто-то читал / знает — подскажите @pfunk21, чтобы они [не] смотрели в этом направлении.
Обсуждают сегодня