Re: Unexpected page allocation behavior on insert-only tables - Mailing list pgsql-hackers

From Tom Lane
Subject Re: Unexpected page allocation behavior on insert-only tables
Date
Msg-id 24230.1275273745@sss.pgh.pa.us
Whole thread Raw
In response to Re: Unexpected page allocation behavior on insert-only tables  (Alvaro Herrera <alvherre@alvh.no-ip.org>)
Responses Re: Unexpected page allocation behavior on insert-only tables
Re: Unexpected page allocation behavior on insert-only tables
Re: Unexpected page allocation behavior on insert-only tables
List pgsql-hackers
Alvaro Herrera <alvherre@alvh.no-ip.org> writes:
> Excerpts from Michael Renner's message of sáb may 15 20:24:36 -0400 2010:
>>> I've written a simple tool to generate traffic on a database [1], which
>>> did about 30 TX/inserts per second to a table. Upon inspecting the data
>>> in the table, I noticed the expected grouping of tuples which came from
>>> a single backend to matching pages [2]. The strange part was that the
>>> pages weren't completely filled but the backends seemed to jump
>>> arbitrarily from one page to the next [3]. For the table in question
>>> this resulted in about 10% wasted space.

> I think this may be related to the smgr_targblock stuff; if the relcache
> entry gets invalidated at the wrong time for whatever reason, the
> "current page" could be abandoned in favor of extending the rel.  This
> has changed since 8.4, but a quick perusal suggests that it should be
> less likely on 9.0 than 8.4 but maybe there's something weird going on.

I found time to try this example finally.  The behavior that I see in
HEAD is even worse than Michael describes: there is room for 136 rows
per block in the bid table, but most blocks have only a few rows.  The
distribution after letting the exerciser run for 500 bids or so is
typically like this:

  #rows  block#
    136  0
      6  1
      5  2
      4  3
      3  4
      5  5
      3  6
      1  7
      4  8
      4  9
    136  10
      6  11
      7  12
      9  13
      9  14
      7  15
      9  16
      7  17
      8  18
      5  19
    136  20
      2  21
      4  22
      4  23
      3  24
      5  25
      3  26
      4  27
      3  28
      2  29
      1  30

Examining the insertion timestamps and bidder numbers (client process
IDs), and correlating this with logged autovacuum activity, makes it
pretty clear what is going on.  See the logic in
RelationGetBufferForTuple, and note that at no time do we have any FSM
data for the bid table:

1. Initially, all backends will decide to insert into block 0.  They do
so until the block is full.

2. At that point, each active backend individually decides it needs to
extend the relation.  They each create a new block and start inserting
into that one, each carefully not telling anyone else about the block
so as to avoid block-level insertion contention.  In the above diagram,
blocks 1-9 are each created by a different backend and the rows inserted
into it come (mostly?) from just one backend.  Block 10's first few rows
also come from the one backend that created it, but it doesn't manage to
fill the block entirely before ...

3. After awhile, autovacuum notices all the insert activity and kicks
off an autoanalyze on the bid table.  When committed, this forces a
relcache flush for each other backend's relcache entry for "bid".
In particular, the smgr targblock gets reset.

4. Now, all the backends again decide to try to insert into the last
available block.  So everybody jams into the partly-filled block 10,
until it gets filled.

5. Lather, rinse, repeat.  Since there are exactly 10 active clients
(by default) in this test program, the repeat distance is exactly 10
blocks.

The obvious thing to do about this would be to not reset targblock
on receipt of a relcache flush event, but we can *not* do that in the
general case.  The reason that that gets reset is so that it's not
left pointing to a no-longer-existent block after a VACUUM truncation.
Maybe we could develop a way to distinguish truncation events from
others, but right now the sinval signaling mechanism can't do that.
This looks like there might be sufficient grounds to do something,
though.

Attached exhibits: contents of relevant columns of the bid table
and postmaster log entries for autovacuum actions during the run.

            regards, tom lane

   ctid   | bidder |             time
