Hi Andrew,<div><br></div><div>Here is more logs covering a larger period that shows multiple of this election cycle. Please note that in the below case I had set dc-deadtime to 5secs & the I_DC_TIMEOUT pops up every 5 secs. I turned this dc-deadtime to 10secs & the long election cycle problem disappeared. It no longer happens. I suspect that before a single election cycle completes, the next I_DC_TIMEOUT kicks-in. Could this be the reason?</div>
<div><br></div><div><div>On node 1:</div><div>Jan 17 12:00:02 vsa-0000003ca-vc-0 crmd: [1120]: info: do_election_count_vote: Election 3 (owner: 00000970-0970-0001-2b91-000000000001) pass: vote from vsa-0000003ca-vc-1 (Age)</div>
<div>Jan 17 12:00:02 vsa-0000003ca-vc-0 crmd: [1120]: info: do_state_transition: State transition S_INTEGRATION -> S_ELECTION [ input=I_ELECTION cause=C_FSA_INTERNAL origin=do_election_count_vote ]</div><div>Jan 17 12:00:02 vsa-0000003ca-vc-0 crmd: [1120]: WARN: do_log: FSA: Input I_JOIN_OFFER from route_message() received in state S_ELECTION</div>
<div>Jan 17 12:00:02 vsa-0000003ca-vc-0 crmd: [1120]: WARN: do_log: FSA: Input I_JOIN_OFFER from route_message() received in state S_ELECTION</div><div>Jan 17 12:00:03 vsa-0000003ca-vc-0 crmd: [1120]: info: do_election_count_vote: Election 4 (owner: 00000970-0970-0001-2b91-000000000001) pass: vote from vsa-0000003ca-vc-1 (Age)</div>
<div>Jan 17 12:00:03 vsa-0000003ca-vc-0 crmd: [1120]: info: do_election_count_vote: Election 5 (owner: 00000970-0970-0001-2b91-000000000001) pass: vote from vsa-0000003ca-vc-1 (Age)</div><div>Jan 17 12:00:04 vsa-0000003ca-vc-0 crmd: [1120]: info: do_state_transition: State transition S_ELECTION -> S_INTEGRATION [ input=I_ELECTION_DC cause=C_FSA_INTERNAL origin=do_election_check ]</div>
<div>Jan 17 12:00:04 vsa-0000003ca-vc-0 crmd: [1120]: info: start_subsystem: Starting sub-system "pengine"</div><div>Jan 17 12:00:04 vsa-0000003ca-vc-0 crmd: [1120]: WARN: start_subsystem: Client pengine already running as pid 4243</div>
<div>Jan 17 12:00:08 vsa-0000003ca-vc-0 crmd: [1120]: info: do_dc_takeover: Taking over DC status for this partition</div><div>Jan 17 12:00:08 vsa-0000003ca-vc-0 cib: [1116]: info: cib_process_readwrite: We are now in R/O mode</div>
<div>Jan 17 12:00:08 vsa-0000003ca-vc-0 cib: [1116]: info: cib_process_request: Operation complete: op cib_slave_all for section 'all' (origin=local/crmd/108, version=1.8.26): ok (rc=0)</div><div>Jan 17 12:00:08 vsa-0000003ca-vc-0 cib: [1116]: info: cib_process_readwrite: We are now in R/W mode</div>
<div>Jan 17 12:00:08 vsa-0000003ca-vc-0 cib: [1116]: info: cib_process_request: Operation complete: op cib_master for section 'all' (origin=local/crmd/109, version=1.8.26): ok (rc=0)</div><div>Jan 17 12:00:08 vsa-0000003ca-vc-0 cib: [1116]: info: cib_process_request: Operation complete: op cib_modify for section cib (origin=local/crmd/110, version=1.8.26): ok (rc=0)</div>
<div>Jan 17 12:00:08 vsa-0000003ca-vc-0 cib: [1116]: info: cib_process_request: Operation complete: op cib_modify for section crm_config (origin=local/crmd/112, version=1.8.26): ok (rc=0)</div><div>Jan 17 12:00:08 vsa-0000003ca-vc-0 crmd: [1120]: info: do_dc_join_offer_all: join-5: Waiting on 2 outstanding join acks</div>
<div>Jan 17 12:00:08 vsa-0000003ca-vc-0 cib: [1116]: info: cib_process_request: Operation complete: op cib_modify for section crm_config (origin=local/crmd/114, version=1.8.26): ok (rc=0)</div><div>Jan 17 12:00:08 vsa-0000003ca-vc-0 crmd: [1120]: info: config_query_callback: Checking for expired actions every 900000ms</div>
<div>Jan 17 12:00:09 vsa-0000003ca-vc-0 crmd: [1120]: info: do_election_count_vote: Election 6 (owner: 00000970-0970-0001-2b91-000000000001) pass: vote from vsa-0000003ca-vc-1 (Age)</div><div>Jan 17 12:00:09 vsa-0000003ca-vc-0 crmd: [1120]: info: update_dc: Set DC to vsa-0000003ca-vc-0 (3.0.1)</div>
<div>Jan 17 12:00:09 vsa-0000003ca-vc-0 crmd: [1120]: info: do_state_transition: State transition S_INTEGRATION -> S_ELECTION [ input=I_ELECTION cause=C_FSA_INTERNAL origin=do_election_count_vote ]</div><div>Jan 17 12:00:09 vsa-0000003ca-vc-0 crmd: [1120]: info: update_dc: Unset DC vsa-0000003ca-vc-0</div>
<div>Jan 17 12:00:09 vsa-0000003ca-vc-0 crmd: [1120]: info: do_election_count_vote: Election 7 (owner: 00000970-0970-0001-2b91-000000000001) pass: vote from vsa-0000003ca-vc-1 (Age)</div><div>Jan 17 12:00:09 vsa-0000003ca-vc-0 crmd: [1120]: WARN: do_log: FSA: Input I_JOIN_REQUEST from route_message() received in state S_ELECTION</div>
<div>Jan 17 12:00:10 vsa-0000003ca-vc-0 crmd: [1120]: info: do_state_transition: State transition S_ELECTION -> S_INTEGRATION [ input=I_ELECTION_DC cause=C_FSA_INTERNAL origin=do_election_check ]</div><div>Jan 17 12:00:10 vsa-0000003ca-vc-0 crmd: [1120]: info: start_subsystem: Starting sub-system "pengine"</div>
<div>Jan 17 12:00:10 vsa-0000003ca-vc-0 crmd: [1120]: WARN: start_subsystem: Client pengine already running as pid 4243</div><div>Jan 17 12:00:14 vsa-0000003ca-vc-0 crmd: [1120]: info: do_dc_takeover: Taking over DC status for this partition</div>
<div>Jan 17 12:00:14 vsa-0000003ca-vc-0 cib: [1116]: info: cib_process_readwrite: We are now in R/O mode</div><div>Jan 17 12:00:14 vsa-0000003ca-vc-0 cib: [1116]: info: cib_process_request: Operation complete: op cib_slave_all for section 'all' (origin=local/crmd/117, version=1.8.26): ok (rc=0)</div>
<div>Jan 17 12:00:14 vsa-0000003ca-vc-0 cib: [1116]: info: cib_process_readwrite: We are now in R/W mode</div><div>Jan 17 12:00:14 vsa-0000003ca-vc-0 cib: [1116]: info: cib_process_request: Operation complete: op cib_master for section 'all' (origin=local/crmd/118, version=1.8.26): ok (rc=0)</div>
<div>Jan 17 12:00:14 vsa-0000003ca-vc-0 cib: [1116]: info: cib_process_request: Operation complete: op cib_modify for section cib (origin=local/crmd/119, version=1.8.26): ok (rc=0)</div><div>Jan 17 12:00:14 vsa-0000003ca-vc-0 cib: [1116]: info: cib_process_request: Operation complete: op cib_modify for section crm_config (origin=local/crmd/121, version=1.8.26): ok (rc=0)</div>
<div>Jan 17 12:00:14 vsa-0000003ca-vc-0 crmd: [1120]: info: do_dc_join_offer_all: join-6: Waiting on 2 outstanding join acks</div><div>Jan 17 12:00:14 vsa-0000003ca-vc-0 cib: [1116]: info: cib_process_request: Operation complete: op cib_modify for section crm_config (origin=local/crmd/123, version=1.8.26): ok (rc=0)</div>
<div>Jan 17 12:00:14 vsa-0000003ca-vc-0 crmd: [1120]: info: config_query_callback: Checking for expired actions every 900000ms</div><div>Jan 17 12:00:15 vsa-0000003ca-vc-0 crmd: [1120]: info: do_election_count_vote: Election 8 (owner: 00000970-0970-0001-2b91-000000000001) pass: vote from vsa-0000003ca-vc-1 (Age)</div>
<div>Jan 17 12:00:15 vsa-0000003ca-vc-0 crmd: [1120]: info: update_dc: Set DC to vsa-0000003ca-vc-0 (3.0.1)</div><div>Jan 17 12:00:15 vsa-0000003ca-vc-0 crmd: [1120]: info: do_state_transition: State transition S_INTEGRATION -> S_ELECTION [ input=I_ELECTION cause=C_FSA_INTERNAL origin=do_election_count_vote ]</div>
<div>Jan 17 12:00:15 vsa-0000003ca-vc-0 crmd: [1120]: info: update_dc: Unset DC vsa-0000003ca-vc-0</div><div>Jan 17 12:00:15 vsa-0000003ca-vc-0 crmd: [1120]: WARN: do_log: FSA: Input I_JOIN_REQUEST from route_message() received in state S_ELECTION</div>
<div>Jan 17 12:00:16 vsa-0000003ca-vc-0 crmd: [1120]: info: do_election_count_vote: Election 9 (owner: 00000970-0970-0001-2b91-000000000001) pass: vote from vsa-0000003ca-vc-1 (Age)</div><div>Jan 17 12:00:17 vsa-0000003ca-vc-0 crmd: [1120]: info: do_state_transition: State transition S_ELECTION -> S_INTEGRATION [ input=I_ELECTION_DC cause=C_FSA_INTERNAL origin=do_election_check ]</div>
<div>Jan 17 12:00:17 vsa-0000003ca-vc-0 crmd: [1120]: info: start_subsystem: Starting sub-system "pengine"</div><div>Jan 17 12:00:17 vsa-0000003ca-vc-0 crmd: [1120]: WARN: start_subsystem: Client pengine already running as pid 4243</div>
<div>Jan 17 12:00:21 vsa-0000003ca-vc-0 crmd: [1120]: info: do_dc_takeover: Taking over DC status for this partition</div><div>Jan 17 12:00:21 vsa-0000003ca-vc-0 cib: [1116]: info: cib_process_readwrite: We are now in R/O mode</div>
<div>Jan 17 12:00:21 vsa-0000003ca-vc-0 cib: [1116]: info: cib_process_request: Operation complete: op cib_slave_all for section 'all' (origin=local/crmd/126, version=1.8.26): ok (rc=0)</div><div>Jan 17 12:00:21 vsa-0000003ca-vc-0 cib: [1116]: info: cib_process_readwrite: We are now in R/W mode</div>
<div>Jan 17 12:00:21 vsa-0000003ca-vc-0 cib: [1116]: info: cib_process_request: Operation complete: op cib_master for section 'all' (origin=local/crmd/127, version=1.8.26): ok (rc=0)</div><div>Jan 17 12:00:21 vsa-0000003ca-vc-0 cib: [1116]: info: cib_process_request: Operation complete: op cib_modify for section cib (origin=local/crmd/128, version=1.8.26): ok (rc=0)</div>
<div>Jan 17 12:00:21 vsa-0000003ca-vc-0 cib: [1116]: info: cib_process_request: Operation complete: op cib_modify for section crm_config (origin=local/crmd/130, version=1.8.26): ok (rc=0)</div><div>Jan 17 12:00:21 vsa-0000003ca-vc-0 crmd: [1120]: info: do_dc_join_offer_all: join-7: Waiting on 2 outstanding join acks</div>
<div>Jan 17 12:00:21 vsa-0000003ca-vc-0 cib: [1116]: info: cib_process_request: Operation complete: op cib_modify for section crm_config (origin=local/crmd/132, version=1.8.26): ok (rc=0)</div><div>Jan 17 12:00:21 vsa-0000003ca-vc-0 crmd: [1120]: info: config_query_callback: Checking for expired actions every 900000ms</div>
<div><br></div><div><br></div><div>On node 2:</div><div>Jan 17 12:00:02 vsa-0000003ca-vc-1 crmd: [1104]: info: crm_timer_popped: Election Trigger (I_DC_TIMEOUT) just popped!</div><div>Jan 17 12:00:02 vsa-0000003ca-vc-1 crmd: [1104]: WARN: do_log: FSA: Input I_DC_TIMEOUT from crm_timer_popped() received in state S_PENDING</div>
<div>Jan 17 12:00:02 vsa-0000003ca-vc-1 crmd: [1104]: info: do_state_transition: State transition S_PENDING -> S_ELECTION [ input=I_DC_TIMEOUT cause=C_TIMER_POPPED origin=crm_timer_popped ]</div><div>Jan 17 12:00:03 vsa-0000003ca-vc-1 crmd: [1104]: WARN: do_log: FSA: Input I_JOIN_OFFER from route_message() received in state S_ELECTION</div>
<div>Jan 17 12:00:03 vsa-0000003ca-vc-1 crmd: [1104]: WARN: do_log: FSA: Input I_JOIN_OFFER from route_message() received in state S_ELECTION</div><div>Jan 17 12:00:03 vsa-0000003ca-vc-1 crmd: [1104]: info: do_state_transition: State transition S_ELECTION -> S_PENDING [ input=I_PENDING cause=C_FSA_INTERNAL origin=do_election_count_vote ]</div>
<div>Jan 17 12:00:03 vsa-0000003ca-vc-1 crmd: [1104]: info: do_dc_release: DC role released</div><div>Jan 17 12:00:03 vsa-0000003ca-vc-1 crmd: [1104]: info: do_te_control: Transitioner is now inactive</div><div>Jan 17 12:00:09 vsa-0000003ca-vc-1 crmd: [1104]: info: crm_timer_popped: Election Trigger (I_DC_TIMEOUT) just popped!</div>
<div>Jan 17 12:00:09 vsa-0000003ca-vc-1 crmd: [1104]: WARN: do_log: FSA: Input I_DC_TIMEOUT from crm_timer_popped() received in state S_PENDING</div><div>Jan 17 12:00:09 vsa-0000003ca-vc-1 crmd: [1104]: info: do_state_transition: State transition S_PENDING -> S_ELECTION [ input=I_DC_TIMEOUT cause=C_TIMER_POPPED origin=crm_timer_popped ]</div>
<div>Jan 17 12:00:09 vsa-0000003ca-vc-1 crmd: [1104]: WARN: do_log: FSA: Input I_JOIN_OFFER from route_message() received in state S_ELECTION</div><div>Jan 17 12:00:10 vsa-0000003ca-vc-1 crmd: [1104]: info: do_state_transition: State transition S_ELECTION -> S_PENDING [ input=I_PENDING cause=C_FSA_INTERNAL origin=do_election_count_vote ]</div>
<div>Jan 17 12:00:10 vsa-0000003ca-vc-1 crmd: [1104]: info: do_dc_release: DC role released</div><div>Jan 17 12:00:10 vsa-0000003ca-vc-1 crmd: [1104]: info: do_te_control: Transitioner is now inactive</div><div>Jan 17 12:00:15 vsa-0000003ca-vc-1 crmd: [1104]: info: crm_timer_popped: Election Trigger (I_DC_TIMEOUT) just popped!</div>
<div>Jan 17 12:00:15 vsa-0000003ca-vc-1 crmd: [1104]: WARN: do_log: FSA: Input I_DC_TIMEOUT from crm_timer_popped() received in state S_PENDING</div><div>Jan 17 12:00:15 vsa-0000003ca-vc-1 crmd: [1104]: info: do_state_transition: State transition S_PENDING -> S_ELECTION [ input=I_DC_TIMEOUT cause=C_TIMER_POPPED origin=crm_timer_popped ]</div>
<div>Jan 17 12:00:16 vsa-0000003ca-vc-1 crmd: [1104]: WARN: do_log: FSA: Input I_JOIN_OFFER from route_message() received in state S_ELECTION</div><div>Jan 17 12:00:16 vsa-0000003ca-vc-1 crmd: [1104]: info: do_state_transition: State transition S_ELECTION -> S_PENDING [ input=I_PENDING cause=C_FSA_INTERNAL origin=do_election_count_vote ]</div>
<div>Jan 17 12:00:16 vsa-0000003ca-vc-1 crmd: [1104]: info: do_dc_release: DC role released</div><div>Jan 17 12:00:16 vsa-0000003ca-vc-1 crmd: [1104]: info: do_te_control: Transitioner is now inactive</div><div>Jan 17 12:00:22 vsa-0000003ca-vc-1 crmd: [1104]: info: crm_timer_popped: Election Trigger (I_DC_TIMEOUT) just popped!</div>
<div>Jan 17 12:00:22 vsa-0000003ca-vc-1 crmd: [1104]: WARN: do_log: FSA: Input I_DC_TIMEOUT from crm_timer_popped() received in state S_PENDING</div><div>Jan 17 12:00:22 vsa-0000003ca-vc-1 crmd: [1104]: info: do_state_transition: State transition S_PENDING -> S_ELECTION [ input=I_DC_TIMEOUT cause=C_TIMER_POPPED origin=crm_timer_popped ]</div>
<div>Jan 17 12:00:22 vsa-0000003ca-vc-1 crmd: [1104]: WARN: do_log: FSA: Input I_JOIN_OFFER from route_message() received in state S_ELECTION</div><div>Jan 17 12:00:23 vsa-0000003ca-vc-1 crmd: [1104]: info: do_state_transition: State transition S_ELECTION -> S_PENDING [ input=I_PENDING cause=C_FSA_INTERNAL origin=do_election_count_vote ]</div>
<div>Jan 17 12:00:23 vsa-0000003ca-vc-1 crmd: [1104]: info: do_dc_release: DC role released</div><div>Jan 17 12:00:23 vsa-0000003ca-vc-1 crmd: [1104]: info: do_te_control: Transitioner is now inactive</div><div><br></div>
<div>--Shyam</div><br><div class="gmail_quote">On Thu, Feb 2, 2012 at 3:28 PM, Andrew Beekhof <span dir="ltr"><<a href="mailto:andrew@beekhof.net">andrew@beekhof.net</a>></span> wrote:<br><blockquote class="gmail_quote" style="margin:0 0 0 .8ex;border-left:1px #ccc solid;padding-left:1ex">
They both think they should be the DC.<br>
But the log fragments dont extend back far enough to say why.<br>
<div><div></div><div class="h5"><br>
On Fri, Jan 27, 2012 at 10:21 PM, Shyam <<a href="mailto:shyam.kaushik@gmail.com">shyam.kaushik@gmail.com</a>> wrote:<br>
> Folks,<br>
><br>
> We are constantly running into a long election cycle where in a 2-node<br>
> cluster when both of them are simultaneously rebooted, they take a long time<br>
> running through election loop.<br>
><br>
> On one node pacemaker loops like:<br>
> Jan 26 22:03:20 vsa-0000009c-vc-1 crmd: [1134]: info: do_dc_takeover: Taking<br>
> over DC status for this partition<br>
> Jan 26 22:03:20 vsa-0000009c-vc-1 cib: [1130]: info: cib_process_readwrite:<br>
> We are now in R/O mode<br>
> Jan 26 22:03:20 vsa-0000009c-vc-1 cib: [1130]: info: cib_process_request:<br>
> Operation complete: op cib_slave_all for section 'all'<br>
> (origin=local/crmd/222, version=1.1.1): ok (rc=0)<br>
> Jan 26 22:03:20 vsa-0000009c-vc-1 cib: [1130]: info: cib_process_readwrite:<br>
> We are now in R/W mode<br>
> Jan 26 22:03:20 vsa-0000009c-vc-1 cib: [1130]: info: cib_process_request:<br>
> Operation complete: op cib_master for section 'all' (origin=local/crmd/223,<br>
> version=1.1.1): ok (rc=0)<br>
> Jan 26 22:03:20 vsa-0000009c-vc-1 cib: [1130]: info: cib_process_request:<br>
> Operation complete: op cib_modify for section cib (origin=local/crmd/224,<br>
> version=1.1.1): ok (rc=0)<br>
> Jan 26 22:03:20 vsa-0000009c-vc-1 cib: [1130]: info: cib_process_request:<br>
> Operation complete: op cib_modify for section crm_config<br>
> (origin=local/crmd/226, version=1.1.1): ok (rc=0)<br>
> Jan 26 22:03:20 vsa-0000009c-vc-1 crmd: [1134]: info: do_dc_join_offer_all:<br>
> join-25: Waiting on 2 outstanding join acks<br>
> Jan 26 22:03:20 vsa-0000009c-vc-1 cib: [1130]: info: cib_process_request:<br>
> Operation complete: op cib_modify for section crm_config<br>
> (origin=local/crmd/228, version=1.1.1): ok (rc=0)<br>
> Jan 26 22:03:20 vsa-0000009c-vc-1 crmd: [1134]: info: config_query_callback:<br>
> Checking for expired actions every 900000ms<br>
> Jan 26 22:03:20 vsa-0000009c-vc-1 crmd: [1134]: info:<br>
> do_election_count_vote: Election 50 (owner:<br>
> 00000156-0156-0000-2b91-000000000000) pass: vote from vsa-0000009c-vc-0<br>
> (Age)<br>
> Jan 26 22:03:20 vsa-0000009c-vc-1 crmd: [1134]: info: update_dc: Set DC to<br>
> vsa-0000009c-vc-1 (3.0.1)<br>
> Jan 26 22:03:20 vsa-0000009c-vc-1 crmd: [1134]: info: do_state_transition:<br>
> State transition S_INTEGRATION -> S_ELECTION [ input=I_ELECTION<br>
> cause=C_FSA_INTERNAL origin=do_election_count_vote ]<br>
> Jan 26 22:03:20 vsa-0000009c-vc-1 crmd: [1134]: info: update_dc: Unset DC<br>
> vsa-0000009c-vc-1<br>
> Jan 26 22:03:21 vsa-0000009c-vc-1 crmd: [1134]: info:<br>
> do_election_count_vote: Election 51 (owner:<br>
> 00000156-0156-0000-2b91-000000000000) pass: vote from vsa-0000009c-vc-0<br>
> (Age)<br>
> Jan 26 22:03:21 vsa-0000009c-vc-1 crmd: [1134]: WARN: do_log: FSA: Input<br>
> I_JOIN_REQUEST from route_message() received in state S_ELECTION<br>
> Jan 26 22:03:22 vsa-0000009c-vc-1 crmd: [1134]: info: do_state_transition:<br>
> State transition S_ELECTION -> S_INTEGRATION [ input=I_ELECTION_DC<br>
> cause=C_FSA_INTERNAL origin=do_election_check ]<br>
> Jan 26 22:03:22 vsa-0000009c-vc-1 crmd: [1134]: info: start_subsystem:<br>
> Starting sub-system "pengine"<br>
> Jan 26 22:03:22 vsa-0000009c-vc-1 crmd: [1134]: WARN: start_subsystem:<br>
> Client pengine already running as pid 1234<br>
> Jan 26 22:03:26 vsa-0000009c-vc-1 crmd: [1134]: info: do_dc_takeover: Taking<br>
> over DC status for this partition<br>
> Jan 26 22:03:26 vsa-0000009c-vc-1 cib: [1130]: info: cib_process_readwrite:<br>
> We are now in R/O mode<br>
> Jan 26 22:03:26 vsa-0000009c-vc-1 cib: [1130]: info: cib_process_request:<br>
> Operation complete: op cib_slave_all for section 'all'<br>
> (origin=local/crmd/231, version=1.1.1): ok (rc=0)<br>
> Jan 26 22:03:26 vsa-0000009c-vc-1 cib: [1130]: info: cib_process_readwrite:<br>
> We are now in R/W mode<br>
> Jan 26 22:03:26 vsa-0000009c-vc-1 cib: [1130]: info: cib_process_request:<br>
> Operation complete: op cib_master for section 'all' (origin=local/crmd/232,<br>
> version=1.1.1): ok (rc=0)<br>
> Jan 26 22:03:26 vsa-0000009c-vc-1 cib: [1130]: info: cib_process_request:<br>
> Operation complete: op cib_modify for section cib (origin=local/crmd/233,<br>
> version=1.1.1): ok (rc=0)<br>
> Jan 26 22:03:26 vsa-0000009c-vc-1 cib: [1130]: info: cib_process_request:<br>
> Operation complete: op cib_modify for section crm_config<br>
> (origin=local/crmd/235, version=1.1.1): ok (rc=0)<br>
> Jan 26 22:03:26 vsa-0000009c-vc-1 crmd: [1134]: info: do_dc_join_offer_all:<br>
> join-26: Waiting on 2 outstanding join acks<br>
> Jan 26 22:03:26 vsa-0000009c-vc-1 cib: [1130]: info: cib_process_request:<br>
> Operation complete: op cib_modify for section crm_config<br>
> (origin=local/crmd/237, version=1.1.1): ok (rc=0)<br>
> Jan 26 22:03:26 vsa-0000009c-vc-1 crmd: [1134]: info: config_query_callback:<br>
> Checking for expired actions every 900000ms<br>
> Jan 26 22:03:26 vsa-0000009c-vc-1 crmd: [1134]: info:<br>
> do_election_count_vote: Election 52 (owner:<br>
> 00000156-0156-0000-2b91-000000000000) pass: vote from vsa-0000009c-vc-0<br>
> (Age)<br>
> Jan 26 22:03:26 vsa-0000009c-vc-1 crmd: [1134]: info: update_dc: Set DC to<br>
> vsa-0000009c-vc-1 (3.0.1)<br>
> Jan 26 22:03:26 vsa-0000009c-vc-1 crmd: [1134]: info: do_state_transition:<br>
> State transition S_INTEGRATION -> S_ELECTION [ input=I_ELECTION<br>
> cause=C_FSA_INTERNAL origin=do_election_count_vote ]<br>
> Jan 26 22:03:26 vsa-0000009c-vc-1 crmd: [1134]: info: update_dc: Unset DC<br>
> vsa-0000009c-vc-1<br>
> Jan 26 22:03:27 vsa-0000009c-vc-1 crmd: [1134]: info:<br>
> do_election_count_vote: Election 53 (owner:<br>
> 00000156-0156-0000-2b91-000000000000) pass: vote from vsa-0000009c-vc-0<br>
> (Age)<br>
> Jan 26 22:03:27 vsa-0000009c-vc-1 crmd: [1134]: WARN: do_log: FSA: Input<br>
> I_JOIN_REQUEST from route_message() received in state S_ELECTION<br>
> Jan 26 22:03:28 vsa-0000009c-vc-1 crmd: [1134]: info: do_state_transition:<br>
> State transition S_ELECTION -> S_INTEGRATION [ input=I_ELECTION_DC<br>
> cause=C_FSA_INTERNAL origin=do_election_check ]<br>
> Jan 26 22:03:28 vsa-0000009c-vc-1 crmd: [1134]: info: start_subsystem:<br>
> Starting sub-system "pengine"<br>
> Jan 26 22:03:28 vsa-0000009c-vc-1 crmd: [1134]: WARN: start_subsystem:<br>
> Client pengine already running as pid 1234<br>
><br>
> &  other node with<br>
> Jan 26 22:03:20 vsa-0000009c-vc-0 crmd: [1314]: info: crm_timer_popped:<br>
> Election Trigger (I_DC_TIMEOUT) just popped!<br>
> Jan 26 22:03:20 vsa-0000009c-vc-0 crmd: [1314]: WARN: do_log: FSA: Input<br>
> I_DC_TIMEOUT from crm_timer_popped() received in state S_PENDING<br>
> Jan 26 22:03:20 vsa-0000009c-vc-0 crmd: [1314]: info: do_state_transition:<br>
> State transition S_PENDING -> S_ELECTION [ input=I_DC_TIMEOUT<br>
> cause=C_TIMER_POPPED origin=crm_timer_popped ]<br>
> Jan 26 22:03:21 vsa-0000009c-vc-0 crmd: [1314]: WARN: do_log: FSA: Input<br>
> I_JOIN_OFFER from route_message() received in state S_ELECTION<br>
> Jan 26 22:03:21 vsa-0000009c-vc-0 crmd: [1314]: info: do_state_transition:<br>
> State transition S_ELECTION -> S_PENDING [ input=I_PENDING<br>
> cause=C_FSA_INTERNAL origin=do_election_count_vote ]<br>
> Jan 26 22:03:21 vsa-0000009c-vc-0 crmd: [1314]: info: do_dc_release: DC role<br>
> released<br>
> Jan 26 22:03:21 vsa-0000009c-vc-0 crmd: [1314]: info: do_te_control:<br>
> Transitioner is now inactive<br>
> Jan 26 22:03:26 vsa-0000009c-vc-0 crmd: [1314]: info: crm_timer_popped:<br>
> Election Trigger (I_DC_TIMEOUT) just popped!<br>
> Jan 26 22:03:26 vsa-0000009c-vc-0 crmd: [1314]: WARN: do_log: FSA: Input<br>
> I_DC_TIMEOUT from crm_timer_popped() received in state S_PENDING<br>
> Jan 26 22:03:26 vsa-0000009c-vc-0 crmd: [1314]: info: do_state_transition:<br>
> State transition S_PENDING -> S_ELECTION [ input=I_DC_TIMEOUT<br>
> cause=C_TIMER_POPPED origin=crm_timer_popped ]<br>
> Jan 26 22:03:27 vsa-0000009c-vc-0 crmd: [1314]: WARN: do_log: FSA: Input<br>
> I_JOIN_OFFER from route_message() received in state S_ELECTION<br>
> Jan 26 22:03:27 vsa-0000009c-vc-0 crmd: [1314]: info: do_state_transition:<br>
> State transition S_ELECTION -> S_PENDING [ input=I_PENDING<br>
> cause=C_FSA_INTERNAL origin=do_election_count_vote ]<br>
> Jan 26 22:03:27 vsa-0000009c-vc-0 crmd: [1314]: info: do_dc_release: DC role<br>
> released<br>
> Jan 26 22:03:27 vsa-0000009c-vc-0 crmd: [1314]: info: do_te_control:<br>
> Transitioner is now inactive<br>
><br>
> This takes several minutes & finally breaks.<br>
><br>
> Any pointers on what can be causing this?<br>
><br>
> Thanks.<br>
><br>
> --Shyam<br>
><br>
</div></div><div><div></div><div class="h5">> _______________________________________________<br>
> Pacemaker mailing list: <a href="mailto:Pacemaker@oss.clusterlabs.org">Pacemaker@oss.clusterlabs.org</a><br>
> <a href="http://oss.clusterlabs.org/mailman/listinfo/pacemaker" target="_blank">http://oss.clusterlabs.org/mailman/listinfo/pacemaker</a><br>
><br>
> Project Home: <a href="http://www.clusterlabs.org" target="_blank">http://www.clusterlabs.org</a><br>
> Getting started: <a href="http://www.clusterlabs.org/doc/Cluster_from_Scratch.pdf" target="_blank">http://www.clusterlabs.org/doc/Cluster_from_Scratch.pdf</a><br>
> Bugs: <a href="http://bugs.clusterlabs.org" target="_blank">http://bugs.clusterlabs.org</a><br>
><br>
<br>
_______________________________________________<br>
Pacemaker mailing list: <a href="mailto:Pacemaker@oss.clusterlabs.org">Pacemaker@oss.clusterlabs.org</a><br>
<a href="http://oss.clusterlabs.org/mailman/listinfo/pacemaker" target="_blank">http://oss.clusterlabs.org/mailman/listinfo/pacemaker</a><br>
<br>
Project Home: <a href="http://www.clusterlabs.org" target="_blank">http://www.clusterlabs.org</a><br>
Getting started: <a href="http://www.clusterlabs.org/doc/Cluster_from_Scratch.pdf" target="_blank">http://www.clusterlabs.org/doc/Cluster_from_Scratch.pdf</a><br>
Bugs: <a href="http://bugs.clusterlabs.org" target="_blank">http://bugs.clusterlabs.org</a><br>
</div></div></blockquote></div><br></div>