[pgcluster: 914] リカバリモードで立ち上げることが出来ません
shimada_yuu ＠ tdc.co.jp
shimada_yuu ＠ tdc.co.jp
2006年 7月 27日 (木) 14:38:33 JST
いつも御世話になっております 島田です
pgcluster1.0.10を使用しております。
前回のときのレプリケーションがうまくいかない現象については
解決したのですが、今度はリカバリのテストをしようとしてうまくいかない状況に
なっています
<手順>
１．クラスタサーバBを冗長構成中にpg_ctlで終了させます。
２．ロードバランサからテーブルに対してinsert文を実行すると応答が返ってこな
い状態になると同時に
pgreplicate.sts、pglb.stsのファイルにerrorのステータスが表示されます。
３．クラスタサーバBにおいてリカバリモードで立ち上げようとします
レプリケータのログにて
2006-07-27 14:10:47 [10158] ERROR:send_recovery_packet:send error: 9(Bad
file descriptor)
というメッセージが表示されているのですが、どういった原因が考えられますで
しょうか。
又、cluster.confにおいてread_writeの設定にしているのですが、更新しようとす
ると応答が返ってこないのですが
それは仕様なのでしょうか、
冗長構成は3台になっています
t20030063.tdc.co.jp(10.20.60.57)
ロードバランサ
レプリケーションサーバ
t199702699.tdc.co.jp(10.20.60.51)
クラスタサーバA
t1997026-13.tdc.co.jp(10.20.60.55)
クラスタサーバB
お手数をお掛けいたしますが、宜しくお願い致します
■リカバリ実行時のコンソールの様子
[postgres ＠ t1997026-13 pgsql]$ pg_ctl start -D /usr/local/pgsql/data -o "-i
-R"
/usr/local/pgsql/bin/postmaster: Assert checking is not compiled in.
postmaster successfully started
■pgreplicate.stsのログ
Thu Jul 27 13:56:29 2006 port(5432) host:t199702699.tdc.co.jp start use
Thu Jul 27 13:56:29 2006 port(5432) host:t1997026-13.tdc.co.jp start use
Thu Jul 27 13:56:29 2006 cascade(t20030063.tdc.co.jp) port(8777) start use
Thu Jul 27 14:05:14 2006 port(5432) host:t1997026-13.tdc.co.jp error
Thu Jul 27 14:10:43 2006 port(5432) host:t1997026-13.tdc.co.jp initialize
Thu Jul 27 14:10:43 2006 port(5432) host:t1997026-13.tdc.co.jp initialize
Thu Jul 27 14:20:56 2006 port(5432) host:t1997026-13.tdc.co.jp initialize
■pglb.stsのログ
Thu Jul 27 13:57:06 2006 port(5432) host:t1997026-13.tdc.co.jp start use
Thu Jul 27 13:57:06 2006 port(5432) host:t199702699.tdc.co.jp start use
Thu Jul 27 14:05:14 2006 port(5432) host:t1997026-13.tdc.co.jp error
■ cluster.conf(クラスタサーバA、B共に同じ設定です)
#============================================================
# Cluster DB Server configuration file
#------------------------------------------------------------
# file: cluster.conf
#------------------------------------------------------------
# This file controls:
# o which hosts & port are replication server
# o which port use for replication request to replication server
# o which command use for recovery function
#============================================================
#------------------------------------------------------------
# set Replication Server information
# o Host_Name : hostname
# o Port : connection for postmaster
# o Recovery_Port : connection for recovery process
#------------------------------------------------------------
<Replicate_Server_Info>
<Host_Name> t20030063.tdc.co.jp </Host_Name>
<Port> 8777 </Port>
<Recovery_Port> 7778 </Recovery_Port>
</Replicate_Server_Info>
#-------------------------------------------------------------
# set Cluster DB Server information
# o Recovery_Port : connection for recovery
# o Rsync_Path : path of rsync command
# o Rsync_Option : file transfer option for rsync
# o Rsync_Compress : use compression option for rsync
# [yes/no]. default : yes
# o When_Stand_Alone : When all replication servers fell,
# you can set up two kinds of permittion,
# "real_only" or "read_write".
#-------------------------------------------------------------
<Recovery_Port> 7779 </Recovery_Port>
<Rsync_Path> /usr/bin/rsync </Rsync_Path>
<Rsync_Option> ssh -1 </Rsync_Option>
<Rsync_Compress> yes </Rsync_Compress>
<When_Stand_Alone> read_write </When_Stand_Alone>
#-------------------------------------------------------------
# set partitional replicate control information
# set DB name and Table name to stop reprication
# o DB_Name : DB name
# o Table_Name : table name
#-------------------------------------------------------------
#<Not_Replicate_Info>
# <DB_Name> test_db </DB_Name>
# <Table_Name> log_table </Table_Name>
#</Not_Replicate_Info>
■pgreplicate.conf
#=============================================================
# PGReplicate configuration file
#-------------------------------------------------------------
# file: pgreplicate.conf
#-------------------------------------------------------------
# This file controls:
# o which hosts & port are cluster server
# o which port use for replication request from cluster server
#=============================================================
#
#-------------------------------------------------------------
# A setup of Cluster DB(s)
#
# o Host_Name : The host name of Cluster DB.
# -- please write a host name by FQDN.
# -- do not write IP address.
# o Port : The connection port with postmaster.
# o Recovery_Port : The connection port at the time of
# a recovery sequence .
#-------------------------------------------------------------
<Cluster_Server_Info>
<Host_Name> t199702699.tdc.co.jp </Host_Name>
<Port> 5432 </Port>
<Recovery_Port> 7779 </Recovery_Port>
</Cluster_Server_Info>
<Cluster_Server_Info>
<Host_Name> t1997026-13.tdc.co.jp </Host_Name>
<Port> 5432 </Port>
<Recovery_Port> 7779 </Recovery_Port>
</Cluster_Server_Info>
#-------------------------------------------------------------
# A setup of Load Balance Server
#
# o Host_Name : The host name of a load balance server.
# -- please write a host name by FQDN.
# -- do not write IP address.
# o Recovery_Port : The connection port at the time of
# a recovery sequence .
#-------------------------------------------------------------
<LoadBalance_Server_Info>
<Host_Name> t20030063.tdc.co.jp </Host_Name>
<Recovery_Port> 8201 </Recovery_Port>
</LoadBalance_Server_Info>
#
#-------------------------------------------------------------
# A setup of a replication server
#
# o Replicate_Port : connection for replication
# o Recovery_Port : connection for recovery
# o Response_mode : timing which returns a response
# normal -- return result of DB which received the query
# reliable -- return result after waiting for response of
# all Cluster DBs.
# o Use_Replication_Log : use replication log
# [yes/no]. default : no
# o RLOG_Port : connection for replication log
#-------------------------------------------------------------
<Replication_Port> 8777 </Replication_Port>
<Recovery_Port> 7778 </Recovery_Port>
<Response_Mode> normal </Response_Mode>
<Use_Replication_Log> no </Use_Replication_Log>
<RLOG_Port> 8301 </RLOG_Port>
■pglb.conf
#============================================================
# Load Balance Server configuration file
#-------------------------------------------------------------
# file: pglb.conf
#-------------------------------------------------------------
# This file controls:
# o which hosts are db cluster server
# o which port use connect to db cluster server
# o how many connections are allowed on each DB server
#============================================================
#-------------------------------------------------------------
# set cluster DB server information
# o Host_Name : hostname
# o Port : connection for postmaster
# o Max_Connection : maximun number of connection to
postmaster
#-------------------------------------------------------------
<Cluster_Server_Info>
<Host_Name> t1997026-13.tdc.co.jp </Host_Name>
<Port> 5432 </Port>
<Max_Connect> 30 </Max_Connect>
</Cluster_Server_Info>
<Cluster_Server_Info>
<Host_Name> t199702699.tdc.co.jp </Host_Name>
<Port> 5432 </Port>
<Max_Connect> 30 </Max_Connect>
</Cluster_Server_Info>
#-------------------------------------------------------------
# set Load Balance server information
# o Backend_Socket_Dir : Unix domain socket path for the
backend
# o Receive_Port : connection from client
# o Recovery_Port : connection for recovery process
# o Max_Cluster_Num : maximun number of cluster DB servers
# o Use_Connection_Pooling : use connection pool [yes/no]
# o Max_Pool_Each_Server : number of pool connections/DB
server
#-------------------------------------------------------------
<Backend_Socket_Dir> /tmp </Backend_Socket_Dir>
<Receive_Port> 5432 </Receive_Port>
<Recovery_Port> 8201 </Recovery_Port>
<Max_Cluster_Num> 128 </Max_Cluster_Num>
<Use_Connection_Pooling> no </Use_Connection_Pooling>
■ロードバランサのログ
[postgres ＠ t20030063 postgres]$ pglb -D /usr/local/pgsql/etc -vn
2006-07-27 13:57:06 [10167]
DEBUG:PGRset_status_on_cluster_tbl():host:t1997026-13.tdc.co.jp port:5432
max:30 use:0 status2
2006-07-27 13:57:06 [10167]
DEBUG:PGRset_status_on_cluster_tbl():host:t199702699.tdc.co.jp port:5432
max:30 use:0 status2
2006-07-27 13:57:06 [10167] DEBUG:init_pglb():Child_Tbl size is[46440]
2006-07-27 13:57:34 [10167] DEBUG:PGRscan_cluster:2 ClusterDB can be used
2006-07-27 13:57:34 [10167] DEBUG:PGRscan_cluster:t1997026-13.tdc.co.jp
[5432],useFlag->2 max->30 use_num->0
2006-07-27 13:57:34 [10173] DEBUG:PGRdo_child():I am 10173
2006-07-27 13:57:34 [10173] DEBUG:do_accept():I am 10173 accept fd 6
2006-07-27 13:57:34 [10173] DEBUG:read_startup_packet():Protocol Major: 2
Minor: 0 database: template1 user: postgres
2006-07-27 13:57:34 [10173] DEBUG:pool_process_query():read kind from
backend pending data Z len: 0 po: 0
2006-07-27 13:57:34 [10173] DEBUG:ProcessFrontendResponse():read kind from
frontend Q(51)
2006-07-27 13:57:34 [10173] DEBUG:pool_read_string: read all from pending
data. po:0 len:0
2006-07-27 13:57:34 [10173] DEBUG:Query(): begin; select
getdatabaseencoding(); commit
2006-07-27 13:57:34 [10173] DEBUG:pool_process_query():read kind from
backend C
2006-07-27 13:57:34 [10173] DEBUG:pool_read_string: read all from pending
data. po:6 len:69
2006-07-27 13:57:34 [10173] DEBUG:CompleteCommandResponse(): string:
"BEGIN"
2006-07-27 13:57:34 [10173] DEBUG:pool_process_query():read kind from
backend pending data P len: 68 po: 7
2006-07-27 13:57:34 [10173] DEBUG:pool_read_string: read all from pending
data. po:13 len:62
2006-07-27 13:57:34 [10173] DEBUG:pool_process_query():read kind from
backend pending data T len: 61 po: 14
2006-07-27 13:57:34 [10173] DEBUG:pool_read_string: read all from pending
data. po:36 len:39
2006-07-27 13:57:34 [10173] DEBUG:RowDescription(): field size:64
2006-07-27 13:57:34 [10173] DEBUG:pool_process_query():read kind from
backend pending data D len: 28 po: 47
2006-07-27 13:57:34 [10173] DEBUG:AsciiRow(): len: 6 data: EUC_JP
2006-07-27 13:57:34 [10173] DEBUG:pool_process_query():read kind from
backend pending data C len: 16 po: 59
2006-07-27 13:57:34 [10173] DEBUG:pool_read_string: read all from pending
data. po:66 len:9
2006-07-27 13:57:34 [10173] DEBUG:CompleteCommandResponse(): string:
"SELECT"
2006-07-27 13:57:34 [10173] DEBUG:pool_process_query():read kind from
backend pending data C len: 8 po: 67
2006-07-27 13:57:34 [10173] DEBUG:pool_read_string: read all from pending
data. po:74 len:1
2006-07-27 13:57:34 [10173] DEBUG:CompleteCommandResponse(): string:
"COMMIT"
2006-07-27 13:57:34 [10173] DEBUG:pool_process_query():read kind from
backend pending data Z len: 0 po: 0
2006-07-27 13:57:34 [10173] DEBUG:ProcessFrontendResponse():read kind from
frontend Q(51)
2006-07-27 13:57:34 [10173] DEBUG:pool_read_string: read all from pending
data. po:0 len:0
2006-07-27 13:57:34 [10173] DEBUG:Query(): SELECT d.datname as "Name",
u.usename as "Owner",
pg_catalog.pg_encoding_to_char(d.encoding) as "Encoding"
FROM pg_catalog.pg_database d
LEFT JOIN pg_catalog.pg_user u ON d.datdba = u.usesysid
ORDER BY 1;
2006-07-27 13:57:34 [10173] DEBUG:pool_process_query():read kind from
backend P
2006-07-27 13:57:34 [10173] DEBUG:pool_read_string: read all from pending
data. po:6 len:168
2006-07-27 13:57:34 [10173] DEBUG:pool_process_query():read kind from
backend pending data T len: 167 po: 7
2006-07-27 13:57:34 [10173] DEBUG:pool_read_string: read all from pending
data. po:14 len:160
2006-07-27 13:57:34 [10173] DEBUG:RowDescription(): field size:64
2006-07-27 13:57:34 [10173] DEBUG:pool_read_string: read all from pending
data. po:30 len:144
2006-07-27 13:57:34 [10173] DEBUG:RowDescription(): field size:64
2006-07-27 13:57:34 [10173] DEBUG:pool_read_string: read all from pending
data. po:49 len:125
2006-07-27 13:57:34 [10173] DEBUG:RowDescription(): field size:64
2006-07-27 13:57:34 [10173] DEBUG:pool_process_query():read kind from
backend pending data D len: 114 po: 60
2006-07-27 13:57:34 [10173] DEBUG:AsciiRow(): len: 9 data: template0
2006-07-27 13:57:34 [10173] DEBUG:AsciiRow(): len: 8 data: postgres
2006-07-27 13:57:34 [10173] DEBUG:AsciiRow(): len: 6 data: EUC_JP
2006-07-27 13:57:34 [10173] DEBUG:pool_process_query():read kind from
backend pending data D len: 77 po: 97
2006-07-27 13:57:34 [10173] DEBUG:AsciiRow(): len: 9 data: template1
2006-07-27 13:57:34 [10173] DEBUG:AsciiRow(): len: 8 data: postgres
2006-07-27 13:57:34 [10173] DEBUG:AsciiRow(): len: 6 data: EUC_JP
2006-07-27 13:57:34 [10173] DEBUG:pool_process_query():read kind from
backend pending data D len: 40 po: 134
2006-07-27 13:57:34 [10173] DEBUG:AsciiRow(): len: 4 data: test
2006-07-27 13:57:34 [10173] DEBUG:AsciiRow(): len: 8 data: postgres
2006-07-27 13:57:34 [10173] DEBUG:AsciiRow(): len: 6 data: EUC_JP
2006-07-27 13:57:34 [10173] DEBUG:pool_process_query():read kind from
backend pending data C len: 8 po: 166
2006-07-27 13:57:34 [10173] DEBUG:pool_read_string: read all from pending
data. po:173 len:1
2006-07-27 13:57:34 [10173] DEBUG:CompleteCommandResponse(): string:
"SELECT"
2006-07-27 13:57:34 [10173] DEBUG:pool_process_query():read kind from
backend pending data Z len: 0 po: 0
2006-07-27 13:57:34 [10173] DEBUG:ProcessFrontendResponse():read kind from
frontend X(58)
2006-07-27 14:03:25 [10167] DEBUG:PGRscan_cluster:2 ClusterDB can be used
2006-07-27 14:03:25 [10167] DEBUG:PGRscan_cluster:t1997026-13.tdc.co.jp
[5432],useFlag->2 max->30 use_num->0
2006-07-27 14:03:25 [10210] DEBUG:PGRdo_child():I am 10210
2006-07-27 14:03:25 [10210] DEBUG:do_accept():I am 10210 accept fd 6
2006-07-27 14:03:25 [10210] DEBUG:read_startup_packet():Protocol Major: 2
Minor: 0 database: template1 user: postgres
2006-07-27 14:03:25 [10210] DEBUG:pool_process_query():read kind from
backend pending data Z len: 0 po: 0
2006-07-27 14:03:25 [10210] DEBUG:ProcessFrontendResponse():read kind from
frontend Q(51)
2006-07-27 14:03:25 [10210] DEBUG:pool_read_string: read all from pending
data. po:0 len:0
2006-07-27 14:03:25 [10210] DEBUG:Query(): begin; select
getdatabaseencoding(); commit
2006-07-27 14:03:25 [10210] DEBUG:pool_process_query():read kind from
backend C
2006-07-27 14:03:25 [10210] DEBUG:pool_read_string: read all from pending
data. po:6 len:69
2006-07-27 14:03:25 [10210] DEBUG:CompleteCommandResponse(): string:
"BEGIN"
2006-07-27 14:03:25 [10210] DEBUG:pool_process_query():read kind from
backend pending data P len: 68 po: 7
2006-07-27 14:03:25 [10210] DEBUG:pool_read_string: read all from pending
data. po:13 len:62
2006-07-27 14:03:25 [10210] DEBUG:pool_process_query():read kind from
backend pending data T len: 61 po: 14
2006-07-27 14:03:25 [10210] DEBUG:pool_read_string: read all from pending
data. po:36 len:39
2006-07-27 14:03:25 [10210] DEBUG:RowDescription(): field size:64
2006-07-27 14:03:25 [10210] DEBUG:pool_process_query():read kind from
backend pending data D len: 28 po: 47
2006-07-27 14:03:25 [10210] DEBUG:AsciiRow(): len: 6 data: EUC_JP
2006-07-27 14:03:25 [10210] DEBUG:pool_process_query():read kind from
backend pending data C len: 16 po: 59
2006-07-27 14:03:25 [10210] DEBUG:pool_read_string: read all from pending
data. po:66 len:9
2006-07-27 14:03:25 [10210] DEBUG:CompleteCommandResponse(): string:
"SELECT"
2006-07-27 14:03:25 [10210] DEBUG:pool_process_query():read kind from
backend pending data C len: 8 po: 67
2006-07-27 14:03:25 [10210] DEBUG:pool_read_string: read all from pending
data. po:74 len:1
2006-07-27 14:03:25 [10210] DEBUG:CompleteCommandResponse(): string:
"COMMIT"
2006-07-27 14:03:25 [10210] DEBUG:pool_process_query():read kind from
backend pending data Z len: 0 po: 0
2006-07-27 14:03:25 [10210] DEBUG:ProcessFrontendResponse():read kind from
frontend Q(51)
2006-07-27 14:03:25 [10210] DEBUG:pool_read_string: read all from pending
data. po:0 len:0
2006-07-27 14:03:25 [10210] DEBUG:Query(): SELECT d.datname as "Name",
u.usename as "Owner",
pg_catalog.pg_encoding_to_char(d.encoding) as "Encoding"
FROM pg_catalog.pg_database d
LEFT JOIN pg_catalog.pg_user u ON d.datdba = u.usesysid
ORDER BY 1;
2006-07-27 14:03:25 [10210] DEBUG:pool_process_query():read kind from
backend P
2006-07-27 14:03:25 [10210] DEBUG:pool_read_string: read all from pending
data. po:6 len:168
2006-07-27 14:03:25 [10210] DEBUG:pool_process_query():read kind from
backend pending data T len: 167 po: 7
2006-07-27 14:03:25 [10210] DEBUG:pool_read_string: read all from pending
data. po:14 len:160
2006-07-27 14:03:25 [10210] DEBUG:RowDescription(): field size:64
2006-07-27 14:03:25 [10210] DEBUG:pool_read_string: read all from pending
data. po:30 len:144
2006-07-27 14:03:25 [10210] DEBUG:RowDescription(): field size:64
2006-07-27 14:03:25 [10210] DEBUG:pool_read_string: read all from pending
data. po:49 len:125
2006-07-27 14:03:25 [10210] DEBUG:RowDescription(): field size:64
2006-07-27 14:03:25 [10210] DEBUG:pool_process_query():read kind from
backend pending data D len: 114 po: 60
2006-07-27 14:03:25 [10210] DEBUG:AsciiRow(): len: 9 data: template0
2006-07-27 14:03:25 [10210] DEBUG:AsciiRow(): len: 8 data: postgres
2006-07-27 14:03:25 [10210] DEBUG:AsciiRow(): len: 6 data: EUC_JP
2006-07-27 14:03:25 [10210] DEBUG:pool_process_query():read kind from
backend pending data D len: 77 po: 97
2006-07-27 14:03:25 [10210] DEBUG:AsciiRow(): len: 9 data: template1
2006-07-27 14:03:25 [10210] DEBUG:AsciiRow(): len: 8 data: postgres
2006-07-27 14:03:25 [10210] DEBUG:AsciiRow(): len: 6 data: EUC_JP
2006-07-27 14:03:25 [10210] DEBUG:pool_process_query():read kind from
backend pending data D len: 40 po: 134
2006-07-27 14:03:25 [10210] DEBUG:AsciiRow(): len: 4 data: test
2006-07-27 14:03:25 [10210] DEBUG:AsciiRow(): len: 8 data: postgres
2006-07-27 14:03:25 [10210] DEBUG:AsciiRow(): len: 6 data: EUC_JP
2006-07-27 14:03:25 [10210] DEBUG:pool_process_query():read kind from
backend pending data C len: 8 po: 166
2006-07-27 14:03:25 [10210] DEBUG:pool_read_string: read all from pending
data. po:173 len:1
2006-07-27 14:03:25 [10210] DEBUG:CompleteCommandResponse(): string:
"SELECT"
2006-07-27 14:03:25 [10210] DEBUG:pool_process_query():read kind from
backend pending data Z len: 0 po: 0
2006-07-27 14:03:25 [10210] DEBUG:ProcessFrontendResponse():read kind from
frontend X(58)
2006-07-27 14:03:31 [10167] DEBUG:PGRscan_cluster:2 ClusterDB can be used
2006-07-27 14:03:31 [10167] DEBUG:PGRscan_cluster:t1997026-13.tdc.co.jp
[5432],useFlag->2 max->30 use_num->0
2006-07-27 14:03:31 [10212] DEBUG:PGRdo_child():I am 10212
2006-07-27 14:03:31 [10212] DEBUG:do_accept():I am 10212 accept fd 6
2006-07-27 14:03:31 [10212] DEBUG:read_startup_packet():Protocol Major: 2
Minor: 0 database: test user: postgres
2006-07-27 14:03:31 [10212] DEBUG:pool_process_query():read kind from
backend pending data Z len: 0 po: 0
2006-07-27 14:03:31 [10212] DEBUG:ProcessFrontendResponse():read kind from
frontend Q(51)
2006-07-27 14:03:31 [10212] DEBUG:pool_read_string: read all from pending
data. po:0 len:0
2006-07-27 14:03:31 [10212] DEBUG:Query(): begin; select
getdatabaseencoding(); commit
2006-07-27 14:03:31 [10212] DEBUG:pool_process_query():read kind from
backend C
2006-07-27 14:03:31 [10212] DEBUG:pool_read_string: read all from pending
data. po:6 len:69
2006-07-27 14:03:31 [10212] DEBUG:CompleteCommandResponse(): string:
"BEGIN"
2006-07-27 14:03:31 [10212] DEBUG:pool_process_query():read kind from
backend pending data P len: 68 po: 7
2006-07-27 14:03:31 [10212] DEBUG:pool_read_string: read all from pending
data. po:13 len:62
2006-07-27 14:03:31 [10212] DEBUG:pool_process_query():read kind from
backend pending data T len: 61 po: 14
2006-07-27 14:03:31 [10212] DEBUG:pool_read_string: read all from pending
data. po:36 len:39
2006-07-27 14:03:31 [10212] DEBUG:RowDescription(): field size:64
2006-07-27 14:03:31 [10212] DEBUG:pool_process_query():read kind from
backend pending data D len: 28 po: 47
2006-07-27 14:03:31 [10212] DEBUG:AsciiRow(): len: 6 data: EUC_JP
2006-07-27 14:03:31 [10212] DEBUG:pool_process_query():read kind from
backend pending data C len: 16 po: 59
2006-07-27 14:03:31 [10212] DEBUG:pool_read_string: read all from pending
data. po:66 len:9
2006-07-27 14:03:31 [10212] DEBUG:CompleteCommandResponse(): string:
"SELECT"
2006-07-27 14:03:31 [10212] DEBUG:pool_process_query():read kind from
backend pending data C len: 8 po: 67
2006-07-27 14:03:31 [10212] DEBUG:pool_read_string: read all from pending
data. po:74 len:1
2006-07-27 14:03:31 [10212] DEBUG:CompleteCommandResponse(): string:
"COMMIT"
2006-07-27 14:03:31 [10212] DEBUG:pool_process_query():read kind from
backend pending data Z len: 0 po: 0
2006-07-27 14:03:31 [10212] DEBUG:ProcessFrontendResponse():read kind from
frontend Q(51)
2006-07-27 14:03:31 [10212] DEBUG:pool_read_string: read all from pending
data. po:0 len:0
2006-07-27 14:03:31 [10212] DEBUG:Query(): BEGIN; SELECT usesuper FROM
pg_catalog.pg_user WHERE usename = 'postgres'; COMMIT
2006-07-27 14:03:31 [10212] DEBUG:pool_process_query():read kind from
backend C
2006-07-27 14:03:31 [10212] DEBUG:pool_read_string: read all from pending
data. po:6 len:53
2006-07-27 14:03:31 [10212] DEBUG:CompleteCommandResponse(): string:
"BEGIN"
2006-07-27 14:03:31 [10212] DEBUG:pool_process_query():read kind from
backend pending data P len: 52 po: 7
2006-07-27 14:03:31 [10212] DEBUG:pool_read_string: read all from pending
data. po:13 len:46
2006-07-27 14:03:31 [10212] DEBUG:pool_process_query():read kind from
backend pending data T len: 45 po: 14
2006-07-27 14:03:31 [10212] DEBUG:pool_read_string: read all from pending
data. po:25 len:34
2006-07-27 14:03:31 [10212] DEBUG:RowDescription(): field size:1
2006-07-27 14:03:31 [10212] DEBUG:pool_process_query():read kind from
backend pending data D len: 23 po: 36
2006-07-27 14:03:31 [10212] DEBUG:AsciiRow(): len: 1 data: t
2006-07-27 14:03:31 [10212] DEBUG:pool_process_query():read kind from
backend pending data C len: 16 po: 43
2006-07-27 14:03:31 [10212] DEBUG:pool_read_string: read all from pending
data. po:50 len:9
2006-07-27 14:03:31 [10212] DEBUG:CompleteCommandResponse(): string:
"SELECT"
2006-07-27 14:03:31 [10212] DEBUG:pool_process_query():read kind from
backend pending data C len: 8 po: 51
2006-07-27 14:03:31 [10212] DEBUG:pool_read_string: read all from pending
data. po:58 len:1
2006-07-27 14:03:31 [10212] DEBUG:CompleteCommandResponse(): string:
"COMMIT"
2006-07-27 14:03:31 [10212] DEBUG:pool_process_query():read kind from
backend pending data Z len: 0 po: 0
2006-07-27 14:03:34 [10212] DEBUG:ProcessFrontendResponse():read kind from
frontend Q(51)
2006-07-27 14:03:34 [10212] DEBUG:pool_read_string: read all from pending
data. po:0 len:0
2006-07-27 14:03:34 [10212] DEBUG:Query(): select * from testtab ;
2006-07-27 14:03:34 [10212] DEBUG:pool_process_query():read kind from
backend P
2006-07-27 14:03:34 [10212] DEBUG:pool_read_string: read all from pending
data. po:6 len:138
2006-07-27 14:03:34 [10212] DEBUG:pool_process_query():read kind from
backend pending data T len: 137 po: 7
2006-07-27 14:03:34 [10212] DEBUG:pool_read_string: read all from pending
data. po:12 len:132
2006-07-27 14:03:34 [10212] DEBUG:RowDescription(): field size:4
2006-07-27 14:03:34 [10212] DEBUG:pool_read_string: read all from pending
data. po:27 len:117
2006-07-27 14:03:34 [10212] DEBUG:RowDescription(): field size:65535
2006-07-27 14:03:34 [10212] DEBUG:pool_process_query():read kind from
backend pending data D len: 106 po: 38
2006-07-27 14:03:34 [10212] DEBUG:AsciiRow(): len: 1 data: 1
2006-07-27 14:03:34 [10212] DEBUG:AsciiRow(): len: 3 data: CCC
2006-07-27 14:03:34 [10212] DEBUG:pool_process_query():read kind from
backend pending data D len: 92 po: 52
2006-07-27 14:03:34 [10212] DEBUG:AsciiRow(): len: 1 data: 2
2006-07-27 14:03:34 [10212] DEBUG:AsciiRow(): len: 3 data: DDD
2006-07-27 14:03:34 [10212] DEBUG:pool_process_query():read kind from
backend pending data D len: 78 po: 66
2006-07-27 14:03:34 [10212] DEBUG:AsciiRow(): len: 1 data: 3
2006-07-27 14:03:34 [10212] DEBUG:AsciiRow(): len: 3 data: ggg
2006-07-27 14:03:34 [10212] DEBUG:pool_process_query():read kind from
backend pending data D len: 64 po: 80
2006-07-27 14:03:34 [10212] DEBUG:AsciiRow(): len: 1 data: 4
2006-07-27 14:03:34 [10212] DEBUG:AsciiRow(): len: 3 data: fff
2006-07-27 14:03:34 [10212] DEBUG:pool_process_query():read kind from
backend pending data D len: 50 po: 94
2006-07-27 14:03:34 [10212] DEBUG:AsciiRow(): len: 1 data: 5
2006-07-27 14:03:34 [10212] DEBUG:AsciiRow(): len: 3 data: ppp
2006-07-27 14:03:34 [10212] DEBUG:pool_process_query():read kind from
backend pending data D len: 36 po: 108
2006-07-27 14:03:34 [10212] DEBUG:AsciiRow(): len: 1 data: 6
2006-07-27 14:03:34 [10212] DEBUG:AsciiRow(): len: 3 data: qqq
2006-07-27 14:03:34 [10212] DEBUG:pool_process_query():read kind from
backend pending data D len: 22 po: 122
2006-07-27 14:03:34 [10212] DEBUG:AsciiRow(): len: 1 data: 7
2006-07-27 14:03:34 [10212] DEBUG:AsciiRow(): len: 3 data: nnn
2006-07-27 14:03:34 [10212] DEBUG:pool_process_query():read kind from
backend pending data C len: 8 po: 136
2006-07-27 14:03:34 [10212] DEBUG:pool_read_string: read all from pending
data. po:143 len:1
2006-07-27 14:03:34 [10212] DEBUG:CompleteCommandResponse(): string:
"SELECT"
2006-07-27 14:03:34 [10212] DEBUG:pool_process_query():read kind from
backend pending data Z len: 0 po: 0
2006-07-27 14:03:58 [10212] DEBUG:ProcessFrontendResponse():read kind from
frontend X(58)
2006-07-27 14:04:41 [10167] DEBUG:PGRscan_cluster:2 ClusterDB can be used
2006-07-27 14:04:41 [10167] DEBUG:PGRscan_cluster:t1997026-13.tdc.co.jp
[5432],useFlag->2 max->30 use_num->0
2006-07-27 14:04:41 [10224] DEBUG:PGRdo_child():I am 10224
2006-07-27 14:04:41 [10224] DEBUG:do_accept():I am 10224 accept fd 6
2006-07-27 14:04:41 [10224] DEBUG:read_startup_packet():Protocol Major: 2
Minor: 0 database: test user: postgres
2006-07-27 14:04:41 [10224] ERROR:connect_inet_domain_socket(): connect()
failed: Connection refused
2006-07-27 14:04:41 [10224]
DEBUG:PGRset_status_on_cluster_tbl():host:t1997026-13.tdc.co.jp port:5432
max:30 use:2 status98
2006-07-27 14:04:43 [10167] DEBUG:PGRscan_cluster:2 ClusterDB can be used
2006-07-27 14:04:43 [10167] DEBUG:PGRscan_cluster:t1997026-13.tdc.co.jp
[5432],useFlag->98 max->30 use_num->1
2006-07-27 14:04:43 [10167] DEBUG:PGRscan_cluster:t199702699.tdc.co.jp
[5432],useFlag->2 max->30 use_num->0
2006-07-27 14:04:43 [10226] DEBUG:PGRdo_child():I am 10226
2006-07-27 14:04:43 [10226] DEBUG:do_accept():I am 10226 accept fd 6
2006-07-27 14:04:43 [10226] DEBUG:read_startup_packet():Protocol Major: 2
Minor: 0 database: test user: postgres
2006-07-27 14:04:43 [10226] DEBUG:pool_process_query():read kind from
backend pending data Z len: 0 po: 0
2006-07-27 14:04:43 [10226] DEBUG:ProcessFrontendResponse():read kind from
frontend Q(51)
2006-07-27 14:04:43 [10226] DEBUG:pool_read_string: read all from pending
data. po:0 len:0
2006-07-27 14:04:43 [10226] DEBUG:Query(): begin; select
getdatabaseencoding(); commit
2006-07-27 14:04:43 [10226] DEBUG:pool_process_query():read kind from
backend C
2006-07-27 14:04:43 [10226] DEBUG:pool_read_string: read all from pending
data. po:6 len:69
2006-07-27 14:04:43 [10226] DEBUG:CompleteCommandResponse(): string:
"BEGIN"
2006-07-27 14:04:43 [10226] DEBUG:pool_process_query():read kind from
backend pending data P len: 68 po: 7
2006-07-27 14:04:43 [10226] DEBUG:pool_read_string: read all from pending
data. po:13 len:62
2006-07-27 14:04:43 [10226] DEBUG:pool_process_query():read kind from
backend pending data T len: 61 po: 14
2006-07-27 14:04:43 [10226] DEBUG:pool_read_string: read all from pending
data. po:36 len:39
2006-07-27 14:04:43 [10226] DEBUG:RowDescription(): field size:64
2006-07-27 14:04:43 [10226] DEBUG:pool_process_query():read kind from
backend pending data D len: 28 po: 47
2006-07-27 14:04:43 [10226] DEBUG:AsciiRow(): len: 6 data: EUC_JP
2006-07-27 14:04:43 [10226] DEBUG:pool_process_query():read kind from
backend pending data C len: 16 po: 59
2006-07-27 14:04:43 [10226] DEBUG:pool_read_string: read all from pending
data. po:66 len:9
2006-07-27 14:04:43 [10226] DEBUG:CompleteCommandResponse(): string:
"SELECT"
2006-07-27 14:04:43 [10226] DEBUG:pool_process_query():read kind from
backend pending data C len: 8 po: 67
2006-07-27 14:04:43 [10226] DEBUG:pool_read_string: read all from pending
data. po:74 len:1
2006-07-27 14:04:43 [10226] DEBUG:CompleteCommandResponse(): string:
"COMMIT"
2006-07-27 14:04:43 [10226] DEBUG:pool_process_query():read kind from
backend pending data Z len: 0 po: 0
2006-07-27 14:04:43 [10226] DEBUG:ProcessFrontendResponse():read kind from
frontend Q(51)
2006-07-27 14:04:43 [10226] DEBUG:pool_read_string: read all from pending
data. po:0 len:0
2006-07-27 14:04:43 [10226] DEBUG:Query(): BEGIN; SELECT usesuper FROM
pg_catalog.pg_user WHERE usename = 'postgres'; COMMIT
2006-07-27 14:04:43 [10226] DEBUG:pool_process_query():read kind from
backend C
2006-07-27 14:04:43 [10226] DEBUG:pool_read_string: read all from pending
data. po:6 len:53
2006-07-27 14:04:43 [10226] DEBUG:CompleteCommandResponse(): string:
"BEGIN"
2006-07-27 14:04:43 [10226] DEBUG:pool_process_query():read kind from
backend pending data P len: 52 po: 7
2006-07-27 14:04:43 [10226] DEBUG:pool_read_string: read all from pending
data. po:13 len:46
2006-07-27 14:04:43 [10226] DEBUG:pool_process_query():read kind from
backend pending data T len: 45 po: 14
2006-07-27 14:04:43 [10226] DEBUG:pool_read_string: read all from pending
data. po:25 len:34
2006-07-27 14:04:43 [10226] DEBUG:RowDescription(): field size:1
2006-07-27 14:04:43 [10226] DEBUG:pool_process_query():read kind from
backend pending data D len: 23 po: 36
2006-07-27 14:04:43 [10226] DEBUG:AsciiRow(): len: 1 data: t
2006-07-27 14:04:43 [10226] DEBUG:pool_process_query():read kind from
backend pending data C len: 16 po: 43
2006-07-27 14:04:43 [10226] DEBUG:pool_read_string: read all from pending
data. po:50 len:9
2006-07-27 14:04:43 [10226] DEBUG:CompleteCommandResponse(): string:
"SELECT"
2006-07-27 14:04:43 [10226] DEBUG:pool_process_query():read kind from
backend pending data C len: 8 po: 51
2006-07-27 14:04:43 [10226] DEBUG:pool_read_string: read all from pending
data. po:58 len:1
2006-07-27 14:04:43 [10226] DEBUG:CompleteCommandResponse(): string:
"COMMIT"
2006-07-27 14:04:43 [10226] DEBUG:pool_process_query():read kind from
backend pending data Z len: 0 po: 0
2006-07-27 14:04:48 [10226] DEBUG:ProcessFrontendResponse():read kind from
frontend Q(51)
2006-07-27 14:04:48 [10226] DEBUG:pool_read_string: read all from pending
data. po:0 len:0
2006-07-27 14:04:48 [10226] DEBUG:Query(): select * from testtab ;
2006-07-27 14:04:48 [10226] DEBUG:pool_process_query():read kind from
backend P
2006-07-27 14:04:48 [10226] DEBUG:pool_read_string: read all from pending
data. po:6 len:138
2006-07-27 14:04:48 [10226] DEBUG:pool_process_query():read kind from
backend pending data T len: 137 po: 7
2006-07-27 14:04:48 [10226] DEBUG:pool_read_string: read all from pending
data. po:12 len:132
2006-07-27 14:04:48 [10226] DEBUG:RowDescription(): field size:4
2006-07-27 14:04:48 [10226] DEBUG:pool_read_string: read all from pending
data. po:27 len:117
2006-07-27 14:04:48 [10226] DEBUG:RowDescription(): field size:65535
2006-07-27 14:04:48 [10226] DEBUG:pool_process_query():read kind from
backend pending data D len: 106 po: 38
2006-07-27 14:04:48 [10226] DEBUG:AsciiRow(): len: 1 data: 1
2006-07-27 14:04:48 [10226] DEBUG:AsciiRow(): len: 3 data: CCC
2006-07-27 14:04:48 [10226] DEBUG:pool_process_query():read kind from
backend pending data D len: 92 po: 52
2006-07-27 14:04:48 [10226] DEBUG:AsciiRow(): len: 1 data: 2
2006-07-27 14:04:48 [10226] DEBUG:AsciiRow(): len: 3 data: DDD
2006-07-27 14:04:48 [10226] DEBUG:pool_process_query():read kind from
backend pending data D len: 78 po: 66
2006-07-27 14:04:48 [10226] DEBUG:AsciiRow(): len: 1 data: 3
2006-07-27 14:04:48 [10226] DEBUG:AsciiRow(): len: 3 data: ggg
2006-07-27 14:04:48 [10226] DEBUG:pool_process_query():read kind from
backend pending data D len: 64 po: 80
2006-07-27 14:04:48 [10226] DEBUG:AsciiRow(): len: 1 data: 4
2006-07-27 14:04:48 [10226] DEBUG:AsciiRow(): len: 3 data: fff
2006-07-27 14:04:48 [10226] DEBUG:pool_process_query():read kind from
backend pending data D len: 50 po: 94
2006-07-27 14:04:48 [10226] DEBUG:AsciiRow(): len: 1 data: 5
2006-07-27 14:04:48 [10226] DEBUG:AsciiRow(): len: 3 data: ppp
2006-07-27 14:04:48 [10226] DEBUG:pool_process_query():read kind from
backend pending data D len: 36 po: 108
2006-07-27 14:04:48 [10226] DEBUG:AsciiRow(): len: 1 data: 6
2006-07-27 14:04:48 [10226] DEBUG:AsciiRow(): len: 3 data: qqq
2006-07-27 14:04:48 [10226] DEBUG:pool_process_query():read kind from
backend pending data D len: 22 po: 122
2006-07-27 14:04:48 [10226] DEBUG:AsciiRow(): len: 1 data: 7
2006-07-27 14:04:48 [10226] DEBUG:AsciiRow(): len: 3 data: nnn
2006-07-27 14:04:48 [10226] DEBUG:pool_process_query():read kind from
backend pending data C len: 8 po: 136
2006-07-27 14:04:48 [10226] DEBUG:pool_read_string: read all from pending
data. po:143 len:1
2006-07-27 14:04:48 [10226] DEBUG:CompleteCommandResponse(): string:
"SELECT"
2006-07-27 14:04:48 [10226] DEBUG:pool_process_query():read kind from
backend pending data Z len: 0 po: 0
2006-07-27 14:05:14 [10226] DEBUG:ProcessFrontendResponse():read kind from
frontend Q(51)
2006-07-27 14:05:14 [10226] DEBUG:pool_read_string: read all from pending
data. po:0 len:0
2006-07-27 14:05:14 [10226] DEBUG:Query(): INSERT INTO testtab VALUES
(8,'mmm');
2006-07-27 14:05:14 [10168] DEBUG:set_recovery():received no:101
2006-07-27 14:05:14 [10168]
DEBUG:PGRset_status_on_cluster_tbl():host:t1997026-13.tdc.co.jp port:5432
max:30 use:98 status99
2006-07-27 14:10:43 [10168] DEBUG:set_recovery():received no:1
2006-07-27 14:10:43 [10168] DEBUG:set_recovery():add_db
host:t1997026-13.tdc.co.jp port:5432 max:32
2006-07-27 14:10:43 [10168]
DEBUG:PGRset_status_on_cluster_tbl():host:t1997026-13.tdc.co.jp port:5432
max:30 use:99 status3
2006-07-27 14:10:47 [10168] DEBUG:set_recovery():received no:3
2006-07-27 14:10:47 [10168] DEBUG:set_recovery():received no:9
2006-07-27 14:10:47 [10168] DEBUG:set_recovery():received no:9
■レプリケータのログ
[postgres ＠ t20030063 postgres]$ pgreplicate -D /usr/local/pgsql/etc -vn
2006-07-27 13:56:29 [10157] DEBUG:PGR_Get_Conf_Data ok
2006-07-27 13:56:29 [10157] DEBUG:LoadBalanceTbl allocate ok
2006-07-27 13:56:29 [10157] DEBUG:PGRset_Conf_Data():CascadeTbl shmget ok
2006-07-27 13:56:29 [10157] DEBUG:PGRset_Conf_Data():CascadeTbl shmat ok
2006-07-27 13:56:29 [10157] DEBUG:PGRset_Conf_Data():CascadeInf shmget ok
2006-07-27 13:56:29 [10157] DEBUG:PGRset_Conf_Data():CascadeInf shmat ok
2006-07-27 13:56:29 [10157] DEBUG:PGRset_Conf_Data():CommitLog shmget ok
2006-07-27 13:56:29 [10157] DEBUG:PGRset_Conf_Data():Commit_Log_Tbl shmat
ok
2006-07-27 13:56:29 [10157] DEBUG:PGRset_Conf_Data():RLog Memory Allocation
ok
2006-07-27 13:56:29 [10157] DEBUG:registering
(key,value)=(Host_Name,t199702699.tdc.co.jp)
2006-07-27 13:56:29 [10157] DEBUG:registering hostname t199702699.tdc.co.jp
2006-07-27 13:56:29 [10157] DEBUG:resolved name is 10.20.60.51
2006-07-27 13:56:29 [10157] DEBUG:registering (key,value)=(Port,5432)
2006-07-27 13:56:29 [10157] DEBUG:registering
(key,value)=(Recovery_Port,7779)
2006-07-27 13:56:29 [10157] DEBUG:registering
(key,value)=(Host_Name,t1997026-13.tdc.co.jp)
2006-07-27 13:56:29 [10157] DEBUG:registering hostname
t1997026-13.tdc.co.jp
2006-07-27 13:56:29 [10157] DEBUG:resolved name is 10.20.60.55
2006-07-27 13:56:29 [10157] DEBUG:registering (key,value)=(Port,5432)
2006-07-27 13:56:29 [10157] DEBUG:registering
(key,value)=(Recovery_Port,7779)
2006-07-27 13:56:29 [10157] DEBUG:registering
(key,value)=(Host_Name,t20030063.tdc.co.jp)
2006-07-27 13:56:29 [10157] DEBUG:registering
(key,value)=(Recovery_Port,8201)
2006-07-27 13:56:29 [10157] DEBUG:registering
(key,value)=(Replication_Port,8777)
2006-07-27 13:56:29 [10157] DEBUG:registering
(key,value)=(Recovery_Port,7778)
2006-07-27 13:56:29 [10157] DEBUG:registering
(key,value)=(Response_Mode,normal)
2006-07-27 13:56:29 [10157] DEBUG:registering
(key,value)=(Use_Replication_Log,no)
2006-07-27 13:56:29 [10157] DEBUG:registering (key,value)=(RLOG_Port,8301)
2006-07-27 13:56:29 [10157] DEBUG:PGRset_Conf_Data():HostTbl shmget ok
2006-07-27 13:56:29 [10157] DEBUG:PGRset_Conf_Data():HostTbl shmat ok
2006-07-27 13:56:29 [10158] DEBUG:PGRrecovery_main():PGRrecovery_main bind
port 7778
2006-07-27 13:56:29 [10157] DEBUG:replicate_main():entering replicate_main
2006-07-27 13:56:29 [10157] DEBUG:replicate_main() 8777 port bind OK
2006-07-27 13:56:29 [10157] DEBUG:cmdSts=N
2006-07-27 13:56:29 [10157] DEBUG:rlog=0
2006-07-27 13:56:29 [10157] DEBUG:port=0
2006-07-27 13:56:29 [10157] DEBUG:pid=0
2006-07-27 13:56:29 [10157] DEBUG:from_host=t20030063.tdc.co.jp
2006-07-27 13:56:29 [10157] DEBUG:dbName=template1
2006-07-27 13:56:29 [10157] DEBUG:userName=postgres
2006-07-27 13:56:29 [10157] DEBUG:recieve sec=0
2006-07-27 13:56:29 [10157] DEBUG:recieve usec=0
2006-07-27 13:56:29 [10157] DEBUG:query_size=69
2006-07-27 13:56:29 [10157] DEBUG:request_id=0
2006-07-27 13:56:29 [10157] DEBUG:replicate_id=0
2006-07-27 13:56:29 [10157] DEBUG:query=SELECT
PGR_SYSTEM_COMMAND_FUNCTION(1,'t20030063.tdc.co.jp',8777,7778)
2006-07-27 13:56:29 [10157] DEBUG:PGRreplicate_packet_send():checking host
t199702699.tdc.co.jp for creating threads
2006-07-27 13:56:29 [10157] DEBUG:PGRis_same_host():not same host
2006-07-27 13:56:29 [10157] DEBUG:pgr_createConn():PQsetdbLogin
host[10.20.60.51] port[5432] db[template1] user[postgres]
2006-07-27 13:56:29 [10157] DEBUG:pgr_createConn():Retry. h_errno is
0,reason is 'could not connect to server: Connection refused
Is the server running on host 10.20.60.51 and accepting
TCP/IP connections on port 5432?
'
2006-07-27 13:56:29 [10157] DEBUG:pgr_createConn():Retry. h_errno is
0,reason is 'could not connect to server: Connection refused
Is the server running on host 10.20.60.51 and accepting
TCP/IP connections on port 5432?
'
2006-07-27 13:56:29 [10157] DEBUG:pgr_createConn():Retry. h_errno is
0,reason is 'could not connect to server: Connection refused
Is the server running on host 10.20.60.51 and accepting
TCP/IP connections on port 5432?
'
2006-07-27 13:56:29 [10157] DEBUG:pgr_createConn():Retry. h_errno is
0,reason is 'could not connect to server: Connection refused
Is the server running on host 10.20.60.51 and accepting
TCP/IP connections on port 5432?
'
2006-07-27 13:56:29 [10157] DEBUG:setTransactionTbl(): 5432 ＠ 10.20.60.51 is
not ready
2006-07-27 13:56:29 [10157]
DEBUG:PGRreplicate_packet_send():setTransactionTbl failed
2006-07-27 13:56:29 [10157] DEBUG:PGRreplicate_packet_send():checking host
t1997026-13.tdc.co.jp for creating threads
2006-07-27 13:56:29 [10157] DEBUG:PGRis_same_host():not same host
2006-07-27 13:56:29 [10157] DEBUG:pgr_createConn():PQsetdbLogin
host[10.20.60.55] port[5432] db[template1] user[postgres]
2006-07-27 13:56:29 [10157] DEBUG:pgr_createConn():Retry. h_errno is
0,reason is 'could not connect to server: Connection refused
Is the server running on host 10.20.60.55 and accepting
TCP/IP connections on port 5432?
'
2006-07-27 13:56:29 [10157] DEBUG:pgr_createConn():Retry. h_errno is
0,reason is 'could not connect to server: Connection refused
Is the server running on host 10.20.60.55 and accepting
TCP/IP connections on port 5432?
'
2006-07-27 13:56:29 [10157] DEBUG:pgr_createConn():Retry. h_errno is
0,reason is 'could not connect to server: Connection refused
Is the server running on host 10.20.60.55 and accepting
TCP/IP connections on port 5432?
'
2006-07-27 13:56:29 [10157] DEBUG:pgr_createConn():Retry. h_errno is
0,reason is 'could not connect to server: Connection refused
Is the server running on host 10.20.60.55 and accepting
TCP/IP connections on port 5432?
'
2006-07-27 13:56:29 [10157] DEBUG:setTransactionTbl(): 5432 ＠ 10.20.60.55 is
not ready
2006-07-27 13:56:29 [10157]
DEBUG:PGRreplicate_packet_send():setTransactionTbl failed
2006-07-27 13:56:29 [10157] DEBUG:thread_send_source was not executed
2006-07-27 14:04:21 [10163] DEBUG:replicate_loop():session closed
2006-07-27 14:04:21 [10163] DEBUG:replicate_loop():replicate loop exit
2006-07-27 14:05:14 [10230] DEBUG:PGRread_packet():PG_read_query returns
INSERT INTO testtab VALUES (8,'mmm').
2006-07-27 14:05:14 [10230] DEBUG:PGRreturn_result():PGRreturn_result[1]
2006-07-27 14:05:14 [10230] DEBUG:PGRreturn_result():128 send
2006-07-27 14:05:14 [10230] DEBUG:PGRread_packet():PG_read_query returns
INSERT INTO testtab VALUES (8,'mmm').
2006-07-27 14:05:14 [10230] DEBUG:PGRdo_replicate():query :: INSERT INTO
testtab VALUES (8,'mmm')
2006-07-27 14:05:14 [10230] DEBUG:cmdSts=Q
2006-07-27 14:05:14 [10230] DEBUG:cmdType=I
2006-07-27 14:05:14 [10230] DEBUG:rlog=0
2006-07-27 14:05:14 [10230] DEBUG:port=5432
2006-07-27 14:05:14 [10230] DEBUG:pid=9819
2006-07-27 14:05:14 [10230] DEBUG:from_host=127.0.0.1
2006-07-27 14:05:14 [10230] DEBUG:dbName=test
2006-07-27 14:05:14 [10230] DEBUG:userName=postgres
2006-07-27 14:05:14 [10230] DEBUG:recieve sec=1153976714
2006-07-27 14:05:14 [10230] DEBUG:recieve usec=75872
2006-07-27 14:05:14 [10230] DEBUG:query_size=36
2006-07-27 14:05:14 [10230] DEBUG:request_id=1
2006-07-27 14:05:14 [10230] DEBUG:replicate_id=0
2006-07-27 14:05:14 [10230] DEBUG:query=INSERT INTO testtab VALUES
(8,'mmm')
2006-07-27 14:05:14 [10230] DEBUG:sem_lock [1] req
2006-07-27 14:05:14 [10230] DEBUG:sem_lock [1] got it
2006-07-27 14:05:14 [10230] DEBUG:PGRreplicate_packet_send():checking host
t199702699.tdc.co.jp for creating threads
2006-07-27 14:05:14 [10230] DEBUG:pgr_createConn():PQsetdbLogin
host[10.20.60.51] port[5432] db[test] user[postgres]
2006-07-27 14:05:14 [10230] DEBUG:pgr_createConn():PQsetdbLogin ok!!
2006-07-27 14:05:14 [10230] DEBUG:start thread_send_cluster()
2006-07-27 14:05:14 [10230] DEBUG:entering
send_replicate_packet_to_server()
2006-07-27 14:05:14 [10230]
DEBUG:send_replicate_packet_to_server():sync_command(SELECT
PGR_SYSTEM_COMMAND_FUNCTION(3,1153976714,75872,1,1,2) )
2006-07-27 14:05:14 [10230] DEBUG:PGRreplicate_packet_send():checking host
t1997026-13.tdc.co.jp for creating threads
2006-07-27 14:05:14 [10230] DEBUG:pgr_createConn():PQsetdbLogin
host[10.20.60.55] port[5432] db[test] user[postgres]
2006-07-27 14:05:14 [10230] DEBUG:send_replicate_packet_to_server()
sync_command returns SYSTEM_COMMAND
2006-07-27 14:05:14 [10230]
DEBUG:send_replicate_packet_to_server():sync_command(SELECT
PGR_SYSTEM_COMMAND_FUNCTION(8,2,0,1) )
2006-07-27 14:05:14 [10230] DEBUG:pgr_createConn():Retry. h_errno is
0,reason is 'could not connect to server: Connection refused
Is the server running on host 10.20.60.55 and accepting
TCP/IP connections on port 5432?
'
2006-07-27 14:05:14 [10230]
DEBUG:send_replicate_packet_to_server():sync_command returns SYSTEM_COMMAND
2006-07-27 14:05:14 [10230] DEBUG:pgr_createConn():Retry. h_errno is
0,reason is 'could not connect to server: Connection refused
Is the server running on host 10.20.60.55 and accepting
TCP/IP connections on port 5432?
'
2006-07-27 14:05:14 [10230] DEBUG:pgr_createConn():Retry. h_errno is
0,reason is 'could not connect to server: Connection refused
Is the server running on host 10.20.60.55 and accepting
TCP/IP connections on port 5432?
'
2006-07-27 14:05:14 [10230] DEBUG:pgr_createConn():Retry. h_errno is
0,reason is 'could not connect to server: Connection refused
Is the server running on host 10.20.60.55 and accepting
TCP/IP connections on port 5432?
'
2006-07-27 14:05:14 [10230] DEBUG:setTransactionTbl(): 5432 ＠ 10.20.60.55 is
not ready
2006-07-27 14:05:14 [10230]
DEBUG:PGRreplicate_packet_send():setTransactionTbl failed
2006-07-27 14:05:14 [10230]
DEBUG:PGRsend_load_balance_packet():host[t20030063.tdc.co.jp] port[8201]
2006-07-27 14:05:14 [10230] DEBUG:send_replicate_packet_to_server():PQexec
send :INSERT INTO testtab VALUES (8,'mmm')
2006-07-27 14:05:14 [10230] DEBUG:send_replicate_packet_to_server():PQexec
returns :INSERT 33377 1
2006-07-27 14:05:14 [10230] DEBUG:thread_send_cluster():return value from
send_replicate_packet_to_server() is 0
2006-07-27 14:05:14 [10230] DEBUG:thread_send_cluster():pthread_exit[0]
2006-07-27 14:05:14 [10230] DEBUG:thread_send_source was not executed
2006-07-27 14:05:14 [10230] DEBUG:sem_unlock[1]
2006-07-27 14:05:14 [10230]
DEBUG:PGRdo_replicate():PGRreplicate_packet_send returns 0
2006-07-27 14:10:43 [10158] DEBUG:pgrecovery_loop():recovery accept port
7778
2006-07-27 14:10:43 [10158] DEBUG:read_packet():receive packet
2006-07-27 14:10:43 [10158] DEBUG:no = 1
2006-07-27 14:10:43 [10158] DEBUG:max_connect = 32
2006-07-27 14:10:43 [10158] DEBUG:port = 5432
2006-07-27 14:10:43 [10158] DEBUG:recoveryPort = 7779
2006-07-27 14:10:43 [10158] DEBUG:hostName = t1997026-13.tdc.co.jp
2006-07-27 14:10:43 [10158] DEBUG:pg_data = /usr/local/pgsql/data
2006-07-27 14:10:43 [10158] DEBUG:pgrecovery_loop():receive packet no:1
2006-07-27 14:10:43 [10158] DEBUG:pgrecovery_loop():1st master - 0
2006-07-27 14:10:43 [10158] DEBUG:pgrecovery_loop():1st target - 0
2006-07-27 14:10:43 [10158] DEBUG:first_setup_recovery():1st setup target
t1997026-13.tdc.co.jp
2006-07-27 14:10:43 [10158] DEBUG:first_setup_recovery():1st setup port
5432
2006-07-27 14:10:43 [10158] DEBUG:first_setup_recovery():add recovery
target to host table
2006-07-27 14:10:43 [10158]
DEBUG:PGRsend_load_balance_packet():host[t20030063.tdc.co.jp] port[8201]
2006-07-27 14:10:43 [10158] DEBUG:first_setup_recovery():set
RECOVERY_PGDATA_REQ packet data
2006-07-27 14:10:43 [10158]
DEBUG:PGRsend_replicate_packet_to_server():host(10.20.60.51) : port(5432)
2006-07-27 14:10:43 [10158] DEBUG:pgr_createConn():PQsetdbLogin
host[10.20.60.51] port[5432] db[template1] user[postgres]
2006-07-27 14:10:43 [10158] DEBUG:pgr_createConn():PQsetdbLogin ok!!
2006-07-27 14:10:43 [10158]
DEBUG:PGRsend_replicate_packet_to_server():connect db:template1 port:5432
user:postgres host:10.20.60.51 query:VACUUM
2006-07-27 14:10:43 [10158] DEBUG:entering
send_replicate_packet_to_server()
2006-07-27 14:10:43 [10158]
DEBUG:send_replicate_packet_to_server():sync_command(SELECT
PGR_SYSTEM_COMMAND_FUNCTION(3,0,0,0,1,2) )
2006-07-27 14:10:45 [10158] DEBUG:send_replicate_packet_to_server()
sync_command returns SYSTEM_COMMAND
2006-07-27 14:10:45 [10158]
DEBUG:send_replicate_packet_to_server():sync_command(SELECT
PGR_SYSTEM_COMMAND_FUNCTION(8,0,0,1) )
2006-07-27 14:10:45 [10158]
DEBUG:send_replicate_packet_to_server():sync_command returns SYSTEM_COMMAND
2006-07-27 14:10:47 [10158] DEBUG:send_replicate_packet_to_server():PQexec
send :VACUUM
2006-07-27 14:10:47 [10158] DEBUG:send_replicate_packet_to_server():PQexec
returns :VACUUM
2006-07-27 14:10:47 [10158] DEBUG:deleteTransactionTbl():
2006-07-27 14:10:47 [10158] DEBUG:first_setup_recovery():send packet to
master t199702699.tdc.co.jp recoveryPort 7779
2006-07-27 14:10:47 [10158] DEBUG:first_setup_recovery():wait answer from
master server
2006-07-27 14:10:47 [10158] DEBUG:read_packet():receive packet
2006-07-27 14:10:47 [10158] DEBUG:no = 3
2006-07-27 14:10:47 [10158] DEBUG:max_connect = 32
2006-07-27 14:10:47 [10158] DEBUG:port = 5432
2006-07-27 14:10:47 [10158] DEBUG:recoveryPort = 7779
2006-07-27 14:10:47 [10158] DEBUG:hostName = T199702699
2006-07-27 14:10:47 [10158] DEBUG:pg_data = /usr/local/pgsql/data
2006-07-27 14:10:47 [10158] DEBUG:first_setup_recovery():get answer from
master:no[3]
2006-07-27 14:10:47 [10158]
DEBUG:PGRsend_load_balance_packet():host[t20030063.tdc.co.jp] port[8201]
2006-07-27 14:10:47 [10158] DEBUG:pgrecovery_loop():first_setup_recovery
end:0
2006-07-27 14:10:47 [10158] DEBUG:read_packet():receive packet
2006-07-27 14:10:47 [10158] DEBUG:no = 200
2006-07-27 14:10:47 [10158] DEBUG:max_connect = 32
2006-07-27 14:10:47 [10158] DEBUG:port = 5432
2006-07-27 14:10:47 [10158] DEBUG:recoveryPort = 7779
2006-07-27 14:10:47 [10158] DEBUG:hostName = t1997026-13.tdc.co.jp
2006-07-27 14:10:47 [10158] DEBUG:pg_data = /usr/local/pgsql/data
2006-07-27 14:10:47 [10158] DEBUG:pgrecovery_loop():receive packet no:200
2006-07-27 14:10:47 [10158] DEBUG:pgrecovery_loop():recovery error accept.
top queueing and initiarse recovery status
2006-07-27 14:10:47 [10158] DEBUG:PGRsend_queue():master
t199702699.tdc.co.jp - 5432
2006-07-27 14:10:47 [10158] DEBUG:PGRsend_queue():send_queue return status
0
2006-07-27 14:10:47 [10158]
DEBUG:PGRsend_load_balance_packet():host[t20030063.tdc.co.jp] port[8201]
2006-07-27 14:10:47 [10158] DEBUG:pgrecovery_loop():recovery accept port
7778
2006-07-27 14:10:47 [10158] DEBUG:read_packet():receive packet
2006-07-27 14:10:47 [10158] DEBUG:no = 200
2006-07-27 14:10:47 [10158] DEBUG:max_connect = 32
2006-07-27 14:10:47 [10158] DEBUG:port = 5432
2006-07-27 14:10:47 [10158] DEBUG:recoveryPort = 7779
2006-07-27 14:10:47 [10158] DEBUG:hostName = t1997026-13.tdc.co.jp
2006-07-27 14:10:47 [10158] DEBUG:pg_data = /usr/local/pgsql/data
2006-07-27 14:10:47 [10158] DEBUG:pgrecovery_loop():receive packet no:200
2006-07-27 14:10:47 [10158] DEBUG:pgrecovery_loop():recovery error accept.
top queueing and initiarse recovery status
2006-07-27 14:10:47 [10158] DEBUG:PGRsend_queue():master
t199702699.tdc.co.jp - 5432
2006-07-27 14:10:47 [10158] DEBUG:PGRsend_queue():send_queue return status
0
2006-07-27 14:10:47 [10158] ERROR:send_recovery_packet:send error: 9(Bad
file descriptor)
2006-07-27 14:10:47 [10265] DEBUG:replicate_loop():session closed
2006-07-27 14:10:47 [10158]
DEBUG:send_packet():PGR_Create_Socket_Connectsock[7]
host[t199702699.tdc.co.jp] port[7779]
2006-07-27 14:10:47 [10158]
DEBUG:PGRsend_load_balance_packet():host[t20030063.tdc.co.jp] port[8201]
2006-07-27 14:10:47 [10265] DEBUG:replicate_loop():replicate loop exit
pgcluster メーリングリストの案内