Re: 8.1.3 and unused files

On Fri, 2006-05-05 at 15:10 -0400, Tom Lane wrote:
> Rod Taylor <pg(at)rbt(dot)ca> writes:
> > On Fri, 2006-05-05 at 14:31 -0400, Tom Lane wrote:
> >> Unless you had an actual backend crash, that's not an adequate
> >> explanation. Transaction abort does clean up created files.
>
> > The only thing I can come up with is that perhaps someone forcefully
> > gave it a kick. SIGTERM is a necessary action once in a while to unwedge
> > a stuck db connection (killing the client script doesn't always get it
> > immediately).
>
> SIGTERM wouldn't cause that either. I hope your people are not in the
> habit of using "kill -9"?

Command line records, etc. are not available, but I did track down a a
snippet of logs from the backups (daily log rotation). Sorry, I didn't
realize there were backups initially -- it's unusual. Appears it did
crash or get killed in some way "exited with exit code 1".

It's a temp DB to try a different database encoding (prep for 8.1
upgrade) with production data.

Is there something you would like me to try doing in an attempt to
reproduce? Preferably with a smaller structure.

The truncate would have happened as part of the prepareTableForCopy()
call.

slony%ssdb 10171 4621947 2006-05-02 05:09:40 EDTLOG: 00000: duration: 30526368.316 ms statement: select "_ssrep".prepareTableForCopy(1010); copy <SNIP> from stdin;
slony%ssdb 10171 4621947 2006-05-02 05:09:40 EDTLOCATION: exec_simple_query, postgres.c:1103
slony%ssdb 10171 4621947 2006-05-02 05:09:40 EDTLOCATION: exec_simple_query, postgres.c:1103
slony%ssdb 10181 0 2006-05-02 15:32:06 EDTLOG: 08P01: unexpected EOF on client connection
slony%ssdb 10181 0 2006-05-02 15:32:06 EDTLOCATION: SocketBackend, postgres.c:295
slony%ssdb 10154 0 2006-05-02 15:32:06 EDTLOG: 08P01: unexpected EOF on client connection
slony%ssdb 10154 0 2006-05-02 15:32:06 EDTLOCATION: SocketBackend, postgres.c:295
slony%ssdb 10173 0 2006-05-02 16:30:53 EDTLOG: 08P01: unexpected EOF on client connection
slony%ssdb 10173 0 2006-05-02 16:30:53 EDTLOCATION: SocketBackend, postgres.c:295
slony%ssdb 10755 0 2006-05-02 16:30:53 EDTLOG: 08P01: unexpected EOF on client connection
slony%ssdb 10755 0 2006-05-02 16:30:53 EDTLOCATION: SocketBackend, postgres.c:295
slony%ssdb 300 0 2006-05-02 16:55:18 EDTLOG: 08P01: unexpected EOF on client connection
slony%ssdb 300 0 2006-05-02 16:55:18 EDTLOCATION: SocketBackend, postgres.c:295
slony%ssdb 301 0 2006-05-02 16:55:18 EDTLOG: 08P01: unexpected EOF on client connection
slony%ssdb 301 0 2006-05-02 16:55:18 EDTLOCATION: SocketBackend, postgres.c:295
% 1960 2006-05-02 17:03:19 EDTLOG: 00000: server process (PID 10171) exited with exit code 1
% 1960 2006-05-02 17:03:19 EDTLOCATION: LogChildExit, postmaster.c:2416
% 1960 2006-05-02 17:03:19 EDTLOG: 00000: terminating any other active server processes
% 1960 2006-05-02 17:03:19 EDTLOCATION: HandleChildCrash, postmaster.c:2306
% 1960 2006-05-02 17:03:19 EDTLOCATION: HandleChildCrash, postmaster.c:2306
% 1960 2006-05-02 17:03:19 EDTLOG: 00000: all server processes terminated; reinitializing
% 1960 2006-05-02 17:03:19 EDTLOCATION: reaper, postmaster.c:2206
< snip connection attempts >
% 5826 2006-05-02 17:03:22 EDTLOG: 00000: database system was interrupted at 2006-05-02 16:06:20 EDT
% 5826 2006-05-02 17:03:22 EDTLOCATION: StartupXLOG, xlog.c:4374
% 5826 2006-05-02 17:03:22 EDTLOG: 00000: checkpoint record is at 59/E0B56920
% 5826 2006-05-02 17:03:22 EDTLOCATION: StartupXLOG, xlog.c:4442
% 5826 2006-05-02 17:03:22 EDTLOG: 00000: redo record is at 59/E0B56920; undo record is at 0/0; shutdown FALSE
% 5826 2006-05-02 17:03:22 EDTLOCATION: StartupXLOG, xlog.c:4469
% 5826 2006-05-02 17:03:22 EDTLOG: 00000: next transaction ID: 4863932; next OID: 1441853
% 5826 2006-05-02 17:03:22 EDTLOCATION: StartupXLOG, xlog.c:4472
% 5826 2006-05-02 17:03:22 EDTLOG: 00000: next MultiXactId: 1; next MultiXactOffset: 0
% 5826 2006-05-02 17:03:22 EDTLOCATION: StartupXLOG, xlog.c:4475
% 5826 2006-05-02 17:03:22 EDTLOG: 00000: database system was not properly shut down; automatic recovery in progress
% 5826 2006-05-02 17:03:22 EDTLOCATION: StartupXLOG, xlog.c:4532
% 5826 2006-05-02 17:03:22 EDTLOG: 00000: redo starts at 59/E0B56970
% 5826 2006-05-02 17:03:22 EDTLOCATION: StartupXLOG, xlog.c:4569
% 5826 2006-05-02 17:03:22 EDTLOG: 00000: record with zero length at 59/E0E429B8
% 5826 2006-05-02 17:03:22 EDTLOCATION: ReadRecord, xlog.c:2764
% 5826 2006-05-02 17:03:22 EDTLOG: 00000: redo done at 59/E0E42988
% 5826 2006-05-02 17:03:22 EDTLOCATION: StartupXLOG, xlog.c:4627
% 5826 2006-05-02 17:03:23 EDTLOG: 00000: database system is ready
% 5826 2006-05-02 17:03:23 EDTLOCATION: StartupXLOG, xlog.c:4821
% 5826 2006-05-02 17:03:23 EDTLOG: 00000: transaction ID wrap limit is 1073749769, limited by database "ssdb"
% 5826 2006-05-02 17:03:23 EDTLOCATION: SetTransactionIdLimit, varsup.c:234
--