Thanks Toshihiro for the feedback! I very much appreciate that.<br><br>Can you please provide any hints/tips on where blocking occurs in the code, and advices on what needs to be changed to have backend messages to be processed in parallel? If it&#39;s not too significant change affecting rest of pgpool features, might decide to do that change in a custom build, at least temporarily.<br>
<br>Would you consider supporting both concurrent and sequential processing of backend messages, make it configurable option and for backward compatibility (especially with non-java clients) have it default to sequential?<br>
<br>Or maybe even go with concurrent for all clients and let non-java drivers fix their support to be proper one and aligned with postgres frontend/backend protocol specification and capabilities?<br><br>Regards,<br>Stevo.<br>
<br><div class="gmail_quote">On Fri, Nov 25, 2011 at 12:47 AM, Toshihiro Kitagawa <span dir="ltr">&lt;<a href="mailto:kitagawa@sraoss.co.jp">kitagawa@sraoss.co.jp</a>&gt;</span> wrote:<br><blockquote class="gmail_quote" style="margin:0 0 0 .8ex;border-left:1px #ccc solid;padding-left:1ex;">
<div class="im">On Thu, 24 Nov 2011 18:19:11 +0900 (JST)<br>
Tatsuo Ishii &lt;<a href="mailto:ishii@sraoss.co.jp">ishii@sraoss.co.jp</a>&gt; wrote:<br>
<br>
&gt; I&#39;m not sure why pgpool need to wait for backend response here. Maybe<br>
&gt; Toshihiro has some idea about this. Toshihiro?<br>
<br>
</div>I guess that the implementation for extended query protocol only succeeded<br>
to the simple design which relay a message at every message.<br>
<br>
I think that pgpool doesn&#39;t need to wait for backend response until Sync<br>
message, if frontend is JDBC driver. However, it might cause problem with<br>
the other drivers.<br>
<span class="HOEnZb"><font color="#888888"><br>
--<br>
Toshihiro Kitagawa<br>
SRA OSS, Inc. Japan<br>
</font></span><div class="HOEnZb"><div class="h5"><br>
&gt; --<br>
&gt; Tatsuo Ishii<br>
&gt; SRA OSS, Inc. Japan<br>
&gt; English: <a href="http://www.sraoss.co.jp/index_en.php" target="_blank">http://www.sraoss.co.jp/index_en.php</a><br>
&gt; Japanese: <a href="http://www.sraoss.co.jp" target="_blank">http://www.sraoss.co.jp</a><br>
&gt;<br>
&gt; &gt; Thanks Tatsuo for quick response!<br>
&gt; &gt;<br>
&gt; &gt; If it was Java based, I would be much more able to contribute in fixing<br>
&gt; &gt; this issue. Child processes which handle connections/communication seem to<br>
&gt; &gt; be single threaded. Not sure where does pgpool block, must be somewhere<br>
&gt; &gt; when reading and/or sending postgres protocol message. Maybe profiling<br>
&gt; &gt; pgpool executable would help - any advices on how to do that properly are<br>
&gt; &gt; welcome.<br>
&gt; &gt;<br>
&gt; &gt; Is pgpool issue tracker at pgfoundry still being used (<br>
&gt; &gt; <a href="http://pgfoundry.org/tracker/?atid=298&amp;group_id=1000055&amp;func=browse" target="_blank">http://pgfoundry.org/tracker/?atid=298&amp;group_id=1000055&amp;func=browse</a> ) ?<br>
&gt; &gt; Checking whether to report this issue there or somewhere else.<br>
&gt; &gt;<br>
&gt; &gt; Would this issue even be considered for fixing or is this design decision<br>
&gt; &gt; that&#39;s not likely to be changed any time soon? Need this info to decide<br>
&gt; &gt; whether to stick with pgpool (much preferred) or look for other solutions.<br>
&gt; &gt;<br>
&gt; &gt; Regards,<br>
&gt; &gt; Stevo.<br>
&gt; &gt;<br>
&gt; &gt; 2011/11/24 Tatsuo Ishii &lt;<a href="mailto:ishii@sraoss.co.jp">ishii@sraoss.co.jp</a>&gt;<br>
&gt; &gt;<br>
&gt; &gt;&gt; &gt; As temporary solution, is it possible to force pgpool to use v2 protocol<br>
&gt; &gt;&gt; &gt; when communicating with backend?<br>
&gt; &gt;&gt;<br>
&gt; &gt;&gt; No.<br>
&gt; &gt;&gt; --<br>
&gt; &gt;&gt; Tatsuo Ishii<br>
&gt; &gt;&gt; SRA OSS, Inc. Japan<br>
&gt; &gt;&gt; English: <a href="http://www.sraoss.co.jp/index_en.php" target="_blank">http://www.sraoss.co.jp/index_en.php</a><br>
&gt; &gt;&gt; Japanese: <a href="http://www.sraoss.co.jp" target="_blank">http://www.sraoss.co.jp</a><br>
&gt; &gt;&gt;<br>
&gt; &gt;&gt; &gt; If yes, can pgpool at the same time use v3 protocol to communicate with<br>
&gt; &gt;&gt; the<br>
&gt; &gt;&gt; &gt; clients (postgresql jdbc driver)?<br>
&gt; &gt;&gt; &gt;<br>
&gt; &gt;&gt; &gt; Regards,<br>
&gt; &gt;&gt; &gt; Stevo.<br>
&gt; &gt;&gt; &gt;<br>
&gt; &gt;&gt; &gt; On Wed, Nov 23, 2011 at 11:46 AM, Stevo Slavić &lt;<a href="mailto:sslavic@gmail.com">sslavic@gmail.com</a>&gt;<br>
&gt; &gt;&gt; wrote:<br>
&gt; &gt;&gt; &gt;<br>
&gt; &gt;&gt; &gt;&gt; It&#39;s definitely pgpool issue with serializing postgres frontend/backend<br>
&gt; &gt;&gt; &gt;&gt; protocol messages. Tried on environment with actual (non-simulated)<br>
&gt; &gt;&gt; &gt;&gt; latency. Same work takes 5 times longer with pgpool then without it.<br>
&gt; &gt;&gt; &gt;&gt;<br>
&gt; &gt;&gt; &gt;&gt; Using pgpool 3.1.<br>
&gt; &gt;&gt; &gt;&gt;<br>
&gt; &gt;&gt; &gt;&gt; Regards,<br>
&gt; &gt;&gt; &gt;&gt; Stevo.<br>
&gt; &gt;&gt; &gt;&gt;<br>
&gt; &gt;&gt; &gt;&gt;<br>
&gt; &gt;&gt; &gt;&gt; On Mon, Nov 21, 2011 at 3:28 PM, Stevo Slavić &lt;<a href="mailto:sslavic@gmail.com">sslavic@gmail.com</a>&gt;<br>
&gt; &gt;&gt; wrote:<br>
&gt; &gt;&gt; &gt;&gt;<br>
&gt; &gt;&gt; &gt;&gt;&gt; Hello pgpool users,<br>
&gt; &gt;&gt; &gt;&gt;&gt;<br>
&gt; &gt;&gt; &gt;&gt;&gt; Using tc (see <a href="http://linux.die.net/man/8/tc" target="_blank">http://linux.die.net/man/8/tc</a> ) I&#39;m simulating 60ms<br>
&gt; &gt;&gt; &gt;&gt;&gt; latency between pgpool and a master backend. When executing a simple<br>
&gt; &gt;&gt; query<br>
&gt; &gt;&gt; &gt;&gt;&gt; over pgpool, backend messages seem to get serialized (see [1], notice<br>
&gt; &gt;&gt; 60ms+<br>
&gt; &gt;&gt; &gt;&gt;&gt; between almost every backend message), while when executing same query<br>
&gt; &gt;&gt; on<br>
&gt; &gt;&gt; &gt;&gt;&gt; same backend but directly (not through pgpool), backend messages get<br>
&gt; &gt;&gt; sent<br>
&gt; &gt;&gt; &gt;&gt;&gt; concurrently (see [2]) back to the driver/client.<br>
&gt; &gt;&gt; &gt;&gt;&gt;<br>
&gt; &gt;&gt; &gt;&gt;&gt; Query is executed from a simple CLI Java application which makes use of<br>
&gt; &gt;&gt; &gt;&gt;&gt; postgresql-9.1-901.jdbc4.jar driver, with logging enabled. See [3]<br>
&gt; &gt;&gt; pgpool<br>
&gt; &gt;&gt; &gt;&gt;&gt; debug log when this query is executed through pgpool.<br>
&gt; &gt;&gt; &gt;&gt;&gt;<br>
&gt; &gt;&gt; &gt;&gt;&gt; Does anyone have any ideas why is pgpool serializing these backend<br>
&gt; &gt;&gt; &gt;&gt;&gt; messages?<br>
&gt; &gt;&gt; &gt;&gt;&gt;<br>
&gt; &gt;&gt; &gt;&gt;&gt; Is there a way to enable postgres protocol messages logging in pgpool?<br>
&gt; &gt;&gt; &gt;&gt;&gt; Would like to configure that to see if pgpool is receiving messages.<br>
&gt; &gt;&gt; &gt;&gt;&gt;<br>
&gt; &gt;&gt; &gt;&gt;&gt; Also timestamp log format seems to fixed, and doesn&#39;t include<br>
&gt; &gt;&gt; &gt;&gt;&gt; milliseconds. Any way to change that?<br>
&gt; &gt;&gt; &gt;&gt;&gt;<br>
&gt; &gt;&gt; &gt;&gt;&gt; Regards,<br>
&gt; &gt;&gt; &gt;&gt;&gt; Stevo.<br>
&gt; &gt;&gt; &gt;&gt;&gt;<br>
&gt; &gt;&gt; &gt;&gt;&gt;<br>
&gt; &gt;&gt; &gt;&gt;&gt; [1] jdbc driver log output, query execution through pgpool<br>
&gt; &gt;&gt; &gt;&gt;&gt; 23:03:22.625 (1) PostgreSQL 9.1 JDBC4 (build 901)<br>
&gt; &gt;&gt; &gt;&gt;&gt; 23:03:22.646 (1) Trying to establish a protocol version 3 connection to<br>
&gt; &gt;&gt; &gt;&gt;&gt; pgsql_srv_standby:9999<br>
&gt; &gt;&gt; &gt;&gt;&gt; 23:03:22.709 (1)  FE=&gt; StartupPacket(user=foo, database=foo,<br>
&gt; &gt;&gt; &gt;&gt;&gt; client_encoding=UTF8, DateStyle=ISO, extra_float_digits=2)<br>
&gt; &gt;&gt; &gt;&gt;&gt; 23:03:22.835 (1)  &lt;=BE AuthenticationOk<br>
&gt; &gt;&gt; &gt;&gt;&gt; 23:03:22.849 (1)  &lt;=BE ParameterStatus(application_name = )<br>
&gt; &gt;&gt; &gt;&gt;&gt; 23:03:22.849 (1)  &lt;=BE ParameterStatus(client_encoding = UTF8)<br>
&gt; &gt;&gt; &gt;&gt;&gt; 23:03:22.851 (1)  &lt;=BE ParameterStatus(DateStyle = ISO, MDY)<br>
&gt; &gt;&gt; &gt;&gt;&gt; 23:03:22.851 (1)  &lt;=BE ParameterStatus(integer_datetimes = on)<br>
&gt; &gt;&gt; &gt;&gt;&gt; 23:03:22.851 (1)  &lt;=BE ParameterStatus(IntervalStyle = postgres)<br>
&gt; &gt;&gt; &gt;&gt;&gt; 23:03:22.851 (1)  &lt;=BE ParameterStatus(is_superuser = on)<br>
&gt; &gt;&gt; &gt;&gt;&gt; 23:03:22.851 (1)  &lt;=BE ParameterStatus(server_encoding = UTF8)<br>
&gt; &gt;&gt; &gt;&gt;&gt; 23:03:22.851 (1)  &lt;=BE ParameterStatus(server_version = 9.1.1)<br>
&gt; &gt;&gt; &gt;&gt;&gt; 23:03:22.852 (1)  &lt;=BE ParameterStatus(session_authorization = foo)<br>
&gt; &gt;&gt; &gt;&gt;&gt; 23:03:22.852 (1)  &lt;=BE ParameterStatus(standard_conforming_strings =<br>
&gt; &gt;&gt; on)<br>
&gt; &gt;&gt; &gt;&gt;&gt; 23:03:22.852 (1)  &lt;=BE ParameterStatus(TimeZone = Europe/Amsterdam)<br>
&gt; &gt;&gt; &gt;&gt;&gt; 23:03:22.852 (1)  &lt;=BE BackendKeyData(pid=24275,ckey=1498571326)<br>
&gt; &gt;&gt; &gt;&gt;&gt; 23:03:22.852 (1)  &lt;=BE ReadyForQuery(I)<br>
&gt; &gt;&gt; &gt;&gt;&gt; 23:03:22.858 (1) simple execute,<br>
&gt; &gt;&gt; &gt;&gt;&gt;<br>
&gt; &gt;&gt; handler=org.postgresql.core.SetupQueryRunner$SimpleResultHandler@7f54ee6,<br>
&gt; &gt;&gt; &gt;&gt;&gt; maxRows=0, fetchSize=0, flags=23<br>
&gt; &gt;&gt; &gt;&gt;&gt; 23:03:22.864 (1)  FE=&gt; Parse(stmt=null,query=&quot;SET extra_float_digits =<br>
&gt; &gt;&gt; &gt;&gt;&gt; 3&quot;,oids={})<br>
&gt; &gt;&gt; &gt;&gt;&gt; 23:03:22.873 (1)  FE=&gt; Bind(stmt=null,portal=null)<br>
&gt; &gt;&gt; &gt;&gt;&gt; 23:03:22.873 (1)  FE=&gt; Execute(portal=null,limit=1)<br>
&gt; &gt;&gt; &gt;&gt;&gt; 23:03:22.874 (1)  FE=&gt; Sync<br>
&gt; &gt;&gt; &gt;&gt;&gt; 23:03:22.934 (1)  &lt;=BE ParseComplete [null]<br>
&gt; &gt;&gt; &gt;&gt;&gt; 23:03:22.995 (1)  &lt;=BE BindComplete [null]<br>
&gt; &gt;&gt; &gt;&gt;&gt; 23:03:23.057 (1)  &lt;=BE CommandStatus(SET)<br>
&gt; &gt;&gt; &gt;&gt;&gt; 23:03:23.118 (1)  &lt;=BE ReadyForQuery(I)<br>
&gt; &gt;&gt; &gt;&gt;&gt; 23:03:23.118 (1)     compatible = 9.1<br>
&gt; &gt;&gt; &gt;&gt;&gt; 23:03:23.118 (1)     loglevel = 2<br>
&gt; &gt;&gt; &gt;&gt;&gt; 23:03:23.118 (1)     prepare threshold = 5<br>
&gt; &gt;&gt; &gt;&gt;&gt; getConnection returning<br>
&gt; &gt;&gt; &gt;&gt;&gt; driver[className=org.postgresql.Driver,org.postgresql.Driver@7f05238]<br>
&gt; &gt;&gt; &gt;&gt;&gt; 23:03:23.150 (1) simple execute,<br>
&gt; &gt;&gt; &gt;&gt;&gt;<br>
&gt; &gt;&gt; handler=org.postgresql.jdbc2.AbstractJdbc2Statement$StatementResultHandler@7f64160<br>
&gt; &gt;&gt; ,<br>
&gt; &gt;&gt; &gt;&gt;&gt; maxRows=0, fetchSize=0, flags=17<br>
&gt; &gt;&gt; &gt;&gt;&gt; 23:03:23.150 (1)  FE=&gt; Parse(stmt=null,query=&quot;select<br>
&gt; &gt;&gt; &gt;&gt;&gt; nextval(&#39;hibernate_sequence&#39;)&quot;,oids={})<br>
&gt; &gt;&gt; &gt;&gt;&gt; 23:03:23.150 (1)  FE=&gt; Bind(stmt=null,portal=null)<br>
&gt; &gt;&gt; &gt;&gt;&gt; 23:03:23.151 (1)  FE=&gt; Describe(portal=null)<br>
&gt; &gt;&gt; &gt;&gt;&gt; 23:03:23.151 (1)  FE=&gt; Execute(portal=null,limit=0)<br>
&gt; &gt;&gt; &gt;&gt;&gt; 23:03:23.151 (1)  FE=&gt; Sync<br>
&gt; &gt;&gt; &gt;&gt;&gt; 23:03:23.212 (1)  &lt;=BE ParseComplete [null]<br>
&gt; &gt;&gt; &gt;&gt;&gt; 23:03:23.273 (1)  &lt;=BE BindComplete [null]<br>
&gt; &gt;&gt; &gt;&gt;&gt; 23:03:23.335 (1)  &lt;=BE RowDescription(1)<br>
&gt; &gt;&gt; &gt;&gt;&gt; 23:03:23.396 (1)  &lt;=BE DataRow<br>
&gt; &gt;&gt; &gt;&gt;&gt; 23:03:23.396 (1)  &lt;=BE CommandStatus(SELECT 1)<br>
&gt; &gt;&gt; &gt;&gt;&gt; 23:03:23.456 (1)  &lt;=BE ReadyForQuery(I)<br>
&gt; &gt;&gt; &gt;&gt;&gt; 23:03:23.462 (1)  FE=&gt; Terminate<br>
&gt; &gt;&gt; &gt;&gt;&gt;<br>
&gt; &gt;&gt; &gt;&gt;&gt; [2] jdbc driver log output, query execution directly on backend<br>
&gt; &gt;&gt; &gt;&gt;&gt; 21:16:05.428 (1) simple execute,<br>
&gt; &gt;&gt; &gt;&gt;&gt;<br>
&gt; &gt;&gt; handler=org.postgresql.jdbc2.AbstractJdbc2Statement$StatementResultHandler@7f74161<br>
&gt; &gt;&gt; ,<br>
&gt; &gt;&gt; &gt;&gt;&gt; maxRows=0, fetchSize=0, flags=17<br>
&gt; &gt;&gt; &gt;&gt;&gt; 21:16:05.428 (1)  FE=&gt; Parse(stmt=null,query=&quot;select<br>
&gt; &gt;&gt; &gt;&gt;&gt; nextval(&#39;hibernate_sequence&#39;)&quot;,oids={})<br>
&gt; &gt;&gt; &gt;&gt;&gt; 21:16:05.428 (1)  FE=&gt; Bind(stmt=null,portal=null)<br>
&gt; &gt;&gt; &gt;&gt;&gt; 21:16:05.428 (1)  FE=&gt; Describe(portal=null)<br>
&gt; &gt;&gt; &gt;&gt;&gt; 21:16:05.428 (1)  FE=&gt; Execute(portal=null,limit=0)<br>
&gt; &gt;&gt; &gt;&gt;&gt; 21:16:05.428 (1)  FE=&gt; Sync<br>
&gt; &gt;&gt; &gt;&gt;&gt; 21:16:05.489 (1)  &lt;=BE ParseComplete [null]<br>
&gt; &gt;&gt; &gt;&gt;&gt; 21:16:05.489 (1)  &lt;=BE BindComplete [null]<br>
&gt; &gt;&gt; &gt;&gt;&gt; 21:16:05.489 (1)  &lt;=BE RowDescription(1)<br>
&gt; &gt;&gt; &gt;&gt;&gt; 21:16:05.489 (1)  &lt;=BE DataRow<br>
&gt; &gt;&gt; &gt;&gt;&gt; 21:16:05.489 (1)  &lt;=BE CommandStatus(SELECT 1)<br>
&gt; &gt;&gt; &gt;&gt;&gt; 21:16:05.489 (1)  &lt;=BE ReadyForQuery(I)<br>
&gt; &gt;&gt; &gt;&gt;&gt;<br>
&gt; &gt;&gt; &gt;&gt;&gt; [3] pgpool debug log<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: key: pid_file_name<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: value:<br>
&gt; &gt;&gt; &gt;&gt;&gt; &#39;/var/run/pgpool-II-91/pgpool.pid&#39; kind: 4<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: key: logdir<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: value: &#39;/data/postgresql_db&#39;<br>
&gt; &gt;&gt; kind: 4<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: key: connection_cache<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: value: off kind: 1<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: key: reset_query_list<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: value: &#39;ABORT; DISCARD ALL&#39;<br>
&gt; &gt;&gt; kind: 4<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: extract_string_tokens: token:<br>
&gt; &gt;&gt; ABORT<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: extract_string_tokens: token:<br>
&gt; &gt;&gt; &gt;&gt;&gt;  DISCARD ALL<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: key: replication_mode<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: value: off kind: 1<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: key: replicate_select<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: value: off kind: 1<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: replicate_select: 0<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: key: insert_lock<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: value: off kind: 1<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: key: lobj_lock_table<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: value: &#39;&#39; kind: 4<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: key: replication_stop_on_mismatch<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: value: off kind: 1<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: replication_stop_on_mismatch: 0<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: key:<br>
&gt; &gt;&gt; &gt;&gt;&gt; failover_if_affected_tuples_mismatch<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: value: off kind: 1<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055:<br>
&gt; &gt;&gt; &gt;&gt;&gt; failover_if_affected_tuples_mismatch: 0<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: key: load_balance_mode<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: value: off kind: 1<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: key: ignore_leading_white_space<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: value: on kind: 1<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: key: white_function_list<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: value: &#39;&#39; kind: 4<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: key: black_function_list<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: value:<br>
&gt; &gt;&gt; &gt;&gt;&gt; &#39;currval,lastval,nextval,setval&#39; kind: 4<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: extract_string_tokens: token:<br>
&gt; &gt;&gt; &gt;&gt;&gt; currval<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: extract_string_tokens: token:<br>
&gt; &gt;&gt; &gt;&gt;&gt; lastval<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: extract_string_tokens: token:<br>
&gt; &gt;&gt; &gt;&gt;&gt; nextval<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: extract_string_tokens: token:<br>
&gt; &gt;&gt; setval<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: add_to_patterns: regex pattern:<br>
&gt; &gt;&gt; &gt;&gt;&gt; ^currval$<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: add_to_patterns: regex pattern:<br>
&gt; &gt;&gt; &gt;&gt;&gt; ^lastval$<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: add_to_patterns: regex pattern:<br>
&gt; &gt;&gt; &gt;&gt;&gt; ^nextval$<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: add_to_patterns: regex pattern:<br>
&gt; &gt;&gt; &gt;&gt;&gt; ^setval$<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: key: backend_hostname0<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: value: &#39;pgsql_srv_primary&#39; kind:<br>
&gt; &gt;&gt; 4<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: key: backend_port0<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: value: 5432 kind: 2<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: key: backend_weight0<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: value: 1 kind: 2<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: key: backend_data_directory0<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: value: &#39;/data/postgresql_db&#39;<br>
&gt; &gt;&gt; kind: 4<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: key: backend_flag0<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: value: &#39;ALLOW_TO_FAILOVER&#39; kind:<br>
&gt; &gt;&gt; 4<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: key: backend_hostname1<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: value: &#39;/tmp&#39; kind: 4<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: key: backend_port1<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: value: 5432 kind: 2<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: key: backend_weight1<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: value: 1 kind: 2<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: key: backend_data_directory1<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: value: &#39;/data/postgresql_db&#39;<br>
&gt; &gt;&gt; kind: 4<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: key: backend_flag1<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: value: &#39;DISALLOW_TO_FAILOVER&#39;<br>
&gt; &gt;&gt; kind:<br>
&gt; &gt;&gt; &gt;&gt;&gt; 4<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: key: master_slave_mode<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: value: off kind: 1<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: key: master_slave_sub_mode<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: value: &#39;stream&#39; kind: 4<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: key: sr_check_period<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: value: 10 kind: 2<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: key: sr_check_user<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: value: &#39;foo&#39; kind: 4<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: key: sr_check_password<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: value: &#39;&#39; kind: 4<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: key: delay_threshold<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: value: 10000000 kind: 2<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: key: follow_master_command<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: value: &#39;&#39; kind: 4<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: key: parallel_mode<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: value: off kind: 1<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: key: enable_query_cache<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: value: off kind: 1<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: key: pgpool2_hostname<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: value: &#39;pgsql_srv_standby&#39; kind:<br>
&gt; &gt;&gt; 4<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: key: system_db_hostname<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: value: &#39;pgsql_srv_primary&#39; kind:<br>
&gt; &gt;&gt; 4<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: key: system_db_port<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: value: 5432 kind: 2<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: key: system_db_dbname<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: value: &#39;pgpool&#39; kind: 4<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: key: system_db_schema<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: value: &#39;pgpool_catalog&#39; kind: 4<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: key: system_db_user<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: value: &#39;postgres&#39; kind: 4<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: key: system_db_password<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: value: &#39;&#39; kind: 4<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: key: health_check_period<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: value: 0 kind: 2<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: key: health_check_timeout<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: value: 20 kind: 2<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: key: health_check_user<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: value: &#39;foo&#39; kind: 4<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: key: health_check_password<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: value: &#39;foo&#39; kind: 4<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: key: fail_over_on_backend_error<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: value: on kind: 1<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: key: recovery_user<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: value: &#39;nobody&#39; kind: 4<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: key: recovery_password<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: value: &#39;&#39; kind: 4<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: key: recovery_1st_stage_command<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: value: &#39;&#39; kind: 4<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: key: recovery_2nd_stage_command<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: value: &#39;&#39; kind: 4<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: key: recovery_timeout<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: value: 90 kind: 2<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: key:<br>
&gt; &gt;&gt; client_idle_limit_in_recovery<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: value: 0 kind: 2<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: key: relcache_expire<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: value: 0 kind: 2<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: num_backends: 2 total_weight:<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2.000000<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: backend 0 weight:<br>
&gt; &gt;&gt; 1073741823.500000<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: backend 0 flag: 0000<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: backend 1 weight:<br>
&gt; &gt;&gt; 1073741823.500000<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: backend 1 flag: 0001<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: I am 30055 accept fd 5<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 LOG:   pid 30055: connection received:<br>
&gt; &gt;&gt; &gt;&gt;&gt; host=pgsql_srv_standby port=32291<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: Protocol Major: 3 Minor: 0<br>
&gt; &gt;&gt; &gt;&gt;&gt; database: foo user: foo<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: new_connection: connecting 0<br>
&gt; &gt;&gt; backend<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: new_connection: connecting 1<br>
&gt; &gt;&gt; backend<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: new_connection: skipping slot 1<br>
&gt; &gt;&gt; &gt;&gt;&gt; because backend_status = 1<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: pool_ssl: SSL requested but SSL<br>
&gt; &gt;&gt; &gt;&gt;&gt; support is not available<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: pool_read_message_length: slot: 0<br>
&gt; &gt;&gt; &gt;&gt;&gt; length: 8<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: pool_do_auth: auth kind:0<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: pool_read_message_length2: master<br>
&gt; &gt;&gt; &gt;&gt;&gt; slot: 0 length: 22<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: 0 th backend: name:<br>
&gt; &gt;&gt; &gt;&gt;&gt; application_name value:<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: pool_read_message_length2: master<br>
&gt; &gt;&gt; &gt;&gt;&gt; slot: 0 length: 25<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: 0 th backend: name:<br>
&gt; &gt;&gt; client_encoding<br>
&gt; &gt;&gt; &gt;&gt;&gt; value: UTF8<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: pool_read_message_length2: master<br>
&gt; &gt;&gt; &gt;&gt;&gt; slot: 0 length: 23<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: 0 th backend: name: DateStyle<br>
&gt; &gt;&gt; &gt;&gt;&gt; value: ISO, MDY<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: pool_read_message_length2: master<br>
&gt; &gt;&gt; &gt;&gt;&gt; slot: 0 length: 25<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: 0 th backend: name:<br>
&gt; &gt;&gt; &gt;&gt;&gt; integer_datetimes value: on<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: pool_read_message_length2: master<br>
&gt; &gt;&gt; &gt;&gt;&gt; slot: 0 length: 27<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: 0 th backend: name: IntervalStyle<br>
&gt; &gt;&gt; &gt;&gt;&gt; value: postgres<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: pool_read_message_length2: master<br>
&gt; &gt;&gt; &gt;&gt;&gt; slot: 0 length: 20<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: 0 th backend: name: is_superuser<br>
&gt; &gt;&gt; &gt;&gt;&gt; value: on<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: pool_read_message_length2: master<br>
&gt; &gt;&gt; &gt;&gt;&gt; slot: 0 length: 25<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: 0 th backend: name:<br>
&gt; &gt;&gt; server_encoding<br>
&gt; &gt;&gt; &gt;&gt;&gt; value: UTF8<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: pool_read_message_length2: master<br>
&gt; &gt;&gt; &gt;&gt;&gt; slot: 0 length: 25<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: 0 th backend: name:<br>
&gt; &gt;&gt; server_version<br>
&gt; &gt;&gt; &gt;&gt;&gt; value: 9.1.1<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: pool_read_message_length2: master<br>
&gt; &gt;&gt; &gt;&gt;&gt; slot: 0 length: 33<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: 0 th backend: name:<br>
&gt; &gt;&gt; &gt;&gt;&gt; session_authorization value: foo<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: pool_read_message_length2: master<br>
&gt; &gt;&gt; &gt;&gt;&gt; slot: 0 length: 35<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: 0 th backend: name:<br>
&gt; &gt;&gt; &gt;&gt;&gt; standard_conforming_strings value: on<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: pool_read_message_length2: master<br>
&gt; &gt;&gt; &gt;&gt;&gt; slot: 0 length: 30<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: 0 th backend: name: TimeZone<br>
&gt; &gt;&gt; value:<br>
&gt; &gt;&gt; &gt;&gt;&gt; Europe/Amsterdam<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: pool_read_message_length: slot: 0<br>
&gt; &gt;&gt; &gt;&gt;&gt; length: 12<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: pool_do_auth:<br>
&gt; &gt;&gt; &gt;&gt;&gt; cp-&gt;info[i]:0x2aacd4239800 pid:24275<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: pool_send_auth_ok: send pid 24275<br>
&gt; &gt;&gt; &gt;&gt;&gt; to frontend<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: pool_unset_query_in_progress:<br>
&gt; &gt;&gt; done<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: pool_unset_command_success: done<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: pool_unset_writing_transaction:<br>
&gt; &gt;&gt; done<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: pool_unset_failed_transaction:<br>
&gt; &gt;&gt; done<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: pool_unset_transaction_isolation:<br>
&gt; &gt;&gt; &gt;&gt;&gt; done<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055:<br>
&gt; &gt;&gt; &gt;&gt;&gt; pool_unset_skip_reading_from_backends: done<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: pool_unset_ignore_till_sync: done<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: read_kind_from_backend: read kind<br>
&gt; &gt;&gt; &gt;&gt;&gt; from 0 th backend Z NUM_BACKENDS: 2<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: ProcessBackendResponse: kind from<br>
&gt; &gt;&gt; &gt;&gt;&gt; backend: Z<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: pool_read_message_length: slot: 0<br>
&gt; &gt;&gt; &gt;&gt;&gt; length: 5<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: ReadyForQuery: transaction<br>
&gt; &gt;&gt; state:NUL<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: ProcessFrontendResponse: kind<br>
&gt; &gt;&gt; from<br>
&gt; &gt;&gt; &gt;&gt;&gt; frontend P(50)<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055:<br>
&gt; &gt;&gt; &gt;&gt;&gt; pool_unset_doing_extended_query_message: done<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055:<br>
&gt; &gt;&gt; &gt;&gt;&gt; pool_set_doing_extended_query_message: done<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: pool_set_query_in_progress: done<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: Parse: statement name &lt;&gt;<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: pool_set_query_in_progress: done<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 LOG:   pid 30055: DB node id: 0 backend pid: 24275<br>
&gt; &gt;&gt; &gt;&gt;&gt; statement: Parse: SET extra_float_digits = 3<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: wait_for_query_response: waiting<br>
&gt; &gt;&gt; &gt;&gt;&gt; for backend 0 completing the query<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: read_kind_from_backend: read kind<br>
&gt; &gt;&gt; &gt;&gt;&gt; from 0 th backend 1 NUM_BACKENDS: 2<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: ProcessBackendResponse: kind from<br>
&gt; &gt;&gt; &gt;&gt;&gt; backend: 1<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: pool_set_command_success: done<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: pool_unset_query_in_progress:<br>
&gt; &gt;&gt; done<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: ProcessFrontendResponse: kind<br>
&gt; &gt;&gt; from<br>
&gt; &gt;&gt; &gt;&gt;&gt; frontend B(42)<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055:<br>
&gt; &gt;&gt; &gt;&gt;&gt; pool_unset_doing_extended_query_message: done<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055:<br>
&gt; &gt;&gt; &gt;&gt;&gt; pool_set_doing_extended_query_message: done<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: pool_set_query_in_progress: done<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: Bind: waiting for master<br>
&gt; &gt;&gt; completing<br>
&gt; &gt;&gt; &gt;&gt;&gt; the query<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 LOG:   pid 30055: DB node id: 0 backend pid: 24275<br>
&gt; &gt;&gt; &gt;&gt;&gt; statement: B message<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: wait_for_query_response: waiting<br>
&gt; &gt;&gt; &gt;&gt;&gt; for backend 0 completing the query<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: read_kind_from_backend: read kind<br>
&gt; &gt;&gt; &gt;&gt;&gt; from 0 th backend 2 NUM_BACKENDS: 2<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: ProcessBackendResponse: kind from<br>
&gt; &gt;&gt; &gt;&gt;&gt; backend: 2<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: pool_set_command_success: done<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: pool_unset_query_in_progress:<br>
&gt; &gt;&gt; done<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: ProcessFrontendResponse: kind<br>
&gt; &gt;&gt; from<br>
&gt; &gt;&gt; &gt;&gt;&gt; frontend E(45)<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055:<br>
&gt; &gt;&gt; &gt;&gt;&gt; pool_unset_doing_extended_query_message: done<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055:<br>
&gt; &gt;&gt; &gt;&gt;&gt; pool_set_doing_extended_query_message: done<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: pool_set_query_in_progress: done<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: Execute: portal name &lt;&gt;<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: Execute: query: SET<br>
&gt; &gt;&gt; &gt;&gt;&gt; extra_float_digits = 3<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 LOG:   pid 30055: DB node id: 0 backend pid: 24275<br>
&gt; &gt;&gt; &gt;&gt;&gt; statement: Execute: SET extra_float_digits = 3<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:22 DEBUG: pid 30055: wait_for_query_response: waiting<br>
&gt; &gt;&gt; &gt;&gt;&gt; for backend 0 completing the query<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:23 DEBUG: pid 30055: read_kind_from_backend: read kind<br>
&gt; &gt;&gt; &gt;&gt;&gt; from 0 th backend C NUM_BACKENDS: 2<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:23 DEBUG: pid 30055: ProcessBackendResponse: kind from<br>
&gt; &gt;&gt; &gt;&gt;&gt; backend: C<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:23 DEBUG: pid 30055: pool_set_command_success: done<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:23 DEBUG: pid 30055: pool_unset_query_in_progress:<br>
&gt; &gt;&gt; done<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:23 DEBUG: pid 30055: ProcessFrontendResponse: kind<br>
&gt; &gt;&gt; from<br>
&gt; &gt;&gt; &gt;&gt;&gt; frontend S(53)<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:23 DEBUG: pid 30055:<br>
&gt; &gt;&gt; &gt;&gt;&gt; pool_unset_doing_extended_query_message: done<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:23 DEBUG: pid 30055:<br>
&gt; &gt;&gt; &gt;&gt;&gt; pool_set_doing_extended_query_message: done<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:23 DEBUG: pid 30055: pool_set_query_in_progress: done<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:23 DEBUG: pid 30055: read_kind_from_backend: read kind<br>
&gt; &gt;&gt; &gt;&gt;&gt; from 0 th backend Z NUM_BACKENDS: 2<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:23 DEBUG: pid 30055: ProcessBackendResponse: kind from<br>
&gt; &gt;&gt; &gt;&gt;&gt; backend: Z<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:23 DEBUG: pid 30055: pool_read_message_length: slot: 0<br>
&gt; &gt;&gt; &gt;&gt;&gt; length: 5<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:23 DEBUG: pid 30055: ReadyForQuery: transaction<br>
&gt; &gt;&gt; state:I<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:23 DEBUG: pid 30055: pool_set_writing_transaction:<br>
&gt; &gt;&gt; done<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:23 DEBUG: pid 30055: pool_unset_query_in_progress:<br>
&gt; &gt;&gt; done<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:23 DEBUG: pid 30055: ProcessFrontendResponse: kind<br>
&gt; &gt;&gt; from<br>
&gt; &gt;&gt; &gt;&gt;&gt; frontend P(50)<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:23 DEBUG: pid 30055:<br>
&gt; &gt;&gt; &gt;&gt;&gt; pool_unset_doing_extended_query_message: done<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:23 DEBUG: pid 30055:<br>
&gt; &gt;&gt; &gt;&gt;&gt; pool_set_doing_extended_query_message: done<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:23 DEBUG: pid 30055: pool_set_query_in_progress: done<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:23 DEBUG: pid 30055: Parse: statement name &lt;&gt;<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:23 DEBUG: pid 30055: pool_set_query_in_progress: done<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:23 LOG:   pid 30055: DB node id: 0 backend pid: 24275<br>
&gt; &gt;&gt; &gt;&gt;&gt; statement: Parse: select nextval(&#39;hibernate_sequence&#39;)<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:23 DEBUG: pid 30055: wait_for_query_response: waiting<br>
&gt; &gt;&gt; &gt;&gt;&gt; for backend 0 completing the query<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:23 DEBUG: pid 30055: read_kind_from_backend: read kind<br>
&gt; &gt;&gt; &gt;&gt;&gt; from 0 th backend 1 NUM_BACKENDS: 2<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:23 DEBUG: pid 30055: ProcessBackendResponse: kind from<br>
&gt; &gt;&gt; &gt;&gt;&gt; backend: 1<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:23 DEBUG: pid 30055: pool_add_sent_message: prepared<br>
&gt; &gt;&gt; &gt;&gt;&gt; statement &quot;&quot; already exists<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:23 DEBUG: pid 30055: can_query_context_destroy: query<br>
&gt; &gt;&gt; &gt;&gt;&gt; context is still used.<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:23 DEBUG: pid 30055: pool_set_command_success: done<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:23 DEBUG: pid 30055: pool_unset_query_in_progress:<br>
&gt; &gt;&gt; done<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:23 DEBUG: pid 30055: ProcessFrontendResponse: kind<br>
&gt; &gt;&gt; from<br>
&gt; &gt;&gt; &gt;&gt;&gt; frontend B(42)<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:23 DEBUG: pid 30055:<br>
&gt; &gt;&gt; &gt;&gt;&gt; pool_unset_doing_extended_query_message: done<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:23 DEBUG: pid 30055:<br>
&gt; &gt;&gt; &gt;&gt;&gt; pool_set_doing_extended_query_message: done<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:23 DEBUG: pid 30055: pool_set_query_in_progress: done<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:23 DEBUG: pid 30055: Bind: waiting for master<br>
&gt; &gt;&gt; completing<br>
&gt; &gt;&gt; &gt;&gt;&gt; the query<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:23 LOG:   pid 30055: DB node id: 0 backend pid: 24275<br>
&gt; &gt;&gt; &gt;&gt;&gt; statement: B message<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:23 DEBUG: pid 30055: wait_for_query_response: waiting<br>
&gt; &gt;&gt; &gt;&gt;&gt; for backend 0 completing the query<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:23 DEBUG: pid 30055: read_kind_from_backend: read kind<br>
&gt; &gt;&gt; &gt;&gt;&gt; from 0 th backend 2 NUM_BACKENDS: 2<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:23 DEBUG: pid 30055: ProcessBackendResponse: kind from<br>
&gt; &gt;&gt; &gt;&gt;&gt; backend: 2<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:23 DEBUG: pid 30055: pool_add_sent_message: portal &quot;&quot;<br>
&gt; &gt;&gt; &gt;&gt;&gt; already exists<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:23 DEBUG: pid 30055: pool_unset_query_in_progress:<br>
&gt; &gt;&gt; done<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:23 DEBUG: pid 30055: pool_set_query_in_progress: done<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:23 DEBUG: pid 30055: pool_set_command_success: done<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:23 DEBUG: pid 30055: pool_unset_query_in_progress:<br>
&gt; &gt;&gt; done<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:23 DEBUG: pid 30055: ProcessFrontendResponse: kind<br>
&gt; &gt;&gt; from<br>
&gt; &gt;&gt; &gt;&gt;&gt; frontend D(44)<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:23 DEBUG: pid 30055:<br>
&gt; &gt;&gt; &gt;&gt;&gt; pool_unset_doing_extended_query_message: done<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:23 DEBUG: pid 30055:<br>
&gt; &gt;&gt; &gt;&gt;&gt; pool_set_doing_extended_query_message: done<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:23 DEBUG: pid 30055: pool_set_query_in_progress: done<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:23 DEBUG: pid 30055: Describe: waiting for master<br>
&gt; &gt;&gt; &gt;&gt;&gt; completing the query<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:23 LOG:   pid 30055: DB node id: 0 backend pid: 24275<br>
&gt; &gt;&gt; &gt;&gt;&gt; statement: D message<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:23 DEBUG: pid 30055: wait_for_query_response: waiting<br>
&gt; &gt;&gt; &gt;&gt;&gt; for backend 0 completing the query<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:23 DEBUG: pid 30055: read_kind_from_backend: read kind<br>
&gt; &gt;&gt; &gt;&gt;&gt; from 0 th backend T NUM_BACKENDS: 2<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:23 DEBUG: pid 30055: ProcessBackendResponse: kind from<br>
&gt; &gt;&gt; &gt;&gt;&gt; backend: T<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:23 DEBUG: pid 30055: pool_unset_query_in_progress:<br>
&gt; &gt;&gt; done<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:23 DEBUG: pid 30055: ProcessFrontendResponse: kind<br>
&gt; &gt;&gt; from<br>
&gt; &gt;&gt; &gt;&gt;&gt; frontend E(45)<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:23 DEBUG: pid 30055:<br>
&gt; &gt;&gt; &gt;&gt;&gt; pool_unset_doing_extended_query_message: done<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:23 DEBUG: pid 30055:<br>
&gt; &gt;&gt; &gt;&gt;&gt; pool_set_doing_extended_query_message: done<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:23 DEBUG: pid 30055: pool_set_query_in_progress: done<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:23 DEBUG: pid 30055: Execute: portal name &lt;&gt;<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:23 DEBUG: pid 30055: Execute: query: select<br>
&gt; &gt;&gt; &gt;&gt;&gt; nextval(&#39;hibernate_sequence&#39;)<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:23 LOG:   pid 30055: DB node id: 0 backend pid: 24275<br>
&gt; &gt;&gt; &gt;&gt;&gt; statement: Execute: select nextval(&#39;hibernate_sequence&#39;)<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:23 DEBUG: pid 30055: wait_for_query_response: waiting<br>
&gt; &gt;&gt; &gt;&gt;&gt; for backend 0 completing the query<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:23 DEBUG: pid 30055: read_kind_from_backend: read kind<br>
&gt; &gt;&gt; &gt;&gt;&gt; from 0 th backend D NUM_BACKENDS: 2<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:23 DEBUG: pid 30055: ProcessBackendResponse: kind from<br>
&gt; &gt;&gt; &gt;&gt;&gt; backend: D<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:23 DEBUG: pid 30055: read_kind_from_backend: read kind<br>
&gt; &gt;&gt; &gt;&gt;&gt; from 0 th backend C NUM_BACKENDS: 2<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:23 DEBUG: pid 30055: ProcessBackendResponse: kind from<br>
&gt; &gt;&gt; &gt;&gt;&gt; backend: C<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:23 DEBUG: pid 30055: pool_set_command_success: done<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:23 DEBUG: pid 30055: pool_unset_query_in_progress:<br>
&gt; &gt;&gt; done<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:23 DEBUG: pid 30055: ProcessFrontendResponse: kind<br>
&gt; &gt;&gt; from<br>
&gt; &gt;&gt; &gt;&gt;&gt; frontend S(53)<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:23 DEBUG: pid 30055:<br>
&gt; &gt;&gt; &gt;&gt;&gt; pool_unset_doing_extended_query_message: done<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:23 DEBUG: pid 30055:<br>
&gt; &gt;&gt; &gt;&gt;&gt; pool_set_doing_extended_query_message: done<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:23 DEBUG: pid 30055: pool_set_query_in_progress: done<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:23 DEBUG: pid 30055: read_kind_from_backend: read kind<br>
&gt; &gt;&gt; &gt;&gt;&gt; from 0 th backend Z NUM_BACKENDS: 2<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:23 DEBUG: pid 30055: ProcessBackendResponse: kind from<br>
&gt; &gt;&gt; &gt;&gt;&gt; backend: Z<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:23 DEBUG: pid 30055: pool_read_message_length: slot: 0<br>
&gt; &gt;&gt; &gt;&gt;&gt; length: 5<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:23 DEBUG: pid 30055: ReadyForQuery: transaction<br>
&gt; &gt;&gt; state:I<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:23 DEBUG: pid 30055: pool_unset_query_in_progress:<br>
&gt; &gt;&gt; done<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:23 DEBUG: pid 30055: ProcessFrontendResponse: kind<br>
&gt; &gt;&gt; from<br>
&gt; &gt;&gt; &gt;&gt;&gt; frontend X(58)<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:23 DEBUG: pid 30055:<br>
&gt; &gt;&gt; &gt;&gt;&gt; pool_unset_doing_extended_query_message: done<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:23 DEBUG: pid 30055: pool_unset_query_in_progress:<br>
&gt; &gt;&gt; done<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:23 DEBUG: pid 30055: can_query_context_destroy: query<br>
&gt; &gt;&gt; &gt;&gt;&gt; context is still used.<br>
&gt; &gt;&gt; &gt;&gt;&gt; 2011-11-21 23:03:23 DEBUG: pid 30055: pool_unset_query_in_progress:<br>
&gt; &gt;&gt; done<br>
&gt; &gt;&gt; &gt;&gt;<br>
&gt; &gt;&gt; &gt;&gt;<br>
&gt; &gt;&gt; &gt;&gt;<br>
&gt; &gt;&gt;<br>
&gt;<br>
<br>
</div></div></blockquote></div><br>