[Pacemaker] CRMd exits because of internal error

Andrew Beekhof andrew at beekhof.net
Thu Jul 25 02:42:25 UTC 2013


On 19/07/2013, at 12:20 AM, K Mehta <kiranmehta1981 at gmail.com> wrote:

> Hi,
> 
> I have a two node cluster. I have few resources configured on it. On vqa12, CRMd dies due to some internal error. It is not clear why CRMd decides to die on May5 at 22:14:50  on system vqa12

Its because of:

May 05 22:14:50 [3518] vqa12       crmd:    error: cib_quorum_update_complete:  Quorum update 135 failed

Could the machine have been overloaded?  Thats usually the reason.

> ================
> May 05 22:14:50 [3518] vqa12       crmd:     info: do_exit:     Performing A_EXIT_0 - gracefully exiting the CRMd
> ================
> 
> 
> Corosync logs vqa12
> ==============
> 
> May 05 22:12:53 [3517] vqa12    pengine:     info: determine_online_status:     Node vqa12 is online
> May 05 22:12:53 [3517] vqa12    pengine:     info: determine_online_status:     Node vqa11 is online
> May 05 22:12:53 [3517] vqa12    pengine:     info: find_anonymous_clone:        Internally renamed vha-94b33532-15ba-4923-a920-ab9268ccd856 on vqa12 to vha-94b33532-15ba-4923-a920-ab9268ccd856:0
> May 05 22:12:53 [3517] vqa12    pengine:     info: native_print:        vgc_virtual_ip  (ocf::heartbeat:IPaddr2):       Started vqa12
> May 05 22:12:53 [3517] vqa12    pengine:     info: clone_print:          Master/Slave Set: ms-94b33532-15ba-4923-a920-ab9268ccd856 [vha-94b33532-15ba-4923-a920-ab9268ccd856]
> May 05 22:12:53 [3517] vqa12    pengine:     info: short_print:              Masters: [ vqa12 ]
> May 05 22:12:53 [3517] vqa12    pengine:     info: short_print:              Stopped: [ vha-94b33532-15ba-4923-a920-ab9268ccd856:1 ]
> May 05 22:12:53 [3517] vqa12    pengine:     info: master_color:        Promoting vha-94b33532-15ba-4923-a920-ab9268ccd856:0 (Master vqa12)
> May 05 22:12:53 [3517] vqa12    pengine:     info: master_color:        ms-94b33532-15ba-4923-a920-ab9268ccd856: Promoted 1 instances of a possible 1 to master
> May 05 22:12:53 [3517] vqa12    pengine:     info: RecurringOp:          Start recurring monitor (31s) for vha-94b33532-15ba-4923-a920-ab9268ccd856:1 on vqa11
> May 05 22:12:53 [3517] vqa12    pengine:     info: RecurringOp:          Start recurring monitor (31s) for vha-94b33532-15ba-4923-a920-ab9268ccd856:1 on vqa11
> May 05 22:12:53 [3517] vqa12    pengine:     info: LogActions:  Leave   vgc_virtual_ip  (Started vqa12)
> May 05 22:12:53 [3517] vqa12    pengine:     info: LogActions:  Leave   vha-94b33532-15ba-4923-a920-ab9268ccd856:0      (Master vqa12)
> May 05 22:12:53 [3517] vqa12    pengine:   notice: LogActions:  Start   vha-94b33532-15ba-4923-a920-ab9268ccd856:1      (vqa11)
> May 05 22:12:53 [3517] vqa12    pengine:   notice: process_pe_message:  Calculated Transition 7: /var/lib/pacemaker/pengine/pe-input-99.bz2
> May 05 22:12:53 [3518] vqa12       crmd:     info: do_state_transition:         State transition S_POLICY_ENGINE -> S_TRANSITION_ENGINE [ input=I_PE_SUCCESS cause=C_IPC_MESSAGE origin=handle_response ]
> May 05 22:12:53 [3518] vqa12       crmd:     info: do_te_invoke:        Processing graph 7 (ref=pe_calc-dc-1367817173-63) derived from /var/lib/pacemaker/pengine/pe-input-99.bz2
> May 05 22:12:53 [3518] vqa12       crmd:     info: te_rsc_command:      Initiating action 5: monitor vgc_virtual_ip_monitor_0 on vqa11
> May 05 22:12:53 [3518] vqa12       crmd:     info: te_rsc_command:      Initiating action 6: monitor vha-94b33532-15ba-4923-a920-ab9268ccd856:1_monitor_0 on vqa11
> May 05 22:12:56 [3518] vqa12       crmd:     info: te_rsc_command:      Initiating action 4: probe_complete probe_complete on vqa11 - no waiting
> May 05 22:12:56 [3518] vqa12       crmd:     info: te_rsc_command:      Initiating action 14: start vha-94b33532-15ba-4923-a920-ab9268ccd856:1_start_0 on vqa11
> May 05 22:12:56 [3518] vqa12       crmd:     info: te_rsc_command:      Initiating action 15: monitor vha-94b33532-15ba-4923-a920-ab9268ccd856:1_monitor_31000 on vqa11
> May 05 22:12:56 [3518] vqa12       crmd:   notice: run_graph:   Transition 7 (Complete=8, Pending=0, Fired=0, Skipped=0, Incomplete=0, Source=/var/lib/pacemaker/pengine/pe-input-99.bz2): Complete
> May 05 22:12:56 [3518] vqa12       crmd:   notice: do_state_transition:         State transition S_TRANSITION_ENGINE -> S_IDLE [ input=I_TE_SUCCESS cause=C_FSA_INTERNAL origin=notify_crmd ]
> May 05 22:14:50 [3518] vqa12       crmd:    error: cib_quorum_update_complete:  Quorum update 135 failed
> May 05 22:14:50 [3518] vqa12       crmd:    error: do_log:      FSA: Input I_ERROR from cib_quorum_update_complete() received in state S_IDLE
> May 05 22:14:50 [3518] vqa12       crmd:   notice: do_state_transition:         State transition S_IDLE -> S_RECOVERY [ input=I_ERROR cause=C_FSA_INTERNAL origin=cib_quorum_update_complete ]
> May 05 22:14:50 [3518] vqa12       crmd:    error: do_recover:  Action A_RECOVER (0000000001000000) not supported
> May 05 22:14:50 [3518] vqa12       crmd:  warning: do_election_vote:    Not voting in election, we're in state S_RECOVERY
> May 05 22:14:50 [3518] vqa12       crmd:     info: do_dc_release:       DC role released
> May 05 22:14:50 [3518] vqa12       crmd:     info: pe_ipc_destroy:      Connection to the Policy Engine released
> May 05 22:14:50 [3518] vqa12       crmd:     info: do_te_control:       Transitioner is now inactive
> May 05 22:14:50 [3518] vqa12       crmd:    error: do_log:      FSA: Input I_TERMINATE from do_recover() received in state S_RECOVERY
> May 05 22:14:50 [3518] vqa12       crmd:     info: do_state_transition:         State transition S_RECOVERY -> S_TERMINATE [ input=I_TERMINATE cause=C_FSA_INTERNAL orig
> May 05 22:14:50 [3518] vqa12       crmd:     info: do_shutdown:         Disconnecting STONITH...
> May 05 22:14:50 [3518] vqa12       crmd:     info: tengine_stonith_connection_destroy:  Fencing daemon disconnected
> May 05 22:14:50 [3515] vqa12       lrmd:     info: cancel_recurring_action:     Cancelling operation vha-94b33532-15ba-4923-a920-ab9268ccd856_monitor_30000
> May 05 22:14:50 [3518] vqa12       crmd:    error: verify_stopped:      Resource vgc_virtual_ip was active at shutdown.  You may ignore this error if it is unmanaged.
> May 05 22:14:50 [3518] vqa12       crmd:    error: verify_stopped:      Resource vha-94b33532-15ba-4923-a920-ab9268ccd856 was active at shutdown.  You may ignore this error if it is unmanaged.
> May 05 22:14:50 [3518] vqa12       crmd:     info: lrmd_api_disconnect:         Disconnecting from lrmd service
> May 05 22:14:50 [3515] vqa12       lrmd:     info: lrmd_ipc_destroy:    LRMD client disconnecting 0x14bf9f0 - name: crmd id: a7e581bd-c0fe-4d3f-9734-acca62e868a8
> May 05 22:14:50 [3518] vqa12       crmd:     info: lrmd_connection_destroy:     connection destroyed
> May 05 22:14:50 [3518] vqa12       crmd:     info: lrm_connection_destroy:      LRM Connection disconnected
> May 05 22:14:50 [3518] vqa12       crmd:     info: do_lrm_control:      Disconnected from the LRM
> May 05 22:14:50 [3518] vqa12       crmd:     info: crm_cluster_disconnect:      Disconnecting from cluster infrastructure: classic openais (with plugin)
> May 05 22:14:50 [3518] vqa12       crmd:   notice: terminate_cs_connection:     Disconnecting from Corosync
> May 05 22:14:50 [3518] vqa12       crmd:     info: crm_cluster_disconnect:      Disconnected from classic openais (with plugin)
> May 05 22:14:50 [3518] vqa12       crmd:     info: do_ha_control:       Disconnected from the cluster
> May 05 22:14:50 [3518] vqa12       crmd:     info: do_cib_control:      Disconnecting CIB
> May 05 22:14:50 corosync [pcmk  ] info: pcmk_ipc_exit: Client crmd (conn=0x231dd00, async-conn=0x231dd00) left
> May 05 22:14:50 [3513] vqa12        cib:     info: cib_process_readwrite:       We are now in R/O mode
> May 05 22:14:50 [3513] vqa12        cib:  warning: qb_ipcs_event_sendv:         new_event_notification (3513-3518-15): Broken pipe (32)
> May 05 22:14:50 [3513] vqa12        cib:     info: crm_ipcs_send:       Event 480 failed, size=162, to=0x2668ba0[3518], queue=1, retries=0, rc=-32: <cib-reply t="cib" cib_op="cib_slave" cib_callid="156" cib_clientid="4589219a-4c81-4a49-803c-df4cc8037f9a" cib_callopt="
> May 05 22:14:50 [3513] vqa12        cib:  warning: do_local_notify:     A-Sync reply to crmd failed: No message of desired type
> May 05 22:14:50 [3518] vqa12       crmd:     info: crmd_cib_connection_destroy:         Connection to the CIB terminated...
> May 05 22:14:50 [3518] vqa12       crmd:     info: qb_ipcs_us_withdraw:         withdrawing server sockets
> May 05 22:14:50 [3518] vqa12       crmd:     info: do_exit:     Performing A_EXIT_0 - gracefully exiting the CRMd
> May 05 22:14:50 [3518] vqa12       crmd:    error: do_exit:     Could not recover from internal error
> May 05 22:14:50 [3518] vqa12       crmd:     info: do_exit:     [crmd] stopped (2)
> May 05 22:14:50 [3518] vqa12       crmd:     info: crmd_exit:   Dropping I_PENDING: [ state=S_TERMINATE cause=C_FSA_INTERNAL origin=do_election_vote ]
> May 05 22:14:50 [3518] vqa12       crmd:     info: crmd_exit:   Dropping I_RELEASE_SUCCESS: [ state=S_TERMINATE cause=C_FSA_INTERNAL origin=do_dc_release ]
> May 05 22:14:50 [3518] vqa12       crmd:     info: crmd_exit:   Dropping I_TERMINATE: [ state=S_TERMINATE cause=C_FSA_INTERNAL origin=do_stop ]
> May 05 22:14:50 [3518] vqa12       crmd:     info: lrmd_api_disconnect:         Disconnecting from lrmd service
> May 05 22:14:50 [3518] vqa12       crmd:     info: crm_xml_cleanup:     Cleaning up memory from libxml2
> May 05 22:14:50 [3507] vqa12 pacemakerd:    error: pcmk_child_exit:     Child process crmd exited (pid=3518, rc=2)
> May 05 22:14:50 [3514] vqa12 stonith-ng:     info: crm_update_peer_proc:        pcmk_mcp_dispatch: Node vqa12[33663168] - unknown is now (null)
> May 05 22:14:50 [3513] vqa12        cib:     info: crm_update_peer_proc:        pcmk_mcp_dispatch: Node vqa12[33663168] - unknown is now (null)
> May 05 22:14:50 [3507] vqa12 pacemakerd:   notice: pcmk_process_exit:   Respawning failed child process: crmd
> May 05 22:14:50 [3507] vqa12 pacemakerd:     info: start_child:         Forked child 7806 for process crmd
> May 05 22:14:50 [3514] vqa12 stonith-ng:     info: crm_update_peer_proc:        pcmk_mcp_dispatch: Node vqa12[33663168] - unknown is now (null)
> May 05 22:14:50 [3513] vqa12        cib:     info: crm_update_peer_proc:        pcmk_mcp_dispatch: Node vqa12[33663168] - unknown is now (null)
> May 05 22:14:50 corosync [pcmk  ] WARN: route_ais_message: Sending message to local.crmd failed: ipc delivery failed (rc=-2)
> May 05 22:14:50 [7806] vqa12       crmd:     info: crm_log_init:        Cannot change active directory to /var/lib/pacemaker/cores/hacluster: Permission denied (13)
> May 05 22:14:50 [7806] vqa12       crmd:   notice: main:        CRM Git Version: 394e906
> May 05 22:14:50 [7806] vqa12       crmd:     info: get_cluster_type:    Cluster type is: 'openais'
> 
> 
> 
> Corosync logs vqa11
> ===============
> 
> May 05 22:12:50 [3553] vqa11        cib:     info: cib_server_process_diff:     Requesting re-sync from peer
> May 05 22:12:50 [3553] vqa11        cib:   notice: cib_server_process_diff:     Not applying diff 0.7126.40 -> 0.7126.41 (sync in progress)
> May 05 22:12:50 corosync [CPG   ] chosen downlist: sender r(0) ip(192.168.1.1) ; members(old:1 left:0)
> May 05 22:12:50 corosync [MAIN  ] Completed service synchronization, ready to provide service.
> May 05 22:12:50 [3553] vqa11        cib:     info: cib_process_replace:         Digest matched on replace from vqa12: dfa194fcf61b3e86b6a79b2506c41a1c
> May 05 22:12:50 [3553] vqa11        cib:     info: cib_process_replace:         Replaced 0.7126.1 with 0.7126.42 from vqa12
> May 05 22:12:50 [3553] vqa11        cib:     info: cib_replace_notify:  Replaced: 0.7126.1 -> 0.7126.42 from vqa12
> May 05 22:12:51 [3554] vqa11 stonith-ng:     info: stonith_command:     Processed register from crmd.3558: OK (0)
> May 05 22:12:51 [3554] vqa11 stonith-ng:     info: stonith_command:     Processed st_notify from crmd.3558: OK (0)
> May 05 22:12:51 [3554] vqa11 stonith-ng:     info: stonith_command:     Processed st_notify from crmd.3558: OK (0)
> May 05 22:12:51 [3558] vqa11       crmd:     info: ais_dispatch_message:        Membership 4120: quorum still lost
> May 05 22:12:51 [3558] vqa11       crmd:     info: crm_get_peer:        Node <null> now has id: 33663168
> May 05 22:12:51 [3558] vqa11       crmd:   notice: crm_update_peer_state:       crm_update_ais_node: Node (null)[33663168] - state is now member
> May 05 22:12:51 [3558] vqa11       crmd:     info: crm_update_peer:     crm_update_ais_node: Node (null): id=33663168 state=member addr=r(0) ip(192.168.1.2)  (new) votes=0 born=0 seen=4120 proc=00000000000000000000000000000000
> May 05 22:12:51 [3558] vqa11       crmd:   notice: ais_dispatch_message:        Membership 4120: quorum acquired
> May 05 22:12:51 [3558] vqa11       crmd:     info: crm_get_peer:        Node 33663168 is now known as vqa12
> May 05 22:12:51 [3558] vqa11       crmd:     info: peer_update_callback:        vqa12 is now member
> May 05 22:12:51 [3558] vqa11       crmd:     info: crm_get_peer:        Node 33663168 has uuid vqa12
> May 05 22:12:51 [3558] vqa11       crmd:     info: crm_update_peer:     crm_update_ais_node: Node vqa12: id=33663168 state=member addr=r(0) ip(192.168.1.2)  votes=1 (new) born=4092 seen=4120 proc=00000000000000000000000000000000
> May 05 22:12:51 [3558] vqa11       crmd:    error: crmd_ais_dispatch:   Recieving messages from a node we think is dead: vqa12[33663168]
> May 05 22:12:51 [3558] vqa11       crmd:     info: crm_update_peer_proc:        crmd_ais_dispatch: Node vqa12[33663168] - ais is now online
> May 05 22:12:51 [3558] vqa11       crmd:     info: peer_update_callback:        Client vqa12/peer now has status [offline] (DC=<null>)
> May 05 22:12:51 [3547] vqa11 pacemakerd:   notice: update_node_processes:       0x13685e0 Node 33663168 now known as vqa12, was:
> May 05 22:12:51 [3558] vqa11       crmd:     info: crm_update_peer_proc:        pcmk_mcp_dispatch: Node vqa12[33663168] - unknown is now (null)
> May 05 22:12:51 [3558] vqa11       crmd:     info: peer_update_callback:        Client vqa12/peer now has status [online] (DC=<null>)
> May 05 22:12:51 [3554] vqa11 stonith-ng:     info: crm_get_peer:        Node vqa12 now has id: 33663168
> May 05 22:12:51 [3554] vqa11 stonith-ng:     info: crm_get_peer:        Node 33663168 is now known as vqa12
> May 05 22:12:51 [3554] vqa11 stonith-ng:     info: crm_get_peer:        Node 33663168 has uuid vqa12
> May 05 22:12:51 [3554] vqa11 stonith-ng:     info: crm_update_peer_proc:        pcmk_mcp_dispatch: Node vqa12[33663168] - unknown is now (null)
> May 05 22:12:51 [3558] vqa11       crmd:     info: update_dc:   Set DC to vqa12 (3.0.7)
> May 05 22:12:51 [3553] vqa11        cib:     info: crm_update_peer_proc:        pcmk_mcp_dispatch: Node vqa12[33663168] - unknown is now (null)
> May 05 22:12:51 [3558] vqa11       crmd:     info: erase_status_tag:    Deleting xpath: //node_state[@uname='vqa11']/transient_attributes
> May 05 22:12:51 [3558] vqa11       crmd:     info: update_attrd:        Connecting to attrd... 5 retries remaining
> May 05 22:12:51 [3558] vqa11       crmd:   notice: do_state_transition:         State transition S_PENDING -> S_NOT_DC [ input=I_NOT_DC cause=C_HA_MESSAGE origin=do_cl_join_finalize_respond ]
> May 05 22:12:51 [3556] vqa11      attrd:   notice: attrd_local_callback:        Sending full refresh (origin=crmd)
> May 05 22:12:51 [3553] vqa11        cib:     info: cib_process_replace:         Digest matched on replace from vqa12: 8e663b021f35b1f922b50f7404cb7032
> May 05 22:12:51 [3553] vqa11        cib:     info: cib_process_replace:         Replaced 0.7126.47 with 0.7126.47 from vqa12
> May 05 22:12:53 [3555] vqa11       lrmd:     info: process_lrmd_get_rsc_info:   Resource 'vgc_virtual_ip' not found (0 active resources)
> May 05 22:12:53 [3555] vqa11       lrmd:     info: process_lrmd_rsc_register:   Added 'vgc_virtual_ip' to the rsc list (1 active resources)
> May 05 22:12:53 [3555] vqa11       lrmd:     info: process_lrmd_get_rsc_info:   Resource 'vha-94b33532-15ba-4923-a920-ab9268ccd856' not found (1 active resources)
> May 05 22:12:53 [3555] vqa11       lrmd:     info: process_lrmd_get_rsc_info:   Resource 'vha-94b33532-15ba-4923-a920-ab9268ccd856:1' not found (1 active resources)
> May 05 22:12:53 [3555] vqa11       lrmd:     info: process_lrmd_rsc_register:   Added 'vha-94b33532-15ba-4923-a920-ab9268ccd856' to the rsc list (2 active resources)
> May 05 22:12:53 [3555] vqa11       lrmd:   notice: operation_finished:  vgc_virtual_ip_monitor_0:3616 [ Converted dotted-quad netmask to CIDR as: 22 ]
> May 05 22:12:54 [3558] vqa11       crmd:     info: services_os_action_execute:  Managed vgc-cm-agent.ocf_meta-data_0 process 3633 exited with rc=0
> May 05 22:12:54 [3558] vqa11       crmd:   notice: process_lrm_event:   LRM operation vha-94b33532-15ba-4923-a920-ab9268ccd856_monitor_0 (call=10, rc=7, cib-update=8, confirmed=true) not running
> May 05 22:12:55 [3558] vqa11       crmd:     info: services_os_action_execute:  Managed IPaddr2_meta-data_0 process 3659 exited with rc=0
> May 05 22:12:55 [3558] vqa11       crmd:   notice: process_lrm_event:   LRM operation vgc_virtual_ip_monitor_0 (call=5, rc=7, cib-update=9, confirmed=true) not running
> May 05 22:12:55 [3556] vqa11      attrd:   notice: attrd_trigger_update:        Sending flush op to all hosts for: probe_complete (true)
> May 05 22:12:55 [3556] vqa11      attrd:   notice: attrd_perform_update:        Sent update 8: probe_complete=true
> May 05 22:12:55 [3558] vqa11       crmd:   notice: process_lrm_event:   LRM operation vha-94b33532-15ba-4923-a920-ab9268ccd856_start_0 (call=14, rc=0, cib-update=10, confirmed=true) ok
> May 05 22:12:56 [3558] vqa11       crmd:   notice: process_lrm_event:   LRM operation vha-94b33532-15ba-4923-a920-ab9268ccd856_monitor_31000 (call=17, rc=0, cib-update=11, confirmed=false) ok
> May 05 22:14:50 [3553] vqa11        cib:     info: crm_update_peer_proc:        pcmk_mcp_dispatch: Node vqa12[33663168] - unknown is now (null)
> May 05 22:14:50 [3558] vqa11       crmd:     info: crm_update_peer_proc:        pcmk_mcp_dispatch: Node vqa12[33663168] - unknown is now (null)
> May 05 22:14:50 [3558] vqa11       crmd:     info: peer_update_callback:        Client vqa12/peer now has status [offline] (DC=vqa12)
> May 05 22:14:50 [3554] vqa11 stonith-ng:     info: crm_update_peer_proc:        pcmk_mcp_dispatch: Node vqa12[33663168] - unknown is now (null)
> May 05 22:14:50 [3558] vqa11       crmd:   notice: peer_update_callback:        Got client status callback - our DC is dead
> May 05 22:14:50 [3558] vqa11       crmd:   notice: do_state_transition:         State transition S_NOT_DC -> S_ELECTION [ input=I_ELECTION cause=C_CRMD_STATUS_CALLBACK origin=peer_update_callback ]
> May 05 22:14:50 [3558] vqa11       crmd:   notice: do_state_transition:         State transition S_ELECTION -> S_INTEGRATION [ input=I_ELECTION_DC cause=C_FSA_INTERNAL origin=do_election_check ]
> May 05 22:14:50 [3558] vqa11       crmd:     info: do_te_control:       Registering TE UUID: 8e6b7382-90eb-4ac5-80bf-d2feac7e0d7e
> May 05 22:14:50 [3554] vqa11 stonith-ng:     info: crm_update_peer_proc:        pcmk_mcp_dispatch: Node vqa12[33663168] - unknown is now (null)
> May 05 22:14:50 [3553] vqa11        cib:     info: crm_update_peer_proc:        pcmk_mcp_dispatch: Node vqa12[33663168] - unknown is now (null)
> May 05 22:14:50 [3558] vqa11       crmd:     info: set_graph_functions:         Setting custom graph functions
> May 05 22:14:50 [3558] vqa11       crmd:     info: do_dc_takeover:      Taking over DC status for this partition
> May 05 22:14:50 [3553] vqa11        cib:     info: cib_process_readwrite:       We are now in R/W mode
> May 05 22:14:50 [3553] vqa11        cib:     info: cib_process_request:         Operation complete: op cib_master for section 'all' (origin=local/crmd/12, version=0.7126.67): OK (rc=0)
> May 05 22:14:50 [3553] vqa11        cib:     info: cib_process_request:         Operation complete: op cib_modify for section cib (origin=local/crmd/13, version=0.7126.68): OK (rc=0)
> May 05 22:14:50 [3553] vqa11        cib:     info: cib_process_request:         Operation complete: op cib_modify for section crm_config (origin=local/crmd/15, version=0.7126.69): OK (rc=0)
> May 05 22:14:50 [3558] vqa11       crmd:     info: join_make_offer:     Making join offers based on membership 4120
> May 05 22:14:50 [3558] vqa11       crmd:     info: do_dc_join_offer_all:        join-1: Waiting on 1 outstanding join acks
> May 05 22:14:50 [3553] vqa11        cib:     info: cib_process_request:         Operation complete: op cib_modify for section crm_config (origin=local/crmd/17, version=0.7126.70): OK (rc=0)
> May 05 22:14:50 [3558] vqa11       crmd:     info: crm_update_peer_expected:    do_dc_join_filter_offer: Node vqa11[16885952] - expected state is now member
> May 05 22:14:51 [3558] vqa11       crmd:     info: do_dc_join_offer_all:        A new node joined the cluster
> May 05 22:14:51 [3558] vqa11       crmd:     info: do_dc_join_offer_all:        join-3: Waiting on 2 outstanding join acks
> May 05 22:14:51 [3558] vqa11       crmd:     info: update_dc:   Set DC to vqa11 (3.0.7)
> May 05 22:14:52 [3558] vqa11       crmd:     info: crm_update_peer_expected:    do_dc_join_filter_offer: Node vqa12[33663168] - expected state is now member
> May 05 22:14:52 [3558] vqa11       crmd:     info: do_state_transition:         State transition S_INTEGRATION -> S_FINALIZE_JOIN [ input=I_INTEGRATED cause=C_FSA_INTERNAL origin=check_join_state ]
> May 05 22:14:52 [3558] vqa11       crmd:     info: do_dc_join_finalize:         join-3: Syncing the CIB from vqa11 to the rest of the cluster
> May 05 22:14:52 [3553] vqa11        cib:     info: cib_process_request:         Operation complete: op cib_sync for section 'all' (origin=local/crmd/27, version=0.7126.73): OK (rc=0)
> May 05 22:14:52 [3553] vqa11        cib:     info: cib_process_request:         Operation complete: op cib_modify for section nodes (origin=local/crmd/28, version=0.7126.74): OK (rc=0)
> May 05 22:14:52 [3553] vqa11        cib:     info: cib_process_request:         Operation complete: op cib_modify for section nodes (origin=local/crmd/29, version=0.7126.75): OK (rc=0)
> May 05 22:14:52 [3553] vqa11        cib:     info: cib_process_request:         Operation complete: op cib_delete for section //node_state[@uname='vqa12']/transient_attributes (origin=vqa12/crmd/8, version=0.7126.76): OK (rc=0)
> May 05 22:14:52 [3553] vqa11        cib:  warning: cib_process_request:         Operation complete: op cib_modify for section status (origin=vqa12/attrd/61, version=0.7126.76): No such device or address (rc=-6)
> May 05 22:14:53 [3558] vqa11       crmd:     info: services_os_action_execute:  Managed vgc-cm-agent.ocf_meta-data_0 process 4015 exited with rc=0
> May 05 22:14:53 [3558] vqa11       crmd:     info: do_dc_join_ack:      join-3: Updating node state to member for vqa12
> May 05 22:14:53 [3558] vqa11       crmd:     info: erase_status_tag:    Deleting xpath: //node_state[@uname='vqa12']/lrm
> May 05 22:14:53 [3558] vqa11       crmd:     info: do_dc_join_ack:      join-3: Updating node state to member for vqa11
> May 05 22:14:53 [3558] vqa11       crmd:     info: erase_status_tag:    Deleting xpath: //node_state[@uname='vqa11']/lrm
> May 05 22:14:53 [3553] vqa11        cib:     info: cib_process_request:         Operation complete: op cib_delete for section //node_state[@uname='vqa12']/lrm (origin=local/crmd/30, version=0.7126.79): OK (rc=0)
> May 05 22:14:53 [3553] vqa11        cib:     info: cib_process_request:         Operation complete: op cib_delete for section //node_state[@uname='vqa11']/lrm (origin=local/crmd/32, version=0.7126.81): OK (rc=0)
> May 05 22:14:53 [3558] vqa11       crmd:     info: do_state_transition:         State transition S_FINALIZE_JOIN -> S_POLICY_ENGINE [ input=I_FINALIZED cause=C_FSA_INTERNAL origin=check_join_state ]
> May 05 22:14:53 [3556] vqa11      attrd:   notice: attrd_local_callback:        Sending full refresh (origin=crmd)
> May 05 22:14:53 [3556] vqa11      attrd:   notice: attrd_trigger_update:        Sending flush op to all hosts for: probe_complete (true)
> May 05 22:14:53 [3558] vqa11       crmd:     info: abort_transition_graph:      do_te_invoke:156 - Triggered transition abort (complete=1) : Peer Cancelled
> May 05 22:14:53 [3553] vqa11        cib:     info: cib_process_request:         Operation complete: op cib_modify for section nodes (origin=local/crmd/34, version=0.7126.83): OK (rc=0)
> May 05 22:14:53 [3553] vqa11        cib:     info: cib_process_request:         Operation complete: op cib_modify for section cib (origin=local/crmd/36, version=0.7126.85): OK (rc=0)
> 
> 
> 
> [root at vqa12 bug17873]# rpm -qa | grep pacemaker
> pacemaker-cluster-libs-1.1.8-7.el6.x86_64
> pacemaker-cli-1.1.8-7.el6.x86_64
> pacemaker-1.1.8-7.el6.x86_64
> pacemaker-libs-1.1.8-7.el6.x86_64
> [root at vqa12 bug17873]# rpm -qa | grep corosync
> corosync-1.4.1-15.el6.x86_64
> corosynclib-1.4.1-15.el6.x86_64
> [root at vqa12 bug17873]# cat /etc/redhat-release
> Red Hat Enterprise Linux Server release 6.2 (Santiago)
> 
> 
> Regards,
>  Kiran
> 
> _______________________________________________
> Pacemaker mailing list: Pacemaker at oss.clusterlabs.org
> http://oss.clusterlabs.org/mailman/listinfo/pacemaker
> 
> Project Home: http://www.clusterlabs.org
> Getting started: http://www.clusterlabs.org/doc/Cluster_from_Scratch.pdf
> Bugs: http://bugs.clusterlabs.org





More information about the Pacemaker mailing list