[pgpool-general-jp: 310] Re: 「初めてのレプリケーション」
Tatsuo Ishii
ishii ＠ sraoss.co.jp
2007年 12月 11日 (火) 12:27:46 JST
陶山さん
石井です。
> 2007-12-11 11:16:59 DEBUG: pid 2615: Protocol Major: 3 Minor: 0 database: template1 user: postgres
> 2007-12-11 11:16:59 DEBUG: pid 2615: new_connection: connecting 0 backend
> 2007-12-11 11:16:59 DEBUG: pid 2615: new_connection: connecting 1 backend
> 2007-12-11 11:16:59 DEBUG: pid 2615: pool_read_message_length: slot: 0 length: 12
> 2007-12-11 11:16:59 DEBUG: pid 2615: pool_read_message_length: slot: 1 length: 8
> 2007-12-11 11:16:59 ERROR: pid 2615: pool_read_message_length: message length (8) in slot 1 does not match with slot 0
ここのところですね。「slot 0」はpgpool.confに最初に書いてある方で，
「slot 1」は2つ目のPostgreSQLです。この2つが認証フェーズでpgpoolに違う
パケットを投げてきているようです。2台のPostgreSQLの設定が異なっている
ようです。2つのPostgreSQLのバージョンが完全に同じだとして，
postgresql.confやpg_hba.confが同じかどうか，見直して頂けますか？
--
Tatsuo Ishii
SRA OSS, Inc. Japan
> 石井さん、こんにちは。陶山です。
>
> 開発者から直々にメールをいただけるとは思っていませんでした。
> よろしくお願いします。
>
> Tue, 11 Dec 2007 11:06:43 +0900 (JST) の
> Re: [pgpool-general-jp: 307] 「初めてのレプリケーション」
> に関するメールについてのお返事です。
>
> Tatsuo> とりあえず，デバッグオプション(-d)を付けてpgpoolを起動し,スタート時か
> Tatsuo> ら，createdbしてエラーになるところまでの出力を全部見せてもらえますか？
>
> $ pgpool -n -d > /tmp/pgpool.log 2>&1 &
> で起動し、得たログファイルの内容が以下のものです。
>
> 関係ないと思われる最初の部分もすべて掲載しました。
>
> −−−−−−−−−−−−−−−−−−−−−−−−−−−−−−−−−−−
> postgres ＠ ylcsrv:/tmp$ cat pgpool.log
> 2007-12-11 11:16:03 DEBUG: pid 2583: key: listen_addresses
> 2007-12-11 11:16:03 DEBUG: pid 2583: value: 'localhost' kind: 4
> 2007-12-11 11:16:03 DEBUG: pid 2583: key: port
> 2007-12-11 11:16:03 DEBUG: pid 2583: value: 9999 kind: 2
> 2007-12-11 11:16:03 DEBUG: pid 2583: key: pcp_port
> 2007-12-11 11:16:03 DEBUG: pid 2583: value: 9898 kind: 2
> 2007-12-11 11:16:03 DEBUG: pid 2583: key: socket_dir
> 2007-12-11 11:16:03 DEBUG: pid 2583: value: '/var/run/postgresql' kind: 4
> 2007-12-11 11:16:03 DEBUG: pid 2583: key: pcp_socket_dir
> 2007-12-11 11:16:03 DEBUG: pid 2583: value: '/var/run/postgresql' kind: 4
> 2007-12-11 11:16:03 DEBUG: pid 2583: key: backend_socket_dir
> 2007-12-11 11:16:03 DEBUG: pid 2583: value: '/var/run/postgresql' kind: 4
> 2007-12-11 11:16:03 DEBUG: pid 2583: key: pcp_timeout
> 2007-12-11 11:16:03 DEBUG: pid 2583: value: 10 kind: 2
> 2007-12-11 11:16:03 DEBUG: pid 2583: key: num_init_children
> 2007-12-11 11:16:03 DEBUG: pid 2583: value: 32 kind: 2
> 2007-12-11 11:16:03 DEBUG: pid 2583: key: max_pool
> 2007-12-11 11:16:03 DEBUG: pid 2583: value: 4 kind: 2
> 2007-12-11 11:16:03 DEBUG: pid 2583: key: child_life_time
> 2007-12-11 11:16:03 DEBUG: pid 2583: value: 300 kind: 2
> 2007-12-11 11:16:03 DEBUG: pid 2583: key: connection_life_time
> 2007-12-11 11:16:03 DEBUG: pid 2583: value: 0 kind: 2
> 2007-12-11 11:16:03 DEBUG: pid 2583: key: child_max_connections
> 2007-12-11 11:16:03 DEBUG: pid 2583: value: 0 kind: 2
> 2007-12-11 11:16:03 DEBUG: pid 2583: key: client_idle_limit
> 2007-12-11 11:16:03 DEBUG: pid 2583: value: 0 kind: 2
> 2007-12-11 11:16:03 DEBUG: pid 2583: key: authentication_timeout
> 2007-12-11 11:16:03 DEBUG: pid 2583: value: 60 kind: 2
> 2007-12-11 11:16:03 DEBUG: pid 2583: key: logdir
> 2007-12-11 11:16:03 DEBUG: pid 2583: value: '/tmp' kind: 4
> 2007-12-11 11:16:03 DEBUG: pid 2583: key: replication_mode
> 2007-12-11 11:16:03 DEBUG: pid 2583: value: true kind: 1
> 2007-12-11 11:16:03 DEBUG: pid 2583: key: replication_timeout
> 2007-12-11 11:16:03 DEBUG: pid 2583: value: 5000 kind: 2
> 2007-12-11 11:16:03 DEBUG: pid 2583: key: load_balance_mode
> 2007-12-11 11:16:03 DEBUG: pid 2583: value: true kind: 1
> 2007-12-11 11:16:03 DEBUG: pid 2583: key: replication_stop_on_mismatch
> 2007-12-11 11:16:03 DEBUG: pid 2583: value: false kind: 1
> 2007-12-11 11:16:03 DEBUG: pid 2583: replication_stop_on_mismatch: 0
> 2007-12-11 11:16:03 DEBUG: pid 2583: key: replicate_select
> 2007-12-11 11:16:03 DEBUG: pid 2583: value: false kind: 1
> 2007-12-11 11:16:03 DEBUG: pid 2583: key: reset_query_list
> 2007-12-11 11:16:03 DEBUG: pid 2583: value: 'ABORT; RESET ALL; SET SESSION AUTHORIZATION DEFAULT' kind: 4
> 2007-12-11 11:16:03 DEBUG: pid 2583: extract_string_tokens: token: ABORT
> 2007-12-11 11:16:03 DEBUG: pid 2583: extract_string_tokens: token: RESET ALL
> 2007-12-11 11:16:03 DEBUG: pid 2583: extract_string_tokens: token: SET SESSION AUTHORIZATION DEFAULT
> 2007-12-11 11:16:03 DEBUG: pid 2583: key: print_timestamp
> 2007-12-11 11:16:03 DEBUG: pid 2583: value: true kind: 1
> 2007-12-11 11:16:03 DEBUG: pid 2583: key: master_slave_mode
> 2007-12-11 11:16:03 DEBUG: pid 2583: value: false kind: 1
> 2007-12-11 11:16:03 DEBUG: pid 2583: key: connection_cache
> 2007-12-11 11:16:03 DEBUG: pid 2583: value: true kind: 1
> 2007-12-11 11:16:03 DEBUG: pid 2583: key: health_check_timeout
> 2007-12-11 11:16:03 DEBUG: pid 2583: value: 20 kind: 2
> 2007-12-11 11:16:03 DEBUG: pid 2583: key: health_check_period
> 2007-12-11 11:16:03 DEBUG: pid 2583: value: 0 kind: 2
> 2007-12-11 11:16:03 DEBUG: pid 2583: key: health_check_user
> 2007-12-11 11:16:03 DEBUG: pid 2583: value: 'nobody' kind: 4
> 2007-12-11 11:16:03 DEBUG: pid 2583: key: failover_command
> 2007-12-11 11:16:03 DEBUG: pid 2583: value: '' kind: 4
> 2007-12-11 11:16:03 DEBUG: pid 2583: key: failback_command
> 2007-12-11 11:16:03 DEBUG: pid 2583: value: '' kind: 4
> 2007-12-11 11:16:03 DEBUG: pid 2583: key: insert_lock
> 2007-12-11 11:16:03 DEBUG: pid 2583: value: false kind: 1
> 2007-12-11 11:16:03 DEBUG: pid 2583: key: ignore_leading_white_space
> 2007-12-11 11:16:03 DEBUG: pid 2583: value: true kind: 1
> 2007-12-11 11:16:03 DEBUG: pid 2583: key: log_statement
> 2007-12-11 11:16:03 DEBUG: pid 2583: value: false kind: 1
> 2007-12-11 11:16:03 DEBUG: pid 2583: key: log_connections
> 2007-12-11 11:16:03 DEBUG: pid 2583: value: false kind: 1
> 2007-12-11 11:16:03 DEBUG: pid 2583: key: log_hostname
> 2007-12-11 11:16:03 DEBUG: pid 2583: value: false kind: 1
> 2007-12-11 11:16:03 DEBUG: pid 2583: key: parallel_mode
> 2007-12-11 11:16:03 DEBUG: pid 2583: value: false kind: 1
> 2007-12-11 11:16:03 DEBUG: pid 2583: key: enable_query_cache
> 2007-12-11 11:16:03 DEBUG: pid 2583: value: false kind: 1
> 2007-12-11 11:16:03 DEBUG: pid 2583: key: pgpool2_hostname
> 2007-12-11 11:16:03 DEBUG: pid 2583: value: '' kind: 4
> 2007-12-11 11:16:03 DEBUG: pid 2583: key: system_db_hostname
> 2007-12-11 11:16:03 DEBUG: pid 2583: value: 'localhost' kind: 4
> 2007-12-11 11:16:03 DEBUG: pid 2583: key: system_db_port
> 2007-12-11 11:16:03 DEBUG: pid 2583: value: 5432 kind: 2
> 2007-12-11 11:16:03 DEBUG: pid 2583: key: system_db_dbname
> 2007-12-11 11:16:03 DEBUG: pid 2583: value: 'pgpool' kind: 4
> 2007-12-11 11:16:03 DEBUG: pid 2583: key: system_db_schema
> 2007-12-11 11:16:03 DEBUG: pid 2583: value: 'pgpool_catalog' kind: 4
> 2007-12-11 11:16:03 DEBUG: pid 2583: key: system_db_user
> 2007-12-11 11:16:03 DEBUG: pid 2583: value: 'pgpool' kind: 4
> 2007-12-11 11:16:03 DEBUG: pid 2583: key: system_db_password
> 2007-12-11 11:16:03 DEBUG: pid 2583: value: '' kind: 4
> 2007-12-11 11:16:03 DEBUG: pid 2583: key: backend_hostname0
> 2007-12-11 11:16:03 DEBUG: pid 2583: value: 'localhost' kind: 4
> 2007-12-11 11:16:03 DEBUG: pid 2583: key: backend_port0
> 2007-12-11 11:16:03 DEBUG: pid 2583: value: 5432 kind: 2
> 2007-12-11 11:16:03 DEBUG: pid 2583: pool_config: port slot number 0
> 2007-12-11 11:16:03 DEBUG: pid 2583: key: backend_weight0
> 2007-12-11 11:16:03 DEBUG: pid 2583: value: 1 kind: 2
> 2007-12-11 11:16:03 DEBUG: pid 2583: pool_config: weight slot number 0 weight: 1.000000
> 2007-12-11 11:16:03 DEBUG: pid 2583: key: backend_hostname1
> 2007-12-11 11:16:03 DEBUG: pid 2583: value: '192.168.24.64' kind: 4
> 2007-12-11 11:16:03 DEBUG: pid 2583: key: backend_port1
> 2007-12-11 11:16:03 DEBUG: pid 2583: value: 5432 kind: 2
> 2007-12-11 11:16:03 DEBUG: pid 2583: pool_config: port slot number 1
> 2007-12-11 11:16:03 DEBUG: pid 2583: key: backend_weight1
> 2007-12-11 11:16:03 DEBUG: pid 2583: value: 1 kind: 2
> 2007-12-11 11:16:03 DEBUG: pid 2583: pool_config: weight slot number 1 weight: 1.000000
> 2007-12-11 11:16:03 DEBUG: pid 2583: key: enable_pool_hba
> 2007-12-11 11:16:03 DEBUG: pid 2583: value: false kind: 1
> 2007-12-11 11:16:03 DEBUG: pid 2583: key: recovery_user
> 2007-12-11 11:16:03 DEBUG: pid 2583: value: 'nobody' kind: 4
> 2007-12-11 11:16:03 DEBUG: pid 2583: key: recovery_password
> 2007-12-11 11:16:03 DEBUG: pid 2583: value: '' kind: 4
> 2007-12-11 11:16:03 DEBUG: pid 2583: key: recovery_1st_stage_command
> 2007-12-11 11:16:03 DEBUG: pid 2583: value: '' kind: 4
> 2007-12-11 11:16:03 DEBUG: pid 2583: key: recovery_2nd_stage_command
> 2007-12-11 11:16:03 DEBUG: pid 2583: value: '' kind: 4
> 2007-12-11 11:16:03 DEBUG: pid 2583: num_backends: 2 num_backends: 2 total_weight: 2.000000
> 2007-12-11 11:16:03 DEBUG: pid 2583: backend 0 weight: 1073741823.500000
> 2007-12-11 11:16:03 DEBUG: pid 2583: backend 1 weight: 1073741823.500000
> 2007-12-11 11:16:03 DEBUG: pid 2584: I am 2584
> 2007-12-11 11:16:03 DEBUG: pid 2585: I am 2585
> 2007-12-11 11:16:03 DEBUG: pid 2586: I am 2586
> 2007-12-11 11:16:03 DEBUG: pid 2587: I am 2587
> 2007-12-11 11:16:03 DEBUG: pid 2588: I am 2588
> 2007-12-11 11:16:03 DEBUG: pid 2589: I am 2589
> 2007-12-11 11:16:03 DEBUG: pid 2590: I am 2590
> 2007-12-11 11:16:03 DEBUG: pid 2591: I am 2591
> 2007-12-11 11:16:03 DEBUG: pid 2592: I am 2592
> 2007-12-11 11:16:03 DEBUG: pid 2593: I am 2593
> 2007-12-11 11:16:03 DEBUG: pid 2594: I am 2594
> 2007-12-11 11:16:03 DEBUG: pid 2595: I am 2595
> 2007-12-11 11:16:03 DEBUG: pid 2596: I am 2596
> 2007-12-11 11:16:03 DEBUG: pid 2597: I am 2597
> 2007-12-11 11:16:03 DEBUG: pid 2598: I am 2598
> 2007-12-11 11:16:03 DEBUG: pid 2599: I am 2599
> 2007-12-11 11:16:03 DEBUG: pid 2600: I am 2600
> 2007-12-11 11:16:03 DEBUG: pid 2601: I am 2601
> 2007-12-11 11:16:03 DEBUG: pid 2602: I am 2602
> 2007-12-11 11:16:03 DEBUG: pid 2603: I am 2603
> 2007-12-11 11:16:03 DEBUG: pid 2604: I am 2604
> 2007-12-11 11:16:03 DEBUG: pid 2605: I am 2605
> 2007-12-11 11:16:03 DEBUG: pid 2606: I am 2606
> 2007-12-11 11:16:03 DEBUG: pid 2607: I am 2607
> 2007-12-11 11:16:03 DEBUG: pid 2608: I am 2608
> 2007-12-11 11:16:03 DEBUG: pid 2609: I am 2609
> 2007-12-11 11:16:03 DEBUG: pid 2610: I am 2610
> 2007-12-11 11:16:03 DEBUG: pid 2611: I am 2611
> 2007-12-11 11:16:03 DEBUG: pid 2612: I am 2612
> 2007-12-11 11:16:03 DEBUG: pid 2613: I am 2613
> 2007-12-11 11:16:03 DEBUG: pid 2614: I am 2614
> 2007-12-11 11:16:03 DEBUG: pid 2615: I am 2615
> 2007-12-11 11:16:03 LOG: pid 2583: pgpool successfully started
> 2007-12-11 11:16:03 DEBUG: pid 2616: I am PCP 2616
> 2007-12-11 11:16:59 DEBUG: pid 2615: I am 2615 accept fd 0
> 2007-12-11 11:16:59 DEBUG: pid 2615: Protocol Major: 3 Minor: 0 database: template1 user: postgres
> 2007-12-11 11:16:59 DEBUG: pid 2615: new_connection: connecting 0 backend
> 2007-12-11 11:16:59 DEBUG: pid 2615: new_connection: connecting 1 backend
> 2007-12-11 11:16:59 DEBUG: pid 2615: pool_read_message_length: slot: 0 length: 12
> 2007-12-11 11:16:59 DEBUG: pid 2615: pool_read_message_length: slot: 1 length: 8
> 2007-12-11 11:16:59 ERROR: pid 2615: pool_read_message_length: message length (8) in slot 1 does not match with slot 0
> postgres ＠ ylcsrv:/tmp$
> −−−−−−−−−−−−−−−−−−−−−−−−−−−−−−−−−−−
>
> _______________________________________________
> pgpool-general-jp mailing list
> pgpool-general-jp ＠ sraoss.jp
> http://www.sraoss.jp/mailman/listinfo/pgpool-general-jp
pgpool-general-jp メーリングリストの案内