Почему при перезагрузке postgres ошибка в то время как база работает?

Question

[Shurik]

Здравствуйте. Столкнулся с проблемой перезагрузки бд postgres.
Перезагружаю командой sudo service postgresql-9.6.service reload
Выдает ошибку:

Redirecting to /bin/systemctl reload postgresql-9.6.service
Job for postgresql-9.6.service invalid.

В то же самое время база продолжает работать (отвечает на запросы к ней).
По команде systemctl list-units --type service выдает -

postgresql.service                 loaded failed failed  PostgreSQL database server

Что может быть не так? Раньше все работало исправно и перезагружалось.

Answer 1

[BorLaze]

reload - это не рестарт, это указание перечитать конфиги.

рестарт sudo service postgresql restart работает?

Comments

[Shurik]

Нет. На каком то этапе моих мучений на попытку перезапустить сервер начал выдавать ошибку

Redirecting to /bin/systemctl restart postgresql-9.6.service
Job for postgresql-9.6.service failed because the control process exited with error code. See "systemctl status postgresql-9.6.service" and "journalctl -xe" for details.

Повторяюсь, PG при этом продолжает работать!

[BorLaze]

Shurik, ну, а systemctl status postgresql-9.6.service и journalctl -xe что в этот момент пишут?

Как же трудно помогать партизанам... :-)

[Shurik]

BorLaze, партизанам))))
Итак:
systemctl status postgresql-9.6.service
пишет:

● postgresql-9.6.service - PostgreSQL 9.6 database server
   Loaded: loaded (/usr/lib/systemd/system/postgresql-9.6.service; enabled; vendor preset: disabled)
   Active: failed (Result: exit-code) since Чт 2021-08-26 01:08:18 MSK; 19h ago
     Docs: https://www.postgresql.org/docs/9.6/static/
  Process: 17245 ExecStart=/usr/pgsql-9.6/bin/postmaster -D ${PGDATA} (code=exited, status=1/FAILURE)
  Process: 17239 ExecStartPre=/usr/pgsql-9.6/bin/postgresql96-check-db-dir ${PGDATA} (code=exited, status=0/SUCCESS)
 Main PID: 17245 (code=exited, status=1/FAILURE)

авг 26 01:08:18 mail systemd[1]: Starting PostgreSQL 9.6 database server...
авг 26 01:08:18 mail postmaster[17245]: < 2021-08-26 01:08:18.737 MSK > В…ует
авг 26 01:08:18 mail postmaster[17245]: < 2021-08-26 01:08:18.737 MSK > П…a"?
авг 26 01:08:18 mail systemd[1]: postgresql-9.6.service: main process ex...RE
авг 26 01:08:18 mail systemd[1]: Failed to start PostgreSQL 9.6 database...r.
авг 26 01:08:18 mail systemd[1]: Unit postgresql-9.6.service entered fai...e.
авг 26 01:08:18 mail systemd[1]: postgresql-9.6.service failed.
Hint: Some lines were ellipsized, use -l to show in full.

journalctl -xe
пишет:

Процесс запуска юнита user-0.slice был завершен.
-- 
авг 26 20:15:20 mail sshd[3866]: Accepted password for svisch_a from 10.10.11.47 port 36160 ssh2
авг 26 20:15:20 mail systemd[1]: Created slice User Slice of svisch_a.
-- Subject: Запуск юнита user-1008.slice завершен
-- Defined-By: systemd
-- Support: http://lists.freedesktop.org/mailman/listinfo/systemd-devel
-- 
-- Процесс запуска юнита user-1008.slice был завершен.
-- 
-- Результат: done.
авг 26 20:15:20 mail systemd[1]: Starting User Slice of svisch_a.
-- Subject: Начинается запуск юнита user-1008.slice
-- Defined-By: systemd
-- Support: http://lists.freedesktop.org/mailman/listinfo/systemd-devel
-- 
-- Начат процесс запуска юнита user-1008.slice.
авг 26 20:15:20 mail systemd-logind[854]: New session 881935 of user svisch_a.
-- Subject: Для пользователя svisch_a создан новый сеанс 881935
-- Defined-By: systemd
-- Support: http://lists.freedesktop.org/mailman/listinfo/systemd-devel
-- Documentation: http://www.freedesktop.org/wiki/Software/systemd/multiseat
-- 
-- Для пользователя svisch_a создан новый сеанс с идентификатором 881935.
-- 
-- Главный процесс нового сеанса имеет индентификатор 3866.
авг 26 20:15:20 mail systemd[1]: Started Session 881935 of user svisch_a.
-- Subject: Запуск юнита session-881935.scope завершен
-- Defined-By: systemd
-- Support: http://lists.freedesktop.org/mailman/listinfo/systemd-devel
-- 
-- Процесс запуска юнита session-881935.scope был завершен.
-- 
-- Результат: done.
авг 26 20:15:20 mail systemd[1]: Starting Session 881935 of user svisch_a.
-- Subject: Начинается запуск юнита session-881935.scope
-- Defined-By: systemd
-- Support: http://lists.freedesktop.org/mailman/listinfo/systemd-devel
-- 
-- Начат процесс запуска юнита session-881935.scope.
авг 26 20:15:20 mail sshd[3866]: pam_unix(sshd:session): session opened for user svisch_a by (uid=0)
авг 26 20:16:01 mail systemd[1]: Created slice User Slice of root.
-- Subject: Запуск юнита user-0.slice завершен
-- Defined-By: systemd
-- Support: http://lists.freedesktop.org/mailman/listinfo/systemd-devel
-- 
-- Процесс запуска юнита user-0.slice был завершен.
-- 
-- Результат: done.
авг 26 20:16:01 mail systemd[1]: Starting User Slice of root.
-- Subject: Начинается запуск юнита user-0.slice
-- Defined-By: systemd
-- Support: http://lists.freedesktop.org/mailman/listinfo/systemd-devel

