... 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
---------------------------(end of broadcast)---------------------------
TIP 9: In versions below 8.0, the planner will ignore your desire to
choose an index scan if your joining column's datatypes do not
match