----------+--------+-------------------------------
 (0,1)    |      1 | 2010-05-30 22:02:34.315279-04
 (0,2)    |      2 | 2010-05-30 22:02:34.664073-04
 (0,3)    |     10 | 2010-05-30 22:02:34.731018-04
 (0,4)    |      4 | 2010-05-30 22:02:34.787941-04
 (0,5)    |      6 | 2010-05-30 22:02:35.873605-04
 (0,6)    |      2 | 2010-05-30 22:02:36.173464-04
 (0,7)    |      4 | 2010-05-30 22:02:36.563819-04
 (0,8)    |      4 | 2010-05-30 22:02:37.039633-04
 (0,9)    |      3 | 2010-05-30 22:02:37.41705-04
 (0,10)   |      9 | 2010-05-30 22:02:37.66857-04
 (0,11)   |      8 | 2010-05-30 22:02:37.842781-04
 (0,12)   |      6 | 2010-05-30 22:02:39.554071-04
 (0,13)   |      9 | 2010-05-30 22:02:39.659859-04
 (0,14)   |      7 | 2010-05-30 22:02:40.470786-04
 (0,15)   |      6 | 2010-05-30 22:02:40.555843-04
 (0,16)   |      6 | 2010-05-30 22:02:42.587344-04
 (0,17)   |      5 | 2010-05-30 22:02:42.613972-04
 (0,18)   |      1 | 2010-05-30 22:02:42.624847-04
 (0,19)   |      3 | 2010-05-30 22:02:43.330164-04
 (0,20)   |      9 | 2010-05-30 22:02:43.480749-04
 (0,21)   |      3 | 2010-05-30 22:02:44.285052-04
 (0,22)   |      2 | 2010-05-30 22:02:44.810929-04
 (0,23)   |      4 | 2010-05-30 22:02:46.635974-04
 (0,24)   |      7 | 2010-05-30 22:02:47.172423-04
 (0,25)   |      1 | 2010-05-30 22:02:47.281285-04
 (0,26)   |      6 | 2010-05-30 22:02:50.451008-04
 (0,27)   |      3 | 2010-05-30 22:02:51.090318-04
 (0,28)   |      3 | 2010-05-30 22:02:51.649891-04
 (0,29)   |      2 | 2010-05-30 22:02:51.856301-04
 (0,30)   |      9 | 2010-05-30 22:02:52.061757-04
 (0,31)   |      6 | 2010-05-30 22:02:52.237952-04
 (0,32)   |      4 | 2010-05-30 22:02:52.468708-04
 (0,33)   |      5 | 2010-05-30 22:02:52.549329-04
 (0,34)   |      2 | 2010-05-30 22:02:52.584932-04
 (0,35)   |      6 | 2010-05-30 22:02:53.267937-04
 (0,36)   |      3 | 2010-05-30 22:02:53.382076-04
 (0,37)   |      7 | 2010-05-30 22:02:54.311843-04
 (0,38)   |      9 | 2010-05-30 22:02:54.877031-04
 (0,39)   |      7 | 2010-05-30 22:02:55.416411-04
 (0,40)   |      7 | 2010-05-30 22:02:55.757207-04
 (0,41)   |      4 | 2010-05-30 22:02:56.614201-04
 (0,42)   |      1 | 2010-05-30 22:02:56.986382-04
 (0,43)   |      3 | 2010-05-30 22:02:58.075939-04
 (0,44)   |      2 | 2010-05-30 22:02:58.158501-04
 (0,45)   |      2 | 2010-05-30 22:02:58.280742-04
 (0,46)   |      9 | 2010-05-30 22:02:58.366737-04
 (0,47)   |      1 | 2010-05-30 22:02:58.883395-04
 (0,48)   |      7 | 2010-05-30 22:03:00.238707-04
 (0,49)   |      3 | 2010-05-30 22:03:00.457997-04
 (0,50)   |      2 | 2010-05-30 22:03:00.64021-04
 (0,51)   |      3 | 2010-05-30 22:03:00.959877-04
 (0,52)   |      5 | 2010-05-30 22:03:01.984486-04
 (0,53)   |      2 | 2010-05-30 22:03:02.59789-04
 (0,54)   |      3 | 2010-05-30 22:03:02.919283-04
 (0,55)   |      7 | 2010-05-30 22:03:03.175857-04
 (0,56)   |      1 | 2010-05-30 22:03:03.328611-04
 (0,57)   |      9 | 2010-05-30 22:03:05.325658-04
 (0,58)   |      5 | 2010-05-30 22:03:08.137349-04
 (0,59)   |      3 | 2010-05-30 22:03:09.037132-04
 (0,60)   |      5 | 2010-05-30 22:03:09.48715-04
 (0,61)   |      9 | 2010-05-30 22:03:09.795161-04
 (0,62)   |      3 | 2010-05-30 22:03:10.429896-04
 (0,63)   |     12 | 2010-05-30 22:03:10.543446-04
 (0,64)   |      7 | 2010-05-30 22:03:11.805945-04
 (0,65)   |      2 | 2010-05-30 22:03:12.33334-04
 (0,66)   |      7 | 2010-05-30 22:03:13.039753-04
 (0,67)   |      3 | 2010-05-30 22:03:13.775375-04
 (0,68)   |      3 | 2010-05-30 22:03:13.983872-04
 (0,69)   |      5 | 2010-05-30 22:03:14.121341-04
 (0,70)   |      3 | 2010-05-30 22:03:15.650233-04
 (0,71)   |     14 | 2010-05-30 22:03:16.58213-04
 (0,72)   |      5 | 2010-05-30 22:03:18.677188-04
 (0,73)   |      3 | 2010-05-30 22:03:18.824854-04
 (0,74)   |     14 | 2010-05-30 22:03:20.04284-04
 (0,75)   |      3 | 2010-05-30 22:03:20.337673-04
 (0,76)   |      7 | 2010-05-30 22:03:20.845697-04
 (0,77)   |      7 | 2010-05-30 22:03:20.939362-04
 (0,78)   |      7 | 2010-05-30 22:03:20.982737-04
 (0,79)   |      7 | 2010-05-30 22:03:21.169013-04
 (0,80)   |      2 | 2010-05-30 22:03:22.072606-04
 (0,81)   |     13 | 2010-05-30 22:03:22.27614-04
 (0,82)   |      3 | 2010-05-30 22:03:22.30986-04
 (0,83)   |      8 | 2010-05-30 22:03:23.003485-04
 (0,84)   |      5 | 2010-05-30 22:03:23.63513-04
 (0,85)   |      5 | 2010-05-30 22:03:23.856639-04
 (0,86)   |      3 | 2010-05-30 22:03:24.348565-04
 (0,87)   |      5 | 2010-05-30 22:03:24.356068-04
 (0,88)   |      3 | 2010-05-30 22:03:26.732694-04
 (0,89)   |      7 | 2010-05-30 22:03:28.11097-04
 (0,90)   |     14 | 2010-05-30 22:03:28.171367-04
 (0,91)   |      2 | 2010-05-30 22:03:28.361304-04
 (0,92)   |      2 | 2010-05-30 22:03:29.65062-04
 (0,93)   |      8 | 2010-05-30 22:03:29.706961-04
 (0,94)   |      7 | 2010-05-30 22:03:30.561132-04
 (0,95)   |     13 | 2010-05-30 22:03:30.791242-04
 (0,96)   |      3 | 2010-05-30 22:03:31.190208-04
 (0,97)   |      8 | 2010-05-30 22:03:32.924914-04
 (0,98)   |      5 | 2010-05-30 22:03:33.361922-04
 (0,99)   |     13 | 2010-05-30 22:03:34.052696-04
 (0,100)  |      5 | 2010-05-30 22:03:34.247399-04
 (0,101)  |      5 | 2010-05-30 22:03:34.733424-04
 (0,102)  |     14 | 2010-05-30 22:03:35.248725-04
 (0,103)  |      3 | 2010-05-30 22:03:35.422218-04
 (0,104)  |     13 | 2010-05-30 22:03:35.613603-04
 (0,105)  |      2 | 2010-05-30 22:03:36.200464-04
 (0,106)  |      3 | 2010-05-30 22:03:37.072832-04
 (0,107)  |      8 | 2010-05-30 22:03:38.61889-04
 (0,108)  |     13 | 2010-05-30 22:03:39.930287-04
 (0,109)  |      7 | 2010-05-30 22:03:40.190397-04
 (0,110)  |      3 | 2010-05-30 22:03:40.46579-04
 (0,111)  |      3 | 2010-05-30 22:03:40.696981-04
 (0,112)  |      5 | 2010-05-30 22:03:40.712213-04
 (0,113)  |     13 | 2010-05-30 22:03:42.487266-04
 (0,114)  |      3 | 2010-05-30 22:03:42.539229-04
 (0,115)  |      5 | 2010-05-30 22:03:43.863454-04
 (0,116)  |     14 | 2010-05-30 22:03:44.422672-04
 (0,117)  |      3 | 2010-05-30 22:03:45.454489-04
 (0,118)  |      7 | 2010-05-30 22:03:45.559527-04
 (0,119)  |      2 | 2010-05-30 22:03:45.870314-04
 (0,120)  |      3 | 2010-05-30 22:03:46.45456-04
 (0,121)  |      3 | 2010-05-30 22:03:46.700556-04
 (0,122)  |      5 | 2010-05-30 22:03:47.408051-04
 (0,123)  |      5 | 2010-05-30 22:03:47.463331-04
 (0,124)  |      5 | 2010-05-30 22:03:47.494352-04
 (0,125)  |     14 | 2010-05-30 22:03:48.159308-04
 (0,126)  |     13 | 2010-05-30 22:03:48.602946-04
 (0,127)  |     13 | 2010-05-30 22:03:49.936622-04
 (0,128)  |     14 | 2010-05-30 22:03:51.156095-04
 (0,129)  |      8 | 2010-05-30 22:03:52.548205-04
 (0,130)  |      5 | 2010-05-30 22:03:52.971054-04
 (0,131)  |      3 | 2010-05-30 22:03:53.251663-04
 (0,132)  |      5 | 2010-05-30 22:03:53.419514-04
 (0,133)  |     14 | 2010-05-30 22:03:53.904537-04
 (0,134)  |      5 | 2010-05-30 22:03:54.994985-04
 (0,135)  |      5 | 2010-05-30 22:03:55.085393-04
 (0,136)  |      7 | 2010-05-30 22:03:55.383185-04
 (1,1)    |      2 | 2010-05-30 22:03:55.390418-04
 (1,2)    |      2 | 2010-05-30 22:03:56.437267-04
 (1,3)    |      2 | 2010-05-30 22:04:01.628036-04
 (1,4)    |      2 | 2010-05-30 22:04:09.869941-04
 (1,5)    |      2 | 2010-05-30 22:04:19.739467-04
 (1,6)    |      2 | 2010-05-30 22:04:21.082397-04
 (2,1)    |      5 | 2010-05-30 22:03:55.631213-04
 (2,2)    |      5 | 2010-05-30 22:04:00.291552-04
 (2,3)    |     15 | 2010-05-30 22:04:08.675358-04
 (2,4)    |     15 | 2010-05-30 22:04:09.504961-04
 (2,5)    |     15 | 2010-05-30 22:04:17.825315-04
 (3,1)    |      3 | 2010-05-30 22:03:56.185684-04
 (3,2)    |      3 | 2010-05-30 22:03:56.217881-04
 (3,3)    |      3 | 2010-05-30 22:04:05.983922-04
 (3,4)    |      3 | 2010-05-30 22:04:14.693978-04
 (4,1)    |     14 | 2010-05-30 22:03:56.529236-04
 (4,2)    |     14 | 2010-05-30 22:04:06.366691-04
 (4,3)    |     14 | 2010-05-30 22:04:12.969244-04
 (5,1)    |      8 | 2010-05-30 22:03:57.032587-04
 (5,2)    |      8 | 2010-05-30 22:04:04.711173-04
 (5,3)    |      8 | 2010-05-30 22:04:10.385307-04
 (5,4)    |      8 | 2010-05-30 22:04:12.995841-04
 (5,5)    |      8 | 2010-05-30 22:04:21.659177-04
 (6,1)    |      5 | 2010-05-30 22:03:57.736973-04
 (6,2)    |      5 | 2010-05-30 22:04:00.918095-04
 (6,3)    |      5 | 2010-05-30 22:04:16.858052-04
 (7,1)    |      3 | 2010-05-30 22:03:58.593202-04
 (8,1)    |     13 | 2010-05-30 22:03:59.459804-04
 (8,2)    |     13 | 2010-05-30 22:04:07.264812-04
 (8,3)    |     13 | 2010-05-30 22:04:14.346924-04
 (8,4)    |     13 | 2010-05-30 22:04:19.506547-04
 (9,1)    |      5 | 2010-05-30 22:04:00.404804-04
 (9,2)    |      5 | 2010-05-30 22:04:03.546589-04
 (9,3)    |      5 | 2010-05-30 22:04:12.098966-04
 (9,4)    |      5 | 2010-05-30 22:04:19.4649-04
 (10,1)   |      7 | 2010-05-30 22:04:02.963213-04
 (10,2)   |      7 | 2010-05-30 22:04:06.407131-04
 (10,3)   |      7 | 2010-05-30 22:04:15.511039-04
 (10,4)   |      7 | 2010-05-30 22:04:22.054093-04
 (10,5)   |      5 | 2010-05-30 22:04:22.875169-04
 (10,6)   |     15 | 2010-05-30 22:04:24.445067-04
 (10,7)   |     13 | 2010-05-30 22:04:24.563925-04
 (10,8)   |      7 | 2010-05-30 22:04:26.156114-04
 (10,9)   |      3 | 2010-05-30 22:04:26.675876-04
 (10,10)  |      5 | 2010-05-30 22:04:26.808316-04
 (10,11)  |      8 | 2010-05-30 22:04:27.162303-04
 (10,12)  |      5 | 2010-05-30 22:04:27.751572-04
 (10,13)  |      3 | 2010-05-30 22:04:29.172045-04
 (10,14)  |      2 | 2010-05-30 22:04:29.427004-04
 (10,15)  |      2 | 2010-05-30 22:04:30.407821-04
 (10,16)  |      8 | 2010-05-30 22:04:32.837345-04
 (10,17)  |      7 | 2010-05-30 22:04:33.27516-04
 (10,18)  |     15 | 2010-05-30 22:04:33.758701-04
 (10,19)  |      3 | 2010-05-30 22:04:34.482641-04
 (10,20)  |     16 | 2010-05-30 22:04:34.664568-04
 (10,21)  |     15 | 2010-05-30 22:04:36.155-04
 (10,22)  |      3 | 2010-05-30 22:04:36.278901-04
 (10,23)  |      2 | 2010-05-30 22:04:36.50105-04
 (10,24)  |      5 | 2010-05-30 22:04:36.827059-04
 (10,25)  |     13 | 2010-05-30 22:04:38.524347-04
 (10,26)  |      2 | 2010-05-30 22:04:39.239226-04
 (10,27)  |      2 | 2010-05-30 22:04:39.694077-04
 (10,28)  |     15 | 2010-05-30 22:04:39.708984-04
 (10,29)  |      5 | 2010-05-30 22:04:39.884655-04
 (10,30)  |      7 | 2010-05-30 22:04:40.578602-04
 (10,31)  |     15 | 2010-05-30 22:04:40.663863-04
 (10,32)  |      3 | 2010-05-30 22:04:41.088619-04
 (10,33)  |      3 | 2010-05-30 22:04:42.086321-04
 (10,34)  |     16 | 2010-05-30 22:04:42.646463-04
 (10,35)  |      8 | 2010-05-30 22:04:42.805799-04
 (10,36)  |      5 | 2010-05-30 22:04:44.25052-04
 (10,37)  |      3 | 2010-05-30 22:04:44.675792-04
 (10,38)  |     15 | 2010-05-30 22:04:45.40194-04
 (10,39)  |     16 | 2010-05-30 22:04:45.820147-04
 (10,40)  |      7 | 2010-05-30 22:04:45.904989-04
 (10,41)  |      2 | 2010-05-30 22:04:46.501651-04
 (10,42)  |     13 | 2010-05-30 22:04:46.679056-04
 (10,43)  |      3 | 2010-05-30 22:04:47.113848-04
 (10,44)  |     10 | 2010-05-30 22:04:47.426067-04
 (10,45)  |      5 | 2010-05-30 22:04:48.909876-04
 (10,46)  |     10 | 2010-05-30 22:04:49.549874-04
 (10,47)  |      3 | 2010-05-30 22:04:50.459377-04
 (10,48)  |     15 | 2010-05-30 22:04:51.902201-04
 (10,49)  |     13 | 2010-05-30 22:04:52.0388-04
 (10,50)  |     16 | 2010-05-30 22:04:52.865359-04
 (10,51)  |      7 | 2010-05-30 22:04:53.282653-04
 (10,52)  |      3 | 2010-05-30 22:04:53.566923-04
 (10,53)  |      3 | 2010-05-30 22:04:54.862262-04
 (10,54)  |      8 | 2010-05-30 22:04:55.431193-04
 (10,55)  |      2 | 2010-05-30 22:04:55.574083-04
 (10,56)  |     16 | 2010-05-30 22:04:56.372021-04
 (10,57)  |     13 | 2010-05-30 22:04:56.459045-04
 (10,58)  |     10 | 2010-05-30 22:04:56.730657-04
 (10,59)  |     15 | 2010-05-30 22:04:57.978827-04
 (10,60)  |      5 | 2010-05-30 22:04:58.093083-04
 (10,61)  |     13 | 2010-05-30 22:04:59.78596-04
 (10,62)  |      2 | 2010-05-30 22:05:00.162797-04
 (10,63)  |     15 | 2010-05-30 22:05:00.547095-04
 (10,64)  |     16 | 2010-05-30 22:05:00.972174-04
 (10,65)  |      8 | 2010-05-30 22:05:00.999949-04
 (10,66)  |     15 | 2010-05-30 22:05:01.469334-04
 (10,67)  |     10 | 2010-05-30 22:05:02.160411-04
 (10,68)  |      3 | 2010-05-30 22:05:02.302325-04
 (10,69)  |     15 | 2010-05-30 22:05:03.18475-04
 (10,70)  |      5 | 2010-05-30 22:05:03.510688-04
 (10,71)  |      5 | 2010-05-30 22:05:03.887237-04
 (10,72)  |     13 | 2010-05-30 22:05:04.422976-04
 (10,73)  |      8 | 2010-05-30 22:05:04.969423-04
 (10,74)  |      5 | 2010-05-30 22:05:06.002372-04
 (10,75)  |      3 | 2010-05-30 22:05:06.139659-04
 (10,76)  |     16 | 2010-05-30 22:05:06.234012-04
 (10,77)  |      2 | 2010-05-30 22:05:06.925084-04
 (10,78)  |      3 | 2010-05-30 22:05:07.064369-04
 (10,79)  |     13 | 2010-05-30 22:05:08.913288-04
 (10,80)  |     16 | 2010-05-30 22:05:08.958923-04
 (10,81)  |      8 | 2010-05-30 22:05:09.327576-04
 (10,82)  |      5 | 2010-05-30 22:05:09.344259-04
 (10,83)  |     15 | 2010-05-30 22:05:09.547854-04
 (10,84)  |     16 | 2010-05-30 22:05:10.008545-04
 (10,85)  |     15 | 2010-05-30 22:05:10.489665-04
 (10,86)  |     16 | 2010-05-30 22:05:10.780785-04
 (10,87)  |     10 | 2010-05-30 22:05:11.578183-04
 (10,88)  |      3 | 2010-05-30 22:05:11.919329-04
 (10,89)  |      3 | 2010-05-30 22:05:14.299399-04
 (10,90)  |     13 | 2010-05-30 22:05:14.479554-04
 (10,91)  |     10 | 2010-05-30 22:05:14.843091-04
 (10,92)  |     15 | 2010-05-30 22:05:14.968214-04
 (10,93)  |      8 | 2010-05-30 22:05:16.619045-04
 (10,94)  |     10 | 2010-05-30 22:05:17.168592-04
 (10,95)  |     15 | 2010-05-30 22:05:17.909316-04
 (10,96)  |     16 | 2010-05-30 22:05:18.099496-04
 (10,97)  |     15 | 2010-05-30 22:05:18.199018-04
 (10,98)  |      2 | 2010-05-30 22:05:18.392446-04
 (10,99)  |     15 | 2010-05-30 22:05:18.610399-04
 (10,100) |      5 | 2010-05-30 22:05:18.671028-04
 (10,101) |     16 | 2010-05-30 22:05:18.855266-04
 (10,102) |      2 | 2010-05-30 22:05:19.187143-04
 (10,103) |     15 | 2010-05-30 22:05:20.530605-04
 (10,104) |      3 | 2010-05-30 22:05:21.433012-04
 (10,105) |      2 | 2010-05-30 22:05:22.176109-04
 (10,106) |     13 | 2010-05-30 22:05:22.671407-04
 (10,107) |      3 | 2010-05-30 22:05:23.427644-04
 (10,108) |      8 | 2010-05-30 22:05:23.785148-04
 (10,109) |     13 | 2010-05-30 22:05:24.346186-04
 (10,110) |     10 | 2010-05-30 22:05:24.430648-04
 (10,111) |     15 | 2010-05-30 22:05:25.949055-04
 (10,112) |      5 | 2010-05-30 22:05:26.212027-04
 (10,113) |     16 | 2010-05-30 22:05:26.754683-04
 (10,114) |      2 | 2010-05-30 22:05:26.818682-04
 (10,115) |      5 | 2010-05-30 22:05:27.245066-04
 (10,116) |     10 | 2010-05-30 22:05:27.970603-04
 (10,117) |      3 | 2010-05-30 22:05:28.884857-04
 (10,118) |      3 | 2010-05-30 22:05:30.219441-04
 (10,119) |      8 | 2010-05-30 22:05:30.246895-04
 (10,120) |     15 | 2010-05-30 22:05:30.277736-04
 (10,121) |      3 | 2010-05-30 22:05:31.896931-04
 (10,122) |      5 | 2010-05-30 22:05:31.964403-04
 (10,123) |      2 | 2010-05-30 22:05:32.483057-04
 (10,124) |      3 | 2010-05-30 22:05:32.724733-04
 (10,125) |     13 | 2010-05-30 22:05:33.10718-04
 (10,126) |      3 | 2010-05-30 22:05:33.189649-04
 (10,127) |      2 | 2010-05-30 22:05:35.284732-04
 (10,128) |     13 | 2010-05-30 22:05:35.786865-04
 (10,129) |     16 | 2010-05-30 22:05:36.017323-04
 (10,130) |     10 | 2010-05-30 22:05:36.0248-04
 (10,131) |      8 | 2010-05-30 22:05:36.10732-04
 (10,132) |      3 | 2010-05-30 22:05:36.129561-04
 (10,133) |     13 | 2010-05-30 22:05:37.494743-04
 (10,134) |     16 | 2010-05-30 22:05:39.198999-04
 (10,135) |     15 | 2010-05-30 22:05:39.645164-04
 (10,136) |      3 | 2010-05-30 22:05:39.871763-04
 (11,1)   |      2 | 2010-05-30 22:05:40.636137-04
 (11,2)   |      2 | 2010-05-30 22:05:48.185134-04
 (11,3)   |      2 | 2010-05-30 22:05:50.280028-04
 (11,4)   |      2 | 2010-05-30 22:05:52.807023-04
 (11,5)   |     12 | 2010-05-30 22:06:09.049648-04
 (11,6)   |     12 | 2010-05-30 22:06:13.486697-04
 (12,1)   |      5 | 2010-05-30 22:05:41.186159-04
 (12,2)   |      5 | 2010-05-30 22:05:54.04493-04
 (12,3)   |      5 | 2010-05-30 22:05:57.985563-04
 (12,4)   |      5 | 2010-05-30 22:06:01.580222-04
 (12,5)   |      5 | 2010-05-30 22:06:06.030723-04
 (12,6)   |      5 | 2010-05-30 22:06:15.474481-04
 (12,7)   |      5 | 2010-05-30 22:06:17.329733-04
 (13,1)   |      2 | 2010-05-30 22:05:41.75727-04
 (13,2)   |      2 | 2010-05-30 22:05:44.06081-04
 (13,3)   |      2 | 2010-05-30 22:05:51.291318-04
 (13,4)   |      2 | 2010-05-30 22:05:54.793822-04
 (13,5)   |      2 | 2010-05-30 22:05:55.853234-04
 (13,6)   |      2 | 2010-05-30 22:06:04.381489-04
 (13,7)   |      2 | 2010-05-30 22:06:12.41618-04
 (13,8)   |      2 | 2010-05-30 22:06:13.555677-04
 (13,9)   |      2 | 2010-05-30 22:06:16.015511-04
 (14,1)   |      3 | 2010-05-30 22:05:42.119008-04
 (14,2)   |      3 | 2010-05-30 22:05:45.76651-04
 (14,3)   |      3 | 2010-05-30 22:05:51.05622-04
 (14,4)   |      3 | 2010-05-30 22:05:54.668724-04
 (14,5)   |      3 | 2010-05-30 22:05:59.961998-04
 (14,6)   |      3 | 2010-05-30 22:06:07.203422-04
 (14,7)   |      3 | 2010-05-30 22:06:07.873917-04
 (14,8)   |      3 | 2010-05-30 22:06:09.014275-04
 (14,9)   |     13 | 2010-05-30 22:06:19.799128-04
 (15,1)   |     16 | 2010-05-30 22:05:42.659635-04
 (15,2)   |     16 | 2010-05-30 22:05:53.244816-04
 (15,3)   |     16 | 2010-05-30 22:05:55.332821-04
 (15,4)   |     16 | 2010-05-30 22:06:05.250527-04
 (15,5)   |     16 | 2010-05-30 22:06:10.529188-04
 (15,6)   |     16 | 2010-05-30 22:06:19.745598-04
 (15,7)   |     16 | 2010-05-30 22:06:20.086468-04
 (16,1)   |     10 | 2010-05-30 22:05:45.32973-04
 (16,2)   |     10 | 2010-05-30 22:05:48.261647-04
 (16,3)   |     10 | 2010-05-30 22:05:49.104934-04
 (16,4)   |     10 | 2010-05-30 22:05:50.333536-04
 (16,5)   |     10 | 2010-05-30 22:05:54.623456-04
 (16,6)   |     10 | 2010-05-30 22:06:03.914076-04
 (16,7)   |     10 | 2010-05-30 22:06:09.022665-04
 (16,8)   |     10 | 2010-05-30 22:06:12.186486-04
 (16,9)   |     10 | 2010-05-30 22:06:19.077916-04
 (17,1)   |      7 | 2010-05-30 22:05:48.100083-04
 (17,2)   |      7 | 2010-05-30 22:05:54.935016-04
 (17,3)   |      7 | 2010-05-30 22:05:59.325791-04
 (17,4)   |      7 | 2010-05-30 22:06:04.875426-04
 (17,5)   |      7 | 2010-05-30 22:06:12.30384-04
 (17,6)   |      7 | 2010-05-30 22:06:17.132685-04
 (17,7)   |      7 | 2010-05-30 22:06:19.537419-04
 (18,1)   |      3 | 2010-05-30 22:05:48.791994-04
 (18,2)   |      3 | 2010-05-30 22:05:55.695815-04
 (18,3)   |      3 | 2010-05-30 22:06:05.437768-04
 (18,4)   |      3 | 2010-05-30 22:06:07.648808-04
 (18,5)   |      3 | 2010-05-30 22:06:14.87233-04
 (18,6)   |      3 | 2010-05-30 22:06:16.676052-04
 (18,7)   |      3 | 2010-05-30 22:06:20.121767-04
 (18,8)   |      3 | 2010-05-30 22:06:20.989883-04
 (19,1)   |     15 | 2010-05-30 22:05:48.933515-04
 (19,2)   |     15 | 2010-05-30 22:05:53.694439-04
 (19,3)   |     15 | 2010-05-30 22:06:03.067453-04
 (19,4)   |     15 | 2010-05-30 22:06:04.767331-04
 (19,5)   |     15 | 2010-05-30 22:06:13.068965-04
 (20,1)   |     13 | 2010-05-30 22:05:55.206664-04
 (20,2)   |     13 | 2010-05-30 22:06:04.930362-04
 (20,3)   |     13 | 2010-05-30 22:06:13.271518-04
 (20,4)   |     13 | 2010-05-30 22:06:22.365863-04
 (20,5)   |     15 | 2010-05-30 22:06:22.587572-04
 (20,6)   |      5 | 2010-05-30 22:06:23.731535-04
 (20,7)   |      2 | 2010-05-30 22:06:24.080207-04
 (20,8)   |     16 | 2010-05-30 22:06:24.431722-04
 (20,9)   |     10 | 2010-05-30 22:06:26.629308-04
 (20,10)  |      5 | 2010-05-30 22:06:26.651286-04
 (20,11)  |     15 | 2010-05-30 22:06:28.219624-04
 (20,12)  |      7 | 2010-05-30 22:06:28.473442-04
 (20,13)  |     13 | 2010-05-30 22:06:29.284351-04
 (20,14)  |      2 | 2010-05-30 22:06:29.568963-04
 (20,15)  |     15 | 2010-05-30 22:06:30.201825-04
 (20,16)  |      3 | 2010-05-30 22:06:30.589735-04
 (20,17)  |     13 | 2010-05-30 22:06:31.109409-04
 (20,18)  |      2 | 2010-05-30 22:06:31.403439-04
 (20,19)  |     10 | 2010-05-30 22:06:31.775056-04
 (20,20)  |     15 | 2010-05-30 22:06:32.397709-04
 (20,21)  |     13 | 2010-05-30 22:06:32.88401-04
 (20,22)  |     13 | 2010-05-30 22:06:33.269569-04
 (20,23)  |      7 | 2010-05-30 22:06:33.787248-04
 (20,24)  |     16 | 2010-05-30 22:06:34.159114-04
 (20,25)  |     15 | 2010-05-30 22:06:34.52088-04
 (20,26)  |     10 | 2010-05-30 22:06:34.799399-04
 (20,27)  |      7 | 2010-05-30 22:06:36.344221-04
 (20,28)  |      7 | 2010-05-30 22:06:36.84367-04
 (20,29)  |      3 | 2010-05-30 22:06:37.068362-04
 (20,30)  |     15 | 2010-05-30 22:06:38.287501-04
 (20,31)  |      2 | 2010-05-30 22:06:39.575314-04
 (20,32)  |     13 | 2010-05-30 22:06:39.883381-04
 (20,33)  |      1 | 2010-05-30 22:06:41.952922-04
 (20,34)  |     16 | 2010-05-30 22:06:42.71706-04
 (20,35)  |      3 | 2010-05-30 22:06:43.27876-04
 (20,36)  |      7 | 2010-05-30 22:06:43.368793-04
 (20,37)  |     10 | 2010-05-30 22:06:44.046684-04
 (20,38)  |     15 | 2010-05-30 22:06:44.296535-04
 (20,39)  |      2 | 2010-05-30 22:06:44.367201-04
 (20,40)  |     10 | 2010-05-30 22:06:46.807453-04
 (20,41)  |     15 | 2010-05-30 22:06:46.997143-04
 (20,42)  |     13 | 2010-05-30 22:06:47.393655-04
 (20,43)  |      1 | 2010-05-30 22:06:49.074983-04
 (20,44)  |      3 | 2010-05-30 22:06:51.558104-04
 (20,45)  |     16 | 2010-05-30 22:06:52.025233-04
 (20,46)  |     16 | 2010-05-30 22:06:52.143461-04
 (20,47)  |     10 | 2010-05-30 22:06:52.281195-04
 (20,48)  |     15 | 2010-05-30 22:06:52.375523-04
 (20,49)  |      7 | 2010-05-30 22:06:52.867119-04
 (20,50)  |     13 | 2010-05-30 22:06:53.741817-04
 (20,51)  |      6 | 2010-05-30 22:06:54.624369-04
 (20,52)  |     16 | 2010-05-30 22:06:54.947188-04
 (20,53)  |     13 | 2010-05-30 22:06:55.307961-04
 (20,54)  |      3 | 2010-05-30 22:06:55.315587-04
 (20,55)  |     15 | 2010-05-30 22:06:55.81127-04
 (20,56)  |      1 | 2010-05-30 22:06:58.550602-04
 (20,57)  |     16 | 2010-05-30 22:06:58.704792-04
 (20,58)  |     10 | 2010-05-30 22:06:58.785682-04
 (20,59)  |     17 | 2010-05-30 22:06:58.838312-04
 (20,60)  |      7 | 2010-05-30 22:07:00.810356-04
 (20,61)  |     16 | 2010-05-30 22:07:02.215049-04
 (20,62)  |     18 | 2010-05-30 22:07:02.786712-04
 (20,63)  |      3 | 2010-05-30 22:07:02.804707-04
 (20,64)  |      6 | 2010-05-30 22:07:02.880215-04
 (20,65)  |      7 | 2010-05-30 22:07:04.391959-04
 (20,66)  |      7 | 2010-05-30 22:07:04.465536-04
 (20,67)  |     15 | 2010-05-30 22:07:05.132527-04
 (20,68)  |      1 | 2010-05-30 22:07:05.280611-04
 (20,69)  |     15 | 2010-05-30 22:07:07.476944-04
 (20,70)  |      7 | 2010-05-30 22:07:07.858762-04
 (20,71)  |     10 | 2010-05-30 22:07:08.304321-04
 (20,72)  |     18 | 2010-05-30 22:07:08.433794-04
 (20,73)  |      6 | 2010-05-30 22:07:08.865215-04
 (20,74)  |     10 | 2010-05-30 22:07:09.303176-04
 (20,75)  |      7 | 2010-05-30 22:07:09.737608-04
 (20,76)  |     16 | 2010-05-30 22:07:10.470157-04
 (20,77)  |      3 | 2010-05-30 22:07:10.641557-04
 (20,78)  |      6 | 2010-05-30 22:07:10.648976-04
 (20,79)  |      1 | 2010-05-30 22:07:11.16398-04
 (20,80)  |     18 | 2010-05-30 22:07:11.431533-04
 (20,81)  |     18 | 2010-05-30 22:07:12.692684-04
 (20,82)  |     16 | 2010-05-30 22:07:12.74969-04
 (20,83)  |     10 | 2010-05-30 22:07:14.210497-04
 (20,84)  |      1 | 2010-05-30 22:07:15.977093-04
 (20,85)  |     18 | 2010-05-30 22:07:17.151924-04
 (20,86)  |     15 | 2010-05-30 22:07:17.217928-04
 (20,87)  |      1 | 2010-05-30 22:07:17.410405-04
 (20,88)  |     16 | 2010-05-30 22:07:18.210138-04
 (20,89)  |      7 | 2010-05-30 22:07:18.457819-04
 (20,90)  |      3 | 2010-05-30 22:07:19.113064-04
 (20,91)  |      6 | 2010-05-30 22:07:20.415202-04
 (20,92)  |     10 | 2010-05-30 22:07:20.509796-04
 (20,93)  |     15 | 2010-05-30 22:07:20.775745-04
 (20,94)  |      1 | 2010-05-30 22:07:20.938108-04
 (20,95)  |      1 | 2010-05-30 22:07:21.970712-04
 (20,96)  |     18 | 2010-05-30 22:07:22.659728-04
 (20,97)  |      1 | 2010-05-30 22:07:22.919481-04
 (20,98)  |      7 | 2010-05-30 22:07:23.256774-04
 (20,99)  |     18 | 2010-05-30 22:07:23.338927-04
 (20,100) |     10 | 2010-05-30 22:07:23.983037-04
 (20,101) |      1 | 2010-05-30 22:07:25.203907-04
 (20,102) |      1 | 2010-05-30 22:07:25.322318-04
 (20,103) |      1 | 2010-05-30 22:07:25.751673-04
 (20,104) |      3 | 2010-05-30 22:07:25.866868-04
 (20,105) |     16 | 2010-05-30 22:07:26.116402-04
 (20,106) |     10 | 2010-05-30 22:07:26.611058-04
 (20,107) |     16 | 2010-05-30 22:07:27.869843-04
 (20,108) |     18 | 2010-05-30 22:07:27.930089-04
 (20,109) |     15 | 2010-05-30 22:07:28.287146-04
 (20,110) |      1 | 2010-05-30 22:07:28.401998-04
 (20,111) |     16 | 2010-05-30 22:07:28.471901-04
 (20,112) |      6 | 2010-05-30 22:07:28.912135-04
 (20,113) |      1 | 2010-05-30 22:07:29.652131-04
 (20,114) |      1 | 2010-05-30 22:07:29.758991-04
 (20,115) |      1 | 2010-05-30 22:07:31.091658-04
 (20,116) |     15 | 2010-05-30 22:07:31.246403-04
 (20,117) |     18 | 2010-05-30 22:07:31.860414-04
 (20,118) |      1 | 2010-05-30 22:07:32.025975-04
 (20,119) |     16 | 2010-05-30 22:07:32.159433-04
 (20,120) |      7 | 2010-05-30 22:07:32.452886-04
 (20,121) |      7 | 2010-05-30 22:07:33.229635-04
 (20,122) |      1 | 2010-05-30 22:07:33.356003-04
 (20,123) |      3 | 2010-05-30 22:07:33.969782-04
 (20,124) |      1 | 2010-05-30 22:07:34.101896-04
 (20,125) |      1 | 2010-05-30 22:07:36.181644-04
 (20,126) |      3 | 2010-05-30 22:07:36.855136-04
 (20,127) |      3 | 2010-05-30 22:07:37.039873-04
 (20,128) |     16 | 2010-05-30 22:07:37.167042-04
 (20,129) |      1 | 2010-05-30 22:07:37.424978-04
 (20,130) |     15 | 2010-05-30 22:07:37.926044-04
 (20,131) |      6 | 2010-05-30 22:07:37.952489-04
 (20,132) |     16 | 2010-05-30 22:07:38.726096-04
 (20,133) |      1 | 2010-05-30 22:07:39.951693-04
 (20,134) |      1 | 2010-05-30 22:07:40.011907-04
 (20,135) |     18 | 2010-05-30 22:07:40.049167-04
 (20,136) |      1 | 2010-05-30 22:07:40.402348-04
 (21,1)   |      1 | 2010-05-30 22:07:41.032789-04
 (21,2)   |      1 | 2010-05-30 22:07:48.666174-04
 (22,1)   |      7 | 2010-05-30 22:07:42.41283-04
 (22,2)   |      7 | 2010-05-30 22:07:42.612303-04
 (22,3)   |      7 | 2010-05-30 22:07:50.427345-04
 (22,4)   |      7 | 2010-05-30 22:07:58.255948-04
 (23,1)   |      1 | 2010-05-30 22:07:43.145863-04
 (23,2)   |      1 | 2010-05-30 22:07:44.931314-04
 (23,3)   |      1 | 2010-05-30 22:07:53.533069-04
 (23,4)   |      1 | 2010-05-30 22:07:55.065667-04
 (24,1)   |      6 | 2010-05-30 22:07:43.864764-04
 (24,2)   |      6 | 2010-05-30 22:07:53.725297-04
 (24,3)   |      6 | 2010-05-30 22:07:59.040013-04
 (25,1)   |      1 | 2010-05-30 22:07:44.369411-04
 (25,2)   |      1 | 2010-05-30 22:07:47.526095-04
 (25,3)   |      1 | 2010-05-30 22:07:50.876715-04
 (25,4)   |      1 | 2010-05-30 22:07:57.394293-04
 (25,5)   |      1 | 2010-05-30 22:07:59.541561-04
 (26,1)   |     15 | 2010-05-30 22:07:45.956699-04
 (26,2)   |     15 | 2010-05-30 22:07:47.204866-04
 (26,3)   |     15 | 2010-05-30 22:07:52.953162-04
 (27,1)   |      3 | 2010-05-30 22:07:46.005272-04
 (27,2)   |      3 | 2010-05-30 22:07:48.432756-04
 (27,3)   |      3 | 2010-05-30 22:07:50.133422-04
 (27,4)   |      3 | 2010-05-30 22:07:57.679767-04
 (28,1)   |     19 | 2010-05-30 22:07:46.619672-04
 (28,2)   |     19 | 2010-05-30 22:07:47.432573-04
 (28,3)   |     19 | 2010-05-30 22:07:49.967202-04
 (29,1)   |     18 | 2010-05-30 22:07:47.298256-04
 (29,2)   |     18 | 2010-05-30 22:07:51.845929-04
 (30,1)   |     20 | 2010-05-30 22:07:59.062775-04
