[pgpool-general-jp: 1253] Re: UPDATE後にキャッシュ削除されない

Tatsuo Ishii ishii @ sraoss.co.jp
2014年 1月 23日 (木) 22:44:49 JST


$B$3$s$P$s$O!#@P0f$G$9!#(B

pgpool.conf$B$r8+$;$F$$$?$@$1$^$9$+!)(B

$B$"$H!"%m%0$N3F9T$,ESCf$G @ Z$l$F$$$k$h$&$G$9$,!"40A4$J$b$N$r8+$;$F$$$?$@(B
$B$1$^$9$+!)(B
--
Tatsuo Ishii
SRA OSS, Inc. Japan
English: http://www.sraoss.co.jp/index_en.php
Japanese: http://www.sraoss.co.jp

> $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 メーリングリストの案内