From: | hubert depesz lubaczewski <depesz(at)depesz(dot)com> |
---|---|
To: | Tom Lane <tgl(at)sss(dot)pgh(dot)pa(dot)us> |
Cc: | Adrian Klaver <adrian(dot)klaver(at)aklaver(dot)com>, PostgreSQL General <pgsql-general(at)lists(dot)postgresql(dot)org>, Chris Wilson <chris+google(at)qwirx(dot)com> |
Subject: | Re: Streaming replica hangs periodically for ~ 1 second - how to diagnose/debug |
Date: | 2025-08-25 12:44:12 |
Message-ID: | aKxanObWI9IMI1MC@depesz.com |
Views: | Whole Thread | Raw Message | Download mbox | Resend email |
Thread: | |
Lists: | pgsql-general |
On Fri, Aug 22, 2025 at 11:55:18AM -0400, Tom Lane wrote:
> hubert depesz lubaczewski <depesz(at)depesz(dot)com> writes:
> > On Fri, Aug 22, 2025 at 11:21:22AM -0400, Tom Lane wrote:
> >> Interesting. That futex call is presumably caused by interaction
> >> with some other process within the standby server, and the only
> >> plausible candidate really is the startup process (which is replaying
> >> WAL received from the primary). There are cases where WAL replay
> >> will take locks that can block queries on the standby. Can you
> >> correlate the delays on the standby server with any DDL events
> >> occurring on the primary?
>
> > Nope. Plus there is certain repetition of these cases, so even if I'd
> > miss *some* create table/alter, it just isn't going to be happening
> > every 4-5 minutes.
>
> Nonetheless, I'm suspecting an interaction with the startup process,
> because there just isn't that much else that this process could be
> needing to deal with. Can you try strace'ing both the process doing
> the test query and the startup process, to see what the startup
> process is doing at the times the futex calls happen?
Sooo. This time, I wasn't able to find freeze for 1s. But I found
a cluster with ~ 600ms freezes.
Ran tight-ish loop of pg_sleep(0.05):
| #!/usr/bin/env bash
| (
| echo '\timing'
| while true
| do
| echo "SELECT now(), pg_backend_pid(), pg_sleep(.05);"
| done
| ) |
| psql -qAtXF, |
| perl -ne '
| if (/^(.*),(.*),\s*\z/) {
| printf("%-30s :: %6s :: ", $1, $2);
| } elsif (/^Time: (\d\d+)\.(\d+) ms\s/) {
| printf "%s.%s ms :: %s\n", $1, $2, "#"x$1
| }
| '
and it logged:
2025-08-25 11:51:44.504176+00 :: 3452279 :: 50.753 ms :: ##################################################
2025-08-25 11:51:44.554945+00 :: 3452279 :: 50.600 ms :: ##################################################
2025-08-25 11:51:44.605597+00 :: 3452279 :: 50.612 ms :: ##################################################
2025-08-25 11:51:44.656266+00 :: 3452279 :: 611.872 ms :: ########################################################…
2025-08-25 11:51:45.268284+00 :: 3452279 :: 51.709 ms :: ###################################################
2025-08-25 11:51:45.319975+00 :: 3452279 :: 50.693 ms :: ##################################################
2025-08-25 11:51:45.370746+00 :: 3452279 :: 50.779 ms :: ##################################################
This time, on this cluster, the problems seemes to happen ~ 10 minutes apart.
Got strace of startup, and of pg backend that did it.
First, the backend processing pg_sleep:
…
11:51:44.554846 recvfrom(9, "\27\3\3\0E", 5, 0, NULL, NULL) = 5 <0.000013>
11:51:44.554895 recvfrom(9, "!:\17\372\355\223k\"\200F\260\27&\7Z\260Z\24\251\235\240\24\24w\372||I\361kX%sC\241\36\227\275\372M\335>U\371bg#\2648O\265\276\305~a_\335\355\204\232\317\264f\316\344\205A\313D", 69, 0, NULL, NULL) = 69 <0.000018>
11:51:44.554998 epoll_pwait(11, [], 1, 50, NULL, 8) = 0 <0.050079>
11:51:44.605157 write(2, "Message to pg.log") = 289 <0.000023>
11:51:44.605225 sendto(9, "to-psql…", 186, 0, NULL, 0) = 186 <0.000031>
11:51:44.605315 recvfrom(9, 0xaaaade97b493, 5, 0, NULL, NULL) = -1 EAGAIN (Resource temporarily unavailable) <0.000013>
11:51:44.605360 epoll_pwait(4, [{EPOLLIN, {u32=3733872600, u64=187650855034840}}], 1, -1, NULL, 8) = 1 <0.000104>
11:51:44.605497 recvfrom(9, "\27\3\3\0E", 5, 0, NULL, NULL) = 5 <0.000013>
11:51:44.605546 recvfrom(9, "\274\211\244\366\205\263\336\224\256\342:-nD\343\243U\206\rSW\364S\307\356\225\25\255\240\244i#\322P\33\316\223\213\36m\221E\262_\222\337\346\215\356\32T\247F\273U\\z\346\313M\1\21\316\270\203\f(at)P\201", 69, 0, NULL, NULL) = 69 <0.000020>
11:51:44.605663 epoll_pwait(11, [], 1, 50, NULL, 8) = 0 <0.050076>
11:51:44.655824 write(2, "Message to pg.log") = 289 <0.000024>
11:51:44.655893 sendto(9, "to-psql…", 186, 0, NULL, 0) = 186 <0.000028>
11:51:44.655978 recvfrom(9, 0xaaaade97b493, 5, 0, NULL, NULL) = -1 EAGAIN (Resource temporarily unavailable) <0.000011>
11:51:44.656021 epoll_pwait(4, [{EPOLLIN, {u32=3733872600, u64=187650855034840}}], 1, -1, NULL, 8) = 1 <0.000103>
11:51:44.656157 recvfrom(9, "\27\3\3\0E", 5, 0, NULL, NULL) = 5 <0.000013>
11:51:44.656207 recvfrom(9, "\327\27\312\363\316\241W\340\5\230\273\376\276\221Be\343\35\322+Y>x\300\1\200\25;\353O\321Y\27IrT\n\314\372\27\240\311\306b\237\264\212\314\22R\232\370\21G\367\303\252\255\3\v7\16\203\353\247u\270\355\373", 69, 0, NULL, NULL) = 69 <0.000020>
11:51:44.656336 epoll_pwait(11, [], 1, 50, NULL, 8) = 0 <0.050076>
11:51:44.706471 futex(0xfff39363d338, FUTEX_WAIT_BITSET|FUTEX_CLOCK_REALTIME, 0, NULL, FUTEX_BITSET_MATCH_ANY) = 0 <0.560006>
11:51:45.266590 write(2, "Message to pg.log") = 290 <0.000046>
11:51:45.266732 sendto(8, "\2\0\0\0(at)\0\0\0\30A\0\0\0\0\0\0\3\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0(\336\n\0\0\0\0\0\234M\2\0\0\0\0\0\0\0\0\0\0\0\0\0", 64, 0, NULL, 0) = 64 <0.000043>
11:51:45.267745 sendto(9, "to-psql…", 186, 0, NULL, 0) = 186 <0.000066>
11:51:45.268065 recvfrom(9, 0xaaaade97b493, 5, 0, NULL, NULL) = -1 EAGAIN (Resource temporarily unavailable) <0.000016>
11:51:45.268128 epoll_pwait(4, [{EPOLLIN, {u32=3733872600, u64=187650855034840}}], 1, -1, NULL, 8) = 1 <0.000011>
11:51:45.268180 recvfrom(9, "\27\3\3\0E", 5, 0, NULL, NULL) = 5 <0.000011>
11:51:45.268236 recvfrom(9, "u\6FJ6r3wb\302\325\203\234\7\375\3575\364\331\r\265\316o\3506}\25\346I\2039j2\232}\224\372\324\200>\270|\353\203\177u\261\35F\1\254\253\24\31\4\20\246\230\25M\361\371\16\301a\274$~\321", 69, 0, NULL, NULL) = 69 <0.000011>
11:51:45.268370 epoll_pwait(11, [], 1, 50, NULL, 8) = 0 <0.050917>
11:51:45.319409 write(2, "Message to pg.log") = 289 <0.000033>
11:51:45.319545 sendto(9, "to-psql…", 186, 0, NULL, 0) = 186 <0.000046>
11:51:45.319661 recvfrom(9, 0xaaaade97b493, 5, 0, NULL, NULL) = -1 EAGAIN (Resource temporarily unavailable) <0.000018>
11:51:45.319725 epoll_pwait(4, [{EPOLLIN, {u32=3733872600, u64=187650855034840}}], 1, -1, NULL, 8) = 1 <0.000083>
11:51:45.319852 recvfrom(9, "\27\3\3\0E", 5, 0, NULL, NULL) = 5 <0.000015>
11:51:45.319919 recvfrom(9, "\\,\213O\270\3139\215D\325a\2577\307\342\343\304\34B\r\202\333\274\251\346\221\270.B\306)oA\346\375_\6XT\3\316$\306\212\224\350ab\324B\263\204\220\372M\215\f\225\277\247c+8\30J\246\262v\307", 69, 0, NULL, NULL) = 69 <0.000019>
11:51:45.320053 epoll_pwait(11, [], 1, 50, NULL, 8) = 0 <0.050078>
11:51:45.370209 write(2, "Message to pg.log") = 289 <0.000026>
11:51:45.370294 sendto(9, "to-psql…", 186, 0, NULL, 0) = 186 <0.000042>
11:51:45.370405 recvfrom(9, 0xaaaade97b493, 5, 0, NULL, NULL) = -1 EAGAIN (Resource temporarily unavailable) <0.000012>
11:51:45.370453 epoll_pwait(4, [{EPOLLIN, {u32=3733872600, u64=187650855034840}}], 1, -1, NULL, 8) = 1 <0.000107>
11:51:45.370623 recvfrom(9, "\27\3\3\0E", 5, 0, NULL, NULL) = 5 <0.000023>
11:51:45.370697 recvfrom(9, "&3\224>\332\313OiS\312\272\326\270\313\221a\216\220w\357P\231z&\255K\17\331\263\32\367\212\31\344Qm\272C\236\316&0\375\225\224m\340\372\314\20]\345\251kaJ\210Y\344\33\277\270B\25E\241\251'\347", 69, 0, NULL, NULL) = 69 <0.000015>
11:51:45.370823 epoll_pwait(11, [], 1, 50, NULL, 8) = 0 <0.050077>
…
The interesting part is of course:
11:51:44.706471 futex(0xfff39363d338, FUTEX_WAIT_BITSET|FUTEX_CLOCK_REALTIME, 0, NULL, FUTEX_BITSET_MATCH_ANY) = 0 <0.560006>
Now, for the startup process around this time:
…
11:51:44.603450 read(9, "…", 1024) = 128 <0.000015>
11:51:44.603498 pread64(4, "…"..., 8192, 4349952) = 8192 <0.000019>
11:51:44.603558 pwrite64(219, "\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0"..., 8192, 398286848) = 8192 <0.000038>
11:51:44.603642 pread64(4, "…"..., 8192, 4358144) = 8192 <0.000691>
11:51:44.604394 newfstatat(AT_FDCWD, "/var/lib/postgresql/14/main/finish.recovery", 0xffffe5362908, 0) = -1 ENOENT (No such file or directory) <0.000017>
11:51:44.604443 kill(1785191, SIGURG) = 0 <0.000021>
11:51:44.604491 epoll_pwait(10, [{EPOLLIN, {u32=3733868344, u64=187650855030584}}], 1, 5000, NULL, 8) = 1 <0.008037>
11:51:44.612568 read(9, "…", 1024) = 128 <0.000013>
11:51:44.612615 pread64(4, "…"..., 8192, 4358144) = 8192 <0.000016>
11:51:44.612693 newfstatat(AT_FDCWD, "/var/lib/postgresql/14/main/finish.recovery", 0xffffe5362908, 0) = -1 ENOENT (No such file or directory) <0.000015>
11:51:44.612742 kill(1785191, SIGURG) = 0 <0.000018>
11:51:44.612787 epoll_pwait(10, [{EPOLLIN, {u32=3733868344, u64=187650855030584}}], 1, 5000, NULL, 8) = 1 <0.001810>
11:51:44.614628 read(9, "…", 1024) = 128 <0.000012>
11:51:44.614670 pread64(4, "…"..., 8192, 4358144) = 8192 <0.000019>
11:51:44.614775 newfstatat(AT_FDCWD, "/var/lib/postgresql/14/main/finish.recovery", 0xffffe5362908, 0) = -1 ENOENT (No such file or directory) <0.000013>
11:51:44.614818 kill(1785191, SIGURG) = 0 <0.000016>
11:51:44.614861 epoll_pwait(10, [{EPOLLIN, {u32=3733868344, u64=187650855030584}}], 1, 5000, NULL, 8) = 1 <0.005601>
11:51:44.620497 read(9, "…", 1024) = 128 <0.000012>
11:51:44.620539 pread64(4, "…"..., 8192, 4358144) = 8192 <0.000524>
11:51:44.621098 pread64(4, "…"..., 8192, 4366336) = 8192 <0.000013>
11:51:44.621152 pread64(4, "…"..., 8192, 4374528) = 8192 <0.000014>
11:51:44.621242 pread64(4, "…"..., 8192, 4382720) = 8192 <0.000014>
11:51:44.621297 newfstatat(AT_FDCWD, "/var/lib/postgresql/14/main/finish.recovery", 0xffffe5362908, 0) = -1 ENOENT (No such file or directory) <0.000014>
11:51:44.621344 epoll_pwait(10, [{EPOLLIN, {u32=3733868344, u64=187650855030584}}], 1, 5000, NULL, 8) = 1 <0.000610>
11:51:44.621985 read(9, "…", 1024) = 128 <0.000012>
11:51:44.622028 pread64(4, "…"..., 8192, 4382720) = 8192 <0.000014>
11:51:44.622075 pread64(4, "…"..., 8192, 4390912) = 8192 <0.000646>
11:51:44.622786 newfstatat(AT_FDCWD, "/var/lib/postgresql/14/main/finish.recovery", 0xffffe5362908, 0) = -1 ENOENT (No such file or directory) <0.000014>
11:51:44.622831 kill(1785191, SIGURG) = 0 <0.000018>
11:51:44.622875 epoll_pwait(10, [{EPOLLIN, {u32=3733868344, u64=187650855030584}}], 1, 5000, NULL, 8) = 1 <0.006632>
11:51:44.629537 read(9, "…", 1024) = 128 <0.000012>
11:51:44.629580 pread64(4, "…"..., 8192, 4390912) = 8192 <0.000014>
11:51:44.629650 pread64(4, "…"..., 8192, 4399104) = 8192 <0.000014>
11:51:44.629720 pread64(4, "…"..., 8192, 4407296) = 8192 <0.000014>
11:51:44.629775 pread64(4, "…"..., 8192, 4415488) = 8192 <0.000604>
11:51:44.630423 pread64(4, "…"..., 8192, 4415488) = 8192 <0.000017>
11:51:44.630474 newfstatat(AT_FDCWD, "/var/lib/postgresql/14/main/finish.recovery", 0xffffe5362908, 0) = -1 ENOENT (No such file or directory) <0.000014>
11:51:44.630520 newfstatat(AT_FDCWD, "/var/lib/postgresql/14/main/finish.recovery", 0xffffe5362908, 0) = -1 ENOENT (No such file or directory) <0.000012>
11:51:44.630563 epoll_pwait(10, [{EPOLLIN, {u32=3733868344, u64=187650855030584}}], 1, 5000, NULL, 8) = 1 <0.000791>
11:51:44.631384 read(9, "…", 1024) = 128 <0.000013>
11:51:44.631426 pread64(4, "…"..., 8192, 4415488) = 8192 <0.000014>
11:51:44.631476 newfstatat(AT_FDCWD, "/var/lib/postgresql/14/main/finish.recovery", 0xffffe5362908, 0) = -1 ENOENT (No such file or directory) <0.000014>
11:51:44.631519 kill(1785191, SIGURG) = 0 <0.000013>
11:51:44.631559 epoll_pwait(10, [{EPOLLIN, {u32=3733868344, u64=187650855030584}}], 1, 5000, NULL, 8) = 1 <0.000917>
11:51:44.632506 read(9, "…", 1024) = 128 <0.000012>
11:51:44.632546 pread64(4, "…"..., 8192, 4415488) = 8192 <0.000013>
11:51:44.632602 newfstatat(AT_FDCWD, "/var/lib/postgresql/14/main/finish.recovery", 0xffffe5362908, 0) = -1 ENOENT (No such file or directory) <0.000013>
11:51:44.632645 kill(1785191, SIGURG) = 0 <0.000016>
11:51:44.632688 epoll_pwait(10, [{EPOLLIN, {u32=3733868344, u64=187650855030584}}], 1, 5000, NULL, 8) = 1 <0.003932>
11:51:44.636650 read(9, "…", 1024) = 128 <0.000012>
11:51:44.636691 pread64(4, "…"..., 8192, 4415488) = 8192 <0.000587>
11:51:44.637334 pread64(4, "…"..., 8192, 4423680) = 8192 <0.000013>
11:51:44.637426 newfstatat(AT_FDCWD, "/var/lib/postgresql/14/main/finish.recovery", 0xffffe5362908, 0) = -1 ENOENT (No such file or directory) <0.000013>
11:51:44.637469 epoll_pwait(10, [{EPOLLIN, {u32=3733868344, u64=187650855030584}}], 1, 5000, NULL, 8) = 1 <0.000796>
11:51:44.638295 read(9, "…", 1024) = 128 <0.000011>
11:51:44.638335 pread64(4, "…"..., 8192, 4431872) = 8192 <0.000013>
11:51:44.638436 newfstatat(AT_FDCWD, "/var/lib/postgresql/14/main/finish.recovery", 0xffffe5362908, 0) = -1 ENOENT (No such file or directory) <0.000013>
11:51:44.638483 kill(1785191, SIGURG) = 0 <0.000016>
11:51:44.638524 epoll_pwait(10, [{EPOLLIN, {u32=3733868344, u64=187650855030584}}], 1, 5000, NULL, 8) = 1 <0.002996>
11:51:44.641552 read(9, "…", 1024) = 128 <0.000012>
11:51:44.641596 pread64(4, "…"..., 8192, 4431872) = 8192 <0.000014>
11:51:44.641656 pread64(4, "…"..., 8192, 4440064) = 8192 <0.000014>
11:51:44.641709 newfstatat(AT_FDCWD, "/var/lib/postgresql/14/main/finish.recovery", 0xffffe5362908, 0) = -1 ENOENT (No such file or directory) <0.000014>
11:51:44.641755 epoll_pwait(10, [{EPOLLIN, {u32=3733868344, u64=187650855030584}}], 1, 5000, NULL, 8) = 1 <0.000838>
11:51:44.642622 read(9, "…", 1024) = 128 <0.000012>
11:51:44.642665 pread64(4, "…"..., 8192, 4440064) = 8192 <0.000014>
11:51:44.642724 newfstatat(AT_FDCWD, "/var/lib/postgresql/14/main/finish.recovery", 0xffffe5362908, 0) = -1 ENOENT (No such file or directory) <0.000014>
11:51:44.642768 kill(1785191, SIGURG) = 0 <0.000016>
11:51:44.642810 epoll_pwait(10, [{EPOLLIN, {u32=3733868344, u64=187650855030584}}], 1, 5000, NULL, 8) = 1 <0.002441>
11:51:44.645305 read(9, "…", 1024) = 128 <0.000013>
11:51:44.645358 pread64(4, "…"..., 8192, 4440064) = 8192 <0.000546>
11:51:44.645936 pread64(4, "…"..., 8192, 4448256) = 8192 <0.000013>
11:51:44.646009 newfstatat(AT_FDCWD, "/var/lib/postgresql/14/main/finish.recovery", 0xffffe5362908, 0) = -1 ENOENT (No such file or directory) <0.000013>
11:51:44.646057 epoll_pwait(10, [{EPOLLIN, {u32=3733868344, u64=187650855030584}}], 1, 5000, NULL, 8) = 1 <0.000813>
11:51:44.646900 read(9, "…", 1024) = 128 <0.000012>
11:51:44.646941 pread64(4, "…"..., 8192, 4448256) = 8192 <0.000013>
11:51:44.646995 pread64(4, "…"..., 8192, 4456448) = 8192 <0.000645>
11:51:44.647692 newfstatat(AT_FDCWD, "/var/lib/postgresql/14/main/finish.recovery", 0xffffe5362908, 0) = -1 ENOENT (No such file or directory) <0.000013>
11:51:44.647736 kill(1785191, SIGURG) = 0 <0.000016>
11:51:44.647778 epoll_pwait(10, [{EPOLLIN, {u32=3733868344, u64=187650855030584}}], 1, 5000, NULL, 8) = 1 <0.004123>
11:51:44.651941 read(9, "…", 1024) = 128 <0.000015>
11:51:44.651988 pread64(4, "…"..., 8192, 4456448) = 8192 <0.000592>
11:51:44.652636 pread64(4, "…"..., 8192, 4464640) = 8192 <0.000016>
11:51:44.652715 pread64(4, "…"..., 8192, 4472832) = 8192 <0.000015>
11:51:44.652808 pread64(4, "…"..., 8192, 4481024) = 8192 <0.000015>
11:51:44.652874 pread64(4, "…"..., 8192, 4489216) = 8192 <0.000014>
11:51:44.652943 newfstatat(AT_FDCWD, "/var/lib/postgresql/14/main/finish.recovery", 0xffffe5362908, 0) = -1 ENOENT (No such file or directory) <0.000016>
11:51:44.652987 epoll_pwait(10, [{EPOLLIN, {u32=3733868344, u64=187650855030584}}], 1, 5000, NULL, 8) = 1 <0.000564>
11:51:44.653577 read(9, "…", 1024) = 128 <0.000013>
11:51:44.653614 pread64(4, "…"..., 8192, 4489216) = 8192 <0.000571>
11:51:44.654210 pread64(4, "…"..., 8192, 4497408) = 8192 <0.000014>
11:51:44.654304 pread64(4, "…"..., 8192, 4505600) = 8192 <0.000014>
11:51:44.654384 pread64(4, "…"..., 8192, 4513792) = 8192 <0.000014>
11:51:44.654447 newfstatat(AT_FDCWD, "/var/lib/postgresql/14/main/finish.recovery", 0xffffe5362908, 0) = -1 ENOENT (No such file or directory) <0.000014>
11:51:44.654491 epoll_pwait(10, [{EPOLLIN, {u32=3733868344, u64=187650855030584}}], 1, 5000, NULL, 8) = 1 <0.000664>
11:51:44.655180 read(9, "…", 1024) = 128 <0.000012>
11:51:44.655222 pread64(4, "…"..., 8192, 4513792) = 8192 <0.000550>
11:51:44.655844 pread64(4, "…"..., 8192, 4521984) = 8192 <0.000023>
11:51:44.655927 newfstatat(AT_FDCWD, "/var/lib/postgresql/14/main/finish.recovery", 0xffffe5362908, 0) = -1 ENOENT (No such file or directory) <0.000032>
11:51:44.656008 epoll_pwait(10, [{EPOLLIN, {u32=3733868344, u64=187650855030584}}], 1, 5000, NULL, 8) = 1 <0.000798>
11:51:44.656838 read(9, "…", 1024) = 128 <0.000014>
11:51:44.656883 pread64(4, "…"..., 8192, 4521984) = 8192 <0.000559>
11:51:44.657497 pread64(4, "…"..., 8192, 4530176) = 8192 <0.000015>
11:51:44.657585 newfstatat(AT_FDCWD, "/var/lib/postgresql/14/main/finish.recovery", 0xffffe5362908, 0) = -1 ENOENT (No such file or directory) <0.000018>
11:51:44.657636 epoll_pwait(10, [{EPOLLIN, {u32=3733868344, u64=187650855030584}}], 1, 5000, NULL, 8) = 1 <0.000770>
11:51:44.658455 read(9, "…", 1024) = 128 <0.000013>
11:51:44.658499 pread64(4, "…"..., 8192, 4530176) = 8192 <0.000014>
11:51:44.658545 pread64(4, "…"..., 8192, 4538368) = 8192 <0.000014>
11:51:44.658605 newfstatat(AT_FDCWD, "/var/lib/postgresql/14/main/finish.recovery", 0xffffe5362908, 0) = -1 ENOENT (No such file or directory) <0.000014>
11:51:44.658650 kill(1785191, SIGURG) = 0 <0.000018>
11:51:44.658696 epoll_pwait(10, [{EPOLLIN, {u32=3733868344, u64=187650855030584}}], 1, 5000, NULL, 8) = 1 <0.004334>
11:51:44.663061 read(9, "…", 1024) = 128 <0.000013>
11:51:44.663103 pread64(4, "…"..., 8192, 4538368) = 8192 <0.000014>
11:51:44.663152 newfstatat(AT_FDCWD, "/var/lib/postgresql/14/main/finish.recovery", 0xffffe5362908, 0) = -1 ENOENT (No such file or directory) <0.000013>
11:51:44.663195 kill(1785191, SIGURG) = 0 <0.000014>
11:51:44.663236 epoll_pwait(10, [{EPOLLIN, {u32=3733868344, u64=187650855030584}}], 1, 5000, NULL, 8) = 1 <0.001864>
11:51:44.665129 read(9, "…", 1024) = 128 <0.000012>
11:51:44.665171 pread64(4, "…"..., 8192, 4538368) = 8192 <0.000014>
11:51:44.665222 pread64(4, "…"..., 8192, 4546560) = 8192 <0.000014>
11:51:44.665317 newfstatat(AT_FDCWD, "/var/lib/postgresql/14/main/finish.recovery", 0xffffe5362908, 0) = -1 ENOENT (No such file or directory) <0.000014>
11:51:44.665368 epoll_pwait(10, [{EPOLLIN, {u32=3733868344, u64=187650855030584}}], 1, 5000, NULL, 8) = 1 <0.000633>
11:51:44.666032 read(9, "…", 1024) = 128 <0.000013>
11:51:44.666075 pread64(4, "…"..., 8192, 4546560) = 8192 <0.000014>
11:51:44.666122 newfstatat(AT_FDCWD, "/var/lib/postgresql/14/main/finish.recovery", 0xffffe5362908, 0) = -1 ENOENT (No such file or directory) <0.000016>
11:51:44.666168 kill(1785191, SIGURG) = 0 <0.000015>
11:51:44.666210 epoll_pwait(10, [{EPOLLIN, {u32=3733868344, u64=187650855030584}}], 1, 5000, NULL, 8) = 1 <0.001302>
11:51:44.667543 read(9, "…", 1024) = 128 <0.000012>
11:51:44.667586 pread64(4, "…"..., 8192, 4546560) = 8192 <0.000014>
11:51:44.667636 newfstatat(AT_FDCWD, "/var/lib/postgresql/14/main/finish.recovery", 0xffffe5362908, 0) = -1 ENOENT (No such file or directory) <0.000014>
11:51:44.667680 kill(1785191, SIGURG) = 0 <0.000015>
11:51:44.667723 epoll_pwait(10, [{EPOLLIN, {u32=3733868344, u64=187650855030584}}], 1, 5000, NULL, 8) = 1 <0.002685>
11:51:44.670446 read(9, "…", 1024) = 128 <0.000029>
11:51:44.670531 pread64(4, "…"..., 8192, 4546560) = 8192 <0.000019>
11:51:44.670593 pread64(4, "…"..., 8192, 4554752) = 8192 <0.000650>
11:51:44.671357 newfstatat(AT_FDCWD, "/var/lib/postgresql/14/main/finish.recovery", 0xffffe5362908, 0) = -1 ENOENT (No such file or directory) <0.000018>
11:51:44.671414 pread64(4, "…"..., 8192, 4554752) = 8192 <0.000015>
11:51:44.671476 newfstatat(AT_FDCWD, "/var/lib/postgresql/14/main/finish.recovery", 0xffffe5362908, 0) = -1 ENOENT (No such file or directory) <0.000013>
11:51:44.671520 kill(1785191, SIGURG) = 0 <0.000018>
11:51:44.671565 epoll_pwait(10, [{EPOLLIN, {u32=3733868344, u64=187650855030584}}], 1, 5000, NULL, 8) = 1 <0.004498>
11:51:44.676108 read(9, "…", 1024) = 128 <0.000013>
11:51:44.676156 pread64(4, "…"..., 8192, 4554752) = 8192 <0.000015>
11:51:44.676239 pread64(4, "…"..., 8192, 4562944) = 8192 <0.000015>
11:51:44.676344 newfstatat(AT_FDCWD, "/var/lib/postgresql/14/main/finish.recovery", 0xffffe5362908, 0) = -1 ENOENT (No such file or directory) <0.000025>
11:51:44.676406 kill(1785191, SIGURG) = 0 <0.000019>
11:51:44.676452 epoll_pwait(10, [{EPOLLIN, {u32=3733868344, u64=187650855030584}}], 1, 5000, NULL, 8) = 1 <0.001968>
11:51:44.678463 read(9, "…", 1024) = 128 <0.000014>
11:51:44.678509 pread64(4, "…"..., 8192, 4562944) = 8192 <0.000016>
11:51:44.678582 newfstatat(AT_FDCWD, "/var/lib/postgresql/14/main/finish.recovery", 0xffffe5362908, 0) = -1 ENOENT (No such file or directory) <0.000016>
11:51:44.678629 kill(1785191, SIGURG) = 0 <0.000017>
11:51:44.678681 epoll_pwait(10, [{EPOLLIN, {u32=3733868344, u64=187650855030584}}], 1, 5000, NULL, 8) = 1 <0.005916>
11:51:44.684634 read(9, "…", 1024) = 128 <0.000015>
11:51:44.684681 pread64(4, "…"..., 8192, 4562944) = 8192 <0.000018>
11:51:44.684755 newfstatat(AT_FDCWD, "/var/lib/postgresql/14/main/finish.recovery", 0xffffe5362908, 0) = -1 ENOENT (No such file or directory) <0.000036>
11:51:44.684825 kill(1785191, SIGURG) = 0 <0.000024>
11:51:44.684876 epoll_pwait(10, [{EPOLLIN, {u32=3733868344, u64=187650855030584}}], 1, 5000, NULL, 8) = 1 <0.003147>
11:51:44.688056 read(9, "…", 1024) = 128 <0.000025>
11:51:44.688111 pread64(4, "…"..., 8192, 4562944) = 8192 <0.000015>
11:51:44.688159 pread64(4, "…"..., 8192, 4571136) = 8192 <0.000013>
11:51:44.688261 newfstatat(AT_FDCWD, "/var/lib/postgresql/14/main/finish.recovery", 0xffffe5362908, 0) = -1 ENOENT (No such file or directory) <0.000015>
11:51:44.688309 epoll_pwait(10, [{EPOLLIN, {u32=3733868344, u64=187650855030584}}], 1, 5000, NULL, 8) = 1 <0.000644>
11:51:44.688983 read(9, "…", 1024) = 128 <0.000012>
11:51:44.689025 pread64(4, "…"..., 8192, 4571136) = 8192 <0.000014>
11:51:44.689086 newfstatat(AT_FDCWD, "/var/lib/postgresql/14/main/finish.recovery", 0xffffe5362908, 0) = -1 ENOENT (No such file or directory) <0.000014>
11:51:44.689139 epoll_pwait(10, [{EPOLLIN, {u32=3733868344, u64=187650855030584}}], 1, 5000, NULL, 8) = 1 <0.000751>
11:51:44.689926 read(9, "…", 1024) = 128 <0.000015>
11:51:44.689977 pread64(4, "…"..., 8192, 4571136) = 8192 <0.000019>
11:51:44.690050 newfstatat(AT_FDCWD, "/var/lib/postgresql/14/main/finish.recovery", 0xffffe5362908, 0) = -1 ENOENT (No such file or directory) <0.000017>
11:51:44.690100 epoll_pwait(10, [{EPOLLIN, {u32=3733868344, u64=187650855030584}}], 1, 5000, NULL, 8) = 1 <0.000908>
11:51:44.691039 read(9, "…", 1024) = 128 <0.000013>
11:51:44.691082 pread64(4, "…"..., 8192, 4571136) = 8192 <0.000014>
11:51:44.691137 pread64(523, "…"..., 8192, 4964352) = 8192 <0.000017>
11:51:44.691195 newfstatat(AT_FDCWD, "/var/lib/postgresql/14/main/finish.recovery", 0xffffe5362908, 0) = -1 ENOENT (No such file or directory) <0.000014>
11:51:44.691240 kill(1785191, SIGURG) = 0 <0.000019>
11:51:44.691286 epoll_pwait(10, [{EPOLLIN, {u32=3733868344, u64=187650855030584}}], 1, 5000, NULL, 8) = 1 <0.001791>
11:51:44.693107 read(9, "…", 1024) = 128 <0.000012>
11:51:44.693150 pread64(4, "…"..., 8192, 4571136) = 8192 <0.000014>
11:51:44.693199 pread64(4, "…"..., 8192, 4579328) = 8192 <0.000667>
11:51:44.693941 newfstatat(AT_FDCWD, "/var/lib/postgresql/14/main/finish.recovery", 0xffffe5362908, 0) = -1 ENOENT (No such file or directory) <0.000014>
11:51:44.693986 epoll_pwait(10, [{EPOLLIN, {u32=3733868344, u64=187650855030584}}], 1, 5000, NULL, 8) = 1 <0.000810>
11:51:44.694829 read(9, "…", 1024) = 128 <0.000014>
11:51:44.694876 pread64(4, "…"..., 8192, 4579328) = 8192 <0.000018>
11:51:44.694939 newfstatat(AT_FDCWD, "/var/lib/postgresql/14/main/finish.recovery", 0xffffe5362908, 0) = -1 ENOENT (No such file or directory) <0.000017>
11:51:44.694988 epoll_pwait(10, [{EPOLLIN, {u32=3733868344, u64=187650855030584}}], 1, 5000, NULL, 8) = 1 <0.000780>
11:51:44.695800 read(9, "…", 1024) = 128 <0.000013>
11:51:44.695844 pread64(4, "…"..., 8192, 4579328) = 8192 <0.000016>
11:51:44.695918 newfstatat(AT_FDCWD, "/var/lib/postgresql/14/main/finish.recovery", 0xffffe5362908, 0) = -1 ENOENT (No such file or directory) <0.000015>
11:51:44.695965 epoll_pwait(10, [{EPOLLIN, {u32=3733868344, u64=187650855030584}}], 1, 5000, NULL, 8) = 1 <0.000802>
11:51:44.696797 read(9, "…", 1024) = 128 <0.000013>
11:51:44.696841 pread64(4, "…"..., 8192, 4579328) = 8192 <0.000014>
11:51:44.696919 newfstatat(AT_FDCWD, "/var/lib/postgresql/14/main/finish.recovery", 0xffffe5362908, 0) = -1 ENOENT (No such file or directory) <0.000014>
11:51:44.696963 epoll_pwait(10, [{EPOLLIN, {u32=3733868344, u64=187650855030584}}], 1, 5000, NULL, 8) = 1 <0.000730>
11:51:44.697724 read(9, "…", 1024) = 128 <0.000012>
11:51:44.697767 pread64(4, "…"..., 8192, 4579328) = 8192 <0.000014>
11:51:44.697818 newfstatat(AT_FDCWD, "/var/lib/postgresql/14/main/finish.recovery", 0xffffe5362908, 0) = -1 ENOENT (No such file or directory) <0.000014>
11:51:44.697872 kill(1785191, SIGURG) = 0 <0.000019>
11:51:44.697919 epoll_pwait(10, [{EPOLLIN, {u32=3733868344, u64=187650855030584}}], 1, 5000, NULL, 8) = 1 <0.002082>
11:51:44.700032 read(9, "…", 1024) = 128 <0.000014>
11:51:44.700076 pread64(4, "…"..., 8192, 4579328) = 8192 <0.000015>
11:51:44.700125 newfstatat(AT_FDCWD, "/var/lib/postgresql/14/main/finish.recovery", 0xffffe5362908, 0) = -1 ENOENT (No such file or directory) <0.000015>
11:51:44.700171 epoll_pwait(10, [{EPOLLIN, {u32=3733868344, u64=187650855030584}}], 1, 5000, NULL, 8) = 1 <0.000817>
11:51:44.701022 read(9, "…", 1024) = 128 <0.000013>
11:51:44.701066 pread64(4, "…"..., 8192, 4579328) = 8192 <0.000014>
11:51:44.701111 pread64(4, "…"..., 8192, 4587520) = 8192 <0.000014>
11:51:44.701163 newfstatat(AT_FDCWD, "/var/lib/postgresql/14/main/finish.recovery", 0xffffe5362908, 0) = -1 ENOENT (No such file or directory) <0.000014>
11:51:44.701209 epoll_pwait(10, [{EPOLLIN, {u32=3733868344, u64=187650855030584}}], 1, 5000, NULL, 8) = 1 <0.000683>
11:51:44.701924 read(9, "…", 1024) = 128 <0.000013>
11:51:44.701966 pread64(4, "…"..., 8192, 4587520) = 8192 <0.000014>
11:51:44.702022 newfstatat(AT_FDCWD, "/var/lib/postgresql/14/main/finish.recovery", 0xffffe5362908, 0) = -1 ENOENT (No such file or directory) <0.000014>
11:51:44.702067 kill(1785191, SIGURG) = 0 <0.000016>
11:51:44.702110 epoll_pwait(10, [{EPOLLIN, {u32=3733868344, u64=187650855030584}}], 1, 5000, NULL, 8) = 1 <0.001738>
11:51:44.703878 read(9, "…", 1024) = 128 <0.000013>
11:51:44.703933 pread64(4, "…"..., 8192, 4587520) = 8192 <0.000015>
11:51:44.703982 newfstatat(AT_FDCWD, "/var/lib/postgresql/14/main/finish.recovery", 0xffffe5362908, 0) = -1 ENOENT (No such file or directory) <0.000015>
11:51:44.704028 kill(1785191, SIGURG) = 0 <0.000015>
11:51:44.704071 epoll_pwait(10, [{EPOLLIN, {u32=3733868344, u64=187650855030584}}], 1, 5000, NULL, 8) = 1 <0.007049>
11:51:44.711185 read(9, "…", 1024) = 128 <0.000015>
11:51:44.711245 pread64(4, "…"..., 8192, 4587520) = 8192 <0.000016>
11:51:44.711373 pread64(4, "…"..., 8192, 4595712) = 8192 <0.000972>
11:51:44.712392 newfstatat(AT_FDCWD, "/var/lib/postgresql/14/main/finish.recovery", 0xffffe5362908, 0) = -1 ENOENT (No such file or directory) <0.000019>
11:51:44.712447 kill(1785191, SIGURG) = 0 <0.000018>
11:51:44.712489 epoll_pwait(10, [{EPOLLIN, {u32=3733868344, u64=187650855030584}}], 1, 5000, NULL, 8) = 1 <0.003785>
11:51:44.716310 read(9, "…", 1024) = 128 <0.000014>
11:51:44.716356 pread64(4, "…"..., 8192, 4595712) = 8192 <0.000027>
11:51:44.716432 pwrite64(219, "\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0"..., 8192, 398295040) = 8192 <0.000035>
11:51:44.716539 newfstatat(AT_FDCWD, "/var/lib/postgresql/14/main/finish.recovery", 0xffffe5362908, 0) = -1 ENOENT (No such file or directory) <0.000018>
11:51:44.716592 kill(1785191, SIGURG) = 0 <0.000019>
11:51:44.716638 epoll_pwait(10, [{EPOLLIN, {u32=3733868344, u64=187650855030584}}], 1, 5000, NULL, 8) = 1 <0.013628>
11:51:44.730329 read(9, "…", 1024) = 128 <0.000015>
11:51:44.730387 pread64(4, "…"..., 8192, 4595712) = 8192 <0.000016>
11:51:44.730449 newfstatat(AT_FDCWD, "/var/lib/postgresql/14/main/finish.recovery", 0xffffe5362908, 0) = -1 ENOENT (No such file or directory) <0.000015>
11:51:44.730503 kill(1785191, SIGURG) = 0 <0.000015>
11:51:44.730546 epoll_pwait(10, [{EPOLLIN, {u32=3733868344, u64=187650855030584}}], 1, 5000, NULL, 8) = 1 <0.006824>
11:51:44.737453 read(9, "…", 1024) = 128 <0.000015>
11:51:44.737510 pread64(4, "…"..., 8192, 4595712) = 8192 <0.000016>
11:51:44.737582 newfstatat(AT_FDCWD, "/var/lib/postgresql/14/main/finish.recovery", 0xffffe5362908, 0) = -1 ENOENT (No such file or directory) <0.000018>
11:51:44.737635 kill(1785191, SIGURG) = 0 <0.000018>
11:51:44.737681 epoll_pwait(10, [{EPOLLIN, {u32=3733868344, u64=187650855030584}}], 1, 5000, NULL, 8) = 1 <0.005750>
11:51:44.743490 read(9, "…", 1024) = 128 <0.000016>
11:51:44.743545 pread64(4, "…"..., 8192, 4595712) = 8192 <0.000017>
11:51:44.743643 newfstatat(AT_FDCWD, "/var/lib/postgresql/14/main/finish.recovery", 0xffffe5362908, 0) = -1 ENOENT (No such file or directory) <0.000017>
11:51:44.743696 epoll_pwait(10, [{EPOLLIN, {u32=3733868344, u64=187650855030584}}], 1, 5000, NULL, 8) = 1 <0.000697>
11:51:44.744442 read(9, "…", 1024) = 128 <0.000014>
11:51:44.744504 pread64(4, "…"..., 8192, 4595712) = 8192 <0.000017>
11:51:44.744559 newfstatat(AT_FDCWD, "/var/lib/postgresql/14/main/finish.recovery", 0xffffe5362908, 0) = -1 ENOENT (No such file or directory) <0.000016>
11:51:44.744611 kill(1785191, SIGURG) = 0 <0.000018>
11:51:44.744657 epoll_pwait(10, [{EPOLLIN, {u32=3733868344, u64=187650855030584}}], 1, 5000, NULL, 8) = 1 <0.008226>
11:51:44.752946 read(9, "…", 1024) = 128 <0.000016>
11:51:44.753004 pread64(4, "…"..., 8192, 4595712) = 8192 <0.000015>
11:51:44.753064 newfstatat(AT_FDCWD, "/var/lib/postgresql/14/main/finish.recovery", 0xffffe5362908, 0) = -1 ENOENT (No such file or directory) <0.000016>
11:51:44.753117 kill(1785191, SIGURG) = 0 <0.000015>
11:51:44.753159 epoll_pwait(10, [{EPOLLIN, {u32=3733868344, u64=187650855030584}}], 1, 5000, NULL, 8) = 1 <0.006172>
11:51:44.759391 read(9, "…", 1024) = 128 <0.000013>
11:51:44.759444 pread64(4, "…"..., 8192, 4595712) = 8192 <0.000013>
11:51:44.759518 pread64(4, "…"..., 8192, 4603904) = 8192 <0.000019>
11:51:44.759599 newfstatat(AT_FDCWD, "/var/lib/postgresql/14/main/finish.recovery", 0xffffe5362908, 0) = -1 ENOENT (No such file or directory) <0.000015>
11:51:44.759650 epoll_pwait(10, [{EPOLLIN, {u32=3733868344, u64=187650855030584}}], 1, 5000, NULL, 8) = 1 <0.000647>
11:51:44.760331 read(9, "…", 1024) = 128 <0.000012>
11:51:44.760388 pread64(4, "…"..., 8192, 4603904) = 8192 <0.000014>
11:51:44.760488 newfstatat(AT_FDCWD, "/var/lib/postgresql/14/main/finish.recovery", 0xffffe5362908, 0) = -1 ENOENT (No such file or directory) <0.000014>
11:51:44.760534 kill(1785191, SIGURG) = 0 <0.000016>
11:51:44.760577 epoll_pwait(10, [{EPOLLIN, {u32=3733868344, u64=187650855030584}}], 1, 5000, NULL, 8) = 1 <0.004137>
11:51:44.764747 read(9, "…", 1024) = 128 <0.000013>
11:51:44.764791 pread64(4, "…"..., 8192, 4603904) = 8192 <0.000015>
11:51:44.764857 pread64(4, "…"..., 8192, 4612096) = 8192 <0.000015>
11:51:44.764905 newfstatat(AT_FDCWD, "/var/lib/postgresql/14/main/finish.recovery", 0xffffe5362908, 0) = -1 ENOENT (No such file or directory) <0.000015>
11:51:44.764952 kill(1785191, SIGURG) = 0 <0.000016>
11:51:44.764995 epoll_pwait(10, [{EPOLLIN, {u32=3733868344, u64=187650855030584}}], 1, 5000, NULL, 8) = 1 <0.007306>
11:51:44.772339 read(9, "…", 1024) = 128 <0.000016>
11:51:44.772390 pread64(4, "…"..., 8192, 4612096) = 8192 <0.000017>
11:51:44.772466 newfstatat(AT_FDCWD, "/var/lib/postgresql/14/main/finish.recovery", 0xffffe5362908, 0) = -1 ENOENT (No such file or directory) <0.000020>
11:51:44.772518 kill(1785191, SIGURG) = 0 <0.000019>
11:51:44.772565 epoll_pwait(10, [{EPOLLIN, {u32=3733868344, u64=187650855030584}}], 1, 5000, NULL, 8) = 1 <0.006657>
11:51:44.779258 read(9, "…", 1024) = 128 <0.000013>
11:51:44.779312 pread64(4, "…"..., 8192, 4612096) = 8192 <0.000015>
11:51:44.779366 pread64(4, "…"..., 8192, 4620288) = 8192 <0.000014>
11:51:44.779475 newfstatat(AT_FDCWD, "/var/lib/postgresql/14/main/finish.recovery", 0xffffe5362908, 0) = -1 ENOENT (No such file or directory) <0.000016>
11:51:44.779524 epoll_pwait(10, [{EPOLLIN, {u32=3733868344, u64=187650855030584}}], 1, 5000, NULL, 8) = 1 <0.000798>
11:51:44.780352 read(9, "…", 1024) = 128 <0.000012>
11:51:44.780396 pread64(4, "…"..., 8192, 4620288) = 8192 <0.000014>
11:51:44.780445 pread64(4, "…"..., 8192, 4628480) = 8192 <0.000079>
11:51:44.780559 newfstatat(AT_FDCWD, "/var/lib/postgresql/14/main/finish.recovery", 0xffffe5362908, 0) = -1 ENOENT (No such file or directory) <0.000014>
11:51:44.780605 kill(1785191, SIGURG) = 0 <0.000017>
11:51:44.780649 epoll_pwait(10, [{EPOLLIN, {u32=3733868344, u64=187650855030584}}], 1, 5000, NULL, 8) = 1 <0.016560>
11:51:44.797272 read(9, "…", 1024) = 128 <0.000013>
11:51:44.797328 pread64(4, "…"..., 8192, 4628480) = 8192 <0.000014>
11:51:44.797430 pread64(4, "…"..., 8192, 4636672) = 8192 <0.000020>
11:51:44.797514 pread64(4, "…"..., 8192, 4644864) = 8192 <0.000015>
11:51:44.797593 newfstatat(AT_FDCWD, "/var/lib/postgresql/14/main/finish.recovery", 0xffffe5362908, 0) = -1 ENOENT (No such file or directory) <0.000015>
11:51:44.797645 kill(1785191, SIGURG) = 0 <0.000016>
11:51:44.797690 epoll_pwait(10, [{EPOLLIN, {u32=3733868344, u64=187650855030584}}], 1, 5000, NULL, 8) = 1 <0.022164>
11:51:44.819913 read(9, "…", 1024) = 128 <0.000016>
11:51:44.819969 pread64(4, "…"..., 8192, 4644864) = 8192 <0.000018>
11:51:44.820049 newfstatat(AT_FDCWD, "/var/lib/postgresql/14/main/finish.recovery", 0xffffe5362908, 0) = -1 ENOENT (No such file or directory) <0.000017>
11:51:44.820102 kill(1785191, SIGURG) = 0 <0.000019>
11:51:44.820149 epoll_pwait(10, [{EPOLLIN, {u32=3733868344, u64=187650855030584}}], 1, 5000, NULL, 8) = 1 <0.001903>
11:51:44.822097 read(9, "…", 1024) = 128 <0.000013>
11:51:44.822143 pread64(4, "…"..., 8192, 4644864) = 8192 <0.000015>
11:51:44.822189 newfstatat(AT_FDCWD, "/var/lib/postgresql/14/main/finish.recovery", 0xffffe5362908, 0) = -1 ENOENT (No such file or directory) <0.000013>
11:51:44.822231 kill(1785191, SIGURG) = 0 <0.000016>
11:51:44.822274 epoll_pwait(10, [{EPOLLIN, {u32=3733868344, u64=187650855030584}}], 1, 5000, NULL, 8) = 1 <0.009359>
11:51:44.832831 read(9, "…", 1024) = 128 <0.000017>
11:51:44.832891 pread64(4, "…"..., 8192, 4644864) = 8192 <0.000017>
11:51:44.832968 newfstatat(AT_FDCWD, "/var/lib/postgresql/14/main/finish.recovery", 0xffffe5362908, 0) = -1 ENOENT (No such file or directory) <0.000018>
11:51:44.833034 kill(1785191, SIGURG) = 0 <0.000018>
11:51:44.833081 epoll_pwait(10, [{EPOLLIN, {u32=3733868344, u64=187650855030584}}], 1, 5000, NULL, 8) = 1 <0.002325>
11:51:44.835464 read(9, "…", 1024) = 128 <0.000014>
11:51:44.835516 pread64(4, "…"..., 8192, 4644864) = 8192 <0.000014>
11:51:44.835577 pread64(4, "…"..., 8192, 4653056) = 8192 <0.000015>
11:51:44.835660 newfstatat(AT_FDCWD, "/var/lib/postgresql/14/main/finish.recovery", 0xffffe5362908, 0) = -1 ENOENT (No such file or directory) <0.000015>
11:51:44.835713 kill(1785191, SIGURG) = 0 <0.000016>
11:51:44.835757 epoll_pwait(10, [{EPOLLIN, {u32=3733868344, u64=187650855030584}}], 1, 5000, NULL, 8) = 1 <0.009059>
11:51:44.844872 read(9, "…", 1024) = 128 <0.000022>
11:51:44.844948 pread64(4, "…"..., 8192, 4653056) = 8192 <0.000015>
11:51:44.845028 pread64(4, "…"..., 8192, 4661248) = 8192 <0.000014>
11:51:44.845097 newfstatat(AT_FDCWD, "/var/lib/postgresql/14/main/finish.recovery", 0xffffe5362908, 0) = -1 ENOENT (No such file or directory) <0.000014>
11:51:44.845147 kill(1785191, SIGURG) = 0 <0.000015>
11:51:44.845189 epoll_pwait(10, [{EPOLLIN, {u32=3733868344, u64=187650855030584}}], 1, 5000, NULL, 8) = 1 <0.003170>
11:51:44.848401 read(9, "…", 1024) = 128 <0.000012>
11:51:44.848446 pread64(4, "…"..., 8192, 4661248) = 8192 <0.000013>
11:51:44.848502 newfstatat(AT_FDCWD, "/var/lib/postgresql/14/main/finish.recovery", 0xffffe5362908, 0) = -1 ENOENT (No such file or directory) <0.000014>
11:51:44.848547 kill(1785191, SIGURG) = 0 <0.000015>
11:51:44.848588 epoll_pwait(10, [{EPOLLIN, {u32=3733868344, u64=187650855030584}}], 1, 5000, NULL, 8) = 1 <0.007233>
11:51:44.855856 read(9, "…", 1024) = 128 <0.000012>
11:51:44.855899 pread64(4, "…"..., 8192, 4661248) = 8192 <0.000014>
11:51:44.855984 pread64(4, "…"..., 8192, 4669440) = 8192 <0.000014>
11:51:44.856037 newfstatat(AT_FDCWD, "/var/lib/postgresql/14/main/finish.recovery", 0xffffe5362908, 0) = -1 ENOENT (No such file or directory) <0.000015>
11:51:44.856086 kill(1785191, SIGURG) = 0 <0.000015>
11:51:44.856127 epoll_pwait(10, [{EPOLLIN, {u32=3733868344, u64=187650855030584}}], 1, 5000, NULL, 8) = 1 <0.001785>
11:51:44.857943 read(9, "…", 1024) = 128 <0.000012>
11:51:44.857984 pread64(4, "…"..., 8192, 4669440) = 8192 <0.000015>
11:51:44.858052 newfstatat(AT_FDCWD, "/var/lib/postgresql/14/main/finish.recovery", 0xffffe5362908, 0) = -1 ENOENT (No such file or directory) <0.000013>
11:51:44.858095 kill(1785191, SIGURG) = 0 <0.000015>
11:51:44.858136 epoll_pwait(10, [{EPOLLIN, {u32=3733868344, u64=187650855030584}}], 1, 5000, NULL, 8) = 1 <0.009127>
11:51:44.867318 read(9, "…", 1024) = 128 <0.000014>
11:51:44.867371 pread64(4, "…"..., 8192, 4669440) = 8192 <0.000016>
11:51:44.867467 newfstatat(AT_FDCWD, "/var/lib/postgresql/14/main/finish.recovery", 0xffffe5362908, 0) = -1 ENOENT (No such file or directory) <0.000016>
11:51:44.867521 kill(1785191, SIGURG) = 0 <0.000016>
11:51:44.867566 epoll_pwait(10, [{EPOLLIN, {u32=3733868344, u64=187650855030584}}], 1, 5000, NULL, 8) = 1 <0.009935>
11:51:44.877535 read(9, "…", 1024) = 128 <0.000014>
11:51:44.877582 pread64(4, "…"..., 8192, 4669440) = 8192 <0.000016>
11:51:44.877650 newfstatat(AT_FDCWD, "/var/lib/postgresql/14/main/finish.recovery", 0xffffe5362908, 0) = -1 ENOENT (No such file or directory) <0.000015>
11:51:44.877699 kill(1785191, SIGURG) = 0 <0.000016>
11:51:44.877745 epoll_pwait(10, [{EPOLLIN, {u32=3733868344, u64=187650855030584}}], 1, 5000, NULL, 8) = 1 <0.002926>
11:51:44.880703 read(9, "…", 1024) = 128 <0.000015>
11:51:44.880749 pread64(4, "…"..., 8192, 4669440) = 8192 <0.000015>
11:51:44.880802 newfstatat(AT_FDCWD, "/var/lib/postgresql/14/main/finish.recovery", 0xffffe5362908, 0) = -1 ENOENT (No such file or directory) <0.000015>
11:51:44.880851 kill(1785191, SIGURG) = 0 <0.000016>
11:51:44.880894 epoll_pwait(10, [{EPOLLIN, {u32=3733868344, u64=187650855030584}}], 1, 5000, NULL, 8) = 1 <0.008904>
11:51:44.889841 read(9, "…", 1024) = 128 <0.000014>
11:51:44.889888 pread64(4, "…"..., 8192, 4669440) = 8192 <0.000015>
11:51:44.889944 newfstatat(AT_FDCWD, "/var/lib/postgresql/14/main/finish.recovery", 0xffffe5362908, 0) = -1 ENOENT (No such file or directory) <0.000015>
11:51:44.889992 kill(1785191, SIGURG) = 0 <0.000016>
11:51:44.890035 epoll_pwait(10, [{EPOLLIN, {u32=3733868344, u64=187650855030584}}], 1, 5000, NULL, 8) = 1 <0.008759>
11:51:44.898838 read(9, "…", 1024) = 128 <0.000013>
11:51:44.898881 pread64(4, "…"..., 8192, 4669440) = 8192 <0.000014>
11:51:44.898941 newfstatat(AT_FDCWD, "/var/lib/postgresql/14/main/finish.recovery", 0xffffe5362908, 0) = -1 ENOENT (No such file or directory) <0.000015>
11:51:44.898990 epoll_pwait(10, [{EPOLLIN, {u32=3733868344, u64=187650855030584}}], 1, 5000, NULL, 8) = 1 <0.000798>
11:51:44.899829 read(9, "…", 1024) = 128 <0.000014>
11:51:44.899874 pread64(4, "…"..., 8192, 4669440) = 8192 <0.000015>
11:51:44.899928 newfstatat(AT_FDCWD, "/var/lib/postgresql/14/main/finish.recovery", 0xffffe5362908, 0) = -1 ENOENT (No such file or directory) <0.000015>
11:51:44.899976 kill(1785191, SIGURG) = 0 <0.000016>
11:51:44.900020 epoll_pwait(10, [{EPOLLIN, {u32=3733868344, u64=187650855030584}}], 1, 5000, NULL, 8) = 1 <0.013089>
11:51:44.913151 read(9, "…", 1024) = 128 <0.000014>
11:51:44.913199 pread64(4, "…"..., 8192, 4669440) = 8192 <0.000019>
11:51:44.913251 pread64(4, "…"..., 8192, 4677632) = 8192 <0.000015>
11:51:44.913302 newfstatat(AT_FDCWD, "/var/lib/postgresql/14/main/finish.recovery", 0xffffe5362908, 0) = -1 ENOENT (No such file or directory) <0.000015>
11:51:44.913352 epoll_pwait(10, [{EPOLLIN, {u32=3733868344, u64=187650855030584}}], 1, 5000, NULL, 8) = 1 <0.000720>
11:51:44.914105 read(9, "…", 1024) = 128 <0.000013>
11:51:44.914159 pread64(4, "…"..., 8192, 4677632) = 8192 <0.000015>
11:51:44.914211 newfstatat(AT_FDCWD, "/var/lib/postgresql/14/main/finish.recovery", 0xffffe5362908, 0) = -1 ENOENT (No such file or directory) <0.000015>
11:51:44.914259 epoll_pwait(10, [{EPOLLIN, {u32=3733868344, u64=187650855030584}}], 1, 5000, NULL, 8) = 1 <0.000788>
11:51:44.915084 read(9, "…", 1024) = 128 <0.000014>
11:51:44.915128 pread64(4, "…"..., 8192, 4677632) = 8192 <0.000016>
11:51:44.915180 newfstatat(AT_FDCWD, "/var/lib/postgresql/14/main/finish.recovery", 0xffffe5362908, 0) = -1 ENOENT (No such file or directory) <0.000015>
11:51:44.915227 kill(1785191, SIGURG) = 0 <0.000017>
11:51:44.915272 epoll_pwait(10, [{EPOLLIN, {u32=3733868344, u64=187650855030584}}], 1, 5000, NULL, 8) = 1 <0.006360>
11:51:44.921673 read(9, "…", 1024) = 128 <0.000014>
11:51:44.921718 pread64(4, "…"..., 8192, 4677632) = 8192 <0.000015>
11:51:44.921775 newfstatat(AT_FDCWD, "/var/lib/postgresql/14/main/finish.recovery", 0xffffe5362908, 0) = -1 ENOENT (No such file or directory) <0.000015>
11:51:44.921825 epoll_pwait(10, [{EPOLLIN, {u32=3733868344, u64=187650855030584}}], 1, 5000, NULL, 8) = 1 <0.000806>
11:51:44.922663 read(9, "…", 1024) = 128 <0.000013>
11:51:44.922708 pread64(4, "…"..., 8192, 4677632) = 8192 <0.000015>
11:51:44.922787 newfstatat(AT_FDCWD, "/var/lib/postgresql/14/main/finish.recovery", 0xffffe5362908, 0) = -1 ENOENT (No such file or directory) <0.000015>
11:51:44.922843 kill(1785191, SIGURG) = 0 <0.000016>
11:51:44.922887 epoll_pwait(10, [{EPOLLIN, {u32=3733868344, u64=187650855030584}}], 1, 5000, NULL, 8) = 1 <0.001993>
11:51:44.924912 read(9, "…", 1024) = 128 <0.000013>
11:51:44.924957 pread64(4, "…"..., 8192, 4677632) = 8192 <0.000015>
11:51:44.925006 newfstatat(AT_FDCWD, "/var/lib/postgresql/14/main/finish.recovery", 0xffffe5362908, 0) = -1 ENOENT (No such file or directory) <0.000015>
11:51:44.925053 kill(1785191, SIGURG) = 0 <0.000016>
11:51:44.925097 epoll_pwait(10, [{EPOLLIN, {u32=3733868344, u64=187650855030584}}], 1, 5000, NULL, 8) = 1 <0.005110>
11:51:44.930239 read(9, "…", 1024) = 128 <0.000014>
11:51:44.930285 pread64(4, "…"..., 8192, 4677632) = 8192 <0.000015>
11:51:44.930343 newfstatat(AT_FDCWD, "/var/lib/postgresql/14/main/finish.recovery", 0xffffe5362908, 0) = -1 ENOENT (No such file or directory) <0.000015>
11:51:44.930390 kill(1785191, SIGURG) = 0 <0.000016>
11:51:44.930434 epoll_pwait(10, [{EPOLLIN, {u32=3733868344, u64=187650855030584}}], 1, 5000, NULL, 8) = 1 <0.001418>
11:51:44.931890 read(9, "…", 1024) = 128 <0.000013>
11:51:44.931935 pread64(4, "…"..., 8192, 4677632) = 8192 <0.000015>
11:51:44.931985 pread64(4, "…"..., 8192, 4685824) = 8192 <0.000015>
11:51:44.932074 newfstatat(AT_FDCWD, "/var/lib/postgresql/14/main/finish.recovery", 0xffffe5362908, 0) = -1 ENOENT (No such file or directory) <0.000016>
11:51:44.932122 kill(1785191, SIGURG) = 0 <0.000016>
11:51:44.932165 epoll_pwait(10, [{EPOLLIN, {u32=3733868344, u64=187650855030584}}], 1, 5000, NULL, 8) = 1 <0.006029>
11:51:44.938243 read(9, "…", 1024) = 128 <0.000014>
11:51:44.938289 pread64(4, "…"..., 8192, 4685824) = 8192 <0.000015>
11:51:44.938342 newfstatat(AT_FDCWD, "/var/lib/postgresql/14/main/finish.recovery", 0xffffe5362908, 0) = -1 ENOENT (No such file or directory) <0.000015>
11:51:44.938391 kill(1785191, SIGURG) = 0 <0.000016>
11:51:44.938435 epoll_pwait(10, [{EPOLLIN, {u32=3733868344, u64=187650855030584}}], 1, 5000, NULL, 8) = 1 <0.004870>
11:51:44.943345 read(9, "…", 1024) = 128 <0.000014>
11:51:44.943391 pread64(4, "…"..., 8192, 4685824) = 8192 <0.000015>
11:51:44.943449 pread64(4, "…"..., 8192, 4694016) = 8192 <0.000070>
11:51:44.943574 newfstatat(AT_FDCWD, "/var/lib/postgresql/14/main/finish.recovery", 0xffffe5362908, 0) = -1 ENOENT (No such file or directory) <0.000016>
11:51:44.943622 kill(1785191, SIGURG) = 0 <0.000017>
11:51:44.943667 epoll_pwait(10, [{EPOLLIN, {u32=3733868344, u64=187650855030584}}], 1, 5000, NULL, 8) = 1 <0.017585>
11:51:44.961298 read(9, "…", 1024) = 128 <0.000014>
11:51:44.961346 pread64(4, "…"..., 8192, 4694016) = 8192 <0.000016>
11:51:44.961437 newfstatat(AT_FDCWD, "/var/lib/postgresql/14/main/finish.recovery", 0xffffe5362908, 0) = -1 ENOENT (No such file or directory) <0.000016>
11:51:44.961487 kill(1785191, SIGURG) = 0 <0.000017>
11:51:44.961532 epoll_pwait(10, [{EPOLLIN, {u32=3733868344, u64=187650855030584}}], 1, 5000, NULL, 8) = 1 <0.004287>
11:51:44.965860 read(9, "…", 1024) = 128 <0.000013>
11:51:44.965906 pread64(4, "…"..., 8192, 4694016) = 8192 <0.000015>
11:51:44.965967 newfstatat(AT_FDCWD, "/var/lib/postgresql/14/main/finish.recovery", 0xffffe5362908, 0) = -1 ENOENT (No such file or directory) <0.000015>
11:51:44.966015 kill(1785191, SIGURG) = 0 <0.000017>
11:51:44.966061 epoll_pwait(10, [{EPOLLIN, {u32=3733868344, u64=187650855030584}}], 1, 5000, NULL, 8) = 1 <0.006415>
11:51:44.972508 read(9, "…", 1024) = 128 <0.000013>
11:51:44.972552 pread64(4, "…"..., 8192, 4694016) = 8192 <0.000014>
11:51:44.972605 pread64(4, "…"..., 8192, 4702208) = 8192 <0.000015>
11:51:44.972658 newfstatat(AT_FDCWD, "/var/lib/postgresql/14/main/finish.recovery", 0xffffe5362908, 0) = -1 ENOENT (No such file or directory) <0.000015>
11:51:44.972705 kill(1785191, SIGURG) = 0 <0.000016>
11:51:44.972748 epoll_pwait(10, [{EPOLLIN, {u32=3733868344, u64=187650855030584}}], 1, 5000, NULL, 8) = 1 <0.005758>
11:51:44.978548 read(9, "…", 1024) = 128 <0.000014>
11:51:44.978594 pread64(4, "…"..., 8192, 4702208) = 8192 <0.000015>
11:51:44.978642 pread64(4, "…"..., 8192, 4710400) = 8192 <0.000016>
11:51:44.978714 newfstatat(AT_FDCWD, "/var/lib/postgresql/14/main/finish.recovery", 0xffffe5362908, 0) = -1 ENOENT (No such file or directory) <0.000015>
11:51:44.978763 kill(1785191, SIGURG) = 0 <0.000016>
11:51:44.978807 epoll_pwait(10, [{EPOLLIN, {u32=3733868344, u64=187650855030584}}], 1, 5000, NULL, 8) = 1 <0.005587>
11:51:44.984426 read(9, "…", 1024) = 128 <0.000013>
11:51:44.984471 pread64(4, "…"..., 8192, 4710400) = 8192 <0.000015>
11:51:44.984557 pread64(4, "…"..., 8192, 4718592) = 8192 <0.000016>
11:51:44.984649 newfstatat(AT_FDCWD, "/var/lib/postgresql/14/main/finish.recovery", 0xffffe5362908, 0) = -1 ENOENT (No such file or directory) <0.000015>
11:51:44.984696 kill(1785191, SIGURG) = 0 <0.000017>
11:51:44.984741 epoll_pwait(10, [{EPOLLIN, {u32=3733868344, u64=187650855030584}}], 1, 5000, NULL, 8) = 1 <0.003444>
11:51:44.988217 read(9, "…", 1024) = 128 <0.000014>
11:51:44.988262 pread64(4, "…"..., 8192, 4718592) = 8192 <0.000018>
11:51:44.988315 newfstatat(AT_FDCWD, "/var/lib/postgresql/14/main/finish.recovery", 0xffffe5362908, 0) = -1 ENOENT (No such file or directory) <0.000015>
11:51:44.988362 epoll_pwait(10, [{EPOLLIN, {u32=3733868344, u64=187650855030584}}], 1, 5000, NULL, 8) = 1 <0.000829>
11:51:44.989222 read(9, "…", 1024) = 128 <0.000014>
11:51:44.989268 pread64(4, "…"..., 8192, 4718592) = 8192 <0.000015>
11:51:44.989315 newfstatat(AT_FDCWD, "/var/lib/postgresql/14/main/finish.recovery", 0xffffe5362908, 0) = -1 ENOENT (No such file or directory) <0.000015>
11:51:44.989369 kill(1785191, SIGURG) = 0 <0.000018>
11:51:44.989416 epoll_pwait(10, [{EPOLLIN, {u32=3733868344, u64=187650855030584}}], 1, 5000, NULL, 8) = 1 <0.008689>
11:51:44.998139 read(9, "…", 1024) = 128 <0.000013>
11:51:44.998183 pread64(4, "…"..., 8192, 4718592) = 8192 <0.000014>
11:51:44.998233 newfstatat(AT_FDCWD, "/var/lib/postgresql/14/main/finish.recovery", 0xffffe5362908, 0) = -1 ENOENT (No such file or directory) <0.000017>
11:51:44.998281 kill(1785191, SIGURG) = 0 <0.000016>
11:51:44.998325 epoll_pwait(10, [{EPOLLIN, {u32=3733868344, u64=187650855030584}}], 1, 5000, NULL, 8) = 1 <0.002090>
…
Does it mean anything to you?
Best regards,
depesz
From | Date | Subject | |
---|---|---|---|
Next Message | Achilleas Mantzios | 2025-08-25 14:40:06 | Re: Strange deadlock with object/target of lock : transaction |
Previous Message | Dominique Devienne | 2025-08-25 12:38:02 | Re: Q: GRANT ... WITH ADMIN on PG 17 |