[pgcluster: 1011] Re: スレーブからの更新がマスタに反映しない

中野浩久@三協 h-nakano @ kk-sankyo.com
2007年 11月 9日 (金) 13:04:08 JST


早速の返信ありがとうございます

以下長くなりますがログです

1.起動時
2007-11-09 13:48:21 [22024] DEBUG:PGR_Get_Conf_Data ok
2007-11-09 13:48:21 [22024] DEBUG:LoadBalanceTbl allocate ok
2007-11-09 13:48:21 [22024] DEBUG:PGRget_Conf_Data():CascadeTbl shmget ok
2007-11-09 13:48:21 [22024] DEBUG:PGRget_Conf_Data():CascadeTbl shmat ok
2007-11-09 13:48:21 [22024] DEBUG:PGRget_Conf_Data():CascadeInf shmget ok
2007-11-09 13:48:21 [22024] DEBUG:PGRget_Conf_Data():CascadeInf shmat ok
2007-11-09 13:48:21 [22024] DEBUG:PGRget_Conf_Data():CommitLog shmget ok
2007-11-09 13:48:21 [22024] DEBUG:PGRget_Conf_Data():Commit_Log_Tbl shmat ok
2007-11-09 13:48:21 [22024] DEBUG:PGRget_Conf_Data():RLog Memory Allocation 
ok
2007-11-09 13:48:21 [22024] DEBUG:registering (key,value)=(Host_Name,test1)
2007-11-09 13:48:21 [22024] DEBUG:registering hostname test1
2007-11-09 13:48:21 [22024] DEBUG:resolved name is 127.0.0.1
2007-11-09 13:48:21 [22024] DEBUG:registering (key,value)=(Port,5432)
2007-11-09 13:48:21 [22024] DEBUG:registering 
(key,value)=(Recovery_Port,7001)
2007-11-09 13:48:21 [22024] DEBUG:registering (key,value)=(Host_Name,test2)
2007-11-09 13:48:21 [22024] DEBUG:registering hostname test2
2007-11-09 13:48:21 [22024] DEBUG:resolved name is 192.168.0.52
2007-11-09 13:48:21 [22024] DEBUG:registering (key,value)=(Port,5432)
2007-11-09 13:48:21 [22024] DEBUG:registering 
(key,value)=(Recovery_Port,7001)
2007-11-09 13:48:21 [22024] DEBUG:registering 
(key,value)=(Host_Name,localhost)
2007-11-09 13:48:21 [22024] DEBUG:registering 
(key,value)=(Replication_Port,8001)
2007-11-09 13:48:21 [22024] DEBUG:registering 
(key,value)=(Recovery_Port,8101)
2007-11-09 13:48:21 [22024] DEBUG:registering (key,value)=(RLOG_Port,8301)
2007-11-09 13:48:21 [22024] DEBUG:registering 
(key,value)=(Response_Mode,normal)
2007-11-09 13:48:21 [22024] DEBUG:registering 
(key,value)=(Use_Replication_Log,no)
2007-11-09 13:48:21 [22024] DEBUG:PGRget_Conf_Data():HostTbl shmget ok
2007-11-09 13:48:21 [22024] DEBUG:PGRget_Conf_Data():HostTbl shmat ok
2007-11-09 13:48:21 [22025] DEBUG:PGRrecovery_main():PGRrecovery_main bind 
port 8101
2007-11-09 13:48:21 [22024] DEBUG:replicate_main():entering replicate_main
2007-11-09 13:48:21 [22024] DEBUG:replicate_main() 8001 port bind OK
2007-11-09 13:48:21 [22024] DEBUG:cmdSts=N
2007-11-09 13:48:21 [22024] DEBUG:rlog=0
2007-11-09 13:48:21 [22024] DEBUG:port=0
2007-11-09 13:48:21 [22024] DEBUG:pid=0
2007-11-09 13:48:21 [22024] DEBUG:from_host=127.0.0.1
2007-11-09 13:48:21 [22024] DEBUG:dbName=template1
2007-11-09 13:48:21 [22024] DEBUG:userName=postgres
2007-11-09 13:48:21 [22024] DEBUG:recieve sec=0
2007-11-09 13:48:21 [22024] DEBUG:recieve usec=0
2007-11-09 13:48:21 [22024] DEBUG:query_size=59
2007-11-09 13:48:21 [22024] DEBUG:request_id=0
2007-11-09 13:48:21 [22024] DEBUG:replicate_id=0
2007-11-09 13:48:21 [22024] DEBUG:recovery_status=0
2007-11-09 13:48:21 [22024] DEBUG:query=SELECT 
PGR_SYSTEM_COMMAND_FUNCTION(1,'127.0.0.1',8001,8101)
2007-11-09 13:48:21 [22024] DEBUG:PGRis_same_host():not same host
2007-11-09 13:48:21 [22024] DEBUG:pgr_createConn():PQsetdbLogin 
host[127.0.0.1] port[5432] db[template1] user[postgres]
2007-11-09 13:48:21 [22024] DEBUG:pgr_createConn():PQsetdbLogin ok!!
2007-11-09 13:48:21 [22024] DEBUG:start thread_send_cluster()
2007-11-09 13:48:21 [22024] 
DEBUG:send_replicate_packet_to_server():sync_command(SELECT 
PGR_SYSTEM_COMMAND_FUNCTION(8,1,0,1) )
2007-11-09 13:48:21 [22024] 
DEBUG:send_replicate_packet_to_server():sync_command returns SYSTEM_COMMAND
2007-11-09 13:48:21 [22024] DEBUG:send_replicate_packet_to_server():PQexec 
send :SELECT PGR_SYSTEM_COMMAND_FUNCTION(1,'127.0.0.1',8001,8101)
2007-11-09 13:48:21 [22024] DEBUG:send_replicate_packet_to_server():PQexec 
returns :SYSTEM_COMMAND
2007-11-09 13:48:21 [22024] DEBUG:deleteTransactionTbl():
2007-11-09 13:48:21 [22024] DEBUG:thread_send_cluster():return value from 
send_replicate_packet_to_server() is 0
2007-11-09 13:48:21 [22024] DEBUG:deleteTransactionTbl():
2007-11-09 13:48:21 [22024] DEBUG:thread_send_cluster():pthread_exit[0]

