View Issue Details
| ID | Project | Category | View Status | Date Submitted | Last Update |
|---|---|---|---|---|---|
| 0000327 | Pgpool-II | Bug | public | 2017-08-09 06:42 | 2017-10-03 15:44 |
| Reporter | akshayjain | Assigned To | t-ishii | ||
| Priority | urgent | Severity | major | Reproducibility | always |
| Status | closed | Resolution | open | ||
| Product Version | 3.5.3 | ||||
| Target Version | 3.5.10 | Fixed in Version | 3.5.10 | ||
| Summary | 0000327: Cannot connect to PGPool when caching is enabled | ||||
| Description | I am using redshift driver (com.amazon.redshift.jdbc41.Driver) to connect to pgpool. When the caching is off, the connection goes well and data is fetched from the DB. But when I switch on the caching, I get continuous errors while connecting. | ||||
| Steps To Reproduce | Driver name : com.amazon.redshift.jdbc41.Driver Connection URL: jdbc:postgresql://IP/prodairanalytics?loginTimeout=5&connectTimeout=5&socketTimeout=8&tcpKeepAlive=true&loglevel=1&preferQueryMode='simple' In config file memory_cache_enabled = on # If on, use the memory cache functionality, off by default memqcache_method = 'memcached' | ||||
| Tags | No tags attached. | ||||
|
|
The pgpool error logs are here 2017-08-08 20:59:55: pid 31643: LOG: new connection received 2017-08-08 20:59:55: pid 31643: DETAIL: connecting host=10.0.20.28 port=65124 2017-08-08 20:59:56: pid 31643: LOG: DB node id: 0 backend pid: 1458 statement: SELECT count(*) FROM pg_catalog.pg_proc AS p WHERE p.proname = 'version' AND p.provolatile = 'i' 2017-08-08 20:59:56: pid 31643: FATAL: Backend throw an error message 2017-08-08 20:59:56: pid 31643: DETAIL: Exiting current session because of an error from backend 2017-08-08 20:59:56: pid 31643: HINT: BACKEND Error: "portal "pgpool31643" does not exist" 2017-08-08 20:59:56: pid 31643: CONTEXT: while searching system catalog, When relcache is missed 2017-08-08 20:59:56: pid 31616: LOG: child process with pid: 31643 exits with status 256 2017-08-08 20:59:56: pid 31616: LOG: fork a new child process with pid: 31644 2017-08-08 20:59:56: pid 31644: LOG: new connection received |
|
|
Have you succeeded in connecting to Pgpool-II using psql? |
|
|
Yes, using PSQL I can connect. And I can also access values from cache. I did try connecting through postgres driver as well (org.postgresql:postgresql:9.4-1201-jdbc41) but I face the same issue as redshift driver. |
|
|
Pgpool-II 3.5.3 is pretty old (released in June 2016). Please try newer version. |
|
|
Which version will you recommend me to use? I need it to be compatible with redshift drivers. Also could you tell me the reason for such errors 2017-08-08 20:59:55: pid 31643: LOG: new connection received 2017-08-08 20:59:55: pid 31643: DETAIL: connecting host=10.0.20.28 port=65124 2017-08-08 20:59:56: pid 31643: LOG: DB node id: 0 backend pid: 1458 statement: SELECT count(*) FROM pg_catalog.pg_proc AS p WHERE p.proname = 'version' AND p.provolatile = 'i' 2017-08-08 20:59:56: pid 31643: FATAL: Backend throw an error message |
|
|
I tried with the version 3.6.1 as well, but I see the same behavior. |
|
|
BACKEND Error: "portal "pgpool31643" does not exist": Pgpool-II sent an extended query for querying to the system catalog. "pgpool31643" is a portal name generated by Pgpool-II. The portal was supposed to be created by prior Bind message. This is strange but also I am curious about the "preferQueryMode='simple'" parameter. It looks to force to use simple queries, rather than extended queries. However, the error shows that Pgpool-II thinks that the JDBC driver requests extended query. Can you share JDBC driver debug log or Pgpool-II debug log? |
|
|
I have attached a screenshot of the logs which I see. |
|
|
This is not a debug log. Please start Pgpool-II with -d option to take a debug log. Also can you please share pgpool.con? |
|
|
Here is the debug log: 2017-08-10 22:08:00: pid 16284: DETAIL: backend:0 of 1 kind = 'Z' 2017-08-10 22:08:00: pid 16284: DEBUG: read_kind_from_backend max_count:1.000000 num_executed_nodes:1 2017-08-10 22:08:00: pid 16284: DEBUG: processing backend response 2017-08-10 22:08:00: pid 16284: DETAIL: received kind 'Z'(5a) from backend 2017-08-10 22:08:00: pid 16284: DEBUG: processing backend response 2017-08-10 22:08:00: pid 16284: DETAIL: Ready For Query received 2017-08-10 22:08:00: pid 16284: DEBUG: session context: clearing ignore till sync. DONE 2017-08-10 22:08:00: pid 16284: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 0 query in progress: 0 doing extended query: 0 2017-08-10 22:08:00: pid 16284: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 0 query in progress: 0 doing extended query: 0 2017-08-10 22:08:00: pid 16284: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 0 query in progress: 0 doing extended query: 0 2017-08-10 22:08:00: pid 16284: DEBUG: reading message length 2017-08-10 22:08:00: pid 16284: DETAIL: slot: 0 length: 5 2017-08-10 22:08:00: pid 16284: DEBUG: processing ReadyForQuery 2017-08-10 22:08:00: pid 16284: DETAIL: transaction state ' 2017-08-10 22:08:00: pid 16283: DEBUG: processing frontend response 2017-08-10 22:08:00: pid 16283: DETAIL: received kind 'P'(50) from frontend 2017-08-10 22:08:00: pid 16283: DEBUG: session context: clearing doing extended query messaging. DONE 2017-08-10 22:08:00: pid 16283: DEBUG: session context: setting doing extended query messaging. DONE 2017-08-10 22:08:00: pid 16283: DEBUG: Parse: statement name <PGJDBCSTMT45405144396933> 2017-08-10 22:08:00: pid 16267: DEBUG: pool_write: to backend: 0 kind:p 2017-08-10 22:08:00: pid 16283: DEBUG: session context: setting query in progress. DONE 2017-08-10 22:08:00: pid 16283: DEBUG: checking if query has INSERT INTO, FOR SHARE or FOR UPDATE 2017-08-10 22:08:00: pid 16283: DETAIL: result = 0 2017-08-10 22:08:00: pid 16283: DEBUG: non immutable function walker. checking function "version" 2017-08-10 22:08:00: pid 16283: DEBUG: checking if the function is IMMUTABLE 2017-08-10 22:08:00: pid 16283: DETAIL: relcache created 2017-08-10 22:08:00: pid 16283: LOG: DB node id: 0 backend pid: 23731 statement: SELECT count(*) FROM pg_catalog.pg_proc AS p WHERE p.proname = 'version' AND p.provolatile = 'i' 2017-08-10 22:08:00: pid 16283: DEBUG: do_query: extended:1 query:"SELECT count(*) FROM pg_catalog.pg_proc AS p WHERE p.proname = 'version' AND p.provolatile = 'i'" 2017-08-10 22:08:00: pid 16283: CONTEXT: while searching system catalog, When relcache is missed 2017-08-10 22:08:00: pid 16283: DEBUG: pool_write: to backend: 0 kind:P 2017-08-10 22:08:00: pid 16283: CONTEXT: while searching system catalog, When relcache is missed 2017-08-10 22:08:00: pid 16283: DEBUG: pool_write: to backend: 0 kind:B 2017-08-10 22:08:00: pid 16283: CONTEXT: while searching system catalog, When relcache is missed 2017-08-10 22:08:00: pid 16283: DEBUG: pool_write: to backend: 0 kind:C 2017-08-10 22:08:00: pid 16283: CONTEXT: while searching system catalog, When relcache is missed 2017-08-10 22:08:00: pid 16283: DEBUG: pool_write: to backend: 0 kind:S 2017-08-10 22:08:00: pid 16283: CONTEXT: while searching system catalog, When relcache is missed 2017-08-10 22:08:00: pid 16283: DEBUG: pool_write: to backend: 0 kind:E 2017-08-10 22:08:00: pid 16283: CONTEXT: while searching system catalog, When relcache is missed 2017-08-10 22:08:00: pid 16283: DEBUG: pool_write: to backend: 0 kind:C 2017-08-10 22:08:00: pid 16283: CONTEXT: while searching system catalog, When relcache is missed 2017-08-10 22:08:00: pid 16283: DEBUG: pool_write: to backend: 0 kind:P 2017-08-10 22:08:00: pid 16283: CONTEXT: while searching system catalog, When relcache is missed 2017-08-10 22:08:00: pid 16283: DEBUG: pool_write: to backend: 0 kind:H 2017-08-10 22:08:00: pid 16283: CONTEXT: while searching system catalog, When relcache is missed 2017-08-10 22:08:00: pid 16267: DEBUG: pool_read: read 410 bytes from backend 0 2017-08-10 22:08:00: pid 16267: DEBUG: reading message length 2017-08-10 22:08:00: pid 16267: DETAIL: master slot: 0 length: 28 2017-08-10 22:08:00: pid 16267: DEBUG: process parameter status 2017-08-10 22:08:00: pid 16267: DETAIL: backend:0 name:"client_encoding" value:"UNICODE" 2017-08-10 22:08:00: pid 16267: DEBUG: reading message length 2017-08-10 22:08:00: pid 16267: DETAIL: master slot: 0 length: 23 2017-08-10 22:08:00: pid 16267: DEBUG: process parameter status 2017-08-10 22:08:00: pid 16267: DETAIL: backend:0 name:"datestyle" value:"ISO, MDY" 2017-08-10 22:08:00: pid 16267: DEBUG: reading message length 2017-08-10 22:08:00: pid 16267: DETAIL: master slot: 0 length: 28 2017-08-10 22:08:00: pid 16267: DEBUG: process parameter status 2017-08-10 22:08:00: pid 16267: DETAIL: backend:0 name:"gconf_case_sensitive" value:"on" 2017-08-10 22:08:00: pid 16267: DEBUG: reading message length 2017-08-10 22:08:00: pid 16267: DETAIL: master slot: 0 length: 25 2017-08-10 22:08:00: pid 16267: DEBUG: process parameter status 2017-08-10 22:08:00: pid 16267: DETAIL: backend:0 name:"integer_datetimes" value:"on" 2017-08-10 22:08:00: pid 16267: DEBUG: reading message length 2017-08-10 22:08:00: pid 16267: DETAIL: master slot: 0 length: 21 2017-08-10 22:08:00: pid 16267: DEBUG: process parameter status 2017-08-10 22:08:00: pid 16267: DETAIL: backend:0 name:"is_superuser" value:"off" 2017-08-10 22:08:00: pid 16267: DEBUG: reading message length 2017-08-10 22:08:00: pid 16267: DETAIL: master slot: 0 length: 28 2017-08-10 22:08:00: pid 16267: DEBUG: process parameter status 2017-08-10 22:08:00: pid 16267: DETAIL: backend:0 name:"server_encoding" value:"UNICODE" 2017-08-10 22:08:00: pid 16267: DEBUG: reading message length 2017-08-10 22:08:00: pid 16267: DETAIL: master slot: 0 length: 25 2017-08-10 22:08:00: pid 16267: DEBUG: process parameter status 2017-08-10 22:08:00: pid 16267: DETAIL: backend:0 name:"server_version" value:"8.0.2" 2017-08-10 22:08:00: pid 16277: DEBUG: I am 16277 accept fd 6 2017-08-10 22:08:00: pid 16267: DEBUG: reading message length 2017-08-10 22:08:00: pid 16267: DETAIL: master slot: 0 length: 35 2017-08-10 22:08:00: pid 16267: DEBUG: process parameter status 2017-08-10 22:08:00: pid 16267: DETAIL: backend:0 name:"session_authorization" value:"epcauser" 2017-08-10 22:08:00: pid 16267: DEBUG: reading message length 2017-08-10 22:08:00: pid 16267: DETAIL: master slot: 0 length: 17 2017-08-10 22:08:00: pid 16267: DEBUG: process parameter status 2017-08-10 22:08:00: pid 16267: DETAIL: backend:0 name:"TimeZone" value:"UTC" 2017-08-10 22:08:00: pid 16267: DEBUG: reading message length 2017-08-10 22:08:00: pid 16267: DETAIL: master slot: 0 length: 35 2017-08-10 22:08:00: pid 16267: DEBUG: process parameter status 2017-08-10 22:08:00: pid 16267: DETAIL: backend:0 name:"timezone_abbreviations" value:"Default" 2017-08-10 22:08:00: pid 16277: LOG: new connection received 2017-08-10 22:08:00: pid 16277: DETAIL: connecting host=10.0.20.28 port=64062 2017-08-10 22:08:00: pid 16267: DEBUG: reading message length 2017-08-10 22:08:00: pid 16267: DETAIL: master slot: 0 length: 18 2017-08-10 22:08:00: pid 16267: DEBUG: process parameter status 2017-08-10 22:08:00: pid 16267: DETAIL: backend:0 name:"padb_version" value:"" 2017-08-10 22:08:00: pid 16267: DEBUG: reading message length 2017-08-10 22:08:00: pid 16267: DETAIL: master slot: 0 length: 29 2017-08-10 22:08:00: pid 16267: DEBUG: process parameter status 2017-08-10 22:08:00: pid 16267: DETAIL: backend:0 name:"padb_revision" value:"1304146bea" 2017-08-10 22:08:00: pid 16267: DEBUG: reading message length 2017-08-10 22:08:00: pid 16267: DETAIL: master slot: 0 length: 29 2017-08-10 22:08:00: pid 16267: DEBUG: process parameter status 2017-08-10 22:08:00: pid 16267: DETAIL: backend:0 name:"max_numeric_precision" value:"38" 2017-08-10 22:08:00: pid 16267: DEBUG: reading message length 2017-08-10 22:08:00: pid 16267: DETAIL: master slot: 0 length: 27 2017-08-10 22:08:00: pid 16267: DEBUG: process parameter status 2017-08-10 22:08:00: pid 16267: DETAIL: backend:0 name:"max_varchar_size" value:"65535" 2017-08-10 22:08:00: pid 16267: DEBUG: reading message length 2017-08-10 22:08:00: pid 16267: DETAIL: slot: 0 length: 12 2017-08-10 22:08:00: pid 16267: DEBUG: authentication backend 2017-08-10 22:08:00: pid 16267: DETAIL: cp->info[i]:0x7f1f8f107000 pid:23733 2017-08-10 22:08:00: pid 16277: DEBUG: reading startup packet 2017-08-10 22:08:00: pid 16277: DETAIL: Protocol Major: 3 Minor: 0 database: abc user: abc 2017-08-10 22:08:00: pid 16267: DEBUG: sending backend key data 2017-08-10 22:08:00: pid 16267: DETAIL: send pid 23733 to frontend 2017-08-10 22:08:00: pid 16267: DEBUG: initializing session context 2017-08-10 22:08:00: pid 16267: DETAIL: selected load balancing node: 0 2017-08-10 22:08:00: pid 16267: DEBUG: session context: unsetting query in progress. DONE 2017-08-10 22:08:00: pid 16267: DEBUG: session context: clearing transaction isolation. DONE 2017-08-10 22:08:00: pid 16277: DEBUG: creating new connection to backend 2017-08-10 22:08:00: pid 16277: DETAIL: connecting 0 backend 2017-08-10 22:08:00: pid 16267: DEBUG: session context: clearing writing transaction. DONE 2017-08-10 22:08:00: pid 16267: DEBUG: session context: clearing failed transaction. DONE 2017-08-10 22:08:00: pid 16267: DEBUG: session context: clearing failed transaction. DONE 2017-08-10 22:08:00: pid 16267: DEBUG: session context: clearing skip reading from backends. DONE 2017-08-10 22:08:00: pid 16267: DEBUG: session context: clearing ignore till sync. DONE 2017-08-10 22:08:00: pid 16267: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 0 query in progress: 0 doing extended query: 0 2017-08-10 22:08:00: pid 16267: DEBUG: reading backend data packet kind 2017-08-10 22:08:00: pid 16267: DETAIL: backend:0 of 1 kind = 'Z' 2017-08-10 22:08:00: pid 16267: DEBUG: read_kind_from_backend max_count:1.000000 num_executed_nodes:1 2017-08-10 22:08:00: pid 16267: DEBUG: processing backend response 2017-08-10 22:08:00: pid 16267: DETAIL: received kind 'Z'(5a) from backend 2017-08-10 22:08:00: pid 16267: DEBUG: processing backend response 2017-08-10 22:08:00: pid 16267: DETAIL: Ready For Query received 2017-08-10 22:08:00: pid 16267: DEBUG: session context: clearing ignore till sync. DONE 2017-08-10 22:08:00: pid 16267: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 0 query in progress: 0 doing extended query: 0 2017-08-10 22:08:00: pid 16267: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 0 query in progress: 0 doing extended query: 0 2017-08-10 22:08:00: pid 16267: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 0 query in progress: 0 doing extended query: 0 2017-08-10 22:08:00: pid 16267: DEBUG: reading message length 2017-08-10 22:08:00: pid 16267: DETAIL: slot: 0 length: 5 2017-08-10 22:08:00: pid 16267: DEBUG: processing ReadyForQuery 2017-08-10 22:08:00: pid 16267: DETAIL: transaction state ' 2017-08-10 22:08:00: pid 16283: DEBUG: pool_read: read 156 bytes from backend 0 2017-08-10 22:08:00: pid 16283: CONTEXT: while searching system catalog, When relcache is missed 2017-08-10 22:08:00: pid 16283: DEBUG: do_query: kind: '1' 2017-08-10 22:08:00: pid 16283: CONTEXT: while searching system catalog, When relcache is missed 2017-08-10 22:08:00: pid 16283: DEBUG: do_query: received PARSE COMPLETE ('1') 2017-08-10 22:08:00: pid 16283: CONTEXT: while searching system catalog, When relcache is missed 2017-08-10 22:08:00: pid 16283: DEBUG: do_query: kind: '2' 2017-08-10 22:08:00: pid 16283: CONTEXT: while searching system catalog, When relcache is missed 2017-08-10 22:08:00: pid 16283: DEBUG: do_query: received BIND COMPLETE ('2') 2017-08-10 22:08:00: pid 16283: CONTEXT: while searching system catalog, When relcache is missed 2017-08-10 22:08:00: pid 16283: DEBUG: do_query: kind: '3' 2017-08-10 22:08:00: pid 16283: CONTEXT: while searching system catalog, When relcache is missed 2017-08-10 22:08:00: pid 16283: DEBUG: do_query: received CLOSE COMPLETE ('3') 2017-08-10 22:08:00: pid 16283: CONTEXT: while searching system catalog, When relcache is missed 2017-08-10 22:08:00: pid 16283: DEBUG: do_query: kind: 'E' 2017-08-10 22:08:00: pid 16283: CONTEXT: while searching system catalog, When relcache is missed 2017-08-10 22:08:00: pid 16283: FATAL: Backend throw an error message 2017-08-10 22:08:00: pid 16283: DETAIL: Exiting current session because of an error from backend 2017-08-10 22:08:00: pid 16283: HINT: BACKEND Error: "portal "pgpool16283" does not exist" 2017-08-10 22:08:00: pid 16283: CONTEXT: while searching system catalog, When relcache is missed 2017-08-10 22:08:00: pid 16283: DEBUG: pool_write: to backend: 0 kind:X 2017-08-10 22:08:00: pid 16247: DEBUG: reaper handler 2017-08-10 22:08:00: pid 16247: LOG: child process with pid: 16283 exits with status 256 2017-08-10 22:08:00: pid 16247: LOG: fork a new child process with pid: 16286 2017-08-10 22:08:00: pid 16247: DEBUG: reaper handler: exiting normally 2017-08-10 22:08:00: pid 16286: DEBUG: initializing backend status 2017-08-10 22:08:00: pid 16277: DEBUG: SSL is requested but SSL support is not available 2017-08-10 22:08:00: pid 16277: DEBUG: pool_read: read 13 bytes from backend 0 2017-08-10 22:08:00: pid 16277: DEBUG: reading message length 2017-08-10 22:08:00: pid 16277: DETAIL: slot: 0 length: 12 2017-08-10 22:08:00: pid 16277: DEBUG: authentication backend 2017-08-10 22:08:00: pid 16277: DETAIL: auth kind:5 2017-08-10 22:08:00: pid 16277: DEBUG: authentication backend 2017-08-10 22:08:00: pid 16277: DETAIL: trying md5 authentication 2017-08-10 22:08:00: pid 16277: DEBUG: performing md5 authentication 2017-08-10 22:08:00: pid 16277: DETAIL: DB node id: 0 salt: 3d781db4 2017-08-10 22:08:00: pid 16284: DEBUG: processing frontend response 2017-08-10 22:08:00: pid 16284: DETAIL: received kind 'P'(50) from frontend 2017-08-10 22:08:00: pid 16284: DEBUG: session context: clearing doing extended query messaging. DONE 2017-08-10 22:08:00: pid 16284: DEBUG: session context: setting doing extended query messaging. DONE 2017-08-10 22:08:00: pid 16284: DEBUG: Parse: statement name <PGJDBCSTMT45405325403502> 2017-08-10 22:08:00: pid 16284: DEBUG: session context: setting query in progress. DONE 2017-08-10 22:08:00: pid 16284: DEBUG: checking if query has INSERT INTO, FOR SHARE or FOR UPDATE 2017-08-10 22:08:00: pid 16284: DETAIL: result = 0 2017-08-10 22:08:00: pid 16284: DEBUG: non immutable function walker. checking function "version" 2017-08-10 22:08:00: pid 16284: DEBUG: checking if the function is IMMUTABLE 2017-08-10 22:08:00: pid 16284: DETAIL: relcache created 2017-08-10 22:08:00: pid 16284: LOG: DB node id: 0 backend pid: 23732 statement: SELECT count(*) FROM pg_catalog.pg_proc AS p WHERE p.proname = 'version' AND p.provolatile = 'i' 2017-08-10 22:08:00: pid 16284: DEBUG: do_query: extended:1 query:"SELECT count(*) FROM pg_catalog.pg_proc AS p WHERE p.proname = 'version' AND p.provolatile = 'i'" 2017-08-10 22:08:00: pid 16284: CONTEXT: while searching system catalog, When relcache is missed 2017-08-10 22:08:00: pid 16284: DEBUG: pool_write: to backend: 0 kind:P 2017-08-10 22:08:00: pid 16284: CONTEXT: while searching system catalog, When relcache is missed 2017-08-10 22:08:00: pid 16284: DEBUG: pool_write: to backend: 0 kind:B 2017-08-10 22:08:00: pid 16284: CONTEXT: while searching system catalog, When relcache is missed 2017-08-10 22:08:00: pid 16284: DEBUG: pool_write: to backend: 0 kind:C 2017-08-10 22:08:00: pid 16284: CONTEXT: while searching system catalog, When relcache is missed 2017-08-10 22:08:00: pid 16284: DEBUG: pool_write: to backend: 0 kind:S 2017-08-10 22:08:00: pid 16284: CONTEXT: while searching system catalog, When relcache is missed 2017-08-10 22:08:00: pid 16284: DEBUG: pool_write: to backend: 0 kind:E 2017-08-10 22:08:00: pid 16284: CONTEXT: while searching system catalog, When relcache is missed 2017-08-10 22:08:00: pid 16284: DEBUG: pool_write: to backend: 0 kind:C 2017-08-10 22:08:00: pid 16284: CONTEXT: while searching system catalog, When relcache is missed 2017-08-10 22:08:00: pid 16284: DEBUG: pool_write: to backend: 0 kind:P 2017-08-10 22:08:00: pid 16284: CONTEXT: while searching system catalog, When relcache is missed 2017-08-10 22:08:00: pid 16284: DEBUG: pool_write: to backend: 0 kind:H 2017-08-10 22:08:00: pid 16284: CONTEXT: while searching system catalog, When relcache is missed 2017-08-10 22:08:00: pid 16284: DEBUG: pool_read: read 156 bytes from backend 0 2017-08-10 22:08:00: pid 16284: CONTEXT: while searching system catalog, When relcache is missed 2017-08-10 22:08:00: pid 16284: DEBUG: do_query: kind: '1' 2017-08-10 22:08:00: pid 16284: CONTEXT: while searching system catalog, When relcache is missed 2017-08-10 22:08:00: pid 16284: DEBUG: do_query: received PARSE COMPLETE ('1') 2017-08-10 22:08:00: pid 16284: CONTEXT: while searching system catalog, When relcache is missed 2017-08-10 22:08:00: pid 16284: DEBUG: do_query: kind: '2' 2017-08-10 22:08:00: pid 16284: CONTEXT: while searching system catalog, When relcache is missed 2017-08-10 22:08:00: pid 16284: DEBUG: do_query: received BIND COMPLETE ('2') 2017-08-10 22:08:00: pid 16284: CONTEXT: while searching system catalog, When relcache is missed 2017-08-10 22:08:00: pid 16284: DEBUG: do_query: kind: '3' 2017-08-10 22:08:00: pid 16284: CONTEXT: while searching system catalog, When relcache is missed 2017-08-10 22:08:00: pid 16284: DEBUG: do_query: received CLOSE COMPLETE ('3') 2017-08-10 22:08:00: pid 16284: CONTEXT: while searching system catalog, When relcache is missed 2017-08-10 22:08:00: pid 16284: DEBUG: do_query: kind: 'E' 2017-08-10 22:08:00: pid 16284: CONTEXT: while searching system catalog, When relcache is missed 2017-08-10 22:08:00: pid 16284: FATAL: Backend throw an error message 2017-08-10 22:08:00: pid 16284: DETAIL: Exiting current session because of an error from backend 2017-08-10 22:08:00: pid 16284: HINT: BACKEND Error: "portal "pgpool16284" does not exist" 2017-08-10 22:08:00: pid 16284: CONTEXT: while searching system catalog, When relcache is missed 2017-08-10 22:08:00: pid 16284: DEBUG: pool_write: to backend: 0 kind:X 2017-08-10 22:08:00: pid 16247: DEBUG: reaper handler 2017-08-10 22:08:00: pid 16247: LOG: child process with pid: 16284 exits with status 256 2017-08-10 22:08:00: pid 16247: LOG: fork a new child process with pid: 16287 2017-08-10 22:08:00: pid 16247: DEBUG: reaper handler: exiting normally 2017-08-10 22:08:00: pid 16287: DEBUG: initializing backend status 2017-08-10 22:08:01: pid 16277: DEBUG: pool_write: to backend: 0 kind:p 2017-08-10 22:08:01: pid 16277: DEBUG: pool_read: read 410 bytes from backend 0 2017-08-10 22:08:01: pid 16277: DEBUG: reading message length 2017-08-10 22:08:01: pid 16277: DETAIL: master slot: 0 length: 28 2017-08-10 22:08:01: pid 16277: DEBUG: process parameter status 2017-08-10 22:08:01: pid 16277: DETAIL: backend:0 name:"client_encoding" value:"UNICODE" 2017-08-10 22:08:01: pid 16277: DEBUG: reading message length 2017-08-10 22:08:01: pid 16277: DETAIL: master slot: 0 length: 23 2017-08-10 22:08:01: pid 16277: DEBUG: process parameter status 2017-08-10 22:08:01: pid 16277: DETAIL: backend:0 name:"datestyle" value:"ISO, MDY" 2017-08-10 22:08:01: pid 16277: DEBUG: reading message length 2017-08-10 22:08:01: pid 16277: DETAIL: master slot: 0 length: 28 2017-08-10 22:08:01: pid 16277: DEBUG: process parameter status 2017-08-10 22:08:01: pid 16277: DETAIL: backend:0 name:"gconf_case_sensitive" value:"on" 2017-08-10 22:08:01: pid 16277: DEBUG: reading message length 2017-08-10 22:08:01: pid 16277: DETAIL: master slot: 0 length: 25 2017-08-10 22:08:01: pid 16277: DEBUG: process parameter status 2017-08-10 22:08:01: pid 16277: DETAIL: backend:0 name:"integer_datetimes" value:"on" 2017-08-10 22:08:01: pid 16277: DEBUG: reading message length 2017-08-10 22:08:01: pid 16277: DETAIL: master slot: 0 length: 21 2017-08-10 22:08:01: pid 16277: DEBUG: process parameter status 2017-08-10 22:08:01: pid 16277: DETAIL: backend:0 name:"is_superuser" value:"off" 2017-08-10 22:08:01: pid 16277: DEBUG: reading message length 2017-08-10 22:08:01: pid 16277: DETAIL: master slot: 0 length: 28 2017-08-10 22:08:01: pid 16277: DEBUG: process parameter status 2017-08-10 22:08:01: pid 16277: DETAIL: backend:0 name:"server_encoding" value:"UNICODE" 2017-08-10 22:08:01: pid 16277: DEBUG: reading message length 2017-08-10 22:08:01: pid 16277: DETAIL: master slot: 0 length: 25 2017-08-10 22:08:01: pid 16277: DEBUG: process parameter status 2017-08-10 22:08:01: pid 16277: DETAIL: backend:0 name:"server_version" value:"8.0.2" 2017-08-10 22:08:01: pid 16277: DEBUG: reading message length 2017-08-10 22:08:01: pid 16277: DETAIL: master slot: 0 length: 35 2017-08-10 22:08:01: pid 16277: DEBUG: process parameter status 2017-08-10 22:08:01: pid 16277: DETAIL: backend:0 name:"session_authorization" value:"epcauser" 2017-08-10 22:08:01: pid 16277: DEBUG: reading message length 2017-08-10 22:08:01: pid 16277: DETAIL: master slot: 0 length: 17 2017-08-10 22:08:01: pid 16277: DEBUG: process parameter status 2017-08-10 22:08:01: pid 16277: DETAIL: backend:0 name:"TimeZone" value:"UTC" 2017-08-10 22:08:01: pid 16277: DEBUG: reading message length 2017-08-10 22:08:01: pid 16277: DETAIL: master slot: 0 length: 35 2017-08-10 22:08:01: pid 16277: DEBUG: process parameter status 2017-08-10 22:08:01: pid 16277: DETAIL: backend:0 name:"timezone_abbreviations" value:"Default" 2017-08-10 22:08:01: pid 16277: DEBUG: reading message length 2017-08-10 22:08:01: pid 16277: DETAIL: master slot: 0 length: 18 2017-08-10 22:08:01: pid 16277: DEBUG: process parameter status 2017-08-10 22:08:01: pid 16277: DETAIL: backend:0 name:"padb_version" value:"" 2017-08-10 22:08:01: pid 16277: DEBUG: reading message length 2017-08-10 22:08:01: pid 16277: DETAIL: master slot: 0 length: 29 2017-08-10 22:08:01: pid 16277: DEBUG: process parameter status 2017-08-10 22:08:01: pid 16277: DETAIL: backend:0 name:"padb_revision" value:"1304146bea" 2017-08-10 22:08:01: pid 16277: DEBUG: reading message length 2017-08-10 22:08:01: pid 16277: DETAIL: master slot: 0 length: 29 2017-08-10 22:08:01: pid 16277: DEBUG: process parameter status 2017-08-10 22:08:01: pid 16277: DETAIL: backend:0 name:"max_numeric_precision" value:"38" 2017-08-10 22:08:01: pid 16277: DEBUG: reading message length 2017-08-10 22:08:01: pid 16277: DETAIL: master slot: 0 length: 27 2017-08-10 22:08:01: pid 16277: DEBUG: process parameter status 2017-08-10 22:08:01: pid 16277: DETAIL: backend:0 name:"max_varchar_size" value:"65535" 2017-08-10 22:08:01: pid 16277: DEBUG: reading message length 2017-08-10 22:08:01: pid 16277: DETAIL: slot: 0 length: 12 2017-08-10 22:08:01: pid 16277: DEBUG: authentication backend 2017-08-10 22:08:01: pid 16277: DETAIL: cp->info[i]:0x7f1f8f1b1000 pid:23734 2017-08-10 22:08:01: pid 16277: DEBUG: sending backend key data 2017-08-10 22:08:01: pid 16277: DETAIL: send pid 23734 to frontend 2017-08-10 22:08:01: pid 16277: DEBUG: initializing session context 2017-08-10 22:08:01: pid 16277: DETAIL: selected load balancing node: 0 2017-08-10 22:08:01: pid 16277: DEBUG: session context: unsetting query in progress. DONE 2017-08-10 22:08:01: pid 16277: DEBUG: session context: clearing transaction isolation. DONE 2017-08-10 22:08:01: pid 16277: DEBUG: session context: clearing writing transaction. DONE 2017-08-10 22:08:01: pid 16277: DEBUG: session context: clearing failed transaction. DONE 2017-08-10 22:08:01: pid 16277: DEBUG: session context: clearing failed transaction. DONE 2017-08-10 22:08:01: pid 16277: DEBUG: session context: clearing skip reading from backends. DONE 2017-08-10 22:08:01: pid 16277: DEBUG: session context: clearing ignore till sync. DONE 2017-08-10 22:08:01: pid 16277: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 0 query in progress: 0 doing extended query: 0 2017-08-10 22:08:01: pid 16277: DEBUG: reading backend data packet kind 2017-08-10 22:08:01: pid 16277: DETAIL: backend:0 of 1 kind = 'Z' 2017-08-10 22:08:01: pid 16277: DEBUG: read_kind_from_backend max_count:1.000000 num_executed_nodes:1 2017-08-10 22:08:01: pid 16277: DEBUG: processing backend response 2017-08-10 22:08:01: pid 16277: DETAIL: received kind 'Z'(5a) from backend 2017-08-10 22:08:01: pid 16277: DEBUG: processing backend response 2017-08-10 22:08:01: pid 16277: DETAIL: Ready For Query received 2017-08-10 22:08:01: pid 16277: DEBUG: session context: clearing ignore till sync. DONE 2017-08-10 22:08:01: pid 16277: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 0 query in progress: 0 doing extended query: 0 2017-08-10 22:08:01: pid 16277: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 0 query in progress: 0 doing extended query: 0 2017-08-10 22:08:01: pid 16277: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 0 query in progress: 0 doing extended query: 0 2017-08-10 22:08:01: pid 16277: DEBUG: reading message length 2017-08-10 22:08:01: pid 16277: DETAIL: slot: 0 length: 5 2017-08-10 22:08:01: pid 16277: DEBUG: processing ReadyForQuery 2017-08-10 22:08:01: pid 16277: DETAIL: transaction state ' 2017-08-10 22:08:01: pid 16271: DEBUG: I am 16271 accept fd 6 2017-08-10 22:08:01: pid 16271: LOG: new connection received 2017-08-10 22:08:01: pid 16271: DETAIL: connecting host=10.0.20.28 port=64063 2017-08-10 22:08:01: pid 16271: DEBUG: reading startup packet 2017-08-10 22:08:01: pid 16271: DETAIL: Protocol Major: 3 Minor: 0 database: abc user: abc 2017-08-10 22:08:01: pid 16271: DEBUG: creating new connection to backend |
|
|
Here is the config file: # ---------------------------- # pgPool-II configuration file # ---------------------------- # # This file consists of lines of the form: # # name = value # # Whitespace may be used. Comments are introduced with "#" anywhere on a line. # The complete list of parameter names and allowed values can be found in the # pgPool-II documentation. # # This file is read on server startup and when the server receives a SIGHUP # signal. If you edit the file on a running system, you have to SIGHUP the # server for the changes to take effect, or use "pgpool reload". Some # parameters, which are marked below, require a server shutdown and restart to # take effect. # #------------------------------------------------------------------------------ # CONNECTIONS #------------------------------------------------------------------------------ # - pgpool Connection Settings - listen_addresses = '*' # Host name or IP address to listen on: # '*' for all, '' for no TCP/IP connections # (change requires restart) port = 5432 # Port number # (change requires restart) socket_dir = '/tmp' # Unix domain socket path # The Debian package defaults to # /var/run/postgresql # (change requires restart) listen_backlog_multiplier = 2 # Set the backlog parameter of listen(2) to # num_init_children * listen_backlog_multiplier. # (change requires restart) serialize_accept = off # whether to serialize accept() call to avoid thundering herd problem # (change requires restart) # - pgpool Communication Manager Connection Settings - pcp_listen_addresses = '*' # Host name or IP address for pcp process to listen on: # '*' for all, '' for no TCP/IP connections # (change requires restart) pcp_port = 9898 # Port number for pcp # (change requires restart) pcp_socket_dir = '/tmp' # Unix domain socket path for pcp # The Debian package defaults to # /var/run/postgresql # (change requires restart) # - Backend Connection Settings - backend_hostname0 = 'flights-analytics.c0yzncw4jhwt.us-east-1.redshift.amazonaws.com' # Host name or IP address to connect to for backend 0 backend_port0 = 5439 # Port number for backend 0 backend_weight0 = 1 # Weight for backend 0 (only in load balancing mode) backend_data_directory0 = '/var/lib/pgsql/data' # Data directory for backend 0 backend_flag0 = 'ALLOW_TO_FAILOVER' # Controls various backend behavior # ALLOW_TO_FAILOVER or DISALLOW_TO_FAILOVER #backend_hostname1 = 'host2' #backend_port1 = 5433 #backend_weight1 = 1 #backend_data_directory1 = '/data1' #backend_flag1 = 'ALLOW_TO_FAILOVER' # - Authentication - enable_pool_hba = off # Use pool_hba.conf for client authentication pool_passwd = '' # File name of pool_passwd for md5 authentication. # "" disables pool_passwd. # (change requires restart) authentication_timeout = 60 # Delay in seconds to complete client authentication # 0 means no timeout. # - SSL Connections - ssl = off # Enable SSL support # (change requires restart) #ssl_key = './server.key' # Path to the SSL private key file # (change requires restart) #ssl_cert = './server.cert' # Path to the SSL public certificate file # (change requires restart) #ssl_ca_cert = '' # Path to a single PEM format file # containing CA root certificate(s) # (change requires restart) #ssl_ca_cert_dir = '' # Directory containing CA root certificate(s) # (change requires restart) #------------------------------------------------------------------------------ # POOLS #------------------------------------------------------------------------------ # - Concurrent session and pool size - num_init_children = 32 # Number of concurrent sessions allowed # (change requires restart) max_pool = 4 # Number of connection pool caches per connection # (change requires restart) # - Life time - child_life_time = 300 # Pool exits after being idle for this many seconds child_max_connections = 0 # Pool exits after receiving that many connections # 0 means no exit connection_life_time = 0 # Connection to backend closes after being idle for this many seconds # 0 means no close client_idle_limit = 0 # Client is disconnected after being idle for that many seconds # (even inside an explicit transactions!) # 0 means no disconnection #------------------------------------------------------------------------------ # LOGS #------------------------------------------------------------------------------ # - Where to log - log_destination = 'stderr' # Where to log # Valid values are combinations of stderr, # and syslog. Default to stderr. # - What to log - log_line_prefix = '%t: pid %p: ' # printf-style string to output at beginning of each log line. log_connections = on # Log connections log_hostname = off # Hostname will be shown in ps status # and in logs if connections are logged log_statement = off # Log all statements log_per_node_statement = on # Log all statements # with node and backend informations log_standby_delay = 'none' # Log standby delay # Valid values are combinations of always, # if_over_threshold, none # - Syslog specific - syslog_facility = 'LOCAL0' # Syslog local facility. Default to LOCAL0 syslog_ident = 'pgpool' # Syslog program identification string # Default to 'pgpool' # - Debug - debug_level = 1 # Debug message verbosity level # 0 means no message, 1 or more mean verbose #log_error_verbosity = verbose # terse, default, or verbose messages #client_min_messages = notice # values in order of decreasing detail: # debug5 # debug4 # debug3 # debug2 # debug1 # log # notice # warning # error #log_min_messages = debug5 # values in order of decreasing detail: # debug5 # debug4 # debug3 # debug2 # debug1 # info # notice # warning # error # log # fatal # panic #------------------------------------------------------------------------------ # FILE LOCATIONS #------------------------------------------------------------------------------ pid_file_name = '/var/run/pgpool/pgpool.pid' # PID file name # (change requires restart) logdir = '/var/log/pgpool' # Directory of pgPool status file # (change requires restart) #------------------------------------------------------------------------------ # CONNECTION POOLING #------------------------------------------------------------------------------ connection_cache = on # Activate connection pools # (change requires restart) # Semicolon separated list of queries # to be issued at the end of a session # The default is for 8.3 and later reset_query_list = 'ABORT; DISCARD ALL' # The following one is for 8.2 and before #reset_query_list = 'ABORT; RESET ALL; SET SESSION AUTHORIZATION DEFAULT' #------------------------------------------------------------------------------ # REPLICATION MODE #------------------------------------------------------------------------------ replication_mode = off # Activate replication mode # (change requires restart) replicate_select = off # Replicate SELECT statements # when in replication mode # replicate_select is higher priority than # load_balance_mode. insert_lock = on # Automatically locks a dummy row or a table # with INSERT statements to keep SERIAL data # consistency # Without SERIAL, no lock will be issued lobj_lock_table = '' # When rewriting lo_creat command in # replication mode, specify table name to # lock # - Degenerate handling - replication_stop_on_mismatch = off # On disagreement with the packet kind # sent from backend, degenerate the node # which is most likely "minority" # If off, just force to exit this session failover_if_affected_tuples_mismatch = off # On disagreement with the number of affected # tuples in UPDATE/DELETE queries, then # degenerate the node which is most likely # "minority". # If off, just abort the transaction to # keep the consistency #------------------------------------------------------------------------------ # LOAD BALANCING MODE #------------------------------------------------------------------------------ load_balance_mode = off # Activate load balancing mode # (change requires restart) ignore_leading_white_space = on # Ignore leading white spaces of each query white_function_list = '' # Comma separated list of function names # that don't write to database # Regexp are accepted black_function_list = 'nextval,setval,nextval,setval' # Comma separated list of function names # that write to database # Regexp are accepted database_redirect_preference_list = '' # comma separated list of pairs of database and node id. # example: postgres:primary,mydb[0-4]:1,mydb[5-9]:2' # valid for streaming replicaton mode only. app_name_redirect_preference_list = '' # comma separated list of pairs of app name and node id. # example: 'psql:primary,myapp[0-4]:1,myapp[5-9]:standby' # valid for streaming replicaton mode only. allow_sql_comments = off # if on, ignore SQL comments when judging if load balance or # query cache is possible. # If off, SQL comments effectively prevent the judgment # (pre 3.4 behavior). #------------------------------------------------------------------------------ # MASTER/SLAVE MODE #------------------------------------------------------------------------------ master_slave_mode = off # Activate master/slave mode # (change requires restart) master_slave_sub_mode = 'slony' # Master/slave sub mode # Valid values are combinations slony or # stream. Default is slony. # (change requires restart) # - Streaming - sr_check_period = 0 # Streaming replication check period # Disabled (0) by default sr_check_user = 'nobody' # Streaming replication check user # This is necessary even if you disable # streaming replication delay check with # sr_check_period = 0 sr_check_password = '' # Password for streaming replication check user sr_check_database = 'postgres' # Database name for streaming replication check delay_threshold = 0 # Threshold before not dispatching query to standby node # Unit is in bytes # Disabled (0) by default # - Special commands - follow_master_command = '' # Executes this command after master failover # Special values: # %d = node id # %h = host name # %p = port number # %D = database cluster path # %m = new master node id # %H = hostname of the new master node # %M = old master node id # %P = old primary node id # %r = new master port number # %R = new master database cluster path # %% = '%' character #------------------------------------------------------------------------------ # HEALTH CHECK #------------------------------------------------------------------------------ health_check_period = 0 # Health check period # Disabled (0) by default health_check_timeout = 20 # Health check timeout # 0 means no timeout health_check_user = 'nobody' # Health check user health_check_password = '' # Password for health check user health_check_database = '' # Database name for health check. If '', tries 'postgres' frist, then 'template1' health_check_max_retries = 0 # Maximum number of times to retry a failed health check before giving up. health_check_retry_delay = 1 # Amount of time to wait (in seconds) between retries. connect_timeout = 10000 # Timeout value in milliseconds before giving up to connect to backend. # Default is 10000 ms (10 second). Flaky network user may want to increase # the value. 0 means no timeout. # Note that this value is not only used for health check, # but also for ordinary conection to backend. #------------------------------------------------------------------------------ # FAILOVER AND FAILBACK #------------------------------------------------------------------------------ failover_command = '' # Executes this command at failover # Special values: # %d = node id # %h = host name # %p = port number # %D = database cluster path # %m = new master node id # %H = hostname of the new master node # %M = old master node id # %P = old primary node id # %r = new master port number # %R = new master database cluster path # %% = '%' character failback_command = '' # Executes this command at failback. # Special values: # %d = node id # %h = host name # %p = port number # %D = database cluster path # %m = new master node id # %H = hostname of the new master node # %M = old master node id # %P = old primary node id # %r = new master port number # %R = new master database cluster path # %% = '%' character fail_over_on_backend_error = on # Initiates failover when reading/writing to the # backend communication socket fails # If set to off, pgpool will report an # error and disconnect the session. search_primary_node_timeout = 300 # Timeout in seconds to search for the # primary node when a failover occurs. # 0 means no timeout, keep searching # for a primary node forever. #------------------------------------------------------------------------------ # ONLINE RECOVERY #------------------------------------------------------------------------------ recovery_user = 'nobody' # Online recovery user recovery_password = '' # Online recovery password recovery_1st_stage_command = '' # Executes a command in first stage recovery_2nd_stage_command = '' # Executes a command in second stage recovery_timeout = 90 # Timeout in seconds to wait for the # recovering node's postmaster to start up # 0 means no wait client_idle_limit_in_recovery = 0 # Client is disconnected after being idle # for that many seconds in the second stage # of online recovery # 0 means no disconnection # -1 means immediate disconnection #------------------------------------------------------------------------------ # WATCHDOG #------------------------------------------------------------------------------ # - Enabling - use_watchdog = off # Activates watchdog # (change requires restart) # -Connection to up stream servers - trusted_servers = '' # trusted server list which are used # to confirm network connection # (hostA,hostB,hostC,...) # (change requires restart) ping_path = '/bin' # ping command path # (change requires restart) # - Watchdog communication Settings - wd_hostname = '' # Host name or IP address of this watchdog # (change requires restart) wd_port = 9000 # port number for watchdog service # (change requires restart) wd_priority = 1 # priority of this watchdog in leader election # (change requires restart) wd_authkey = '' # Authentication key for watchdog communication # (change requires restart) wd_ipc_socket_dir = '/tmp' # Unix domain socket path for watchdog IPC socket # The Debian package defaults to # /var/run/postgresql # (change requires restart) # - Virtual IP control Setting - delegate_IP = '' # delegate IP address # If this is empty, virtual IP never bring up. # (change requires restart) if_cmd_path = '/sbin' # path to the directory where if_up/down_cmd exists # (change requires restart) if_up_cmd = 'ip addr add $_IP_$/24 dev eth0 label eth0:0' # startup delegate IP command # (change requires restart) if_down_cmd = 'ip addr del $_IP_$/24 dev eth0' # shutdown delegate IP command # (change requires restart) arping_path = '/usr/sbin' # arping command path # (change requires restart) arping_cmd = 'arping -U $_IP_$ -w 1' # arping command # (change requires restart) # - Behaivor on escalation Setting - clear_memqcache_on_escalation = on # Clear all the query cache on shared memory # when standby pgpool escalate to active pgpool # (= virtual IP holder). # This should be off if client connects to pgpool # not using virtual IP. # (change requires restart) wd_escalation_command = '' # Executes this command at escalation on new active pgpool. # (change requires restart) wd_de_escalation_command = '' # Executes this command when master pgpool resigns from being master. # (change requires restart) # - Lifecheck Setting - # -- common -- wd_monitoring_interfaces_list = '' # Comma separated list of interfaces names to monitor. # if any interface from the list is active the watchdog will # consider the network is fine # 'any' to enable monitoring on all interfaces except loopback # '' to disable monitoring wd_lifecheck_method = 'heartbeat' # Method of watchdog lifecheck ('heartbeat' or 'query' or 'external') # (change requires restart) wd_interval = 10 # lifecheck interval (sec) > 0 # (change requires restart) # -- heartbeat mode -- wd_heartbeat_port = 9694 # Port number for receiving heartbeat signal # (change requires restart) wd_heartbeat_keepalive = 2 # Interval time of sending heartbeat signal (sec) # (change requires restart) wd_heartbeat_deadtime = 30 # Deadtime interval for heartbeat signal (sec) # (change requires restart) heartbeat_destination0 = 'host0_ip1' # Host name or IP address of destination 0 # for sending heartbeat signal. # (change requires restart) heartbeat_destination_port0 = 9694 # Port number of destination 0 for sending # heartbeat signal. Usually this is the # same as wd_heartbeat_port. # (change requires restart) heartbeat_device0 = '' # Name of NIC device (such like 'eth0') # used for sending/receiving heartbeat # signal to/from destination 0. # This works only when this is not empty # and pgpool has root privilege. # (change requires restart) #heartbeat_destination1 = 'host0_ip2' #heartbeat_destination_port1 = 9694 #heartbeat_device1 = '' # -- query mode -- wd_life_point = 3 # lifecheck retry times # (change requires restart) wd_lifecheck_query = 'SELECT 1' # lifecheck query to pgpool from watchdog # (change requires restart) wd_lifecheck_dbname = 'template1' # Database name connected for lifecheck # (change requires restart) wd_lifecheck_user = 'nobody' # watchdog user monitoring pgpools in lifecheck # (change requires restart) wd_lifecheck_password = '' # Password for watchdog user in lifecheck # (change requires restart) # - Other pgpool Connection Settings - #other_pgpool_hostname0 = 'host0' # Host name or IP address to connect to for other pgpool 0 # (change requires restart) #other_pgpool_port0 = 5432 # Port number for othet pgpool 0 # (change requires restart) #other_wd_port0 = 9000 # Port number for othet watchdog 0 # (change requires restart) #other_pgpool_hostname1 = 'host1' #other_pgpool_port1 = 5432 #other_wd_port1 = 9000 #------------------------------------------------------------------------------ # OTHERS #------------------------------------------------------------------------------ relcache_expire = 0 # Life time of relation cache in seconds. # 0 means no cache expiration(the default). # The relation cache is used for cache the # query result against PostgreSQL system # catalog to obtain various information # including table structures or if it's a # temporary table or not. The cache is # maintained in a pgpool child local memory # and being kept as long as it survives. # If someone modify the table by using # ALTER TABLE or some such, the relcache is # not consistent anymore. # For this purpose, cache_expiration # controls the life time of the cache. relcache_size = 256 # Number of relation cache # entry. If you see frequently: # "pool_search_relcache: cache replacement happend" # in the pgpool log, you might want to increate this number. check_temp_table = on # If on, enable temporary table check in SELECT statements. # This initiates queries against system catalog of primary/master # thus increases load of master. # If you are absolutely sure that your system never uses temporary tables # and you want to save access to primary/master, you could turn this off. # Default is on. check_unlogged_table = on # If on, enable unlogged table check in SELECT statements. # This initiates queries against system catalog of primary/master # thus increases load of master. # If you are absolutely sure that your system never uses unlogged tables # and you want to save access to primary/master, you could turn this off. # Default is on. #------------------------------------------------------------------------------ # IN MEMORY QUERY MEMORY CACHE #------------------------------------------------------------------------------ memory_cache_enabled = on # If on, use the memory cache functionality, off by default memqcache_method = 'shmem' # Cache storage method. either 'shmem'(shared memory) or # 'memcached'. 'shmem' by default # (change requires restart) memqcache_memcached_host = 'localhost' # Memcached host name or IP address. Mandatory if # memqcache_method = 'memcached'. # Defaults to localhost. # (change requires restart) memqcache_memcached_port = 11211 # Memcached port number. Mondatory if memqcache_method = 'memcached'. # Defaults to 11211. # (change requires restart) memqcache_total_size = 67108864 # Total memory size in bytes for storing memory cache. # Mandatory if memqcache_method = 'shmem'. # Defaults to 64MB. # (change requires restart) memqcache_max_num_cache = 1000000 # Total number of cache entries. Mandatory # if memqcache_method = 'shmem'. # Each cache entry consumes 48 bytes on shared memory. # Defaults to 1,000,000(45.8MB). # (change requires restart) memqcache_expire = 0 # Memory cache entry life time specified in seconds. # 0 means infinite life time. 0 by default. # (change requires restart) memqcache_auto_cache_invalidation = on # If on, invalidation of query cache is triggered by corresponding # DDL/DML/DCL(and memqcache_expire). If off, it is only triggered # by memqcache_expire. on by default. # (change requires restart) memqcache_maxcache = 409600 # Maximum SELECT result size in bytes. # Must be smaller than memqcache_cache_block_size. Defaults to 400KB. # (change requires restart) memqcache_cache_block_size = 1048576 # Cache block size in bytes. Mandatory if memqcache_method = 'shmem'. # Defaults to 1MB. # (change requires restart) memqcache_oiddir = '/var/log/pgpool/oiddir' # Temporary work directory to record table oids # (change requires restart) white_memqcache_table_list = '' # Comma separated list of table names to memcache # that don't write to database # Regexp are accepted black_memqcache_table_list = '' # Comma separated list of table names not to memcache # that don't write to database # Regexp are accepted |
|
|
Can you connect to ordinary PostgreSQL using Pgpool-II withh same parameter? If not, you may hit a bug with Pgpool-II. In this please try to change pgpool.conf: master_slave_mode = on master_slave_sub_mode = 'stream' |
|
|
I tried making these changes in the config, yet facing the same issue. I logged into the pgpool box and tried to connect to it using PSQL, and that worked fine with caching being on as well. Just reiterating - I get this connectivity issue only when caching is switched on, with caching off it is able to connect successfully. |
|
|
I also tried to connect to this PGPool instance through another application using a node redshift driver, and the connection was established. While using Redshift and Postgres driver it is giving me errors. I am using C3 connection pooling as well. |
|
|
Interesting. So Redshift driver + query cache : gives no error PostgreSQL driver + query cache: gives error Can you share redshift driver case (no error) Pgpool-II log? I'm interested in the difference those two cases above. |
|
|
Yes that is correct. Here are the logs for no error case 2017-08-14 17:19:17: pid 4350: DEBUG: session context: clearing ignore till sync. DONE 2017-08-14 17:19:17: pid 4350: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 1 query in progress: 1 doing extended query: 1 2017-08-14 17:19:17: pid 4350: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 1 query in progress: 1 doing extended query: 1 2017-08-14 17:19:17: pid 4350: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 1 query in progress: 1 doing extended query: 1 2017-08-14 17:19:17: pid 4350: DEBUG: reading message length 2017-08-14 17:19:17: pid 4350: DETAIL: slot: 0 length: 5 2017-08-14 17:19:17: pid 4350: DEBUG: processing ReadyForQuery 2017-08-14 17:19:17: pid 4350: DETAIL: transaction state 'I'(49) 2017-08-14 17:19:17: pid 4350: DEBUG: session context: unsetting query in progress. DONE 2017-08-14 17:19:17: pid 4350: DEBUG: processing frontend response 2017-08-14 17:19:17: pid 4350: DETAIL: received kind 'P'(50) from frontend 2017-08-14 17:19:17: pid 4350: DEBUG: session context: clearing doing extended query messaging. DONE 2017-08-14 17:19:17: pid 4350: DEBUG: session context: setting doing extended query messaging. DONE 2017-08-14 17:19:17: pid 4350: DEBUG: Parse: statement name <PGJDBCSTMT18575271149039> 2017-08-14 17:19:17: pid 4350: DEBUG: session context: setting query in progress. DONE 2017-08-14 17:19:17: pid 4350: DEBUG: checking if query has INSERT INTO, FOR SHARE or FOR UPDATE 2017-08-14 17:19:17: pid 4350: DETAIL: result = 0 2017-08-14 17:19:17: pid 4350: DEBUG: decide where to send the queries 2017-08-14 17:19:17: pid 4350: DETAIL: destination = 2 for query= "select version()" 2017-08-14 17:19:17: pid 4350: DEBUG: session context: setting query in progress. DONE 2017-08-14 17:19:17: pid 4350: LOG: DB node id: 0 backend pid: 16934 statement: Parse: select version() 2017-08-14 17:19:17: pid 4350: DEBUG: pool_send_and_wait: pool_set_sync_map: 0 2017-08-14 17:19:17: pid 4350: DEBUG: pool_write: to backend: 0 kind:P 2017-08-14 17:19:17: pid 4350: DEBUG: called by pool_add_sent_message: sent message: address: 0x24ad3d0 kind: P name: =PGJDBCSTMT18575271149039= 2017-08-14 17:19:17: pid 4350: DEBUG: session context: unsetting query in progress. DONE 2017-08-14 17:19:17: pid 4350: DEBUG: processing frontend response 2017-08-14 17:19:17: pid 4350: DETAIL: received kind 'B'(42) from frontend 2017-08-14 17:19:17: pid 4350: DEBUG: session context: clearing doing extended query messaging. DONE 2017-08-14 17:19:17: pid 4350: DEBUG: session context: setting doing extended query messaging. DONE 2017-08-14 17:19:17: pid 4350: DEBUG: Bind: waiting for master completing the query 2017-08-14 17:19:17: pid 4350: DEBUG: session context: setting query in progress. DONE 2017-08-14 17:19:17: pid 4350: LOG: DB node id: 0 backend pid: 16934 statement: B message 2017-08-14 17:19:17: pid 4350: DEBUG: pool_send_and_wait: pool_set_sync_map: 0 2017-08-14 17:19:17: pid 4350: DEBUG: pool_write: to backend: 0 kind:B 2017-08-14 17:19:17: pid 4350: DEBUG: session context: unsetting query in progress. DONE 2017-08-14 17:19:17: pid 4350: DEBUG: called by pool_add_sent_message: sent message: address: 0x24ad470 kind: B name: =PGJDBCPRTL-0-18575271159742= 2017-08-14 17:19:17: pid 4350: DEBUG: processing frontend response 2017-08-14 17:19:17: pid 4350: DETAIL: received kind 'D'(44) from frontend 2017-08-14 17:19:17: pid 4350: DEBUG: session context: clearing doing extended query messaging. DONE 2017-08-14 17:19:17: pid 4350: DEBUG: session context: setting doing extended query messaging. DONE 2017-08-14 17:19:17: pid 4350: DEBUG: Describe: waiting for master completing the query 2017-08-14 17:19:17: pid 4350: DEBUG: session context: setting query in progress. DONE 2017-08-14 17:19:17: pid 4350: LOG: DB node id: 0 backend pid: 16934 statement: D message 2017-08-14 17:19:17: pid 4350: DEBUG: pool_send_and_wait: pool_set_sync_map: 0 2017-08-14 17:19:17: pid 4350: DEBUG: pool_write: to backend: 0 kind:D 2017-08-14 17:19:17: pid 4350: DEBUG: session context: unsetting query in progress. DONE 2017-08-14 17:19:17: pid 4350: DEBUG: processing frontend response 2017-08-14 17:19:17: pid 4350: DETAIL: received kind 'E'(45) from frontend 2017-08-14 17:19:17: pid 4350: DEBUG: session context: clearing doing extended query messaging. DONE 2017-08-14 17:19:17: pid 4350: DEBUG: session context: setting doing extended query messaging. DONE 2017-08-14 17:19:17: pid 4350: DEBUG: session context: setting query in progress. DONE 2017-08-14 17:19:17: pid 4350: LOG: DB node id: 0 backend pid: 16934 statement: Execute: select version() 2017-08-14 17:19:17: pid 4350: DEBUG: pool_send_and_wait: pool_set_sync_map: 0 2017-08-14 17:19:17: pid 4350: DEBUG: pool_write: to backend: 0 kind:E 2017-08-14 17:19:17: pid 4350: DEBUG: pool_write: to backend: 0 kind:H 2017-08-14 17:19:17: pid 4350: DEBUG: pool_send_and_wait: send flush message to 0 2017-08-14 17:19:17: pid 4350: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 1 query in progress: 1 doing extended query: 1 2017-08-14 17:19:17: pid 4350: DEBUG: reading backend data packet kind 2017-08-14 17:19:17: pid 4350: DETAIL: master node id: 0 2017-08-14 17:19:17: pid 4350: DEBUG: pool_read: read 186 bytes from backend 0 2017-08-14 17:19:17: pid 4350: DEBUG: reading backend data packet kind 2017-08-14 17:19:17: pid 4350: DETAIL: backend:0 of 1 kind = '1' 2017-08-14 17:19:17: pid 4350: DEBUG: read_kind_from_backend max_count:1.000000 num_executed_nodes:1 2017-08-14 17:19:17: pid 4350: DEBUG: processing backend response 2017-08-14 17:19:17: pid 4350: DETAIL: received kind '1'(31) from backend 2017-08-14 17:19:17: pid 4350: DEBUG: called by pool_add_sent_message: sent message: address: 0x24ad470 kind: B name: =PGJDBCPRTL-0-18575271159742= 2017-08-14 17:19:17: pid 4350: DEBUG: adding sent message to list 2017-08-14 17:19:17: pid 4350: DETAIL: adding exactly same message is prohibited 2017-08-14 17:19:17: pid 4350: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 1 query in progress: 1 doing extended query: 1 2017-08-14 17:19:17: pid 4350: DEBUG: SimpleForwardToFrontend: packet:1 length:0 2017-08-14 17:19:17: pid 4350: DEBUG: session context: setting command success. DONE 2017-08-14 17:19:17: pid 4350: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 1 query in progress: 1 doing extended query: 1 2017-08-14 17:19:17: pid 4350: DEBUG: reading backend data packet kind 2017-08-14 17:19:17: pid 4350: DETAIL: master node id: 0 2017-08-14 17:19:17: pid 4350: DEBUG: reading backend data packet kind 2017-08-14 17:19:17: pid 4350: DETAIL: backend:0 of 1 kind = '2' 2017-08-14 17:19:17: pid 4350: DEBUG: read_kind_from_backend max_count:1.000000 num_executed_nodes:1 2017-08-14 17:19:17: pid 4350: DEBUG: processing backend response 2017-08-14 17:19:17: pid 4350: DETAIL: received kind '2'(32) from backend 2017-08-14 17:19:17: pid 4350: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 1 query in progress: 1 doing extended query: 1 2017-08-14 17:19:17: pid 4350: DEBUG: SimpleForwardToFrontend: packet:2 length:0 2017-08-14 17:19:17: pid 4350: DEBUG: session context: setting command success. DONE 2017-08-14 17:19:17: pid 4350: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 1 query in progress: 1 doing extended query: 1 2017-08-14 17:19:17: pid 4350: DEBUG: reading backend data packet kind 2017-08-14 17:19:17: pid 4350: DETAIL: master node id: 0 2017-08-14 17:19:17: pid 4350: DEBUG: reading backend data packet kind 2017-08-14 17:19:17: pid 4350: DETAIL: backend:0 of 1 kind = 'T' 2017-08-14 17:19:17: pid 4350: DEBUG: read_kind_from_backend max_count:1.000000 num_executed_nodes:1 2017-08-14 17:19:17: pid 4350: DEBUG: processing backend response 2017-08-14 17:19:17: pid 4350: DETAIL: received kind 'T'(54) from backend 2017-08-14 17:19:17: pid 4350: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 1 query in progress: 1 doing extended query: 1 2017-08-14 17:19:17: pid 4350: DEBUG: SimpleForwardToFrontend: packet:T length:28 2017-08-14 17:19:17: pid 4350: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 1 query in progress: 1 doing extended query: 1 2017-08-14 17:19:17: pid 4350: DEBUG: reading backend data packet kind 2017-08-14 17:19:17: pid 4350: DETAIL: master node id: 0 2017-08-14 17:19:17: pid 4350: DEBUG: reading backend data packet kind 2017-08-14 17:19:17: pid 4350: DETAIL: backend:0 of 1 kind = 'D' 2017-08-14 17:19:17: pid 4350: DEBUG: read_kind_from_backend max_count:1.000000 num_executed_nodes:1 2017-08-14 17:19:17: pid 4350: DEBUG: processing backend response 2017-08-14 17:19:17: pid 4350: DETAIL: received kind 'D'(44) from backend 2017-08-14 17:19:17: pid 4350: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 1 query in progress: 1 doing extended query: 1 2017-08-14 17:19:17: pid 4350: DEBUG: SimpleForwardToFrontend: packet:D length:126 2017-08-14 17:19:17: pid 4350: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 1 query in progress: 1 doing extended query: 1 2017-08-14 17:19:17: pid 4350: DEBUG: reading backend data packet kind 2017-08-14 17:19:17: pid 4350: DETAIL: master node id: 0 2017-08-14 17:19:17: pid 4350: DEBUG: reading backend data packet kind 2017-08-14 17:19:17: pid 4350: DETAIL: backend:0 of 1 kind = 'C' 2017-08-14 17:19:17: pid 4350: DEBUG: read_kind_from_backend max_count:1.000000 num_executed_nodes:1 2017-08-14 17:19:17: pid 4350: DEBUG: processing backend response 2017-08-14 17:19:17: pid 4350: DETAIL: received kind 'C'(43) from backend 2017-08-14 17:19:17: pid 4350: DEBUG: session context: unsetting query in progress. DONE 2017-08-14 17:19:17: pid 4350: DEBUG: session context: setting command success. DONE 2017-08-14 17:19:17: pid 4350: DEBUG: processing frontend response 2017-08-14 17:19:17: pid 4350: DETAIL: received kind 'H'(48) from frontend 2017-08-14 17:19:17: pid 4350: DEBUG: session context: clearing doing extended query messaging. DONE 2017-08-14 17:19:17: pid 4350: DEBUG: pool_write: to backend: 0 kind:H 2017-08-14 17:19:17: pid 4350: DEBUG: processing frontend response 2017-08-14 17:19:17: pid 4350: DETAIL: received kind 'S'(53) from frontend 2017-08-14 17:19:17: pid 4350: DEBUG: session context: clearing doing extended query messaging. DONE 2017-08-14 17:19:17: pid 4350: DEBUG: session context: setting doing extended query messaging. DONE 2017-08-14 17:19:17: pid 4350: DEBUG: session context: setting query in progress. DONE 2017-08-14 17:19:17: pid 4350: DEBUG: pool_write: to backend: 0 kind:S 2017-08-14 17:19:17: pid 4350: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 1 query in progress: 1 doing extended query: 1 2017-08-14 17:19:17: pid 4350: DEBUG: reading backend data packet kind 2017-08-14 17:19:17: pid 4350: DETAIL: master node id: 0 2017-08-14 17:19:17: pid 4350: DEBUG: pool_read: read 6 bytes from backend 0 2017-08-14 17:19:17: pid 4350: DEBUG: reading backend data packet kind 2017-08-14 17:19:17: pid 4350: DETAIL: backend:0 of 1 kind = 'Z' 2017-08-14 17:19:17: pid 4350: DEBUG: read_kind_from_backend max_count:1.000000 num_executed_nodes:1 2017-08-14 17:19:17: pid 4350: DEBUG: processing backend response 2017-08-14 17:19:17: pid 4350: DETAIL: received kind 'Z'(5a) from backend 2017-08-14 17:19:17: pid 4350: DEBUG: processing backend response 2017-08-14 17:19:17: pid 4350: DETAIL: Ready For Query received 2017-08-14 17:19:17: pid 4350: DEBUG: session context: clearing ignore till sync. DONE 2017-08-14 17:19:17: pid 4350: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 1 query in progress: 1 doing extended query: 1 2017-08-14 17:19:17: pid 4350: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 1 query in progress: 1 doing extended query: 1 2017-08-14 17:19:17: pid 4350: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 1 query in progress: 1 doing extended query: 1 2017-08-14 17:19:17: pid 4350: DEBUG: reading message length 2017-08-14 17:19:17: pid 4350: DETAIL: slot: 0 length: 5 2017-08-14 17:19:17: pid 4350: DEBUG: processing ReadyForQuery 2017-08-14 17:19:17: pid 4350: DETAIL: transaction state 'I'(49) 2017-08-14 17:19:17: pid 4350: DEBUG: session context: unsetting query in progress. DONE 2017-08-14 17:19:17: pid 4350: DEBUG: processing frontend response 2017-08-14 17:19:17: pid 4350: DETAIL: received kind 'C'(43) from frontend 2017-08-14 17:19:17: pid 4350: DEBUG: session context: clearing doing extended query messaging. DONE 2017-08-14 17:19:17: pid 4350: DEBUG: session context: setting doing extended query messaging. DONE 2017-08-14 17:19:17: pid 4350: DEBUG: session context: setting query in progress. DONE 2017-08-14 17:19:17: pid 4350: DEBUG: Close: waiting for master completing the query 2017-08-14 17:19:17: pid 4350: DEBUG: session context: setting query in progress. DONE 2017-08-14 17:19:17: pid 4350: LOG: DB node id: 0 backend pid: 16934 statement: C message 2017-08-14 17:19:17: pid 4350: DEBUG: pool_send_and_wait: pool_set_sync_map: 0 2017-08-14 17:19:17: pid 4350: DEBUG: pool_write: to backend: 0 kind:C 2017-08-14 17:19:17: pid 4350: DEBUG: pool_write: to backend: 0 kind:H 2017-08-14 17:19:17: pid 4350: DEBUG: pool_send_and_wait: send flush message to 0 2017-08-14 17:19:17: pid 4350: DEBUG: waiting for query response 2017-08-14 17:19:17: pid 4350: DETAIL: waiting for backend:0 to complete the query 2017-08-14 17:19:17: pid 4350: DEBUG: pool_read: read 5 bytes from backend 0 2017-08-14 17:19:17: pid 4350: DEBUG: pool_pending_message_add: message type:2 message len:26 2017-08-14 17:19:17: pid 4350: DEBUG: session context: unsetting query in progress. DONE 2017-08-14 17:19:17: pid 4350: DEBUG: pool_use_sync_map: we can use sync map: 1 2017-08-14 17:19:17: pid 4350: DEBUG: reading backend data packet kind 2017-08-14 17:19:17: pid 4350: DETAIL: master node id: 0 2017-08-14 17:19:17: pid 4350: DEBUG: reading backend data packet kind 2017-08-14 17:19:17: pid 4350: DETAIL: backend:0 of 1 kind = '3' 2017-08-14 17:19:17: pid 4350: DEBUG: read_kind_from_backend max_count:1.000000 num_executed_nodes:1 2017-08-14 17:19:17: pid 4350: DEBUG: processing backend response 2017-08-14 17:19:17: pid 4350: DETAIL: received kind '3'(33) from backend 2017-08-14 17:19:17: pid 4350: DEBUG: pool_use_sync_map: we can use sync map: 1 2017-08-14 17:19:17: pid 4350: DEBUG: SimpleForwardToFrontend: packet:3 length:0 2017-08-14 17:19:17: pid 4350: DEBUG: session context: setting command success. DONE 2017-08-14 17:19:17: pid 4350: DEBUG: processing frontend response 2017-08-14 17:19:17: pid 4350: DETAIL: received kind 'H'(48) from frontend 2017-08-14 17:19:17: pid 4350: DEBUG: session context: clearing doing extended query messaging. DONE 2017-08-14 17:19:17: pid 4350: DEBUG: pool_write: to backend: 0 kind:H 2017-08-14 17:19:17: pid 4350: DEBUG: processing frontend response 2017-08-14 17:19:17: pid 4350: DETAIL: received kind 'S'(53) from frontend 2017-08-14 17:19:17: pid 4350: DEBUG: session context: clearing doing extended query messaging. DONE 2017-08-14 17:19:17: pid 4350: DEBUG: session context: setting doing extended query messaging. DONE 2017-08-14 17:19:17: pid 4350: DEBUG: session context: setting query in progress. DONE 2017-08-14 17:19:17: pid 4350: DEBUG: pool_write: to backend: 0 kind:S 2017-08-14 17:19:17: pid 4350: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 1 query in progress: 1 doing extended query: 1 2017-08-14 17:19:17: pid 4350: DEBUG: reading backend data packet kind 2017-08-14 17:19:17: pid 4350: DETAIL: master node id: 0 2017-08-14 17:19:17: pid 4350: DEBUG: pool_read: read 6 bytes from backend 0 2017-08-14 17:19:17: pid 4350: DEBUG: reading backend data packet kind 2017-08-14 17:19:17: pid 4350: DETAIL: backend:0 of 1 kind = 'Z' 2017-08-14 17:19:17: pid 4350: DEBUG: read_kind_from_backend max_count:1.000000 num_executed_nodes:1 2017-08-14 17:19:17: pid 4350: DEBUG: processing backend response 2017-08-14 17:19:17: pid 4350: DETAIL: received kind 'Z'(5a) from backend 2017-08-14 17:19:17: pid 4350: DEBUG: processing backend response 2017-08-14 17:19:17: pid 4350: DETAIL: Ready For Query received 2017-08-14 17:19:17: pid 4350: DEBUG: session context: clearing ignore till sync. DONE 2017-08-14 17:19:17: pid 4350: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 1 query in progress: 1 doing extended query: 1 2017-08-14 17:19:17: pid 4350: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 1 query in progress: 1 doing extended query: 1 2017-08-14 17:19:17: pid 4350: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 1 query in progress: 1 doing extended query: 1 2017-08-14 17:19:17: pid 4350: DEBUG: reading message length 2017-08-14 17:19:17: pid 4350: DETAIL: slot: 0 length: 5 2017-08-14 17:19:17: pid 4350: DEBUG: processing ReadyForQuery 2017-08-14 17:19:17: pid 4350: DETAIL: transaction state 'I'(49) 2017-08-14 17:19:17: pid 4350: DEBUG: session context: unsetting query in progress. DONE 2017-08-14 17:19:18: pid 4350: DEBUG: processing frontend response 2017-08-14 17:19:18: pid 4350: DETAIL: received kind 'P'(50) from frontend 2017-08-14 17:19:18: pid 4350: DEBUG: session context: clearing doing extended query messaging. DONE 2017-08-14 17:19:18: pid 4350: DEBUG: session context: setting doing extended query messaging. DONE 2017-08-14 17:19:18: pid 4350: DEBUG: Parse: statement name <PGJDBCSTMT18575626433372> 2017-08-14 17:19:18: pid 4350: LOG: Unable to parse the query: "SELECT TOP 50 cp.originmetro, SUM(cp.TotalPaymentAmountUSD) AS TotalAirlinePaymentusd, SUM(cp.TotalTicketCount) AS TotalAirlineTicketCount, (CASE WHEN SUM(cp.TotalTicketCount)> 0 THEN SUM (cp.TotalPaymentAmountUSD) * 1.00/SUM(cp.TotalTicketCount) ELSE 0 END ) AS Avg_TicketPrice, (CASE WHEN SUM(cp.TotalOriginTicketCount)> 0 THEN SUM(cp.TotalTicketCount)*100.00 / SUM(cp.TotalOriginTicketCount) ELSE 0 END ) AS AirlineOriginTicketMarketShare, (CASE WHEN NVL(MIN (AirlineOriginTicketMarketShare_pp), 0)>0 THEN ((CASE WHEN SUM(cp.TotalOriginTicketCount)> 0 THEN SUM(cp.TotalTicketCount)*100.00 / SUM(cp.TotalOriginTicketCount) ELSE 0 END) - NVL(MIN (AirlineOriginTicketMarketShare_pp), 0)) *100/ NVL(MIN (AirlineOriginTicketMarketShare_pp), 0) ELSE null END) AS AirlineOriginTicketMarketShare_PoP, (CASE WHEN NVL(MIN (AirlineOriginTicketMarketShare_py), 0)>0 THEN ((CASE WHEN SUM(cp.TotalOriginTicketCount)> 0 THEN SUM(cp.TotalTicketCount)*100.00 / SUM(cp.TotalOriginTicketCount) ELSE 0 END) - NVL(MIN (AirlineOriginTicketMarketShare_py), 0)) *100/ NVL(MIN (AirlineOriginTicketMarketShare_py), 0) ELSE null END) AS AirlineOriginTicketMarketShare_YoY FROM epca.airline_origin_count cp LEFT JOIN (SELECT pp.originmetro, (CASE WHEN SUM(pp.TotalOriginTicketCount)> 0 THEN SUM(pp.TotalTicketCount)*100.00 / SUM(pp.TotalOriginTicketCount) ELSE 0 END ) AS AirlineOriginTicketMarketShare_pp FROM epca.airline_origin_count pp WHERE pp.Date >= DATEADD(day, -(DATEDIFF(day, $1, $2)+1), $3) AND pp.Date <= DATEADD(day, -(DATEDIFF(day, $4, $5)+1), $6) AND pp.Airline = $7 AND pp.tpid = $8 GROUP BY pp.originmetro )pp ON cp.originmetro = pp.originmetro LEFT JOIN (SELECT py.originmetro, (CASE WHEN SUM(py.TotalOriginTicketCount)> 0 THEN SUM(py.TotalTicketCount)*100.00 / SUM(py.TotalOriginTicketCount) ELSE 0 END ) AS AirlineOriginTicketMarketShare_py FROM epca.airline_origin_count py WHERE py.Date >= DATEADD(year, -1, $9) AND py.Date <= DATEADD(year, -1, $10) AND py.Airline = $11 AND py.tpid = $12 GROUP BY py.originmetro )py ON cp.originmetro = py.originmetro WHERE cp.Date >= $13 AND cp.Date <= $14 AND cp.Airline = $15 AND cp.tpid = $16 GROUP BY cp.originmetro ORDER BY TotalAirlineTicketCount DESC , TotalAirlinePaymentUSD DESC" from client 10.0.20.28(59032) 2017-08-14 17:19:18: pid 4350: DEBUG: session context: setting query in progress. DONE 2017-08-14 17:19:18: pid 4350: DEBUG: decide where to send the queries 2017-08-14 17:19:18: pid 4350: DETAIL: destination = 0 for query= "SELECT TOP 50 cp.originmetro, SUM(cp.TotalPaymentAmountUSD) AS TotalAirlinePaymentusd, SUM(cp.TotalTicketCount) AS TotalAirlineTicketCount, (CASE WHEN SUM(cp.TotalTicketCount)> 0 THEN SUM (cp.TotalPaymentAmountUSD) * 1.00/SUM(cp.TotalTicketCount) ELSE 0 END ) AS Avg_TicketPrice, (CASE WHEN SUM(cp.TotalOriginTicketCount)> 0 THEN SUM(cp.TotalTicketCount)*100.00 / SUM(cp.TotalOriginTicketCount) ELSE 0 END ) AS AirlineOriginTicketMarketShare, (CASE WHEN NVL(MIN (AirlineOriginTicketMarketShare_pp), 0)>0 THEN ((CASE WHEN SUM(cp.TotalOriginTicketCount)> 0 THEN SUM(cp.TotalTicketCount)*100.00 / SUM(cp.TotalOriginTicketCount) ELSE 0 END) - NVL(MIN (AirlineOriginTicketMarketShare_pp), 0)) *100/ NVL(MIN (AirlineOriginTicketMarketShare_pp), 0) ELSE null END) AS AirlineOriginTicketMarketShare_PoP, (CASE WHEN NVL(MIN (AirlineOriginTicketMarketShare_py), 0)>0 THEN ((CASE WHEN SUM(cp.TotalOriginTicketCount)> 0 THEN SUM(cp.TotalTicketCount)*100.00 / SUM(cp.TotalOriginTicketCount) ELSE 0 END) - NVL(MIN (AirlineOriginTicketMarketShare_py), 0)) *100/ NVL(MIN (AirlineOriginTicketMarketShare_py), 0) ELSE null END) AS AirlineOriginTicketMarketShare_YoY FROM epca.airline_origin_count cp LEFT JOIN (SELECT pp.originmetro, (CASE WHEN SUM(pp.TotalOriginTicketCount)> 0 THEN SUM(pp.TotalTicketCount)*100.00 / SUM(pp.TotalOriginTicketCount) ELSE 0 END ) AS AirlineOriginTicketMarketShare_pp FROM epca.airline_origin_count pp WHERE pp.Date >= DATEADD(day, -(DATEDIFF(day, $1, $2)+1), $3) AND pp.Date <= DATEADD(day, -(DATEDIFF(day, $4, $5)+1), $6) AND pp.Airline = $7 AND pp.tpid = $8 GROUP BY pp.originmetro )pp ON cp.originmetro = pp.originmetro LEFT JOIN (SELECT py.originmetro, (CASE WHEN SUM(py.TotalOriginTicketCount)> 0 THEN SUM(py.TotalTicketCount)*100.00 / SUM(py.TotalOriginTicketCount) ELSE 0 END ) AS AirlineOriginTicketMarketShare_py FROM epca.airline_origin_count py WHERE py.Date >= DATEADD(year, -1, $9) AND py.Date <= DATEADD(year, -1, $10) AND py.Airline = $11 AND py.tpid = $12 GROUP BY py.originmetro )py ON cp.originmetro = py.originmetro WHERE cp.Date >= $13 AND cp.Date <= $14 AND cp.Airline = $15 AND cp.tpid = $16 GROUP BY cp.originmetro ORDER BY TotalAirlineTicketCount DESC , TotalAirlinePaymentUSD DESC" 2017-08-14 17:19:18: pid 4350: DEBUG: session context: setting query in progress. DONE 2017-08-14 17:19:18: pid 4350: LOG: DB node id: 0 backend pid: 16934 statement: Parse: SELECT TOP 50 cp.originmetro, SUM(cp.TotalPaymentAmountUSD) AS TotalAirlinePaymentusd, SUM(cp.TotalTicketCount) AS TotalAirlineTicketCount, (CASE WHEN SUM(cp.TotalTicketCount)> 0 THEN SUM (cp.TotalPaymentAmountUSD) * 1.00/SUM(cp.TotalTicketCount) ELSE 0 END ) AS Avg_TicketPrice, (CASE WHEN SUM(cp.TotalOriginTicketCount)> 0 THEN SUM(cp.TotalTicketCount)*100.00 / SUM(cp.TotalOriginTicketCount) ELSE 0 END ) AS AirlineOriginTicketMarketShare, (CASE WHEN NVL(MIN (AirlineOriginTicketMarketShare_pp), 0)>0 THEN ((CASE WHEN SUM(cp.TotalOriginTicketCount)> 0 THEN SUM(cp.TotalTicketCount)*100.00 / SUM(cp.TotalOriginTicketCount) ELSE 0 END) - NVL(MIN (AirlineOriginTicketMarketShare_pp), 0)) *100/ NVL(MIN (AirlineOriginTicketMarketShare_pp), 0) ELSE null END) AS AirlineOriginTicketMarketShare_PoP, (CASE WHEN NVL(MIN (AirlineOriginTicketMarketShare_py), 0)>0 THEN ((CASE WHEN SUM(cp.TotalOriginTicketCount)> 0 THEN SUM(cp.TotalTicketCount)*100.00 / SUM(cp.TotalOriginTicketCount) ELSE 0 END) - NVL(MIN (AirlineOriginTi 2017-08-14 17:19:18: pid 4350: DEBUG: pool_send_and_wait: pool_set_sync_map: 0 2017-08-14 17:19:18: pid 4350: DEBUG: pool_write: to backend: 0 kind:P 2017-08-14 17:19:18: pid 4350: DEBUG: called by pool_add_sent_message: sent message: address: 0x24ad690 kind: P name: =PGJDBCSTMT18575626433372= 2017-08-14 17:19:18: pid 4350: DEBUG: session context: unsetting query in progress. DONE 2017-08-14 17:19:18: pid 4350: DEBUG: processing frontend response 2017-08-14 17:19:18: pid 4350: DETAIL: received kind 'D'(44) from frontend 2017-08-14 17:19:18: pid 4350: DEBUG: session context: clearing doing extended query messaging. DONE 2017-08-14 17:19:18: pid 4350: DEBUG: session context: setting doing extended query messaging. DONE 2017-08-14 17:19:18: pid 4350: DEBUG: Describe: waiting for master completing the query 2017-08-14 17:19:18: pid 4350: DEBUG: session context: setting query in progress. DONE 2017-08-14 17:19:18: pid 4350: LOG: DB node id: 0 backend pid: 16934 statement: D message 2017-08-14 17:19:18: pid 4350: DEBUG: pool_send_and_wait: pool_set_sync_map: 0 2017-08-14 17:19:18: pid 4350: DEBUG: pool_write: to backend: 0 kind:D 2017-08-14 17:19:18: pid 4350: DEBUG: session context: unsetting query in progress. DONE 2017-08-14 17:19:18: pid 4350: DEBUG: processing frontend response 2017-08-14 17:19:18: pid 4350: DETAIL: received kind 'H'(48) from frontend 2017-08-14 17:19:18: pid 4350: DEBUG: session context: clearing doing extended query messaging. DONE 2017-08-14 17:19:18: pid 4350: DEBUG: pool_write: to backend: 0 kind:H 2017-08-14 17:19:18: pid 4350: DEBUG: processing frontend response 2017-08-14 17:19:18: pid 4350: DETAIL: received kind 'S'(53) from frontend 2017-08-14 17:19:18: pid 4350: DEBUG: session context: clearing doing extended query messaging. DONE 2017-08-14 17:19:18: pid 4350: DEBUG: session context: setting doing extended query messaging. DONE 2017-08-14 17:19:18: pid 4350: DEBUG: session context: setting query in progress. DONE 2017-08-14 17:19:18: pid 4350: DEBUG: pool_write: to backend: 0 kind:S 2017-08-14 17:19:18: pid 4350: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 1 query in progress: 1 doing extended query: 1 2017-08-14 17:19:18: pid 4350: DEBUG: reading backend data packet kind 2017-08-14 17:19:18: pid 4350: DETAIL: master node id: 0 2017-08-14 17:19:18: pid 4350: DEBUG: pool_read: read 385 bytes from backend 0 2017-08-14 17:19:18: pid 4350: DEBUG: reading backend data packet kind 2017-08-14 17:19:18: pid 4350: DETAIL: backend:0 of 1 kind = '1' 2017-08-14 17:19:18: pid 4350: DEBUG: read_kind_from_backend max_count:1.000000 num_executed_nodes:1 2017-08-14 17:19:18: pid 4350: DEBUG: processing backend response 2017-08-14 17:19:18: pid 4350: DETAIL: received kind '1'(31) from backend 2017-08-14 17:19:18: pid 4350: DEBUG: called by pool_add_sent_message: sent message: address: 0x24ad690 kind: P name: =PGJDBCSTMT18575626433372= 2017-08-14 17:19:18: pid 4350: DEBUG: adding sent message to list 2017-08-14 17:19:18: pid 4350: DETAIL: adding exactly same message is prohibited 2017-08-14 17:19:18: pid 4350: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 1 query in progress: 1 doing extended query: 1 2017-08-14 17:19:18: pid 4350: DEBUG: SimpleForwardToFrontend: packet:1 length:0 2017-08-14 17:19:18: pid 4350: DEBUG: session context: setting command success. DONE 2017-08-14 17:19:18: pid 4350: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 1 query in progress: 1 doing extended query: 1 2017-08-14 17:19:18: pid 4350: DEBUG: reading backend data packet kind 2017-08-14 17:19:18: pid 4350: DETAIL: master node id: 0 2017-08-14 17:19:18: pid 4350: DEBUG: reading backend data packet kind 2017-08-14 17:19:18: pid 4350: DETAIL: backend:0 of 1 kind = 't' 2017-08-14 17:19:18: pid 4350: DEBUG: read_kind_from_backend max_count:1.000000 num_executed_nodes:1 2017-08-14 17:19:18: pid 4350: DEBUG: processing backend response 2017-08-14 17:19:18: pid 4350: DETAIL: received kind 't'(74) from backend 2017-08-14 17:19:18: pid 4350: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 1 query in progress: 1 doing extended query: 1 2017-08-14 17:19:18: pid 4350: DEBUG: SimpleForwardToFrontend: packet:t length:66 2017-08-14 17:19:18: pid 4350: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 1 query in progress: 1 doing extended query: 1 2017-08-14 17:19:18: pid 4350: DEBUG: reading backend data packet kind 2017-08-14 17:19:18: pid 4350: DETAIL: master node id: 0 2017-08-14 17:19:18: pid 4350: DEBUG: reading backend data packet kind 2017-08-14 17:19:18: pid 4350: DETAIL: backend:0 of 1 kind = 'T' 2017-08-14 17:19:18: pid 4350: DEBUG: read_kind_from_backend max_count:1.000000 num_executed_nodes:1 2017-08-14 17:19:18: pid 4350: DEBUG: processing backend response 2017-08-14 17:19:18: pid 4350: DETAIL: received kind 'T'(54) from backend 2017-08-14 17:19:18: pid 4350: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 1 query in progress: 1 doing extended query: 1 2017-08-14 17:19:18: pid 4350: DEBUG: SimpleForwardToFrontend: packet:T length:304 2017-08-14 17:19:18: pid 4350: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 1 query in progress: 1 doing extended query: 1 2017-08-14 17:19:18: pid 4350: DEBUG: reading backend data packet kind 2017-08-14 17:19:18: pid 4350: DETAIL: master node id: 0 2017-08-14 17:19:18: pid 4350: DEBUG: pool_read: read 6 bytes from backend 0 2017-08-14 17:19:18: pid 4350: DEBUG: reading backend data packet kind 2017-08-14 17:19:18: pid 4350: DETAIL: backend:0 of 1 kind = 'Z' 2017-08-14 17:19:18: pid 4350: DEBUG: read_kind_from_backend max_count:1.000000 num_executed_nodes:1 2017-08-14 17:19:18: pid 4350: DEBUG: processing backend response 2017-08-14 17:19:18: pid 4350: DETAIL: received kind 'Z'(5a) from backend 2017-08-14 17:19:18: pid 4350: DEBUG: processing backend response 2017-08-14 17:19:18: pid 4350: DETAIL: Ready For Query received 2017-08-14 17:19:18: pid 4350: DEBUG: session context: clearing ignore till sync. DONE 2017-08-14 17:19:18: pid 4350: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 1 query in progress: 1 doing extended query: 1 2017-08-14 17:19:18: pid 4350: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 1 query in progress: 1 doing extended query: 1 2017-08-14 17:19:18: pid 4350: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 1 query in progress: 1 doing extended query: 1 2017-08-14 17:19:18: pid 4350: DEBUG: reading message length 2017-08-14 17:19:18: pid 4350: DETAIL: slot: 0 length: 5 2017-08-14 17:19:18: pid 4350: DEBUG: processing ReadyForQuery 2017-08-14 17:19:18: pid 4350: DETAIL: transaction state 'I'(49) 2017-08-14 17:19:18: pid 4350: DEBUG: session context: unsetting query in progress. DONE 2017-08-14 17:19:18: pid 4350: DEBUG: processing frontend response 2017-08-14 17:19:18: pid 4350: DETAIL: received kind 'C'(43) from frontend 2017-08-14 17:19:18: pid 4350: DEBUG: session context: clearing doing extended query messaging. DONE 2017-08-14 17:19:18: pid 4350: DEBUG: session context: setting doing extended query messaging. DONE 2017-08-14 17:19:18: pid 4350: DEBUG: session context: setting query in progress. DONE 2017-08-14 17:19:18: pid 4350: DEBUG: Close: waiting for master completing the query 2017-08-14 17:19:18: pid 4350: DEBUG: session context: setting query in progress. DONE 2017-08-14 17:19:18: pid 4350: LOG: DB node id: 0 backend pid: 16934 statement: C message 2017-08-14 17:19:18: pid 4350: DEBUG: pool_send_and_wait: pool_set_sync_map: 0 2017-08-14 17:19:18: pid 4350: DEBUG: pool_write: to backend: 0 kind:C 2017-08-14 17:19:18: pid 4350: DEBUG: pool_write: to backend: 0 kind:H 2017-08-14 17:19:18: pid 4350: DEBUG: pool_send_and_wait: send flush message to 0 2017-08-14 17:19:18: pid 4350: DEBUG: waiting for query response 2017-08-14 17:19:18: pid 4350: DETAIL: waiting for backend:0 to complete the query 2017-08-14 17:19:18: pid 4350: DEBUG: pool_read: read 5 bytes from backend 0 2017-08-14 17:19:18: pid 4350: DEBUG: pool_pending_message_add: message type:2 message len:26 2017-08-14 17:19:18: pid 4350: DEBUG: session context: unsetting query in progress. DONE 2017-08-14 17:19:18: pid 4350: DEBUG: pool_use_sync_map: we can use sync map: 1 2017-08-14 17:19:18: pid 4350: DEBUG: reading backend data packet kind 2017-08-14 17:19:18: pid 4350: DETAIL: master node id: 0 2017-08-14 17:19:18: pid 4350: DEBUG: reading backend data packet kind 2017-08-14 17:19:18: pid 4350: DETAIL: backend:0 of 1 kind = '3' 2017-08-14 17:19:18: pid 4350: DEBUG: read_kind_from_backend max_count:1.000000 num_executed_nodes:1 2017-08-14 17:19:18: pid 4350: DEBUG: processing backend response 2017-08-14 17:19:18: pid 4350: DETAIL: received kind '3'(33) from backend 2017-08-14 17:19:18: pid 4350: DEBUG: pool_use_sync_map: we can use sync map: 1 2017-08-14 17:19:18: pid 4350: DEBUG: SimpleForwardToFrontend: packet:3 length:0 2017-08-14 17:19:18: pid 4350: DEBUG: session context: setting command success. DONE 2017-08-14 17:19:18: pid 4350: DEBUG: processing frontend response 2017-08-14 17:19:18: pid 4350: DETAIL: received kind 'H'(48) from frontend 2017-08-14 17:19:18: pid 4350: DEBUG: session context: clearing doing extended query messaging. DONE 2017-08-14 17:19:18: pid 4350: DEBUG: pool_write: to backend: 0 kind:H 2017-08-14 17:19:18: pid 4350: DEBUG: processing frontend response 2017-08-14 17:19:18: pid 4350: DETAIL: received kind 'S'(53) from frontend 2017-08-14 17:19:18: pid 4350: DEBUG: session context: clearing doing extended query messaging. DONE 2017-08-14 17:19:18: pid 4350: DEBUG: session context: setting doing extended query messaging. DONE 2017-08-14 17:19:18: pid 4350: DEBUG: session context: setting query in progress. DONE 2017-08-14 17:19:18: pid 4350: DEBUG: pool_write: to backend: 0 kind:S 2017-08-14 17:19:18: pid 4350: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 1 query in progress: 1 doing extended query: 1 2017-08-14 17:19:18: pid 4350: DEBUG: reading backend data packet kind 2017-08-14 17:19:18: pid 4350: DETAIL: master node id: 0 2017-08-14 17:19:18: pid 4350: DEBUG: pool_read: read 6 bytes from backend 0 2017-08-14 17:19:18: pid 4350: DEBUG: reading backend data packet kind 2017-08-14 17:19:18: pid 4350: DETAIL: backend:0 of 1 kind = 'Z' 2017-08-14 17:19:18: pid 4350: DEBUG: read_kind_from_backend max_count:1.000000 num_executed_nodes:1 2017-08-14 17:19:18: pid 4350: DEBUG: processing backend response 2017-08-14 17:19:18: pid 4350: DETAIL: received kind 'Z'(5a) from backend 2017-08-14 17:19:18: pid 4350: DEBUG: processing backend response 2017-08-14 17:19:18: pid 4350: DETAIL: Ready For Query received 2017-08-14 17:19:18: pid 4350: DEBUG: session context: clearing ignore till sync. DONE 2017-08-14 17:19:18: pid 4350: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 1 query in progress: 1 doing extended query: 1 2017-08-14 17:19:18: pid 4350: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 1 query in progress: 1 doing extended query: 1 2017-08-14 17:19:18: pid 4350: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 1 query in progress: 1 doing extended query: 1 2017-08-14 17:19:18: pid 4350: DEBUG: reading message length 2017-08-14 17:19:18: pid 4350: DETAIL: slot: 0 length: 5 2017-08-14 17:19:18: pid 4350: DEBUG: processing ReadyForQuery 2017-08-14 17:19:18: pid 4350: DETAIL: transaction state 'I'(49) 2017-08-14 17:19:18: pid 4350: DEBUG: session context: unsetting query in progress. DONE 2017-08-14 17:19:18: pid 4350: DEBUG: processing frontend response 2017-08-14 17:19:18: pid 4350: DETAIL: received kind 'P'(50) from frontend 2017-08-14 17:19:18: pid 4350: DEBUG: session context: clearing doing extended query messaging. DONE 2017-08-14 17:19:18: pid 4350: DEBUG: session context: setting doing extended query messaging. DONE 2017-08-14 17:19:18: pid 4350: DEBUG: Parse: statement name <PGJDBCSTMT18575754188604> 2017-08-14 17:19:18: pid 4350: LOG: Unable to parse the query: "SELECT TOP 50 cp.originmetro, SUM(cp.TotalPaymentAmountUSD) AS TotalAirlinePaymentusd, SUM(cp.TotalTicketCount) AS TotalAirlineTicketCount, (CASE WHEN SUM(cp.TotalTicketCount)> 0 THEN SUM (cp.TotalPaymentAmountUSD) * 1.00/SUM(cp.TotalTicketCount) ELSE 0 END ) AS Avg_TicketPrice, (CASE WHEN SUM(cp.TotalOriginTicketCount)> 0 THEN SUM(cp.TotalTicketCount)*100.00 / SUM(cp.TotalOriginTicketCount) ELSE 0 END ) AS AirlineOriginTicketMarketShare, (CASE WHEN NVL(MIN (AirlineOriginTicketMarketShare_pp), 0)>0 THEN ((CASE WHEN SUM(cp.TotalOriginTicketCount)> 0 THEN SUM(cp.TotalTicketCount)*100.00 / SUM(cp.TotalOriginTicketCount) ELSE 0 END) - NVL(MIN (AirlineOriginTicketMarketShare_pp), 0)) *100/ NVL(MIN (AirlineOriginTicketMarketShare_pp), 0) ELSE null END) AS AirlineOriginTicketMarketShare_PoP, (CASE WHEN NVL(MIN (AirlineOriginTicketMarketShare_py), 0)>0 THEN ((CASE WHEN SUM(cp.TotalOriginTicketCount)> 0 THEN SUM(cp.TotalTicketCount)*100.00 / SUM(cp.TotalOriginTicketCount) ELSE 0 END) - NVL(MIN (AirlineOriginTicketMarketShare_py), 0)) *100/ NVL(MIN (AirlineOriginTicketMarketShare_py), 0) ELSE null END) AS AirlineOriginTicketMarketShare_YoY FROM epca.airline_origin_count cp LEFT JOIN (SELECT pp.originmetro, (CASE WHEN SUM(pp.TotalOriginTicketCount)> 0 THEN SUM(pp.TotalTicketCount)*100.00 / SUM(pp.TotalOriginTicketCount) ELSE 0 END ) AS AirlineOriginTicketMarketShare_pp FROM epca.airline_origin_count pp WHERE pp.Date >= DATEADD(day, -(DATEDIFF(day, $1, $2)+1), $3) AND pp.Date <= DATEADD(day, -(DATEDIFF(day, $4, $5)+1), $6) AND pp.Airline = $7 AND pp.tpid = $8 GROUP BY pp.originmetro )pp ON cp.originmetro = pp.originmetro LEFT JOIN (SELECT py.originmetro, (CASE WHEN SUM(py.TotalOriginTicketCount)> 0 THEN SUM(py.TotalTicketCount)*100.00 / SUM(py.TotalOriginTicketCount) ELSE 0 END ) AS AirlineOriginTicketMarketShare_py FROM epca.airline_origin_count py WHERE py.Date >= DATEADD(year, -1, $9) AND py.Date <= DATEADD(year, -1, $10) AND py.Airline = $11 AND py.tpid = $12 GROUP BY py.originmetro )py ON cp.originmetro = py.originmetro WHERE cp.Date >= $13 AND cp.Date <= $14 AND cp.Airline = $15 AND cp.tpid = $16 GROUP BY cp.originmetro ORDER BY TotalAirlineTicketCount DESC , TotalAirlinePaymentUSD DESC" from client 10.0.20.28(59032) 2017-08-14 17:19:18: pid 4350: DEBUG: session context: setting query in progress. DONE 2017-08-14 17:19:18: pid 4350: DEBUG: decide where to send the queries 2017-08-14 17:19:18: pid 4350: DETAIL: destination = 0 for query= "SELECT TOP 50 cp.originmetro, SUM(cp.TotalPaymentAmountUSD) AS TotalAirlinePaymentusd, SUM(cp.TotalTicketCount) AS TotalAirlineTicketCount, (CASE WHEN SUM(cp.TotalTicketCount)> 0 THEN SUM (cp.TotalPaymentAmountUSD) * 1.00/SUM(cp.TotalTicketCount) ELSE 0 END ) AS Avg_TicketPrice, (CASE WHEN SUM(cp.TotalOriginTicketCount)> 0 THEN SUM(cp.TotalTicketCount)*100.00 / SUM(cp.TotalOriginTicketCount) ELSE 0 END ) AS AirlineOriginTicketMarketShare, (CASE WHEN NVL(MIN (AirlineOriginTicketMarketShare_pp), 0)>0 THEN ((CASE WHEN SUM(cp.TotalOriginTicketCount)> 0 THEN SUM(cp.TotalTicketCount)*100.00 / SUM(cp.TotalOriginTicketCount) ELSE 0 END) - NVL(MIN (AirlineOriginTicketMarketShare_pp), 0)) *100/ NVL(MIN (AirlineOriginTicketMarketShare_pp), 0) ELSE null END) AS AirlineOriginTicketMarketShare_PoP, (CASE WHEN NVL(MIN (AirlineOriginTicketMarketShare_py), 0)>0 THEN ((CASE WHEN SUM(cp.TotalOriginTicketCount)> 0 THEN SUM(cp.TotalTicketCount)*100.00 / SUM(cp.TotalOriginTicketCount) ELSE 0 END) - NVL(MIN (AirlineOriginTicketMarketShare_py), 0)) *100/ NVL(MIN (AirlineOriginTicketMarketShare_py), 0) ELSE null END) AS AirlineOriginTicketMarketShare_YoY FROM epca.airline_origin_count cp LEFT JOIN (SELECT pp.originmetro, (CASE WHEN SUM(pp.TotalOriginTicketCount)> 0 THEN SUM(pp.TotalTicketCount)*100.00 / SUM(pp.TotalOriginTicketCount) ELSE 0 END ) AS AirlineOriginTicketMarketShare_pp FROM epca.airline_origin_count pp WHERE pp.Date >= DATEADD(day, -(DATEDIFF(day, $1, $2)+1), $3) AND pp.Date <= DATEADD(day, -(DATEDIFF(day, $4, $5)+1), $6) AND pp.Airline = $7 AND pp.tpid = $8 GROUP BY pp.originmetro )pp ON cp.originmetro = pp.originmetro LEFT JOIN (SELECT py.originmetro, (CASE WHEN SUM(py.TotalOriginTicketCount)> 0 THEN SUM(py.TotalTicketCount)*100.00 / SUM(py.TotalOriginTicketCount) ELSE 0 END ) AS AirlineOriginTicketMarketShare_py FROM epca.airline_origin_count py WHERE py.Date >= DATEADD(year, -1, $9) AND py.Date <= DATEADD(year, -1, $10) AND py.Airline = $11 AND py.tpid = $12 GROUP BY py.originmetro )py ON cp.originmetro = py.originmetro WHERE cp.Date >= $13 AND cp.Date <= $14 AND cp.Airline = $15 AND cp.tpid = $16 GROUP BY cp.originmetro ORDER BY TotalAirlineTicketCount DESC , TotalAirlinePaymentUSD DESC" 2017-08-14 17:19:18: pid 4350: DEBUG: session context: setting query in progress. DONE 2017-08-14 17:19:18: pid 4350: LOG: DB node id: 0 backend pid: 16934 statement: Parse: SELECT TOP 50 cp.originmetro, SUM(cp.TotalPaymentAmountUSD) AS TotalAirlinePaymentusd, SUM(cp.TotalTicketCount) AS TotalAirlineTicketCount, (CASE WHEN SUM(cp.TotalTicketCount)> 0 THEN SUM (cp.TotalPaymentAmountUSD) * 1.00/SUM(cp.TotalTicketCount) ELSE 0 END ) AS Avg_TicketPrice, (CASE WHEN SUM(cp.TotalOriginTicketCount)> 0 THEN SUM(cp.TotalTicketCount)*100.00 / SUM(cp.TotalOriginTicketCount) ELSE 0 END ) AS AirlineOriginTicketMarketShare, (CASE WHEN NVL(MIN (AirlineOriginTicketMarketShare_pp), 0)>0 THEN ((CASE WHEN SUM(cp.TotalOriginTicketCount)> 0 THEN SUM(cp.TotalTicketCount)*100.00 / SUM(cp.TotalOriginTicketCount) ELSE 0 END) - NVL(MIN (AirlineOriginTicketMarketShare_pp), 0)) *100/ NVL(MIN (AirlineOriginTicketMarketShare_pp), 0) ELSE null END) AS AirlineOriginTicketMarketShare_PoP, (CASE WHEN NVL(MIN (AirlineOriginTicketMarketShare_py), 0)>0 THEN ((CASE WHEN SUM(cp.TotalOriginTicketCount)> 0 THEN SUM(cp.TotalTicketCount)*100.00 / SUM(cp.TotalOriginTicketCount) ELSE 0 END) - NVL(MIN (AirlineOriginTi 2017-08-14 17:19:18: pid 4350: DEBUG: pool_send_and_wait: pool_set_sync_map: 0 2017-08-14 17:19:18: pid 4350: DEBUG: pool_write: to backend: 0 kind:P 2017-08-14 17:19:18: pid 4350: DEBUG: called by pool_add_sent_message: sent message: address: 0x24ad830 kind: P name: =PGJDBCSTMT18575754188604= 2017-08-14 17:19:18: pid 4350: DEBUG: session context: unsetting query in progress. DONE 2017-08-14 17:19:18: pid 4350: DEBUG: processing frontend response 2017-08-14 17:19:18: pid 4350: DETAIL: received kind 'D'(44) from frontend 2017-08-14 17:19:18: pid 4350: DEBUG: session context: clearing doing extended query messaging. DONE 2017-08-14 17:19:18: pid 4350: DEBUG: session context: setting doing extended query messaging. DONE 2017-08-14 17:19:18: pid 4350: DEBUG: Describe: waiting for master completing the query 2017-08-14 17:19:18: pid 4350: DEBUG: session context: setting query in progress. DONE 2017-08-14 17:19:18: pid 4350: LOG: DB node id: 0 backend pid: 16934 statement: D message 2017-08-14 17:19:18: pid 4350: DEBUG: pool_send_and_wait: pool_set_sync_map: 0 2017-08-14 17:19:18: pid 4350: DEBUG: pool_write: to backend: 0 kind:D 2017-08-14 17:19:18: pid 4350: DEBUG: session context: unsetting query in progress. DONE 2017-08-14 17:19:18: pid 4350: DEBUG: processing frontend response 2017-08-14 17:19:18: pid 4350: DETAIL: received kind 'H'(48) from frontend 2017-08-14 17:19:18: pid 4350: DEBUG: session context: clearing doing extended query messaging. DONE 2017-08-14 17:19:18: pid 4350: DEBUG: pool_write: to backend: 0 kind:H 2017-08-14 17:19:18: pid 4350: DEBUG: processing frontend response 2017-08-14 17:19:18: pid 4350: DETAIL: received kind 'S'(53) from frontend 2017-08-14 17:19:18: pid 4350: DEBUG: session context: clearing doing extended query messaging. DONE 2017-08-14 17:19:18: pid 4350: DEBUG: session context: setting doing extended query messaging. DONE 2017-08-14 17:19:18: pid 4350: DEBUG: session context: setting query in progress. DONE 2017-08-14 17:19:18: pid 4350: DEBUG: pool_write: to backend: 0 kind:S 2017-08-14 17:19:18: pid 4350: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 1 query in progress: 1 doing extended query: 1 2017-08-14 17:19:18: pid 4350: DEBUG: reading backend data packet kind 2017-08-14 17:19:18: pid 4350: DETAIL: master node id: 0 2017-08-14 17:19:18: pid 4350: DEBUG: pool_read: read 385 bytes from backend 0 2017-08-14 17:19:18: pid 4350: DEBUG: reading backend data packet kind 2017-08-14 17:19:18: pid 4350: DETAIL: backend:0 of 1 kind = '1' 2017-08-14 17:19:18: pid 4350: DEBUG: read_kind_from_backend max_count:1.000000 num_executed_nodes:1 2017-08-14 17:19:18: pid 4350: DEBUG: processing backend response 2017-08-14 17:19:18: pid 4350: DETAIL: received kind '1'(31) from backend 2017-08-14 17:19:18: pid 4350: DEBUG: called by pool_add_sent_message: sent message: address: 0x24ad830 kind: P name: =PGJDBCSTMT18575754188604= 2017-08-14 17:19:18: pid 4350: DEBUG: adding sent message to list 2017-08-14 17:19:18: pid 4350: DETAIL: adding exactly same message is prohibited 2017-08-14 17:19:18: pid 4350: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 1 query in progress: 1 doing extended query: 1 2017-08-14 17:19:18: pid 4350: DEBUG: SimpleForwardToFrontend: packet:1 length:0 2017-08-14 17:19:18: pid 4350: DEBUG: session context: setting command success. DONE 2017-08-14 17:19:18: pid 4350: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 1 query in progress: 1 doing extended query: 1 2017-08-14 17:19:18: pid 4350: DEBUG: reading backend data packet kind 2017-08-14 17:19:18: pid 4350: DETAIL: master node id: 0 2017-08-14 17:19:18: pid 4350: DEBUG: reading backend data packet kind 2017-08-14 17:19:18: pid 4350: DETAIL: backend:0 of 1 kind = 't' 2017-08-14 17:19:18: pid 4350: DEBUG: read_kind_from_backend max_count:1.000000 num_executed_nodes:1 2017-08-14 17:19:18: pid 4350: DEBUG: processing backend response 2017-08-14 17:19:18: pid 4350: DETAIL: received kind 't'(74) from backend 2017-08-14 17:19:18: pid 4350: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 1 query in progress: 1 doing extended query: 1 2017-08-14 17:19:18: pid 4350: DEBUG: SimpleForwardToFrontend: packet:t length:66 2017-08-14 17:19:18: pid 4350: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 1 query in progress: 1 doing extended query: 1 2017-08-14 17:19:18: pid 4350: DEBUG: reading backend data packet kind 2017-08-14 17:19:18: pid 4350: DETAIL: master node id: 0 2017-08-14 17:19:18: pid 4350: DEBUG: reading backend data packet kind 2017-08-14 17:19:18: pid 4350: DETAIL: backend:0 of 1 kind = 'T' 2017-08-14 17:19:18: pid 4350: DEBUG: read_kind_from_backend max_count:1.000000 num_executed_nodes:1 2017-08-14 17:19:18: pid 4350: DEBUG: processing backend response 2017-08-14 17:19:18: pid 4350: DETAIL: received kind 'T'(54) from backend 2017-08-14 17:19:18: pid 4350: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 1 query in progress: 1 doing extended query: 1 2017-08-14 17:19:18: pid 4350: DEBUG: SimpleForwardToFrontend: packet:T length:304 2017-08-14 17:19:18: pid 4350: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 1 query in progress: 1 doing extended query: 1 2017-08-14 17:19:18: pid 4350: DEBUG: reading backend data packet kind 2017-08-14 17:19:18: pid 4350: DETAIL: master node id: 0 2017-08-14 17:19:18: pid 4350: DEBUG: pool_read: read 6 bytes from backend 0 2017-08-14 17:19:18: pid 4350: DEBUG: reading backend data packet kind 2017-08-14 17:19:18: pid 4350: DETAIL: backend:0 of 1 kind = 'Z' 2017-08-14 17:19:18: pid 4350: DEBUG: read_kind_from_backend max_count:1.000000 num_executed_nodes:1 2017-08-14 17:19:18: pid 4350: DEBUG: processing backend response 2017-08-14 17:19:18: pid 4350: DETAIL: received kind 'Z'(5a) from backend 2017-08-14 17:19:18: pid 4350: DEBUG: processing backend response 2017-08-14 17:19:18: pid 4350: DETAIL: Ready For Query received 2017-08-14 17:19:18: pid 4350: DEBUG: session context: clearing ignore till sync. DONE 2017-08-14 17:19:18: pid 4350: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 1 query in progress: 1 doing extended query: 1 2017-08-14 17:19:18: pid 4350: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 1 query in progress: 1 doing extended query: 1 2017-08-14 17:19:18: pid 4350: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 1 query in progress: 1 doing extended query: 1 2017-08-14 17:19:18: pid 4350: DEBUG: reading message length 2017-08-14 17:19:18: pid 4350: DETAIL: slot: 0 length: 5 2017-08-14 17:19:18: pid 4350: DEBUG: processing ReadyForQuery 2017-08-14 17:19:18: pid 4350: DETAIL: transaction state 'I'(49) 2017-08-14 17:19:18: pid 4350: DEBUG: session context: unsetting query in progress. DONE 2017-08-14 17:19:18: pid 4350: DEBUG: processing frontend response 2017-08-14 17:19:18: pid 4350: DETAIL: received kind 'B'(42) from frontend 2017-08-14 17:19:18: pid 4350: DEBUG: session context: clearing doing extended query messaging. DONE 2017-08-14 17:19:18: pid 4350: DEBUG: session context: setting doing extended query messaging. DONE 2017-08-14 17:19:18: pid 4350: DEBUG: Bind: waiting for master completing the query 2017-08-14 17:19:18: pid 4350: DEBUG: session context: setting query in progress. DONE 2017-08-14 17:19:18: pid 4350: LOG: DB node id: 0 backend pid: 16934 statement: B message 2017-08-14 17:19:18: pid 4350: DEBUG: pool_send_and_wait: pool_set_sync_map: 0 2017-08-14 17:19:18: pid 4350: DEBUG: pool_write: to backend: 0 kind:B 2017-08-14 17:19:18: pid 4350: DEBUG: session context: unsetting query in progress. DONE 2017-08-14 17:19:18: pid 4350: DEBUG: called by pool_add_sent_message: sent message: address: 0x24ad880 kind: B name: =PGJDBCPRTL-0-18575949560940= 2017-08-14 17:19:18: pid 4350: DEBUG: processing frontend response 2017-08-14 17:19:18: pid 4350: DETAIL: received kind 'D'(44) from frontend 2017-08-14 17:19:18: pid 4350: DEBUG: session context: clearing doing extended query messaging. DONE 2017-08-14 17:19:18: pid 4350: DEBUG: session context: setting doing extended query messaging. DONE 2017-08-14 17:19:18: pid 4350: DEBUG: Describe: waiting for master completing the query 2017-08-14 17:19:18: pid 4350: DEBUG: session context: setting query in progress. DONE 2017-08-14 17:19:18: pid 4350: LOG: DB node id: 0 backend pid: 16934 statement: D message 2017-08-14 17:19:18: pid 4350: DEBUG: pool_send_and_wait: pool_set_sync_map: 0 2017-08-14 17:19:18: pid 4350: DEBUG: pool_write: to backend: 0 kind:D 2017-08-14 17:19:18: pid 4350: DEBUG: session context: unsetting query in progress. DONE 2017-08-14 17:19:18: pid 4350: DEBUG: processing frontend response 2017-08-14 17:19:18: pid 4350: DETAIL: received kind 'E'(45) from frontend 2017-08-14 17:19:18: pid 4350: DEBUG: session context: clearing doing extended query messaging. DONE 2017-08-14 17:19:18: pid 4350: DEBUG: session context: setting doing extended query messaging. DONE 2017-08-14 17:19:18: pid 4350: DEBUG: session context: setting query in progress. DONE 2017-08-14 17:19:18: pid 4350: LOG: DB node id: 0 backend pid: 16934 statement: Execute: SELECT TOP 50 cp.originmetro, SUM(cp.TotalPaymentAmountUSD) AS TotalAirlinePaymentusd, SUM(cp.TotalTicketCount) AS TotalAirlineTicketCount, (CASE WHEN SUM(cp.TotalTicketCount)> 0 THEN SUM (cp.TotalPaymentAmountUSD) * 1.00/SUM(cp.TotalTicketCount) ELSE 0 END ) AS Avg_TicketPrice, (CASE WHEN SUM(cp.TotalOriginTicketCount)> 0 THEN SUM(cp.TotalTicketCount)*100.00 / SUM(cp.TotalOriginTicketCount) ELSE 0 END ) AS AirlineOriginTicketMarketShare, (CASE WHEN NVL(MIN (AirlineOriginTicketMarketShare_pp), 0)>0 THEN ((CASE WHEN SUM(cp.TotalOriginTicketCount)> 0 THEN SUM(cp.TotalTicketCount)*100.00 / SUM(cp.TotalOriginTicketCount) ELSE 0 END) - NVL(MIN (AirlineOriginTicketMarketShare_pp), 0)) *100/ NVL(MIN (AirlineOriginTicketMarketShare_pp), 0) ELSE null END) AS AirlineOriginTicketMarketShare_PoP, (CASE WHEN NVL(MIN (AirlineOriginTicketMarketShare_py), 0)>0 THEN ((CASE WHEN SUM(cp.TotalOriginTicketCount)> 0 THEN SUM(cp.TotalTicketCount)*100.00 / SUM(cp.TotalOriginTicketCount) ELSE 0 END) - NVL(MIN (AirlineOrigin 2017-08-14 17:19:18: pid 4350: DEBUG: pool_send_and_wait: pool_set_sync_map: 0 2017-08-14 17:19:18: pid 4350: DEBUG: pool_write: to backend: 0 kind:E 2017-08-14 17:19:18: pid 4350: DEBUG: pool_write: to backend: 0 kind:H 2017-08-14 17:19:18: pid 4350: DEBUG: pool_send_and_wait: send flush message to 0 2017-08-14 17:19:18: pid 4350: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 1 query in progress: 1 doing extended query: 1 2017-08-14 17:19:18: pid 4350: DEBUG: reading backend data packet kind 2017-08-14 17:19:18: pid 4350: DETAIL: master node id: 0 2017-08-14 17:19:20: pid 4350: DEBUG: pool_read: read 1024 bytes from backend 0 2017-08-14 17:19:20: pid 4350: DEBUG: reading backend data packet kind 2017-08-14 17:19:20: pid 4350: DETAIL: backend:0 of 1 kind = '2' 2017-08-14 17:19:20: pid 4350: DEBUG: read_kind_from_backend max_count:1.000000 num_executed_nodes:1 2017-08-14 17:19:20: pid 4350: DEBUG: processing backend response 2017-08-14 17:19:20: pid 4350: DETAIL: received kind '2'(32) from backend 2017-08-14 17:19:20: pid 4350: DEBUG: called by pool_add_sent_message: sent message: address: 0x24ad880 kind: B name: =PGJDBCPRTL-0-18575949560940= 2017-08-14 17:19:20: pid 4350: DEBUG: adding sent message to list 2017-08-14 17:19:20: pid 4350: DETAIL: adding exactly same message is prohibited 2017-08-14 17:19:20: pid 4350: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 1 query in progress: 1 doing extended query: 1 2017-08-14 17:19:20: pid 4350: DEBUG: SimpleForwardToFrontend: packet:2 length:0 2017-08-14 17:19:20: pid 4350: DEBUG: session context: setting command success. DONE 2017-08-14 17:19:20: pid 4350: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 1 query in progress: 1 doing extended query: 1 2017-08-14 17:19:20: pid 4350: DEBUG: reading backend data packet kind 2017-08-14 17:19:20: pid 4350: DETAIL: master node id: 0 2017-08-14 17:19:20: pid 4350: DEBUG: reading backend data packet kind 2017-08-14 17:19:20: pid 4350: DETAIL: backend:0 of 1 kind = 'T' 2017-08-14 17:19:20: pid 4350: DEBUG: read_kind_from_backend max_count:1.000000 num_executed_nodes:1 2017-08-14 17:19:20: pid 4350: DEBUG: processing backend response 2017-08-14 17:19:20: pid 4350: DETAIL: received kind 'T'(54) from backend 2017-08-14 17:19:20: pid 4350: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 1 query in progress: 1 doing extended query: 1 2017-08-14 17:19:20: pid 4350: DEBUG: SimpleForwardToFrontend: packet:T length:304 2017-08-14 17:19:20: pid 4350: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 1 query in progress: 1 doing extended query: 1 2017-08-14 17:19:20: pid 4350: DEBUG: reading backend data packet kind 2017-08-14 17:19:20: pid 4350: DETAIL: master node id: 0 2017-08-14 17:19:20: pid 4350: DEBUG: reading backend data packet kind 2017-08-14 17:19:20: pid 4350: DETAIL: backend:0 of 1 kind = 'D' 2017-08-14 17:19:20: pid 4350: DEBUG: read_kind_from_backend max_count:1.000000 num_executed_nodes:1 2017-08-14 17:19:20: pid 4350: DEBUG: processing backend response 2017-08-14 17:19:20: pid 4350: DETAIL: received kind 'D'(44) from backend 2017-08-14 17:19:20: pid 4350: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 1 query in progress: 1 doing extended query: 1 2017-08-14 17:19:20: pid 4350: DEBUG: SimpleForwardToFrontend: packet:D length:86 2017-08-14 17:19:20: pid 4350: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 1 query in progress: 1 doing extended query: 1 2017-08-14 17:19:20: pid 4350: DEBUG: reading backend data packet kind 2017-08-14 17:19:20: pid 4350: DETAIL: master node id: 0 2017-08-14 17:19:20: pid 4350: DEBUG: reading backend data packet kind 2017-08-14 17:19:20: pid 4350: DETAIL: backend:0 of 1 kind = 'D' 2017-08-14 17:19:20: pid 4350: DEBUG: read_kind_from_backend max_count:1.000000 num_executed_nodes:1 2017-08-14 17:19:20: pid 4350: DEBUG: processing backend response 2017-08-14 17:19:20: pid 4350: DETAIL: received kind 'D'(44) from backend 2017-08-14 17:19:20: pid 4350: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 1 query in progress: 1 doing extended query: 1 2017-08-14 17:19:20: pid 4350: DEBUG: SimpleForwardToFrontend: packet:D length:86 2017-08-14 17:19:20: pid 4350: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 1 query in progress: 1 doing extended query: 1 2017-08-14 17:19:20: pid 4350: DEBUG: reading backend data packet kind 2017-08-14 17:19:20: pid 4350: DETAIL: master node id: 0 2017-08-14 17:19:20: pid 4350: DEBUG: reading backend data packet kind 2017-08-14 17:19:20: pid 4350: DETAIL: backend:0 of 1 kind = 'D' 2017-08-14 17:19:20: pid 4350: DEBUG: read_kind_from_backend max_count:1.000000 num_executed_nodes:1 2017-08-14 17:19:20: pid 4350: DEBUG: processing backend response 2017-08-14 17:19:20: pid 4350: DETAIL: received kind 'D'(44) from backend 2017-08-14 17:19:20: pid 4350: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 1 query in progress: 1 doing extended query: 1 2017-08-14 17:19:20: pid 4350: DEBUG: SimpleForwardToFrontend: packet:D length:86 2017-08-14 17:19:20: pid 4350: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 1 query in progress: 1 doing extended query: 1 2017-08-14 17:19:20: pid 4350: DEBUG: reading backend data packet kind 2017-08-14 17:19:20: pid 4350: DETAIL: master node id: 0 2017-08-14 17:19:20: pid 4350: DEBUG: reading backend data packet kind 2017-08-14 17:19:20: pid 4350: DETAIL: backend:0 of 1 kind = 'D' 2017-08-14 17:19:20: pid 4350: DEBUG: read_kind_from_backend max_count:1.000000 num_executed_nodes:1 2017-08-14 17:19:20: pid 4350: DEBUG: processing backend response 2017-08-14 17:19:20: pid 4350: DETAIL: received kind 'D'(44) from backend 2017-08-14 17:19:20: pid 4350: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 1 query in progress: 1 doing extended query: 1 2017-08-14 17:19:20: pid 4350: DEBUG: SimpleForwardToFrontend: packet:D length:87 2017-08-14 17:19:20: pid 4350: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 1 query in progress: 1 doing extended query: 1 2017-08-14 17:19:20: pid 4350: DEBUG: reading backend data packet kind 2017-08-14 17:19:20: pid 4350: DETAIL: master node id: 0 2017-08-14 17:19:20: pid 4350: DEBUG: reading backend data packet kind 2017-08-14 17:19:20: pid 4350: DETAIL: backend:0 of 1 kind = 'D' 2017-08-14 17:19:20: pid 4350: DEBUG: read_kind_from_backend max_count:1.000000 num_executed_nodes:1 2017-08-14 17:19:20: pid 4350: DEBUG: processing backend response 2017-08-14 17:19:20: pid 4350: DETAIL: received kind 'D'(44) from backend 2017-08-14 17:19:20: pid 4350: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 1 query in progress: 1 doing extended query: 1 2017-08-14 17:19:20: pid 4350: DEBUG: SimpleForwardToFrontend: packet:D length:85 2017-08-14 17:19:20: pid 4350: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 1 query in progress: 1 doing extended query: 1 2017-08-14 17:19:20: pid 4350: DEBUG: reading backend data packet kind 2017-08-14 17:19:20: pid 4350: DETAIL: master node id: 0 2017-08-14 17:19:20: pid 4350: DEBUG: reading backend data packet kind 2017-08-14 17:19:20: pid 4350: DETAIL: backend:0 of 1 kind = 'D' 2017-08-14 17:19:20: pid 4350: DEBUG: read_kind_from_backend max_count:1.000000 num_executed_nodes:1 2017-08-14 17:19:20: pid 4350: DEBUG: processing backend response 2017-08-14 17:19:20: pid 4350: DETAIL: received kind 'D'(44) from backend 2017-08-14 17:19:20: pid 4350: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 1 query in progress: 1 doing extended query: 1 2017-08-14 17:19:20: pid 4350: DEBUG: SimpleForwardToFrontend: packet:D length:86 2017-08-14 17:19:20: pid 4350: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 1 query in progress: 1 doing extended query: 1 2017-08-14 17:19:20: pid 4350: DEBUG: reading backend data packet kind 2017-08-14 17:19:20: pid 4350: DETAIL: master node id: 0 2017-08-14 17:19:20: pid 4350: DEBUG: reading backend data packet kind 2017-08-14 17:19:20: pid 4350: DETAIL: backend:0 of 1 kind = 'D' 2017-08-14 17:19:20: pid 4350: DEBUG: read_kind_from_backend max_count:1.000000 num_executed_nodes:1 2017-08-14 17:19:20: pid 4350: DEBUG: processing backend response 2017-08-14 17:19:20: pid 4350: DETAIL: received kind 'D'(44) from backend 2017-08-14 17:19:20: pid 4350: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 1 query in progress: 1 doing extended query: 1 2017-08-14 17:19:20: pid 4350: DEBUG: SimpleForwardToFrontend: packet:D length:85 2017-08-14 17:19:20: pid 4350: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 1 query in progress: 1 doing extended query: 1 2017-08-14 17:19:20: pid 4350: DEBUG: reading backend data packet kind 2017-08-14 17:19:20: pid 4350: DETAIL: master node id: 0 2017-08-14 17:19:20: pid 4350: DEBUG: reading backend data packet kind 2017-08-14 17:19:20: pid 4350: DETAIL: backend:0 of 1 kind = 'D' 2017-08-14 17:19:20: pid 4350: DEBUG: read_kind_from_backend max_count:1.000000 num_executed_nodes:1 2017-08-14 17:19:20: pid 4350: DEBUG: processing backend response 2017-08-14 17:19:20: pid 4350: DETAIL: received kind 'D'(44) from backend 2017-08-14 17:19:20: pid 4350: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 1 query in progress: 1 doing extended query: 1 2017-08-14 17:19:20: pid 4350: DEBUG: pool_read2: read 18 bytes from backend 0 2017-08-14 17:19:20: pid 4350: DEBUG: SimpleForwardToFrontend: packet:D length:87 2017-08-14 17:19:20: pid 4350: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 1 query in progress: 1 doing extended query: 1 2017-08-14 17:19:20: pid 4350: DEBUG: reading backend data packet kind 2017-08-14 17:19:20: pid 4350: DETAIL: master node id: 0 2017-08-14 17:19:20: pid 4350: DEBUG: pool_read: read 1024 bytes from backend 0 2017-08-14 17:19:20: pid 4350: DEBUG: reading backend data packet kind 2017-08-14 17:19:20: pid 4350: DETAIL: backend:0 of 1 kind = 'D' 2017-08-14 17:19:20: pid 4350: DEBUG: read_kind_from_backend max_count:1.000000 num_executed_nodes:1 2017-08-14 17:19:20: pid 4350: DEBUG: processing backend response 2017-08-14 17:19:20: pid 4350: DETAIL: received kind 'D'(44) from backend 2017-08-14 17:19:20: pid 4350: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 1 query in progress: 1 doing extended query: 1 2017-08-14 17:19:20: pid 4350: DEBUG: SimpleForwardToFrontend: packet:D length:85 2017-08-14 17:19:20: pid 4350: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 1 query in progress: 1 doing extended query: 1 2017-08-14 17:19:20: pid 4350: DEBUG: reading backend data packet kind 2017-08-14 17:19:20: pid 4350: DETAIL: master node id: 0 2017-08-14 17:19:20: pid 4350: DEBUG: reading backend data packet kind 2017-08-14 17:19:20: pid 4350: DETAIL: backend:0 of 1 kind = 'D' 2017-08-14 17:19:20: pid 4350: DEBUG: read_kind_from_backend max_count:1.000000 num_executed_nodes:1 2017-08-14 17:19:20: pid 4350: DEBUG: processing backend response 2017-08-14 17:19:20: pid 4350: DETAIL: received kind 'D'(44) from backend 2017-08-14 17:19:20: pid 4350: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 1 query in progress: 1 doing extended query: 1 2017-08-14 17:19:20: pid 4350: DEBUG: SimpleForwardToFrontend: packet:D length:86 2017-08-14 17:19:20: pid 4350: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 1 query in progress: 1 doing extended query: 1 2017-08-14 17:19:20: pid 4350: DEBUG: reading backend data packet kind 2017-08-14 17:19:20: pid 4350: DETAIL: master node id: 0 2017-08-14 17:19:20: pid 4350: DEBUG: reading backend data packet kind 2017-08-14 17:19:20: pid 4350: DETAIL: backend:0 of 1 kind = 'D' 2017-08-14 17:19:20: pid 4350: DEBUG: read_kind_from_backend max_count:1.000000 num_executed_nodes:1 2017-08-14 17:19:20: pid 4350: DEBUG: processing backend response 2017-08-14 17:19:20: pid 4350: DETAIL: received kind 'D'(44) from backend 2017-08-14 17:19:20: pid 4350: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 1 query in progress: 1 doing extended query: 1 2017-08-14 17:19:20: pid 4350: DEBUG: SimpleForwardToFrontend: packet:D length:85 2017-08-14 17:19:20: pid 4350: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 1 query in progress: 1 doing extended query: 1 2017-08-14 17:19:20: pid 4350: DEBUG: reading backend data packet kind 2017-08-14 17:19:20: pid 4350: DETAIL: master node id: 0 2017-08-14 17:19:20: pid 4350: DEBUG: reading backend data packet kind 2017-08-14 17:19:20: pid 4350: DETAIL: backend:0 of 1 kind = 'D' 2017-08-14 17:19:20: pid 4350: DEBUG: read_kind_from_backend max_count:1.000000 num_executed_nodes:1 2017-08-14 17:19:20: pid 4350: DEBUG: processing backend response 2017-08-14 17:19:20: pid 4350: DETAIL: received kind 'D'(44) from backend 2017-08-14 17:19:20: pid 4350: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 1 query in progress: 1 doing extended query: 1 2017-08-14 17:19:20: pid 4350: DEBUG: SimpleForwardToFrontend: packet:D length:86 2017-08-14 17:19:20: pid 4350: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 1 query in progress: 1 doing extended query: 1 2017-08-14 17:19:20: pid 4350: DEBUG: reading backend data packet kind 2017-08-14 17:19:20: pid 4350: DETAIL: master node id: 0 2017-08-14 17:19:20: pid 4350: DEBUG: reading backend data packet kind 2017-08-14 17:19:20: pid 4350: DETAIL: backend:0 of 1 kind = 'D' 2017-08-14 17:19:20: pid 4350: DEBUG: read_kind_from_backend max_count:1.000000 num_executed_nodes:1 2017-08-14 17:19:20: pid 4350: DEBUG: processing backend response 2017-08-14 17:19:20: pid 4350: DETAIL: received kind 'D'(44) from backend 2017-08-14 17:19:20: pid 4350: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 1 query in progress: 1 doing extended query: 1 2017-08-14 17:19:20: pid 4350: DEBUG: SimpleForwardToFrontend: packet:D length:87 2017-08-14 17:19:20: pid 4350: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 1 query in progress: 1 doing extended query: 1 2017-08-14 17:19:20: pid 4350: DEBUG: reading backend data packet kind 2017-08-14 17:19:20: pid 4350: DETAIL: master node id: 0 2017-08-14 17:19:20: pid 4350: DEBUG: reading backend data packet kind 2017-08-14 17:19:20: pid 4350: DETAIL: backend:0 of 1 kind = 'D' 2017-08-14 17:19:20: pid 4350: DEBUG: read_kind_from_backend max_count:1.000000 num_executed_nodes:1 2017-08-14 17:19:20: pid 4350: DEBUG: processing backend response 2017-08-14 17:19:20: pid 4350: DETAIL: received kind 'D'(44) from backend 2017-08-14 17:19:20: pid 4350: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 1 query in progress: 1 doing extended query: 1 2017-08-14 17:19:20: pid 4350: DEBUG: SimpleForwardToFrontend: packet:D length:84 2017-08-14 17:19:20: pid 4350: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 1 query in progress: 1 doing extended query: 1 2017-08-14 17:19:20: pid 4350: DEBUG: reading backend data packet kind 2017-08-14 17:19:20: pid 4350: DETAIL: master node id: 0 2017-08-14 17:19:20: pid 4350: DEBUG: reading backend data packet kind 2017-08-14 17:19:20: pid 4350: DETAIL: backend:0 of 1 kind = 'D' 2017-08-14 17:19:20: pid 4350: DEBUG: read_kind_from_backend max_count:1.000000 num_executed_nodes:1 2017-08-14 17:19:20: pid 4350: DEBUG: processing backend response 2017-08-14 17:19:20: pid 4350: DETAIL: received kind 'D'(44) from backend 2017-08-14 17:19:20: pid 4350: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 1 query in progress: 1 doing extended query: 1 2017-08-14 17:19:20: pid 4350: DEBUG: SimpleForwardToFrontend: packet:D length:88 2017-08-14 17:19:20: pid 4350: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 1 query in progress: 1 doing extended query: 1 2017-08-14 17:19:20: pid 4350: DEBUG: reading backend data packet kind 2017-08-14 17:19:20: pid 4350: DETAIL: master node id: 0 2017-08-14 17:19:20: pid 4350: DEBUG: reading backend data packet kind 2017-08-14 17:19:20: pid 4350: DETAIL: backend:0 of 1 kind = 'D' 2017-08-14 17:19:20: pid 4350: DEBUG: read_kind_from_backend max_count:1.000000 num_executed_nodes:1 2017-08-14 17:19:20: pid 4350: DEBUG: processing backend response 2017-08-14 17:19:20: pid 4350: DETAIL: received kind 'D'(44) from backend 2017-08-14 17:19:20: pid 4350: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 1 query in progress: 1 doing extended query: 1 2017-08-14 17:19:20: pid 4350: DEBUG: SimpleForwardToFrontend: packet:D length:85 2017-08-14 17:19:20: pid 4350: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 1 query in progress: 1 doing extended query: 1 2017-08-14 17:19:20: pid 4350: DEBUG: reading backend data packet kind 2017-08-14 17:19:20: pid 4350: DETAIL: master node id: 0 2017-08-14 17:19:20: pid 4350: DEBUG: reading backend data packet kind 2017-08-14 17:19:20: pid 4350: DETAIL: backend:0 of 1 kind = 'D' 2017-08-14 17:19:20: pid 4350: DEBUG: read_kind_from_backend max_count:1.000000 num_executed_nodes:1 2017-08-14 17:19:20: pid 4350: DEBUG: processing backend response 2017-08-14 17:19:20: pid 4350: DETAIL: received kind 'D'(44) from backend 2017-08-14 17:19:20: pid 4350: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 1 query in progress: 1 doing extended query: 1 2017-08-14 17:19:20: pid 4350: DEBUG: SimpleForwardToFrontend: packet:D length:85 2017-08-14 17:19:20: pid 4350: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 1 query in progress: 1 doing extended query: 1 2017-08-14 17:19:20: pid 4350: DEBUG: reading backend data packet kind 2017-08-14 17:19:20: pid 4350: DETAIL: master node id: 0 2017-08-14 17:19:20: pid 4350: DEBUG: reading backend data packet kind 2017-08-14 17:19:20: pid 4350: DETAIL: backend:0 of 1 kind = 'D' 2017-08-14 17:19:20: pid 4350: DEBUG: read_kind_from_backend max_count:1.000000 num_executed_nodes:1 2017-08-14 17:19:20: pid 4350: DEBUG: processing backend response 2017-08-14 17:19:20: pid 4350: DETAIL: received kind 'D'(44) from backend 2017-08-14 17:19:20: pid 4350: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 1 query in progress: 1 doing extended query: 1 2017-08-14 17:19:20: pid 4350: DEBUG: SimpleForwardToFrontend: packet:D length:84 2017-08-14 17:19:20: pid 4350: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 1 query in progress: 1 doing extended query: 1 2017-08-14 17:19:20: pid 4350: DEBUG: reading backend data packet kind 2017-08-14 17:19:20: pid 4350: DETAIL: master node id: 0 2017-08-14 17:19:20: pid 4350: DEBUG: reading backend data packet kind 2017-08-14 17:19:20: pid 4350: DETAIL: backend:0 of 1 kind = 'D' 2017-08-14 17:19:20: pid 4350: DEBUG: read_kind_from_backend max_count:1.000000 num_executed_nodes:1 2017-08-14 17:19:20: pid 4350: DEBUG: processing backend response 2017-08-14 17:19:20: pid 4350: DETAIL: received kind 'D'(44) from backend 2017-08-14 17:19:20: pid 4350: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 1 query in progress: 1 doing extended query: 1 2017-08-14 17:19:20: pid 4350: DEBUG: SimpleForwardToFrontend: packet:D length:87 2017-08-14 17:19:20: pid 4350: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 1 query in progress: 1 doing extended query: 1 2017-08-14 17:19:20: pid 4350: DEBUG: reading backend data packet kind 2017-08-14 17:19:20: pid 4350: DETAIL: master node id: 0 2017-08-14 17:19:20: pid 4350: DEBUG: reading backend data packet kind 2017-08-14 17:19:20: pid 4350: DETAIL: backend:0 of 1 kind = 'D' 2017-08-14 17:19:20: pid 4350: DEBUG: read_kind_from_backend max_count:1.000000 num_executed_nodes:1 2017-08-14 17:19:20: pid 4350: DEBUG: processing backend response 2017-08-14 17:19:20: pid 4350: DETAIL: received kind 'D'(44) from backend 2017-08-14 17:19:20: pid 4350: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 1 query in progress: 1 doing extended query: 1 2017-08-14 17:19:20: pid 4350: DEBUG: pool_read2: read 63 bytes from backend 0 2017-08-14 17:19:20: pid 4350: DEBUG: SimpleForwardToFrontend: packet:D length:85 2017-08-14 17:19:20: pid 4350: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 1 query in progress: 1 doing extended query: 1 2017-08-14 17:19:20: pid 4350: DEBUG: reading backend data packet kind 2017-08-14 17:19:20: pid 4350: DETAIL: master node id: 0 2017-08-14 17:19:20: pid 4350: DEBUG: pool_read: read 1024 bytes from backend 0 2017-08-14 17:19:20: pid 4350: DEBUG: reading backend data packet kind 2017-08-14 17:19:20: pid 4350: DETAIL: backend:0 of 1 kind = 'D' 2017-08-14 17:19:20: pid 4350: DEBUG: read_kind_from_backend max_count:1.000000 num_executed_nodes:1 2017-08-14 17:19:20: pid 4350: DEBUG: processing backend response 2017-08-14 17:19:20: pid 4350: DETAIL: received kind 'D'(44) from backend 2017-08-14 17:19:20: pid 4350: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 1 query in progress: 1 doing extended query: 1 2017-08-14 17:19:20: pid 4350: DEBUG: SimpleForwardToFrontend: packet:D length:85 2017-08-14 17:19:20: pid 4350: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 1 query in progress: 1 doing extended query: 1 2017-08-14 17:19:20: pid 4350: DEBUG: reading backend data packet kind 2017-08-14 17:19:20: pid 4350: DETAIL: master node id: 0 2017-08-14 17:19:20: pid 4350: DEBUG: reading backend data packet kind 2017-08-14 17:19:20: pid 4350: DETAIL: backend:0 of 1 kind = 'D' 2017-08-14 17:19:20: pid 4350: DEBUG: read_kind_from_backend max_count:1.000000 num_executed_nodes:1 2017-08-14 17:19:20: pid 4350: DEBUG: processing backend response 2017-08-14 17:19:20: pid 4350: DETAIL: received kind 'D'(44) from backend 2017-08-14 17:19:20: pid 4350: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 1 query in progress: 1 doing extended query: 1 2017-08-14 17:19:20: pid 4350: DEBUG: SimpleForwardToFrontend: packet:D length:84 2017-08-14 17:19:20: pid 4350: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 1 query in progress: 1 doing extended query: 1 2017-08-14 17:19:20: pid 4350: DEBUG: reading backend data packet kind 2017-08-14 17:19:20: pid 4350: DETAIL: master node id: 0 2017-08-14 17:19:20: pid 4350: DEBUG: reading backend data packet kind 2017-08-14 17:19:20: pid 4350: DETAIL: backend:0 of 1 kind = 'D' 2017-08-14 17:19:20: pid 4350: DEBUG: read_kind_from_backend max_count:1.000000 num_executed_nodes:1 2017-08-14 17:19:20: pid 4350: DEBUG: processing backend response 2017-08-14 17:19:20: pid 4350: DETAIL: received kind 'D'(44) from backend 2017-08-14 17:19:20: pid 4350: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 1 query in progress: 1 doing extended query: 1 2017-08-14 17:19:20: pid 4350: DEBUG: SimpleForwardToFrontend: packet:D length:83 2017-08-14 17:19:20: pid 4350: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 1 query in progress: 1 doing extended query: 1 2017-08-14 17:19:20: pid 4350: DEBUG: reading backend data packet kind 2017-08-14 17:19:20: pid 4350: DETAIL: master node id: 0 2017-08-14 17:19:20: pid 4350: DEBUG: reading backend data packet kind 2017-08-14 17:19:20: pid 4350: DETAIL: backend:0 of 1 kind = 'D' 2017-08-14 17:19:20: pid 4350: DEBUG: read_kind_from_backend max_count:1.000000 num_executed_nodes:1 2017-08-14 17:19:20: pid 4350: DEBUG: processing backend response 2017-08-14 17:19:20: pid 4350: DETAIL: received kind 'D'(44) from backend 2017-08-14 17:19:20: pid 4350: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 1 query in progress: 1 doing extended query: 1 2017-08-14 17:19:20: pid 4350: DEBUG: SimpleForwardToFrontend: packet:D length:85 2017-08-14 17:19:20: pid 4350: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 1 query in progress: 1 doing extended query: 1 2017-08-14 17:19:20: pid 4350: DEBUG: reading backend data packet kind 2017-08-14 17:19:20: pid 4350: DETAIL: master node id: 0 2017-08-14 17:19:20: pid 4350: DEBUG: reading backend data packet kind 2017-08-14 17:19:20: pid 4350: DETAIL: backend:0 of 1 kind = 'D' 2017-08-14 17:19:20: pid 4350: DEBUG: read_kind_from_backend max_count:1.000000 num_executed_nodes:1 2017-08-14 17:19:20: pid 4350: DEBUG: processing backend response 2017-08-14 17:19:20: pid 4350: DETAIL: received kind 'D'(44) from backend 2017-08-14 17:19:20: pid 4350: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 1 query in progress: 1 doing extended query: 1 2017-08-14 17:19:20: pid 4350: DEBUG: SimpleForwardToFrontend: packet:D length:85 2017-08-14 17:19:20: pid 4350: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 1 query in progress: 1 doing extended query: 1 2017-08-14 17:19:20: pid 4350: DEBUG: reading backend data packet kind 2017-08-14 17:19:20: pid 4350: DETAIL: master node id: 0 2017-08-14 17:19:20: pid 4350: DEBUG: reading backend data packet kind 2017-08-14 17:19:20: pid 4350: DETAIL: backend:0 of 1 kind = 'D' 2017-08-14 17:19:20: pid 4350: DEBUG: read_kind_from_backend max_count:1.000000 num_executed_nodes:1 2017-08-14 17:19:20: pid 4350: DEBUG: processing backend response 2017-08-14 17:19:20: pid 4350: DETAIL: received kind 'D'(44) from backend 2017-08-14 17:19:20: pid 4350: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 1 query in progress: 1 doing extended query: 1 2017-08-14 17:19:20: pid 4350: DEBUG: SimpleForwardToFrontend: packet:D length:85 2017-08-14 17:19:20: pid 4350: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 1 query in progress: 1 doing extended query: 1 2017-08-14 17:19:20: pid 4350: DEBUG: reading backend data packet kind 2017-08-14 17:19:20: pid 4350: DETAIL: master node id: 0 2017-08-14 17:19:20: pid 4350: DEBUG: reading backend data packet kind 2017-08-14 17:19:20: pid 4350: DETAIL: backend:0 of 1 kind = 'D' 2017-08-14 17:19:20: pid 4350: DEBUG: read_kind_from_backend max_count:1.000000 num_executed_nodes:1 2017-08-14 17:19:20: pid 4350: DEBUG: processing backend response 2017-08-14 17:19:20: pid 4350: DETAIL: received kind 'D'(44) from backend 2017-08-14 17:19:20: pid 4350: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 1 query in progress: 1 doing extended query: 1 2017-08-14 17:19:20: pid 4350: DEBUG: SimpleForwardToFrontend: packet:D length:84 2017-08-14 17:19:20: pid 4350: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 1 query in progress: 1 doing extended query: 1 2017-08-14 17:19:20: pid 4350: DEBUG: reading backend data packet kind 2017-08-14 17:19:20: pid 4350: DETAIL: master node id: 0 2017-08-14 17:19:20: pid 4350: DEBUG: reading backend data packet kind 2017-08-14 17:19:20: pid 4350: DETAIL: backend:0 of 1 kind = 'D' 2017-08-14 17:19:20: pid 4350: DEBUG: read_kind_from_backend max_count:1.000000 num_executed_nodes:1 2017-08-14 17:19:20: pid 4350: DEBUG: processing backend response 2017-08-14 17:19:20: pid 4350: DETAIL: received kind 'D'(44) from backend 2017-08-14 17:19:20: pid 4350: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 1 query in progress: 1 doing extended query: 1 2017-08-14 17:19:20: pid 4350: DEBUG: SimpleForwardToFrontend: packet:D length:83 2017-08-14 17:19:20: pid 4350: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 1 query in progress: 1 doing extended query: 1 2017-08-14 17:19:20: pid 4350: DEBUG: reading backend data packet kind 2017-08-14 17:19:20: pid 4350: DETAIL: master node id: 0 2017-08-14 17:19:20: pid 4350: DEBUG: reading backend data packet kind 2017-08-14 17:19:20: pid 4350: DETAIL: backend:0 of 1 kind = 'D' 2017-08-14 17:19:20: pid 4350: DEBUG: read_kind_from_backend max_count:1.000000 num_executed_nodes:1 2017-08-14 17:19:20: pid 4350: DEBUG: processing backend response 2017-08-14 17:19:20: pid 4350: DETAIL: received kind 'D'(44) from backend 2017-08-14 17:19:20: pid 4350: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 1 query in progress: 1 doing extended query: 1 2017-08-14 17:19:20: pid 4350: DEBUG: SimpleForwardToFrontend: packet:D length:86 2017-08-14 17:19:20: pid 4350: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 1 query in progress: 1 doing extended query: 1 2017-08-14 17:19:20: pid 4350: DEBUG: reading backend data packet kind 2017-08-14 17:19:20: pid 4350: DETAIL: master node id: 0 2017-08-14 17:19:20: pid 4350: DEBUG: reading backend data packet kind 2017-08-14 17:19:20: pid 4350: DETAIL: backend:0 of 1 kind = 'D' 2017-08-14 17:19:20: pid 4350: DEBUG: read_kind_from_backend max_count:1.000000 num_executed_nodes:1 2017-08-14 17:19:20: pid 4350: DEBUG: processing backend response 2017-08-14 17:19:20: pid 4350: DETAIL: received kind 'D'(44) from backend 2017-08-14 17:19:20: pid 4350: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 1 query in progress: 1 doing extended query: 1 2017-08-14 17:19:20: pid 4350: DEBUG: SimpleForwardToFrontend: packet:D length:83 2017-08-14 17:19:20: pid 4350: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 1 query in progress: 1 doing extended query: 1 2017-08-14 17:19:20: pid 4350: DEBUG: reading backend data packet kind 2017-08-14 17:19:20: pid 4350: DETAIL: master node id: 0 2017-08-14 17:19:20: pid 4350: DEBUG: reading backend data packet kind 2017-08-14 17:19:20: pid 4350: DETAIL: backend:0 of 1 kind = 'D' 2017-08-14 17:19:20: pid 4350: DEBUG: read_kind_from_backend max_count:1.000000 num_executed_nodes:1 2017-08-14 17:19:20: pid 4350: DEBUG: processing backend response 2017-08-14 17:19:20: pid 4350: DETAIL: received kind 'D'(44) from backend 2017-08-14 17:19:20: pid 4350: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 1 query in progress: 1 doing extended query: 1 2017-08-14 17:19:20: pid 4350: DEBUG: SimpleForwardToFrontend: packet:D length:84 2017-08-14 17:19:20: pid 4350: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 1 query in progress: 1 doing extended query: 1 2017-08-14 17:19:20: pid 4350: DEBUG: reading backend data packet kind 2017-08-14 17:19:20: pid 4350: DETAIL: master node id: 0 2017-08-14 17:19:20: pid 4350: DEBUG: reading backend data packet kind 2017-08-14 17:19:20: pid 4350: DETAIL: backend:0 of 1 kind = 'D' 2017-08-14 17:19:20: pid 4350: DEBUG: read_kind_from_backend max_count:1.000000 num_executed_nodes:1 2017-08-14 17:19:20: pid 4350: DEBUG: processing backend response 2017-08-14 17:19:20: pid 4350: DETAIL: received kind 'D'(44) from backend 2017-08-14 17:19:20: pid 4350: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 1 query in progress: 1 doing extended query: 1 2017-08-14 17:19:20: pid 4350: DEBUG: pool_read2: read 48 bytes from backend 0 2017-08-14 17:19:20: pid 4350: DEBUG: SimpleForwardToFrontend: packet:D length:85 2017-08-14 17:19:20: pid 4350: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 1 query in progress: 1 doing extended query: 1 2017-08-14 17:19:20: pid 4350: DEBUG: reading backend data packet kind 2017-08-14 17:19:20: pid 4350: DETAIL: master node id: 0 2017-08-14 17:19:20: pid 4350: DEBUG: pool_read: read 1024 bytes from backend 0 2017-08-14 17:19:20: pid 4350: DEBUG: reading backend data packet kind 2017-08-14 17:19:20: pid 4350: DETAIL: backend:0 of 1 kind = 'D' 2017-08-14 17:19:20: pid 4350: DEBUG: read_kind_from_backend max_count:1.000000 num_executed_nodes:1 2017-08-14 17:19:20: pid 4350: DEBUG: processing backend response 2017-08-14 17:19:20: pid 4350: DETAIL: received kind 'D'(44) from backend 2017-08-14 17:19:20: pid 4350: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 1 query in progress: 1 doing extended query: 1 2017-08-14 17:19:20: pid 4350: DEBUG: SimpleForwardToFrontend: packet:D length:84 2017-08-14 17:19:20: pid 4350: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 1 query in progress: 1 doing extended query: 1 2017-08-14 17:19:20: pid 4350: DEBUG: reading backend data packet kind 2017-08-14 17:19:20: pid 4350: DETAIL: master node id: 0 2017-08-14 17:19:20: pid 4350: DEBUG: reading backend data packet kind 2017-08-14 17:19:20: pid 4350: DETAIL: backend:0 of 1 kind = 'D' 2017-08-14 17:19:20: pid 4350: DEBUG: read_kind_from_backend max_count:1.000000 num_executed_nodes:1 2017-08-14 17:19:20: pid 4350: DEBUG: processing backend response 2017-08-14 17:19:20: pid 4350: DETAIL: received kind 'D'(44) from backend 2017-08-14 17:19:20: pid 4350: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 1 query in progress: 1 doing extended query: 1 2017-08-14 17:19:20: pid 4350: DEBUG: SimpleForwardToFrontend: packet:D length:86 2017-08-14 17:19:20: pid 4350: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 1 query in progress: 1 doing extended query: 1 2017-08-14 17:19:20: pid 4350: DEBUG: reading backend data packet kind 2017-08-14 17:19:20: pid 4350: DETAIL: master node id: 0 2017-08-14 17:19:20: pid 4350: DEBUG: reading backend data packet kind 2017-08-14 17:19:20: pid 4350: DETAIL: backend:0 of 1 kind = 'D' 2017-08-14 17:19:20: pid 4350: DEBUG: read_kind_from_backend max_count:1.000000 num_executed_nodes:1 2017-08-14 17:19:20: pid 4350: DEBUG: processing backend response 2017-08-14 17:19:20: pid 4350: DETAIL: received kind 'D'(44) from backend 2017-08-14 17:19:20: pid 4350: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 1 query in progress: 1 doing extended query: 1 2017-08-14 17:19:20: pid 4350: DEBUG: SimpleForwardToFrontend: packet:D length:86 2017-08-14 17:19:20: pid 4350: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 1 query in progress: 1 doing extended query: 1 2017-08-14 17:19:20: pid 4350: DEBUG: reading backend data packet kind 2017-08-14 17:19:20: pid 4350: DETAIL: master node id: 0 2017-08-14 17:19:20: pid 4350: DEBUG: reading backend data packet kind 2017-08-14 17:19:20: pid 4350: DETAIL: backend:0 of 1 kind = 'D' 2017-08-14 17:19:20: pid 4350: DEBUG: read_kind_from_backend max_count:1.000000 num_executed_nodes:1 2017-08-14 17:19:20: pid 4350: DEBUG: processing backend response 2017-08-14 17:19:20: pid 4350: DETAIL: received kind 'D'(44) from backend 2017-08-14 17:19:20: pid 4350: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 1 query in progress: 1 doing extended query: 1 2017-08-14 17:19:20: pid 4350: DEBUG: SimpleForwardToFrontend: packet:D length:85 2017-08-14 17:19:20: pid 4350: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 1 query in progress: 1 doing extended query: 1 2017-08-14 17:19:20: pid 4350: DEBUG: reading backend data packet kind 2017-08-14 17:19:20: pid 4350: DETAIL: master node id: 0 2017-08-14 17:19:20: pid 4350: DEBUG: reading backend data packet kind 2017-08-14 17:19:20: pid 4350: DETAIL: backend:0 of 1 kind = 'D' 2017-08-14 17:19:20: pid 4350: DEBUG: read_kind_from_backend max_count:1.000000 num_executed_nodes:1 2017-08-14 17:19:20: pid 4350: DEBUG: processing backend response 2017-08-14 17:19:20: pid 4350: DETAIL: received kind 'D'(44) from backend 2017-08-14 17:19:20: pid 4350: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 1 query in progress: 1 doing extended query: 1 2017-08-14 17:19:20: pid 4350: DEBUG: SimpleForwardToFrontend: packet:D length:85 2017-08-14 17:19:20: pid 4350: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 1 query in progress: 1 doing extended query: 1 2017-08-14 17:19:20: pid 4350: DEBUG: reading backend data packet kind 2017-08-14 17:19:20: pid 4350: DETAIL: master node id: 0 2017-08-14 17:19:20: pid 4350: DEBUG: reading backend data packet kind 2017-08-14 17:19:20: pid 4350: DETAIL: backend:0 of 1 kind = 'D' 2017-08-14 17:19:20: pid 4350: DEBUG: read_kind_from_backend max_count:1.000000 num_executed_nodes:1 2017-08-14 17:19:20: pid 4350: DEBUG: processing backend response 2017-08-14 17:19:20: pid 4350: DETAIL: received kind 'D'(44) from backend 2017-08-14 17:19:20: pid 4350: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 1 query in progress: 1 doing extended query: 1 2017-08-14 17:19:20: pid 4350: DEBUG: SimpleForwardToFrontend: packet:D length:86 2017-08-14 17:19:20: pid 4350: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 1 query in progress: 1 doing extended query: 1 2017-08-14 17:19:20: pid 4350: DEBUG: reading backend data packet kind 2017-08-14 17:19:20: pid 4350: DETAIL: master node id: 0 2017-08-14 17:19:20: pid 4350: DEBUG: reading backend data packet kind 2017-08-14 17:19:20: pid 4350: DETAIL: backend:0 of 1 kind = 'D' 2017-08-14 17:19:20: pid 4350: DEBUG: read_kind_from_backend max_count:1.000000 num_executed_nodes:1 2017-08-14 17:19:20: pid 4350: DEBUG: processing backend response 2017-08-14 17:19:20: pid 4350: DETAIL: received kind 'D'(44) from backend 2017-08-14 17:19:20: pid 4350: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 1 query in progress: 1 doing extended query: 1 2017-08-14 17:19:20: pid 4350: DEBUG: SimpleForwardToFrontend: packet:D length:83 2017-08-14 17:19:20: pid 4350: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 1 query in progress: 1 doing extended query: 1 2017-08-14 17:19:20: pid 4350: DEBUG: reading backend data packet kind 2017-08-14 17:19:20: pid 4350: DETAIL: master node id: 0 2017-08-14 17:19:20: pid 4350: DEBUG: reading backend data packet kind 2017-08-14 17:19:20: pid 4350: DETAIL: backend:0 of 1 kind = 'D' 2017-08-14 17:19:20: pid 4350: DEBUG: read_kind_from_backend max_count:1.000000 num_executed_nodes:1 2017-08-14 17:19:20: pid 4350: DEBUG: processing backend response 2017-08-14 17:19:20: pid 4350: DETAIL: received kind 'D'(44) from backend 2017-08-14 17:19:20: pid 4350: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 1 query in progress: 1 doing extended query: 1 2017-08-14 17:19:20: pid 4350: DEBUG: SimpleForwardToFrontend: packet:D length:84 2017-08-14 17:19:20: pid 4350: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 1 query in progress: 1 doing extended query: 1 2017-08-14 17:19:20: pid 4350: DEBUG: reading backend data packet kind 2017-08-14 17:19:20: pid 4350: DETAIL: master node id: 0 2017-08-14 17:19:20: pid 4350: DEBUG: reading backend data packet kind 2017-08-14 17:19:20: pid 4350: DETAIL: backend:0 of 1 kind = 'D' 2017-08-14 17:19:20: pid 4350: DEBUG: read_kind_from_backend max_count:1.000000 num_executed_nodes:1 2017-08-14 17:19:20: pid 4350: DEBUG: processing backend response 2017-08-14 17:19:20: pid 4350: DETAIL: received kind 'D'(44) from backend 2017-08-14 17:19:20: pid 4350: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 1 query in progress: 1 doing extended query: 1 2017-08-14 17:19:20: pid 4350: DEBUG: SimpleForwardToFrontend: packet:D length:87 2017-08-14 17:19:20: pid 4350: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 1 query in progress: 1 doing extended query: 1 2017-08-14 17:19:20: pid 4350: DEBUG: reading backend data packet kind 2017-08-14 17:19:20: pid 4350: DETAIL: master node id: 0 2017-08-14 17:19:20: pid 4350: DEBUG: reading backend data packet kind 2017-08-14 17:19:20: pid 4350: DETAIL: backend:0 of 1 kind = 'D' 2017-08-14 17:19:20: pid 4350: DEBUG: read_kind_from_backend max_count:1.000000 num_executed_nodes:1 2017-08-14 17:19:20: pid 4350: DEBUG: processing backend response 2017-08-14 17:19:20: pid 4350: DETAIL: received kind 'D'(44) from backend 2017-08-14 17:19:20: pid 4350: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 1 query in progress: 1 doing extended query: 1 2017-08-14 17:19:20: pid 4350: DEBUG: SimpleForwardToFrontend: packet:D length:85 2017-08-14 17:19:20: pid 4350: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 1 query in progress: 1 doing extended query: 1 2017-08-14 17:19:20: pid 4350: DEBUG: reading backend data packet kind 2017-08-14 17:19:20: pid 4350: DETAIL: master node id: 0 2017-08-14 17:19:20: pid 4350: DEBUG: reading backend data packet kind 2017-08-14 17:19:20: pid 4350: DETAIL: backend:0 of 1 kind = 'D' 2017-08-14 17:19:20: pid 4350: DEBUG: read_kind_from_backend max_count:1.000000 num_executed_nodes:1 2017-08-14 17:19:20: pid 4350: DEBUG: processing backend response 2017-08-14 17:19:20: pid 4350: DETAIL: received kind 'D'(44) from backend 2017-08-14 17:19:20: pid 4350: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 1 query in progress: 1 doing extended query: 1 2017-08-14 17:19:20: pid 4350: DEBUG: SimpleForwardToFrontend: packet:D length:85 2017-08-14 17:19:20: pid 4350: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 1 query in progress: 1 doing extended query: 1 2017-08-14 17:19:20: pid 4350: DEBUG: reading backend data packet kind 2017-08-14 17:19:20: pid 4350: DETAIL: master node id: 0 2017-08-14 17:19:20: pid 4350: DEBUG: reading backend data packet kind 2017-08-14 17:19:20: pid 4350: DETAIL: backend:0 of 1 kind = 'D' 2017-08-14 17:19:20: pid 4350: DEBUG: read_kind_from_backend max_count:1.000000 num_executed_nodes:1 2017-08-14 17:19:20: pid 4350: DEBUG: processing backend response 2017-08-14 17:19:20: pid 4350: DETAIL: received kind 'D'(44) from backend 2017-08-14 17:19:20: pid 4350: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 1 query in progress: 1 doing extended query: 1 2017-08-14 17:19:20: pid 4350: DEBUG: pool_read2: read 57 bytes from backend 0 2017-08-14 17:19:20: pid 4350: DEBUG: SimpleForwardToFrontend: packet:D length:85 2017-08-14 17:19:20: pid 4350: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 1 query in progress: 1 doing extended query: 1 2017-08-14 17:19:20: pid 4350: DEBUG: reading backend data packet kind 2017-08-14 17:19:20: pid 4350: DETAIL: master node id: 0 2017-08-14 17:19:20: pid 4350: DEBUG: pool_read: read 555 bytes from backend 0 2017-08-14 17:19:20: pid 4350: DEBUG: reading backend data packet kind 2017-08-14 17:19:20: pid 4350: DETAIL: backend:0 of 1 kind = 'D' 2017-08-14 17:19:20: pid 4350: DEBUG: read_kind_from_backend max_count:1.000000 num_executed_nodes:1 2017-08-14 17:19:20: pid 4350: DEBUG: processing backend response 2017-08-14 17:19:20: pid 4350: DETAIL: received kind 'D'(44) from backend 2017-08-14 17:19:20: pid 4350: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 1 query in progress: 1 doing extended query: 1 2017-08-14 17:19:20: pid 4350: DEBUG: SimpleForwardToFrontend: packet:D length:86 2017-08-14 17:19:20: pid 4350: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 1 query in progress: 1 doing extended query: 1 2017-08-14 17:19:20: pid 4350: DEBUG: reading backend data packet kind 2017-08-14 17:19:20: pid 4350: DETAIL: master node id: 0 2017-08-14 17:19:20: pid 4350: DEBUG: reading backend data packet kind 2017-08-14 17:19:20: pid 4350: DETAIL: backend:0 of 1 kind = 'D' 2017-08-14 17:19:20: pid 4350: DEBUG: read_kind_from_backend max_count:1.000000 num_executed_nodes:1 2017-08-14 17:19:20: pid 4350: DEBUG: processing backend response 2017-08-14 17:19:20: pid 4350: DETAIL: received kind 'D'(44) from backend 2017-08-14 17:19:20: pid 4350: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 1 query in progress: 1 doing extended query: 1 2017-08-14 17:19:20: pid 4350: DEBUG: SimpleForwardToFrontend: packet:D length:86 2017-08-14 17:19:20: pid 4350: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 1 query in progress: 1 doing extended query: 1 2017-08-14 17:19:20: pid 4350: DEBUG: reading backend data packet kind 2017-08-14 17:19:20: pid 4350: DETAIL: master node id: 0 2017-08-14 17:19:20: pid 4350: DEBUG: reading backend data packet kind 2017-08-14 17:19:20: pid 4350: DETAIL: backend:0 of 1 kind = 'D' 2017-08-14 17:19:20: pid 4350: DEBUG: read_kind_from_backend max_count:1.000000 num_executed_nodes:1 2017-08-14 17:19:20: pid 4350: DEBUG: processing backend response 2017-08-14 17:19:20: pid 4350: DETAIL: received kind 'D'(44) from backend 2017-08-14 17:19:20: pid 4350: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 1 query in progress: 1 doing extended query: 1 2017-08-14 17:19:20: pid 4350: DEBUG: SimpleForwardToFrontend: packet:D length:83 2017-08-14 17:19:20: pid 4350: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 1 query in progress: 1 doing extended query: 1 2017-08-14 17:19:20: pid 4350: DEBUG: reading backend data packet kind 2017-08-14 17:19:20: pid 4350: DETAIL: master node id: 0 2017-08-14 17:19:20: pid 4350: DEBUG: reading backend data packet kind 2017-08-14 17:19:20: pid 4350: DETAIL: backend:0 of 1 kind = 'D' 2017-08-14 17:19:20: pid 4350: DEBUG: read_kind_from_backend max_count:1.000000 num_executed_nodes:1 2017-08-14 17:19:20: pid 4350: DEBUG: processing backend response 2017-08-14 17:19:20: pid 4350: DETAIL: received kind 'D'(44) from backend 2017-08-14 17:19:20: pid 4350: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 1 query in progress: 1 doing extended query: 1 2017-08-14 17:19:20: pid 4350: DEBUG: SimpleForwardToFrontend: packet:D length:86 2017-08-14 17:19:20: pid 4350: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 1 query in progress: 1 doing extended query: 1 2017-08-14 17:19:20: pid 4350: DEBUG: reading backend data packet kind 2017-08-14 17:19:20: pid 4350: DETAIL: master node id: 0 2017-08-14 17:19:20: pid 4350: DEBUG: reading backend data packet kind 2017-08-14 17:19:20: pid 4350: DETAIL: backend:0 of 1 kind = 'D' 2017-08-14 17:19:20: pid 4350: DEBUG: read_kind_from_backend max_count:1.000000 num_executed_nodes:1 2017-08-14 17:19:20: pid 4350: DEBUG: processing backend response 2017-08-14 17:19:20: pid 4350: DETAIL: received kind 'D'(44) from backend 2017-08-14 17:19:20: pid 4350: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 1 query in progress: 1 doing extended query: 1 2017-08-14 17:19:20: pid 4350: DEBUG: SimpleForwardToFrontend: packet:D length:85 2017-08-14 17:19:20: pid 4350: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 1 query in progress: 1 doing extended query: 1 2017-08-14 17:19:20: pid 4350: DEBUG: reading backend data packet kind 2017-08-14 17:19:20: pid 4350: DETAIL: master node id: 0 2017-08-14 17:19:20: pid 4350: DEBUG: reading backend data packet kind 2017-08-14 17:19:20: pid 4350: DETAIL: backend:0 of 1 kind = 'D' 2017-08-14 17:19:20: pid 4350: DEBUG: read_kind_from_backend max_count:1.000000 num_executed_nodes:1 2017-08-14 17:19:20: pid 4350: DEBUG: processing backend response 2017-08-14 17:19:20: pid 4350: DETAIL: received kind 'D'(44) from backend 2017-08-14 17:19:20: pid 4350: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 1 query in progress: 1 doing extended query: 1 2017-08-14 17:19:20: pid 4350: DEBUG: SimpleForwardToFrontend: packet:D length:87 2017-08-14 17:19:20: pid 4350: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 1 query in progress: 1 doing extended query: 1 2017-08-14 17:19:20: pid 4350: DEBUG: reading backend data packet kind 2017-08-14 17:19:20: pid 4350: DETAIL: master node id: 0 2017-08-14 17:19:20: pid 4350: DEBUG: reading backend data packet kind 2017-08-14 17:19:20: pid 4350: DETAIL: backend:0 of 1 kind = 'C' 2017-08-14 17:19:20: pid 4350: DEBUG: read_kind_from_backend max_count:1.000000 num_executed_nodes:1 2017-08-14 17:19:20: pid 4350: DEBUG: processing backend response 2017-08-14 17:19:20: pid 4350: DETAIL: received kind 'C'(43) from backend 2017-08-14 17:19:20: pid 4350: DEBUG: session context: unsetting query in progress. DONE 2017-08-14 17:19:20: pid 4350: DEBUG: session context: setting command success. DONE 2017-08-14 17:19:20: pid 4350: DEBUG: processing frontend response 2017-08-14 17:19:20: pid 4350: DETAIL: received kind 'H'(48) from frontend 2017-08-14 17:19:20: pid 4350: DEBUG: session context: clearing doing extended query messaging. DONE 2017-08-14 17:19:20: pid 4350: DEBUG: pool_write: to backend: 0 kind:H 2017-08-14 17:19:20: pid 4350: DEBUG: processing frontend response 2017-08-14 17:19:20: pid 4350: DETAIL: received kind 'S'(53) from frontend 2017-08-14 17:19:20: pid 4350: DEBUG: session context: clearing doing extended query messaging. DONE 2017-08-14 17:19:20: pid 4350: DEBUG: session context: setting doing extended query messaging. DONE 2017-08-14 17:19:20: pid 4350: DEBUG: session context: setting query in progress. DONE 2017-08-14 17:19:20: pid 4350: DEBUG: pool_write: to backend: 0 kind:S 2017-08-14 17:19:20: pid 4350: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 1 query in progress: 1 doing extended query: 1 2017-08-14 17:19:20: pid 4350: DEBUG: reading backend data packet kind 2017-08-14 17:19:20: pid 4350: DETAIL: master node id: 0 2017-08-14 17:19:20: pid 4350: DEBUG: pool_read: read 6 bytes from backend 0 2017-08-14 17:19:20: pid 4350: DEBUG: reading backend data packet kind 2017-08-14 17:19:20: pid 4350: DETAIL: backend:0 of 1 kind = 'Z' 2017-08-14 17:19:20: pid 4350: DEBUG: read_kind_from_backend max_count:1.000000 num_executed_nodes:1 2017-08-14 17:19:20: pid 4350: DEBUG: processing backend response 2017-08-14 17:19:20: pid 4350: DETAIL: received kind 'Z'(5a) from backend 2017-08-14 17:19:20: pid 4350: DEBUG: processing backend response 2017-08-14 17:19:20: pid 4350: DETAIL: Ready For Query received 2017-08-14 17:19:20: pid 4350: DEBUG: session context: clearing ignore till sync. DONE 2017-08-14 17:19:20: pid 4350: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 1 query in progress: 1 doing extended query: 1 2017-08-14 17:19:20: pid 4350: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 1 query in progress: 1 doing extended query: 1 2017-08-14 17:19:20: pid 4350: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 1 query in progress: 1 doing extended query: 1 2017-08-14 17:19:20: pid 4350: DEBUG: reading message length 2017-08-14 17:19:20: pid 4350: DETAIL: slot: 0 length: 5 2017-08-14 17:19:20: pid 4350: DEBUG: processing ReadyForQuery 2017-08-14 17:19:20: pid 4350: DETAIL: transaction state 'I'(49) 2017-08-14 17:19:20: pid 4350: DEBUG: session context: unsetting query in progress. DONE 2017-08-14 17:19:20: pid 4350: DEBUG: processing frontend response 2017-08-14 17:19:20: pid 4350: DETAIL: received kind 'C'(43) from frontend 2017-08-14 17:19:20: pid 4350: DEBUG: session context: clearing doing extended query messaging. DONE 2017-08-14 17:19:20: pid 4350: DEBUG: session context: setting doing extended query messaging. DONE 2017-08-14 17:19:20: pid 4350: DEBUG: session context: setting query in progress. DONE 2017-08-14 17:19:20: pid 4350: DEBUG: Close: waiting for master completing the query 2017-08-14 17:19:20: pid 4350: DEBUG: session context: setting query in progress. DONE 2017-08-14 17:19:20: pid 4350: LOG: DB node id: 0 backend pid: 16934 statement: C message 2017-08-14 17:19:20: pid 4350: DEBUG: pool_send_and_wait: pool_set_sync_map: 0 2017-08-14 17:19:20: pid 4350: DEBUG: pool_write: to backend: 0 kind:C 2017-08-14 17:19:20: pid 4350: DEBUG: pool_write: to backend: 0 kind:H 2017-08-14 17:19:20: pid 4350: DEBUG: pool_send_and_wait: send flush message to 0 2017-08-14 17:19:20: pid 4350: DEBUG: waiting for query response 2017-08-14 17:19:20: pid 4350: DETAIL: waiting for backend:0 to complete the query 2017-08-14 17:19:20: pid 4350: DEBUG: pool_read: read 5 bytes from backend 0 2017-08-14 17:19:20: pid 4350: DEBUG: pool_pending_message_add: message type:2 message len:26 2017-08-14 17:19:20: pid 4350: DEBUG: session context: unsetting query in progress. DONE 2017-08-14 17:19:20: pid 4350: DEBUG: pool_use_sync_map: we can use sync map: 1 2017-08-14 17:19:20: pid 4350: DEBUG: reading backend data packet kind 2017-08-14 17:19:20: pid 4350: DETAIL: master node id: 0 2017-08-14 17:19:20: pid 4350: DEBUG: reading backend data packet kind 2017-08-14 17:19:20: pid 4350: DETAIL: backend:0 of 1 kind = '3' 2017-08-14 17:19:20: pid 4350: DEBUG: read_kind_from_backend max_count:1.000000 num_executed_nodes:1 2017-08-14 17:19:20: pid 4350: DEBUG: processing backend response 2017-08-14 17:19:20: pid 4350: DETAIL: received kind '3'(33) from backend 2017-08-14 17:19:20: pid 4350: DEBUG: pool_use_sync_map: we can use sync map: 1 2017-08-14 17:19:20: pid 4350: DEBUG: SimpleForwardToFrontend: packet:3 length:0 2017-08-14 17:19:20: pid 4350: DEBUG: session context: setting command success. DONE 2017-08-14 17:19:20: pid 4350: DEBUG: processing frontend response 2017-08-14 17:19:20: pid 4350: DETAIL: received kind 'H'(48) from frontend 2017-08-14 17:19:20: pid 4350: DEBUG: session context: clearing doing extended query messaging. DONE 2017-08-14 17:19:20: pid 4350: DEBUG: pool_write: to backend: 0 kind:H 2017-08-14 17:19:20: pid 4350: DEBUG: processing frontend response 2017-08-14 17:19:20: pid 4350: DETAIL: received kind 'S'(53) from frontend 2017-08-14 17:19:20: pid 4350: DEBUG: session context: clearing doing extended query messaging. DONE 2017-08-14 17:19:20: pid 4350: DEBUG: session context: setting doing extended query messaging. DONE 2017-08-14 17:19:20: pid 4350: DEBUG: session context: setting query in progress. DONE 2017-08-14 17:19:20: pid 4350: DEBUG: pool_write: to backend: 0 kind:S 2017-08-14 17:19:20: pid 4350: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 1 query in progress: 1 doing extended query: 1 2017-08-14 17:19:20: pid 4350: DEBUG: reading backend data packet kind 2017-08-14 17:19:20: pid 4350: DETAIL: master node id: 0 2017-08-14 17:19:20: pid 4350: DEBUG: pool_read: read 6 bytes from backend 0 2017-08-14 17:19:20: pid 4350: DEBUG: reading backend data packet kind 2017-08-14 17:19:20: pid 4350: DETAIL: backend:0 of 1 kind = 'Z' 2017-08-14 17:19:20: pid 4350: DEBUG: read_kind_from_backend max_count:1.000000 num_executed_nodes:1 2017-08-14 17:19:20: pid 4350: DEBUG: processing backend response 2017-08-14 17:19:20: pid 4350: DETAIL: received kind 'Z'(5a) from backend 2017-08-14 17:19:20: pid 4350: DEBUG: processing backend response 2017-08-14 17:19:20: pid 4350: DETAIL: Ready For Query received 2017-08-14 17:19:20: pid 4350: DEBUG: session context: clearing ignore till sync. DONE 2017-08-14 17:19:20: pid 4350: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 1 query in progress: 1 doing extended query: 1 2017-08-14 17:19:20: pid 4350: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 1 query in progress: 1 doing extended query: 1 2017-08-14 17:19:20: pid 4350: DEBUG: pool_use_sync_map: we cannot use sync map because STREAM: 1 query in progress: 1 doing extended query: 1 2017-08-14 17:19:20: pid 4350: DEBUG: reading message length 2017-08-14 17:19:20: pid 4350: DETAIL: slot: 0 length: 5 2017-08-14 17:19:20: pid 4350: DEBUG: processing ReadyForQuery 2017-08-14 17:19:20: pid 4350: DETAIL: transaction state 'I'(49) 2017-08-14 17:19:20: pid 4350: DEBUG: session context: unsetting query in progress. DONE |
|
|
I would correct my self and be more specific: Redshift driver + query cache : gives error PostgreSQL driver + query cache : gives error Redshift Node driver + query cache : no error Redshift Java drive + no query cache : no erriro |
|
|
What's "Redshift Node driver"? I'm not familiar with Redshift. |
|
|
The driver I am trying to use is a redshift Java driver which is giving errors. I tried to connect using a different application, which is a node application, and there it connected without any trouble. In the previous reply I have attached the logs with trying to connect using the redshift Java driver without caching, which was successful, I also tried to connect using Postgres driver which also connects without cache but gives error with cache. |
|
|
Oh I see. BTW, do you have PostgreSQL instance (not Redshift) you can connect to? If so, I would like to know if: PostgreSQL (or Redshit driver) ->Pgpool-II (query cache enabled) -> PostgreSQL works. If it works, the problem is likely specific to Redshit. If it does not work, the problem is likely with Pgpool-II. In this case you might want to try the 3.6 stable head, which I made fix for query cache recently. (https://git.postgresql.org/gitweb/?p=pgpool2.git;a=commit;h=730a545ae6c46e64afafa6aef3f7630437cbf016) |
|
|
Can you please tell me which version is the stable one which includes your latest fixes? This is the command I use to download the version - curl -L -o pgpool-II-3.6.1.tar.gz http://www.pgpool.net/download.php?f=pgpool-II-3.6.1.tar.gz |
|
|
|
|
|
The latest stable version will be 3.6.6, which is expected to be released by the end of this month or early next month. You can download the git source from https://git.postgresql.org/gitweb/?p=pgpool2.git;a=summary In the mean time I have created a tar ball which is identical to the source above. You can try it if you like. (Note that the version number in the tar ball is still 3.6.5 but includes all the fix after 3.6.5 including the one I mentioned). |
|
|
Thank you. I am trying to implement it. Also wanted to know if PGPool supports caching of queries, which are hitting views? |
|
|
Queries hitting views are not cached because if underlying base tables are changed, the cache becomes bogus. |
|
|
No follow up over 1 month. Issue closed. |
| Date Modified | Username | Field | Change |
|---|---|---|---|
| 2017-08-09 06:42 | akshayjain | New Issue | |
| 2017-08-09 06:44 | akshayjain | Note Added: 0001627 | |
| 2017-08-09 07:31 | t-ishii | Note Added: 0001628 | |
| 2017-08-09 07:34 | akshayjain | Note Added: 0001629 | |
| 2017-08-09 17:16 | t-ishii | Note Added: 0001630 | |
| 2017-08-10 01:38 | akshayjain | Note Added: 0001633 | |
| 2017-08-10 02:18 | akshayjain | Note Added: 0001634 | |
| 2017-08-10 18:04 | t-ishii | Assigned To | => t-ishii |
| 2017-08-10 18:04 | t-ishii | Status | new => assigned |
| 2017-08-10 18:11 | t-ishii | Note Added: 0001635 | |
| 2017-08-10 18:17 | t-ishii | Note Edited: 0001635 | |
| 2017-08-11 02:27 | akshayjain | File Added: Screen Shot 2017-08-10 at 10.26.14 AM.png | |
| 2017-08-11 02:27 | akshayjain | Note Added: 0001636 | |
| 2017-08-11 07:03 | t-ishii | Note Added: 0001637 | |
| 2017-08-11 07:11 | akshayjain | Note Added: 0001638 | |
| 2017-08-11 07:33 | akshayjain | Note Added: 0001639 | |
| 2017-08-11 08:02 | t-ishii | Note Added: 0001640 | |
| 2017-08-11 19:45 | t-ishii | Status | assigned => feedback |
| 2017-08-12 03:22 | akshayjain | Note Added: 0001645 | |
| 2017-08-12 03:22 | akshayjain | Status | feedback => assigned |
| 2017-08-12 03:41 | akshayjain | Note Added: 0001646 | |
| 2017-08-12 23:35 | t-ishii | Note Added: 0001647 | |
| 2017-08-13 22:51 | t-ishii | Status | assigned => feedback |
| 2017-08-15 02:22 | akshayjain | Note Added: 0001651 | |
| 2017-08-15 02:22 | akshayjain | Status | feedback => assigned |
| 2017-08-15 08:48 | akshayjain | Note Added: 0001652 | |
| 2017-08-15 10:50 | t-ishii | Note Added: 0001653 | |
| 2017-08-15 10:51 | t-ishii | Status | assigned => feedback |
| 2017-08-15 10:56 | akshayjain | Note Added: 0001654 | |
| 2017-08-15 10:56 | akshayjain | Status | feedback => assigned |
| 2017-08-15 11:03 | t-ishii | Note Added: 0001655 | |
| 2017-08-15 14:45 | t-ishii | Status | assigned => feedback |
| 2017-08-16 07:07 | akshayjain | Note Added: 0001660 | |
| 2017-08-16 07:07 | akshayjain | Status | feedback => assigned |
| 2017-08-16 08:34 | t-ishii | File Added: pgpool-II-3.6.5.tar.gz | |
| 2017-08-16 08:34 | t-ishii | Note Added: 0001661 | |
| 2017-08-16 11:07 | t-ishii | Status | assigned => feedback |
| 2017-08-19 06:52 | akshayjain | Note Added: 0001674 | |
| 2017-08-19 06:52 | akshayjain | Status | feedback => assigned |
| 2017-08-19 21:07 | t-ishii | Note Added: 0001675 | |
| 2017-08-24 22:31 | t-ishii | Target Version | => 3.5.10 |
| 2017-08-28 14:05 | t-ishii | Status | assigned => feedback |
| 2017-10-03 15:42 | t-ishii | Note Added: 0001727 | |
| 2017-10-03 15:44 | t-ishii | Status | feedback => closed |
| 2017-10-03 15:44 | t-ishii | Fixed in Version | => 3.5.10 |
