Windows: Ошибка бэкапа в случае залоченного файла

[ykurenkov]

2019-04-22 23:00:01 RTZ 4 (зима): INFO: Backup start, pg_probackup version: 2.0.27, backup ID: PQDJC1, backup mode: delta, instance: ny1-10, stream: true, remote: false
2019-04-22 23:00:04 RTZ 4 (зима): ERROR: cannot stat file "D:/IBIS_DATA/ny1-10/base/15520165/15522614": Permission denied
2019-04-22 23:00:04 RTZ 4 (зима): WARNING: Backup PQDJC1 is running, setting its status to ERROR
2019-04-22 23:00:04 RTZ 4 (зима): WARNING: backup in progress, stop backup

Я не знаю, что делает postgresql, что лочит иногда вот так эксклюзивно файл. Иногда сталкиваюсь с подобным явлением. Под виндой. Бывает, что лочит на несколько дней. Обычно это лечат перезагрузкой, если долго не отпускает. Попадётся мне живьем, надо oid2name посмотреть что это. Соответственно и pg_probackup не может выполнить бэкап.

[gsmolk]

А сейчас этот файл существует?

[ykurenkov]

Сейчас - нет.

[gsmolk]

А упомянутый лок на несколько дней висит на одном и том же файле?

[ykurenkov]

Пару раз такое встречал. Даже было на нескольких файлах. Причем, после рестарта постгреса эти файлы или становились доступны для чтения или переставали существовать. Даже видел один раз такой лок длительностью несколько недель по времени последней записи в файл. Даже в логах ругань autovacuum была на невозможность получить доступ к файлу. Что в них было (таблицы, индексы, временные таблицы) - трудно предположить, но вероятность того, что это обычные таблицы, скорее всего самая низкая у перечисленых.

[gsmolk]

Вот тут обвиняют в аналогичном поведении антивирус:
https://www.postgresql.org/message-id/flat/9837222c1001310820u4c05a6ccn9aff1d7d3ceb129c%40mail.gmail.com#c4a2799c767cc5a3239edbfa4cdd5ec9

[ykurenkov]

Проверил - стандартный Windows Server 2016 без установленного стороннего антивирусного ПО. Попросил админов добавить PGDATA в исключения встроенного в винду антивируса.

[gsmolk]

Если это решит проблему, то мы напишем об этом в документации.

[ykurenkov]

Будем ждать бэкапа со статусом ERROR. Посмотрим в логи.

[ykurenkov]

Вот, только что произошло. Дата изменения на файле около двух с половиной часов назад. Вот запись из лога:

2019-05-20 13:20:11 RTZ 4 (зима): INFO: command: D:\IBIS_ADM\bin\pg_probackup\pg_probackup_5.exe backup -B \\10.20.9.240\Ibis\IBIS_BACKUPS\PGBACKUPS\ --instance ny1-10 --backup-mode=DELTA --skip-block-validation --stream --progress -j 15 
2019-05-20 13:20:11 RTZ 4 (зима): INFO: Backup start, pg_probackup version: 2.0.27, backup ID: PRSN5N, backup mode: delta, instance: ny1-10, stream: true, remote: false
2019-05-20 13:20:16 RTZ 4 (зима): ERROR: cannot stat file "D:/IBIS_DATA/ny1-10/base/15520165/15526327": Permission denied
2019-05-20 13:20:16 RTZ 4 (зима): WARNING: Backup PRSN5N is running, setting its status to ERROR
2019-05-20 13:20:16 RTZ 4 (зима): WARNING: backup in progress, stop backup

[ykurenkov]

Всё еще...

2019-05-20 13:58:07 RTZ 4 (зима): INFO: command: D:\IBIS_ADM\bin\pg_probackup\pg_probackup_5.exe backup -B \\10.20.9.240\Ibis\IBIS_BACKUPS\PGBACKUPS\ --instance ny1-10 --backup-mode=DELTA --skip-block-validation --stream --progress -j 15 
2019-05-20 13:58:07 RTZ 4 (зима): INFO: Backup start, pg_probackup version: 2.0.27, backup ID: PRSOWV, backup mode: delta, instance: ny1-10, stream: true, remote: false
2019-05-20 13:58:13 RTZ 4 (зима): ERROR: cannot stat file "D:/IBIS_DATA/ny1-10/base/15520165/15526327": Permission denied
2019-05-20 13:58:13 RTZ 4 (зима): WARNING: Backup PRSOWV is running, setting its status to ERROR
2019-05-20 13:58:13 RTZ 4 (зима): WARNING: backup in progress, stop backup

[gsmolk]

Давайте попробуем понять, что это за релейшен. Нужно подключиться к базе с oid 15520165, ее легко вычислить с помощью запроса:
select datname from pg_database where oid = 15520165

После подключения к ней выполняем запрос:
select pg_filenode_relation(1663, 15526327);

[ykurenkov]

Хм... А ничего не вернул запрос

ny1=# select datname from pg_database where oid = 15520165;
 datname
---------
 ny1
(1 row)

ny1=# select pg_filenode_relation(1663, 15526327);
 pg_filenode_relation
----------------------

(1 row)

ny1=#

На всякий случай проверил:

ny1=# select dattablespace from pg_database where oid = 15520165;
 dattablespace
---------------
          1663
(1 row)

ny1=#