<div class="im" style="color:rgb(80,0,80);font-family:arial,sans-serif;font-size:13px;background-color:rgb(255,255,255)"><blockquote style="margin:0px 0px 0px 40px;border:none;padding:0px"><div>&gt; For this to happen, am I right to say that I need to rsync the files in</div>
<div>&gt; pg_xlog (node 0) to my archive folder ( /thumbnails/postgres_archives/ ) on</div><div>&gt; node 1 ? My stage 2 script does not do that right now.</div><div><br></div><span style="color:rgb(34,34,34)">No at all. Copying pg_xlog to archive folder should be done by<br>
</span><span style="color:rgb(34,34,34)">archive_command by PostgreSQL.<br><br><br></span></blockquote><div><br></div></div><div style="color:rgb(34,34,34);font-family:arial,sans-serif;font-size:13px;background-color:rgb(255,255,255)">
<font color="#222222" face="arial, sans-serif">I do have an archive_command that will copy pg_xlog to my archive dir. However, I have two different archive folders, one on each of the DB servers. As a test, I ran an rsync from the archive folder on node0 to the archive folder on node1 (at the end of pgpool_recovery_pitr) and my recovery works fine now, including the transactions done during the recovery. Now, I suppose I could/should have the archive folder on a different server and have both node servers point to it to access that common archive folder</font></div>
<div style="color:rgb(34,34,34);font-family:arial,sans-serif;font-size:13px;background-color:rgb(255,255,255)"><font color="#222222" face="arial, sans-serif"><br></font></div><div style="color:rgb(34,34,34);font-family:arial,sans-serif;font-size:13px;background-color:rgb(255,255,255)">
<font color="#222222" face="arial, sans-serif">Thanks for your help!</font></div><br><div class="gmail_quote">On Thu, Jan 17, 2013 at 6:14 PM, Tatsuo Ishii <span dir="ltr">&lt;<a href="mailto:ishii@postgresql.org" target="_blank">ishii@postgresql.org</a>&gt;</span> wrote:<br>
<blockquote class="gmail_quote" style="margin:0 0 0 .8ex;border-left:1px #ccc solid;padding-left:1ex"><div class="im">&gt; Thank you for the reply.<br>
&gt;<br>
&gt; In the node 1 log, I dont find a &quot;last completed transaction&quot; line as you<br>
&gt; have in your example.<br>
<br>
</div>That&#39;s the cause of the problem. For your convenience, I attached my<br>
PostgreSQL log with &quot;log_min_messages = debug5&quot;.<br>
<br>
I guess you don&#39;t have desired archive files in<br>
/thumbnails/postgres_archives/pg_log.  Are you sure that you have<br>
/thumbnails/postgres_archives/pg_log/00000002000000040000008D on node 1?<br>
<br>
I suggest double check your postgresql.conf setting on node 0. Here is<br>
mine(different from the default):<br>
<br>
listen_addresses = &#39;*&#39;<br>
port = 11000<br>
logging_collector = on<br>
log_filename = &#39;%A.log&#39;<br>
log_line_prefix = &#39;%p %t &#39;<br>
log_truncate_on_rotation = on<br>
log_statement = &#39;all&#39;<br>
max_prepared_transactions = 10<br>
hot_standby = on<br>
wal_level = hot_standby<br>
max_wal_senders = 2<br>
log_connections = on<br>
log_disconnections = on<br>
archive_mode = on<br>
archive_command = &#39;test ! -f /home/t-ishii/work/<a href="http://git.postgresql.org/test.repli/archivedir/%f" target="_blank">git.postgresql.org/test.repli/archivedir/%f</a> &amp;&amp; cp %p /home/t-ishii/work/<a href="http://git.postgresql.org/test.repli/archivedir/%f" target="_blank">git.postgresql.org/test.repli/archivedir/%f</a>&#39;<br>

log_min_messages = debug5<br>
<br>
And also you should see log on node 0 something like this:<br>
<br>
16362 2013-01-18 07:48:11 JST LOG:  pgpool_switch_xlog: waiting for &quot;/home/t-ishii/work/<a href="http://git.postgresql.org/test.repli/archivedir/000000030000000100000025" target="_blank">git.postgresql.org/test.repli/archivedir/000000030000000100000025</a>&quot;<br>

