Re: fsync and semctl errors with 8.1.5/win32 - Mailing list pgsql-bugs

From Thomas H.
Subject Re: fsync and semctl errors with 8.1.5/win32
Date
Msg-id 00f801c71827$43e5e700$0201a8c0@iwing
Whole thread Raw
In response to Re: fsync and semctl errors with 8.1.5/win32  ("Magnus Hagander" <mha@sollentuna.net>)
List pgsql-bugs
>> ... in addition to the above messages, the log is now also
>> flooded by:
>
>> 2006-12-05 04:16:29 [5196] LOG:  could not rename temporary statistics
>> file
>> "global/pgstat.tmp" to "global/pgstat.stat": A blocking operation was
>> interrupted by a call to WSACancelBlockingCall.
>
> Hm ... there simply isn't anything that holds pgstat.stat open for long,
> so this behavior seems independent of any other issues we might have.
> Can you find any evidence about what's wrong here?

hope this helps:

05:33:14 postgres.exe:5196 CREATE D:\DB\postgreSQL.82\data\global\pgstat.tmp
SUCCESS Options: OverwriteIf  Access: 00120196
05:33:14 postgres.exe:5196 WRITE  D:\DB\postgreSQL.82\data\global\pgstat.tmp
SUCCESS Offset: 0 Length: 4096
05:33:14 postgres.exe:5196 WRITE  D:\DB\postgreSQL.82\data\global\pgstat.tmp
SUCCESS Offset: 4096 Length: 4096
05:33:14 postgres.exe:5196 WRITE  D:\DB\postgreSQL.82\data\global\pgstat.tmp
SUCCESS Offset: 8192 Length: 4096
05:33:14 postgres.exe:5196 WRITE  D:\DB\postgreSQL.82\data\global\pgstat.tmp
SUCCESS Offset: 12288 Length: 4096
05:33:14 postgres.exe:5196 WRITE  D:\DB\postgreSQL.82\data\global\pgstat.tmp
SUCCESS Offset: 16384 Length: 4096
05:33:14 postgres.exe:5196 WRITE  D:\DB\postgreSQL.82\data\global\pgstat.tmp
SUCCESS Offset: 20480 Length: 4096
05:33:14 postgres.exe:5196 WRITE  D:\DB\postgreSQL.82\data\global\pgstat.tmp
SUCCESS Offset: 24576 Length: 4096
05:33:14 postgres.exe:5196 WRITE  D:\DB\postgreSQL.82\data\global\pgstat.tmp
SUCCESS Offset: 28672 Length: 4096
05:33:14 postgres.exe:5196 WRITE  D:\DB\postgreSQL.82\data\global\pgstat.tmp
SUCCESS Offset: 32768 Length: 4096
05:33:14 postgres.exe:5196 WRITE  D:\DB\postgreSQL.82\data\global\pgstat.tmp
SUCCESS Offset: 36864 Length: 4096
05:33:14 postgres.exe:5196 WRITE  D:\DB\postgreSQL.82\data\global\pgstat.tmp
SUCCESS Offset: 40960 Length: 4096
05:33:14 postgres.exe:5196 WRITE  D:\DB\postgreSQL.82\data\global\pgstat.tmp
SUCCESS Offset: 45056 Length: 4096
05:33:14 postgres.exe:5196 WRITE  D:\DB\postgreSQL.82\data\global\pgstat.tmp
SUCCESS Offset: 49152 Length: 1650
05:33:14 postgres.exe:5196 CLOSE D:\DB\postgreSQL.82\data\global\pgstat.tmp
SUCCESS
05:33:14 postgres.exe:5196 OPEN D:\DB\postgreSQL.82\data\global\pgstat.tmp
SUCCESS Options: Open  Access: 00110080
05:33:14 postgres.exe:5196 QUERY INFORMATION
D:\DB\postgreSQL.82\data\global\pgstat.tmp SUCCESS
FileAttributeTagInformation
05:33:14 postgres.exe:5196 QUERY INFORMATION
D:\DB\postgreSQL.82\data\global\pgstat.tmp SUCCESS Attributes: A
05:33:14 postgres.exe:5196 OPEN D:\DB\postgreSQL.82\data\global\pgstat.stat
SUCCESS Options: Open  Access: 00100002
05:33:14 postgres.exe:5196 SET INFORMATION
D:\DB\postgreSQL.82\data\global\pgstat.tmp * 0xC0000123
FileRenameInformation
05:33:14 postgres.exe:5196 CLOSE D:\DB\postgreSQL.82\data\global\pgstat.stat
SUCCESS
05:33:14 postgres.exe:5196 OPEN D:\DB\postgreSQL.82\data\global\pgstat.tmp
NOT FOUND Options: Open  Access: 00110080
05:33:14 postgres.exe:5196 OPEN D:\DB\postgreSQL.82\data\global\pgstat.tmp
NOT FOUND Options: Open  Access: 00010080
05:33:14 postgres.exe:5196 CREATE D:\DB\postgreSQL.82\data\global\pgstat.tmp
SUCCESS Options: OverwriteIf  Access: 00120196
05:33:14 postgres.exe:5196 WRITE  D:\DB\postgreSQL.82\data\global\pgstat.tmp
SUCCESS Offset: 0 Length: 4096
05:33:14 postgres.exe:5196 WRITE  D:\DB\postgreSQL.82\data\global\pgstat.tmp
SUCCESS Offset: 4096 Length: 4096
05:33:14 postgres.exe:5196 WRITE  D:\DB\postgreSQL.82\data\global\pgstat.tmp
SUCCESS Offset: 8192 Length: 4096
05:33:14 postgres.exe:5196 WRITE  D:\DB\postgreSQL.82\data\global\pgstat.tmp
SUCCESS Offset: 12288 Length: 4096
05:33:14 postgres.exe:5196 WRITE  D:\DB\postgreSQL.82\data\global\pgstat.tmp
SUCCESS Offset: 16384 Length: 4096
05:33:14 postgres.exe:5196 WRITE  D:\DB\postgreSQL.82\data\global\pgstat.tmp
SUCCESS Offset: 20480 Length: 4096
05:33:14 postgres.exe:5196 WRITE  D:\DB\postgreSQL.82\data\global\pgstat.tmp
SUCCESS Offset: 24576 Length: 4096
05:33:14 postgres.exe:5196 WRITE  D:\DB\postgreSQL.82\data\global\pgstat.tmp
SUCCESS Offset: 28672 Length: 4096
05:33:14 postgres.exe:5196 WRITE  D:\DB\postgreSQL.82\data\global\pgstat.tmp
SUCCESS Offset: 32768 Length: 4096
05:33:14 postgres.exe:5196 WRITE  D:\DB\postgreSQL.82\data\global\pgstat.tmp
SUCCESS Offset: 36864 Length: 4096
05:33:14 postgres.exe:5196 WRITE  D:\DB\postgreSQL.82\data\global\pgstat.tmp
SUCCESS Offset: 40960 Length: 4096
05:33:14 postgres.exe:5196 WRITE  D:\DB\postgreSQL.82\data\global\pgstat.tmp
SUCCESS Offset: 45056 Length: 4096
05:33:14 postgres.exe:5196 WRITE  D:\DB\postgreSQL.82\data\global\pgstat.tmp
SUCCESS Offset: 49152 Length: 1650
05:33:14 postgres.exe:5196 CLOSE D:\DB\postgreSQL.82\data\global\pgstat.tmp
SUCCESS
05:33:14 postgres.exe:5196 OPEN D:\DB\postgreSQL.82\data\global\pgstat.tmp
SUCCESS Options: Open  Access: 00110080
05:33:14 postgres.exe:5196 QUERY INFORMATION
D:\DB\postgreSQL.82\data\global\pgstat.tmp SUCCESS
FileAttributeTagInformation
05:33:14 postgres.exe:5196 QUERY INFORMATION
D:\DB\postgreSQL.82\data\global\pgstat.tmp SUCCESS Attributes: A
05:33:14 postgres.exe:5196 OPEN D:\DB\postgreSQL.82\data\global\pgstat.stat
SUCCESS Options: Open  Access: 00100002
05:33:14 postgres.exe:5196 SET INFORMATION
D:\DB\postgreSQL.82\data\global\pgstat.tmp * 0xC0000123
FileRenameInformation
05:33:14 postgres.exe:5196 CLOSE D:\DB\postgreSQL.82\data\global\pgstat.stat
SUCCESS
05:33:14 postgres.exe:5196 OPEN D:\DB\postgreSQL.82\data\global\pgstat.tmp
NOT FOUND Options: Open  Access: 00110080
05:33:14 postgres.exe:5196 OPEN D:\DB\postgreSQL.82\data\global\pgstat.tmp
NOT FOUND Options: Open  Access: 00010080

those are the file actions that are happening at the same time as the error
log message appears.
2006-12-05 05:33:14 [5196] LOG:  could not rename temporary statistics file
"global/pgstat.tmp" to "global/pgstat.stat": A blocking operation was
interrupted by a call to WSACancelBlockingCall.
2006-12-05 05:33:14 [5196] LOG:  could not rename temporary statistics file
"global/pgstat.tmp" to "global/pgstat.stat": A blocking operation was
interrupted by a call to WSACancelBlockingCall.

the next file actions with pgstat take place some seconds later.

more or less the same thing is happening every 2-3 seconds when the log
errors appear. there was a short pause of ~30min when this error didn't
appear, but for over 40min now it is logged every few seconds. the rc1
doesn't show this behaviour, i've just doublechecked.

- thomas

pgsql-bugs by date:

Previous
From: Tom Lane
Date:
Subject: Re: fsync and semctl errors with 8.1.5/win32
Next
From: "Thomas H."
Date:
Subject: Re: fsync and semctl errors with 8.1.5/win32