2010-10-07 13:39:14 DEBUG: pid 29326: key: listen_addresses
2010-10-07 13:39:14 DEBUG: pid 29326: value: '*' kind: 4
2010-10-07 13:39:14 DEBUG: pid 29326: key: port
2010-10-07 13:39:14 DEBUG: pid 29326: value: 9999 kind: 2
2010-10-07 13:39:14 DEBUG: pid 29326: key: pcp_port
2010-10-07 13:39:14 DEBUG: pid 29326: value: 9898 kind: 2
2010-10-07 13:39:14 DEBUG: pid 29326: key: socket_dir
2010-10-07 13:39:14 DEBUG: pid 29326: value: '/tmp' kind: 4
2010-10-07 13:39:14 DEBUG: pid 29326: key: pcp_socket_dir
2010-10-07 13:39:14 DEBUG: pid 29326: value: '/tmp' kind: 4
2010-10-07 13:39:14 DEBUG: pid 29326: key: backend_socket_dir
2010-10-07 13:39:14 DEBUG: pid 29326: value: '/tmp' kind: 4
2010-10-07 13:39:14 DEBUG: pid 29326: key: pcp_timeout
2010-10-07 13:39:14 DEBUG: pid 29326: value: 10 kind: 2
2010-10-07 13:39:14 DEBUG: pid 29326: key: num_init_children
2010-10-07 13:39:14 DEBUG: pid 29326: value: 32 kind: 2
2010-10-07 13:39:14 DEBUG: pid 29326: key: max_pool
2010-10-07 13:39:14 DEBUG: pid 29326: value: 4 kind: 2
2010-10-07 13:39:14 DEBUG: pid 29326: key: child_life_time
2010-10-07 13:39:14 DEBUG: pid 29326: value: 300 kind: 2
2010-10-07 13:39:14 DEBUG: pid 29326: key: connection_life_time
2010-10-07 13:39:14 DEBUG: pid 29326: value: 0 kind: 2
2010-10-07 13:39:14 DEBUG: pid 29326: key: child_max_connections
2010-10-07 13:39:14 DEBUG: pid 29326: value: 0 kind: 2
2010-10-07 13:39:14 DEBUG: pid 29326: key: client_idle_limit
2010-10-07 13:39:14 DEBUG: pid 29326: value: 0 kind: 2
2010-10-07 13:39:14 DEBUG: pid 29326: key: authentication_timeout
2010-10-07 13:39:14 DEBUG: pid 29326: value: 60 kind: 2
2010-10-07 13:39:14 DEBUG: pid 29326: key: logdir
2010-10-07 13:39:14 DEBUG: pid 29326: value: '/usr/local/pgpool2/logs' kind: 4
2010-10-07 13:39:14 DEBUG: pid 29326: key: pid_file_name
2010-10-07 13:39:14 DEBUG: pid 29326: value: '/usr/local/pgpool2/etc/pgpool.pid' kind: 4
2010-10-07 13:39:14 DEBUG: pid 29326: key: replication_mode
2010-10-07 13:39:14 DEBUG: pid 29326: value: true kind: 1
2010-10-07 13:39:14 DEBUG: pid 29326: key: load_balance_mode
2010-10-07 13:39:14 DEBUG: pid 29326: value: true kind: 1
2010-10-07 13:39:14 DEBUG: pid 29326: key: replication_stop_on_mismatch
2010-10-07 13:39:14 DEBUG: pid 29326: value: false kind: 1
2010-10-07 13:39:14 DEBUG: pid 29326: replication_stop_on_mismatch: 0
2010-10-07 13:39:14 DEBUG: pid 29326: key: failover_if_affected_tuples_mismatch
2010-10-07 13:39:14 DEBUG: pid 29326: value: false kind: 1
2010-10-07 13:39:14 DEBUG: pid 29326: failover_if_affected_tuples_mismatch: 0
2010-10-07 13:39:14 DEBUG: pid 29326: key: replicate_select
2010-10-07 13:39:14 DEBUG: pid 29326: value: false kind: 1
2010-10-07 13:39:14 DEBUG: pid 29326: replicate_select: 0
2010-10-07 13:39:14 DEBUG: pid 29326: key: reset_query_list
2010-10-07 13:39:14 DEBUG: pid 29326: value: 'ABORT; DISCARD ALL' kind: 4
2010-10-07 13:39:14 DEBUG: pid 29326: extract_string_tokens: token: ABORT
2010-10-07 13:39:14 DEBUG: pid 29326: extract_string_tokens: token:  DISCARD ALL
2010-10-07 13:39:14 DEBUG: pid 29326: key: white_function_list
2010-10-07 13:39:14 DEBUG: pid 29326: value: '' kind: 4
2010-10-07 13:39:14 DEBUG: pid 29326: key: black_function_list
2010-10-07 13:39:14 DEBUG: pid 29326: value: 'nextval,setval' kind: 4
2010-10-07 13:39:14 DEBUG: pid 29326: extract_string_tokens: token: nextval
2010-10-07 13:39:14 DEBUG: pid 29326: extract_string_tokens: token: setval
2010-10-07 13:39:14 DEBUG: pid 29326: key: print_timestamp
2010-10-07 13:39:14 DEBUG: pid 29326: value: true kind: 1
2010-10-07 13:39:14 DEBUG: pid 29326: key: master_slave_mode
2010-10-07 13:39:14 DEBUG: pid 29326: value: false kind: 1
2010-10-07 13:39:14 DEBUG: pid 29326: key: master_slave_sub_mode
2010-10-07 13:39:14 DEBUG: pid 29326: value: 'slony' kind: 4
2010-10-07 13:39:14 DEBUG: pid 29326: key: delay_threshold
2010-10-07 13:39:14 DEBUG: pid 29326: value: 0 kind: 2
2010-10-07 13:39:14 DEBUG: pid 29326: key: log_standby_delay
2010-10-07 13:39:14 DEBUG: pid 29326: value: 'none' kind: 4
2010-10-07 13:39:14 DEBUG: pid 29326: key: connection_cache
2010-10-07 13:39:14 DEBUG: pid 29326: value: true kind: 1
2010-10-07 13:39:14 DEBUG: pid 29326: key: health_check_timeout
2010-10-07 13:39:14 DEBUG: pid 29326: value: 20 kind: 2
2010-10-07 13:39:14 DEBUG: pid 29326: key: health_check_period
2010-10-07 13:39:14 DEBUG: pid 29326: value: 0 kind: 2
2010-10-07 13:39:14 DEBUG: pid 29326: key: health_check_user
2010-10-07 13:39:14 DEBUG: pid 29326: value: 'nobody' kind: 4
2010-10-07 13:39:14 DEBUG: pid 29326: key: failover_command
2010-10-07 13:39:14 DEBUG: pid 29326: value: '' kind: 4
2010-10-07 13:39:14 DEBUG: pid 29326: key: failback_command
2010-10-07 13:39:14 DEBUG: pid 29326: value: '' kind: 4
2010-10-07 13:39:14 DEBUG: pid 29326: key: fail_over_on_backend_error
2010-10-07 13:39:14 DEBUG: pid 29326: value: true kind: 1
2010-10-07 13:39:14 DEBUG: pid 29326: key: insert_lock
2010-10-07 13:39:14 DEBUG: pid 29326: value: true kind: 1
2010-10-07 13:39:14 DEBUG: pid 29326: key: ignore_leading_white_space
2010-10-07 13:39:14 DEBUG: pid 29326: value: true kind: 1
2010-10-07 13:39:14 DEBUG: pid 29326: key: log_statement
2010-10-07 13:39:14 DEBUG: pid 29326: value: true kind: 1
2010-10-07 13:39:14 DEBUG: pid 29326: key: log_per_node_statement
2010-10-07 13:39:14 DEBUG: pid 29326: value: true kind: 1
2010-10-07 13:39:14 DEBUG: pid 29326: key: log_connections
2010-10-07 13:39:14 DEBUG: pid 29326: value: true kind: 1
2010-10-07 13:39:14 DEBUG: pid 29326: key: log_hostname
2010-10-07 13:39:14 DEBUG: pid 29326: value: true kind: 1
2010-10-07 13:39:14 DEBUG: pid 29326: key: parallel_mode
2010-10-07 13:39:14 DEBUG: pid 29326: value: false kind: 1
2010-10-07 13:39:14 DEBUG: pid 29326: key: enable_query_cache
2010-10-07 13:39:14 DEBUG: pid 29326: value: false kind: 1
2010-10-07 13:39:14 DEBUG: pid 29326: key: pgpool2_hostname
2010-10-07 13:39:14 DEBUG: pid 29326: value: '' kind: 4
2010-10-07 13:39:14 DEBUG: pid 29326: key: system_db_hostname
2010-10-07 13:39:14 DEBUG: pid 29326: value: 'localhost' kind: 4
2010-10-07 13:39:14 DEBUG: pid 29326: key: system_db_port
2010-10-07 13:39:14 DEBUG: pid 29326: value: 5432 kind: 2
2010-10-07 13:39:14 DEBUG: pid 29326: key: system_db_dbname
2010-10-07 13:39:14 DEBUG: pid 29326: value: 'pgpool' kind: 4
2010-10-07 13:39:14 DEBUG: pid 29326: key: system_db_schema
2010-10-07 13:39:14 DEBUG: pid 29326: value: 'pgpool_catalog' kind: 4
2010-10-07 13:39:14 DEBUG: pid 29326: key: system_db_user
2010-10-07 13:39:14 DEBUG: pid 29326: value: 'pgpool' kind: 4
2010-10-07 13:39:14 DEBUG: pid 29326: key: system_db_password
2010-10-07 13:39:14 DEBUG: pid 29326: value: '' kind: 4
2010-10-07 13:39:14 DEBUG: pid 29326: key: backend_hostname0
2010-10-07 13:39:14 DEBUG: pid 29326: value: 'localhost' kind: 4
2010-10-07 13:39:14 DEBUG: pid 29326: key: backend_port0
2010-10-07 13:39:14 DEBUG: pid 29326: value: 5432 kind: 2
2010-10-07 13:39:14 DEBUG: pid 29326: pool_config: port slot number 0 
2010-10-07 13:39:14 DEBUG: pid 29326: key: backend_weight0
2010-10-07 13:39:14 DEBUG: pid 29326: value: 1 kind: 2
2010-10-07 13:39:14 DEBUG: pid 29326: pool_config: weight slot number 0 weight: 1.000000
2010-10-07 13:39:14 DEBUG: pid 29326: key: backend_data_directory0
2010-10-07 13:39:14 DEBUG: pid 29326: value: '/usr/local/pgsql/data' kind: 4
2010-10-07 13:39:14 DEBUG: pid 29326: key: enable_pool_hba
2010-10-07 13:39:14 DEBUG: pid 29326: value: false kind: 1
2010-10-07 13:39:14 DEBUG: pid 29326: key: recovery_user
2010-10-07 13:39:14 DEBUG: pid 29326: value: 'nobody' kind: 4
2010-10-07 13:39:14 DEBUG: pid 29326: key: recovery_password
2010-10-07 13:39:14 DEBUG: pid 29326: value: '' kind: 4
2010-10-07 13:39:14 DEBUG: pid 29326: key: recovery_1st_stage_command
2010-10-07 13:39:14 DEBUG: pid 29326: value: '' kind: 4
2010-10-07 13:39:14 DEBUG: pid 29326: key: recovery_2nd_stage_command
2010-10-07 13:39:14 DEBUG: pid 29326: value: '' kind: 4
2010-10-07 13:39:14 DEBUG: pid 29326: key: recovery_timeout
2010-10-07 13:39:14 DEBUG: pid 29326: value: 90 kind: 2
2010-10-07 13:39:14 DEBUG: pid 29326: key: client_idle_limit_in_recovery
2010-10-07 13:39:14 DEBUG: pid 29326: value: 0 kind: 2
2010-10-07 13:39:14 DEBUG: pid 29326: key: lobj_lock_table
2010-10-07 13:39:14 DEBUG: pid 29326: value: '' kind: 4
2010-10-07 13:39:14 DEBUG: pid 29326: key: ssl
2010-10-07 13:39:14 DEBUG: pid 29326: value: false kind: 1
2010-10-07 13:39:14 DEBUG: pid 29326: key: debug_level
2010-10-07 13:39:14 DEBUG: pid 29326: value: 1 kind: 2
2010-10-07 13:39:14 DEBUG: pid 29326: num_backends: 1 total_weight: 1.000000
2010-10-07 13:39:14 DEBUG: pid 29326: backend 0 weight: 2147483647.000000
2010-10-07 13:39:14 LOG:   pid 29326: Backend status file /usr/local/pgpool2/logs/pgpool_status does not exist
2010-10-07 13:39:14 DEBUG: pid 29328: I am 29328
2010-10-07 13:39:14 DEBUG: pid 29329: I am 29329
2010-10-07 13:39:14 DEBUG: pid 29330: I am 29330
2010-10-07 13:39:14 DEBUG: pid 29331: I am 29331
2010-10-07 13:39:14 DEBUG: pid 29332: I am 29332
2010-10-07 13:39:14 DEBUG: pid 29333: I am 29333
2010-10-07 13:39:14 DEBUG: pid 29334: I am 29334
2010-10-07 13:39:14 DEBUG: pid 29336: I am 29336
2010-10-07 13:39:14 DEBUG: pid 29335: I am 29335
2010-10-07 13:39:14 DEBUG: pid 29337: I am 29337
2010-10-07 13:39:14 DEBUG: pid 29338: I am 29338
2010-10-07 13:39:14 DEBUG: pid 29339: I am 29339
2010-10-07 13:39:14 DEBUG: pid 29340: I am 29340
2010-10-07 13:39:14 DEBUG: pid 29341: I am 29341
2010-10-07 13:39:14 DEBUG: pid 29342: I am 29342
2010-10-07 13:39:14 DEBUG: pid 29343: I am 29343
2010-10-07 13:39:14 DEBUG: pid 29344: I am 29344
2010-10-07 13:39:14 DEBUG: pid 29345: I am 29345
2010-10-07 13:39:14 DEBUG: pid 29346: I am 29346
2010-10-07 13:39:14 DEBUG: pid 29347: I am 29347
2010-10-07 13:39:14 DEBUG: pid 29348: I am 29348
2010-10-07 13:39:14 DEBUG: pid 29349: I am 29349
2010-10-07 13:39:14 DEBUG: pid 29350: I am 29350
2010-10-07 13:39:14 DEBUG: pid 29351: I am 29351
2010-10-07 13:39:14 DEBUG: pid 29352: I am 29352
2010-10-07 13:39:14 DEBUG: pid 29353: I am 29353
2010-10-07 13:39:14 DEBUG: pid 29354: I am 29354
2010-10-07 13:39:14 DEBUG: pid 29355: I am 29355
2010-10-07 13:39:14 DEBUG: pid 29356: I am 29356
2010-10-07 13:39:14 DEBUG: pid 29357: I am 29357
2010-10-07 13:39:14 DEBUG: pid 29358: I am 29358
2010-10-07 13:39:14 DEBUG: pid 29359: I am 29359
2010-10-07 13:39:14 LOG:   pid 29326: pgpool-II successfully started. version 3.0 (umiyameboshi)
2010-10-07 13:39:14 DEBUG: pid 29361: I am 29361
2010-10-07 13:39:41 DEBUG: pid 29359: I am 29359 accept fd 6
2010-10-07 13:39:41 LOG:   pid 29359: connection received: host=[local]
2010-10-07 13:39:41 DEBUG: pid 29359: Protocol Major: 3 Minor: 0 database: postgres user: postgres
2010-10-07 13:39:41 DEBUG: pid 29359: new_connection: connecting 0 backend
2010-10-07 13:39:41 DEBUG: pid 29359: pool_ssl: SSL requested but SSL support is not available
2010-10-07 13:39:41 DEBUG: pid 29359: pool_read_message_length: slot: 0 length: 8
2010-10-07 13:39:41 DEBUG: pid 29359: pool_read_message_length2: master slot: 0 length: 25
2010-10-07 13:39:41 DEBUG: pid 29359: 0 th backend: name: client_encoding value: UTF8
2010-10-07 13:39:41 DEBUG: pid 29359: pool_read_message_length2: master slot: 0 length: 23
2010-10-07 13:39:41 DEBUG: pid 29359: 0 th backend: name: DateStyle value: ISO, YMD
2010-10-07 13:39:41 DEBUG: pid 29359: pool_read_message_length2: master slot: 0 length: 25
2010-10-07 13:39:41 DEBUG: pid 29359: 0 th backend: name: integer_datetimes value: on
2010-10-07 13:39:41 DEBUG: pid 29359: pool_read_message_length2: master slot: 0 length: 27
2010-10-07 13:39:41 DEBUG: pid 29359: 0 th backend: name: IntervalStyle value: postgres
2010-10-07 13:39:41 DEBUG: pid 29359: pool_read_message_length2: master slot: 0 length: 20
2010-10-07 13:39:41 DEBUG: pid 29359: 0 th backend: name: is_superuser value: on
2010-10-07 13:39:41 DEBUG: pid 29359: pool_read_message_length2: master slot: 0 length: 25
2010-10-07 13:39:41 DEBUG: pid 29359: 0 th backend: name: server_encoding value: UTF8
2010-10-07 13:39:41 DEBUG: pid 29359: pool_read_message_length2: master slot: 0 length: 25
2010-10-07 13:39:41 DEBUG: pid 29359: 0 th backend: name: server_version value: 8.4.2
2010-10-07 13:39:41 DEBUG: pid 29359: pool_read_message_length2: master slot: 0 length: 35
2010-10-07 13:39:41 DEBUG: pid 29359: 0 th backend: name: session_authorization value: postgres
2010-10-07 13:39:41 DEBUG: pid 29359: pool_read_message_length2: master slot: 0 length: 36
2010-10-07 13:39:41 DEBUG: pid 29359: 0 th backend: name: standard_conforming_strings value: off
2010-10-07 13:39:41 DEBUG: pid 29359: pool_read_message_length2: master slot: 0 length: 19
2010-10-07 13:39:41 DEBUG: pid 29359: 0 th backend: name: TimeZone value: Japan
2010-10-07 13:39:41 DEBUG: pid 29359: pool_read_message_length: slot: 0 length: 12
2010-10-07 13:39:41 DEBUG: pid 29359: pool_do_auth: cp->info[i]:0xb7ee1000 pid:29396
2010-10-07 13:39:41 DEBUG: pid 29359: pool_send_auth_ok: send pid 29396 to frontend
2010-10-07 13:39:41 DEBUG: pid 29359: select_load_balancing_node: selected backend id is 0
2010-10-07 13:39:41 DEBUG: pid 29359: selected load balancing node: 0
2010-10-07 13:39:41 DEBUG: pid 29359: pool_unset_query_in_progress: done
2010-10-07 13:39:41 DEBUG: pid 29359: pool_unset_skip_reading_from_backends: done
2010-10-07 13:39:41 DEBUG: pid 29359: pool_unset_ignore_till_sync: done
2010-10-07 13:39:41 DEBUG: pid 29359: read_kind_from_backend: read kind from 0 th backend Z NUM_BACKENDS: 1
2010-10-07 13:39:41 DEBUG: pid 29359: ProcessBackendResponse: kind from backend: Z
2010-10-07 13:39:41 DEBUG: pid 29359: pool_read_message_length: slot: 0 length: 5
2010-10-07 13:39:55 DEBUG: pid 29359: ProcessFrontendResponse: kind from frontend Q(51)
2010-10-07 13:39:55 DEBUG: pid 29359: pool_unset_doing_extended_query_message: done
2010-10-07 13:39:55 LOG:   pid 29359: statement: show pool_version;
2010-10-07 13:39:55 DEBUG: pid 29359: pool_set_query_in_progress: done
2010-10-07 13:39:55 DEBUG: pid 29359: version reporting
2010-10-07 13:39:55 DEBUG: pid 29359: pool_unset_query_in_progress: done
2010-10-07 13:39:55 DEBUG: pid 29359: pool_set_skip_reading_from_backends: done
2010-10-07 13:39:55 DEBUG: pid 29359: pool_unset_skip_reading_from_backends: done
2010-10-07 13:40:06 DEBUG: pid 29359: ProcessFrontendResponse: kind from frontend Q(51)
2010-10-07 13:40:06 DEBUG: pid 29359: pool_unset_doing_extended_query_message: done
2010-10-07 13:40:06 LOG:   pid 29359: statement: show pool_status;
2010-10-07 13:40:06 DEBUG: pid 29359: pool_set_query_in_progress: done
2010-10-07 13:40:06 DEBUG: pid 29359: config reporting
2010-10-07 13:40:06 DEBUG: pid 29359: pool_unset_query_in_progress: done
2010-10-07 13:40:06 DEBUG: pid 29359: pool_set_skip_reading_from_backends: done
2010-10-07 13:40:06 DEBUG: pid 29359: pool_unset_skip_reading_from_backends: done
2010-10-07 13:40:19 DEBUG: pid 29358: I am 29358 accept fd 6
2010-10-07 13:40:19 LOG:   pid 29358: connection received: host=wcyh01c192 port=1808
2010-10-07 13:40:19 DEBUG: pid 29358: Protocol Major: 3 Minor: 0 database: toybox-bcas user: postgres
2010-10-07 13:40:19 DEBUG: pid 29358: new_connection: connecting 0 backend
2010-10-07 13:40:19 DEBUG: pid 29358: pool_ssl: SSL requested but SSL support is not available
2010-10-07 13:40:19 DEBUG: pid 29358: pool_read_message_length: slot: 0 length: 8
2010-10-07 13:40:19 DEBUG: pid 29358: pool_read_message_length2: master slot: 0 length: 28
2010-10-07 13:40:19 DEBUG: pid 29358: 0 th backend: name: client_encoding value: UNICODE
2010-10-07 13:40:19 DEBUG: pid 29358: pool_read_message_length2: master slot: 0 length: 23
2010-10-07 13:40:19 DEBUG: pid 29358: 0 th backend: name: DateStyle value: ISO, YMD
2010-10-07 13:40:19 DEBUG: pid 29358: pool_read_message_length2: master slot: 0 length: 25
2010-10-07 13:40:19 DEBUG: pid 29358: 0 th backend: name: integer_datetimes value: on
2010-10-07 13:40:19 DEBUG: pid 29358: pool_read_message_length2: master slot: 0 length: 27
2010-10-07 13:40:19 DEBUG: pid 29358: 0 th backend: name: IntervalStyle value: postgres
2010-10-07 13:40:19 DEBUG: pid 29358: pool_read_message_length2: master slot: 0 length: 20
2010-10-07 13:40:19 DEBUG: pid 29358: 0 th backend: name: is_superuser value: on
2010-10-07 13:40:19 DEBUG: pid 29358: pool_read_message_length2: master slot: 0 length: 25
2010-10-07 13:40:19 DEBUG: pid 29358: 0 th backend: name: server_encoding value: UTF8
2010-10-07 13:40:19 DEBUG: pid 29358: pool_read_message_length2: master slot: 0 length: 25
2010-10-07 13:40:19 DEBUG: pid 29358: 0 th backend: name: server_version value: 8.4.2
2010-10-07 13:40:19 DEBUG: pid 29358: pool_read_message_length2: master slot: 0 length: 35
2010-10-07 13:40:19 DEBUG: pid 29358: 0 th backend: name: session_authorization value: postgres
2010-10-07 13:40:19 DEBUG: pid 29358: pool_read_message_length2: master slot: 0 length: 36
2010-10-07 13:40:19 DEBUG: pid 29358: 0 th backend: name: standard_conforming_strings value: off
2010-10-07 13:40:19 DEBUG: pid 29358: pool_read_message_length2: master slot: 0 length: 19
2010-10-07 13:40:19 DEBUG: pid 29358: 0 th backend: name: TimeZone value: Japan
2010-10-07 13:40:19 DEBUG: pid 29358: pool_read_message_length: slot: 0 length: 12
2010-10-07 13:40:19 DEBUG: pid 29358: pool_do_auth: cp->info[i]:0xb7ed1000 pid:29402
2010-10-07 13:40:19 DEBUG: pid 29358: pool_send_auth_ok: send pid 29402 to frontend
2010-10-07 13:40:19 DEBUG: pid 29358: select_load_balancing_node: selected backend id is 0
2010-10-07 13:40:19 DEBUG: pid 29358: selected load balancing node: 0
2010-10-07 13:40:19 DEBUG: pid 29358: pool_unset_query_in_progress: done
2010-10-07 13:40:19 DEBUG: pid 29358: pool_unset_skip_reading_from_backends: done
2010-10-07 13:40:19 DEBUG: pid 29358: pool_unset_ignore_till_sync: done
2010-10-07 13:40:19 DEBUG: pid 29358: read_kind_from_backend: read kind from 0 th backend Z NUM_BACKENDS: 1
2010-10-07 13:40:19 DEBUG: pid 29358: ProcessBackendResponse: kind from backend: Z
2010-10-07 13:40:19 DEBUG: pid 29358: pool_read_message_length: slot: 0 length: 5
2010-10-07 13:40:19 DEBUG: pid 29358: ProcessFrontendResponse: kind from frontend P(50)
2010-10-07 13:40:19 DEBUG: pid 29358: pool_unset_doing_extended_query_message: done
2010-10-07 13:40:19 DEBUG: pid 29358: pool_set_doing_extended_query_message: done
2010-10-07 13:40:19 DEBUG: pid 29358: pool_set_query_in_progress: done
2010-10-07 13:40:19 DEBUG: pid 29358: Parse: statement name <S_1>
2010-10-07 13:40:19 DEBUG: pid 29358: pool_set_query_in_progress: done
2010-10-07 13:40:19 DEBUG: pid 29358: pool_read_message_length: slot: 0 length: 5
2010-10-07 13:40:19 DEBUG: pid 29358: Parse: waiting for master completing the query
2010-10-07 13:40:19 LOG:   pid 29358: DB node id: 0 backend pid: 29402 statement: S_1
2010-10-07 13:40:19 DEBUG: pid 29358: wait_for_query_response: waiting for backend 0 completing the query
2010-10-07 13:40:19 DEBUG: pid 29358: detect_error: kind: 1
2010-10-07 13:40:19 DEBUG: pid 29358: read_kind_from_backend: read kind from 0 th backend 1 NUM_BACKENDS: 1
2010-10-07 13:40:19 DEBUG: pid 29358: ProcessBackendResponse: kind from backend: 1
2010-10-07 13:40:19 DEBUG: pid 29358: pool_unset_query_in_progress: done
2010-10-07 13:40:19 DEBUG: pid 29358: ProcessFrontendResponse: kind from frontend B(42)
2010-10-07 13:40:19 DEBUG: pid 29358: pool_unset_doing_extended_query_message: done
2010-10-07 13:40:19 DEBUG: pid 29358: pool_set_doing_extended_query_message: done
2010-10-07 13:40:19 DEBUG: pid 29358: pool_set_query_in_progress: done
2010-10-07 13:40:19 DEBUG: pid 29358: Bind: waiting for master completing the query
2010-10-07 13:40:19 LOG:   pid 29358: DB node id: 0 backend pid: 29402 statement: 
2010-10-07 13:40:19 DEBUG: pid 29358: wait_for_query_response: waiting for backend 0 completing the query
2010-10-07 13:40:19 DEBUG: pid 29358: read_kind_from_backend: read kind from 0 th backend 2 NUM_BACKENDS: 1
2010-10-07 13:40:19 DEBUG: pid 29358: ProcessBackendResponse: kind from backend: 2
2010-10-07 13:40:19 DEBUG: pid 29358: pool_unset_query_in_progress: done
2010-10-07 13:40:19 DEBUG: pid 29358: ProcessFrontendResponse: kind from frontend E(45)
2010-10-07 13:40:19 DEBUG: pid 29358: pool_unset_doing_extended_query_message: done
2010-10-07 13:40:19 DEBUG: pid 29358: pool_set_doing_extended_query_message: done
2010-10-07 13:40:19 DEBUG: pid 29358: pool_set_query_in_progress: done
2010-10-07 13:40:19 DEBUG: pid 29358: Execute: portal name <>
2010-10-07 13:40:19 DEBUG: pid 29358: Execute: query: BEGIN
2010-10-07 13:40:19 LOG:   pid 29358: DB node id: 0 backend pid: 29402 statement: 
2010-10-07 13:40:19 DEBUG: pid 29358: wait_for_query_response: waiting for backend 0 completing the query
2010-10-07 13:40:19 DEBUG: pid 29358: detect_error: kind: C
2010-10-07 13:40:19 DEBUG: pid 29358: detect_error: kind: C
2010-10-07 13:40:19 DEBUG: pid 29358: detect_error: kind: C
2010-10-07 13:40:19 DEBUG: pid 29358: detect_error: kind: C
2010-10-07 13:40:19 DEBUG: pid 29358: read_kind_from_backend: read kind from 0 th backend C NUM_BACKENDS: 1
2010-10-07 13:40:19 DEBUG: pid 29358: ProcessBackendResponse: kind from backend: C
2010-10-07 13:40:19 DEBUG: pid 29358: pool_unset_query_in_progress: done
2010-10-07 13:40:19 DEBUG: pid 29358: ProcessFrontendResponse: kind from frontend P(50)
2010-10-07 13:40:19 DEBUG: pid 29358: pool_unset_doing_extended_query_message: done
2010-10-07 13:40:19 DEBUG: pid 29358: pool_set_doing_extended_query_message: done
2010-10-07 13:40:19 DEBUG: pid 29358: pool_set_query_in_progress: done
2010-10-07 13:40:19 DEBUG: pid 29358: Parse: statement name <S_2>
2010-10-07 13:40:19 DEBUG: pid 29358: pool_set_query_in_progress: done
2010-10-07 13:40:19 DEBUG: pid 29358: Parse: waiting for master completing the query
2010-10-07 13:40:19 LOG:   pid 29358: DB node id: 0 backend pid: 29402 statement: S_2
2010-10-07 13:40:19 DEBUG: pid 29358: wait_for_query_response: waiting for backend 0 completing the query
2010-10-07 13:40:19 DEBUG: pid 29358: detect_error: kind: 1
2010-10-07 13:40:19 DEBUG: pid 29358: read_kind_from_backend: read kind from 0 th backend 1 NUM_BACKENDS: 1
2010-10-07 13:40:19 DEBUG: pid 29358: ProcessBackendResponse: kind from backend: 1
2010-10-07 13:40:19 DEBUG: pid 29358: pool_unset_query_in_progress: done
2010-10-07 13:40:19 DEBUG: pid 29358: ProcessFrontendResponse: kind from frontend B(42)
2010-10-07 13:40:19 DEBUG: pid 29358: pool_unset_doing_extended_query_message: done
2010-10-07 13:40:19 DEBUG: pid 29358: pool_set_doing_extended_query_message: done
2010-10-07 13:40:19 DEBUG: pid 29358: pool_set_query_in_progress: done
2010-10-07 13:40:19 DEBUG: pid 29358: Bind: waiting for master completing the query
2010-10-07 13:40:19 LOG:   pid 29358: DB node id: 0 backend pid: 29402 statement: C_3
2010-10-07 13:40:19 DEBUG: pid 29358: wait_for_query_response: waiting for backend 0 completing the query
2010-10-07 13:40:19 DEBUG: pid 29358: read_kind_from_backend: read kind from 0 th backend 2 NUM_BACKENDS: 1
2010-10-07 13:40:19 DEBUG: pid 29358: ProcessBackendResponse: kind from backend: 2
2010-10-07 13:40:19 DEBUG: pid 29358: pool_unset_query_in_progress: done
2010-10-07 13:40:19 DEBUG: pid 29358: ProcessFrontendResponse: kind from frontend D(44)
2010-10-07 13:40:19 DEBUG: pid 29358: pool_unset_doing_extended_query_message: done
2010-10-07 13:40:19 DEBUG: pid 29358: pool_set_doing_extended_query_message: done
2010-10-07 13:40:19 DEBUG: pid 29358: pool_set_query_in_progress: done
2010-10-07 13:40:19 DEBUG: pid 29358: Describe: waiting for master completing the query
2010-10-07 13:40:19 LOG:   pid 29358: DB node id: 0 backend pid: 29402 statement: PC_3
2010-10-07 13:40:19 DEBUG: pid 29358: wait_for_query_response: waiting for backend 0 completing the query
2010-10-07 13:40:19 DEBUG: pid 29358: read_kind_from_backend: read kind from 0 th backend T NUM_BACKENDS: 1
2010-10-07 13:40:19 DEBUG: pid 29358: ProcessBackendResponse: kind from backend: T
2010-10-07 13:40:19 DEBUG: pid 29358: pool_unset_query_in_progress: done
2010-10-07 13:40:19 DEBUG: pid 29358: ProcessFrontendResponse: kind from frontend E(45)
2010-10-07 13:40:19 DEBUG: pid 29358: pool_unset_doing_extended_query_message: done
2010-10-07 13:40:19 DEBUG: pid 29358: pool_set_doing_extended_query_message: done
2010-10-07 13:40:19 DEBUG: pid 29358: pool_set_query_in_progress: done
2010-10-07 13:40:19 DEBUG: pid 29358: Execute: portal name <C_3>
2010-10-07 13:40:19 DEBUG: pid 29358: Execute: query: select nextval('HandleHistory_handleID_seq') as handleID
2010-10-07 13:40:19 LOG:   pid 29358: DB node id: 0 backend pid: 29402 statement: C_3
2010-10-07 13:40:19 DEBUG: pid 29358: wait_for_query_response: waiting for backend 0 completing the query
2010-10-07 13:40:19 DEBUG: pid 29358: detect_error: kind: D
2010-10-07 13:40:19 DEBUG: pid 29358: detect_error: kind: D
2010-10-07 13:40:19 DEBUG: pid 29358: detect_error: kind: D
2010-10-07 13:40:19 DEBUG: pid 29358: detect_error: kind: D
2010-10-07 13:40:19 DEBUG: pid 29358: read_kind_from_backend: read kind from 0 th backend D NUM_BACKENDS: 1
2010-10-07 13:40:19 DEBUG: pid 29358: ProcessBackendResponse: kind from backend: D
2010-10-07 13:40:19 DEBUG: pid 29358: read_kind_from_backend: read kind from 0 th backend C NUM_BACKENDS: 1
2010-10-07 13:40:19 DEBUG: pid 29358: ProcessBackendResponse: kind from backend: C
2010-10-07 13:40:19 DEBUG: pid 29358: pool_unset_query_in_progress: done
2010-10-07 13:40:19 DEBUG: pid 29358: ProcessFrontendResponse: kind from frontend S(53)
2010-10-07 13:40:19 DEBUG: pid 29358: pool_unset_doing_extended_query_message: done
2010-10-07 13:40:19 DEBUG: pid 29358: pool_set_doing_extended_query_message: done
2010-10-07 13:40:19 DEBUG: pid 29358: pool_set_query_in_progress: done
2010-10-07 13:40:19 DEBUG: pid 29358: read_kind_from_backend: read kind from 0 th backend Z NUM_BACKENDS: 1
2010-10-07 13:40:19 DEBUG: pid 29358: ProcessBackendResponse: kind from backend: Z
2010-10-07 13:40:19 DEBUG: pid 29358: pool_read_message_length: slot: 0 length: 5
2010-10-07 13:40:19 DEBUG: pid 29358: pool_unset_query_in_progress: done
2010-10-07 13:40:19 DEBUG: pid 29358: ProcessFrontendResponse: kind from frontend C(43)
2010-10-07 13:40:19 DEBUG: pid 29358: pool_unset_doing_extended_query_message: done
2010-10-07 13:40:19 DEBUG: pid 29358: pool_set_query_in_progress: done
2010-10-07 13:40:19 DEBUG: pid 29358: Close: waiting for master completing the query
2010-10-07 13:40:19 LOG:   pid 29358: DB node id: 0 backend pid: 29402 statement: SS_2
2010-10-07 13:40:19 DEBUG: pid 29358: wait_for_query_response: waiting for backend 0 completing the query
2010-10-07 13:40:19 DEBUG: pid 29358: read_kind_from_backend: read kind from 0 th backend 3 NUM_BACKENDS: 1
2010-10-07 13:40:19 DEBUG: pid 29358: ProcessBackendResponse: kind from backend: 3
2010-10-07 13:40:19 DEBUG: pid 29358: pool_unset_query_in_progress: done
2010-10-07 13:40:19 DEBUG: pid 29358: pool_set_query_in_progress: done
2010-10-07 13:40:19 DEBUG: pid 29358: pool_unset_query_in_progress: done
2010-10-07 13:40:19 DEBUG: pid 29358: ProcessFrontendResponse: kind from frontend C(43)
2010-10-07 13:40:19 DEBUG: pid 29358: pool_unset_doing_extended_query_message: done
2010-10-07 13:40:19 DEBUG: pid 29358: pool_set_query_in_progress: done
2010-10-07 13:40:19 ERROR: pid 29358: Close: cannot get portal
2010-10-07 13:40:19 LOG:   pid 29358: do_child: exits with status 1 due to error
2010-10-07 13:40:19 DEBUG: pid 29326: reap_handler called
2010-10-07 13:40:19 DEBUG: pid 29326: reap_handler: call wait3
2010-10-07 13:40:19 DEBUG: pid 29326: child 29358 exits with status 256
2010-10-07 13:40:19 DEBUG: pid 29403: I am 29403
2010-10-07 13:40:19 DEBUG: pid 29326: fork a new child pid 29403
2010-10-07 13:40:19 DEBUG: pid 29326: reap_handler: normally exited