[pgpool-general: 1795] Re: Select AddGeometryColumn query failing on one backend - urgent help needed

Deval Chauhan deval_chauhan at yahoo.com
Wed May 29 13:32:21 JST 2013 

Thanks Tatsua for your immediate response. 

AddGeometryColumn adds geometry column to the table and also registers this column in geography_columns table of postgis(extension of postgresql). 

Is there a way I can tell Pgpool that Select AddGeometryColumn is a writing function? 

The thing that I would also like to know why would restarting postgresql service on broken node(where it was not creating 'geom' and 'geom_low' columns) would make everything fine even Select AddGeometryColumn is now working fine, not sure when it will break again. The error does shows up frequently though.

Your reply will be again appreciated...


 


________________________________
 From: Tatsuo Ishii <ishii at postgresql.org>
To: deval_chauhan at yahoo.com 
Cc: pgpool-general at pgpool.net 
Sent: Tuesday, May 28, 2013 6:11 PM
Subject: Re: [pgpool-general: 1792] Select AddGeometryColumn query failing on one backend - urgent help needed
 

What does AddGeometryColumn do? My guess is it issues ALTER TABLE
inside. If so, what happens here would be:

1) SELECT AddGeometryColumn only gets executed on one of the
   PostgreSQL servers, because you did not tell pgpool-II that it is a
   writing function.

2) Subsequent INSERT gets executed on both of PostgreSQL servers
   because pgpool-II realized it needs to be executed on both
   PostgreSQL.

3) PostgreSQL A has newly added column by #1, while B does not. So
   pgpool-II gets an error from PostgreSQL.
--
Tatsuo Ishii
SRA OSS, Inc. Japan
English: http://www.sraoss.co.jp/index_en.php
Japanese: http://www.sraoss.co.jp