2.test1からのリクエストの処理
2007-11-09 13:48:21 [22024] DEBUG:PGRis_same_host():not same host
2007-11-09 13:48:21 [22024] DEBUG:pgr_createConn():PQsetdbLogin 
host[192.168.0.52] port[5432] db[template1] user[postgres]
2007-11-09 13:48:21 [22024] DEBUG:pgr_createConn():PQsetdbLogin ok!!
2007-11-09 13:48:21 [22024] DEBUG:start thread_send_cluster()
2007-11-09 13:48:21 [22024] 
DEBUG:send_replicate_packet_to_server():sync_command(SELECT 
PGR_SYSTEM_COMMAND_FUNCTION(8,1,0,1) )
2007-11-09 13:48:21 [22024] 
DEBUG:send_replicate_packet_to_server():sync_command returns SYSTEM_COMMAND
2007-11-09 13:48:21 [22024] DEBUG:send_replicate_packet_to_server():PQexec 
send :SELECT PGR_SYSTEM_COMMAND_FUNCTION(1,'127.0.0.1',8001,8101)
2007-11-09 13:48:21 [22024] DEBUG:send_replicate_packet_to_server():PQexec 
returns :SYSTEM_COMMAND
2007-11-09 13:48:21 [22024] DEBUG:deleteTransactionTbl():
2007-11-09 13:48:21 [22024] DEBUG:thread_send_cluster():return value from 
send_replicate_packet_to_server() is 0
2007-11-09 13:48:21 [22024] DEBUG:deleteTransactionTbl():
2007-11-09 13:48:21 [22024] DEBUG:thread_send_cluster():pthread_exit[1]
2007-11-09 13:57:44 [22037] DEBUG:PGRread_packet():PG_read_query returns 
insert into test1 values ('1111','aaaa').
2007-11-09 13:57:44 [22037] DEBUG:PGRreturn_result():PGRreturn_result[1]
2007-11-09 13:57:44 [22037] DEBUG:PGRreturn_result():128 send
2007-11-09 13:57:44 [22037] DEBUG:PGRread_packet():PG_read_query returns 
insert into test1 values ('1111','aaaa').
2007-11-09 13:57:44 [22037] DEBUG:PGRdo_replicate():query :: insert into 
test1 values ('1111','aaaa')
2007-11-09 13:57:44 [22037] DEBUG:cmdSts=Q
2007-11-09 13:57:44 [22037] DEBUG:cmdType=I
2007-11-09 13:57:44 [22037] DEBUG:rlog=0
2007-11-09 13:57:44 [22037] DEBUG:port=5432
2007-11-09 13:57:44 [22037] DEBUG:pid=22035
2007-11-09 13:57:44 [22037] DEBUG:from_host=127.0.0.1
2007-11-09 13:57:44 [22037] DEBUG:dbName=testdb
2007-11-09 13:57:44 [22037] DEBUG:userName=postgres
2007-11-09 13:57:44 [22037] DEBUG:recieve sec=1194584264
2007-11-09 13:57:44 [22037] DEBUG:recieve usec=697849
2007-11-09 13:57:44 [22037] DEBUG:query_size=40
2007-11-09 13:57:44 [22037] DEBUG:request_id=1
2007-11-09 13:57:44 [22037] DEBUG:replicate_id=0
2007-11-09 13:57:44 [22037] DEBUG:recovery_status=0
2007-11-09 13:57:44 [22037] DEBUG:query=insert into test1 values 
('1111','aaaa')
2007-11-09 13:57:44 [22037] DEBUG:sem_lock [1] req
2007-11-09 13:57:44 [22037] DEBUG:sem_lock [1] got it
2007-11-09 13:57:44 [22037] DEBUG:same host
2007-11-09 13:57:44 [22037] DEBUG:pgr_createConn():PQsetdbLogin 
host[192.168.0.52] port[5432] db[testdb] user[postgres]
2007-11-09 13:57:44 [22037] DEBUG:pgr_createConn():PQsetdbLogin ok!!
2007-11-09 13:57:44 [22037] DEBUG:start thread_send_cluster()
2007-11-09 13:57:44 [22037] 
DEBUG:send_replicate_packet_to_server():sync_command(SELECT 
PGR_SYSTEM_COMMAND_FUNCTION(3,1194584264,697849,8,1,2) )
2007-11-09 13:57:44 [22037] 
DEBUG:send_replicate_packet_to_server():sync_command(SELECT 
PGR_SYSTEM_COMMAND_FUNCTION(8,2,0,1) )
2007-11-09 13:57:44 [22037] 
DEBUG:send_replicate_packet_to_server():sync_command returns SYSTEM_COMMAND
2007-11-09 13:57:44 [22037] DEBUG:send_replicate_packet_to_server():PQexec 
send :insert into test1 values ('1111','aaaa')
2007-11-09 13:57:44 [22037] DEBUG:send_replicate_packet_to_server():PQexec 
returns :
2007-11-09 13:57:44 [22037] DEBUG:thread_send_cluster():return value from 
send_replicate_packet_to_server() is 0
2007-11-09 13:57:44 [22037] DEBUG:thread_send_cluster():pthread_exit[1]
2007-11-09 13:57:44 [22037] 
DEBUG:PGRreturn_result():PGRreturn_result[3,1194584264,697849,8,1,2]
2007-11-09 13:57:44 [22037] DEBUG:PGRreturn_result():128 send
2007-11-09 13:57:44 [22037] DEBUG:PGRread_packet():PG_read_query returns 
PGR_QUERY_DONE_NOTICE_CMD.
2007-11-09 13:57:44 [22037] 
DEBUG:read_answer():answer[PGR_QUERY_DONE_NOTICE_CMD]
2007-11-09 13:57:44 [22037] DEBUG:read_answer():QUERY DONE
2007-11-09 13:57:44 [22037] DEBUG:end thread_send_source()
2007-11-09 13:57:44 [22037] DEBUG:sem_unlock[1]

test2からリクエストを出したところログに何の記録も残っていませんでした

以上です
よろしくお願いします


> こんにちは。
>
> test2にクエリーを投げた時の
> レプリケーションサーバのデバッグログを見れば
> 何か判るかもしれません。
> レプリケーションサーバを-vnオプションをつけて起動してみてください。
>
> 三谷
>




pgcluster メーリングリストの案内