(541 rows)

LOG:  database system was shut down at 2010-05-30 17:32:38 EDT
LOG:  autovacuum launcher started
LOG:  database system is ready to accept connections
LOG:  received SIGHUP, reloading configuration files
2010-05-30 22:01:54.698 EDT 6405 LOG:  parameter "log_line_prefix" changed to "%m %p "
2010-05-30 22:01:54.698 EDT 6405 LOG:  parameter "log_autovacuum_min_duration" changed to "0"
2010-05-30 22:03:21.846 EDT 8713 LOG:  automatic analyze of table "sqlsim.public.auction" system usage: CPU 0.00s/0.00u
secelapsed 0.03 sec 
2010-05-30 22:03:21.860 EDT 8713 LOG:  automatic analyze of table "sqlsim.public.bid" system usage: CPU 0.00s/0.00u sec
elapsed0.00 sec 
2010-05-30 22:04:21.857 EDT 8720 LOG:  automatic analyze of table "sqlsim.public.auction" system usage: CPU 0.00s/0.00u
secelapsed 0.02 sec 
2010-05-30 22:04:21.871 EDT 8720 LOG:  automatic analyze of table "sqlsim.public.bid" system usage: CPU 0.00s/0.00u sec
elapsed0.00 sec 
2010-05-30 22:05:21.869 EDT 8726 LOG:  automatic analyze of table "sqlsim.public.auction" system usage: CPU 0.00s/0.00u
secelapsed 0.02 sec 
2010-05-30 22:05:21.884 EDT 8726 LOG:  automatic analyze of table "sqlsim.public.bid" system usage: CPU 0.00s/0.00u sec
elapsed0.00 sec 
2010-05-30 22:06:21.881 EDT 8733 LOG:  automatic analyze of table "sqlsim.public.auction" system usage: CPU 0.00s/0.00u
secelapsed 0.02 sec 
2010-05-30 22:06:21.894 EDT 8733 LOG:  automatic analyze of table "sqlsim.public.bid" system usage: CPU 0.00s/0.00u sec
elapsed0.00 sec 
2010-05-30 22:07:21.895 EDT 8740 LOG:  automatic analyze of table "sqlsim.public.auction" system usage: CPU 0.00s/0.00u
secelapsed 0.02 sec 
2010-05-30 22:07:21.910 EDT 8740 LOG:  automatic analyze of table "sqlsim.public.bid" system usage: CPU 0.00s/0.00u sec
elapsed0.00 sec 
2010-05-30 22:08:21.908 EDT 8753 LOG:  automatic analyze of table "sqlsim.public.auction" system usage: CPU 0.00s/0.00u
secelapsed 0.03 sec 

pgsql-hackers by date:

Previous
From: Daniele Varrazzo
Date:
Subject: Adding regexp_match() function
Next
From: Robert Haas
Date:
Subject: Re: Unexpected page allocation behavior on insert-only tables