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

Tatsuo Ishii ishii at postgresql.org
Wed May 29 10:11:58 JST 2013 

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

More information about the pgpool-general mailing list