16362 2013-01-18 07:48:11 JST STATEMENT:  SELECT pgpool_switch_xlog(&#39;/home/t-ishii/work/<a href="http://git.postgresql.org/test.repli/archivedir" target="_blank">git.postgresql.org/test.repli/archivedir</a>&#39;)<br>

16168 2013-01-18 07:48:11 JST DEBUG:  executing archive command &quot;test ! -f /home/t-ishii/work/<a href="http://git.postgresql.org/test.repli/archivedir/000000030000000100000025" target="_blank">git.postgresql.org/test.repli/archivedir/000000030000000100000025</a> &amp;&amp; cp pg_xlog/000000030000000100000025 /home/t-ishii/work/<a href="http://git.postgresql.org/test.repli/archivedir/000000030000000100000025" target="_blank">git.postgresql.org/test.repli/archivedir/000000030000000100000025</a>&quot;<br>

16168 2013-01-18 07:48:12 JST DEBUG:  archived transaction log file &quot;000000030000000100000025&quot;<br>
16362 2013-01-18 07:48:12 JST DEBUG:  CommitTransactionCommand<br>
16362 2013-01-18 07:48:12 JST DEBUG:  CommitTransaction<br>
16362 2013-01-18 07:48:12 JST DEBUG:  name: unnamed; blockState:       STARTED; state: INPROGR, xid/subid/cid: 0/1/0, nestlvl: 1, children:<br>
                                  pgpool_switch_xlog<br>
--------------------------------------------------------------------------------------<br>
 /home/t-ishii/work/<a href="http://git.postgresql.org/test.repli/archivedir/000000030000000100000025" target="_blank">git.postgresql.org/test.repli/archivedir/000000030000000100000025</a><br>
(1 row)<br>
<div class="im"><br>
&gt; For this to happen, am I right to say that I need to rsync the files in<br>
&gt; pg_xlog (node 0) to my archive folder ( /thumbnails/postgres_archives/ ) on<br>
&gt; node 1 ? My stage 2 script does not do that right now.<br>
<br>
</div>No at all. Copying pg_xlog to archive folder should be done by<br>
archive_command by PostgreSQL.<br>
<div class="im"><br>
&gt; I am not completely comfortable with what the files in pg_xlog contain<br>
&gt; exactly, at the various stages. Also, I find it strange that the log show<br>
&gt; many &quot;No such file or directory&quot;, even in your example.<br>
<br>
</div>That&#39;s normal. PostgreSQL continues recoverying until requested file<br>
does not exist.<br>
<div class="im HOEnZb">--<br>
Tatsuo Ishii<br>
SRA OSS, Inc. Japan<br>
English: <a href="http://www.sraoss.co.jp/index_en.php" target="_blank">http://www.sraoss.co.jp/index_en.php</a><br>
Japanese: <a href="http://www.sraoss.co.jp" target="_blank">http://www.sraoss.co.jp</a><br>
<br>
</div><div class="HOEnZb"><div class="h5">&gt; Thanks<br>
&gt;<br>
&gt; Gilbert<br>
&gt;<br>
&gt; ~~~~<br>
&gt; LOg file for postgres on node 1 during recovery:<br>
&gt;<br>
&gt; 2013-01-16 19:50:20 EST : LOG:  database system was interrupted; last known<br>
&gt; up at 2013-01-16 19:50:16 EST<br>
&gt; 2013-01-16 19:50:20 EST : LOG:  starting archive recovery<br>
&gt; 2013-01-16 19:50:20 EST : LOG:  restore_command = &#39;cp<br>
&gt;  /thumbnails/postgres_archives/pg_log/%f %p&#39;<br>
&gt; 2013-01-16 19:50:20 EST : DEBUG:  executing restore command &quot;cp<br>
&gt;  /thumbnails/postgres_archives/pg_log/00000002.history<br>
&gt; pg_xlog/RECOVERYHISTORY&quot;<br>
&gt; cp: cannot stat `/thumbnails/postgres_archives/pg_log/00000002.history&#39;: No<br>
&gt; such file or directory<br>
&gt; 2013-01-16 19:50:20 EST : DEBUG:  could not restore file &quot;00000002.history&quot;<br>
&gt; from archive: return code 256<br>
&gt; 2013-01-16 19:50:20 EST : DEBUG:  history of timeline 2 is (i 2 1)<br>
&gt; 2013-01-16 19:50:20 EST : DEBUG:  executing restore command &quot;cp<br>
&gt;  /thumbnails/postgres_archives/pg_log/00000002000000040000008D.00000020.backup<br>
&gt; pg_xlog/RECOVERY<br>
&gt; HISTORY&quot;<br>
&gt; cp: cannot stat<br>
&gt; `/thumbnails/postgres_archives/pg_log/00000002000000040000008D.00000020.backup&#39;:<br>
&gt; No such file or directory<br>
&gt; 2013-01-16 19:50:20 EST : DEBUG:  could not restore file<br>
&gt; &quot;00000002000000040000008D.00000020.backup&quot; from archive: return code 256<br>
&gt; 2013-01-16 19:50:20 EST : DEBUG:  executing restore command &quot;cp<br>
&gt;  /thumbnails/postgres_archives/pg_log/00000002000000040000008D<br>
&gt; pg_xlog/RECOVERYXLOG&quot;<br>
&gt; cp: cannot stat<br>
&gt; `/thumbnails/postgres_archives/pg_log/00000002000000040000008D&#39;: No such<br>
&gt; file or directory<br>
&gt; 2013-01-16 19:50:20 EST : DEBUG:  could not restore file<br>
&gt; &quot;00000002000000040000008D&quot; from archive: return code 256<br>
&gt; 2013-01-16 19:50:20 EST : DEBUG:  checkpoint record is at 4/8D000020<br>
&gt; 2013-01-16 19:50:20 EST : DEBUG:  redo record is at 4/8D000020; shutdown<br>
&gt; FALSE<br>
&gt; 2013-01-16 19:50:20 EST : DEBUG:  next transaction ID: 0/1625; next OID:<br>
&gt; 17797<br>
&gt; 2013-01-16 19:50:20 EST : DEBUG:  next MultiXactId: 1; next<br>
&gt; MultiXactOffset: 0<br>
&gt; 2013-01-16 19:50:20 EST : LOG:  automatic recovery in progress<br>
&gt; 2013-01-16 19:50:20 EST : LOG:  record with zero length at 4/8D000068<br>
&gt; 2013-01-16 19:50:20 EST : LOG:  redo is not required<br>
&gt; 2013-01-16 19:50:20 EST : DEBUG:  executing restore command &quot;cp<br>
&gt;  /thumbnails/postgres_archives/pg_log/00000002000000040000008D<br>
&gt; pg_xlog/RECOVERYXLOG&quot;<br>
&gt; cp: cannot stat<br>
&gt; `/thumbnails/postgres_archives/pg_log/00000002000000040000008D&#39;: No such<br>
&gt; file or directory<br>
&gt; 2013-01-16 19:50:20 EST : DEBUG:  could not restore file<br>
&gt; &quot;00000002000000040000008D&quot; from archive: return code 256<br>
&gt; 2013-01-16 19:50:20 EST : DEBUG:  executing restore command &quot;cp<br>
&gt;  /thumbnails/postgres_archives/pg_log/00000003.history<br>
&gt; pg_xlog/RECOVERYHISTORY&quot;<br>
&gt; 2013-01-16 19:50:20 EST : LOG:  restored log file &quot;00000003.history&quot; from<br>
&gt; archive<br>
&gt; 2013-01-16 19:50:20 EST : DEBUG:  executing restore command &quot;cp<br>
&gt;  /thumbnails/postgres_archives/pg_log/00000004.history<br>
&gt; pg_xlog/RECOVERYHISTORY&quot;<br>
&gt; 2013-01-16 19:50:20 EST : LOG:  restored log file &quot;00000004.history&quot; from<br>
&gt; archive<br>
&gt; 2013-01-16 19:50:20 EST : DEBUG:  executing restore command &quot;cp<br>
&gt;  /thumbnails/postgres_archives/pg_log/00000005.history<br>
&gt; pg_xlog/RECOVERYHISTORY&quot;<br>
&gt; 2013-01-16 19:50:20 EST : LOG:  restored log file &quot;00000005.history&quot; from<br>
&gt; archive<br>
&gt; 2013-01-16 19:50:20 EST : DEBUG:  executing restore command &quot;cp<br>
&gt;  /thumbnails/postgres_archives/pg_log/00000006.history<br>
&gt; pg_xlog/RECOVERYHISTORY&quot;<br>
&gt; cp: cannot stat `/thumbnails/postgres_archives/pg_log/00000006.history&#39;: No<br>
&gt; such file or directory<br>
&gt; 2013-01-16 19:50:20 EST : DEBUG:  could not restore file &quot;00000006.history&quot;<br>
&gt; from archive: return code 256<br>
&gt; 2013-01-16 19:50:20 EST : LOG:  selected new timeline ID: 6<br>
&gt; 2013-01-16 19:50:20 EST : DEBUG:  executing restore command &quot;cp<br>
&gt;  /thumbnails/postgres_archives/pg_log/00000002.history<br>
&gt; pg_xlog/RECOVERYHISTORY&quot;<br>
&gt; cp: cannot stat `/thumbnails/postgres_archives/pg_log/00000002.history&#39;: No<br>
&gt; such file or directory<br>
&gt; 2013-01-16 19:50:20 EST : DEBUG:  could not restore file &quot;00000002.history&quot;<br>
&gt; from archive: return code 256<br>
&gt; 2013-01-16 19:50:20 EST : LOG:  archive recovery complete<br>
&gt; 2013-01-16 19:50:20 EST : DEBUG:  recycled transaction log file<br>
&gt; &quot;00000002000000040000008C&quot;<br>
&gt; 2013-01-16 19:50:20 EST : DEBUG:  transaction ID wrap limit is 2147484295,<br>
&gt; limited by database &quot;template1&quot;<br>
&gt; 2013-01-16 19:50:20 EST : DEBUG:  shmem_exit(0): 3 callbacks to make<br>
&gt; 2013-01-16 19:50:20 EST : DEBUG:  proc_exit(0): 2 callbacks to make<br>
&gt; 2013-01-16 19:50:20 EST : DEBUG:  exit(0)<br>
&gt; 2013-01-16 19:50:20 EST : DEBUG:  shmem_exit(-1): 0 callbacks to make<br>
&gt; 2013-01-16 19:50:20 EST : DEBUG:  proc_exit(-1): 0 callbacks to make<br>
&gt; 2013-01-16 19:50:20 EST : DEBUG:  reaping dead processes<br>
&gt; 2013-01-16 19:50:20 EST : LOG:  autovacuum launcher started<br>
&gt; 2013-01-16 19:50:20 EST : LOG:  database system is ready to accept<br>
&gt; connections<br>
&gt;<br>
&gt;<br>
&gt; On Wed, Jan 16, 2013 at 10:06 PM, Tatsuo Ishii &lt;<a href="mailto:ishii@postgresql.org">ishii@postgresql.org</a>&gt; wrote:<br>
&gt;<br>
&gt;&gt; &gt; Hello<br>
&gt;&gt; &gt;<br>
&gt;&gt; &gt; I hope this is the place whee I can ask general questions about pgpool. I<br>
&gt;&gt; &gt; could not find a forum. Otheriwse, please let me know where I should go<br>
&gt;&gt; to<br>
&gt;&gt; &gt; get help<br>
&gt;&gt;<br>
&gt;&gt; Yes, this is the perfect place to ask general questions about<br>
&gt;&gt; pgpool(there&#39;s no forum).<br>
&gt;&gt;<br>
&gt;&gt; &gt; Here is my question:<br>
&gt;&gt; &gt;<br>
&gt;&gt; &gt; I am trying to setup pgpool II with 2 postgres nodes using replication<br>
&gt;&gt; and<br>
&gt;&gt; &gt; load balancing. I have setup the watch dog to switch the virtual IP<br>
&gt;&gt; &gt; automatically in case of failure. The 2 postgres nodes run on 2 identical<br>
&gt;&gt; &gt; computers.<br>
&gt;&gt; &gt;<br>
&gt;&gt; &gt; Everything works fine except for the recovery which I dont quite<br>
&gt;&gt; undestand.<br>
&gt;&gt; &gt; I have installed and configured the scripts:<br>
&gt;&gt; &gt;<br>
&gt;&gt; &gt; basebackup.sh<br>
&gt;&gt; &gt; pgpool_remote_start<br>
&gt;&gt; &gt; pgpool_recovery_pitr<br>
&gt;&gt; &gt;<br>
&gt;&gt; &gt;<br>
&gt;&gt; &gt; Then I simulate a failure : I shutdown one of the postgres nodes. This<br>
&gt;&gt; gets<br>
&gt;&gt; &gt; detected fine and pgpool is still functional with the remaining node<br>
&gt;&gt; (which<br>
&gt;&gt; &gt; can still accept connections).<br>
&gt;&gt; &gt;<br>
&gt;&gt; &gt; When I try to recover, it does not work as I expected. I thought that we<br>
&gt;&gt; &gt; could recover *while pgpool keeps accepting requests*. (at least for<br>
&gt;&gt; stage<br>
&gt;&gt; &gt; 1 of the recover). So, I simulated this by inserting a new line in a<br>
&gt;&gt; table<br>
&gt;&gt; &gt; of my database just before the end of the basebackup.sh script  (just<br>
&gt;&gt; *after<br>
&gt;&gt; &gt; * the rsync and *before* the pg_stop_backup() ).<br>
&gt;&gt; &gt;<br>
&gt;&gt; &gt; So, my basebackup.sh script looks like:<br>
&gt;&gt; &gt; ~~~~~~~~~~<br>
&gt;&gt; &gt;<br>
&gt;&gt; &gt; ....<br>
&gt;&gt; &gt;<br>
&gt;&gt; &gt; *psql -U postgres -p 5432 -c &quot;insert into users values (3000, &#39;def1&#39;);&quot;<br>
&gt;&gt; &gt; myDB      # this line was added to basebackup.sh to simulate a<br>
&gt;&gt; transaction<br>
&gt;&gt; &gt; during a recover, stage 1      *<br>
&gt;&gt; &gt;<br>
&gt;&gt; &gt; psql -U postgres -p 5432 -c &quot;SELECT pg_stop_backup()&quot; postgres<br>
&gt;&gt; &gt; ~~~~~~~~~~~~<br>
&gt;&gt; &gt;<br>
&gt;&gt; &gt; I then try to recover my failed node with:<br>
&gt;&gt; &gt;<br>
&gt;&gt; &gt; /usr/local/bin/pcp_recovery_node -d 15 192.168.0.110 9898 pgpool pgpool 1<br>
&gt;&gt; &gt;<br>
&gt;&gt; &gt; Which succeeds. However, when I look at the content of the databases,<br>
&gt;&gt; only<br>
&gt;&gt; &gt; the master database has the new line  &quot;3000  test&quot; (purposely added in my<br>
&gt;&gt; &gt; basebackup.sh script). The recovered database has all other lines but not<br>
&gt;&gt; &gt; this one. So the recovery is not complete in my opinion (or I dont<br>
&gt;&gt; &gt; understand what it is supposed to do).<br>
&gt;&gt; &gt;<br>
&gt;&gt; &gt; Am I wrong in expecting that this should work (i.e. after the recover,<br>
&gt;&gt; the<br>
&gt;&gt; &gt; 2 database should be completely in sync) ?<br>
&gt;&gt;<br>
&gt;&gt; Works fine for me. I guess you have problem with archive recovery<br>
&gt;&gt; setting of PostgreSQL.<br>
&gt;&gt;<br>
&gt;&gt; Below is my recovered PostgreSQL log.  Do you find something like<br>
&gt;&gt; &quot;restored log file &quot;000000030000000100000021&quot; from archive&quot; in your<br>
&gt;&gt; PostgreSQL log(in your case DB node 1)?<br>
&gt;&gt;<br>
&gt;&gt; 31055 2013-01-17 11:38:14 JST LOG:  database system was interrupted; last<br>
&gt;&gt; known up at 2013-01-17 11:38:11 JST<br>
&gt;&gt; 31055 2013-01-17 11:38:14 JST LOG:  creating missing WAL directory<br>
&gt;&gt; &quot;pg_xlog/archive_status&quot;<br>
&gt;&gt; cp: cannot stat `/home/t-ishii/work/<br>
&gt;&gt; <a href="http://git.postgresql.org/test.repli/archivedir/00000003.history" target="_blank">git.postgresql.org/test.repli/archivedir/00000003.history</a>&#39;: No such file<br>
&gt;&gt; or directory<br>
&gt;&gt; 31055 2013-01-17 11:38:14 JST LOG:  starting archive recovery<br>
&gt;&gt; 31055 2013-01-17 11:38:14 JST LOG:  restored log file<br>
&gt;&gt; &quot;000000030000000100000020&quot; from archive<br>
&gt;&gt; 31055 2013-01-17 11:38:14 JST LOG:  redo starts at 1/20000020<br>
&gt;&gt; 31055 2013-01-17 11:38:14 JST LOG:  consistent recovery state reached at<br>
&gt;&gt; 1/21000000<br>
&gt;&gt; 31053 2013-01-17 11:38:14 JST LOG:  database system is ready to accept<br>
&gt;&gt; read only connections<br>
&gt;&gt; 31055 2013-01-17 11:38:14 JST LOG:  restored log file<br>
&gt;&gt; &quot;000000030000000100000021&quot; from archive<br>
&gt;&gt; cp: cannot stat `/home/t-ishii/work/<br>
&gt;&gt; <a href="http://git.postgresql.org/test.repli/archivedir/000000030000000100000022" target="_blank">git.postgresql.org/test.repli/archivedir/000000030000000100000022</a>&#39;: No<br>
&gt;&gt; such file or directory<br>
&gt;&gt; 31055 2013-01-17 11:38:14 JST LOG:  could not open file<br>
&gt;&gt; &quot;pg_xlog/000000030000000100000022&quot; (log file 1, segment 34): No such file<br>
&gt;&gt; or directory<br>
&gt;&gt; 31055 2013-01-17 11:38:14 JST LOG:  redo done at 1/21000E48<br>
&gt;&gt; 31055 2013-01-17 11:38:14 JST LOG:  last completed transaction was at log<br>
&gt;&gt; time 2013-01-17 11:38:12.376859+09<br>
&gt;&gt; 31055 2013-01-17 11:38:14 JST LOG:  restored log file<br>
&gt;&gt; &quot;000000030000000100000021&quot; from archive<br>
&gt;&gt; cp: cannot stat `/home/t-ishii/work/<br>
&gt;&gt; <a href="http://git.postgresql.org/test.repli/archivedir/00000004.history" target="_blank">git.postgresql.org/test.repli/archivedir/00000004.history</a>&#39;: No such file<br>
&gt;&gt; or directory<br>
&gt;&gt; 31055 2013-01-17 11:38:14 JST LOG:  selected new timeline ID: 4<br>
&gt;&gt; cp: cannot stat `/home/t-ishii/work/<br>
&gt;&gt; <a href="http://git.postgresql.org/test.repli/archivedir/00000003.history" target="_blank">git.postgresql.org/test.repli/archivedir/00000003.history</a>&#39;: No such file<br>
&gt;&gt; or directory<br>
&gt;&gt; 31055 2013-01-17 11:38:15 JST LOG:  archive recovery complete<br>
&gt;&gt; 31053 2013-01-17 11:38:15 JST LOG:  database system is ready to accept<br>
&gt;&gt; connections<br>
&gt;&gt; 31067 2013-01-17 11:38:15 JST LOG:  autovacuum launcher started<br>
&gt;&gt; --<br>
&gt;&gt; Tatsuo Ishii<br>
&gt;&gt; SRA OSS, Inc. Japan<br>
&gt;&gt; English: <a href="http://www.sraoss.co.jp/index_en.php" target="_blank">http://www.sraoss.co.jp/index_en.php</a><br>
&gt;&gt; Japanese: <a href="http://www.sraoss.co.jp" target="_blank">http://www.sraoss.co.jp</a><br>
&gt;&gt;<br>
</div></div><br>15875 2013-01-18 07:44:08 JST LOG:  connection authorized: user=t-ishii database=test<br>
15875 2013-01-18 07:44:11 JST LOG:  statement: SELECT n.nspname as &quot;Schema&quot;,<br>
          c.relname as &quot;Name&quot;,<br>
          CASE c.relkind WHEN &#39;r&#39; THEN &#39;table&#39; WHEN &#39;v&#39; THEN &#39;view&#39; WHEN &#39;i&#39; THEN &#39;index&#39; WHEN &#39;S&#39; THEN &#39;sequence&#39; WHEN &#39;s&#39; THEN &#39;special&#39; WHEN &#39;f&#39; THEN &#39;foreign table&#39; END as &quot;Type&quot;,<br>

          pg_catalog.pg_get_userbyid(c.relowner) as &quot;Owner&quot;<br>
        FROM pg_catalog.pg_class c<br>
             LEFT JOIN pg_catalog.pg_namespace n ON n.oid = c.relnamespace<br>
        WHERE c.relkind IN (&#39;r&#39;,&#39;&#39;)<br>
              AND n.nspname &lt;&gt; &#39;pg_catalog&#39;<br>
              AND n.nspname &lt;&gt; &#39;information_schema&#39;<br>
              AND n.nspname !~ &#39;^pg_toast&#39;<br>
          AND pg_catalog.pg_table_is_visible(c.oid)<br>
        ORDER BY 1,2;<br>
15875 2013-01-18 07:44:19 JST LOG:  statement: select * from t1;<br>
15875 2013-01-18 07:44:21 JST LOG:  statement:  DISCARD ALL<br>
15875 2013-01-18 07:44:24 JST LOG:  disconnection: session time: 0:00:16.148 user=t-ishii database=test host=[local]<br>
31053 2013-01-18 07:44:27 JST LOG:  received fast shutdown request<br>
31053 2013-01-18 07:44:27 JST LOG:  aborting any active transactions<br>
31067 2013-01-18 07:44:27 JST LOG:  autovacuum launcher shutting down<br>
31058 2013-01-18 07:44:27 JST LOG:  shutting down<br>
31058 2013-01-18 07:44:27 JST LOG:  database system is shut down<br>
16189 2013-01-18 07:47:26 JST LOG:  database system was shut down at 2013-01-18 07:44:27 JST<br>
16189 2013-01-18 07:47:26 JST DEBUG:  history of timeline 4 is (i 4 3)<br>
16189 2013-01-18 07:47:26 JST DEBUG:  checkpoint record is at 1/22000080<br>
16189 2013-01-18 07:47:26 JST DEBUG:  redo record is at 1/22000080; shutdown TRUE<br>
16189 2013-01-18 07:47:26 JST DEBUG:  next transaction ID: 0/438215; next OID: 305262<br>
16189 2013-01-18 07:47:26 JST DEBUG:  next MultiXactId: 1; next MultiXactOffset: 0<br>
16189 2013-01-18 07:47:26 JST DEBUG:  oldest unfrozen transaction ID: 686, in database 1<br>
16189 2013-01-18 07:47:26 JST DEBUG:  transaction ID wrap limit is 2147484333, limited by database with OID 1<br>
16189 2013-01-18 07:47:26 JST DEBUG:  shmem_exit(0): 4 callbacks to make<br>
16189 2013-01-18 07:47:26 JST DEBUG:  proc_exit(0): 2 callbacks to make<br>
16189 2013-01-18 07:47:26 JST DEBUG:  exit(0)<br>
16189 2013-01-18 07:47:26 JST DEBUG:  shmem_exit(-1): 0 callbacks to make<br>
16189 2013-01-18 07:47:26 JST DEBUG:  proc_exit(-1): 0 callbacks to make<br>
16187 2013-01-18 07:47:26 JST DEBUG:  reaping dead processes<br>
16190 2013-01-18 07:47:26 JST DEBUG:  checkpointer updated shared memory configuration values<br>
16187 2013-01-18 07:47:26 JST LOG:  database system is ready to accept connections<br>
16193 2013-01-18 07:47:26 JST LOG:  autovacuum launcher started<br>
16193 2013-01-18 07:47:26 JST DEBUG:  InitPostgres<br>
16193 2013-01-18 07:47:26 JST DEBUG:  my backend ID is 1<br>
16193 2013-01-18 07:47:26 JST DEBUG:  StartTransaction<br>
16193 2013-01-18 07:47:26 JST DEBUG:  name: unnamed; blockState:       DEFAULT; state: INPROGR, xid/subid/cid: 0/1/0, nestlvl: 1, children:<br>
16193 2013-01-18 07:47:26 JST DEBUG:  CommitTransaction<br>
16193 2013-01-18 07:47:26 JST DEBUG:  name: unnamed; blockState:       STARTED; state: INPROGR, xid/subid/cid: 0/1/0, nestlvl: 1, children:<br>
16187 2013-01-18 07:47:27 JST DEBUG:  forked new backend, pid=16196 socket=12<br>
16196 2013-01-18 07:47:27 JST LOG:  connection received: host=[local]<br>
16196 2013-01-18 07:47:27 JST DEBUG:  postgres child[16196]: starting with (<br>
16196 2013-01-18 07:47:27 JST DEBUG:    postgres<br>
16196 2013-01-18 07:47:27 JST DEBUG:    postgres<br>
16196 2013-01-18 07:47:27 JST DEBUG:  )<br>
16196 2013-01-18 07:47:27 JST DEBUG:  InitPostgres<br>
16196 2013-01-18 07:47:27 JST DEBUG:  my backend ID is 2<br>
16196 2013-01-18 07:47:27 JST DEBUG:  StartTransaction<br>
16196 2013-01-18 07:47:27 JST DEBUG:  name: unnamed; blockState:       DEFAULT; state: INPROGR, xid/subid/cid: 0/1/0, nestlvl: 1, children:<br>
16196 2013-01-18 07:47:27 JST LOG:  connection authorized: user=t-ishii database=postgres<br>
16196 2013-01-18 07:47:27 JST DEBUG:  CommitTransaction<br>
16196 2013-01-18 07:47:27 JST DEBUG:  name: unnamed; blockState:       STARTED; state: INPROGR, xid/subid/cid: 0/1/0, nestlvl: 1, children:<br>
16196 2013-01-18 07:47:27 JST DEBUG:  shmem_exit(0): 7 callbacks to make<br>
16196 2013-01-18 07:47:27 JST DEBUG:  proc_exit(0): 4 callbacks to make<br>
16196 2013-01-18 07:47:27 JST LOG:  disconnection: session time: 0:00:00.005 user=t-ishii database=postgres host=[local]<br>
16196 2013-01-18 07:47:27 JST DEBUG:  exit(0)<br>
16196 2013-01-18 07:47:27 JST DEBUG:  shmem_exit(-1): 0 callbacks to make<br>
16196 2013-01-18 07:47:27 JST DEBUG:  proc_exit(-1): 0 callbacks to make<br>
16187 2013-01-18 07:47:27 JST DEBUG:  reaping dead processes<br>
16187 2013-01-18 07:47:27 JST DEBUG:  server process (PID 16196) exited with exit code 0<br>
16187 2013-01-18 07:47:49 JST DEBUG:  postmaster received signal 2<br>
16187 2013-01-18 07:47:49 JST LOG:  received fast shutdown request<br>
16187 2013-01-18 07:47:49 JST LOG:  aborting any active transactions<br>
16193 2013-01-18 07:47:49 JST LOG:  autovacuum launcher shutting down<br>
16193 2013-01-18 07:47:49 JST DEBUG:  shmem_exit(0): 7 callbacks to make<br>
16193 2013-01-18 07:47:49 JST DEBUG:  proc_exit(0): 2 callbacks to make<br>
16193 2013-01-18 07:47:49 JST DEBUG:  exit(0)<br>
16192 2013-01-18 07:47:49 JST DEBUG:  shmem_exit(0): 4 callbacks to make<br>
16191 2013-01-18 07:47:49 JST DEBUG:  shmem_exit(0): 4 callbacks to make<br>
16192 2013-01-18 07:47:49 JST DEBUG:  proc_exit(0): 2 callbacks to make<br>
16191 2013-01-18 07:47:49 JST DEBUG:  proc_exit(0): 2 callbacks to make<br>
16193 2013-01-18 07:47:49 JST DEBUG:  shmem_exit(-1): 0 callbacks to make<br>
16192 2013-01-18 07:47:49 JST DEBUG:  exit(0)<br>
16193 2013-01-18 07:47:49 JST DEBUG:  proc_exit(-1): 0 callbacks to make<br>
16191 2013-01-18 07:47:49 JST DEBUG:  exit(0)<br>
16192 2013-01-18 07:47:49 JST DEBUG:  shmem_exit(-1): 0 callbacks to make<br>
16192 2013-01-18 07:47:49 JST DEBUG:  proc_exit(-1): 0 callbacks to make<br>
16191 2013-01-18 07:47:49 JST DEBUG:  shmem_exit(-1): 0 callbacks to make<br>
16191 2013-01-18 07:47:49 JST DEBUG:  proc_exit(-1): 0 callbacks to make<br>
16187 2013-01-18 07:47:49 JST DEBUG:  reaping dead processes<br>
16187 2013-01-18 07:47:49 JST DEBUG:  reaping dead processes<br>
16190 2013-01-18 07:47:49 JST LOG:  shutting down<br>
16190 2013-01-18 07:47:49 JST DEBUG:  SlruScanDirectory invoking callback on pg_multixact/offsets/0000<br>
16190 2013-01-18 07:47:49 JST DEBUG:  SlruScanDirectory invoking callback on pg_multixact/members/0000<br>
16190 2013-01-18 07:47:49 JST DEBUG:  attempting to remove WAL segments older than log file 000000040000000100000021<br>
16190 2013-01-18 07:47:49 JST DEBUG:  SlruScanDirectory invoking callback on pg_subtrans/0006<br>
16190 2013-01-18 07:47:49 JST LOG:  database system is shut down<br>
16190 2013-01-18 07:47:49 JST DEBUG:  shmem_exit(0): 4 callbacks to make<br>
16190 2013-01-18 07:47:49 JST DEBUG:  proc_exit(0): 2 callbacks to make<br>
16190 2013-01-18 07:47:49 JST DEBUG:  exit(0)<br>
16190 2013-01-18 07:47:49 JST DEBUG:  shmem_exit(-1): 0 callbacks to make<br>
16190 2013-01-18 07:47:49 JST DEBUG:  proc_exit(-1): 0 callbacks to make<br>
16187 2013-01-18 07:47:49 JST DEBUG:  reaping dead processes<br>
16194 2013-01-18 07:47:49 JST DEBUG:  shmem_exit(-1): 0 callbacks to make<br>
16194 2013-01-18 07:47:49 JST DEBUG:  proc_exit(-1): 0 callbacks to make<br>
16187 2013-01-18 07:47:49 JST DEBUG:  reaping dead processes<br>
16187 2013-01-18 07:47:49 JST DEBUG:  shmem_exit(0): 3 callbacks to make<br>
16187 2013-01-18 07:47:49 JST DEBUG:  proc_exit(0): 3 callbacks to make<br>
16187 2013-01-18 07:47:49 JST DEBUG:  exit(0)<br>
16187 2013-01-18 07:47:49 JST DEBUG:  shmem_exit(-1): 0 callbacks to make<br>
16187 2013-01-18 07:47:49 JST DEBUG:  proc_exit(-1): 0 callbacks to make<br>
16188 2013-01-18 07:47:49 JST DEBUG:  logger shutting down<br>
16188 2013-01-18 07:47:49 JST DEBUG:  shmem_exit(0): 0 callbacks to make<br>
16188 2013-01-18 07:47:49 JST DEBUG:  proc_exit(0): 0 callbacks to make<br>
16188 2013-01-18 07:47:49 JST DEBUG:  exit(0)<br>
16188 2013-01-18 07:47:49 JST DEBUG:  shmem_exit(-1): 0 callbacks to make<br>
16188 2013-01-18 07:47:49 JST DEBUG:  proc_exit(-1): 0 callbacks to make<br>
16376 2013-01-18 07:48:13 JST LOG:  database system was interrupted; last known up at 2013-01-18 07:48:10 JST<br>
16376 2013-01-18 07:48:13 JST LOG:  creating missing WAL directory &quot;pg_xlog/archive_status&quot;<br>
16376 2013-01-18 07:48:13 JST DEBUG:  restore_command = &#39;cp /home/t-ishii/work/<a href="http://git.postgresql.org/test.repli/archivedir/%f" target="_blank">git.postgresql.org/test.repli/archivedir/%f</a> %p&#39;<br>
16376 2013-01-18 07:48:13 JST DEBUG:  executing restore command &quot;cp /home/t-ishii/work/<a href="http://git.postgresql.org/test.repli/archivedir/00000003.history" target="_blank">git.postgresql.org/test.repli/archivedir/00000003.history</a> pg_xlog/RECOVERYHISTORY&quot;<br>

cp: cannot stat `/home/t-ishii/work/<a href="http://git.postgresql.org/test.repli/archivedir/00000003.history" target="_blank">git.postgresql.org/test.repli/archivedir/00000003.history</a>&#39;: No such file or directory<br>

16376 2013-01-18 07:48:13 JST DEBUG:  could not restore file &quot;00000003.history&quot; from archive: return code 256<br>
16376 2013-01-18 07:48:13 JST LOG:  starting archive recovery<br>
16376 2013-01-18 07:48:13 JST DEBUG:  executing restore command &quot;cp /home/t-ishii/work/<a href="http://git.postgresql.org/test.repli/archivedir/000000030000000100000024" target="_blank">git.postgresql.org/test.repli/archivedir/000000030000000100000024</a> pg_xlog/RECOVERYXLOG&quot;<br>

16376 2013-01-18 07:48:13 JST LOG:  restored log file &quot;000000030000000100000024&quot; from archive<br>
16376 2013-01-18 07:48:13 JST DEBUG:  got WAL segment from archive<br>
16376 2013-01-18 07:48:13 JST DEBUG:  checkpoint record is at 1/24000058<br>
16376 2013-01-18 07:48:13 JST DEBUG:  redo record is at 1/24000020; shutdown FALSE<br>
16376 2013-01-18 07:48:13 JST DEBUG:  next transaction ID: 0/438215; next OID: 305262<br>
16376 2013-01-18 07:48:13 JST DEBUG:  next MultiXactId: 1; next MultiXactOffset: 0<br>
16376 2013-01-18 07:48:13 JST DEBUG:  oldest unfrozen transaction ID: 686, in database 1<br>
16376 2013-01-18 07:48:13 JST DEBUG:  transaction ID wrap limit is 2147484333, limited by database with OID 1<br>
16376 2013-01-18 07:48:13 JST DEBUG:  resetting unlogged relations: cleanup 1 init 0<br>
16376 2013-01-18 07:48:13 JST DEBUG:  initializing for hot standby<br>
16376 2013-01-18 07:48:13 JST DEBUG:  my backend ID is 1<br>
16376 2013-01-18 07:48:13 JST LOG:  redo starts at 1/24000020<br>
16376 2013-01-18 07:48:13 JST DEBUG:  prune KnownAssignedXids to 438215<br>
16376 2013-01-18 07:48:13 JST CONTEXT:  xlog redo  running xacts: nextXid 438215 latestCompletedXid 438214 oldestRunningXid 438215<br>
16376 2013-01-18 07:48:13 JST DEBUG:  0 KnownAssignedXids (num=0 tail=0 head=0)<br>
16376 2013-01-18 07:48:13 JST CONTEXT:  xlog redo  running xacts: nextXid 438215 latestCompletedXid 438214 oldestRunningXid 438215<br>
16376 2013-01-18 07:48:13 JST DEBUG:  recovery snapshots are now enabled<br>
16376 2013-01-18 07:48:13 JST CONTEXT:  xlog redo  running xacts: nextXid 438215 latestCompletedXid 438214 oldestRunningXid 438215<br>
16376 2013-01-18 07:48:13 JST DEBUG:  record known xact 438215 latestObservedXid 438214<br>
16376 2013-01-18 07:48:13 JST CONTEXT:  xlog redo insert: rel 1663/16389/305218; tid 0/18<br>
16376 2013-01-18 07:48:13 JST DEBUG:  record known xact 438215 latestObservedXid 438215<br>
16376 2013-01-18 07:48:13 JST CONTEXT:  xlog redo commit: 2013-01-18 07:48:10.799457+09<br>
16376 2013-01-18 07:48:13 JST DEBUG:  record known xact 438215 latestObservedXid 438215<br>
16376 2013-01-18 07:48:13 JST CONTEXT:  xlog redo commit: 2013-01-18 07:48:10.799457+09<br>
16376 2013-01-18 07:48:13 JST DEBUG:  remove KnownAssignedXid 438215<br>
16376 2013-01-18 07:48:13 JST CONTEXT:  xlog redo commit: 2013-01-18 07:48:10.799457+09<br>
16376 2013-01-18 07:48:13 JST DEBUG:  end of backup reached<br>
16376 2013-01-18 07:48:13 JST CONTEXT:  xlog redo backup end: 1/24000020<br>
16379 2013-01-18 07:48:13 JST DEBUG:  checkpointer updated shared memory configuration values<br>
16376 2013-01-18 07:48:13 JST LOG:  consistent recovery state reached at 1/25000000<br>
16376 2013-01-18 07:48:13 JST DEBUG:  executing restore command &quot;cp /home/t-ishii/work/<a href="http://git.postgresql.org/test.repli/archivedir/000000030000000100000025" target="_blank">git.postgresql.org/test.repli/archivedir/000000030000000100000025</a> pg_xlog/RECOVERYXLOG&quot;<br>

16374 2013-01-18 07:48:13 JST LOG:  database system is ready to accept read only connections<br>
16376 2013-01-18 07:48:13 JST LOG:  restored log file &quot;000000030000000100000025&quot; from archive<br>
16376 2013-01-18 07:48:13 JST DEBUG:  got WAL segment from archive<br>
16376 2013-01-18 07:48:13 JST DEBUG:  prune KnownAssignedXids to 438216<br>
16376 2013-01-18 07:48:13 JST CONTEXT:  xlog redo  running xacts: nextXid 438216 latestCompletedXid 438215 oldestRunningXid 438216<br>
16376 2013-01-18 07:48:13 JST DEBUG:  executing restore command &quot;cp /home/t-ishii/work/<a href="http://git.postgresql.org/test.repli/archivedir/000000030000000100000026" target="_blank">git.postgresql.org/test.repli/archivedir/000000030000000100000026</a> pg_xlog/RECOVERYXLOG&quot;<br>

cp: cannot stat `/home/t-ishii/work/<a href="http://git.postgresql.org/test.repli/archivedir/000000030000000100000026" target="_blank">git.postgresql.org/test.repli/archivedir/000000030000000100000026</a>&#39;: No such file or directory<br>

16376 2013-01-18 07:48:13 JST DEBUG:  could not restore file &quot;000000030000000100000026&quot; from archive: return code 256<br>
16376 2013-01-18 07:48:13 JST LOG:  could not open file &quot;pg_xlog/000000030000000100000026&quot; (log file 1, segment 38): No such file or directory<br>
16376 2013-01-18 07:48:13 JST LOG:  redo done at 1/25000E48<br>
16376 2013-01-18 07:48:13 JST LOG:  last completed transaction was at log time 2013-01-18 07:48:10.799457+09<br>
16376 2013-01-18 07:48:13 JST DEBUG:  executing restore command &quot;cp /home/t-ishii/work/<a href="http://git.postgresql.org/test.repli/archivedir/000000030000000100000025" target="_blank">git.postgresql.org/test.repli/archivedir/000000030000000100000025</a> pg_xlog/RECOVERYXLOG&quot;<br>

16376 2013-01-18 07:48:13 JST LOG:  restored log file &quot;000000030000000100000025&quot; from archive<br>
16376 2013-01-18 07:48:13 JST DEBUG:  got WAL segment from archive<br>
16376 2013-01-18 07:48:13 JST DEBUG:  executing restore command &quot;cp /home/t-ishii/work/<a href="http://git.postgresql.org/test.repli/archivedir/00000004.history" target="_blank">git.postgresql.org/test.repli/archivedir/00000004.history</a> pg_xlog/RECOVERYHISTORY&quot;<br>

cp: cannot stat `/home/t-ishii/work/<a href="http://git.postgresql.org/test.repli/archivedir/00000004.history" target="_blank">git.postgresql.org/test.repli/archivedir/00000004.history</a>&#39;: No such file or directory<br>

16376 2013-01-18 07:48:13 JST DEBUG:  could not restore file &quot;00000004.history&quot; from archive: return code 256<br>
16376 2013-01-18 07:48:13 JST LOG:  selected new timeline ID: 4<br>
16376 2013-01-18 07:48:13 JST DEBUG:  executing restore command &quot;cp /home/t-ishii/work/<a href="http://git.postgresql.org/test.repli/archivedir/00000003.history" target="_blank">git.postgresql.org/test.repli/archivedir/00000003.history</a> pg_xlog/RECOVERYHISTORY&quot;<br>

cp: cannot stat `/home/t-ishii/work/<a href="http://git.postgresql.org/test.repli/archivedir/00000003.history" target="_blank">git.postgresql.org/test.repli/archivedir/00000003.history</a>&#39;: No such file or directory<br>

16376 2013-01-18 07:48:13 JST DEBUG:  could not restore file &quot;00000003.history&quot; from archive: return code 256<br>
16376 2013-01-18 07:48:13 JST DEBUG:  updated min recovery point to 1/26000000<br>
16376 2013-01-18 07:48:13 JST LOG:  archive recovery complete<br>
16379 2013-01-18 07:48:13 JST DEBUG:  creating and filling new WAL file<br>
16379 2013-01-18 07:48:13 JST DEBUG:  done creating and filling new WAL file<br>
16379 2013-01-18 07:48:13 JST DEBUG:  attempting to remove WAL segments older than log file 000000040000000100000023<br>
16376 2013-01-18 07:48:13 JST DEBUG:  resetting unlogged relations: cleanup 0 init 1<br>
16376 2013-01-18 07:48:13 JST DEBUG:  removing all KnownAssignedXids<br>
16376 2013-01-18 07:48:13 JST DEBUG:  release all standby locks<br>
16376 2013-01-18 07:48:13 JST DEBUG:  shmem_exit(0): 5 callbacks to make<br>
16376 2013-01-18 07:48:13 JST DEBUG:  proc_exit(0): 2 callbacks to make<br>
16376 2013-01-18 07:48:13 JST DEBUG:  exit(0)<br>
16376 2013-01-18 07:48:13 JST DEBUG:  shmem_exit(-1): 0 callbacks to make<br>
16376 2013-01-18 07:48:13 JST DEBUG:  proc_exit(-1): 0 callbacks to make<br>
16374 2013-01-18 07:48:13 JST DEBUG:  reaping dead processes<br>
16374 2013-01-18 07:48:13 JST LOG:  database system is ready to accept connections<br>
16388 2013-01-18 07:48:13 JST LOG:  autovacuum launcher started<br>
16388 2013-01-18 07:48:13 JST DEBUG:  InitPostgres<br>
16388 2013-01-18 07:48:13 JST DEBUG:  my backend ID is 1<br>
16388 2013-01-18 07:48:13 JST DEBUG:  StartTransaction<br>
16388 2013-01-18 07:48:13 JST DEBUG:  name: unnamed; blockState:       DEFAULT; state: INPROGR, xid/subid/cid: 0/1/0, nestlvl: 1, children:<br>
16388 2013-01-18 07:48:13 JST DEBUG:  CommitTransaction<br>
16388 2013-01-18 07:48:13 JST DEBUG:  name: unnamed; blockState:       STARTED; state: INPROGR, xid/subid/cid: 0/1/0, nestlvl: 1, children:<br>
16374 2013-01-18 07:48:14 JST DEBUG:  forked new backend, pid=16394 socket=10<br>
16394 2013-01-18 07:48:14 JST LOG:  connection received: host=[local]<br>
16394 2013-01-18 07:48:14 JST DEBUG:  postgres child[16394]: starting with (<br>
16394 2013-01-18 07:48:14 JST DEBUG:    postgres<br>
16394 2013-01-18 07:48:14 JST DEBUG:    postgres<br>
16394 2013-01-18 07:48:14 JST DEBUG:  )<br>
16394 2013-01-18 07:48:14 JST DEBUG:  InitPostgres<br>
16394 2013-01-18 07:48:14 JST DEBUG:  my backend ID is 2<br>
16394 2013-01-18 07:48:14 JST DEBUG:  StartTransaction<br>
16394 2013-01-18 07:48:14 JST DEBUG:  name: unnamed; blockState:       DEFAULT; state: INPROGR, xid/subid/cid: 0/1/0, nestlvl: 1, children:<br>
16394 2013-01-18 07:48:14 JST LOG:  connection authorized: user=t-ishii database=postgres<br>
16394 2013-01-18 07:48:14 JST DEBUG:  CommitTransaction<br>
16394 2013-01-18 07:48:14 JST DEBUG:  name: unnamed; blockState:       STARTED; state: INPROGR, xid/subid/cid: 0/1/0, nestlvl: 1, children:<br>
16394 2013-01-18 07:48:14 JST DEBUG:  shmem_exit(0): 7 callbacks to make<br>
16394 2013-01-18 07:48:14 JST DEBUG:  proc_exit(0): 4 callbacks to make<br>
16394 2013-01-18 07:48:14 JST LOG:  disconnection: session time: 0:00:00.008 user=t-ishii database=postgres host=[local]<br>
16394 2013-01-18 07:48:14 JST DEBUG:  exit(0)<br>
16394 2013-01-18 07:48:14 JST DEBUG:  shmem_exit(-1): 0 callbacks to make<br>
16394 2013-01-18 07:48:14 JST DEBUG:  proc_exit(-1): 0 callbacks to make<br>
16374 2013-01-18 07:48:14 JST DEBUG:  reaping dead processes<br>
16374 2013-01-18 07:48:14 JST DEBUG:  server process (PID 16394) exited with exit code 0<br>
16374 2013-01-18 07:48:15 JST DEBUG:  forked new backend, pid=16404 socket=10<br>
16404 2013-01-18 07:48:15 JST LOG:  connection received: host=[local]<br>
16404 2013-01-18 07:48:15 JST DEBUG:  postgres child[16404]: starting with (<br>
16404 2013-01-18 07:48:15 JST DEBUG:    postgres<br>
16404 2013-01-18 07:48:15 JST DEBUG:    postgres<br>
16404 2013-01-18 07:48:15 JST DEBUG:  )<br>
16404 2013-01-18 07:48:15 JST DEBUG:  InitPostgres<br>
16404 2013-01-18 07:48:15 JST DEBUG:  my backend ID is 2<br>
16404 2013-01-18 07:48:15 JST DEBUG:  StartTransaction<br>
16404 2013-01-18 07:48:15 JST DEBUG:  name: unnamed; blockState:       DEFAULT; state: INPROGR, xid/subid/cid: 0/1/0, nestlvl: 1, children:<br>
16404 2013-01-18 07:48:15 JST LOG:  connection authorized: user=t-ishii database=postgres<br>
16404 2013-01-18 07:48:15 JST DEBUG:  CommitTransaction<br>
16404 2013-01-18 07:48:15 JST DEBUG:  name: unnamed; blockState:       STARTED; state: INPROGR, xid/subid/cid: 0/1/0, nestlvl: 1, children:<br>
16404 2013-01-18 07:48:16 JST DEBUG:  shmem_exit(0): 7 callbacks to make<br>
16404 2013-01-18 07:48:16 JST DEBUG:  proc_exit(0): 4 callbacks to make<br>
16404 2013-01-18 07:48:16 JST LOG:  disconnection: session time: 0:00:00.004 user=t-ishii database=postgres host=[local]<br>
16404 2013-01-18 07:48:16 JST DEBUG:  exit(0)<br>
16404 2013-01-18 07:48:16 JST DEBUG:  shmem_exit(-1): 0 callbacks to make<br>
16404 2013-01-18 07:48:16 JST DEBUG:  proc_exit(-1): 0 callbacks to make<br>
16374 2013-01-18 07:48:16 JST DEBUG:  reaping dead processes<br>
16374 2013-01-18 07:48:16 JST DEBUG:  server process (PID 16404) exited with exit code 0<br>
16388 2013-01-18 07:49:13 JST DEBUG:  StartTransaction<br>
16388 2013-01-18 07:49:13 JST DEBUG:  name: unnamed; blockState:       DEFAULT; state: INPROGR, xid/subid/cid: 0/1/0, nestlvl: 1, children:<br>
16388 2013-01-18 07:49:13 JST DEBUG:  CommitTransaction<br>
16388 2013-01-18 07:49:13 JST DEBUG:  name: unnamed; blockState:       STARTED; state: INPROGR, xid/subid/cid: 0/1/0, nestlvl: 1, children:<br>
16388 2013-01-18 07:49:13 JST DEBUG:  StartTransaction<br>
16388 2013-01-18 07:49:13 JST DEBUG:  name: unnamed; blockState:       DEFAULT; state: INPROGR, xid/subid/cid: 0/1/0, nestlvl: 1, children:<br>
16388 2013-01-18 07:49:13 JST DEBUG:  CommitTransaction<br>
16388 2013-01-18 07:49:13 JST DEBUG:  name: unnamed; blockState:       STARTED; state: INPROGR, xid/subid/cid: 0/1/0, nestlvl: 1, children:<br>
16509 2013-01-18 07:49:13 JST DEBUG:  InitPostgres<br>
16509 2013-01-18 07:49:13 JST DEBUG:  my backend ID is 2<br>
16509 2013-01-18 07:49:13 JST DEBUG:  StartTransaction<br>
16509 2013-01-18 07:49:13 JST DEBUG:  name: unnamed; blockState:       DEFAULT; state: INPROGR, xid/subid/cid: 0/1/0, nestlvl: 1, children:<br>
16509 2013-01-18 07:49:13 JST DEBUG:  CommitTransaction<br>
16509 2013-01-18 07:49:13 JST DEBUG:  name: unnamed; blockState:       STARTED; state: INPROGR, xid/subid/cid: 0/1/0, nestlvl: 1, children:<br>
16509 2013-01-18 07:49:13 JST DEBUG:  autovacuum: processing database &quot;postgres&quot;<br>
16509 2013-01-18 07:49:13 JST DEBUG:  StartTransaction<br>
16509 2013-01-18 07:49:13 JST DEBUG:  name: unnamed; blockState:       DEFAULT; state: INPROGR, xid/subid/cid: 0/1/0, nestlvl: 1, children:<br>
16509 2013-01-18 07:49:13 JST DEBUG:  pg_authid: vac: 0 (threshold 50), anl: 0 (threshold 50)<br>
16509 2013-01-18 07:49:13 JST DEBUG:  pg_class: vac: 0 (threshold 108), anl: 0 (threshold 79)<br>
16509 2013-01-18 07:49:13 JST DEBUG:  pg_attribute: vac: 0 (threshold 499), anl: 0 (threshold 275)<br>
16509 2013-01-18 07:49:13 JST DEBUG:  pg_opclass: vac: 0 (threshold 74), anl: 0 (threshold 62)<br>
16509 2013-01-18 07:49:13 JST DEBUG:  pg_am: vac: 0 (threshold 51), anl: 0 (threshold 50)<br>
16509 2013-01-18 07:49:13 JST DEBUG:  pg_amproc: vac: 0 (threshold 107), anl: 0 (threshold 79)<br>
16509 2013-01-18 07:49:13 JST DEBUG:  pg_database: vac: 0 (threshold 50), anl: 0 (threshold 50)<br>
16509 2013-01-18 07:49:13 JST DEBUG:  pg_index: vac: 0 (threshold 72), anl: 0 (threshold 61)<br>
16509 2013-01-18 07:49:13 JST DEBUG:  CommitTransaction<br>
16509 2013-01-18 07:49:13 JST DEBUG:  name: unnamed; blockState:       STARTED; state: INPROGR, xid/subid/cid: 0/1/0, nestlvl: 1, children:<br>
16509 2013-01-18 07:49:13 JST DEBUG:  shmem_exit(0): 8 callbacks to make<br>
16509 2013-01-18 07:49:13 JST DEBUG:  proc_exit(0): 2 callbacks to make<br>
16509 2013-01-18 07:49:13 JST DEBUG:  exit(0)<br>
16509 2013-01-18 07:49:13 JST DEBUG:  shmem_exit(-1): 0 callbacks to make<br>
16509 2013-01-18 07:49:13 JST DEBUG:  proc_exit(-1): 0 callbacks to make<br>
16374 2013-01-18 07:49:13 JST DEBUG:  reaping dead processes<br>
16374 2013-01-18 07:49:13 JST DEBUG:  server process (PID 16509) exited with exit code 0<br>
16388 2013-01-18 07:50:13 JST DEBUG:  StartTransaction<br>
16388 2013-01-18 07:50:13 JST DEBUG:  name: unnamed; blockState:       DEFAULT; state: INPROGR, xid/subid/cid: 0/1/0, nestlvl: 1, children:<br>
16388 2013-01-18 07:50:13 JST DEBUG:  CommitTransaction<br>
16388 2013-01-18 07:50:13 JST DEBUG:  name: unnamed; blockState:       STARTED; state: INPROGR, xid/subid/cid: 0/1/0, nestlvl: 1, children:<br>
16596 2013-01-18 07:50:13 JST DEBUG:  InitPostgres<br>
16596 2013-01-18 07:50:13 JST DEBUG:  my backend ID is 2<br>
16596 2013-01-18 07:50:13 JST DEBUG:  StartTransaction<br>
16596 2013-01-18 07:50:13 JST DEBUG:  name: unnamed; blockState:       DEFAULT; state: INPROGR, xid/subid/cid: 0/1/0, nestlvl: 1, children:<br>
16596 2013-01-18 07:50:13 JST DEBUG:  CommitTransaction<br>
16596 2013-01-18 07:50:13 JST DEBUG:  name: unnamed; blockState:       STARTED; state: INPROGR, xid/subid/cid: 0/1/0, nestlvl: 1, children:<br>
16596 2013-01-18 07:50:13 JST DEBUG:  autovacuum: processing database &quot;postgres&quot;<br>
16596 2013-01-18 07:50:13 JST DEBUG:  StartTransaction<br>
16596 2013-01-18 07:50:13 JST DEBUG:  name: unnamed; blockState:       DEFAULT; state: INPROGR, xid/subid/cid: 0/1/0, nestlvl: 1, children:<br>
16596 2013-01-18 07:50:13 JST DEBUG:  pg_authid: vac: 0 (threshold 50), anl: 0 (threshold 50)<br>
16596 2013-01-18 07:50:13 JST DEBUG:  pg_class: vac: 0 (threshold 108), anl: 0 (threshold 79)<br>
16596 2013-01-18 07:50:13 JST DEBUG:  pg_attribute: vac: 0 (threshold 499), anl: 0 (threshold 275)<br>
16596 2013-01-18 07:50:13 JST DEBUG:  pg_opclass: vac: 0 (threshold 74), anl: 0 (threshold 62)<br>
16596 2013-01-18 07:50:13 JST DEBUG:  pg_am: vac: 0 (threshold 51), anl: 0 (threshold 50)<br>
16596 2013-01-18 07:50:13 JST DEBUG:  pg_amproc: vac: 0 (threshold 107), anl: 0 (threshold 79)<br>
16596 2013-01-18 07:50:13 JST DEBUG:  pg_database: vac: 0 (threshold 50), anl: 0 (threshold 50)<br>
16596 2013-01-18 07:50:13 JST DEBUG:  pg_index: vac: 0 (threshold 72), anl: 0 (threshold 61)<br>
16596 2013-01-18 07:50:13 JST DEBUG:  CommitTransaction<br>
16596 2013-01-18 07:50:13 JST DEBUG:  name: unnamed; blockState:       STARTED; state: INPROGR, xid/subid/cid: 0/1/0, nestlvl: 1, children:<br>
16596 2013-01-18 07:50:13 JST DEBUG:  shmem_exit(0): 8 callbacks to make<br>
16596 2013-01-18 07:50:13 JST DEBUG:  proc_exit(0): 2 callbacks to make<br>
16596 2013-01-18 07:50:13 JST DEBUG:  exit(0)<br>
16596 2013-01-18 07:50:13 JST DEBUG:  shmem_exit(-1): 0 callbacks to make<br>
16596 2013-01-18 07:50:13 JST DEBUG:  proc_exit(-1): 0 callbacks to make<br>
16374 2013-01-18 07:50:13 JST DEBUG:  reaping dead processes<br>
16374 2013-01-18 07:50:13 JST DEBUG:  server process (PID 16596) exited with exit code 0<br>
16388 2013-01-18 07:51:13 JST DEBUG:  StartTransaction<br>
16388 2013-01-18 07:51:13 JST DEBUG:  name: unnamed; blockState:       DEFAULT; state: INPROGR, xid/subid/cid: 0/1/0, nestlvl: 1, children:<br>
16388 2013-01-18 07:51:13 JST DEBUG:  CommitTransaction<br>
16388 2013-01-18 07:51:13 JST DEBUG:  name: unnamed; blockState:       STARTED; state: INPROGR, xid/subid/cid: 0/1/0, nestlvl: 1, children:<br>
16682 2013-01-18 07:51:13 JST DEBUG:  InitPostgres<br>
16682 2013-01-18 07:51:13 JST DEBUG:  my backend ID is 2<br>
16682 2013-01-18 07:51:13 JST DEBUG:  StartTransaction<br>
16682 2013-01-18 07:51:13 JST DEBUG:  name: unnamed; blockState:       DEFAULT; state: INPROGR, xid/subid/cid: 0/1/0, nestlvl: 1, children:<br>
16682 2013-01-18 07:51:13 JST DEBUG:  CommitTransaction<br>
16682 2013-01-18 07:51:13 JST DEBUG:  name: unnamed; blockState:       STARTED; state: INPROGR, xid/subid/cid: 0/1/0, nestlvl: 1, children:<br>
16682 2013-01-18 07:51:13 JST DEBUG:  autovacuum: processing database &quot;postgres&quot;<br>
16682 2013-01-18 07:51:13 JST DEBUG:  StartTransaction<br>
16682 2013-01-18 07:51:13 JST DEBUG:  name: unnamed; blockState:       DEFAULT; state: INPROGR, xid/subid/cid: 0/1/0, nestlvl: 1, children:<br>
16682 2013-01-18 07:51:13 JST DEBUG:  pg_authid: vac: 0 (threshold 50), anl: 0 (threshold 50)<br>
16682 2013-01-18 07:51:13 JST DEBUG:  pg_class: vac: 0 (threshold 108), anl: 0 (threshold 79)<br>
16682 2013-01-18 07:51:13 JST DEBUG:  pg_attribute: vac: 0 (threshold 499), anl: 0 (threshold 275)<br>
16682 2013-01-18 07:51:13 JST DEBUG:  pg_opclass: vac: 0 (threshold 74), anl: 0 (threshold 62)<br>
16682 2013-01-18 07:51:13 JST DEBUG:  pg_am: vac: 0 (threshold 51), anl: 0 (threshold 50)<br>
16682 2013-01-18 07:51:13 JST DEBUG:  pg_amproc: vac: 0 (threshold 107), anl: 0 (threshold 79)<br>
16682 2013-01-18 07:51:13 JST DEBUG:  pg_database: vac: 0 (threshold 50), anl: 0 (threshold 50)<br>
16682 2013-01-18 07:51:13 JST DEBUG:  pg_index: vac: 0 (threshold 72), anl: 0 (threshold 61)<br>
16682 2013-01-18 07:51:13 JST DEBUG:  CommitTransaction<br>
16682 2013-01-18 07:51:13 JST DEBUG:  name: unnamed; blockState:       STARTED; state: INPROGR, xid/subid/cid: 0/1/0, nestlvl: 1, children:<br>
16682 2013-01-18 07:51:13 JST DEBUG:  shmem_exit(0): 8 callbacks to make<br>
16682 2013-01-18 07:51:13 JST DEBUG:  proc_exit(0): 2 callbacks to make<br>
16682 2013-01-18 07:51:13 JST DEBUG:  exit(0)<br>
16682 2013-01-18 07:51:13 JST DEBUG:  shmem_exit(-1): 0 callbacks to make<br>
16682 2013-01-18 07:51:13 JST DEBUG:  proc_exit(-1): 0 callbacks to make<br>
16374 2013-01-18 07:51:13 JST DEBUG:  reaping dead processes<br>
16374 2013-01-18 07:51:13 JST DEBUG:  server process (PID 16682) exited with exit code 0<br>
16388 2013-01-18 07:52:13 JST DEBUG:  StartTransaction<br>
16388 2013-01-18 07:52:13 JST DEBUG:  name: unnamed; blockState:       DEFAULT; state: INPROGR, xid/subid/cid: 0/1/0, nestlvl: 1, children:<br>
16388 2013-01-18 07:52:13 JST DEBUG:  CommitTransaction<br>
16388 2013-01-18 07:52:13 JST DEBUG:  name: unnamed; blockState:       STARTED; state: INPROGR, xid/subid/cid: 0/1/0, nestlvl: 1, children:<br>
16767 2013-01-18 07:52:13 JST DEBUG:  InitPostgres<br>
16767 2013-01-18 07:52:13 JST DEBUG:  my backend ID is 2<br>
16767 2013-01-18 07:52:13 JST DEBUG:  StartTransaction<br>
16767 2013-01-18 07:52:13 JST DEBUG:  name: unnamed; blockState:       DEFAULT; state: INPROGR, xid/subid/cid: 0/1/0, nestlvl: 1, children:<br>
16767 2013-01-18 07:52:13 JST DEBUG:  CommitTransaction<br>
16767 2013-01-18 07:52:13 JST DEBUG:  name: unnamed; blockState:       STARTED; state: INPROGR, xid/subid/cid: 0/1/0, nestlvl: 1, children:<br>
16767 2013-01-18 07:52:13 JST DEBUG:  autovacuum: processing database &quot;postgres&quot;<br>
16767 2013-01-18 07:52:14 JST DEBUG:  StartTransaction<br>
16767 2013-01-18 07:52:14 JST DEBUG:  name: unnamed; blockState:       DEFAULT; state: INPROGR, xid/subid/cid: 0/1/0, nestlvl: 1, children:<br>
16767 2013-01-18 07:52:14 JST DEBUG:  pg_authid: vac: 0 (threshold 50), anl: 0 (threshold 50)<br>
16767 2013-01-18 07:52:14 JST DEBUG:  pg_class: vac: 0 (threshold 108), anl: 0 (threshold 79)<br>
16767 2013-01-18 07:52:14 JST DEBUG:  pg_attribute: vac: 0 (threshold 499), anl: 0 (threshold 275)<br>
16767 2013-01-18 07:52:14 JST DEBUG:  pg_opclass: vac: 0 (threshold 74), anl: 0 (threshold 62)<br>
16767 2013-01-18 07:52:14 JST DEBUG:  pg_am: vac: 0 (threshold 51), anl: 0 (threshold 50)<br>
16767 2013-01-18 07:52:14 JST DEBUG:  pg_amproc: vac: 0 (threshold 107), anl: 0 (threshold 79)<br>
16767 2013-01-18 07:52:14 JST DEBUG:  pg_database: vac: 0 (threshold 50), anl: 0 (threshold 50)<br>
16767 2013-01-18 07:52:14 JST DEBUG:  pg_index: vac: 0 (threshold 72), anl: 0 (threshold 61)<br>
16767 2013-01-18 07:52:14 JST DEBUG:  CommitTransaction<br>
16767 2013-01-18 07:52:14 JST DEBUG:  name: unnamed; blockState:       STARTED; state: INPROGR, xid/subid/cid: 0/1/0, nestlvl: 1, children:<br>
16767 2013-01-18 07:52:14 JST DEBUG:  shmem_exit(0): 8 callbacks to make<br>
16767 2013-01-18 07:52:14 JST DEBUG:  proc_exit(0): 2 callbacks to make<br>
16767 2013-01-18 07:52:14 JST DEBUG:  exit(0)<br>
16767 2013-01-18 07:52:14 JST DEBUG:  shmem_exit(-1): 0 callbacks to make<br>
16767 2013-01-18 07:52:14 JST DEBUG:  proc_exit(-1): 0 callbacks to make<br>
16374 2013-01-18 07:52:14 JST DEBUG:  reaping dead processes<br>
16374 2013-01-18 07:52:14 JST DEBUG:  server process (PID 16767) exited with exit code 0<br>
16388 2013-01-18 07:53:14 JST DEBUG:  StartTransaction<br>
16388 2013-01-18 07:53:14 JST DEBUG:  name: unnamed; blockState:       DEFAULT; state: INPROGR, xid/subid/cid: 0/1/0, nestlvl: 1, children:<br>
16388 2013-01-18 07:53:14 JST DEBUG:  CommitTransaction<br>
16388 2013-01-18 07:53:14 JST DEBUG:  name: unnamed; blockState:       STARTED; state: INPROGR, xid/subid/cid: 0/1/0, nestlvl: 1, children:<br>
16849 2013-01-18 07:53:14 JST DEBUG:  InitPostgres<br>
16849 2013-01-18 07:53:14 JST DEBUG:  my backend ID is 2<br>
16849 2013-01-18 07:53:14 JST DEBUG:  StartTransaction<br>
16849 2013-01-18 07:53:14 JST DEBUG:  name: unnamed; blockState:       DEFAULT; state: INPROGR, xid/subid/cid: 0/1/0, nestlvl: 1, children:<br>
16849 2013-01-18 07:53:14 JST DEBUG:  CommitTransaction<br>
16849 2013-01-18 07:53:14 JST DEBUG:  name: unnamed; blockState:       STARTED; state: INPROGR, xid/subid/cid: 0/1/0, nestlvl: 1, children:<br>
16849 2013-01-18 07:53:14 JST DEBUG:  autovacuum: processing database &quot;postgres&quot;<br>
16849 2013-01-18 07:53:14 JST DEBUG:  StartTransaction<br>
16849 2013-01-18 07:53:14 JST DEBUG:  name: unnamed; blockState:       DEFAULT; state: INPROGR, xid/subid/cid: 0/1/0, nestlvl: 1, children:<br>
16849 2013-01-18 07:53:14 JST DEBUG:  pg_authid: vac: 0 (threshold 50), anl: 0 (threshold 50)<br>
16849 2013-01-18 07:53:14 JST DEBUG:  pg_class: vac: 0 (threshold 108), anl: 0 (threshold 79)<br>
16849 2013-01-18 07:53:14 JST DEBUG:  pg_attribute: vac: 0 (threshold 499), anl: 0 (threshold 275)<br>
16849 2013-01-18 07:53:14 JST DEBUG:  pg_opclass: vac: 0 (threshold 74), anl: 0 (threshold 62)<br>
16849 2013-01-18 07:53:14 JST DEBUG:  pg_am: vac: 0 (threshold 51), anl: 0 (threshold 50)<br>
16849 2013-01-18 07:53:14 JST DEBUG:  pg_amproc: vac: 0 (threshold 107), anl: 0 (threshold 79)<br>
16849 2013-01-18 07:53:14 JST DEBUG:  pg_database: vac: 0 (threshold 50), anl: 0 (threshold 50)<br>
16849 2013-01-18 07:53:14 JST DEBUG:  pg_index: vac: 0 (threshold 72), anl: 0 (threshold 61)<br>
16849 2013-01-18 07:53:14 JST DEBUG:  CommitTransaction<br>
16849 2013-01-18 07:53:14 JST DEBUG:  name: unnamed; blockState:       STARTED; state: INPROGR, xid/subid/cid: 0/1/0, nestlvl: 1, children:<br>
16849 2013-01-18 07:53:14 JST DEBUG:  shmem_exit(0): 8 callbacks to make<br>
16849 2013-01-18 07:53:14 JST DEBUG:  proc_exit(0): 2 callbacks to make<br>
16849 2013-01-18 07:53:14 JST DEBUG:  exit(0)<br>
16849 2013-01-18 07:53:14 JST DEBUG:  shmem_exit(-1): 0 callbacks to make<br>
16849 2013-01-18 07:53:14 JST DEBUG:  proc_exit(-1): 0 callbacks to make<br>
16374 2013-01-18 07:53:14 JST DEBUG:  reaping dead processes<br>
16374 2013-01-18 07:53:14 JST DEBUG:  server process (PID 16849) exited with exit code 0<br>
16388 2013-01-18 07:54:14 JST DEBUG:  StartTransaction<br>
16388 2013-01-18 07:54:14 JST DEBUG:  name: unnamed; blockState:       DEFAULT; state: INPROGR, xid/subid/cid: 0/1/0, nestlvl: 1, children:<br>
16388 2013-01-18 07:54:14 JST DEBUG:  CommitTransaction<br>
16388 2013-01-18 07:54:14 JST DEBUG:  name: unnamed; blockState:       STARTED; state: INPROGR, xid/subid/cid: 0/1/0, nestlvl: 1, children:<br>
16934 2013-01-18 07:54:14 JST DEBUG:  InitPostgres<br>
16934 2013-01-18 07:54:14 JST DEBUG:  my backend ID is 2<br>
16934 2013-01-18 07:54:14 JST DEBUG:  StartTransaction<br>
16934 2013-01-18 07:54:14 JST DEBUG:  name: unnamed; blockState:       DEFAULT; state: INPROGR, xid/subid/cid: 0/1/0, nestlvl: 1, children:<br>
16934 2013-01-18 07:54:14 JST DEBUG:  CommitTransaction<br>
16934 2013-01-18 07:54:14 JST DEBUG:  name: unnamed; blockState:       STARTED; state: INPROGR, xid/subid/cid: 0/1/0, nestlvl: 1, children:<br>
16934 2013-01-18 07:54:14 JST DEBUG:  autovacuum: processing database &quot;postgres&quot;<br>
16934 2013-01-18 07:54:14 JST DEBUG:  StartTransaction<br>
16934 2013-01-18 07:54:14 JST DEBUG:  name: unnamed; blockState:       DEFAULT; state: INPROGR, xid/subid/cid: 0/1/0, nestlvl: 1, children:<br>
16934 2013-01-18 07:54:14 JST DEBUG:  pg_authid: vac: 0 (threshold 50), anl: 0 (threshold 50)<br>
16934 2013-01-18 07:54:14 JST DEBUG:  pg_class: vac: 0 (threshold 108), anl: 0 (threshold 79)<br>
16934 2013-01-18 07:54:14 JST DEBUG:  pg_attribute: vac: 0 (threshold 499), anl: 0 (threshold 275)<br>
16934 2013-01-18 07:54:14 JST DEBUG:  pg_opclass: vac: 0 (threshold 74), anl: 0 (threshold 62)<br>
16934 2013-01-18 07:54:14 JST DEBUG:  pg_am: vac: 0 (threshold 51), anl: 0 (threshold 50)<br>
16934 2013-01-18 07:54:14 JST DEBUG:  pg_amproc: vac: 0 (threshold 107), anl: 0 (threshold 79)<br>
16934 2013-01-18 07:54:14 JST DEBUG:  pg_database: vac: 0 (threshold 50), anl: 0 (threshold 50)<br>
16934 2013-01-18 07:54:14 JST DEBUG:  pg_index: vac: 0 (threshold 72), anl: 0 (threshold 61)<br>
16934 2013-01-18 07:54:14 JST DEBUG:  CommitTransaction<br>
16934 2013-01-18 07:54:14 JST DEBUG:  name: unnamed; blockState:       STARTED; state: INPROGR, xid/subid/cid: 0/1/0, nestlvl: 1, children:<br>
16934 2013-01-18 07:54:14 JST DEBUG:  shmem_exit(0): 8 callbacks to make<br>
16934 2013-01-18 07:54:14 JST DEBUG:  proc_exit(0): 2 callbacks to make<br>
16934 2013-01-18 07:54:14 JST DEBUG:  exit(0)<br>
16934 2013-01-18 07:54:14 JST DEBUG:  shmem_exit(-1): 0 callbacks to make<br>
16934 2013-01-18 07:54:14 JST DEBUG:  proc_exit(-1): 0 callbacks to make<br>
16374 2013-01-18 07:54:14 JST DEBUG:  reaping dead processes<br>
16374 2013-01-18 07:54:14 JST DEBUG:  server process (PID 16934) exited with exit code 0<br>
16388 2013-01-18 07:55:14 JST DEBUG:  StartTransaction<br>
16388 2013-01-18 07:55:14 JST DEBUG:  name: unnamed; blockState:       DEFAULT; state: INPROGR, xid/subid/cid: 0/1/0, nestlvl: 1, children:<br>
16388 2013-01-18 07:55:14 JST DEBUG:  CommitTransaction<br>
16388 2013-01-18 07:55:14 JST DEBUG:  name: unnamed; blockState:       STARTED; state: INPROGR, xid/subid/cid: 0/1/0, nestlvl: 1, children:<br>
17019 2013-01-18 07:55:14 JST DEBUG:  InitPostgres<br>
17019 2013-01-18 07:55:14 JST DEBUG:  my backend ID is 2<br>
17019 2013-01-18 07:55:14 JST DEBUG:  StartTransaction<br>
17019 2013-01-18 07:55:14 JST DEBUG:  name: unnamed; blockState:       DEFAULT; state: INPROGR, xid/subid/cid: 0/1/0, nestlvl: 1, children:<br>
17019 2013-01-18 07:55:14 JST DEBUG:  CommitTransaction<br>
17019 2013-01-18 07:55:14 JST DEBUG:  name: unnamed; blockState:       STARTED; state: INPROGR, xid/subid/cid: 0/1/0, nestlvl: 1, children:<br>
17019 2013-01-18 07:55:14 JST DEBUG:  autovacuum: processing database &quot;postgres&quot;<br>
17019 2013-01-18 07:55:14 JST DEBUG:  StartTransaction<br>
17019 2013-01-18 07:55:14 JST DEBUG:  name: unnamed; blockState:       DEFAULT; state: INPROGR, xid/subid/cid: 0/1/0, nestlvl: 1, children:<br>
17019 2013-01-18 07:55:14 JST DEBUG:  pg_authid: vac: 0 (threshold 50), anl: 0 (threshold 50)<br>
17019 2013-01-18 07:55:14 JST DEBUG:  pg_class: vac: 0 (threshold 108), anl: 0 (threshold 79)<br>
17019 2013-01-18 07:55:14 JST DEBUG:  pg_attribute: vac: 0 (threshold 499), anl: 0 (threshold 275)<br>
17019 2013-01-18 07:55:14 JST DEBUG:  pg_opclass: vac: 0 (threshold 74), anl: 0 (threshold 62)<br>
17019 2013-01-18 07:55:14 JST DEBUG:  pg_am: vac: 0 (threshold 51), anl: 0 (threshold 50)<br>
17019 2013-01-18 07:55:14 JST DEBUG:  pg_amproc: vac: 0 (threshold 107), anl: 0 (threshold 79)<br>
17019 2013-01-18 07:55:14 JST DEBUG:  pg_database: vac: 0 (threshold 50), anl: 0 (threshold 50)<br>
17019 2013-01-18 07:55:14 JST DEBUG:  pg_index: vac: 0 (threshold 72), anl: 0 (threshold 61)<br>
17019 2013-01-18 07:55:14 JST DEBUG:  CommitTransaction<br>
17019 2013-01-18 07:55:14 JST DEBUG:  name: unnamed; blockState:       STARTED; state: INPROGR, xid/subid/cid: 0/1/0, nestlvl: 1, children:<br>
17019 2013-01-18 07:55:14 JST DEBUG:  shmem_exit(0): 8 callbacks to make<br>
17019 2013-01-18 07:55:14 JST DEBUG:  proc_exit(0): 2 callbacks to make<br>
17019 2013-01-18 07:55:14 JST DEBUG:  exit(0)<br>
17019 2013-01-18 07:55:14 JST DEBUG:  shmem_exit(-1): 0 callbacks to make<br>
17019 2013-01-18 07:55:14 JST DEBUG:  proc_exit(-1): 0 callbacks to make<br>
16374 2013-01-18 07:55:14 JST DEBUG:  reaping dead processes<br>
16374 2013-01-18 07:55:14 JST DEBUG:  server process (PID 17019) exited with exit code 0<br>
16388 2013-01-18 07:56:14 JST DEBUG:  StartTransaction<br>
16388 2013-01-18 07:56:14 JST DEBUG:  name: unnamed; blockState:       DEFAULT; state: INPROGR, xid/subid/cid: 0/1/0, nestlvl: 1, children:<br>
16388 2013-01-18 07:56:14 JST DEBUG:  CommitTransaction<br>
16388 2013-01-18 07:56:14 JST DEBUG:  name: unnamed; blockState:       STARTED; state: INPROGR, xid/subid/cid: 0/1/0, nestlvl: 1, children:<br>
17106 2013-01-18 07:56:14 JST DEBUG:  InitPostgres<br>
17106 2013-01-18 07:56:14 JST DEBUG:  my backend ID is 2<br>
17106 2013-01-18 07:56:14 JST DEBUG:  StartTransaction<br>
17106 2013-01-18 07:56:14 JST DEBUG:  name: unnamed; blockState:       DEFAULT; state: INPROGR, xid/subid/cid: 0/1/0, nestlvl: 1, children:<br>
17106 2013-01-18 07:56:14 JST DEBUG:  CommitTransaction<br>
17106 2013-01-18 07:56:14 JST DEBUG:  name: unnamed; blockState:       STARTED; state: INPROGR, xid/subid/cid: 0/1/0, nestlvl: 1, children:<br>
17106 2013-01-18 07:56:14 JST DEBUG:  autovacuum: processing database &quot;postgres&quot;<br>
17106 2013-01-18 07:56:14 JST DEBUG:  StartTransaction<br>
17106 2013-01-18 07:56:14 JST DEBUG:  name: unnamed; blockState:       DEFAULT; state: INPROGR, xid/subid/cid: 0/1/0, nestlvl: 1, children:<br>
17106 2013-01-18 07:56:14 JST DEBUG:  pg_authid: vac: 0 (threshold 50), anl: 0 (threshold 50)<br>
17106 2013-01-18 07:56:14 JST DEBUG:  pg_class: vac: 0 (threshold 108), anl: 0 (threshold 79)<br>
17106 2013-01-18 07:56:14 JST DEBUG:  pg_attribute: vac: 0 (threshold 499), anl: 0 (threshold 275)<br>
17106 2013-01-18 07:56:14 JST DEBUG:  pg_opclass: vac: 0 (threshold 74), anl: 0 (threshold 62)<br>
17106 2013-01-18 07:56:14 JST DEBUG:  pg_am: vac: 0 (threshold 51), anl: 0 (threshold 50)<br>
17106 2013-01-18 07:56:14 JST DEBUG:  pg_amproc: vac: 0 (threshold 107), anl: 0 (threshold 79)<br>
17106 2013-01-18 07:56:14 JST DEBUG:  pg_database: vac: 0 (threshold 50), anl: 0 (threshold 50)<br>
17106 2013-01-18 07:56:14 JST DEBUG:  pg_index: vac: 0 (threshold 72), anl: 0 (threshold 61)<br>
17106 2013-01-18 07:56:14 JST DEBUG:  CommitTransaction<br>
17106 2013-01-18 07:56:14 JST DEBUG:  name: unnamed; blockState:       STARTED; state: INPROGR, xid/subid/cid: 0/1/0, nestlvl: 1, children:<br>
17106 2013-01-18 07:56:14 JST DEBUG:  shmem_exit(0): 8 callbacks to make<br>
17106 2013-01-18 07:56:14 JST DEBUG:  proc_exit(0): 2 callbacks to make<br>
17106 2013-01-18 07:56:14 JST DEBUG:  exit(0)<br>
17106 2013-01-18 07:56:14 JST DEBUG:  shmem_exit(-1): 0 callbacks to make<br>
17106 2013-01-18 07:56:14 JST DEBUG:  proc_exit(-1): 0 callbacks to make<br>
16374 2013-01-18 07:56:14 JST DEBUG:  reaping dead processes<br>
16374 2013-01-18 07:56:14 JST DEBUG:  server process (PID 17106) exited with exit code 0<br>
16388 2013-01-18 07:57:14 JST DEBUG:  StartTransaction<br>
16388 2013-01-18 07:57:14 JST DEBUG:  name: unnamed; blockState:       DEFAULT; state: INPROGR, xid/subid/cid: 0/1/0, nestlvl: 1, children:<br>
16388 2013-01-18 07:57:14 JST DEBUG:  CommitTransaction<br>
16388 2013-01-18 07:57:14 JST DEBUG:  name: unnamed; blockState:       STARTED; state: INPROGR, xid/subid/cid: 0/1/0, nestlvl: 1, children:<br>
17190 2013-01-18 07:57:14 JST DEBUG:  InitPostgres<br>
17190 2013-01-18 07:57:14 JST DEBUG:  my backend ID is 2<br>
17190 2013-01-18 07:57:14 JST DEBUG:  StartTransaction<br>
17190 2013-01-18 07:57:14 JST DEBUG:  name: unnamed; blockState:       DEFAULT; state: INPROGR, xid/subid/cid: 0/1/0, nestlvl: 1, children:<br>
17190 2013-01-18 07:57:14 JST DEBUG:  CommitTransaction<br>
17190 2013-01-18 07:57:14 JST DEBUG:  name: unnamed; blockState:       STARTED; state: INPROGR, xid/subid/cid: 0/1/0, nestlvl: 1, children:<br>
17190 2013-01-18 07:57:14 JST DEBUG:  autovacuum: processing database &quot;postgres&quot;<br>
17190 2013-01-18 07:57:14 JST DEBUG:  StartTransaction<br>
17190 2013-01-18 07:57:14 JST DEBUG:  name: unnamed; blockState:       DEFAULT; state: INPROGR, xid/subid/cid: 0/1/0, nestlvl: 1, children:<br>
17190 2013-01-18 07:57:14 JST DEBUG:  pg_authid: vac: 0 (threshold 50), anl: 0 (threshold 50)<br>
17190 2013-01-18 07:57:14 JST DEBUG:  pg_class: vac: 0 (threshold 108), anl: 0 (threshold 79)<br>
17190 2013-01-18 07:57:14 JST DEBUG:  pg_attribute: vac: 0 (threshold 499), anl: 0 (threshold 275)<br>
17190 2013-01-18 07:57:14 JST DEBUG:  pg_opclass: vac: 0 (threshold 74), anl: 0 (threshold 62)<br>
17190 2013-01-18 07:57:14 JST DEBUG:  pg_am: vac: 0 (threshold 51), anl: 0 (threshold 50)<br>
17190 2013-01-18 07:57:14 JST DEBUG:  pg_amproc: vac: 0 (threshold 107), anl: 0 (threshold 79)<br>
17190 2013-01-18 07:57:14 JST DEBUG:  pg_database: vac: 0 (threshold 50), anl: 0 (threshold 50)<br>
17190 2013-01-18 07:57:14 JST DEBUG:  pg_index: vac: 0 (threshold 72), anl: 0 (threshold 61)<br>
17190 2013-01-18 07:57:14 JST DEBUG:  CommitTransaction<br>
17190 2013-01-18 07:57:14 JST DEBUG:  name: unnamed; blockState:       STARTED; state: INPROGR, xid/subid/cid: 0/1/0, nestlvl: 1, children:<br>
17190 2013-01-18 07:57:14 JST DEBUG:  shmem_exit(0): 8 callbacks to make<br>
17190 2013-01-18 07:57:14 JST DEBUG:  proc_exit(0): 2 callbacks to make<br>
17190 2013-01-18 07:57:14 JST DEBUG:  exit(0)<br>
17190 2013-01-18 07:57:14 JST DEBUG:  shmem_exit(-1): 0 callbacks to make<br>
17190 2013-01-18 07:57:14 JST DEBUG:  proc_exit(-1): 0 callbacks to make<br>
16374 2013-01-18 07:57:14 JST DEBUG:  reaping dead processes<br>
16374 2013-01-18 07:57:14 JST DEBUG:  server process (PID 17190) exited with exit code 0<br>
16388 2013-01-18 07:58:14 JST DEBUG:  StartTransaction<br>
16388 2013-01-18 07:58:14 JST DEBUG:  name: unnamed; blockState:       DEFAULT; state: INPROGR, xid/subid/cid: 0/1/0, nestlvl: 1, children:<br>
16388 2013-01-18 07:58:14 JST DEBUG:  CommitTransaction<br>
16388 2013-01-18 07:58:14 JST DEBUG:  name: unnamed; blockState:       STARTED; state: INPROGR, xid/subid/cid: 0/1/0, nestlvl: 1, children:<br>
17271 2013-01-18 07:58:14 JST DEBUG:  InitPostgres<br>
17271 2013-01-18 07:58:14 JST DEBUG:  my backend ID is 2<br>
17271 2013-01-18 07:58:14 JST DEBUG:  StartTransaction<br>
17271 2013-01-18 07:58:14 JST DEBUG:  name: unnamed; blockState:       DEFAULT; state: INPROGR, xid/subid/cid: 0/1/0, nestlvl: 1, children:<br>
17271 2013-01-18 07:58:14 JST DEBUG:  CommitTransaction<br>
17271 2013-01-18 07:58:14 JST DEBUG:  name: unnamed; blockState:       STARTED; state: INPROGR, xid/subid/cid: 0/1/0, nestlvl: 1, children:<br>
17271 2013-01-18 07:58:14 JST DEBUG:  autovacuum: processing database &quot;postgres&quot;<br>
17271 2013-01-18 07:58:14 JST DEBUG:  StartTransaction<br>
17271 2013-01-18 07:58:14 JST DEBUG:  name: unnamed; blockState:       DEFAULT; state: INPROGR, xid/subid/cid: 0/1/0, nestlvl: 1, children:<br>
17271 2013-01-18 07:58:14 JST DEBUG:  pg_authid: vac: 0 (threshold 50), anl: 0 (threshold 50)<br>
17271 2013-01-18 07:58:14 JST DEBUG:  pg_class: vac: 0 (threshold 108), anl: 0 (threshold 79)<br>
17271 2013-01-18 07:58:14 JST DEBUG:  pg_attribute: vac: 0 (threshold 499), anl: 0 (threshold 275)<br>
17271 2013-01-18 07:58:14 JST DEBUG:  pg_opclass: vac: 0 (threshold 74), anl: 0 (threshold 62)<br>
17271 2013-01-18 07:58:14 JST DEBUG:  pg_am: vac: 0 (threshold 51), anl: 0 (threshold 50)<br>
17271 2013-01-18 07:58:14 JST DEBUG:  pg_amproc: vac: 0 (threshold 107), anl: 0 (threshold 79)<br>
17271 2013-01-18 07:58:14 JST DEBUG:  pg_database: vac: 0 (threshold 50), anl: 0 (threshold 50)<br>
17271 2013-01-18 07:58:14 JST DEBUG:  pg_index: vac: 0 (threshold 72), anl: 0 (threshold 61)<br>
17271 2013-01-18 07:58:14 JST DEBUG:  CommitTransaction<br>
17271 2013-01-18 07:58:14 JST DEBUG:  name: unnamed; blockState:       STARTED; state: INPROGR, xid/subid/cid: 0/1/0, nestlvl: 1, children:<br>
17271 2013-01-18 07:58:14 JST DEBUG:  shmem_exit(0): 8 callbacks to make<br>
17271 2013-01-18 07:58:14 JST DEBUG:  proc_exit(0): 2 callbacks to make<br>
17271 2013-01-18 07:58:14 JST DEBUG:  exit(0)<br>
17271 2013-01-18 07:58:14 JST DEBUG:  shmem_exit(-1): 0 callbacks to make<br>
17271 2013-01-18 07:58:14 JST DEBUG:  proc_exit(-1): 0 callbacks to make<br>
16374 2013-01-18 07:58:14 JST DEBUG:  reaping dead processes<br>
16374 2013-01-18 07:58:14 JST DEBUG:  server process (PID 17271) exited with exit code 0<br>
16388 2013-01-18 07:59:14 JST DEBUG:  StartTransaction<br>
16388 2013-01-18 07:59:14 JST DEBUG:  name: unnamed; blockState:       DEFAULT; state: INPROGR, xid/subid/cid: 0/1/0, nestlvl: 1, children:<br>
16388 2013-01-18 07:59:14 JST DEBUG:  CommitTransaction<br>
16388 2013-01-18 07:59:14 JST DEBUG:  name: unnamed; blockState:       STARTED; state: INPROGR, xid/subid/cid: 0/1/0, nestlvl: 1, children:<br>
17357 2013-01-18 07:59:14 JST DEBUG:  InitPostgres<br>
17357 2013-01-18 07:59:14 JST DEBUG:  my backend ID is 2<br>
17357 2013-01-18 07:59:14 JST DEBUG:  StartTransaction<br>
17357 2013-01-18 07:59:14 JST DEBUG:  name: unnamed; blockState:       DEFAULT; state: INPROGR, xid/subid/cid: 0/1/0, nestlvl: 1, children:<br>
17357 2013-01-18 07:59:14 JST DEBUG:  CommitTransaction<br>
17357 2013-01-18 07:59:14 JST DEBUG:  name: unnamed; blockState:       STARTED; state: INPROGR, xid/subid/cid: 0/1/0, nestlvl: 1, children:<br>
17357 2013-01-18 07:59:14 JST DEBUG:  autovacuum: processing database &quot;postgres&quot;<br>
17357 2013-01-18 07:59:14 JST DEBUG:  StartTransaction<br>
17357 2013-01-18 07:59:14 JST DEBUG:  name: unnamed; blockState:       DEFAULT; state: INPROGR, xid/subid/cid: 0/1/0, nestlvl: 1, children:<br>
17357 2013-01-18 07:59:14 JST DEBUG:  pg_authid: vac: 0 (threshold 50), anl: 0 (threshold 50)<br>
17357 2013-01-18 07:59:14 JST DEBUG:  pg_class: vac: 0 (threshold 108), anl: 0 (threshold 79)<br>
17357 2013-01-18 07:59:14 JST DEBUG:  pg_attribute: vac: 0 (threshold 499), anl: 0 (threshold 275)<br>
17357 2013-01-18 07:59:14 JST DEBUG:  pg_opclass: vac: 0 (threshold 74), anl: 0 (threshold 62)<br>
17357 2013-01-18 07:59:14 JST DEBUG:  pg_am: vac: 0 (threshold 51), anl: 0 (threshold 50)<br>
17357 2013-01-18 07:59:14 JST DEBUG:  pg_amproc: vac: 0 (threshold 107), anl: 0 (threshold 79)<br>
17357 2013-01-18 07:59:14 JST DEBUG:  pg_database: vac: 0 (threshold 50), anl: 0 (threshold 50)<br>
17357 2013-01-18 07:59:14 JST DEBUG:  pg_index: vac: 0 (threshold 72), anl: 0 (threshold 61)<br>
17357 2013-01-18 07:59:14 JST DEBUG:  CommitTransaction<br>
17357 2013-01-18 07:59:14 JST DEBUG:  name: unnamed; blockState:       STARTED; state: INPROGR, xid/subid/cid: 0/1/0, nestlvl: 1, children:<br>
17357 2013-01-18 07:59:14 JST DEBUG:  shmem_exit(0): 8 callbacks to make<br>
17357 2013-01-18 07:59:14 JST DEBUG:  proc_exit(0): 2 callbacks to make<br>
17357 2013-01-18 07:59:14 JST DEBUG:  exit(0)<br>
17357 2013-01-18 07:59:14 JST DEBUG:  shmem_exit(-1): 0 callbacks to make<br>
17357 2013-01-18 07:59:14 JST DEBUG:  proc_exit(-1): 0 callbacks to make<br>
16374 2013-01-18 07:59:14 JST DEBUG:  reaping dead processes<br>
16374 2013-01-18 07:59:14 JST DEBUG:  server process (PID 17357) exited with exit code 0<br>
16388 2013-01-18 08:00:14 JST DEBUG:  StartTransaction<br>
16388 2013-01-18 08:00:14 JST DEBUG:  name: unnamed; blockState:       DEFAULT; state: INPROGR, xid/subid/cid: 0/1/0, nestlvl: 1, children:<br>
16388 2013-01-18 08:00:14 JST DEBUG:  CommitTransaction<br>
16388 2013-01-18 08:00:14 JST DEBUG:  name: unnamed; blockState:       STARTED; state: INPROGR, xid/subid/cid: 0/1/0, nestlvl: 1, children:<br>
17442 2013-01-18 08:00:14 JST DEBUG:  InitPostgres<br>
17442 2013-01-18 08:00:14 JST DEBUG:  my backend ID is 2<br>
17442 2013-01-18 08:00:14 JST DEBUG:  StartTransaction<br>
17442 2013-01-18 08:00:14 JST DEBUG:  name: unnamed; blockState:       DEFAULT; state: INPROGR, xid/subid/cid: 0/1/0, nestlvl: 1, children:<br>
17442 2013-01-18 08:00:14 JST DEBUG:  CommitTransaction<br>
17442 2013-01-18 08:00:14 JST DEBUG:  name: unnamed; blockState:       STARTED; state: INPROGR, xid/subid/cid: 0/1/0, nestlvl: 1, children:<br>
17442 2013-01-18 08:00:14 JST DEBUG:  autovacuum: processing database &quot;postgres&quot;<br>
17442 2013-01-18 08:00:14 JST DEBUG:  StartTransaction<br>
17442 2013-01-18 08:00:14 JST DEBUG:  name: unnamed; blockState:       DEFAULT; state: INPROGR, xid/subid/cid: 0/1/0, nestlvl: 1, children:<br>
17442 2013-01-18 08:00:14 JST DEBUG:  pg_authid: vac: 0 (threshold 50), anl: 0 (threshold 50)<br>
17442 2013-01-18 08:00:14 JST DEBUG:  pg_class: vac: 0 (threshold 108), anl: 0 (threshold 79)<br>
17442 2013-01-18 08:00:14 JST DEBUG:  pg_attribute: vac: 0 (threshold 499), anl: 0 (threshold 275)<br>
17442 2013-01-18 08:00:14 JST DEBUG:  pg_opclass: vac: 0 (threshold 74), anl: 0 (threshold 62)<br>
17442 2013-01-18 08:00:14 JST DEBUG:  pg_am: vac: 0 (threshold 51), anl: 0 (threshold 50)<br>
17442 2013-01-18 08:00:14 JST DEBUG:  pg_amproc: vac: 0 (threshold 107), anl: 0 (threshold 79)<br>
17442 2013-01-18 08:00:14 JST DEBUG:  pg_database: vac: 0 (threshold 50), anl: 0 (threshold 50)<br>
17442 2013-01-18 08:00:14 JST DEBUG:  pg_index: vac: 0 (threshold 72), anl: 0 (threshold 61)<br>
17442 2013-01-18 08:00:14 JST DEBUG:  CommitTransaction<br>
17442 2013-01-18 08:00:14 JST DEBUG:  name: unnamed; blockState:       STARTED; state: INPROGR, xid/subid/cid: 0/1/0, nestlvl: 1, children:<br>
17442 2013-01-18 08:00:14 JST DEBUG:  shmem_exit(0): 8 callbacks to make<br>
17442 2013-01-18 08:00:14 JST DEBUG:  proc_exit(0): 2 callbacks to make<br>
17442 2013-01-18 08:00:14 JST DEBUG:  exit(0)<br>
17442 2013-01-18 08:00:14 JST DEBUG:  shmem_exit(-1): 0 callbacks to make<br>
17442 2013-01-18 08:00:14 JST DEBUG:  proc_exit(-1): 0 callbacks to make<br>
16374 2013-01-18 08:00:14 JST DEBUG:  reaping dead processes<br>
16374 2013-01-18 08:00:14 JST DEBUG:  server process (PID 17442) exited with exit code 0<br>
16388 2013-01-18 08:01:14 JST DEBUG:  StartTransaction<br>
16388 2013-01-18 08:01:14 JST DEBUG:  name: unnamed; blockState:       DEFAULT; state: INPROGR, xid/subid/cid: 0/1/0, nestlvl: 1, children:<br>
16388 2013-01-18 08:01:14 JST DEBUG:  CommitTransaction<br>
16388 2013-01-18 08:01:14 JST DEBUG:  name: unnamed; blockState:       STARTED; state: INPROGR, xid/subid/cid: 0/1/0, nestlvl: 1, children:<br>
17529 2013-01-18 08:01:14 JST DEBUG:  InitPostgres<br>
17529 2013-01-18 08:01:14 JST DEBUG:  my backend ID is 2<br>
17529 2013-01-18 08:01:14 JST DEBUG:  StartTransaction<br>
17529 2013-01-18 08:01:14 JST DEBUG:  name: unnamed; blockState:       DEFAULT; state: INPROGR, xid/subid/cid: 0/1/0, nestlvl: 1, children:<br>
17529 2013-01-18 08:01:14 JST DEBUG:  CommitTransaction<br>
17529 2013-01-18 08:01:14 JST DEBUG:  name: unnamed; blockState:       STARTED; state: INPROGR, xid/subid/cid: 0/1/0, nestlvl: 1, children:<br>
17529 2013-01-18 08:01:14 JST DEBUG:  autovacuum: processing database &quot;postgres&quot;<br>
17529 2013-01-18 08:01:14 JST DEBUG:  StartTransaction<br>
17529 2013-01-18 08:01:14 JST DEBUG:  name: unnamed; blockState:       DEFAULT; state: INPROGR, xid/subid/cid: 0/1/0, nestlvl: 1, children:<br>
17529 2013-01-18 08:01:14 JST DEBUG:  pg_authid: vac: 0 (threshold 50), anl: 0 (threshold 50)<br>
17529 2013-01-18 08:01:14 JST DEBUG:  pg_class: vac: 0 (threshold 108), anl: 0 (threshold 79)<br>
17529 2013-01-18 08:01:14 JST DEBUG:  pg_attribute: vac: 0 (threshold 499), anl: 0 (threshold 275)<br>
17529 2013-01-18 08:01:14 JST DEBUG:  pg_opclass: vac: 0 (threshold 74), anl: 0 (threshold 62)<br>
17529 2013-01-18 08:01:14 JST DEBUG:  pg_am: vac: 0 (threshold 51), anl: 0 (threshold 50)<br>
17529 2013-01-18 08:01:14 JST DEBUG:  pg_amproc: vac: 0 (threshold 107), anl: 0 (threshold 79)<br>
17529 2013-01-18 08:01:14 JST DEBUG:  pg_database: vac: 0 (threshold 50), anl: 0 (threshold 50)<br>
17529 2013-01-18 08:01:14 JST DEBUG:  pg_index: vac: 0 (threshold 72), anl: 0 (threshold 61)<br>
17529 2013-01-18 08:01:14 JST DEBUG:  CommitTransaction<br>
17529 2013-01-18 08:01:14 JST DEBUG:  name: unnamed; blockState:       STARTED; state: INPROGR, xid/subid/cid: 0/1/0, nestlvl: 1, children:<br>
17529 2013-01-18 08:01:14 JST DEBUG:  shmem_exit(0): 8 callbacks to make<br>
17529 2013-01-18 08:01:14 JST DEBUG:  proc_exit(0): 2 callbacks to make<br>
17529 2013-01-18 08:01:14 JST DEBUG:  exit(0)<br>
17529 2013-01-18 08:01:14 JST DEBUG:  shmem_exit(-1): 0 callbacks to make<br>
17529 2013-01-18 08:01:14 JST DEBUG:  proc_exit(-1): 0 callbacks to make<br>
16374 2013-01-18 08:01:14 JST DEBUG:  reaping dead processes<br>
16374 2013-01-18 08:01:14 JST DEBUG:  server process (PID 17529) exited with exit code 0<br>
16388 2013-01-18 08:02:14 JST DEBUG:  StartTransaction<br>
16388 2013-01-18 08:02:14 JST DEBUG:  name: unnamed; blockState:       DEFAULT; state: INPROGR, xid/subid/cid: 0/1/0, nestlvl: 1, children:<br>
16388 2013-01-18 08:02:14 JST DEBUG:  CommitTransaction<br>
16388 2013-01-18 08:02:14 JST DEBUG:  name: unnamed; blockState:       STARTED; state: INPROGR, xid/subid/cid: 0/1/0, nestlvl: 1, children:<br>
17610 2013-01-18 08:02:14 JST DEBUG:  InitPostgres<br>
17610 2013-01-18 08:02:14 JST DEBUG:  my backend ID is 2<br>
17610 2013-01-18 08:02:14 JST DEBUG:  StartTransaction<br>
17610 2013-01-18 08:02:14 JST DEBUG:  name: unnamed; blockState:       DEFAULT; state: INPROGR, xid/subid/cid: 0/1/0, nestlvl: 1, children:<br>
17610 2013-01-18 08:02:14 JST DEBUG:  CommitTransaction<br>
17610 2013-01-18 08:02:14 JST DEBUG:  name: unnamed; blockState:       STARTED; state: INPROGR, xid/subid/cid: 0/1/0, nestlvl: 1, children:<br>
17610 2013-01-18 08:02:14 JST DEBUG:  autovacuum: processing database &quot;postgres&quot;<br>
17610 2013-01-18 08:02:14 JST DEBUG:  StartTransaction<br>
17610 2013-01-18 08:02:14 JST DEBUG:  name: unnamed; blockState:       DEFAULT; state: INPROGR, xid/subid/cid: 0/1/0, nestlvl: 1, children:<br>
17610 2013-01-18 08:02:14 JST DEBUG:  pg_authid: vac: 0 (threshold 50), anl: 0 (threshold 50)<br>
17610 2013-01-18 08:02:14 JST DEBUG:  pg_class: vac: 0 (threshold 108), anl: 0 (threshold 79)<br>
17610 2013-01-18 08:02:14 JST DEBUG:  pg_attribute: vac: 0 (threshold 499), anl: 0 (threshold 275)<br>
17610 2013-01-18 08:02:14 JST DEBUG:  pg_opclass: vac: 0 (threshold 74), anl: 0 (threshold 62)<br>
17610 2013-01-18 08:02:14 JST DEBUG:  pg_am: vac: 0 (threshold 51), anl: 0 (threshold 50)<br>
17610 2013-01-18 08:02:14 JST DEBUG:  pg_amproc: vac: 0 (threshold 107), anl: 0 (threshold 79)<br>
17610 2013-01-18 08:02:14 JST DEBUG:  pg_database: vac: 0 (threshold 50), anl: 0 (threshold 50)<br>
17610 2013-01-18 08:02:14 JST DEBUG:  pg_index: vac: 0 (threshold 72), anl: 0 (threshold 61)<br>
17610 2013-01-18 08:02:14 JST DEBUG:  CommitTransaction<br>
17610 2013-01-18 08:02:14 JST DEBUG:  name: unnamed; blockState:       STARTED; state: INPROGR, xid/subid/cid: 0/1/0, nestlvl: 1, children:<br>
17610 2013-01-18 08:02:14 JST DEBUG:  shmem_exit(0): 8 callbacks to make<br>
17610 2013-01-18 08:02:14 JST DEBUG:  proc_exit(0): 2 callbacks to make<br>
17610 2013-01-18 08:02:14 JST DEBUG:  exit(0)<br>
17610 2013-01-18 08:02:14 JST DEBUG:  shmem_exit(-1): 0 callbacks to make<br>
17610 2013-01-18 08:02:14 JST DEBUG:  proc_exit(-1): 0 callbacks to make<br>
16374 2013-01-18 08:02:14 JST DEBUG:  reaping dead processes<br>
16374 2013-01-18 08:02:14 JST DEBUG:  server process (PID 17610) exited with exit code 0<br>
16388 2013-01-18 08:03:14 JST DEBUG:  StartTransaction<br>
16388 2013-01-18 08:03:14 JST DEBUG:  name: unnamed; blockState:       DEFAULT; state: INPROGR, xid/subid/cid: 0/1/0, nestlvl: 1, children:<br>
16388 2013-01-18 08:03:14 JST DEBUG:  CommitTransaction<br>
16388 2013-01-18 08:03:14 JST DEBUG:  name: unnamed; blockState:       STARTED; state: INPROGR, xid/subid/cid: 0/1/0, nestlvl: 1, children:<br>
17696 2013-01-18 08:03:14 JST DEBUG:  InitPostgres<br>
17696 2013-01-18 08:03:14 JST DEBUG:  my backend ID is 2<br>
17696 2013-01-18 08:03:14 JST DEBUG:  StartTransaction<br>
17696 2013-01-18 08:03:14 JST DEBUG:  name: unnamed; blockState:       DEFAULT; state: INPROGR, xid/subid/cid: 0/1/0, nestlvl: 1, children:<br>
17696 2013-01-18 08:03:14 JST DEBUG:  CommitTransaction<br>
17696 2013-01-18 08:03:14 JST DEBUG:  name: unnamed; blockState:       STARTED; state: INPROGR, xid/subid/cid: 0/1/0, nestlvl: 1, children:<br>
17696 2013-01-18 08:03:14 JST DEBUG:  autovacuum: processing database &quot;postgres&quot;<br>
17696 2013-01-18 08:03:14 JST DEBUG:  StartTransaction<br>
17696 2013-01-18 08:03:14 JST DEBUG:  name: unnamed; blockState:       DEFAULT; state: INPROGR, xid/subid/cid: 0/1/0, nestlvl: 1, children:<br>
17696 2013-01-18 08:03:14 JST DEBUG:  pg_authid: vac: 0 (threshold 50), anl: 0 (threshold 50)<br>
17696 2013-01-18 08:03:14 JST DEBUG:  pg_class: vac: 0 (threshold 108), anl: 0 (threshold 79)<br>
17696 2013-01-18 08:03:14 JST DEBUG:  pg_attribute: vac: 0 (threshold 499), anl: 0 (threshold 275)<br>
17696 2013-01-18 08:03:14 JST DEBUG:  pg_opclass: vac: 0 (threshold 74), anl: 0 (threshold 62)<br>
17696 2013-01-18 08:03:14 JST DEBUG:  pg_am: vac: 0 (threshold 51), anl: 0 (threshold 50)<br>
17696 2013-01-18 08:03:14 JST DEBUG:  pg_amproc: vac: 0 (threshold 107), anl: 0 (threshold 79)<br>
17696 2013-01-18 08:03:14 JST DEBUG:  pg_database: vac: 0 (threshold 50), anl: 0 (threshold 50)<br>
17696 2013-01-18 08:03:14 JST DEBUG:  pg_index: vac: 0 (threshold 72), anl: 0 (threshold 61)<br>
17696 2013-01-18 08:03:14 JST DEBUG:  CommitTransaction<br>
17696 2013-01-18 08:03:14 JST DEBUG:  name: unnamed; blockState:       STARTED; state: INPROGR, xid/subid/cid: 0/1/0, nestlvl: 1, children:<br>
17696 2013-01-18 08:03:14 JST DEBUG:  shmem_exit(0): 8 callbacks to make<br>
17696 2013-01-18 08:03:14 JST DEBUG:  proc_exit(0): 2 callbacks to make<br>
17696 2013-01-18 08:03:14 JST DEBUG:  exit(0)<br>
17696 2013-01-18 08:03:14 JST DEBUG:  shmem_exit(-1): 0 callbacks to make<br>
17696 2013-01-18 08:03:14 JST DEBUG:  proc_exit(-1): 0 callbacks to make<br>
16374 2013-01-18 08:03:14 JST DEBUG:  reaping dead processes<br>
16374 2013-01-18 08:03:14 JST DEBUG:  server process (PID 17696) exited with exit code 0<br>
16388 2013-01-18 08:04:14 JST DEBUG:  StartTransaction<br>
16388 2013-01-18 08:04:14 JST DEBUG:  name: unnamed; blockState:       DEFAULT; state: INPROGR, xid/subid/cid: 0/1/0, nestlvl: 1, children:<br>
16388 2013-01-18 08:04:14 JST DEBUG:  CommitTransaction<br>
16388 2013-01-18 08:04:14 JST DEBUG:  name: unnamed; blockState:       STARTED; state: INPROGR, xid/subid/cid: 0/1/0, nestlvl: 1, children:<br>
17781 2013-01-18 08:04:14 JST DEBUG:  InitPostgres<br>
17781 2013-01-18 08:04:14 JST DEBUG:  my backend ID is 2<br>
17781 2013-01-18 08:04:14 JST DEBUG:  StartTransaction<br>
17781 2013-01-18 08:04:14 JST DEBUG:  name: unnamed; blockState:       DEFAULT; state: INPROGR, xid/subid/cid: 0/1/0, nestlvl: 1, children:<br>
17781 2013-01-18 08:04:14 JST DEBUG:  CommitTransaction<br>
17781 2013-01-18 08:04:14 JST DEBUG:  name: unnamed; blockState:       STARTED; state: INPROGR, xid/subid/cid: 0/1/0, nestlvl: 1, children:<br>
17781 2013-01-18 08:04:14 JST DEBUG:  autovacuum: processing database &quot;postgres&quot;<br>
17781 2013-01-18 08:04:14 JST DEBUG:  StartTransaction<br>
17781 2013-01-18 08:04:14 JST DEBUG:  name: unnamed; blockState:       DEFAULT; state: INPROGR, xid/subid/cid: 0/1/0, nestlvl: 1, children:<br>
17781 2013-01-18 08:04:14 JST DEBUG:  pg_authid: vac: 0 (threshold 50), anl: 0 (threshold 50)<br>
17781 2013-01-18 08:04:14 JST DEBUG:  pg_class: vac: 0 (threshold 108), anl: 0 (threshold 79)<br>
17781 2013-01-18 08:04:14 JST DEBUG:  pg_attribute: vac: 0 (threshold 499), anl: 0 (threshold 275)<br>
17781 2013-01-18 08:04:14 JST DEBUG:  pg_opclass: vac: 0 (threshold 74), anl: 0 (threshold 62)<br>
17781 2013-01-18 08:04:14 JST DEBUG:  pg_am: vac: 0 (threshold 51), anl: 0 (threshold 50)<br>
17781 2013-01-18 08:04:14 JST DEBUG:  pg_amproc: vac: 0 (threshold 107), anl: 0 (threshold 79)<br>
17781 2013-01-18 08:04:14 JST DEBUG:  pg_database: vac: 0 (threshold 50), anl: 0 (threshold 50)<br>
17781 2013-01-18 08:04:14 JST DEBUG:  pg_index: vac: 0 (threshold 72), anl: 0 (threshold 61)<br>
17781 2013-01-18 08:04:14 JST DEBUG:  CommitTransaction<br>
17781 2013-01-18 08:04:14 JST DEBUG:  name: unnamed; blockState:       STARTED; state: INPROGR, xid/subid/cid: 0/1/0, nestlvl: 1, children:<br>
17781 2013-01-18 08:04:14 JST DEBUG:  shmem_exit(0): 8 callbacks to make<br>
17781 2013-01-18 08:04:14 JST DEBUG:  proc_exit(0): 2 callbacks to make<br>
17781 2013-01-18 08:04:14 JST DEBUG:  exit(0)<br>
17781 2013-01-18 08:04:14 JST DEBUG:  shmem_exit(-1): 0 callbacks to make<br>
17781 2013-01-18 08:04:14 JST DEBUG:  proc_exit(-1): 0 callbacks to make<br>
16374 2013-01-18 08:04:14 JST DEBUG:  reaping dead processes<br>
16374 2013-01-18 08:04:14 JST DEBUG:  server process (PID 17781) exited with exit code 0<br>
16388 2013-01-18 08:05:14 JST DEBUG:  StartTransaction<br>
16388 2013-01-18 08:05:14 JST DEBUG:  name: unnamed; blockState:       DEFAULT; state: INPROGR, xid/subid/cid: 0/1/0, nestlvl: 1, children:<br>
16388 2013-01-18 08:05:14 JST DEBUG:  CommitTransaction<br>
16388 2013-01-18 08:05:14 JST DEBUG:  name: unnamed; blockState:       STARTED; state: INPROGR, xid/subid/cid: 0/1/0, nestlvl: 1, children:<br>
17866 2013-01-18 08:05:14 JST DEBUG:  InitPostgres<br>
17866 2013-01-18 08:05:14 JST DEBUG:  my backend ID is 2<br>
17866 2013-01-18 08:05:14 JST DEBUG:  StartTransaction<br>
17866 2013-01-18 08:05:14 JST DEBUG:  name: unnamed; blockState:       DEFAULT; state: INPROGR, xid/subid/cid: 0/1/0, nestlvl: 1, children:<br>
17866 2013-01-18 08:05:14 JST DEBUG:  CommitTransaction<br>
17866 2013-01-18 08:05:14 JST DEBUG:  name: unnamed; blockState:       STARTED; state: INPROGR, xid/subid/cid: 0/1/0, nestlvl: 1, children:<br>
17866 2013-01-18 08:05:14 JST DEBUG:  autovacuum: processing database &quot;postgres&quot;<br>
17866 2013-01-18 08:05:14 JST DEBUG:  StartTransaction<br>
17866 2013-01-18 08:05:14 JST DEBUG:  name: unnamed; blockState:       DEFAULT; state: INPROGR, xid/subid/cid: 0/1/0, nestlvl: 1, children:<br>
17866 2013-01-18 08:05:14 JST DEBUG:  pg_authid: vac: 0 (threshold 50), anl: 0 (threshold 50)<br>
17866 2013-01-18 08:05:14 JST DEBUG:  pg_class: vac: 0 (threshold 108), anl: 0 (threshold 79)<br>
17866 2013-01-18 08:05:14 JST DEBUG:  pg_attribute: vac: 0 (threshold 499), anl: 0 (threshold 275)<br>
17866 2013-01-18 08:05:14 JST DEBUG:  pg_opclass: vac: 0 (threshold 74), anl: 0 (threshold 62)<br>
17866 2013-01-18 08:05:14 JST DEBUG:  pg_am: vac: 0 (threshold 51), anl: 0 (threshold 50)<br>
17866 2013-01-18 08:05:14 JST DEBUG:  pg_amproc: vac: 0 (threshold 107), anl: 0 (threshold 79)<br>
17866 2013-01-18 08:05:14 JST DEBUG:  pg_database: vac: 0 (threshold 50), anl: 0 (threshold 50)<br>
17866 2013-01-18 08:05:14 JST DEBUG:  pg_index: vac: 0 (threshold 72), anl: 0 (threshold 61)<br>
17866 2013-01-18 08:05:14 JST DEBUG:  CommitTransaction<br>
17866 2013-01-18 08:05:14 JST DEBUG:  name: unnamed; blockState:       STARTED; state: INPROGR, xid/subid/cid: 0/1/0, nestlvl: 1, children:<br>
17866 2013-01-18 08:05:14 JST DEBUG:  shmem_exit(0): 8 callbacks to make<br>
17866 2013-01-18 08:05:14 JST DEBUG:  proc_exit(0): 2 callbacks to make<br>
17866 2013-01-18 08:05:14 JST DEBUG:  exit(0)<br>
17866 2013-01-18 08:05:14 JST DEBUG:  shmem_exit(-1): 0 callbacks to make<br>
17866 2013-01-18 08:05:14 JST DEBUG:  proc_exit(-1): 0 callbacks to make<br>
16374 2013-01-18 08:05:14 JST DEBUG:  reaping dead processes<br>
16374 2013-01-18 08:05:14 JST DEBUG:  server process (PID 17866) exited with exit code 0<br>
16388 2013-01-18 08:06:14 JST DEBUG:  StartTransaction<br>
16388 2013-01-18 08:06:14 JST DEBUG:  name: unnamed; blockState:       DEFAULT; state: INPROGR, xid/subid/cid: 0/1/0, nestlvl: 1, children:<br>
16388 2013-01-18 08:06:14 JST DEBUG:  CommitTransaction<br>
16388 2013-01-18 08:06:14 JST DEBUG:  name: unnamed; blockState:       STARTED; state: INPROGR, xid/subid/cid: 0/1/0, nestlvl: 1, children:<br>
17953 2013-01-18 08:06:14 JST DEBUG:  InitPostgres<br>
17953 2013-01-18 08:06:14 JST DEBUG:  my backend ID is 2<br>
17953 2013-01-18 08:06:14 JST DEBUG:  StartTransaction<br>
17953 2013-01-18 08:06:14 JST DEBUG:  name: unnamed; blockState:       DEFAULT; state: INPROGR, xid/subid/cid: 0/1/0, nestlvl: 1, children:<br>
17953 2013-01-18 08:06:14 JST DEBUG:  CommitTransaction<br>
17953 2013-01-18 08:06:14 JST DEBUG:  name: unnamed; blockState:       STARTED; state: INPROGR, xid/subid/cid: 0/1/0, nestlvl: 1, children:<br>
17953 2013-01-18 08:06:14 JST DEBUG:  autovacuum: processing database &quot;postgres&quot;<br>
17953 2013-01-18 08:06:14 JST DEBUG:  StartTransaction<br>
17953 2013-01-18 08:06:14 JST DEBUG:  name: unnamed; blockState:       DEFAULT; state: INPROGR, xid/subid/cid: 0/1/0, nestlvl: 1, children:<br>
17953 2013-01-18 08:06:14 JST DEBUG:  pg_authid: vac: 0 (threshold 50), anl: 0 (threshold 50)<br>
17953 2013-01-18 08:06:14 JST DEBUG:  pg_class: vac: 0 (threshold 108), anl: 0 (threshold 79)<br>
17953 2013-01-18 08:06:14 JST DEBUG:  pg_attribute: vac: 0 (threshold 499), anl: 0 (threshold 275)<br>
17953 2013-01-18 08:06:14 JST DEBUG:  pg_opclass: vac: 0 (threshold 74), anl: 0 (threshold 62)<br>
17953 2013-01-18 08:06:14 JST DEBUG:  pg_am: vac: 0 (threshold 51), anl: 0 (threshold 50)<br>
17953 2013-01-18 08:06:14 JST DEBUG:  pg_amproc: vac: 0 (threshold 107), anl: 0 (threshold 79)<br>
17953 2013-01-18 08:06:14 JST DEBUG:  pg_database: vac: 0 (threshold 50), anl: 0 (threshold 50)<br>
17953 2013-01-18 08:06:14 JST DEBUG:  pg_index: vac: 0 (threshold 72), anl: 0 (threshold 61)<br>
17953 2013-01-18 08:06:14 JST DEBUG:  CommitTransaction<br>
17953 2013-01-18 08:06:14 JST DEBUG:  name: unnamed; blockState:       STARTED; state: INPROGR, xid/subid/cid: 0/1/0, nestlvl: 1, children:<br>
17953 2013-01-18 08:06:14 JST DEBUG:  shmem_exit(0): 8 callbacks to make<br>
17953 2013-01-18 08:06:14 JST DEBUG:  proc_exit(0): 2 callbacks to make<br>
17953 2013-01-18 08:06:14 JST DEBUG:  exit(0)<br>
17953 2013-01-18 08:06:14 JST DEBUG:  shmem_exit(-1): 0 callbacks to make<br>
17953 2013-01-18 08:06:14 JST DEBUG:  proc_exit(-1): 0 callbacks to make<br>
16374 2013-01-18 08:06:14 JST DEBUG:  reaping dead processes<br>
16374 2013-01-18 08:06:14 JST DEBUG:  server process (PID 17953) exited with exit code 0<br>
16388 2013-01-18 08:07:14 JST DEBUG:  StartTransaction<br>
16388 2013-01-18 08:07:14 JST DEBUG:  name: unnamed; blockState:       DEFAULT; state: INPROGR, xid/subid/cid: 0/1/0, nestlvl: 1, children:<br>
16388 2013-01-18 08:07:14 JST DEBUG:  CommitTransaction<br>
16388 2013-01-18 08:07:14 JST DEBUG:  name: unnamed; blockState:       STARTED; state: INPROGR, xid/subid/cid: 0/1/0, nestlvl: 1, children:<br>
18032 2013-01-18 08:07:14 JST DEBUG:  InitPostgres<br>
18032 2013-01-18 08:07:14 JST DEBUG:  my backend ID is 2<br>
18032 2013-01-18 08:07:14 JST DEBUG:  StartTransaction<br>
18032 2013-01-18 08:07:14 JST DEBUG:  name: unnamed; blockState:       DEFAULT; state: INPROGR, xid/subid/cid: 0/1/0, nestlvl: 1, children:<br>
18032 2013-01-18 08:07:14 JST DEBUG:  CommitTransaction<br>
18032 2013-01-18 08:07:14 JST DEBUG:  name: unnamed; blockState:       STARTED; state: INPROGR, xid/subid/cid: 0/1/0, nestlvl: 1, children:<br>
18032 2013-01-18 08:07:14 JST DEBUG:  autovacuum: processing database &quot;postgres&quot;<br>
18032 2013-01-18 08:07:14 JST DEBUG:  StartTransaction<br>
18032 2013-01-18 08:07:14 JST DEBUG:  name: unnamed; blockState:       DEFAULT; state: INPROGR, xid/subid/cid: 0/1/0, nestlvl: 1, children:<br>
18032 2013-01-18 08:07:14 JST DEBUG:  pg_authid: vac: 0 (threshold 50), anl: 0 (threshold 50)<br>
18032 2013-01-18 08:07:14 JST DEBUG:  pg_class: vac: 0 (threshold 108), anl: 0 (threshold 79)<br>
18032 2013-01-18 08:07:14 JST DEBUG:  pg_attribute: vac: 0 (threshold 499), anl: 0 (threshold 275)<br>
18032 2013-01-18 08:07:14 JST DEBUG:  pg_opclass: vac: 0 (threshold 74), anl: 0 (threshold 62)<br>
18032 2013-01-18 08:07:14 JST DEBUG:  pg_am: vac: 0 (threshold 51), anl: 0 (threshold 50)<br>
18032 2013-01-18 08:07:14 JST DEBUG:  pg_amproc: vac: 0 (threshold 107), anl: 0 (threshold 79)<br>
18032 2013-01-18 08:07:14 JST DEBUG:  pg_database: vac: 0 (threshold 50), anl: 0 (threshold 50)<br>
18032 2013-01-18 08:07:14 JST DEBUG:  pg_index: vac: 0 (threshold 72), anl: 0 (threshold 61)<br>
18032 2013-01-18 08:07:14 JST DEBUG:  CommitTransaction<br>
18032 2013-01-18 08:07:14 JST DEBUG:  name: unnamed; blockState:       STARTED; state: INPROGR, xid/subid/cid: 0/1/0, nestlvl: 1, children:<br>
18032 2013-01-18 08:07:14 JST DEBUG:  shmem_exit(0): 8 callbacks to make<br>
18032 2013-01-18 08:07:14 JST DEBUG:  proc_exit(0): 2 callbacks to make<br>
18032 2013-01-18 08:07:14 JST DEBUG:  exit(0)<br>
18032 2013-01-18 08:07:14 JST DEBUG:  shmem_exit(-1): 0 callbacks to make<br>
18032 2013-01-18 08:07:14 JST DEBUG:  proc_exit(-1): 0 callbacks to make<br>
16374 2013-01-18 08:07:14 JST DEBUG:  reaping dead processes<br>
16374 2013-01-18 08:07:14 JST DEBUG:  server process (PID 18032) exited with exit code 0<br>
<br></blockquote></div><br>