[pgpool-general-jp: 1252] UPDATE後にキャッシュ削除されない
佐藤慎
s_sato ＠ eltex.co.jp
2014年 1月 23日 (木) 19:24:54 JST
$B$*@$OC$K$J$C$F$*$j$^$9!#:4F#$H?=$7$^$9!#(B
pgpool3.3.2$B$N%*%s%a%b%j%/%(%j%-%c%C%7%e$K$D$$$F$N<ALd$G$9!#(B
$B%/%(%j%-%c%C%7%eM-8z$+L58z$+$G(BSELECT$B7k2L$,0c$C$F$7$^$&$H$$$&LdBj$,=P$F$*$j$^$9!#(B
SELECT$B!"(BUPDATE$B!"(BSELECT$B$N=g$K%"%W%j%1!<%7%g%s$NCf$G<B9T$7$F$$$^$9!#(B
$B:G8e$N(BSELECT$B$G$O!"(BUPDATE$B8e$N7k2L$,JV$k$N$, ＠ 5$7$$$N$G$9$,!"(B
$B%/%(%j%-%c%C%7%eM-8z$K$7$?>l9g0J2<$N$h$&$JF0$-$K$J$j!"(B
$B-!$H-#$GF1$87k2L$,JV$C$F$-$F$7$^$C$F$$$^$9!#(B
$B-!(B SELECT $B!&!&!&%-%c%C%7%e:n ＠ .(B
$B-"(B UPDATE $B!&!&!&%-%c%C%7%e$,$J$<$+:o=|$5$l$J$$(B
$B-#(B SELECT $B!&!&!&%-%c%C%7%e$+$i7k2L$,JV$C$F$7$^$&(B
$B-$(B COMMIT
$B0J2<$N$$$:$l$N>l9g$O!"@5>o$J7k2L$,JV$C$F$-$^$9!#(B
$B%/%(%j%-%c%C%7%e$rL58z$K$7$?>l9g(B
psql$B$+$i@\B3$7$F(BSQL$B$rN.$7$?>l9g(B
/*NO QUERY CACHE*/$B!W%3%a%s%H$rIU$1$?>l9g(B
pgpool$B$N;EMM>e$N@)Ls$K0z$C$+$+$C$F$$$k$+IT6q9g$@$H;W$$$^$9!#(B
$B2?$+$7$i$N>pJs$rD:$1$^$9$H9,$$$G$9!#(B
$BLdBjH/@8$7$?:]$N(Bpgpool$B$N%m%0$rKvHx$K$D$1$^$7$?$,!"(BUPDATE$B8e$K%-%c%C%7%e$,:o=|$5$l$F$$$J$$$N$,5$$K$J$j$^$9!#(B
$B0J2<$K3F<o>pJs$r5-:\$5$;$FD:$-$^$9!#(B
[$B3F<o4D6-(B]
pgpool-II 3.3.2
PostgreSQL 9.2.4
Web$B%5!<%P!<(B Apache 2.2.23
Tomcat Version 7.0.29
JDBC$B%I%i%$%P!<(B postgresql-9.1-902.jdbc4.jar
Java 1.7.0_05-b06
[pgpool$B%G%P%C%/%m%0(B]
----------------------------------------------------------------------------------------------------------------------------------
2014-01-23 14:56:31 DEBUG: pid 613: send_to_where: 0 query: UPDATE
t_work_flow SET company_no = $1 , action_code = $2 , work_st
2014-01-23 14:56:31 DEBUG: pid 613: Parse: waiting for master
completing the query
2014-01-23 14:56:31 LOG: pid 613: DB node id: 0 backend pid: 5317
statement: Parse: UPDATE t_work_flow SET company_no = $1 , a
2014-01-23 14:56:31 DEBUG: pid 613: wait_for_query_response: waiting
for backend 0 completing the query
2014-01-23 14:56:31 DEBUG: pid 613: detect_error: kind: 1
2014-01-23 14:56:31 DEBUG: pid 613: pool_unset_skip_reading_from_backends: done
2014-01-23 14:56:31 DEBUG: pid 613: read_kind_from_one_backend: read
kind from 0 th backend 1
2014-01-23 14:56:31 DEBUG: pid 613: read_kind_from_backend: kind: 1
from 0 th backend
2014-01-23 14:56:31 DEBUG: pid 613: read_kind_from_backend: read kind
from 0 th backend 1 NUM_BACKENDS: 2
2014-01-23 14:56:31 DEBUG: pid 613: ProcessBackendResponse: kind from backend: 1
2014-01-23 14:56:31 DEBUG: pid 613: pool_add_sent_message: prepared
statement "" already exists
2014-01-23 14:56:31 DEBUG: pid 613: can_query_context_destroy: query
context 0x1d4c1e0 is still used. query: SELECT t_work_flow.co
2014-01-23 14:56:31 DEBUG: pid 613: can_query_context_destroy: query
context 0x1d4c1e0 is still used. query: SELECT t_work_flow.co
2014-01-23 14:56:31 DEBUG: pid 613: can_query_context_destroy: query
context 0x1d4c1e0 is still used for 2 times.
2014-01-23 14:56:31 DEBUG: pid 613: pool_set_command_success: done
2014-01-23 14:56:31 DEBUG: pid 613: pool_unset_query_in_progress: done
2014-01-23 14:56:31 DEBUG: pid 613: ProcessFrontendResponse: kind from
frontend B(42)
2014-01-23 14:56:31 DEBUG: pid 613:
pool_unset_doing_extended_query_message: done
2014-01-23 14:56:31 DEBUG: pid 613: pool_set_doing_extended_query_message: done
2014-01-23 14:56:31 DEBUG: pid 613: pool_set_query_in_progress: done
2014-01-23 14:56:31 DEBUG: pid 613: Bind: waiting for master
completing the query
2014-01-23 14:56:31 LOG: pid 613: DB node id: 0 backend pid: 5317
statement: B message
2014-01-23 14:56:31 DEBUG: pid 613: wait_for_query_response: waiting
for backend 0 completing the query
2014-01-23 14:56:31 DEBUG: pid 613: read_kind_from_one_backend: read
kind from 0 th backend 2
2014-01-23 14:56:31 DEBUG: pid 613: read_kind_from_backend: kind: 2
from 0 th backend
2014-01-23 14:56:31 DEBUG: pid 613: read_kind_from_backend: read kind
from 0 th backend 2 NUM_BACKENDS: 2
2014-01-23 14:56:31 DEBUG: pid 613: ProcessBackendResponse: kind from backend: 2
2014-01-23 14:56:31 DEBUG: pid 613: pool_add_sent_message: portal ""
already exists
2014-01-23 14:56:31 DEBUG: pid 613: can_query_context_destroy: query
context 0x1d4c1e0 is still used. query: SELECT t_work_flow.co
2014-01-23 14:56:31 DEBUG: pid 613: pool_unset_query_in_progress: done
2014-01-23 14:56:31 DEBUG: pid 613: pool_set_query_in_progress: done
2014-01-23 14:56:31 DEBUG: pid 613: pool_set_command_success: done
2014-01-23 14:56:31 DEBUG: pid 613: pool_unset_query_in_progress: done
2014-01-23 14:56:31 DEBUG: pid 613: ProcessFrontendResponse: kind from
frontend D(44)
2014-01-23 14:56:31 DEBUG: pid 613:
pool_unset_doing_extended_query_message: done
2014-01-23 14:56:31 DEBUG: pid 613: pool_set_doing_extended_query_message: done
2014-01-23 14:56:31 DEBUG: pid 613: pool_set_query_in_progress: done
2014-01-23 14:56:31 DEBUG: pid 613: Describe: waiting for master
completing the query
2014-01-23 14:56:31 LOG: pid 613: DB node id: 0 backend pid: 5317
statement: D message
2014-01-23 14:56:31 DEBUG: pid 613: wait_for_query_response: waiting
for backend 0 completing the query
2014-01-23 14:56:31 DEBUG: pid 613: read_kind_from_one_backend: read
kind from 0 th backend n
2014-01-23 14:56:31 DEBUG: pid 613: read_kind_from_backend: kind: n
from 0 th backend
2014-01-23 14:56:31 DEBUG: pid 613: read_kind_from_backend: read kind
from 0 th backend n NUM_BACKENDS: 2
2014-01-23 14:56:31 DEBUG: pid 613: ProcessBackendResponse: kind from backend: n
2014-01-23 14:56:31 DEBUG: pid 613: pool_unset_query_in_progress: done
2014-01-23 14:56:31 DEBUG: pid 613: ProcessFrontendResponse: kind from
frontend E(45)
2014-01-23 14:56:31 DEBUG: pid 613:
pool_unset_doing_extended_query_message: done
2014-01-23 14:56:31 DEBUG: pid 613: pool_set_doing_extended_query_message: done
2014-01-23 14:56:31 DEBUG: pid 613: pool_set_query_in_progress: done
2014-01-23 14:56:31 DEBUG: pid 613: Execute: portal name <>
2014-01-23 14:56:31 DEBUG: pid 613: Execute: query string = < UPDATE
t_work_flow SET company_no = $1 , action_code = $2 , work_s
2014-01-23 14:56:31 LOG: pid 613: DB node id: 0 backend pid: 5317
statement: Execute: UPDATE t_work_flow SET company_no = $1 ,
2014-01-23 14:56:31 DEBUG: pid 613: wait_for_query_response: waiting
for backend 0 completing the query
2014-01-23 14:56:31 DEBUG: pid 613: read_kind_from_one_backend: read
kind from 0 th backend C
2014-01-23 14:56:31 DEBUG: pid 613: read_kind_from_backend: kind: C
from 0 th backend
2014-01-23 14:56:31 DEBUG: pid 613: read_kind_from_backend: read kind
from 0 th backend C NUM_BACKENDS: 2
2014-01-23 14:56:31 DEBUG: pid 613: ProcessBackendResponse: kind from backend: C
2014-01-23 14:56:31 DEBUG: pid 613: pool_set_command_success: done
2014-01-23 14:56:31 DEBUG: pid 613: pool_unset_query_in_progress: done
2014-01-23 14:56:31 DEBUG: pid 613: ProcessFrontendResponse: kind from
frontend S(53)
2014-01-23 14:56:31 DEBUG: pid 613:
pool_unset_doing_extended_query_message: done
2014-01-23 14:56:31 DEBUG: pid 613: pool_set_doing_extended_query_message: done
2014-01-23 14:56:31 DEBUG: pid 613: pool_set_query_in_progress: done
2014-01-23 14:56:31 DEBUG: pid 613: read_kind_from_one_backend: read
kind from 0 th backend Z
2014-01-23 14:56:31 DEBUG: pid 613: read_kind_from_backend: kind: Z
from 0 th backend
2014-01-23 14:56:31 DEBUG: pid 613: read_kind_from_backend: read kind
from 0 th backend Z NUM_BACKENDS: 2
2014-01-23 14:56:31 DEBUG: pid 613: ProcessBackendResponse: kind from backend: Z
2014-01-23 14:56:31 DEBUG: pid 613: pool_read_message_length: slot: 0 length: 5
2014-01-23 14:56:31 DEBUG: pid 613: ReadyForQuery: transaction state:T
2014-01-23 14:56:31 DEBUG: pid 613: pool_set_writing_transaction: done
2014-01-23 14:56:31 DEBUG: pid 613: pool_unset_query_in_progress: done
2014-01-23 14:56:31 DEBUG: pid 613: ProcessBackendResponse: Ready For Query
2014-01-23 14:56:31 DEBUG: pid 613: ProcessFrontendResponse: kind from
frontend P(50)
2014-01-23 14:56:31 DEBUG: pid 613:
pool_unset_doing_extended_query_message: done
2014-01-23 14:56:31 DEBUG: pid 613: pool_set_doing_extended_query_message: done
2014-01-23 14:56:31 DEBUG: pid 613: pool_set_query_in_progress: done
2014-01-23 14:56:31 DEBUG: pid 613: Parse: statement name <>
2014-01-23 14:56:31 DEBUG: pid 613: pool_set_query_in_progress: done
2014-01-23 14:56:31 DEBUG: pid 613:
pool_has_insertinto_or_locking_clause: returns 0
2014-01-23 14:56:31 DEBUG: pid 613: pool_has_non_immutable_function_call: 0
2014-01-23 14:56:31 DEBUG: pid 613: temp_table_walker: relname: t_work_flow
2014-01-23 14:56:31 DEBUG: pid 613: system_catalog_walker: relname: t_work_flow
2014-01-23 14:56:31 DEBUG: pid 613: make_table_name_from_rangevar:
tablename:t_work_flow
2014-01-23 14:56:31 DEBUG: pid 613: view_walker: relname: t_work_flow
2014-01-23 14:56:31 DEBUG: pid 613: make_table_name_from_rangevar:
tablename:t_work_flow
2014-01-23 14:56:31 DEBUG: pid 613: unlogged_table_walker: relname: t_work_flow
2014-01-23 14:56:31 DEBUG: pid 613:
pool_has_insertinto_or_locking_clause: returns 0
2014-01-23 14:56:31 DEBUG: pid 613: send_to_where: 2 query: SELECT
t_work_flow.company_no AS T1_C1,t_work_flow.shop_no AS T1_C2,t_
2014-01-23 14:56:31 DEBUG: pid 613: Parse: waiting for master
completing the query
2014-01-23 14:56:31 LOG: pid 613: DB node id: 0 backend pid: 5317
statement: Parse: SELECT t_work_flow.company_no AS T1_C1,t_wor
2014-01-23 14:56:31 DEBUG: pid 613: wait_for_query_response: waiting
for backend 0 completing the query
2014-01-23 14:56:31 DEBUG: pid 613: detect_error: kind: 1
2014-01-23 14:56:31 DEBUG: pid 613: read_kind_from_one_backend: read
kind from 0 th backend 1
2014-01-23 14:56:31 DEBUG: pid 613: read_kind_from_backend: kind: 1
from 0 th backend
2014-01-23 14:56:31 DEBUG: pid 613: read_kind_from_backend: read kind
from 0 th backend 1 NUM_BACKENDS: 2
2014-01-23 14:56:31 DEBUG: pid 613: ProcessBackendResponse: kind from backend: 1
2014-01-23 14:56:31 DEBUG: pid 613: pool_add_sent_message: prepared
statement "" already exists
2014-01-23 14:56:31 DEBUG: pid 613: can_query_context_destroy: query
context 0x1b2be60 is still used. query: UPDATE t_work_flow SE
2014-01-23 14:56:31 DEBUG: pid 613: can_query_context_destroy: query
context 0x1b2be60 is still used. query: UPDATE t_work_flow SE
2014-01-23 14:56:31 DEBUG: pid 613: can_query_context_destroy: query
context 0x1b2be60 is still used for 2 times.
2014-01-23 14:56:31 DEBUG: pid 613: pool_add_buffer: realloc old
size:0 new size:8192
2014-01-23 14:56:31 DEBUG: pid 613: pool_add_buffer: len:1, total:1 bufsize:8192
2014-01-23 14:56:31 DEBUG: pid 613: pool_add_buffer: len:4, total:5 bufsize:8192
2014-01-23 14:56:31 DEBUG: pid 613: pool_set_command_success: done
2014-01-23 14:56:31 DEBUG: pid 613: pool_unset_query_in_progress: done
2014-01-23 14:56:31 DEBUG: pid 613: ProcessFrontendResponse: kind from
frontend B(42)
2014-01-23 14:56:31 DEBUG: pid 613:
pool_unset_doing_extended_query_message: done
2014-01-23 14:56:31 DEBUG: pid 613: pool_set_doing_extended_query_message: done
2014-01-23 14:56:31 DEBUG: pid 613: pool_set_query_in_progress: done
2014-01-23 14:56:31 DEBUG: pid 613:
pool_has_insertinto_or_locking_clause: returns 0
2014-01-23 14:56:31 DEBUG: pid 613: send_to_where: 2 query: SELECT
t_work_flow.company_no AS T1_C1,t_work_flow.shop_no AS T1_C2,t_
2014-01-23 14:56:31 DEBUG: pid 613: Bind: waiting for master
completing the query
2014-01-23 14:56:31 LOG: pid 613: DB node id: 0 backend pid: 5317
statement: B message
2014-01-23 14:56:31 DEBUG: pid 613: wait_for_query_response: waiting
for backend 0 completing the query
2014-01-23 14:56:31 DEBUG: pid 613: read_kind_from_one_backend: read
kind from 0 th backend 2
2014-01-23 14:56:31 DEBUG: pid 613: read_kind_from_backend: kind: 2
from 0 th backend
2014-01-23 14:56:31 DEBUG: pid 613: read_kind_from_backend: read kind
from 0 th backend 2 NUM_BACKENDS: 2
2014-01-23 14:56:31 DEBUG: pid 613: ProcessBackendResponse: kind from backend: 2
2014-01-23 14:56:31 DEBUG: pid 613: pool_add_sent_message: portal ""
already exists
2014-01-23 14:56:31 DEBUG: pid 613: can_query_context_destroy: query
context 0x1b2be60 is still used. query: UPDATE t_work_flow SE
2014-01-23 14:56:31 DEBUG: pid 613: pool_unset_query_in_progress: done
2014-01-23 14:56:31 DEBUG: pid 613: pool_set_query_in_progress: done
2014-01-23 14:56:31 DEBUG: pid 613: pool_add_buffer: len:1, total:6 bufsize:8192
2014-01-23 14:56:31 DEBUG: pid 613: pool_add_buffer: len:4, total:10
bufsize:8192
2014-01-23 14:56:31 DEBUG: pid 613: pool_set_command_success: done
2014-01-23 14:56:31 DEBUG: pid 613: pool_unset_query_in_progress: done
2014-01-23 14:56:31 DEBUG: pid 613: ProcessFrontendResponse: kind from
frontend D(44)
2014-01-23 14:56:31 DEBUG: pid 613:
pool_unset_doing_extended_query_message: done
2014-01-23 14:56:31 DEBUG: pid 613: pool_set_doing_extended_query_message: done
2014-01-23 14:56:31 DEBUG: pid 613: pool_set_query_in_progress: done
2014-01-23 14:56:31 DEBUG: pid 613: Describe: waiting for master
completing the query
2014-01-23 14:56:31 LOG: pid 613: DB node id: 0 backend pid: 5317
statement: D message
2014-01-23 14:56:31 DEBUG: pid 613: wait_for_query_response: waiting
for backend 0 completing the query
2014-01-23 14:56:31 DEBUG: pid 613: read_kind_from_one_backend: read
kind from 0 th backend T
2014-01-23 14:56:31 DEBUG: pid 613: read_kind_from_backend: kind: T
from 0 th backend
2014-01-23 14:56:31 DEBUG: pid 613: read_kind_from_backend: read kind
from 0 th backend T NUM_BACKENDS: 2
2014-01-23 14:56:31 DEBUG: pid 613: ProcessBackendResponse: kind from backend: T
2014-01-23 14:56:31 DEBUG: pid 613: pool_add_buffer: len:1, total:11
bufsize:8192
2014-01-23 14:56:31 DEBUG: pid 613: pool_add_buffer: len:4, total:15
bufsize:8192
2014-01-23 14:56:31 DEBUG: pid 613: pool_add_buffer: len:1443,
total:1458 bufsize:8192
2014-01-23 14:56:31 DEBUG: pid 613: pool_unset_query_in_progress: done
2014-01-23 14:56:31 DEBUG: pid 613: ProcessFrontendResponse: kind from
frontend E(45)
2014-01-23 14:56:31 DEBUG: pid 613:
pool_unset_doing_extended_query_message: done
2014-01-23 14:56:31 DEBUG: pid 613: pool_set_doing_extended_query_message: done
2014-01-23 14:56:31 DEBUG: pid 613: pool_set_query_in_progress: done
2014-01-23 14:56:31 DEBUG: pid 613: Execute: portal name <>
2014-01-23 14:56:31 DEBUG: pid 613: Execute: query string = SELECT
t_work_flow.company_no AS T1_C1,t_work_flow.shop_no AS T1_C2,t
2014-01-23 14:56:31 DEBUG: pid 613: encode_key: username xspuser
2014-01-23 14:56:31 DEBUG: pid 613: encode_key: database_name xspdb
2014-01-23 14:56:31 DEBUG: pid 613: encode_key: query SELECT
t_work_flow.company_no AS T1_C1,t_work_flow.shop_no AS T1_C2,t_work_f
2014-01-23 14:56:31 DEBUG: pid 613: encode_key: `xspuserSELECT
t_work_flow.company_no AS T1_C1,t_work_flow.shop_no AS T1_C2,t_work
2014-01-23 14:56:31 DEBUG: pid 613: pool_fetch_cache: search key
==e5455205c36a11c2249b964547032887==
2014-01-23 14:56:31 DEBUG: pid 613: pool_fetch_cache: query=SELECT
t_work_flow.company_no AS T1_C1,t_work_flow.shop_no AS T1_C2,t_
2014-01-23 14:56:31 DEBUG: pid 613: send_cached_messages: D len: 702
2014-01-23 14:56:31 DEBUG: pid 613: send_message: kind=D, len=702,
data=0x1da6077
2014-01-23 14:56:31 DEBUG: pid 613: send_cached_messages: C len: 13
2014-01-23 14:56:31 DEBUG: pid 613: send_message: kind=C, len=13, data=0x1da6336
2014-01-23 14:56:31 DEBUG: pid 613: pool_fetch_from_memory_cache:
expecting sync: S
2014-01-23 14:56:31 DEBUG: pid 613: send_message: kind=Z, len=5,
data=0x7fff8e381bff
2014-01-23 14:56:31 LOG: pid 613: query result fetched from cache.
statement: SELECT t_work_flow.company_no AS T1_C1,t_work_flow
2014-01-23 14:56:31 DEBUG: pid 613: pool_fetch_from_memory_cache: a
query result found in the query cache, SELECT t_work_flow.comp
----------------------------------------------------------------------------------------------------------------------------------
$B$h$m$7$/$*4j$$$$$?$7$^$9!#(B
--
$B!=!=!=!=!=!=!=!=!=!=!=!=!=!=!=!=!=!=!=!=!=!=!=!=!=(B
$B:4F#!!?5(B
$B3t<02q<R%(%k%F%C%/%9(B
$B%7%9%F%`%^%M!<%8%a%s%H%5!<%S%9It(B
$B")(B240-0005$B!!2#IM;TJ]EZ%vC+6h?@8MD.(B134
$B2#IM%S%8%M%9%Q!<%/!!%$!<%9%H%?%o!<(B14$B3,(B
$BEEOC!'(B045-332-7865$B!!(BFax$B!'(B045-332-6644
URL $B!'(Bhttp://www.eltex.co.jp/
facebook$B!'(Bhttp://www.facebook.com/ELTEXinc
$B!=!=!=!=!=!=!=!=!=!=!=!=!=!=!=!=!=!=(B(c)ELTEX, Inc.
pgpool-general-jp メーリングリストの案内