Как то так)

[BorLaze]

Shurik, честно говоря, всякое желание продолжать пропало окончательно.

Вот что должно быть понятно из строк

авг 26 01:08:18 mail postmaster[17245]: < 2021-08-26 01:08:18.737 MSK > В…ует
авг 26 01:08:18 mail postmaster[17245]: < 2021-08-26 01:08:18.737 MSK > П…a"?

, например?

Вот это

Hint: Some lines were ellipsized, use -l to show in full.

тебя ни на какие мысли не натолкнуло, прежде чем постить сюда лог?

[Shurik]

BorLaze, прошу прощения. Не знаю почему обрезало код ошибки. Да и с модификатором -l выдает тоже самое. Попробую еще раз тут разместить:
sudo systemctl status postgresql-9.6.service -l

● postgresql-9.6.service - PostgreSQL 9.6 database server
   Loaded: loaded (/usr/lib/systemd/system/postgresql-9.6.service; enabled; vendor preset: disabled)
   Active: failed (Result: exit-code) since Чт 2021-08-26 01:08:18 MSK; 20h ago
     Docs: https://www.postgresql.org/docs/9.6/static/
  Process: 17245 ExecStart=/usr/pgsql-9.6/bin/postmaster -D ${PGDATA} (code=exited, status=1/FAILURE)
  Process: 17239 ExecStartPre=/usr/pgsql-9.6/bin/postgresql96-check-db-dir ${PGDATA} (code=exited, status=0/SUCCESS)
 Main PID: 17245 (code=exited, status=1/FAILURE)

авг 26 01:08:18 mail systemd[1]: Starting PostgreSQL 9.6 database server...
авг 26 01:08:18 mail postmaster[17245]: < 2021-08-26 01:08:18.737 MSK > ВАЖНО:  файл блокировки "postmaster.pid" уже существует
авг 26 01:08:18 mail postmaster[17245]: < 2021-08-26 01:08:18.737 MSK > ПОДСКАЗКА:  Другой экземпляр postmaster (PID 27717) работает с каталогом данных "/var/lib/pgsql/9.6/data"?
авг 26 01:08:18 mail systemd[1]: postgresql-9.6.service: main process exited, code=exited, status=1/FAILURE
авг 26 01:08:18 mail systemd[1]: Failed to start PostgreSQL 9.6 database server.
авг 26 01:08:18 mail systemd[1]: Unit postgresql-9.6.service entered failed state.
авг 26 01:08:18 mail systemd[1]: postgresql-9.6.service failed.

[BorLaze]

Shurik, похоже, в какой-то момент постгрес некорректно завершился, и теперь в postmaster.pid лежит неправильный process id

посмотри параметры запуска постгреса
ps auxwf | grep bin/postgres

должно быть что-то типа

postgres 3285 0.0 0.6 17391484 401724 ? Ss Aug20 2:02 /usr/lib/postgresql/12/bin/postgres -D /var/lib/postgresql/12/main -c config_file=/etc/postgresql/12/main/postgresql.conf

и в каталоге, указанном в опции -D, должен быть файл postmaster.pid (у меня это /var/lib/postgresql/12/main/postmaster.pid)

грохни его и рестартуй постгрес через

sudo service postgresql restart

[Shurik]

BorLaze, спасибо огромное - помогло))
Вот меня удивляло всегда откуда вы(знатоки линукса) все это знаете!? Может посоветуете какую нибудь дельную литературу? Потому как в программировании только последовательное изучение по книгам принесло какие то плоды, думаю с Linux тоже самое плюс минус)

[BorLaze]

Shurik, знаешь, чем "вышка" отличается от школы? В школе учат знаниям, в ВУЗе – учат учиться.

Никто не знает линукс. Просто умеют решать проблемы.

Конкретно в твоем случае – проблема могла быть где угодно. Просто надо было сделать то, то сделал я:

– посмотреть детальные сообщения об ошибках, а не просто перечитывать сообщение о невозможности рестарта

See "systemctl status postgresql-9.6.service" and "journalctl -xe" for details

- обратить внимание на

авг 26 01:08:18 mail postmaster[17245]: < 2021-08-26 01:08:18.737 MSK > ВАЖНО: файл блокировки "postmaster.pid" уже существует
авг 26 01:08:18 mail postmaster[17245]: < 2021-08-26 01:08:18.737 MSK > ПОДСКАЗКА: Другой экземпляр postmaster (PID 27717) работает с каталогом данных "/var/lib/pgsql/9.6/data"?

- сделать выводы

В принципе, софт сам тебе же прямо обо всем этом написал - что процесс 17245 не может работать, поскольку система считает, что с данными работает процесс 27717 (который прописан в postmaster.pid).

В принципе, можно было глянуть еще в содержимое postmaster.pid и увидеть там все тот же 27717, но причина и так очевидна.

Единственное, надо было "знать" - так это иметь представление о том, что такое .pid файл, его смысл и откуда он обычно берется.

В общем... "курите логи, читайте маны" :-)

[Shurik]

BorLaze, понял) ладно буду курить и читать! Ещё раз спасибо))