> Update - After I restarted Postgresql service on broken node, things were fine. So not sure, if it is related to postgresql or pgpool ? But if I use postgresql directly for inserts there is never such problem.
> 
> 
> 
> Pgpool oftens errors out with "Connection is Broken" message with logs which states "geom" column does not exist on one Postgresql server. I did verified this that this holds true on one postgresql server that it did not have geom and geom_low columns.
> 
> Here is my set-up.
> 
> Two postgresql boxes on separate boxes
> One Pgpool box pointing to above mentioned postgresql boxes.
> 
> I am attaching my logs for your references--
> 
> Here is the sequence of queries that gets fired :
> 
> SET CLIENT_ENCODING TO UTF8;SET STANDARD_CONFORMING_STRINGS TO ON;BEGIN; create table public._1ebac66315f34b00a9fcfd0b19bfad69 ( gid serial PRIMARY KEY, SRA  varchar(3),INCORP  varchar(1),HAZ_CODE  int,HAZ_CLASS  varchar(25),VH_REC  varchar(25),Shape_Leng  double precision,Shape_Area  double precision ) ;
> 
> 
> SELECT AddGeometryColumn('public','_1ebac66315f34b00a9fcfd0b19bfad69','geom','3857','MULTIPOLYGON',2);  
> 
> SELECT AddGeometryColumn('public','_1ebac66315f34b00a9fcfd0b19bfad69','geom_low','3857','MULTIPOLYGON',2);
> 
> 
> insert into _1ebac66315f34b00a9fcfd0b19bfad69  ( SRA,HAZ_CODE,HAZ_CLASS,Shape_Leng,Shape_Area,geom) values  ( 'FRA',2,'High',3.63920547962e+003,1.47780526502e+005, ST_GeomFromWKB(st_multi(decode('AQMAAAABAAAAHwAAAKWSCbtw62jB4EhufEg7TkGVAj2cXOtowcWr/AFHO05BLlKxzVvraMFzC4X0RjtOQaEpMD9662jB1UPX3QM6TkEDlBzQoutowX55VodcOE5BxK3ftKLraME/tVvzazhOQVn3UPyh62jBff7ZcdQ4TkG27El5putowTKMIfLUOE5BPyhnWabraMHmf0oA5zhOQc0rYdaq62jBvTuOgOc4TkEreJ2WqutowRhv6pwLOU5B5YmZE6/raMEdjSodDDlOQVyd19Ou62jBcFiUOTA5TkEJy85ExetowfydmroyOU5BfaUWBcXraMGIoRPXVjlOQW0MF4LJ62jBEmw8V1c5TkFYPXp9yetowXzUgfRZOU5Bv6yoVsnraMHMCgL1bzlOQdrjsPqM62jBoRGC6G45TkHX8ixRjOtowR6SZpMCOk5BHNWuTYzraMFVkPrjAzpOQT++MEqM62jBRaGKNAU6TkE9afhsietowTS2m+IEOk5ByktnzYjraME2nJsqXzpOQX/JkVqL62jBOh+hc186TkGMLuHPiutowSA6Cq+TOk5BmxXnl23raME/sqL7kTpOQSpg4WRt62jBho7wdNI6TkGBi+n6bOtowROfMokSO05B7ldWGnHraMFYnjqaEjtOQaWSCbtw62jB4EhufEg7TkEAAAAA','base64')),3857));
> 
> 
> Any help will be greatly appreciated....
> 
> Thank you in advance,
> 
> 
> pgpool.log file:
> 
> 2013-05-28 12:52:28 DEBUG: pid 2414: statement2: SET CLIENT_ENCODING TO UTF8;SET STANDARD_CONFORMING_STRINGS TO ON;BEGIN; create table public._1ebac66315f34b00a9fcfd0b19bfad69 ( gid serial PRIMARY KEY, SRA  varchar(3),INCORP  varchar(1),HAZ_CODE  int,HAZ_CLASS  varchar(25),VH_REC  varchar(25),Shape_Leng  double precision,Shape_Area  double precision ) ;
> 2013-05-28 12:52:28 DEBUG: pid 2414: pool_set_query_in_progress: done
> 2013-05-28 12:52:28 DEBUG: pid 2414: do_command: Query: BEGIN
> 2013-05-28 12:52:28 DEBUG: pid 2414: wait_for_query_response: waiting for backend 0 completing the query
> 2013-05-28 12:52:28 DEBUG: pid 2414: detect_error: kind: C
> 2013-05-28 12:52:28 DEBUG: pid 2414: do_command: kind: C
> 2013-05-28 12:52:28 DEBUG: pid 2414: len:a000000
> 2013-05-28 12:52:28 DEBUG: pid 2414: do_command: kind: Z
> 2013-05-28 12:52:28 DEBUG: pid 2414: do_command: transaction state: T
> 2013-05-28 12:52:28 DEBUG: pid 2414: pool_unset_writing_transaction: done
> 2013-05-28 12:52:28 DEBUG: pid 2414: do_command: Query: BEGIN
> 2013-05-28 12:52:28 DEBUG: pid 2414: wait_for_query_response: waiting for backend 1 completing the query
> 2013-05-28 12:52:28 DEBUG: pid 2414: detect_error: kind: C
> 2013-05-28 12:52:28 DEBUG: pid 2414: do_command: kind: C
> 2013-05-28 12:52:28 DEBUG: pid 2414: len:a000000
> 2013-05-28 12:52:28 DEBUG: pid 2414: do_command: kind: Z
> 2013-05-28 12:52:28 DEBUG: pid 2414: do_command: transaction state: T
> 2013-05-28 12:52:28 DEBUG: pid 2414: pool_unset_writing_transaction: done
> 2013-05-28 12:52:28 DEBUG: pid 2414: wait_for_query_response: waiting for backend 0 completing the query
> 2013-05-28 12:52:28 DEBUG: pid 2414: detect_error: kind: S
> 2013-05-28 12:52:28 DEBUG: pid 2414: detect_error: kind: S
> 2013-05-28 12:52:28 DEBUG: pid 2414: detect_error: kind: S
> 2013-05-28 12:52:28 DEBUG: pid 2414: detect_error: kind: S
> 2013-05-28 12:52:28 DEBUG: pid 2414: wait_for_query_response: waiting for backend 1 completing the query
> 2013-05-28 12:52:28 DEBUG: pid 2414: read_kind_from_backend: kind: S from 0 th backend
> 2013-05-28 12:52:28 DEBUG: pid 2414: read_kind_from_backend: parameter name: client_encoding value: UTF8
> 2013-05-28 12:52:28 DEBUG: pid 2414: read_kind_from_backend: kind: C from 0 th backend
> 2013-05-28 12:52:28 DEBUG: pid 2414: read_kind_from_backend: read kind from 0 th backend C NUM_BACKENDS: 2
> 2013-05-28 12:52:28 DEBUG: pid 2414: read_kind_from_backend: kind: S from 1 th backend
> 2013-05-28 12:52:28 DEBUG: pid 2414: read_kind_from_backend: parameter name: client_encoding value: UTF8
> 2013-05-28 12:52:28 DEBUG: pid 2414: read_kind_from_backend: kind: C from 1 th backend
> 2013-05-28 12:52:28 DEBUG: pid 2414: read_kind_from_backend: read kind from 1 th backend C NUM_BACKENDS: 2
> 2013-05-28 12:52:28 DEBUG: pid 2414: ProcessBackendResponse: kind from backend: C
> 2013-05-28 12:52:28 DEBUG: pid 2414: pool_set_command_success: done
> 2013-05-28 12:52:28 DEBUG: pid 2414: read_kind_from_backend: kind: S from 0 th backend
> 2013-05-28 12:52:28 DEBUG: pid 2414: read_kind_from_backend: parameter name: standard_conforming_strings value: on
> 2013-05-28 12:52:28 DEBUG: pid 2414: read_kind_from_backend: kind: C from 0 th backend
> 2013-05-28 12:52:28 DEBUG: pid 2414: read_kind_from_backend: read kind from 0 th backend C NUM_BACKENDS: 2
> 2013-05-28 12:52:28 DEBUG: pid 2414: read_kind_from_backend: kind: S from 1 th backend
> 2013-05-28 12:52:28 DEBUG: pid 2414: read_kind_from_backend: parameter name: standard_conforming_strings value: on
> 2013-05-28 12:52:28 DEBUG: pid 2414: read_kind_from_backend: kind: C from 1 th backend
> 2013-05-28 12:52:28 DEBUG: pid 2414: read_kind_from_backend: read kind from 1 th backend C NUM_BACKENDS: 2
> 2013-05-28 12:52:28 DEBUG: pid 2414: ProcessBackendResponse: kind from backend: C
> 2013-05-28 12:52:28 DEBUG: pid 2414: pool_set_command_success: done
> 2013-05-28 12:52:28 DEBUG: pid 2414: read_kind_from_backend: kind: N from 0 th backend
> 2013-05-28 12:52:28 DEBUG: pid 2414: read_kind_from_backend: read kind from 0 th backend N NUM_BACKENDS: 2
> 2013-05-28 12:52:28 DEBUG: pid 2414: read_kind_from_backend: kind: N from 1 th backend
> 2013-05-28 12:52:28 DEBUG: pid 2414: read_kind_from_backend: read kind from 1 th backend N NUM_BACKENDS: 2
> 2013-05-28 12:52:28 DEBUG: pid 2414: ProcessBackendResponse: kind from backend: N
> 2013-05-28 12:52:28 DEBUG: pid 2414: read_kind_from_backend: kind: C from 0 th backend
> 2013-05-28 12:52:28 DEBUG: pid 2414: read_kind_from_backend: read kind from 0 th backend C NUM_BACKENDS: 2
> 2013-05-28 12:52:28 DEBUG: pid 2414: read_kind_from_backend: kind: C from 1 th backend
> 2013-05-28 12:52:28 DEBUG: pid 2414: read_kind_from_backend: read kind from 1 th backend C NUM_BACKENDS: 2
> 2013-05-28 12:52:28 DEBUG: pid 2414: ProcessBackendResponse: kind from backend: C
> 2013-05-28 12:52:28 DEBUG: pid 2414: pool_set_command_success: done
> 2013-05-28 12:52:28 DEBUG: pid 2414: read_kind_from_backend: kind: N from 0 th backend
> 2013-05-28 12:52:28 DEBUG: pid 2414: read_kind_from_backend: read kind from 0 th backend N NUM_BACKENDS: 2
> 2013-05-28 12:52:28 DEBUG: pid 2414: read_kind_from_backend: kind: N from 1 th backend
> 2013-05-28 12:52:28 DEBUG: pid 2414: read_kind_from_backend: read kind from 1 th backend N NUM_BACKENDS: 2
> 2013-05-28 12:52:28 DEBUG: pid 2414: ProcessBackendResponse: kind from backend: N
> 2013-05-28 12:52:28 DEBUG: pid 2414: read_kind_from_backend: kind: N from 0 th backend
> 2013-05-28 12:52:28 DEBUG: pid 2414: read_kind_from_backend: read kind from 0 th backend N NUM_BACKENDS: 2
> 2013-05-28 12:52:28 DEBUG: pid 2414: read_kind_from_backend: kind: N from 1 th backend
> 2013-05-28 12:52:28 DEBUG: pid 2414: read_kind_from_backend: read kind from 1 th backend N NUM_BACKENDS: 2
> 2013-05-28 12:52:28 DEBUG: pid 2414: ProcessBackendResponse: kind from backend: N
> 2013-05-28 12:52:28 DEBUG: pid 2414: read_kind_from_backend: kind: C from 0 th backend
> 2013-05-28 12:52:28 DEBUG: pid 2414: read_kind_from_backend: read kind from 0 th backend C NUM_BACKENDS: 2
> 2013-05-28 12:52:28 DEBUG: pid 2414: read_kind_from_backend: kind: C from 1 th backend
> 2013-05-28 12:52:28 DEBUG: pid 2414: read_kind_from_backend: read kind from 1 th backend C NUM_BACKENDS: 2
> 2013-05-28 12:52:28 DEBUG: pid 2414: ProcessBackendResponse: kind from backend: C
> 2013-05-28 12:52:28 DEBUG: pid 2414: pool_set_command_success: done
> 2013-05-28 12:52:28 DEBUG: pid 2414: read_kind_from_backend: kind: Z from 0 th backend
> 2013-05-28 12:52:28 DEBUG: pid 2414: read_kind_from_backend: read kind from 0 th backend Z NUM_BACKENDS: 2
> 2013-05-28 12:52:28 DEBUG: pid 2414: read_kind_from_backend: kind: Z from 1 th backend
> 2013-05-28 12:52:28 DEBUG: pid 2414: read_kind_from_backend: read kind from 1 th backend Z NUM_BACKENDS: 2
> 2013-05-28 12:52:28 DEBUG: pid 2414: ProcessBackendResponse: kind from backend: Z
> 2013-05-28 12:52:28 DEBUG: pid 2414: do_command: Query: COMMIT
> 2013-05-28 12:52:28 DEBUG: pid 2414: wait_for_query_response: waiting for backend 1 completing the query
> 2013-05-28 12:52:28 DEBUG: pid 2414: detect_error: kind: C
> 2013-05-28 12:52:28 DEBUG: pid 2414: do_command: kind: C
> 2013-05-28 12:52:28 DEBUG: pid 2414: len:b000000
> 2013-05-28 12:52:28 DEBUG: pid 2414: do_command: kind: Z
> 2013-05-28 12:52:28 DEBUG: pid 2414: do_command: Query: COMMIT
> 2013-05-28 12:52:28 DEBUG: pid 2414: wait_for_query_response: waiting for backend 0 completing the query
> 2013-05-28 12:52:28 DEBUG: pid 2414: detect_error: kind: C
> 2013-05-28 12:52:28 DEBUG: pid 2414: do_command: kind: C
> 2013-05-28 12:52:28 DEBUG: pid 2414: len:b000000
> 2013-05-28 12:52:28 DEBUG: pid 2414: do_command: kind: Z
> 2013-05-28 12:52:28 DEBUG: pid 2414: pool_read_message_length: slot: 0 length: 5
> 2013-05-28 12:52:28 DEBUG: pid 2414: pool_read_message_length: slot: 1 length: 5
> 2013-05-28 12:52:28 DEBUG: pid 2414: ReadyForQuery: transaction state:T
> 2013-05-28 12:52:28 DEBUG: pid 2414: ReadyForQuery: transaction state:I
> 2013-05-28 12:52:28 DEBUG: pid 2414: pool_unset_query_in_progress: done
> 2013-05-28 12:52:28 DEBUG: pid 2414: pool_unset_query_in_progress: done
> 2013-05-28 12:52:28 DEBUG: pid 2414: ProcessBackendResponse: Ready For Query
> 2013-05-28 12:52:28 DEBUG: pid 2414: ProcessFrontendResponse: kind from frontend Q(51)
> 2013-05-28 12:52:28 DEBUG: pid 2414: pool_unset_doing_extended_query_message: done
> 2013-05-28 12:52:28 DEBUG: pid 2414: statement2: unlisten *
> 2013-05-28 12:52:28 DEBUG: pid 2414: pool_set_query_in_progress: done
> 2013-05-28 12:52:28 DEBUG: pid 2414: do_command: Query: BEGIN
> 2013-05-28 12:52:28 DEBUG: pid 2414: wait_for_query_response: waiting for backend 0 completing the query
> 2013-05-28 12:52:28 DEBUG: pid 2414: detect_error: kind: C
> 2013-05-28 12:52:28 DEBUG: pid 2414: do_command: kind: C
> 2013-05-28 12:52:28 DEBUG: pid 2414: len:a000000
> 2013-05-28 12:52:28 DEBUG: pid 2414: do_command: kind: Z
> 2013-05-28 12:52:28 DEBUG: pid 2414: do_command: transaction state: T
> 2013-05-28 12:52:28 DEBUG: pid 2414: pool_unset_writing_transaction: done
> 2013-05-28 12:52:28 DEBUG: pid 2414: do_command: Query: BEGIN
> 2013-05-28 12:52:28 DEBUG: pid 2414: wait_for_query_response: waiting for backend 1 completing the query
> 2013-05-28 12:52:28 DEBUG: pid 2414: detect_error: kind: C
> 2013-05-28 12:52:28 DEBUG: pid 2414: do_command: kind: C
> 2013-05-28 12:52:28 DEBUG: pid 2414: len:a000000
> 2013-05-28 12:52:28 DEBUG: pid 2414: do_command: kind: Z
> 2013-05-28 12:52:28 DEBUG: pid 2414: do_command: transaction state: T
> 2013-05-28 12:52:28 DEBUG: pid 2414: pool_unset_writing_transaction: done
> 2013-05-28 12:52:28 DEBUG: pid 2414: wait_for_query_response: waiting for backend 0 completing the query
> 2013-05-28 12:52:28 DEBUG: pid 2414: detect_error: kind: C
> 2013-05-28 12:52:28 DEBUG: pid 2414: detect_error: kind: C
> 2013-05-28 12:52:28 DEBUG: pid 2414: detect_error: kind: C
> 2013-05-28 12:52:28 DEBUG: pid 2414: detect_error: kind: C
> 2013-05-28 12:52:28 DEBUG: pid 2414: wait_for_query_response: waiting for backend 1 completing the query
> 2013-05-28 12:52:28 DEBUG: pid 2414: read_kind_from_backend: kind: C from 0 th backend
> 2013-05-28 12:52:28 DEBUG: pid 2414: read_kind_from_backend: read kind from 0 th backend C NUM_BACKENDS: 2
> 2013-05-28 12:52:28 DEBUG: pid 2414: read_kind_from_backend: kind: C from 1 th backend
> 2013-05-28 12:52:28 DEBUG: pid 2414: read_kind_from_backend: read kind from 1 th backend C NUM_BACKENDS: 2
> 2013-05-28 12:52:28 DEBUG: pid 2414: ProcessBackendResponse: kind from backend: C
> 2013-05-28 12:52:28 DEBUG: pid 2414: pool_set_command_success: done
> 2013-05-28 12:52:28 DEBUG: pid 2414: read_kind_from_backend: kind: Z from 0 th backend
> 2013-05-28 12:52:28 DEBUG: pid 2414: read_kind_from_backend: read kind from 0 th backend Z NUM_BACKENDS: 2
> 2013-05-28 12:52:28 DEBUG: pid 2414: read_kind_from_backend: kind: Z from 1 th backend
> 2013-05-28 12:52:28 DEBUG: pid 2414: read_kind_from_backend: read kind from 1 th backend Z NUM_BACKENDS: 2
> 2013-05-28 12:52:28 DEBUG: pid 2414: ProcessBackendResponse: kind from backend: Z
> 2013-05-28 12:52:28 DEBUG: pid 2414: do_command: Query: COMMIT
> 2013-05-28 12:52:28 DEBUG: pid 2414: wait_for_query_response: waiting for backend 1 completing the query
> 2013-05-28 12:52:28 DEBUG: pid 2414: detect_error: kind: C
> 2013-05-28 12:52:28 DEBUG: pid 2414: do_command: kind: C
> 2013-05-28 12:52:28 DEBUG: pid 2414: len:b000000
> 2013-05-28 12:52:28 DEBUG: pid 2414: do_command: kind: Z
> 2013-05-28 12:52:28 DEBUG: pid 2414: do_command: Query: COMMIT
> 2013-05-28 12:52:28 DEBUG: pid 2414: wait_for_query_response: waiting for backend 0 completing the query
> 2013-05-28 12:52:28 DEBUG: pid 2414: detect_error: kind: C
> 2013-05-28 12:52:28 DEBUG: pid 2414: do_command: kind: C
> 2013-05-28 12:52:28 DEBUG: pid 2414: len:b000000
> 2013-05-28 12:52:28 DEBUG: pid 2414: do_command: kind: Z
> 2013-05-28 12:52:28 DEBUG: pid 2414: pool_read_message_length: slot: 0 length: 5
> 2013-05-28 12:52:28 DEBUG: pid 2414: pool_read_message_length: slot: 1 length: 5
> 2013-05-28 12:52:28 DEBUG: pid 2414: ReadyForQuery: transaction state:T
> 2013-05-28 12:52:28 DEBUG: pid 2414: ReadyForQuery: transaction state:I
> 2013-05-28 12:52:28 DEBUG: pid 2414: pool_unset_query_in_progress: done
> 2013-05-28 12:52:28 DEBUG: pid 2414: pool_unset_query_in_progress: done
> 2013-05-28 12:52:28 DEBUG: pid 2414: ProcessBackendResponse: Ready For Query
> 2013-05-28 12:52:28 DEBUG: pid 2414: ProcessFrontendResponse: kind from frontend Q(51)
> 2013-05-28 12:52:28 DEBUG: pid 2414: pool_unset_doing_extended_query_message: done
> 2013-05-28 12:52:28 DEBUG: pid 2414: statement2: select 1
> 2013-05-28 12:52:28 DEBUG: pid 2414: pool_set_query_in_progress: done
> 2013-05-28 12:52:28 DEBUG: pid 2414: wait_for_query_response: waiting for backend 0 completing the query
> 2013-05-28 12:52:28 DEBUG: pid 2414: detect_error: kind: T
> 2013-05-28 12:52:28 DEBUG: pid 2414: detect_error: kind: T
> 2013-05-28 12:52:28 DEBUG: pid 2414: detect_error: kind: T
> 2013-05-28 12:52:28 DEBUG: pid 2414: detect_error: kind: T
> 2013-05-28 12:52:28 DEBUG: pid 2414: read_kind_from_backend: kind: T from 0 th backend
> 2013-05-28 12:52:28 DEBUG: pid 2414: read_kind_from_backend: read kind from 0 th backend T NUM_BACKENDS: 2
> 2013-05-28 12:52:28 DEBUG: pid 2414: ProcessBackendResponse: kind from backend: T
> 2013-05-28 12:52:28 DEBUG: pid 2414: read_kind_from_backend: kind: D from 0 th backend
> 2013-05-28 12:52:28 DEBUG: pid 2414: read_kind_from_backend: read kind from 0 th backend D NUM_BACKENDS: 2
> 2013-05-28 12:52:28 DEBUG: pid 2414: ProcessBackendResponse: kind from backend: D
> 2013-05-28 12:52:28 DEBUG: pid 2414: read_kind_from_backend: kind: C from 0 th backend
> 2013-05-28 12:52:28 DEBUG: pid 2414: read_kind_from_backend: read kind from 0 th backend C NUM_BACKENDS: 2
> 2013-05-28 12:52:28 DEBUG: pid 2414: ProcessBackendResponse: kind from backend: C
> 2013-05-28 12:52:28 DEBUG: pid 2414: pool_set_command_success: done
> 2013-05-28 12:52:28 DEBUG: pid 2414: read_kind_from_backend: kind: Z from 0 th backend
> 2013-05-28 12:52:28 DEBUG: pid 2414: read_kind_from_backend: read kind from 0 th backend Z NUM_BACKENDS: 2
> 2013-05-28 12:52:28 DEBUG: pid 2414: ProcessBackendResponse: kind from backend: Z
> 2013-05-28 12:52:28 DEBUG: pid 2414: pool_read_message_length: slot: 0 length: 5
> 2013-05-28 12:52:28 DEBUG: pid 2414: ReadyForQuery: transaction state:I
> 2013-05-28 12:52:28 DEBUG: pid 2414: pool_unset_query_in_progress: done
> 2013-05-28 12:52:28 DEBUG: pid 2414: pool_unset_query_in_progress: done
> 2013-05-28 12:52:28 DEBUG: pid 2414: ProcessBackendResponse: Ready For Query
> 2013-05-28 12:52:28 DEBUG: pid 2414: ProcessFrontendResponse: kind from frontend Q(51)
> 2013-05-28 12:52:28 DEBUG: pid 2414: pool_unset_doing_extended_query_message: done
> 2013-05-28 12:52:28 DEBUG: pid 2414: statement2: SELECT AddGeometryColumn('public','_1ebac66315f34b00a9fcfd0b19bfad69','geom','3857','MULTIPOLYGON',2);
> 2013-05-28 12:52:28 DEBUG: pid 2414: pool_set_query_in_progress: done
> 2013-05-28 12:52:28 DEBUG: pid 2414: function_call_walker: function name: addgeometrycolumn
> 2013-05-28 12:52:28 DEBUG: pid 2414: pattern_compare: black_function_list (^nextval$) not matched: addgeometrycolumn
> 2013-05-28 12:52:28 DEBUG: pid 2414: pattern_compare: black_function_list (^setval$) not matched: addgeometrycolumn
> 2013-05-28 12:52:28 DEBUG: pid 2414: wait_for_query_response: waiting for backend 0 completing the query
> 2013-05-28 12:52:28 DEBUG: pid 2414: detect_error: kind: T
> 2013-05-28 12:52:28 DEBUG: pid 2414: detect_error: kind: T
> 2013-05-28 12:52:28 DEBUG: pid 2414: detect_error: kind: T
> 2013-05-28 12:52:28 DEBUG: pid 2414: detect_error: kind: T
> 2013-05-28 12:52:28 DEBUG: pid 2414: read_kind_from_backend: kind: T from 0 th backend
> 2013-05-28 12:52:28 DEBUG: pid 2414: read_kind_from_backend: read kind from 0 th backend T NUM_BACKENDS: 2
> 2013-05-28 12:52:28 DEBUG: pid 2414: ProcessBackendResponse: kind from backend: T
> 2013-05-28 12:52:28 DEBUG: pid 2414: read_kind_from_backend: kind: D from 0 th backend
> 2013-05-28 12:52:28 DEBUG: pid 2414: read_kind_from_backend: read kind from 0 th backend D NUM_BACKENDS: 2
> 2013-05-28 12:52:28 DEBUG: pid 2414: ProcessBackendResponse: kind from backend: D
> 2013-05-28 12:52:28 DEBUG: pid 2414: read_kind_from_backend: kind: C from 0 th backend
> 2013-05-28 12:52:28 DEBUG: pid 2414: read_kind_from_backend: read kind from 0 th backend C NUM_BACKENDS: 2
> 2013-05-28 12:52:28 DEBUG: pid 2414: ProcessBackendResponse: kind from backend: C
> 2013-05-28 12:52:28 DEBUG: pid 2414: pool_set_command_success: done
> 2013-05-28 12:52:28 DEBUG: pid 2414: read_kind_from_backend: kind: Z from 0 th backend
> 2013-05-28 12:52:28 DEBUG: pid 2414: read_kind_from_backend: read kind from 0 th backend Z NUM_BACKENDS: 2
> 2013-05-28 12:52:28 DEBUG: pid 2414: ProcessBackendResponse: kind from backend: Z
> 2013-05-28 12:52:28 DEBUG: pid 2414: pool_read_message_length: slot: 0 length: 5
> 2013-05-28 12:52:28 DEBUG: pid 2414: ReadyForQuery: transaction state:I
> 2013-05-28 12:52:28 DEBUG: pid 2414: pool_unset_query_in_progress: done
> 2013-05-28 12:52:28 DEBUG: pid 2414: pool_unset_query_in_progress: done
> 2013-05-28 12:52:28 DEBUG: pid 2414: ProcessBackendResponse: Ready For Query
> 2013-05-28 12:52:28 DEBUG: pid 2414: ProcessFrontendResponse: kind from frontend Q(51)
> 2013-05-28 12:52:28 DEBUG: pid 2414: pool_unset_doing_extended_query_message: done
> 2013-05-28 12:52:28 DEBUG: pid 2414: statement2: unlisten *
> 2013-05-28 12:52:28 DEBUG: pid 2414: pool_set_query_in_progress: done
> 2013-05-28 12:52:28 DEBUG: pid 2414: do_command: Query: BEGIN
> 2013-05-28 12:52:28 DEBUG: pid 2414: wait_for_query_response: waiting for backend 0 completing the query
> 2013-05-28 12:52:28 DEBUG: pid 2414: detect_error: kind: C
> 2013-05-28 12:52:28 DEBUG: pid 2414: do_command: kind: C
> 2013-05-28 12:52:28 DEBUG: pid 2414: len:a000000
> 2013-05-28 12:52:28 DEBUG: pid 2414: do_command: kind: Z
> 2013-05-28 12:52:28 DEBUG: pid 2414: do_command: transaction state: T
> 2013-05-28 12:52:28 DEBUG: pid 2414: pool_unset_writing_transaction: done
> 2013-05-28 12:52:28 DEBUG: pid 2414: do_command: Query: BEGIN
> 2013-05-28 12:52:28 DEBUG: pid 2414: wait_for_query_response: waiting for backend 1 completing the query
> 2013-05-28 12:52:28 DEBUG: pid 2414: detect_error: kind: C
> 2013-05-28 12:52:28 DEBUG: pid 2414: do_command: kind: C
> 2013-05-28 12:52:28 DEBUG: pid 2414: len:a000000
> 2013-05-28 12:52:28 DEBUG: pid 2414: do_command: kind: Z
> 2013-05-28 12:52:28 DEBUG: pid 2414: do_command: transaction state: T
> 2013-05-28 12:52:28 DEBUG: pid 2414: pool_unset_writing_transaction: done
> 2013-05-28 12:52:28 DEBUG: pid 2414: wait_for_query_response: waiting for backend 0 completing the query
> 2013-05-28 12:52:28 DEBUG: pid 2414: detect_error: kind: C
> 2013-05-28 12:52:28 DEBUG: pid 2414: detect_error: kind: C
> 2013-05-28 12:52:28 DEBUG: pid 2414: detect_error: kind: C
> 2013-05-28 12:52:28 DEBUG: pid 2414: detect_error: kind: C
> 2013-05-28 12:52:28 DEBUG: pid 2414: wait_for_query_response: waiting for backend 1 completing the query
> 2013-05-28 12:52:28 DEBUG: pid 2414: read_kind_from_backend: kind: C from 0 th backend
> 2013-05-28 12:52:28 DEBUG: pid 2414: read_kind_from_backend: read kind from 0 th backend C NUM_BACKENDS: 2
> 2013-05-28 12:52:28 DEBUG: pid 2414: read_kind_from_backend: kind: C from 1 th backend
> 2013-05-28 12:52:28 DEBUG: pid 2414: read_kind_from_backend: read kind from 1 th backend C NUM_BACKENDS: 2
> 2013-05-28 12:52:28 DEBUG: pid 2414: ProcessBackendResponse: kind from backend: C
> 2013-05-28 12:52:28 DEBUG: pid 2414: pool_set_command_success: done
> 2013-05-28 12:52:28 DEBUG: pid 2414: read_kind_from_backend: kind: Z from 0 th backend
> 2013-05-28 12:52:28 DEBUG: pid 2414: read_kind_from_backend: read kind from 0 th backend Z NUM_BACKENDS: 2
> 2013-05-28 12:52:28 DEBUG: pid 2414: read_kind_from_backend: kind: Z from 1 th backend
> 2013-05-28 12:52:28 DEBUG: pid 2414: read_kind_from_backend: read kind from 1 th backend Z NUM_BACKENDS: 2
> 2013-05-28 12:52:28 DEBUG: pid 2414: ProcessBackendResponse: kind from backend: Z
> 2013-05-28 12:52:28 DEBUG: pid 2414: do_command: Query: COMMIT
> 2013-05-28 12:52:28 DEBUG: pid 2414: wait_for_query_response: waiting for backend 1 completing the query
> 2013-05-28 12:52:28 DEBUG: pid 2414: detect_error: kind: C
> 2013-05-28 12:52:28 DEBUG: pid 2414: do_command: kind: C
> 2013-05-28 12:52:28 DEBUG: pid 2414: len:b000000
> 2013-05-28 12:52:28 DEBUG: pid 2414: do_command: kind: Z
> 2013-05-28 12:52:28 DEBUG: pid 2414: do_command: Query: COMMIT
> 2013-05-28 12:52:28 DEBUG: pid 2414: wait_for_query_response: waiting for backend 0 completing the query
> 2013-05-28 12:52:28 DEBUG: pid 2414: detect_error: kind: C
> 2013-05-28 12:52:28 DEBUG: pid 2414: do_command: kind: C
> 2013-05-28 12:52:28 DEBUG: pid 2414: len:b000000
> 2013-05-28 12:52:28 DEBUG: pid 2414: do_command: kind: Z
> 2013-05-28 12:52:28 DEBUG: pid 2414: pool_read_message_length: slot: 0 length: 5
> 2013-05-28 12:52:28 DEBUG: pid 2414: pool_read_message_length: slot: 1 length: 5
> 2013-05-28 12:52:28 DEBUG: pid 2414: ReadyForQuery: transaction state:T
> 2013-05-28 12:52:28 DEBUG: pid 2414: ReadyForQuery: transaction state:I
> 2013-05-28 12:52:28 DEBUG: pid 2414: pool_unset_query_in_progress: done
> 2013-05-28 12:52:28 DEBUG: pid 2414: pool_unset_query_in_progress: done
> 2013-05-28 12:52:28 DEBUG: pid 2414: ProcessBackendResponse: Ready For Query
> 2013-05-28 12:52:28 DEBUG: pid 2414: ProcessFrontendResponse: kind from frontend Q(51)
> 2013-05-28 12:52:28 DEBUG: pid 2414: pool_unset_doing_extended_query_message: done
> 2013-05-28 12:52:28 DEBUG: pid 2414: statement2: select 1
> 2013-05-28 12:52:28 DEBUG: pid 2414: pool_set_query_in_progress: done
> 2013-05-28 12:52:28 DEBUG: pid 2414: wait_for_query_response: waiting for backend 0 completing the query
> 2013-05-28 12:52:28 DEBUG: pid 2414: detect_error: kind: T
> 2013-05-28 12:52:28 DEBUG: pid 2414: detect_error: kind: T
> 2013-05-28 12:52:28 DEBUG: pid 2414: detect_error: kind: T
> 2013-05-28 12:52:28 DEBUG: pid 2414: detect_error: kind: T
> 2013-05-28 12:52:28 DEBUG: pid 2414: read_kind_from_backend: kind: T from 0 th backend
> 2013-05-28 12:52:28 DEBUG: pid 2414: read_kind_from_backend: read kind from 0 th backend T NUM_BACKENDS: 2
> 2013-05-28 12:52:28 DEBUG: pid 2414: ProcessBackendResponse: kind from backend: T
> 2013-05-28 12:52:28 DEBUG: pid 2414: read_kind_from_backend: kind: D from 0 th backend
> 2013-05-28 12:52:28 DEBUG: pid 2414: read_kind_from_backend: read kind from 0 th backend D NUM_BACKENDS: 2
> 2013-05-28 12:52:28 DEBUG: pid 2414: ProcessBackendResponse: kind from backend: D
> 2013-05-28 12:52:28 DEBUG: pid 2414: read_kind_from_backend: kind: C from 0 th backend
> 2013-05-28 12:52:28 DEBUG: pid 2414: read_kind_from_backend: read kind from 0 th backend C NUM_BACKENDS: 2
> 2013-05-28 12:52:28 DEBUG: pid 2414: ProcessBackendResponse: kind from backend: C
> 2013-05-28 12:52:28 DEBUG: pid 2414: pool_set_command_success: done
> 2013-05-28 12:52:28 DEBUG: pid 2414: read_kind_from_backend: kind: Z from 0 th backend
> 2013-05-28 12:52:28 DEBUG: pid 2414: read_kind_from_backend: read kind from 0 th backend Z NUM_BACKENDS: 2
> 2013-05-28 12:52:28 DEBUG: pid 2414: ProcessBackendResponse: kind from backend: Z
> 2013-05-28 12:52:28 DEBUG: pid 2414: pool_read_message_length: slot: 0 length: 5
> 2013-05-28 12:52:28 DEBUG: pid 2414: ReadyForQuery: transaction state:I
> 2013-05-28 12:52:28 DEBUG: pid 2414: pool_unset_query_in_progress: done
> 2013-05-28 12:52:28 DEBUG: pid 2414: pool_unset_query_in_progress: done
> 2013-05-28 12:52:28 DEBUG: pid 2414: ProcessBackendResponse: Ready For Query
> 2013-05-28 12:52:28 DEBUG: pid 2414: ProcessFrontendResponse: kind from frontend Q(51)
> 2013-05-28 12:52:28 DEBUG: pid 2414: pool_unset_doing_extended_query_message: done
> 2013-05-28 12:52:28 DEBUG: pid 2414: statement2: SELECT AddGeometryColumn('public','_1ebac66315f34b00a9fcfd0b19bfad69','geom_low','3857','MULTIPOLYGON',2);
> 2013-05-28 12:52:28 DEBUG: pid 2414: pool_set_query_in_progress: done
> 2013-05-28 12:52:28 DEBUG: pid 2414: function_call_walker: function name: addgeometrycolumn
> 2013-05-28 12:52:28 DEBUG: pid 2414: pattern_compare: black_function_list (^nextval$) not matched: addgeometrycolumn
> 2013-05-28 12:52:28 DEBUG: pid 2414: pattern_compare: black_function_list (^setval$) not matched: addgeometrycolumn
> 2013-05-28 12:52:28 DEBUG: pid 2414: wait_for_query_response: waiting for backend 0 completing the query
> 2013-05-28 12:52:28 DEBUG: pid 2414: detect_error: kind: T
> 2013-05-28 12:52:28 DEBUG: pid 2414: detect_error: kind: T
> 2013-05-28 12:52:28 DEBUG: pid 2414: detect_error: kind: T
> 2013-05-28 12:52:28 DEBUG: pid 2414: detect_error: kind: T
> 2013-05-28 12:52:28 DEBUG: pid 2414: read_kind_from_backend: kind: T from 0 th backend
> 2013-05-28 12:52:28 DEBUG: pid 2414: read_kind_from_backend: read kind from 0 th backend T NUM_BACKENDS: 2
> 2013-05-28 12:52:28 DEBUG: pid 2414: ProcessBackendResponse: kind from backend: T
> 2013-05-28 12:52:28 DEBUG: pid 2414: read_kind_from_backend: kind: D from 0 th backend
> 2013-05-28 12:52:28 DEBUG: pid 2414: read_kind_from_backend: read kind from 0 th backend D NUM_BACKENDS: 2
> 2013-05-28 12:52:28 DEBUG: pid 2414: ProcessBackendResponse: kind from backend: D
> 2013-05-28 12:52:28 DEBUG: pid 2414: read_kind_from_backend: kind: C from 0 th backend
> 2013-05-28 12:52:28 DEBUG: pid 2414: read_kind_from_backend: read kind from 0 th backend C NUM_BACKENDS: 2
> 2013-05-28 12:52:28 DEBUG: pid 2414: ProcessBackendResponse: kind from backend: C
> 2013-05-28 12:52:28 DEBUG: pid 2414: pool_set_command_success: done
> 2013-05-28 12:52:28 DEBUG: pid 2414: read_kind_from_backend: kind: Z from 0 th backend
> 2013-05-28 12:52:28 DEBUG: pid 2414: read_kind_from_backend: read kind from 0 th backend Z NUM_BACKENDS: 2
> 2013-05-28 12:52:28 DEBUG: pid 2414: ProcessBackendResponse: kind from backend: Z
> 2013-05-28 12:52:28 DEBUG: pid 2414: pool_read_message_length: slot: 0 length: 5
> 2013-05-28 12:52:28 DEBUG: pid 2414: ReadyForQuery: transaction state:I
> 2013-05-28 12:52:28 DEBUG: pid 2414: pool_unset_query_in_progress: done
> 2013-05-28 12:52:28 DEBUG: pid 2414: pool_unset_query_in_progress: done
> 2013-05-28 12:52:28 DEBUG: pid 2414: ProcessBackendResponse: Ready For Query
> 2013-05-28 12:52:28 DEBUG: pid 2414: ProcessFrontendResponse: kind from frontend Q(51)
> 2013-05-28 12:52:28 DEBUG: pid 2414: pool_unset_doing_extended_query_message: done
> 2013-05-28 12:52:28 DEBUG: pid 2414: statement2: unlisten *
> 2013-05-28 12:52:28 DEBUG: pid 2414: pool_set_query_in_progress: done
> 2013-05-28 12:52:28 DEBUG: pid 2414: do_command: Query: BEGIN
> 2013-05-28 12:52:28 DEBUG: pid 2414: wait_for_query_response: waiting for backend 0 completing the query
> 2013-05-28 12:52:28 DEBUG: pid 2414: detect_error: kind: C
> 2013-05-28 12:52:28 DEBUG: pid 2414: do_command: kind: C
> 2013-05-28 12:52:28 DEBUG: pid 2414: len:a000000
> 2013-05-28 12:52:28 DEBUG: pid 2414: do_command: kind: Z
> 2013-05-28 12:52:28 DEBUG: pid 2414: do_command: transaction state: T
> 2013-05-28 12:52:28 DEBUG: pid 2414: pool_unset_writing_transaction: done
> 2013-05-28 12:52:28 DEBUG: pid 2414: do_command: Query: BEGIN
> 2013-05-28 12:52:28 DEBUG: pid 2414: wait_for_query_response: waiting for backend 1 completing the query
> 2013-05-28 12:52:28 DEBUG: pid 2414: detect_error: kind: C
> 2013-05-28 12:52:28 DEBUG: pid 2414: do_command: kind: C
> 2013-05-28 12:52:28 DEBUG: pid 2414: len:a000000
> 2013-05-28 12:52:28 DEBUG: pid 2414: do_command: kind: Z
> 2013-05-28 12:52:28 DEBUG: pid 2414: do_command: transaction state: T
> 2013-05-28 12:52:28 DEBUG: pid 2414: pool_unset_writing_transaction: done
> 2013-05-28 12:52:28 DEBUG: pid 2414: wait_for_query_response: waiting for backend 0 completing the query
> 2013-05-28 12:52:28 DEBUG: pid 2414: detect_error: kind: C
> 2013-05-28 12:52:28 DEBUG: pid 2414: detect_error: kind: C
> 2013-05-28 12:52:28 DEBUG: pid 2414: detect_error: kind: C
> 2013-05-28 12:52:28 DEBUG: pid 2414: detect_error: kind: C
> 2013-05-28 12:52:28 DEBUG: pid 2414: wait_for_query_response: waiting for backend 1 completing the query
> 2013-05-28 12:52:28 DEBUG: pid 2414: read_kind_from_backend: kind: C from 0 th backend
> 2013-05-28 12:52:28 DEBUG: pid 2414: read_kind_from_backend: read kind from 0 th backend C NUM_BACKENDS: 2
> 2013-05-28 12:52:28 DEBUG: pid 2414: read_kind_from_backend: kind: C from 1 th backend
> 2013-05-28 12:52:28 DEBUG: pid 2414: read_kind_from_backend: read kind from 1 th backend C NUM_BACKENDS: 2
> 2013-05-28 12:52:28 DEBUG: pid 2414: ProcessBackendResponse: kind from backend: C
> 2013-05-28 12:52:28 DEBUG: pid 2414: pool_set_command_success: done
> 2013-05-28 12:52:28 DEBUG: pid 2414: read_kind_from_backend: kind: Z from 0 th backend
> 2013-05-28 12:52:28 DEBUG: pid 2414: read_kind_from_backend: read kind from 0 th backend Z NUM_BACKENDS: 2
> 2013-05-28 12:52:28 DEBUG: pid 2414: read_kind_from_backend: kind: Z from 1 th backend
> 2013-05-28 12:52:28 DEBUG: pid 2414: read_kind_from_backend: read kind from 1 th backend Z NUM_BACKENDS: 2
> 2013-05-28 12:52:28 DEBUG: pid 2414: ProcessBackendResponse: kind from backend: Z
> 2013-05-28 12:52:28 DEBUG: pid 2414: do_command: Query: COMMIT
> 2013-05-28 12:52:28 DEBUG: pid 2414: wait_for_query_response: waiting for backend 1 completing the query
> 2013-05-28 12:52:28 DEBUG: pid 2414: detect_error: kind: C
> 2013-05-28 12:52:28 DEBUG: pid 2414: do_command: kind: C
> 2013-05-28 12:52:28 DEBUG: pid 2414: len:b000000
> 2013-05-28 12:52:28 DEBUG: pid 2414: do_command: kind: Z
> 2013-05-28 12:52:28 DEBUG: pid 2414: do_command: Query: COMMIT
> 2013-05-28 12:52:28 DEBUG: pid 2414: wait_for_query_response: waiting for backend 0 completing the query
> 2013-05-28 12:52:28 DEBUG: pid 2414: detect_error: kind: C
> 2013-05-28 12:52:28 DEBUG: pid 2414: do_command: kind: C
> 2013-05-28 12:52:28 DEBUG: pid 2414: len:b000000
> 2013-05-28 12:52:28 DEBUG: pid 2414: do_command: kind: Z
> 2013-05-28 12:52:28 DEBUG: pid 2414: pool_read_message_length: slot: 0 length: 5
> 2013-05-28 12:52:28 DEBUG: pid 2414: pool_read_message_length: slot: 1 length: 5
> 2013-05-28 12:52:28 DEBUG: pid 2414: ReadyForQuery: transaction state:T
> 2013-05-28 12:52:28 DEBUG: pid 2414: ReadyForQuery: transaction state:I
> 2013-05-28 12:52:28 DEBUG: pid 2414: pool_unset_query_in_progress: done
> 2013-05-28 12:52:28 DEBUG: pid 2414: pool_unset_query_in_progress: done
> 2013-05-28 12:52:28 DEBUG: pid 2414: ProcessBackendResponse: Ready For Query
> 2013-05-28 12:52:30 DEBUG: pid 2414: ProcessFrontendResponse: kind from frontend Q(51)
> 2013-05-28 12:52:30 DEBUG: pid 2414: pool_unset_doing_extended_query_message: done
> 2013-05-28 12:52:30 DEBUG: pid 2414: statement2: select 1
> 2013-05-28 12:52:30 DEBUG: pid 2414: pool_set_query_in_progress: done
> 2013-05-28 12:52:30 DEBUG: pid 2414: wait_for_query_response: waiting for backend 0 completing the query
> 2013-05-28 12:52:30 DEBUG: pid 2414: detect_error: kind: T
> 2013-05-28 12:52:30 DEBUG: pid 2414: detect_error: kind: T
> 2013-05-28 12:52:30 DEBUG: pid 2414: detect_error: kind: T
> 2013-05-28 12:52:30 DEBUG: pid 2414: detect_error: kind: T
> 2013-05-28 12:52:30 DEBUG: pid 2414: read_kind_from_backend: kind: T from 0 th backend
> 2013-05-28 12:52:30 DEBUG: pid 2414: read_kind_from_backend: read kind from 0 th backend T NUM_BACKENDS: 2
> 2013-05-28 12:52:30 DEBUG: pid 2414: ProcessBackendResponse: kind from backend: T
> 2013-05-28 12:52:30 DEBUG: pid 2414: read_kind_from_backend: kind: D from 0 th backend
> 2013-05-28 12:52:30 DEBUG: pid 2414: read_kind_from_backend: read kind from 0 th backend D NUM_BACKENDS: 2
> 2013-05-28 12:52:30 DEBUG: pid 2414: ProcessBackendResponse: kind from backend: D
> 2013-05-28 12:52:30 DEBUG: pid 2414: read_kind_from_backend: kind: C from 0 th backend
> 2013-05-28 12:52:30 DEBUG: pid 2414: read_kind_from_backend: read kind from 0 th backend C NUM_BACKENDS: 2
> 2013-05-28 12:52:30 DEBUG: pid 2414: ProcessBackendResponse: kind from backend: C
> 2013-05-28 12:52:30 DEBUG: pid 2414: pool_set_command_success: done
> 2013-05-28 12:52:30 DEBUG: pid 2414: read_kind_from_backend: kind: Z from 0 th backend
> 2013-05-28 12:52:30 DEBUG: pid 2414: read_kind_from_backend: read kind from 0 th backend Z NUM_BACKENDS: 2
> 2013-05-28 12:52:30 DEBUG: pid 2414: ProcessBackendResponse: kind from backend: Z
> 2013-05-28 12:52:30 DEBUG: pid 2414: pool_read_message_length: slot: 0 length: 5
> 2013-05-28 12:52:30 DEBUG: pid 2414: ReadyForQuery: transaction state:I
> 2013-05-28 12:52:30 DEBUG: pid 2414: pool_unset_query_in_progress: done
> 2013-05-28 12:52:30 DEBUG: pid 2414: pool_unset_query_in_progress: done
> 2013-05-28 12:52:30 DEBUG: pid 2414: ProcessBackendResponse: Ready For Query
> 2013-05-28 12:52:30 DEBUG: pid 2414: ProcessFrontendResponse: kind from frontend Q(51)
> 2013-05-28 12:52:30 DEBUG: pid 2414: pool_unset_doing_extended_query_message: done
> 2013-05-28 12:52:30 DEBUG: pid 2414: statement2: insert into _1ebac66315f34b00a9fcfd0b19bfad69  ( SRA,HAZ_CODE,HAZ_CLASS,Shape_Leng,Shape_Area,geom) values  ( 'FRA',2,'High',3.63920547962e+003,1.47780526502e+005, ST_GeomFromWKB(st_multi(decode('AQMAAAABAAAAHwAAAKWSCbtw62jB4EhufEg7TkGVAj2cXOtowcWr/AFHO05BLlKxzVvraMFzC4X0RjtOQaEpMD9662jB1UPX3QM6TkEDlBzQoutowX55VodcOE5BxK3ftKLraME/tVvzazhOQVn3UPyh62jBff7ZcdQ4TkG27El5putowTKMIfLUOE5BPyhnWabraMHmf0oA5zhOQc0rYdaq62jBvTuOgOc4TkEreJ2WqutowRhv6pwLOU5B5YmZE6/raMEdjSodDDlOQVyd19Ou62jBcFiUOTA5TkEJy85ExetowfydmroyOU5BfaUWBcXraMGIoRPXVjlOQW0MF4LJ62jBEmw8V1c5TkFYPXp9yetowXzUgfRZOU5Bv6yoVsnraMHMCgL1bzlOQdrjsPqM62jBoRGC6G45TkHX8ixRjOtowR6SZpMCOk5BHNWuTYzraMFVkPrjAzpOQT++MEqM62jBRaGKNAU6TkE9afhsietowTS2m+IEOk5ByktnzYjraME2nJsqXzpOQX/JkVqL62jBOh+hc186TkGMLuHPiutowSA6Cq+TOk5BmxXnl23raME/sqL7kTpOQSpg4WRt62jBho7wdNI6TkGBi+n6bOtowROfMokSO05B7ldWGnHraMFYnjqaEjtOQaWSCbtw62jB4EhufEg7TkEAAAAA','base64')),3857));
> 2013-05-28 12:52:30 DEBUG: pid 2414: pool_set_query_in_progress: done
> 2013-05-28 12:52:30 DEBUG: pid 2414: do_command: Query: BEGIN
> 2013-05-28 12:52:30 DEBUG: pid 2414: wait_for_query_response: waiting for backend 0 completing the query
> 2013-05-28 12:52:30 DEBUG: pid 2414: detect_error: kind: C
> 2013-05-28 12:52:30 DEBUG: pid 2414: do_command: kind: C
> 2013-05-28 12:52:30 DEBUG: pid 2414: len:a000000
> 2013-05-28 12:52:30 DEBUG: pid 2414: do_command: kind: Z
> 2013-05-28 12:52:30 DEBUG: pid 2414: do_command: transaction state: T
> 2013-05-28 12:52:30 DEBUG: pid 2414: pool_unset_writing_transaction: done
> 2013-05-28 12:52:30 DEBUG: pid 2414: do_command: Query: BEGIN
> 2013-05-28 12:52:30 DEBUG: pid 2414: wait_for_query_response: waiting for backend 1 completing the query
> 2013-05-28 12:52:30 DEBUG: pid 2414: detect_error: kind: C
> 2013-05-28 12:52:30 DEBUG: pid 2414: do_command: kind: C
> 2013-05-28 12:52:30 DEBUG: pid 2414: len:a000000
> 2013-05-28 12:52:30 DEBUG: pid 2414: do_command: kind: Z
> 2013-05-28 12:52:30 DEBUG: pid 2414: do_command: transaction state: T
> 2013-05-28 12:52:30 DEBUG: pid 2414: pool_unset_writing_transaction: done
> 2013-05-28 12:52:30 DEBUG: pid 2414: get_insert_command_table_name: extracted table name: "_1ebac66315f34b00a9fcfd0b19bfad69"
> 2013-05-28 12:52:30 DEBUG: pid 2414: do_query: extended:0 query:SELECT count(*) FROM pg_catalog.pg_attrdef AS d, pg_catalog.pg_class AS c WHERE d.adrelid = c.oid AND d.adsrc ~ 'nextval' AND c.relname = '_1ebac66315f34b00a9fcfd0b19bfad69'
> 2013-05-28 12:52:30 DEBUG: pid 2414: do_query: kind: T
> 2013-05-28 12:52:30 DEBUG: pid 2414: do_query: row description received
> 2013-05-28 12:52:30 DEBUG: pid 2414: num_fileds: 1
> 2013-05-28 12:52:30 DEBUG: pid 2414: do_query: kind: D
> 2013-05-28 12:52:30 DEBUG: pid 2414: do_query: data row received
> 2013-05-28 12:52:30 DEBUG: pid 2414: do_query: kind: C
> 2013-05-28 12:52:30 DEBUG: pid 2414: do_query: Command complete received
> 2013-05-28 12:52:30 DEBUG: pid 2414: do_query: kind: Z
> 2013-05-28 12:52:30 DEBUG: pid 2414: do_query: Ready for query
> 2013-05-28 12:52:30 DEBUG: pid 2414: get_insert_command_table_name: extracted table name: "_1ebac66315f34b00a9fcfd0b19bfad69"
> 2013-05-28 12:52:30 DEBUG: pid 2414: do_command: Query: LOCK TABLE "_1ebac66315f34b00a9fcfd0b19bfad69" IN SHARE ROW EXCLUSIVE MODE
> 2013-05-28 12:52:30 DEBUG: pid 2414: wait_for_query_response: waiting for backend 0 completing the query
> 2013-05-28 12:52:30 DEBUG: pid 2414: detect_error: kind: C
> 2013-05-28 12:52:30 DEBUG: pid 2414: do_command: kind: C
> 2013-05-28 12:52:30 DEBUG: pid 2414: len:f000000
> 2013-05-28 12:52:30 DEBUG: pid 2414: do_command: kind: Z
> 2013-05-28 12:52:30 DEBUG: pid 2414: do_command: transaction state: T
> 2013-05-28 12:52:30 DEBUG: pid 2414: do_command: Query: LOCK TABLE "_1ebac66315f34b00a9fcfd0b19bfad69" IN SHARE ROW EXCLUSIVE MODE
> 2013-05-28 12:52:30 DEBUG: pid 2414: wait_for_query_response: waiting for backend 1 completing the query
> 2013-05-28 12:52:30 DEBUG: pid 2414: detect_error: kind: C
> 2013-05-28 12:52:30 DEBUG: pid 2414: do_command: kind: C
> 2013-05-28 12:52:30 DEBUG: pid 2414: len:f000000
> 2013-05-28 12:52:30 DEBUG: pid 2414: do_command: kind: Z
> 2013-05-28 12:52:30 DEBUG: pid 2414: do_command: transaction state: T
> 2013-05-28 12:52:30 DEBUG: pid 2414: do_query: extended:0 query:SELECT attname, d.adsrc, coalesce((d.adsrc LIKE '%now()%' OR d.adsrc LIKE '%''now''::text%') AND (a.atttypid = 'timestamp'::regtype::oid OR a.atttypid = 'timestamp with time zone'::regtype::oid OR a.atttypid = 'date'::regtype::oid OR a.atttypid = 'time'::regtype::oid OR a.atttypid = 'time with time zone'::regtype::oid) , false) FROM pg_catalog.pg_class c, pg_catalog.pg_attribute a  LEFT JOIN pg_catalog.pg_attrdef d ON (a.attrelid = d.adrelid AND a.attnum = d.adnum) WHERE c.oid = a.attrelid AND a.attnum >= 1 AND a.attisdropped = 'f' AND c.relname = '_1ebac66315f34b00a9fcfd0b19bfad69' ORDER BY a.attnum
> 2013-05-28 12:52:30 DEBUG: pid 2414: do_query: kind: T
> 2013-05-28 12:52:30 DEBUG: pid 2414: do_query: row description received
> 2013-05-28 12:52:30 DEBUG: pid 2414: num_fileds: 3
> 2013-05-28 12:52:30 DEBUG: pid 2414: do_query: kind: D
> 2013-05-28 12:52:30 DEBUG: pid 2414: do_query: data row received
> 2013-05-28 12:52:30 DEBUG: pid 2414: do_query: kind: D
> 2013-05-28 12:52:30 DEBUG: pid 2414: do_query: data row received
> 2013-05-28 12:52:30 DEBUG: pid 2414: do_query: kind: D
> 2013-05-28 12:52:30 DEBUG: pid 2414: do_query: data row received
> 2013-05-28 12:52:30 DEBUG: pid 2414: do_query: kind: D
> 2013-05-28 12:52:30 DEBUG: pid 2414: do_query: data row received
> 2013-05-28 12:52:30 DEBUG: pid 2414: do_query: kind: D
> 2013-05-28 12:52:30 DEBUG: pid 2414: do_query: data row received
> 2013-05-28 12:52:30 DEBUG: pid 2414: do_query: kind: D
> 2013-05-28 12:52:30 DEBUG: pid 2414: do_query: data row received
> 2013-05-28 12:52:30 DEBUG: pid 2414: do_query: kind: D
> 2013-05-28 12:52:30 DEBUG: pid 2414: do_query: data row received
> 2013-05-28 12:52:30 DEBUG: pid 2414: do_query: kind: D
> 2013-05-28 12:52:30 DEBUG: pid 2414: do_query: data row received
> 2013-05-28 12:52:30 DEBUG: pid 2414: do_query: kind: D
> 2013-05-28 12:52:30 DEBUG: pid 2414: do_query: data row received
> 2013-05-28 12:52:30 DEBUG: pid 2414: do_query: kind: D
> 2013-05-28 12:52:30 DEBUG: pid 2414: do_query: data row received
> 2013-05-28 12:52:30 DEBUG: pid 2414: do_query: kind: C
> 2013-05-28 12:52:30 DEBUG: pid 2414: do_query: Command complete received
> 2013-05-28 12:52:30 DEBUG: pid 2414: do_query: kind: Z
> 2013-05-28 12:52:30 DEBUG: pid 2414: do_query: Ready for query
> 2013-05-28 12:52:30 DEBUG: pid 2414: attrname gid adsrc nextval('_1ebac66315f34b00a9fcfd0b19bfad69_gid_seq'::regclass) use_timestamp = 0
> 2013-05-28 12:52:30 DEBUG: pid 2414: attrname sra adsrc NULL use_timestamp = 0
> 2013-05-28 12:52:30 DEBUG: pid 2414: attrname incorp adsrc NULL use_timestamp = 0
> 2013-05-28 12:52:30 DEBUG: pid 2414: attrname haz_code adsrc NULL use_timestamp = 0
> 2013-05-28 12:52:30 DEBUG: pid 2414: attrname haz_class adsrc NULL use_timestamp = 0
> 2013-05-28 12:52:30 DEBUG: pid 2414: attrname vh_rec adsrc NULL use_timestamp = 0
> 2013-05-28 12:52:30 DEBUG: pid 2414: attrname shape_leng adsrc NULL use_timestamp = 0
> 2013-05-28 12:52:30 DEBUG: pid 2414: attrname shape_area adsrc NULL use_timestamp = 0
> 2013-05-28 12:52:30 DEBUG: pid 2414: attrname geom adsrc NULL use_timestamp = 0
> 2013-05-28 12:52:30 DEBUG: pid 2414: attrname geom_low adsrc NULL use_timestamp = 0
> 2013-05-28 12:52:30 DEBUG: pid 2414: wait_for_query_response: waiting for backend 0 completing the query
> 2013-05-28 12:52:30 DEBUG: pid 2414: detect_error: kind: C
> 2013-05-28 12:52:30 DEBUG: pid 2414: detect_error: kind: C
> 2013-05-28 12:52:30 DEBUG: pid 2414: detect_error: kind: C
> 2013-05-28 12:52:30 DEBUG: pid 2414: detect_error: kind: C
> 2013-05-28 12:52:30 DEBUG: pid 2414: wait_for_query_response: waiting for backend 1 completing the query
> 2013-05-28 12:52:30 LOG:   pid 2414: pool_send_and_wait: Error or notice message from backend: : DB node id: 1 backend pid: 14036 statement: insert into _1ebac66315f34b00a9fcfd0b19bfad69  ( SRA,HAZ_CODE,HAZ_CLASS,Shape_Leng,Shape_Area,geom) values  ( 'FRA',2,'High',3.63920547962e+003,1.47780526502e+005,
>  ST_GeomFromWKB(st_multi(decode('AQMAAAABAAAAHwAAAKWSCbtw62jB4EhufEg7TkGVAj2cXOtowcWr/AFHO05BLlKxzVvraMFzC4X0RjtOQaEpMD9662jB1UPX3QM6TkEDlBzQoutowX55VodcOE5BxK3ftKLraME/tVvzazhOQVn3UPyh62jBff7ZcdQ4TkG27El5putowTKMIfLUOE5BPyhnWabraMHmf0oA5zhOQc0rYdaq62jBvTuOgOc4TkEreJ2WqutowRhv6pwLOU5B5YmZE6/raMEdjSodDDlOQVyd19Ou62jBcFiUOTA5TkEJy85ExetowfydmroyOU5BfaUWBcXraMGIoRPXVjlOQW0MF4LJ62jBEmw8V1c5TkFYPXp9yetowXzUgfRZOU5Bv6yoVsnraMHMCgL1bzlOQdrjsPqM62jBoRGC6G45TkHX8ixRjOtowR6SZpMCOk5BHNWuTYzraMFVkPrjAzpOQT++MEqM62jBRaGKNAU6TkE9afhsietowTS2m+IEOk5ByktnzYjraME2nJsqXzpOQX/JkVqL62jBOh+hc186TkGMLuHPiutowSA6Cq+TOk5BmxXnl23raME/sqL7kTpOQSpg4WRt62jBho7wdNI6TkGBi+n6bOtowROfMokSO05B7ldWGnHraMFYnjqaEjtOQaWSCbtw62jB4EhufEg7TkEAAAAA','base64')),3857)); message: column "geom" of relation "_1ebac66315f34b00a9fcfd0b19bfad69" does not exist
> 2013-05-28 12:52:30 DEBUG: pid 2414: read_kind_from_backend: kind: C from 0 th backend
> 2013-05-28 12:52:30 DEBUG: pid 2414: read_kind_from_backend: read kind from 0 th backend C NUM_BACKENDS: 2
> 2013-05-28 12:52:30 DEBUG: pid 2414: read_kind_from_backend: kind: E from 1 th backend
> 2013-05-28 12:52:30 DEBUG: pid 2414: read_kind_from_backend: read kind from 1 th backend E NUM_BACKENDS: 2
> 2013-05-28 12:52:30 ERROR: pid 2414: read_kind_from_backend: 1 th kind E does not match with master or majority connection kind C
> 2013-05-28 12:52:30 ERROR: pid 2414: kind mismatch among backends. Possible last query was: "insert into _1ebac66315f34b00a9fcfd0b19bfad69  ( SRA,HAZ_CODE,HAZ_CLASS,Shape_Leng,Shape_Area,geom) values  ( 'FRA',2,'High',3.63920547962e+003,1.47780526502e+005,
>  ST_GeomFromWKB(st_multi(decode('AQMAAAABAAAAHwAAAKWSCbtw62jB4EhufEg7TkGVAj2cXOtowcWr/AFHO05BLlKxzVvraMFzC4X0RjtOQaEpMD9662jB1UPX3QM6TkEDlBzQoutowX55VodcOE5BxK3ftKLraME/tVvzazhOQVn3UPyh62jBff7ZcdQ4TkG27El5putowTKMIfLUOE5BPyhnWabraMHmf0oA5zhOQc0rYdaq62jBvTuOgOc4TkEreJ2WqutowRhv6pwLOU5B5YmZE6/raMEdjSodDDlOQVyd19Ou62jBcFiUOTA5TkEJy85ExetowfydmroyOU5BfaUWBcXraMGIoRPXVjlOQW0MF4LJ62jBEmw8V1c5TkFYPXp9yetowXzUgfRZOU5Bv6yoVsnraMHMCgL1bzlOQdrjsPqM62jBoRGC6G45TkHX8ixRjOtowR6SZpMCOk5BHNWuTYzraMFVkPrjAzpOQT++MEqM62jBRaGKNAU6TkE9afhsietowTS2m+IEOk5ByktnzYjraME2nJsqXzpOQX/JkVqL62jBOh+hc186TkGMLuHPiutowSA6Cq+TOk5BmxXnl23raME/sqL7kTpOQSpg4WRt62jBho7wdNI6TkGBi+n6bOtowROfMokSO05B7ldWGnHraMFYnjqaEjtOQaWSCbtw62jB4EhufEg7TkEAAAAA','base64')),3857));" kind details are: 0[C] 1[E: column "geom" of relation "_1ebac66315f34b00a9fcfd0b19bfad69" does not exist]
> 2013-05-28 12:52:30 LOG:   pid 2414: do_child: exits with status 1 due to error
> 2013-05-28 12:52:30 DEBUG: pid 2398: reap_handler called
> 2013-05-28 12:52:30 DEBUG: pid 2398: reap_handler: call wait3
> 2013-05-28 12:52:30 DEBUG: pid 2398: child 2414 exits with status 256
> 2013-05-28 12:52:30 DEBUG: pid 2398: fork a new child pid 25761
> 2013-05-28 12:52:30 DEBUG: pid 2398: reap_handler: normally exited
> 2013-05-28 12:52:30 DEBUG: pid 25761: I am 25761
> 2013-05-28 12:52:30 DEBUG: pid 25761: pool_initialize_private_backend_status: initialize backend status
> 2013-05-28 12:52:30 DEBUG: pid 9649: I am 9649 accept fd 6
> 2013-05-28 12:52:30 DEBUG: pid 9649: Protocol Major: 3 Minor: 0 database: postgis_central user: postgres
> 2013-05-28 12:52:30 DEBUG: pid 9649: do_child: connection exists but startup packet length is not identical
> 2013-05-28 12:52:30 DEBUG: pid 9649: new_connection: connecting 0 backend
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://www.sraoss.jp/pipermail/pgpool-general/attachments/20130528/1f9ab215/attachment-0001.html>

More information about the pgpool-general mailing list