Re: SV: Log files polluted with permission denied error messages after every 10 seconds

Andrus <kobruleht2@hot.ee>

From: Andrus <kobruleht2@hot.ee>
To: Thomas Munro <thomas.munro@gmail.com>
Cc: Niels Jespersen <NJN@dst.dk>, Tom Lane <tgl@sss.pgh.pa.us>, Michael Paquier <michael@paquier.xyz>, Laurenz Albe <laurenz.albe@cybertec.at>, pgsql-general <pgsql-general@postgresql.org>
Date: 2021-03-10T13:20:47Z
Lists: pgsql-general
Hi!

After re-starting postgres service problem persists.

Log file contains permission denied errors exactly after every 10 seconds:

...

2021-03-10 15:15:45 EET     checkpointer LOG:  could not rename file 
"pg_wal/000000010000000A00000030": Permission denied
2021-03-10 15:15:55 EET     checkpointer LOG:  could not rename file 
"pg_wal/000000010000000A00000031": Permission denied
2021-03-10 15:16:06 EET     checkpointer LOG:  could not rename file 
"pg_wal/000000010000000A00000032": Permission denied
2021-03-10 15:16:16 EET     checkpointer LOG:  could not rename file 
"pg_wal/000000010000000A00000033": Permission denied
2021-03-10 15:16:26 EET     checkpointer LOG:  could not rename file 
"pg_wal/000000010000000A00000034": Permission denied
2021-03-10 15:16:36 EET     checkpointer LOG:  could not rename file 
"pg_wal/000000010000000A00000037": Permission denied
2021-03-10 15:16:46 EET     checkpointer LOG:  could not rename file 
"pg_wal/000000010000000A00000038": Permission denied
2021-03-10 15:16:56 EET     checkpointer LOG:  could not rename file 
"pg_wal/000000010000000A00000039": Permission denied
2021-03-10 15:17:06 EET     checkpointer LOG:  could not rename file 
"pg_wal/000000010000000A0000003B": Permission denied
2021-03-10 15:17:16 EET     checkpointer LOG:  could not rename file 
"pg_wal/000000010000000A0000003E": Permission denied
2021-03-10 15:17:26 EET     checkpointer LOG:  could not rename file 
"pg_wal/000000010000000A0000003F": Permission denied
2021-03-10 15:17:36 EET     checkpointer LOG:  could not rename file 
"pg_wal/000000010000000A00000041": Permission denied
2021-03-10 15:17:46 EET     checkpointer LOG:  could not rename file 
"pg_wal/000000010000000A00000044": Permission denied
2021-03-10 15:17:56 EET     checkpointer LOG:  could not rename file 
"pg_wal/000000010000000A00000045": Permission denied
2021-03-10 15:18:06 EET     checkpointer LOG:  could not rename file 
"pg_wal/000000010000000A00000046": Permission denied
2021-03-10 15:18:16 EET     checkpointer LOG:  could not rename file 
"pg_wal/000000010000000A00000047": Permission denied
2021-03-10 15:18:26 EET     checkpointer LOG:  could not rename file 
"pg_wal/000000010000000A00000048": Permission denied
2021-03-10 15:18:36 EET     checkpointer LOG:  could not rename file 
"pg_wal/000000010000000A00000049": Permission denied
2021-03-10 15:18:46 EET     checkpointer LOG:  could not rename file 
"pg_wal/000000010000000A0000004B": Permission denied


Windows Resource manger shows that wal files are used by large number of 
postgres processes:

postgres.exe    22656    File    C:\Program 
Files\PostgreSQL\13\data\pg_wal\000000010000000A00000075
postgres.exe    30788    File    C:\Program 
Files\PostgreSQL\13\data\pg_wal\000000010000000A00000075
postgres.exe    14144    File    C:\Program 
Files\PostgreSQL\13\data\pg_wal\000000010000000A00000074
postgres.exe    20360    File    C:\Program 
Files\PostgreSQL\13\data\pg_wal\000000010000000A00000073
postgres.exe    25356    File    C:\Program 
Files\PostgreSQL\13\data\pg_wal\000000010000000A00000073
postgres.exe    4136    File    C:\Program 
Files\PostgreSQL\13\data\pg_wal\000000010000000A00000075
postgres.exe    7712    File    C:\Program 
Files\PostgreSQL\13\data\pg_wal\000000010000000A00000073
postgres.exe    7020    File    C:\Program 
Files\PostgreSQL\13\data\pg_wal\000000010000000A00000073
postgres.exe    26128    File    C:\Program 
Files\PostgreSQL\13\data\pg_wal\000000010000000A00000073
postgres.exe    13740    File    C:\Program 
Files\PostgreSQL\13\data\pg_wal\000000010000000A00000073
postgres.exe    25660    File    C:\Program 
Files\PostgreSQL\13\data\pg_wal\000000010000000A00000073
postgres.exe    19120    File    C:\Program 
Files\PostgreSQL\13\data\pg_wal\000000010000000A00000073
postgres.exe    30152    File    C:\Program 
Files\PostgreSQL\13\data\pg_wal\000000010000000A00000073
postgres.exe    12140    File    C:\Program 
Files\PostgreSQL\13\data\pg_wal\000000010000000A00000072
postgres.exe    500    File    C:\Program 
Files\PostgreSQL\13\data\pg_wal\000000010000000A00000072
postgres.exe    17616    File    C:\Program 
Files\PostgreSQL\13\data\pg_wal\000000010000000A00000072
postgres.exe    8552    File    C:\Program 
Files\PostgreSQL\13\data\pg_wal\000000010000000A00000072
postgres.exe    23764    File    C:\Program 
Files\PostgreSQL\13\data\pg_wal\000000010000000A00000074
postgres.exe    28400    File    C:\Program 
Files\PostgreSQL\13\data\pg_wal\000000010000000A00000072
postgres.exe    30172    File    C:\Program 
Files\PostgreSQL\13\data\pg_wal\000000010000000A00000072
postgres.exe    28004    File    C:\Program 
Files\PostgreSQL\13\data\pg_wal\000000010000000A00000071
postgres.exe    28236    File    C:\Program 
Files\PostgreSQL\13\data\pg_wal\000000010000000A00000071
postgres.exe    25840    File    C:\Program 
Files\PostgreSQL\13\data\pg_wal\000000010000000A00000072
postgres.exe    20048    File    C:\Program 
Files\PostgreSQL\13\data\pg_wal\000000010000000A00000071
postgres.exe    30156    File    C:\Program 
Files\PostgreSQL\13\data\pg_wal\000000010000000A00000071
postgres.exe    26976    File    C:\Program 
Files\PostgreSQL\13\data\pg_wal\000000010000000A00000071
postgres.exe    26312    File    C:\Program 
Files\PostgreSQL\13\data\pg_wal\000000010000000A00000074

...

Many wal files are used by multiple processes.

Andrus.

Commits

Same data as JSON: GET /api/v1/messages/:b64id/commits the thread's linked commits as JSON, with link sources. API reference →
  1. Fix concurrency issues with WAL segment recycling on Windows