Skip to content

Instantly share code, notes, and snippets.

@rhossi

rhossi/pgpool log Secret

Created November 20, 2016 14:55
Show Gist options
  • Star 0 You must be signed in to star a gist
  • Fork 0 You must be signed in to fork a gist
  • Save rhossi/acb85d5d578484c38bf9baccf2182213 to your computer and use it in GitHub Desktop.
Save rhossi/acb85d5d578484c38bf9baccf2182213 to your computer and use it in GitHub Desktop.
Download ZIP
Raw
pgpool log
[ec2-user@ip-172-31-18-217 ~]$ pgpool -n -d
2016-11-20 14:55:31: pid 20828: DEBUG: loading hba configuration
2016-11-20 14:55:31: pid 20828: DETAIL: loading file :"/usr/local/etc/pool_hba.conf" for client authentication configuration file
2016-11-20 14:55:31: pid 20828: DEBUG: pool_coninfo_size: num_init_children (10) * max_pool (4) * MAX_NUM_BACKENDS (128) * sizeof(ConnectionInfo) (136) = 696320 bytes requested for shared memory
2016-11-20 14:55:31: pid 20828: DEBUG: ProcessInfo: num_init_children (10) * sizeof(ProcessInfo) (32) = 320 bytes requested for shared memory
2016-11-20 14:55:31: pid 20828: DEBUG: Request info are: sizeof(POOL_REQUEST_INFO) 5224 bytes requested for shared memory
2016-11-20 14:55:31: pid 20828: DEBUG: Recovery management area: sizeof(int) 4 bytes requested for shared memory
2016-11-20 14:55:31: pid 20828: LOG: Setting up socket for 0.0.0.0:5432
2016-11-20 14:55:31: pid 20828: LOG: Setting up socket for :::5432
2016-11-20 14:55:31: pid 20828: LOG: pgpool-II successfully started. version 3.5.4 (ekieboshi)
2016-11-20 14:55:31: pid 20828: DEBUG: find_primary_node: not in streaming replication mode
2016-11-20 14:55:31: pid 20834: DEBUG: initializing backend status
2016-11-20 14:55:31: pid 20835: DEBUG: initializing backend status
2016-11-20 14:55:31: pid 20832: DEBUG: initializing backend status
2016-11-20 14:55:31: pid 20833: DEBUG: initializing backend status
2016-11-20 14:55:31: pid 20836: DEBUG: initializing backend status
2016-11-20 14:55:31: pid 20831: DEBUG: initializing backend status
2016-11-20 14:55:31: pid 20837: DEBUG: initializing backend status
2016-11-20 14:55:31: pid 20838: DEBUG: initializing backend status
2016-11-20 14:55:31: pid 20839: DEBUG: I am PCP child with pid:20839
2016-11-20 14:55:31: pid 20830: DEBUG: initializing backend status
2016-11-20 14:55:31: pid 20840: DEBUG: I am 20840
2016-11-20 14:55:31: pid 20840: DEBUG: initializing backend status
2016-11-20 14:55:31: pid 20829: DEBUG: initializing backend status
2016-11-20 14:55:35: pid 20834: DEBUG: I am 20834 accept fd 7
2016-11-20 14:55:35: pid 20834: LOG: new connection received
2016-11-20 14:55:35: pid 20834: DETAIL: connecting host=177.35.181.177 port=54960
2016-11-20 14:55:36: pid 20834: DEBUG: reading startup packet
2016-11-20 14:55:36: pid 20834: DETAIL: Protocol Major: 3 Minor: 0 database: dev user: master
2016-11-20 14:55:36: pid 20834: DEBUG: creating new connection to backend
2016-11-20 14:55:36: pid 20834: DETAIL: connecting 0 backend
2016-11-20 14:55:36: pid 20834: DEBUG: creating new connection to backend
2016-11-20 14:55:36: pid 20834: DETAIL: connecting 1 backend
2016-11-20 14:55:36: pid 20834: DEBUG: SSL is requested but SSL support is not available
2016-11-20 14:55:36: pid 20834: DEBUG: SSL is requested but SSL support is not available
2016-11-20 14:55:36: pid 20834: DEBUG: pool_read: read 13 bytes from backend 0
2016-11-20 14:55:36: pid 20834: DEBUG: pool_read: read 13 bytes from backend 1
2016-11-20 14:55:36: pid 20834: DEBUG: reading message length
2016-11-20 14:55:36: pid 20834: DETAIL: slot: 0 length: 12
2016-11-20 14:55:36: pid 20834: DEBUG: reading message length
2016-11-20 14:55:36: pid 20834: DETAIL: slot: 1 length: 12
2016-11-20 14:55:36: pid 20834: DEBUG: authentication backend
2016-11-20 14:55:36: pid 20834: DETAIL: auth kind:5
2016-11-20 14:55:36: pid 20834: DEBUG: authentication backend
2016-11-20 14:55:36: pid 20834: DETAIL: trying md5 authentication
2016-11-20 14:55:36: pid 20834: DEBUG: performing md5 authentication
2016-11-20 14:55:36: pid 20834: DETAIL: DB node id: 0 salt: 44e2cc99
2016-11-20 14:55:36: pid 20834: DEBUG: pool_write: to backend: 0 kind:p
2016-11-20 14:55:36: pid 20834: DEBUG: pool_read: read 407 bytes from backend 0
2016-11-20 14:55:36: pid 20834: DEBUG: authentication backend
2016-11-20 14:55:36: pid 20834: DETAIL: trying md5 authentication
2016-11-20 14:55:36: pid 20834: DEBUG: performing md5 authentication
2016-11-20 14:55:36: pid 20834: DETAIL: DB node id: 1 salt: 266cf06d
2016-11-20 14:55:36: pid 20834: DEBUG: pool_write: to backend: 1 kind:p
2016-11-20 14:55:36: pid 20834: DEBUG: pool_read: read 407 bytes from backend 1
2016-11-20 14:55:36: pid 20834: DEBUG: reading message length
2016-11-20 14:55:36: pid 20834: DETAIL: master slot: 0 length: 28
2016-11-20 14:55:36: pid 20834: DEBUG: reading message length
2016-11-20 14:55:36: pid 20834: DETAIL: master slot: 1 length: 28
2016-11-20 14:55:36: pid 20834: DEBUG: process parameter status
2016-11-20 14:55:36: pid 20834: DETAIL: backend:0 name:"client_encoding" value:"UNICODE"
2016-11-20 14:55:36: pid 20834: DEBUG: process parameter status
2016-11-20 14:55:36: pid 20834: DETAIL: backend:1 name:"client_encoding" value:"UNICODE"
2016-11-20 14:55:36: pid 20834: DEBUG: reading message length
2016-11-20 14:55:36: pid 20834: DETAIL: master slot: 0 length: 23
2016-11-20 14:55:36: pid 20834: DEBUG: reading message length
2016-11-20 14:55:36: pid 20834: DETAIL: master slot: 1 length: 23
2016-11-20 14:55:36: pid 20834: DEBUG: process parameter status
2016-11-20 14:55:36: pid 20834: DETAIL: backend:0 name:"datestyle" value:"ISO, MDY"
2016-11-20 14:55:36: pid 20834: DEBUG: process parameter status
2016-11-20 14:55:36: pid 20834: DETAIL: backend:1 name:"datestyle" value:"ISO, MDY"
2016-11-20 14:55:36: pid 20834: DEBUG: reading message length
2016-11-20 14:55:36: pid 20834: DETAIL: master slot: 0 length: 28
2016-11-20 14:55:36: pid 20834: DEBUG: reading message length
2016-11-20 14:55:36: pid 20834: DETAIL: master slot: 1 length: 28
2016-11-20 14:55:36: pid 20834: DEBUG: process parameter status
2016-11-20 14:55:36: pid 20834: DETAIL: backend:0 name:"gconf_case_sensitive" value:"on"
2016-11-20 14:55:36: pid 20834: DEBUG: process parameter status
2016-11-20 14:55:36: pid 20834: DETAIL: backend:1 name:"gconf_case_sensitive" value:"on"
2016-11-20 14:55:36: pid 20834: DEBUG: reading message length
2016-11-20 14:55:36: pid 20834: DETAIL: master slot: 0 length: 25
2016-11-20 14:55:36: pid 20834: DEBUG: reading message length
2016-11-20 14:55:36: pid 20834: DETAIL: master slot: 1 length: 25
2016-11-20 14:55:36: pid 20834: DEBUG: process parameter status
2016-11-20 14:55:36: pid 20834: DETAIL: backend:0 name:"integer_datetimes" value:"on"
2016-11-20 14:55:36: pid 20834: DEBUG: process parameter status
2016-11-20 14:55:36: pid 20834: DETAIL: backend:1 name:"integer_datetimes" value:"on"
2016-11-20 14:55:36: pid 20834: DEBUG: reading message length
2016-11-20 14:55:36: pid 20834: DETAIL: master slot: 0 length: 20
2016-11-20 14:55:36: pid 20834: DEBUG: reading message length
2016-11-20 14:55:36: pid 20834: DETAIL: master slot: 1 length: 20
2016-11-20 14:55:36: pid 20834: DEBUG: process parameter status
2016-11-20 14:55:36: pid 20834: DETAIL: backend:0 name:"is_superuser" value:"on"
2016-11-20 14:55:36: pid 20834: DEBUG: process parameter status
2016-11-20 14:55:36: pid 20834: DETAIL: backend:1 name:"is_superuser" value:"on"
2016-11-20 14:55:36: pid 20834: DEBUG: reading message length
2016-11-20 14:55:36: pid 20834: DETAIL: master slot: 0 length: 28
2016-11-20 14:55:36: pid 20834: DEBUG: reading message length
2016-11-20 14:55:36: pid 20834: DETAIL: master slot: 1 length: 28
2016-11-20 14:55:36: pid 20834: DEBUG: process parameter status
2016-11-20 14:55:36: pid 20834: DETAIL: backend:0 name:"server_encoding" value:"UNICODE"
2016-11-20 14:55:36: pid 20834: DEBUG: process parameter status
2016-11-20 14:55:36: pid 20834: DETAIL: backend:1 name:"server_encoding" value:"UNICODE"
2016-11-20 14:55:36: pid 20834: DEBUG: reading message length
2016-11-20 14:55:36: pid 20834: DETAIL: master slot: 0 length: 25
2016-11-20 14:55:36: pid 20834: DEBUG: reading message length
2016-11-20 14:55:36: pid 20834: DETAIL: master slot: 1 length: 25
2016-11-20 14:55:36: pid 20834: DEBUG: process parameter status
2016-11-20 14:55:36: pid 20834: DETAIL: backend:0 name:"server_version" value:"8.0.2"
2016-11-20 14:55:36: pid 20834: DEBUG: process parameter status
2016-11-20 14:55:36: pid 20834: DETAIL: backend:1 name:"server_version" value:"8.0.2"
2016-11-20 14:55:36: pid 20834: DEBUG: reading message length
2016-11-20 14:55:36: pid 20834: DETAIL: master slot: 0 length: 33
2016-11-20 14:55:36: pid 20834: DEBUG: reading message length
2016-11-20 14:55:36: pid 20834: DETAIL: master slot: 1 length: 33
2016-11-20 14:55:36: pid 20834: DEBUG: process parameter status
2016-11-20 14:55:36: pid 20834: DETAIL: backend:0 name:"session_authorization" value:"master"
2016-11-20 14:55:36: pid 20834: DEBUG: process parameter status
2016-11-20 14:55:36: pid 20834: DETAIL: backend:1 name:"session_authorization" value:"master"
2016-11-20 14:55:36: pid 20834: DEBUG: reading message length
2016-11-20 14:55:36: pid 20834: DETAIL: master slot: 0 length: 17
2016-11-20 14:55:36: pid 20834: DEBUG: reading message length
2016-11-20 14:55:36: pid 20834: DETAIL: master slot: 1 length: 17
2016-11-20 14:55:36: pid 20834: DEBUG: process parameter status
2016-11-20 14:55:36: pid 20834: DETAIL: backend:0 name:"TimeZone" value:"UTC"
2016-11-20 14:55:36: pid 20834: DEBUG: process parameter status
2016-11-20 14:55:36: pid 20834: DETAIL: backend:1 name:"TimeZone" value:"UTC"
2016-11-20 14:55:36: pid 20834: DEBUG: reading message length
2016-11-20 14:55:36: pid 20834: DETAIL: master slot: 0 length: 35
2016-11-20 14:55:36: pid 20834: DEBUG: reading message length
2016-11-20 14:55:36: pid 20834: DETAIL: master slot: 1 length: 35
2016-11-20 14:55:36: pid 20834: DEBUG: process parameter status
2016-11-20 14:55:36: pid 20834: DETAIL: backend:0 name:"timezone_abbreviations" value:"Default"
2016-11-20 14:55:36: pid 20834: DEBUG: process parameter status
2016-11-20 14:55:36: pid 20834: DETAIL: backend:1 name:"timezone_abbreviations" value:"Default"
2016-11-20 14:55:36: pid 20834: DEBUG: reading message length
2016-11-20 14:55:36: pid 20834: DETAIL: master slot: 0 length: 18
2016-11-20 14:55:36: pid 20834: DEBUG: reading message length
2016-11-20 14:55:36: pid 20834: DETAIL: master slot: 1 length: 18
2016-11-20 14:55:36: pid 20834: DEBUG: process parameter status
2016-11-20 14:55:36: pid 20834: DETAIL: backend:0 name:"padb_version" value:""
2016-11-20 14:55:36: pid 20834: DEBUG: process parameter status
2016-11-20 14:55:36: pid 20834: DETAIL: backend:1 name:"padb_version" value:""
2016-11-20 14:55:36: pid 20834: DEBUG: reading message length
2016-11-20 14:55:36: pid 20834: DETAIL: master slot: 0 length: 29
2016-11-20 14:55:36: pid 20834: DEBUG: reading message length
2016-11-20 14:55:36: pid 20834: DETAIL: master slot: 1 length: 29
2016-11-20 14:55:36: pid 20834: DEBUG: process parameter status
2016-11-20 14:55:36: pid 20834: DETAIL: backend:0 name:"padb_revision" value:"ba4de096e2"
2016-11-20 14:55:36: pid 20834: DEBUG: process parameter status
2016-11-20 14:55:36: pid 20834: DETAIL: backend:1 name:"padb_revision" value:"ba4de096e2"
2016-11-20 14:55:36: pid 20834: DEBUG: reading message length
2016-11-20 14:55:36: pid 20834: DETAIL: master slot: 0 length: 29
2016-11-20 14:55:36: pid 20834: DEBUG: reading message length
2016-11-20 14:55:36: pid 20834: DETAIL: master slot: 1 length: 29
2016-11-20 14:55:36: pid 20834: DEBUG: process parameter status
2016-11-20 14:55:36: pid 20834: DETAIL: backend:0 name:"max_numeric_precision" value:"38"
2016-11-20 14:55:36: pid 20834: DEBUG: process parameter status
2016-11-20 14:55:36: pid 20834: DETAIL: backend:1 name:"max_numeric_precision" value:"38"
2016-11-20 14:55:36: pid 20834: DEBUG: reading message length
2016-11-20 14:55:36: pid 20834: DETAIL: master slot: 0 length: 27
2016-11-20 14:55:36: pid 20834: DEBUG: reading message length
2016-11-20 14:55:36: pid 20834: DETAIL: master slot: 1 length: 27
2016-11-20 14:55:36: pid 20834: DEBUG: process parameter status
2016-11-20 14:55:36: pid 20834: DETAIL: backend:0 name:"max_varchar_size" value:"65535"
2016-11-20 14:55:36: pid 20834: DEBUG: process parameter status
2016-11-20 14:55:36: pid 20834: DETAIL: backend:1 name:"max_varchar_size" value:"65535"
2016-11-20 14:55:36: pid 20834: DEBUG: reading message length
2016-11-20 14:55:36: pid 20834: DETAIL: slot: 0 length: 12
2016-11-20 14:55:36: pid 20834: DEBUG: reading message length
2016-11-20 14:55:36: pid 20834: DETAIL: slot: 1 length: 12
2016-11-20 14:55:36: pid 20834: DEBUG: authentication backend
2016-11-20 14:55:36: pid 20834: DETAIL: cp->info[i]:0x7fc6af6ab000 pid:12781
2016-11-20 14:55:36: pid 20834: DEBUG: authentication backend
2016-11-20 14:55:36: pid 20834: DETAIL: cp->info[i]:0x7fc6af6ab088 pid:6370
2016-11-20 14:55:36: pid 20834: DEBUG: sending backend key data
2016-11-20 14:55:36: pid 20834: DETAIL: send pid 6370 to frontend
2016-11-20 14:55:36: pid 20834: DEBUG: initializing session context
2016-11-20 14:55:36: pid 20834: DETAIL: selected load balancing node: 0
2016-11-20 14:55:36: pid 20834: DEBUG: session context: unsetting query in progress. DONE
2016-11-20 14:55:36: pid 20834: DEBUG: session context: clearing transaction isolation. DONE
2016-11-20 14:55:36: pid 20834: DEBUG: session context: clearing writing transaction. DONE
2016-11-20 14:55:36: pid 20834: DEBUG: session context: clearing failed transaction. DONE
2016-11-20 14:55:36: pid 20834: DEBUG: session context: clearing failed transaction. DONE
2016-11-20 14:55:36: pid 20834: DEBUG: session context: clearing skip reading from backends. DONE
2016-11-20 14:55:36: pid 20834: DEBUG: session context: clearing ignore till sync. DONE
2016-11-20 14:55:36: pid 20834: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 0 query in progress: 0 doing extended query: 0
2016-11-20 14:55:36: pid 20834: DEBUG: reading backend data packet kind
2016-11-20 14:55:36: pid 20834: DETAIL: backend:0 of 2 kind = 'Z'
2016-11-20 14:55:36: pid 20834: DEBUG: reading backend data packet kind
2016-11-20 14:55:36: pid 20834: DETAIL: backend:1 of 2 kind = 'Z'
2016-11-20 14:55:36: pid 20834: DEBUG: read_kind_from_backend max_count:2.000000 num_executed_nodes:2
2016-11-20 14:55:36: pid 20834: DEBUG: processing backend response
2016-11-20 14:55:36: pid 20834: DETAIL: received kind 'Z'(5a) from backend
2016-11-20 14:55:36: pid 20834: DEBUG: processing backend response
2016-11-20 14:55:36: pid 20834: DETAIL: Ready For Query received
2016-11-20 14:55:36: pid 20834: DEBUG: session context: clearing ignore till sync. DONE
2016-11-20 14:55:36: pid 20834: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 0 query in progress: 0 doing extended query: 0
2016-11-20 14:55:36: pid 20834: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 0 query in progress: 0 doing extended query: 0
2016-11-20 14:55:36: pid 20834: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 0 query in progress: 0 doing extended query: 0
2016-11-20 14:55:36: pid 20834: DEBUG: reading message length
2016-11-20 14:55:36: pid 20834: DETAIL: slot: 0 length: 5
2016-11-20 14:55:36: pid 20834: DEBUG: reading message length
2016-11-20 14:55:36: pid 20834: DETAIL: slot: 1 length: 5
2016-11-20 14:55:36: pid 20834: DEBUG: processing ReadyForQuery
2016-11-20 14:55:36: pid 20834: DETAIL: transaction state '
2016-11-20 14:55:36: pid 20834: DEBUG: processing ReadyForQuery
2016-11-20 14:55:36: pid 20834: DETAIL: transaction state 'I'(49)
2016-11-20 14:55:36: pid 20834: DEBUG: processing frontend response
2016-11-20 14:55:36: pid 20834: DETAIL: received kind 'P'(50) from frontend
2016-11-20 14:55:36: pid 20834: DEBUG: session context: clearing doing extended query messaging. DONE
2016-11-20 14:55:36: pid 20834: DEBUG: session context: setting doing extended query messaging. DONE
2016-11-20 14:55:36: pid 20834: DEBUG: Parse: statement name <>
2016-11-20 14:55:36: pid 20834: DEBUG: session context: setting query in progress. DONE
2016-11-20 14:55:36: pid 20834: DEBUG: pool_write: to backend: 0 kind:S
2016-11-20 14:55:36: pid 20834: DEBUG: pool_write: to backend: 0 kind:H
2016-11-20 14:55:36: pid 20834: DEBUG: pool_write: to backend: 1 kind:S
2016-11-20 14:55:36: pid 20834: DEBUG: pool_write: to backend: 1 kind:H
2016-11-20 14:55:36: pid 20834: DEBUG: pool_read: read 6 bytes from backend 0
2016-11-20 14:55:36: pid 20834: DEBUG: pool_read: read 6 bytes from backend 1
2016-11-20 14:55:36: pid 20834: DEBUG: session context: clearing ignore till sync. DONE
2016-11-20 14:55:36: pid 20834: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 0 query in progress: 1 doing extended query: 1
2016-11-20 14:55:36: pid 20834: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 0 query in progress: 1 doing extended query: 1
2016-11-20 14:55:36: pid 20834: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 0 query in progress: 1 doing extended query: 1
2016-11-20 14:55:36: pid 20834: DEBUG: reading message length
2016-11-20 14:55:36: pid 20834: DETAIL: slot: 0 length: 5
2016-11-20 14:55:36: pid 20834: DEBUG: reading message length
2016-11-20 14:55:36: pid 20834: DETAIL: slot: 1 length: 5
2016-11-20 14:55:36: pid 20834: DEBUG: processing ReadyForQuery
2016-11-20 14:55:36: pid 20834: DETAIL: transaction state 'I'(49)
2016-11-20 14:55:36: pid 20834: DEBUG: processing ReadyForQuery
2016-11-20 14:55:36: pid 20834: DETAIL: transaction state 'I'(49)
2016-11-20 14:55:36: pid 20834: DEBUG: session context: unsetting query in progress. DONE
2016-11-20 14:55:36: pid 20834: DEBUG: session context: setting query in progress. DONE
2016-11-20 14:55:36: pid 20834: DEBUG: Parse: waiting for master completing the query
2016-11-20 14:55:36: pid 20834: LOG: DB node id: 0 backend pid: 12781 statement: Parse: drop table if exists testtable
2016-11-20 14:55:36: pid 20834: DEBUG: pool_send_and_wait: pool_set_sync_map: 0
2016-11-20 14:55:36: pid 20834: DEBUG: pool_write: to backend: 0 kind:P
2016-11-20 14:55:36: pid 20834: DEBUG: pool_write: to backend: 0 kind:H
2016-11-20 14:55:36: pid 20834: DEBUG: waiting for query response
2016-11-20 14:55:36: pid 20834: DETAIL: waiting for backend:0 to complete the query
2016-11-20 14:55:36: pid 20834: DEBUG: pool_read: read 5 bytes from backend 0
2016-11-20 14:55:36: pid 20834: DEBUG: detect error: kind: 1
2016-11-20 14:55:36: pid 20834: LOG: DB node id: 1 backend pid: 6370 statement: Parse: drop table if exists testtable
2016-11-20 14:55:36: pid 20834: DEBUG: pool_send_and_wait: pool_set_sync_map: 1
2016-11-20 14:55:36: pid 20834: DEBUG: pool_write: to backend: 1 kind:P
2016-11-20 14:55:36: pid 20834: DEBUG: pool_write: to backend: 1 kind:H
2016-11-20 14:55:36: pid 20834: DEBUG: waiting for query response
2016-11-20 14:55:36: pid 20834: DETAIL: waiting for backend:1 to complete the query
2016-11-20 14:55:36: pid 20834: DEBUG: pool_read: read 5 bytes from backend 1
2016-11-20 14:55:36: pid 20834: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 0 query in progress: 1 doing extended query: 1
2016-11-20 14:55:36: pid 20834: DEBUG: reading backend data packet kind
2016-11-20 14:55:36: pid 20834: DETAIL: backend:0 of 2 kind = '1'
2016-11-20 14:55:36: pid 20834: DEBUG: reading backend data packet kind
2016-11-20 14:55:36: pid 20834: DETAIL: backend:1 of 2 kind = '1'
2016-11-20 14:55:36: pid 20834: DEBUG: read_kind_from_backend max_count:2.000000 num_executed_nodes:2
2016-11-20 14:55:36: pid 20834: DEBUG: processing backend response
2016-11-20 14:55:36: pid 20834: DETAIL: received kind '1'(31) from backend
2016-11-20 14:55:36: pid 20834: DEBUG: called by pool_add_sent_message: sent message: address: 0x10644d0 kind: P name: ==
2016-11-20 14:55:36: pid 20834: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 0 query in progress: 1 doing extended query: 1
2016-11-20 14:55:36: pid 20834: DEBUG: SimpleForwardToFrontend: packet:1 length:0
2016-11-20 14:55:36: pid 20834: DEBUG: session context: setting command success. DONE
2016-11-20 14:55:36: pid 20834: DEBUG: session context: unsetting query in progress. DONE
2016-11-20 14:55:36: pid 20834: DEBUG: processing frontend response
2016-11-20 14:55:36: pid 20834: DETAIL: received kind 'B'(42) from frontend
2016-11-20 14:55:36: pid 20834: DEBUG: session context: clearing doing extended query messaging. DONE
2016-11-20 14:55:36: pid 20834: DEBUG: session context: setting doing extended query messaging. DONE
2016-11-20 14:55:36: pid 20834: DEBUG: Bind: checking strict query
2016-11-20 14:55:36: pid 20834: DEBUG: Bind: checking insert lock
2016-11-20 14:55:36: pid 20834: DEBUG: Bind: waiting for master completing the query
2016-11-20 14:55:36: pid 20834: DEBUG: session context: setting query in progress. DONE
2016-11-20 14:55:36: pid 20834: LOG: DB node id: 0 backend pid: 12781 statement: B message
2016-11-20 14:55:36: pid 20834: DEBUG: pool_send_and_wait: pool_set_sync_map: 0
2016-11-20 14:55:36: pid 20834: DEBUG: pool_write: to backend: 0 kind:B
2016-11-20 14:55:36: pid 20834: DEBUG: pool_write: to backend: 0 kind:H
2016-11-20 14:55:36: pid 20834: DEBUG: waiting for query response
2016-11-20 14:55:36: pid 20834: DETAIL: waiting for backend:0 to complete the query
2016-11-20 14:55:36: pid 20834: DEBUG: pool_read: read 5 bytes from backend 0
2016-11-20 14:55:36: pid 20834: LOG: DB node id: 1 backend pid: 6370 statement: B message
2016-11-20 14:55:36: pid 20834: DEBUG: pool_send_and_wait: pool_set_sync_map: 1
2016-11-20 14:55:36: pid 20834: DEBUG: pool_write: to backend: 1 kind:B
2016-11-20 14:55:36: pid 20834: DEBUG: pool_write: to backend: 1 kind:H
2016-11-20 14:55:36: pid 20834: DEBUG: waiting for query response
2016-11-20 14:55:36: pid 20834: DETAIL: waiting for backend:1 to complete the query
2016-11-20 14:55:36: pid 20834: DEBUG: pool_read: read 5 bytes from backend 1
2016-11-20 14:55:36: pid 20834: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 0 query in progress: 1 doing extended query: 1
2016-11-20 14:55:36: pid 20834: DEBUG: reading backend data packet kind
2016-11-20 14:55:36: pid 20834: DETAIL: backend:0 of 2 kind = '2'
2016-11-20 14:55:36: pid 20834: DEBUG: reading backend data packet kind
2016-11-20 14:55:36: pid 20834: DETAIL: backend:1 of 2 kind = '2'
2016-11-20 14:55:36: pid 20834: DEBUG: read_kind_from_backend max_count:2.000000 num_executed_nodes:2
2016-11-20 14:55:36: pid 20834: DEBUG: processing backend response
2016-11-20 14:55:36: pid 20834: DETAIL: received kind '2'(32) from backend
2016-11-20 14:55:36: pid 20834: DEBUG: called by pool_add_sent_message: sent message: address: 0x1064588 kind: B name: ==
2016-11-20 14:55:36: pid 20834: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 0 query in progress: 1 doing extended query: 1
2016-11-20 14:55:36: pid 20834: DEBUG: SimpleForwardToFrontend: packet:2 length:0
2016-11-20 14:55:36: pid 20834: DEBUG: session context: setting command success. DONE
2016-11-20 14:55:36: pid 20834: DEBUG: session context: unsetting query in progress. DONE
2016-11-20 14:55:36: pid 20834: DEBUG: processing frontend response
2016-11-20 14:55:36: pid 20834: DETAIL: received kind 'D'(44) from frontend
2016-11-20 14:55:36: pid 20834: DEBUG: session context: clearing doing extended query messaging. DONE
2016-11-20 14:55:36: pid 20834: DEBUG: session context: setting doing extended query messaging. DONE
2016-11-20 14:55:36: pid 20834: DEBUG: Describe: waiting for master completing the query
2016-11-20 14:55:36: pid 20834: DEBUG: session context: setting query in progress. DONE
2016-11-20 14:55:36: pid 20834: LOG: DB node id: 0 backend pid: 12781 statement: D message
2016-11-20 14:55:36: pid 20834: DEBUG: pool_send_and_wait: pool_set_sync_map: 0
2016-11-20 14:55:36: pid 20834: DEBUG: pool_write: to backend: 0 kind:D
2016-11-20 14:55:36: pid 20834: DEBUG: pool_write: to backend: 0 kind:H
2016-11-20 14:55:36: pid 20834: DEBUG: waiting for query response
2016-11-20 14:55:36: pid 20834: DETAIL: waiting for backend:0 to complete the query
2016-11-20 14:55:36: pid 20834: DEBUG: pool_read: read 5 bytes from backend 0
2016-11-20 14:55:36: pid 20834: LOG: DB node id: 1 backend pid: 6370 statement: D message
2016-11-20 14:55:36: pid 20834: DEBUG: pool_send_and_wait: pool_set_sync_map: 1
2016-11-20 14:55:36: pid 20834: DEBUG: pool_write: to backend: 1 kind:D
2016-11-20 14:55:36: pid 20834: DEBUG: pool_write: to backend: 1 kind:H
2016-11-20 14:55:36: pid 20834: DEBUG: waiting for query response
2016-11-20 14:55:36: pid 20834: DETAIL: waiting for backend:1 to complete the query
2016-11-20 14:55:36: pid 20834: DEBUG: pool_read: read 5 bytes from backend 1
2016-11-20 14:55:36: pid 20834: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 0 query in progress: 1 doing extended query: 1
2016-11-20 14:55:36: pid 20834: DEBUG: reading backend data packet kind
2016-11-20 14:55:36: pid 20834: DETAIL: backend:0 of 2 kind = 'n'
2016-11-20 14:55:36: pid 20834: DEBUG: reading backend data packet kind
2016-11-20 14:55:36: pid 20834: DETAIL: backend:1 of 2 kind = 'n'
2016-11-20 14:55:36: pid 20834: DEBUG: read_kind_from_backend max_count:2.000000 num_executed_nodes:2
2016-11-20 14:55:36: pid 20834: DEBUG: processing backend response
2016-11-20 14:55:36: pid 20834: DETAIL: received kind 'n'(6e) from backend
2016-11-20 14:55:36: pid 20834: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 0 query in progress: 1 doing extended query: 1
2016-11-20 14:55:36: pid 20834: DEBUG: SimpleForwardToFrontend: packet:n length:0
2016-11-20 14:55:36: pid 20834: DEBUG: session context: unsetting query in progress. DONE
2016-11-20 14:55:36: pid 20834: DEBUG: processing frontend response
2016-11-20 14:55:36: pid 20834: DETAIL: received kind 'E'(45) from frontend
2016-11-20 14:55:36: pid 20834: DEBUG: session context: clearing doing extended query messaging. DONE
2016-11-20 14:55:36: pid 20834: DEBUG: session context: setting doing extended query messaging. DONE
2016-11-20 14:55:36: pid 20834: DEBUG: session context: setting query in progress. DONE
2016-11-20 14:55:36: pid 20834: LOG: DB node id: 0 backend pid: 12781 statement: Execute: drop table if exists testtable
2016-11-20 14:55:36: pid 20834: DEBUG: pool_send_and_wait: pool_set_sync_map: 0
2016-11-20 14:55:36: pid 20834: DEBUG: pool_write: to backend: 0 kind:E
2016-11-20 14:55:36: pid 20834: DEBUG: pool_write: to backend: 0 kind:H
2016-11-20 14:55:36: pid 20834: DEBUG: waiting for query response
2016-11-20 14:55:36: pid 20834: DETAIL: waiting for backend:0 to complete the query
2016-11-20 14:55:36: pid 20834: DEBUG: pool_read: read 16 bytes from backend 0
2016-11-20 14:55:36: pid 20834: DEBUG: detect error: kind: C
2016-11-20 14:55:36: pid 20834: DEBUG: detect error: kind: C
2016-11-20 14:55:36: pid 20834: DEBUG: detect error: kind: C
2016-11-20 14:55:36: pid 20834: DEBUG: detect error: kind: C
2016-11-20 14:55:36: pid 20834: LOG: DB node id: 1 backend pid: 6370 statement: Execute: drop table if exists testtable
2016-11-20 14:55:36: pid 20834: DEBUG: pool_send_and_wait: pool_set_sync_map: 1
2016-11-20 14:55:36: pid 20834: DEBUG: pool_write: to backend: 1 kind:E
2016-11-20 14:55:36: pid 20834: DEBUG: pool_write: to backend: 1 kind:H
2016-11-20 14:55:36: pid 20834: DEBUG: waiting for query response
2016-11-20 14:55:36: pid 20834: DETAIL: waiting for backend:1 to complete the query
2016-11-20 14:55:36: pid 20834: DEBUG: pool_read: read 16 bytes from backend 1
2016-11-20 14:55:36: pid 20834: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 0 query in progress: 1 doing extended query: 1
2016-11-20 14:55:36: pid 20834: DEBUG: reading backend data packet kind
2016-11-20 14:55:36: pid 20834: DETAIL: backend:0 of 2 kind = 'C'
2016-11-20 14:55:36: pid 20834: DEBUG: reading backend data packet kind
2016-11-20 14:55:36: pid 20834: DETAIL: backend:1 of 2 kind = 'C'
2016-11-20 14:55:36: pid 20834: DEBUG: read_kind_from_backend max_count:2.000000 num_executed_nodes:2
2016-11-20 14:55:36: pid 20834: DEBUG: processing backend response
2016-11-20 14:55:36: pid 20834: DETAIL: received kind 'C'(43) from backend
2016-11-20 14:55:36: pid 20834: DEBUG: session context: setting command success. DONE
2016-11-20 14:55:36: pid 20834: DEBUG: session context: unsetting query in progress. DONE
2016-11-20 14:55:36: pid 20834: DEBUG: processing frontend response
2016-11-20 14:55:36: pid 20834: DETAIL: received kind 'P'(50) from frontend
2016-11-20 14:55:36: pid 20834: DEBUG: session context: clearing doing extended query messaging. DONE
2016-11-20 14:55:36: pid 20834: DEBUG: session context: setting doing extended query messaging. DONE
2016-11-20 14:55:36: pid 20834: DEBUG: Parse: statement name <>
2016-11-20 14:55:36: pid 20834: DEBUG: session context: setting query in progress. DONE
2016-11-20 14:55:36: pid 20834: DEBUG: pool_write: to backend: 0 kind:S
2016-11-20 14:55:36: pid 20834: DEBUG: pool_write: to backend: 0 kind:H
2016-11-20 14:55:36: pid 20834: DEBUG: pool_write: to backend: 1 kind:S
2016-11-20 14:55:36: pid 20834: DEBUG: pool_write: to backend: 1 kind:H
2016-11-20 14:55:36: pid 20834: DEBUG: pool_read: read 6 bytes from backend 0
2016-11-20 14:55:36: pid 20834: DEBUG: pool_read: read 6 bytes from backend 1
2016-11-20 14:55:36: pid 20834: DEBUG: session context: clearing ignore till sync. DONE
2016-11-20 14:55:36: pid 20834: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 0 query in progress: 1 doing extended query: 1
2016-11-20 14:55:36: pid 20834: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 0 query in progress: 1 doing extended query: 1
2016-11-20 14:55:36: pid 20834: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 0 query in progress: 1 doing extended query: 1
2016-11-20 14:55:36: pid 20834: DEBUG: reading message length
2016-11-20 14:55:36: pid 20834: DETAIL: slot: 0 length: 5
2016-11-20 14:55:36: pid 20834: DEBUG: reading message length
2016-11-20 14:55:36: pid 20834: DETAIL: slot: 1 length: 5
2016-11-20 14:55:36: pid 20834: DEBUG: processing ReadyForQuery
2016-11-20 14:55:36: pid 20834: DETAIL: transaction state 'I'(49)
2016-11-20 14:55:36: pid 20834: DEBUG: processing ReadyForQuery
2016-11-20 14:55:36: pid 20834: DETAIL: transaction state 'I'(49)
2016-11-20 14:55:36: pid 20834: DEBUG: session context: unsetting query in progress. DONE
2016-11-20 14:55:36: pid 20834: DEBUG: session context: setting query in progress. DONE
2016-11-20 14:55:36: pid 20834: DEBUG: Parse: waiting for master completing the query
2016-11-20 14:55:36: pid 20834: LOG: DB node id: 0 backend pid: 12781 statement: Parse: create table testtable (testcol int)
2016-11-20 14:55:36: pid 20834: DEBUG: pool_send_and_wait: pool_set_sync_map: 0
2016-11-20 14:55:36: pid 20834: DEBUG: pool_write: to backend: 0 kind:P
2016-11-20 14:55:36: pid 20834: DEBUG: pool_write: to backend: 0 kind:H
2016-11-20 14:55:36: pid 20834: DEBUG: waiting for query response
2016-11-20 14:55:36: pid 20834: DETAIL: waiting for backend:0 to complete the query
2016-11-20 14:55:36: pid 20834: DEBUG: pool_read: read 5 bytes from backend 0
2016-11-20 14:55:36: pid 20834: DEBUG: detect error: kind: 1
2016-11-20 14:55:36: pid 20834: LOG: DB node id: 1 backend pid: 6370 statement: Parse: create table testtable (testcol int)
2016-11-20 14:55:36: pid 20834: DEBUG: pool_send_and_wait: pool_set_sync_map: 1
2016-11-20 14:55:36: pid 20834: DEBUG: pool_write: to backend: 1 kind:P
2016-11-20 14:55:36: pid 20834: DEBUG: pool_write: to backend: 1 kind:H
2016-11-20 14:55:36: pid 20834: DEBUG: waiting for query response
2016-11-20 14:55:36: pid 20834: DETAIL: waiting for backend:1 to complete the query
2016-11-20 14:55:36: pid 20834: DEBUG: pool_read: read 5 bytes from backend 1
2016-11-20 14:55:36: pid 20834: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 0 query in progress: 1 doing extended query: 1
2016-11-20 14:55:36: pid 20834: DEBUG: reading backend data packet kind
2016-11-20 14:55:36: pid 20834: DETAIL: backend:0 of 2 kind = '1'
2016-11-20 14:55:36: pid 20834: DEBUG: reading backend data packet kind
2016-11-20 14:55:36: pid 20834: DETAIL: backend:1 of 2 kind = '1'
2016-11-20 14:55:36: pid 20834: DEBUG: read_kind_from_backend max_count:2.000000 num_executed_nodes:2
2016-11-20 14:55:36: pid 20834: DEBUG: processing backend response
2016-11-20 14:55:36: pid 20834: DETAIL: received kind '1'(31) from backend
2016-11-20 14:55:36: pid 20834: DEBUG: called by pool_add_sent_message: sent message: address: 0x1064608 kind: P name: ==
2016-11-20 14:55:36: pid 20834: DEBUG: adding sent message to list
2016-11-20 14:55:36: pid 20834: DETAIL: prepared statement "" already exists
2016-11-20 14:55:36: pid 20834: DEBUG: called by pool_sent_message_destroy: sent message: address: 0x10644d0 kind: P name: ==
2016-11-20 14:55:36: pid 20834: DEBUG: checking if query context can be safely destroyed
2016-11-20 14:55:36: pid 20834: DETAIL: query context 0x10668a0 is still used 2 times. query:"drop table if exists testtable"
2016-11-20 14:55:36: pid 20834: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 0 query in progress: 1 doing extended query: 1
2016-11-20 14:55:36: pid 20834: DEBUG: SimpleForwardToFrontend: packet:1 length:0
2016-11-20 14:55:36: pid 20834: DEBUG: session context: setting command success. DONE
2016-11-20 14:55:36: pid 20834: DEBUG: session context: unsetting query in progress. DONE
2016-11-20 14:55:36: pid 20834: DEBUG: processing frontend response
2016-11-20 14:55:36: pid 20834: DETAIL: received kind 'B'(42) from frontend
2016-11-20 14:55:36: pid 20834: DEBUG: session context: clearing doing extended query messaging. DONE
2016-11-20 14:55:36: pid 20834: DEBUG: session context: setting doing extended query messaging. DONE
2016-11-20 14:55:36: pid 20834: DEBUG: Bind: checking strict query
2016-11-20 14:55:36: pid 20834: DEBUG: Bind: checking insert lock
2016-11-20 14:55:36: pid 20834: DEBUG: Bind: waiting for master completing the query
2016-11-20 14:55:36: pid 20834: DEBUG: session context: setting query in progress. DONE
2016-11-20 14:55:36: pid 20834: LOG: DB node id: 0 backend pid: 12781 statement: B message
2016-11-20 14:55:36: pid 20834: DEBUG: pool_send_and_wait: pool_set_sync_map: 0
2016-11-20 14:55:36: pid 20834: DEBUG: pool_write: to backend: 0 kind:B
2016-11-20 14:55:36: pid 20834: DEBUG: pool_write: to backend: 0 kind:H
2016-11-20 14:55:36: pid 20834: DEBUG: waiting for query response
2016-11-20 14:55:36: pid 20834: DETAIL: waiting for backend:0 to complete the query
2016-11-20 14:55:36: pid 20834: DEBUG: pool_read: read 5 bytes from backend 0
2016-11-20 14:55:36: pid 20834: LOG: DB node id: 1 backend pid: 6370 statement: B message
2016-11-20 14:55:36: pid 20834: DEBUG: pool_send_and_wait: pool_set_sync_map: 1
2016-11-20 14:55:36: pid 20834: DEBUG: pool_write: to backend: 1 kind:B
2016-11-20 14:55:36: pid 20834: DEBUG: pool_write: to backend: 1 kind:H
2016-11-20 14:55:36: pid 20834: DEBUG: waiting for query response
2016-11-20 14:55:36: pid 20834: DETAIL: waiting for backend:1 to complete the query
2016-11-20 14:55:36: pid 20834: DEBUG: pool_read: read 5 bytes from backend 1
2016-11-20 14:55:36: pid 20834: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 0 query in progress: 1 doing extended query: 1
2016-11-20 14:55:36: pid 20834: DEBUG: reading backend data packet kind
2016-11-20 14:55:36: pid 20834: DETAIL: backend:0 of 2 kind = '2'
2016-11-20 14:55:36: pid 20834: DEBUG: reading backend data packet kind
2016-11-20 14:55:36: pid 20834: DETAIL: backend:1 of 2 kind = '2'
2016-11-20 14:55:36: pid 20834: DEBUG: read_kind_from_backend max_count:2.000000 num_executed_nodes:2
2016-11-20 14:55:36: pid 20834: DEBUG: processing backend response
2016-11-20 14:55:36: pid 20834: DETAIL: received kind '2'(32) from backend
2016-11-20 14:55:36: pid 20834: DEBUG: called by pool_add_sent_message: sent message: address: 0x10644d0 kind: B name: ==
2016-11-20 14:55:36: pid 20834: DEBUG: adding sent message to list
2016-11-20 14:55:36: pid 20834: DETAIL: portal "" already exists
2016-11-20 14:55:36: pid 20834: DEBUG: called by pool_sent_message_destroy: sent message: address: 0x1064588 kind: B name: ==
2016-11-20 14:55:36: pid 20834: DEBUG: session context: unsetting query in progress. DONE
2016-11-20 14:55:36: pid 20834: DEBUG: session context: setting query in progress. DONE
2016-11-20 14:55:36: pid 20834: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 0 query in progress: 1 doing extended query: 1
2016-11-20 14:55:36: pid 20834: DEBUG: SimpleForwardToFrontend: packet:2 length:0
2016-11-20 14:55:36: pid 20834: DEBUG: session context: setting command success. DONE
2016-11-20 14:55:36: pid 20834: DEBUG: session context: unsetting query in progress. DONE
2016-11-20 14:55:36: pid 20834: DEBUG: processing frontend response
2016-11-20 14:55:36: pid 20834: DETAIL: received kind 'D'(44) from frontend
2016-11-20 14:55:36: pid 20834: DEBUG: session context: clearing doing extended query messaging. DONE
2016-11-20 14:55:36: pid 20834: DEBUG: session context: setting doing extended query messaging. DONE
2016-11-20 14:55:36: pid 20834: DEBUG: Describe: waiting for master completing the query
2016-11-20 14:55:36: pid 20834: DEBUG: session context: setting query in progress. DONE
2016-11-20 14:55:36: pid 20834: LOG: DB node id: 0 backend pid: 12781 statement: D message
2016-11-20 14:55:36: pid 20834: DEBUG: pool_send_and_wait: pool_set_sync_map: 0
2016-11-20 14:55:36: pid 20834: DEBUG: pool_write: to backend: 0 kind:D
2016-11-20 14:55:36: pid 20834: DEBUG: pool_write: to backend: 0 kind:H
2016-11-20 14:55:36: pid 20834: DEBUG: waiting for query response
2016-11-20 14:55:36: pid 20834: DETAIL: waiting for backend:0 to complete the query
2016-11-20 14:55:36: pid 20834: DEBUG: pool_read: read 5 bytes from backend 0
2016-11-20 14:55:36: pid 20834: LOG: DB node id: 1 backend pid: 6370 statement: D message
2016-11-20 14:55:37: pid 20834: DEBUG: pool_send_and_wait: pool_set_sync_map: 1
2016-11-20 14:55:37: pid 20834: DEBUG: pool_write: to backend: 1 kind:D
2016-11-20 14:55:37: pid 20834: DEBUG: pool_write: to backend: 1 kind:H
2016-11-20 14:55:37: pid 20834: DEBUG: waiting for query response
2016-11-20 14:55:37: pid 20834: DETAIL: waiting for backend:1 to complete the query
2016-11-20 14:55:37: pid 20834: DEBUG: pool_read: read 5 bytes from backend 1
2016-11-20 14:55:37: pid 20834: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 0 query in progress: 1 doing extended query: 1
2016-11-20 14:55:37: pid 20834: DEBUG: reading backend data packet kind
2016-11-20 14:55:37: pid 20834: DETAIL: backend:0 of 2 kind = 'n'
2016-11-20 14:55:37: pid 20834: DEBUG: reading backend data packet kind
2016-11-20 14:55:37: pid 20834: DETAIL: backend:1 of 2 kind = 'n'
2016-11-20 14:55:37: pid 20834: DEBUG: read_kind_from_backend max_count:2.000000 num_executed_nodes:2
2016-11-20 14:55:37: pid 20834: DEBUG: processing backend response
2016-11-20 14:55:37: pid 20834: DETAIL: received kind 'n'(6e) from backend
2016-11-20 14:55:37: pid 20834: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 0 query in progress: 1 doing extended query: 1
2016-11-20 14:55:37: pid 20834: DEBUG: SimpleForwardToFrontend: packet:n length:0
2016-11-20 14:55:37: pid 20834: DEBUG: session context: unsetting query in progress. DONE
2016-11-20 14:55:37: pid 20834: DEBUG: processing frontend response
2016-11-20 14:55:37: pid 20834: DETAIL: received kind 'E'(45) from frontend
2016-11-20 14:55:37: pid 20834: DEBUG: session context: clearing doing extended query messaging. DONE
2016-11-20 14:55:37: pid 20834: DEBUG: session context: setting doing extended query messaging. DONE
2016-11-20 14:55:37: pid 20834: DEBUG: session context: setting query in progress. DONE
2016-11-20 14:55:37: pid 20834: LOG: DB node id: 0 backend pid: 12781 statement: Execute: create table testtable (testcol int)
2016-11-20 14:55:37: pid 20834: DEBUG: pool_send_and_wait: pool_set_sync_map: 0
2016-11-20 14:55:37: pid 20834: DEBUG: pool_write: to backend: 0 kind:E
2016-11-20 14:55:37: pid 20834: DEBUG: pool_write: to backend: 0 kind:H
2016-11-20 14:55:37: pid 20834: DEBUG: waiting for query response
2016-11-20 14:55:37: pid 20834: DETAIL: waiting for backend:0 to complete the query
2016-11-20 14:55:37: pid 20834: DEBUG: pool_read: read 18 bytes from backend 0
2016-11-20 14:55:37: pid 20834: DEBUG: detect error: kind: C
2016-11-20 14:55:37: pid 20834: DEBUG: detect error: kind: C
2016-11-20 14:55:37: pid 20834: DEBUG: detect error: kind: C
2016-11-20 14:55:37: pid 20834: DEBUG: detect error: kind: C
2016-11-20 14:55:37: pid 20834: LOG: DB node id: 1 backend pid: 6370 statement: Execute: create table testtable (testcol int)
2016-11-20 14:55:37: pid 20834: DEBUG: pool_send_and_wait: pool_set_sync_map: 1
2016-11-20 14:55:37: pid 20834: DEBUG: pool_write: to backend: 1 kind:E
2016-11-20 14:55:37: pid 20834: DEBUG: pool_write: to backend: 1 kind:H
2016-11-20 14:55:37: pid 20834: DEBUG: waiting for query response
2016-11-20 14:55:37: pid 20834: DETAIL: waiting for backend:1 to complete the query
2016-11-20 14:55:37: pid 20834: DEBUG: pool_read: read 18 bytes from backend 1
2016-11-20 14:55:37: pid 20834: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 0 query in progress: 1 doing extended query: 1
2016-11-20 14:55:37: pid 20834: DEBUG: reading backend data packet kind
2016-11-20 14:55:37: pid 20834: DETAIL: backend:0 of 2 kind = 'C'
2016-11-20 14:55:37: pid 20834: DEBUG: reading backend data packet kind
2016-11-20 14:55:37: pid 20834: DETAIL: backend:1 of 2 kind = 'C'
2016-11-20 14:55:37: pid 20834: DEBUG: read_kind_from_backend max_count:2.000000 num_executed_nodes:2
2016-11-20 14:55:37: pid 20834: DEBUG: processing backend response
2016-11-20 14:55:37: pid 20834: DETAIL: received kind 'C'(43) from backend
2016-11-20 14:55:37: pid 20834: DEBUG: session context: setting command success. DONE
2016-11-20 14:55:37: pid 20834: DEBUG: session context: unsetting query in progress. DONE
2016-11-20 14:55:37: pid 20834: DEBUG: processing frontend response
2016-11-20 14:55:37: pid 20834: DETAIL: received kind 'H'(48) from frontend
2016-11-20 14:55:37: pid 20834: DEBUG: session context: clearing doing extended query messaging. DONE
2016-11-20 14:55:37: pid 20834: DEBUG: pool_write: to backend: 0 kind:H
2016-11-20 14:55:37: pid 20834: DEBUG: pool_write: to backend: 1 kind:H
2016-11-20 14:55:37: pid 20834: DEBUG: processing frontend response
2016-11-20 14:55:37: pid 20834: DETAIL: received kind 'S'(53) from frontend
2016-11-20 14:55:37: pid 20834: DEBUG: session context: clearing doing extended query messaging. DONE
2016-11-20 14:55:37: pid 20834: DEBUG: session context: setting doing extended query messaging. DONE
2016-11-20 14:55:37: pid 20834: DEBUG: session context: setting query in progress. DONE
2016-11-20 14:55:37: pid 20834: DEBUG: pool_write: to backend: 0 kind:S
2016-11-20 14:55:37: pid 20834: DEBUG: pool_write: to backend: 1 kind:S
2016-11-20 14:55:37: pid 20834: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 0 query in progress: 1 doing extended query: 1
2016-11-20 14:55:37: pid 20834: DEBUG: pool_read: read 6 bytes from backend 0
2016-11-20 14:55:37: pid 20834: DEBUG: reading backend data packet kind
2016-11-20 14:55:37: pid 20834: DETAIL: backend:0 of 2 kind = 'Z'
2016-11-20 14:55:37: pid 20834: DEBUG: pool_read: read 6 bytes from backend 1
2016-11-20 14:55:37: pid 20834: DEBUG: reading backend data packet kind
2016-11-20 14:55:37: pid 20834: DETAIL: backend:1 of 2 kind = 'Z'
2016-11-20 14:55:37: pid 20834: DEBUG: read_kind_from_backend max_count:2.000000 num_executed_nodes:2
2016-11-20 14:55:37: pid 20834: DEBUG: processing backend response
2016-11-20 14:55:37: pid 20834: DETAIL: received kind 'Z'(5a) from backend
2016-11-20 14:55:37: pid 20834: DEBUG: processing backend response
2016-11-20 14:55:37: pid 20834: DETAIL: Ready For Query received
2016-11-20 14:55:37: pid 20834: DEBUG: session context: clearing ignore till sync. DONE
2016-11-20 14:55:37: pid 20834: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 0 query in progress: 1 doing extended query: 1
2016-11-20 14:55:37: pid 20834: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 0 query in progress: 1 doing extended query: 1
2016-11-20 14:55:37: pid 20834: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 0 query in progress: 1 doing extended query: 1
2016-11-20 14:55:37: pid 20834: DEBUG: reading message length
2016-11-20 14:55:37: pid 20834: DETAIL: slot: 0 length: 5
2016-11-20 14:55:37: pid 20834: DEBUG: reading message length
2016-11-20 14:55:37: pid 20834: DETAIL: slot: 1 length: 5
2016-11-20 14:55:37: pid 20834: DEBUG: processing ReadyForQuery
2016-11-20 14:55:37: pid 20834: DETAIL: transaction state 'I'(49)
2016-11-20 14:55:37: pid 20834: DEBUG: processing ReadyForQuery
2016-11-20 14:55:37: pid 20834: DETAIL: transaction state 'I'(49)
2016-11-20 14:55:37: pid 20834: DEBUG: session context: unsetting query in progress. DONE
2016-11-20 14:55:37: pid 20834: DEBUG: processing frontend response
2016-11-20 14:55:37: pid 20834: DETAIL: received kind 'P'(50) from frontend
2016-11-20 14:55:37: pid 20834: DEBUG: session context: clearing doing extended query messaging. DONE
2016-11-20 14:55:37: pid 20834: DEBUG: session context: setting doing extended query messaging. DONE
2016-11-20 14:55:37: pid 20834: DEBUG: Parse: statement name <>
2016-11-20 14:55:37: pid 20834: DEBUG: make table name from rangevar: tablename:""testtable""
2016-11-20 14:55:37: pid 20834: LOG: DB node id: 0 backend pid: 12781 statement: SELECT count(*) from (SELECT has_function_privilege('master', 'to_regclass(cstring)', 'execute') WHERE EXISTS(SELECT * FROM pg_catalog.pg_proc AS p WHERE p.proname = 'to_regclass')) AS s
2016-11-20 14:55:37: pid 20834: DEBUG: do_query: extended:1 query:"SELECT count(*) from (SELECT has_function_privilege('master', 'to_regclass(cstring)', 'execute') WHERE EXISTS(SELECT * FROM pg_catalog.pg_proc AS p WHERE p.proname = 'to_regclass')) AS s"
2016-11-20 14:55:37: pid 20834: CONTEXT: while searching system catalog, When relcache is missed
2016-11-20 14:55:37: pid 20834: DEBUG: pool_write: to backend: 0 kind:P
2016-11-20 14:55:37: pid 20834: CONTEXT: while searching system catalog, When relcache is missed
2016-11-20 14:55:37: pid 20834: DEBUG: pool_write: to backend: 0 kind:B
2016-11-20 14:55:37: pid 20834: CONTEXT: while searching system catalog, When relcache is missed
2016-11-20 14:55:37: pid 20834: DEBUG: pool_write: to backend: 0 kind:C
2016-11-20 14:55:37: pid 20834: CONTEXT: while searching system catalog, When relcache is missed
2016-11-20 14:55:37: pid 20834: DEBUG: pool_write: to backend: 0 kind:S
2016-11-20 14:55:37: pid 20834: CONTEXT: while searching system catalog, When relcache is missed
2016-11-20 14:55:37: pid 20834: DEBUG: pool_write: to backend: 0 kind:E
2016-11-20 14:55:37: pid 20834: CONTEXT: while searching system catalog, When relcache is missed
2016-11-20 14:55:37: pid 20834: DEBUG: pool_write: to backend: 0 kind:C
2016-11-20 14:55:37: pid 20834: CONTEXT: while searching system catalog, When relcache is missed
2016-11-20 14:55:37: pid 20834: DEBUG: pool_write: to backend: 0 kind:P
2016-11-20 14:55:37: pid 20834: CONTEXT: while searching system catalog, When relcache is missed
2016-11-20 14:55:37: pid 20834: DEBUG: pool_write: to backend: 0 kind:H
2016-11-20 14:55:37: pid 20834: CONTEXT: while searching system catalog, When relcache is missed
2016-11-20 14:55:37: pid 20834: DEBUG: pool_read: read 153 bytes from backend 0
2016-11-20 14:55:37: pid 20834: CONTEXT: while searching system catalog, When relcache is missed
2016-11-20 14:55:37: pid 20834: DEBUG: do_query: kind: '1'
2016-11-20 14:55:37: pid 20834: CONTEXT: while searching system catalog, When relcache is missed
2016-11-20 14:55:37: pid 20834: DEBUG: do_query: received PARSE COMPLETE ('1')
2016-11-20 14:55:37: pid 20834: CONTEXT: while searching system catalog, When relcache is missed
2016-11-20 14:55:37: pid 20834: DEBUG: do_query: kind: '2'
2016-11-20 14:55:37: pid 20834: CONTEXT: while searching system catalog, When relcache is missed
2016-11-20 14:55:37: pid 20834: DEBUG: do_query: received BIND COMPLETE ('2')
2016-11-20 14:55:37: pid 20834: CONTEXT: while searching system catalog, When relcache is missed
2016-11-20 14:55:37: pid 20834: DEBUG: do_query: kind: '3'
2016-11-20 14:55:37: pid 20834: CONTEXT: while searching system catalog, When relcache is missed
2016-11-20 14:55:37: pid 20834: DEBUG: do_query: received CLOSE COMPLETE ('3')
2016-11-20 14:55:37: pid 20834: CONTEXT: while searching system catalog, When relcache is missed
2016-11-20 14:55:37: pid 20834: DEBUG: do_query: kind: 'E'
2016-11-20 14:55:37: pid 20834: CONTEXT: while searching system catalog, When relcache is missed
2016-11-20 14:55:37: pid 20834: FATAL: Backend throw an error message
2016-11-20 14:55:37: pid 20834: DETAIL: Exiting current session because of an error from backend
2016-11-20 14:55:37: pid 20834: HINT: BACKEND Error: "portal "pgpool20834" does not exist"
2016-11-20 14:55:37: pid 20834: CONTEXT: while searching system catalog, When relcache is missed
2016-11-20 14:55:37: pid 20834: DEBUG: pool_write: to backend: 0 kind:X
2016-11-20 14:55:37: pid 20834: DEBUG: pool_write: to backend: 1 kind:X
2016-11-20 14:55:37: pid 20828: DEBUG: reaper handler
2016-11-20 14:55:37: pid 20828: LOG: child process with pid: 20834 exits with status 256
2016-11-20 14:55:37: pid 20828: LOG: fork a new child process with pid: 20841
2016-11-20 14:55:37: pid 20828: DEBUG: reaper handler: exiting normally
2016-11-20 14:55:37: pid 20841: DEBUG: initializing backend status
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment