Feb 21 18:21:49 bl460g1n6 corosync[9078]: [MAIN ] Corosync Cluster Engine ('2.3.3'): started and ready to provide service.
Feb 21 18:21:49 bl460g1n6 corosync[9078]: [MAIN ] Corosync built-in features: watchdog upstart pie relro bindnow
Feb 21 18:21:49 bl460g1n6 corosync[9083]: [TOTEM ] Initializing transport (UDP/IP Multicast).
Feb 21 18:21:49 bl460g1n6 corosync[9083]: [TOTEM ] Initializing transmit/receive security (NSS) crypto: aes256 hash: sha1
Feb 21 18:21:49 bl460g1n6 corosync[9083]: [TOTEM ] Initializing transport (UDP/IP Multicast).
Feb 21 18:21:49 bl460g1n6 corosync[9083]: [TOTEM ] Initializing transmit/receive security (NSS) crypto: aes256 hash: sha1
Feb 21 18:21:49 bl460g1n6 corosync[9083]: [TOTEM ] The network interface [192.168.101.216] is now up.
Feb 21 18:21:49 bl460g1n6 corosync[9083]: [SERV ] Service engine loaded: corosync configuration map access [0]
Feb 21 18:21:49 bl460g1n6 corosync[9083]: [QB ] server name: cmap
Feb 21 18:21:49 bl460g1n6 corosync[9083]: [SERV ] Service engine loaded: corosync configuration service [1]
Feb 21 18:21:49 bl460g1n6 corosync[9083]: [QB ] server name: cfg
Feb 21 18:21:49 bl460g1n6 corosync[9083]: [SERV ] Service engine loaded: corosync cluster closed process group service v1.01 [2]
Feb 21 18:21:49 bl460g1n6 corosync[9083]: [QB ] server name: cpg
Feb 21 18:21:49 bl460g1n6 corosync[9083]: [SERV ] Service engine loaded: corosync profile loading service [4]
Feb 21 18:21:49 bl460g1n6 corosync[9083]: [WD ] Watchdog is now been tickled by corosync.
Feb 21 18:21:49 bl460g1n6 corosync[9083]: [WD ] no resources configured.
Feb 21 18:21:49 bl460g1n6 corosync[9083]: [SERV ] Service engine loaded: corosync watchdog service [7]
Feb 21 18:21:49 bl460g1n6 corosync[9083]: [QUORUM] Using quorum provider corosync_votequorum
Feb 21 18:21:49 bl460g1n6 corosync[9083]: [SERV ] Service engine loaded: corosync vote quorum service v1.0 [5]
Feb 21 18:21:49 bl460g1n6 corosync[9083]: [QB ] server name: votequorum
Feb 21 18:21:49 bl460g1n6 corosync[9083]: [SERV ] Service engine loaded: corosync cluster quorum service v0.1 [3]
Feb 21 18:21:49 bl460g1n6 corosync[9083]: [QB ] server name: quorum
Feb 21 18:21:49 bl460g1n6 corosync[9083]: [TOTEM ] The network interface [192.168.102.216] is now up.
Feb 21 18:21:50 bl460g1n6 corosync[9083]: [TOTEM ] A new membership (192.168.101.216:16) was formed. Members joined: -1062705704
Feb 21 18:21:50 bl460g1n6 corosync[9083]: [QUORUM] Members[1]: -1062705704
Feb 21 18:21:50 bl460g1n6 corosync[9083]: [MAIN ] Completed service synchronization, ready to provide service.
Feb 21 18:21:52 bl460g1n6 pacemakerd[9094]: info: crm_log_init: Changed active directory to /var/lib/heartbeat/cores/root
Feb 21 18:21:52 bl460g1n6 pacemakerd[9094]: info: crm_ipc_connect: Could not establish pacemakerd connection: Connection refused (111)
Feb 21 18:21:52 bl460g1n6 pacemakerd[9094]: info: get_cluster_type: Detected an active 'corosync' cluster
Feb 21 18:21:52 bl460g1n6 pacemakerd[9094]: info: mcp_read_config: Reading configure for stack: corosync
Feb 21 18:21:52 bl460g1n6 pacemakerd[9094]: notice: mcp_read_config: Configured corosync to accept connections from group 189: OK (1)
Feb 21 18:21:52 bl460g1n6 pacemakerd[9094]: notice: main: Starting Pacemaker 1.1.10 (Build: 0b7d85a): ncurses libqb-logging libqb-ipc lha-fencing upstart nagios corosync-native snmp
Feb 21 18:21:52 bl460g1n6 pacemakerd[9094]: info: main: Maximum core file size is: 18446744073709551615
Feb 21 18:21:52 bl460g1n6 pacemakerd[9094]: info: qb_ipcs_us_publish: server name: pacemakerd
Feb 21 18:21:52 bl460g1n6 pacemakerd[9094]: info: crm_get_peer: Created entry 2a5408e7-bbbb-4b4c-b83d-00749ddb8dcb/0x10ae760 for node (null)/3232261592 (1 total)
Feb 21 18:21:52 bl460g1n6 pacemakerd[9094]: notice: corosync_node_name: Unable to get node name for nodeid 3232261592
Feb 21 18:21:52 bl460g1n6 pacemakerd[9094]: notice: get_node_name: Could not obtain a node name for corosync nodeid 3232261592
Feb 21 18:21:52 bl460g1n6 pacemakerd[9094]: info: crm_get_peer: Node 3232261592 has uuid 3232261592
Feb 21 18:21:52 bl460g1n6 pacemakerd[9094]: info: crm_update_peer_proc: cluster_connect_cpg: Node (null)[3232261592] - corosync-cpg is now online
Feb 21 18:21:52 bl460g1n6 pacemakerd[9094]: notice: cluster_connect_quorum: Quorum lost
Feb 21 18:21:52 bl460g1n6 pacemakerd[9094]: notice: corosync_node_name: Unable to get node name for nodeid 3232261592
Feb 21 18:21:52 bl460g1n6 pacemakerd[9094]: notice: get_node_name: Defaulting to uname -n for the local corosync node name
Feb 21 18:21:52 bl460g1n6 pacemakerd[9094]: info: crm_get_peer: Node 3232261592 is now known as bl460g1n6
Feb 21 18:21:52 bl460g1n6 pacemakerd[9094]: info: start_child: Using uid=189 and group=189 for process cib
Feb 21 18:21:52 bl460g1n6 pacemakerd[9094]: info: start_child: Forked child 9098 for process cib
Feb 21 18:21:52 bl460g1n6 pacemakerd[9094]: info: start_child: Forked child 9099 for process stonith-ng
Feb 21 18:21:52 bl460g1n6 pacemakerd[9094]: info: start_child: Forked child 9100 for process lrmd
Feb 21 18:21:52 bl460g1n6 pacemakerd[9094]: info: start_child: Using uid=189 and group=189 for process attrd
Feb 21 18:21:52 bl460g1n6 pacemakerd[9094]: info: start_child: Forked child 9101 for process attrd
Feb 21 18:21:52 bl460g1n6 pacemakerd[9094]: info: start_child: Using uid=189 and group=189 for process pengine
Feb 21 18:21:52 bl460g1n6 pacemakerd[9094]: info: start_child: Forked child 9102 for process pengine
Feb 21 18:21:52 bl460g1n6 pacemakerd[9094]: info: start_child: Using uid=189 and group=189 for process crmd
Feb 21 18:21:52 bl460g1n6 pacemakerd[9094]: info: start_child: Forked child 9103 for process crmd
Feb 21 18:21:52 bl460g1n6 pacemakerd[9094]: info: main: Starting mainloop
Feb 21 18:21:52 bl460g1n6 pacemakerd[9094]: info: pcmk_quorum_notification: Membership 16: quorum still lost (1)
Feb 21 18:21:52 bl460g1n6 pacemakerd[9094]: notice: crm_update_peer_state: pcmk_quorum_notification: Node bl460g1n6[3232261592] - state is now member (was (null))
Feb 21 18:21:52 bl460g1n6 cib[9098]: info: crm_log_init: Changed active directory to /var/lib/heartbeat/cores/hacluster
Feb 21 18:21:52 bl460g1n6 cib[9098]: info: get_cluster_type: Verifying cluster type: 'corosync'
Feb 21 18:21:52 bl460g1n6 cib[9098]: info: get_cluster_type: Assuming an active 'corosync' cluster
Feb 21 18:21:52 bl460g1n6 lrmd[9100]: info: crm_log_init: Changed active directory to /var/lib/heartbeat/cores/root
Feb 21 18:21:52 bl460g1n6 cib[9098]: info: retrieveCib: Reading cluster configuration from: /var/lib/pacemaker/cib/cib.xml (digest: /var/lib/pacemaker/cib/cib.xml.sig)
Feb 21 18:21:52 bl460g1n6 lrmd[9100]: info: qb_ipcs_us_publish: server name: lrmd
Feb 21 18:21:52 bl460g1n6 lrmd[9100]: info: main: Starting
Feb 21 18:21:52 bl460g1n6 cib[9098]: info: validate_with_relaxng: Creating RNG parser context
Feb 21 18:21:52 bl460g1n6 attrd[9101]: info: crm_log_init: Changed active directory to /var/lib/heartbeat/cores/hacluster
Feb 21 18:21:52 bl460g1n6 stonith-ng[9099]: info: crm_log_init: Changed active directory to /var/lib/heartbeat/cores/root
Feb 21 18:21:52 bl460g1n6 stonith-ng[9099]: info: get_cluster_type: Verifying cluster type: 'corosync'
Feb 21 18:21:52 bl460g1n6 attrd[9101]: info: main: Starting up
Feb 21 18:21:52 bl460g1n6 attrd[9101]: info: get_cluster_type: Verifying cluster type: 'corosync'
Feb 21 18:21:52 bl460g1n6 stonith-ng[9099]: info: get_cluster_type: Assuming an active 'corosync' cluster
Feb 21 18:21:52 bl460g1n6 stonith-ng[9099]: notice: crm_cluster_connect: Connecting to cluster infrastructure: corosync
Feb 21 18:21:52 bl460g1n6 attrd[9101]: info: get_cluster_type: Assuming an active 'corosync' cluster
Feb 21 18:21:52 bl460g1n6 attrd[9101]: notice: crm_cluster_connect: Connecting to cluster infrastructure: corosync
Feb 21 18:21:52 bl460g1n6 pengine[9102]: info: crm_log_init: Changed active directory to /var/lib/heartbeat/cores/hacluster
Feb 21 18:21:52 bl460g1n6 pengine[9102]: info: qb_ipcs_us_publish: server name: pengine
Feb 21 18:21:52 bl460g1n6 pengine[9102]: info: main: Starting pengine
Feb 21 18:21:52 bl460g1n6 crmd[9103]: info: crm_log_init: Changed active directory to /var/lib/heartbeat/cores/hacluster
Feb 21 18:21:52 bl460g1n6 crmd[9103]: notice: main: CRM Git Version: 0b7d85a
Feb 21 18:21:52 bl460g1n6 crmd[9103]: info: do_log: FSA: Input I_STARTUP from crmd_init() received in state S_STARTING
Feb 21 18:21:52 bl460g1n6 crmd[9103]: info: get_cluster_type: Verifying cluster type: 'corosync'
Feb 21 18:21:52 bl460g1n6 crmd[9103]: info: get_cluster_type: Assuming an active 'corosync' cluster
Feb 21 18:21:52 bl460g1n6 crmd[9103]: info: crm_ipc_connect: Could not establish cib_shm connection: Connection refused (111)
Feb 21 18:21:52 bl460g1n6 cib[9098]: info: startCib: CIB Initialization completed successfully
Feb 21 18:21:52 bl460g1n6 cib[9098]: notice: crm_cluster_connect: Connecting to cluster infrastructure: corosync
Feb 21 18:21:52 bl460g1n6 stonith-ng[9099]: info: crm_get_peer: Created entry 73cd6f62-266f-4dfd-9ecc-7ea7969990be/0xef7090 for node (null)/3232261592 (1 total)
Feb 21 18:21:52 bl460g1n6 attrd[9101]: info: crm_get_peer: Created entry 90c0745d-31a4-4727-bdce-7a1e28f8a4bc/0x10c9e50 for node (null)/3232261592 (1 total)
Feb 21 18:21:52 bl460g1n6 cib[9098]: info: crm_get_peer: Created entry 7e556512-fc9a-4293-b202-37dde9d27d11/0xf4cb00 for node (null)/3232261592 (1 total)
Feb 21 18:21:52 bl460g1n6 stonith-ng[9099]: notice: corosync_node_name: Unable to get node name for nodeid 3232261592
Feb 21 18:21:52 bl460g1n6 stonith-ng[9099]: notice: get_node_name: Could not obtain a node name for corosync nodeid 3232261592
Feb 21 18:21:52 bl460g1n6 stonith-ng[9099]: info: crm_get_peer: Node 3232261592 has uuid 3232261592
Feb 21 18:21:52 bl460g1n6 stonith-ng[9099]: info: crm_update_peer_proc: cluster_connect_cpg: Node (null)[3232261592] - corosync-cpg is now online
Feb 21 18:21:52 bl460g1n6 stonith-ng[9099]: info: init_cs_connection_once: Connection to 'corosync': established
Feb 21 18:21:52 bl460g1n6 attrd[9101]: notice: corosync_node_name: Unable to get node name for nodeid 3232261592
Feb 21 18:21:52 bl460g1n6 attrd[9101]: notice: get_node_name: Could not obtain a node name for corosync nodeid 3232261592
Feb 21 18:21:52 bl460g1n6 attrd[9101]: info: crm_get_peer: Node 3232261592 has uuid 3232261592
Feb 21 18:21:52 bl460g1n6 attrd[9101]: info: crm_update_peer_proc: cluster_connect_cpg: Node (null)[3232261592] - corosync-cpg is now online
Feb 21 18:21:52 bl460g1n6 attrd[9101]: notice: crm_update_peer_state: attrd_peer_change_cb: Node (null)[3232261592] - state is now member (was (null))
Feb 21 18:21:52 bl460g1n6 attrd[9101]: info: init_cs_connection_once: Connection to 'corosync': established
Feb 21 18:21:52 bl460g1n6 cib[9098]: notice: corosync_node_name: Unable to get node name for nodeid 3232261592
Feb 21 18:21:52 bl460g1n6 cib[9098]: notice: get_node_name: Could not obtain a node name for corosync nodeid 3232261592
Feb 21 18:21:52 bl460g1n6 cib[9098]: info: crm_get_peer: Node 3232261592 has uuid 3232261592
Feb 21 18:21:52 bl460g1n6 stonith-ng[9099]: notice: corosync_node_name: Unable to get node name for nodeid 3232261592
Feb 21 18:21:52 bl460g1n6 stonith-ng[9099]: notice: get_node_name: Defaulting to uname -n for the local corosync node name
Feb 21 18:21:52 bl460g1n6 stonith-ng[9099]: info: crm_get_peer: Node 3232261592 is now known as bl460g1n6
Feb 21 18:21:52 bl460g1n6 cib[9098]: info: crm_update_peer_proc: cluster_connect_cpg: Node (null)[3232261592] - corosync-cpg is now online
Feb 21 18:21:52 bl460g1n6 cib[9098]: info: init_cs_connection_once: Connection to 'corosync': established
Feb 21 18:21:52 bl460g1n6 stonith-ng[9099]: info: crm_ipc_connect: Could not establish cib_rw connection: Connection refused (111)
Feb 21 18:21:52 bl460g1n6 attrd[9101]: notice: corosync_node_name: Unable to get node name for nodeid 3232261592
Feb 21 18:21:52 bl460g1n6 attrd[9101]: notice: get_node_name: Defaulting to uname -n for the local corosync node name
Feb 21 18:21:52 bl460g1n6 attrd[9101]: info: crm_get_peer: Node 3232261592 is now known as bl460g1n6
Feb 21 18:21:52 bl460g1n6 attrd[9101]: info: main: Cluster connection active
Feb 21 18:21:52 bl460g1n6 cib[9098]: notice: corosync_node_name: Unable to get node name for nodeid 3232261592
Feb 21 18:21:52 bl460g1n6 attrd[9101]: info: qb_ipcs_us_publish: server name: attrd
Feb 21 18:21:52 bl460g1n6 attrd[9101]: info: main: Accepting attribute updates
Feb 21 18:21:52 bl460g1n6 cib[9098]: notice: get_node_name: Defaulting to uname -n for the local corosync node name
Feb 21 18:21:52 bl460g1n6 cib[9098]: info: crm_get_peer: Node 3232261592 is now known as bl460g1n6
Feb 21 18:21:52 bl460g1n6 attrd[9101]: info: crm_ipc_connect: Could not establish cib_rw connection: Connection refused (111)
Feb 21 18:21:52 bl460g1n6 cib[9098]: info: qb_ipcs_us_publish: server name: cib_ro
Feb 21 18:21:52 bl460g1n6 cib[9098]: info: qb_ipcs_us_publish: server name: cib_rw
Feb 21 18:21:52 bl460g1n6 cib[9098]: info: qb_ipcs_us_publish: server name: cib_shm
Feb 21 18:21:52 bl460g1n6 cib[9098]: info: cib_init: Starting cib mainloop
Feb 21 18:21:52 bl460g1n6 cib[9098]: info: pcmk_cpg_membership: Joined[0.0] cib.3232261592
Feb 21 18:21:52 bl460g1n6 cib[9098]: info: pcmk_cpg_membership: Member[0.0] cib.3232261592
Feb 21 18:21:52 bl460g1n6 cib[9104]: info: write_cib_contents: Archived previous version as /var/lib/pacemaker/cib/cib-7.raw
Feb 21 18:21:52 bl460g1n6 cib[9104]: info: write_cib_contents: Wrote version 0.3.0 of the CIB to disk (digest: e93adb14da8ab6f393fc1d7367714a48)
Feb 21 18:21:52 bl460g1n6 cib[9104]: info: retrieveCib: Reading cluster configuration from: /var/lib/pacemaker/cib/cib.JX7Ua5 (digest: /var/lib/pacemaker/cib/cib.VQBPSr)
Feb 21 18:21:53 bl460g1n6 cib[9098]: info: crm_client_new: Connecting 0xf4fc10 for uid=189 gid=189 pid=9103 id=fdffbb79-89d3-4a50-b2d1-f0fafbcf92b4
Feb 21 18:21:53 bl460g1n6 crmd[9103]: info: do_cib_control: CIB connection established
Feb 21 18:21:53 bl460g1n6 crmd[9103]: notice: crm_cluster_connect: Connecting to cluster infrastructure: corosync
Feb 21 18:21:53 bl460g1n6 cib[9098]: info: cib_process_request: Completed cib_query operation for section 'all': OK (rc=0, origin=local/crmd/2, version=0.3.0)
Feb 21 18:21:53 bl460g1n6 crmd[9103]: info: crm_get_peer: Created entry ad98ac4c-0a5e-48be-832d-57353f997608/0x2105a50 for node (null)/3232261592 (1 total)
Feb 21 18:21:53 bl460g1n6 crmd[9103]: notice: corosync_node_name: Unable to get node name for nodeid 3232261592
Feb 21 18:21:53 bl460g1n6 crmd[9103]: notice: get_node_name: Could not obtain a node name for corosync nodeid 3232261592
Feb 21 18:21:53 bl460g1n6 crmd[9103]: info: crm_get_peer: Node 3232261592 has uuid 3232261592
Feb 21 18:21:53 bl460g1n6 crmd[9103]: info: crm_update_peer_proc: cluster_connect_cpg: Node (null)[3232261592] - corosync-cpg is now online
Feb 21 18:21:53 bl460g1n6 crmd[9103]: info: init_cs_connection_once: Connection to 'corosync': established
Feb 21 18:21:53 bl460g1n6 crmd[9103]: notice: corosync_node_name: Unable to get node name for nodeid 3232261592
Feb 21 18:21:53 bl460g1n6 crmd[9103]: notice: get_node_name: Defaulting to uname -n for the local corosync node name
Feb 21 18:21:53 bl460g1n6 crmd[9103]: info: crm_get_peer: Node 3232261592 is now known as bl460g1n6
Feb 21 18:21:53 bl460g1n6 crmd[9103]: info: peer_update_callback: bl460g1n6 is now (null)
Feb 21 18:21:53 bl460g1n6 crmd[9103]: notice: cluster_connect_quorum: Quorum lost
Feb 21 18:21:53 bl460g1n6 cib[9098]: info: crm_client_new: Connecting 0xfd2780 for uid=0 gid=0 pid=9099 id=3ec08e3a-683d-4a1d-8288-c736e2c073b6
Feb 21 18:21:53 bl460g1n6 stonith-ng[9099]: notice: setup_cib: Watching for stonith topology changes
Feb 21 18:21:53 bl460g1n6 stonith-ng[9099]: info: qb_ipcs_us_publish: server name: stonith-ng
Feb 21 18:21:53 bl460g1n6 stonith-ng[9099]: info: main: Starting stonith-ng mainloop
Feb 21 18:21:53 bl460g1n6 cib[9098]: info: cib_process_request: Completed cib_query operation for section 'all': OK (rc=0, origin=local/crmd/2, version=0.3.0)
Feb 21 18:21:53 bl460g1n6 stonith-ng[9099]: info: pcmk_cpg_membership: Joined[0.0] stonith-ng.3232261592
Feb 21 18:21:53 bl460g1n6 stonith-ng[9099]: info: pcmk_cpg_membership: Member[0.0] stonith-ng.3232261592
Feb 21 18:21:53 bl460g1n6 stonith-ng[9099]: info: init_cib_cache_cb: Updating device list from the cib: init
Feb 21 18:21:53 bl460g1n6 stonith-ng[9099]: notice: unpack_config: On loss of CCM Quorum: Ignore
Feb 21 18:21:53 bl460g1n6 stonith-ng[9099]: warning: handle_startup_fencing: Blind faith: not fencing unseen nodes
Feb 21 18:21:53 bl460g1n6 crmd[9103]: info: do_ha_control: Connected to the cluster
Feb 21 18:21:53 bl460g1n6 cib[9098]: info: cib_process_request: Forwarding cib_modify operation for section nodes to master (origin=local/crmd/3)
Feb 21 18:21:53 bl460g1n6 crmd[9103]: info: lrmd_ipc_connect: Connecting to lrmd
Feb 21 18:21:53 bl460g1n6 lrmd[9100]: info: crm_client_new: Connecting 0x1867760 for uid=189 gid=189 pid=9103 id=bb684bbd-ee24-4901-84a5-b3ce42311c9f
Feb 21 18:21:53 bl460g1n6 crmd[9103]: info: do_lrm_control: LRM connection established
Feb 21 18:21:53 bl460g1n6 crmd[9103]: info: do_started: Delaying start, no membership data (0000000000100000)
Feb 21 18:21:53 bl460g1n6 crmd[9103]: info: pcmk_quorum_notification: Membership 16: quorum still lost (1)
Feb 21 18:21:53 bl460g1n6 crmd[9103]: notice: crm_update_peer_state: pcmk_quorum_notification: Node bl460g1n6[3232261592] - state is now member (was (null))
Feb 21 18:21:53 bl460g1n6 crmd[9103]: info: peer_update_callback: bl460g1n6 is now member (was (null))
Feb 21 18:21:53 bl460g1n6 crmd[9103]: notice: corosync_node_name: Unable to get node name for nodeid 3232261592
Feb 21 18:21:53 bl460g1n6 crmd[9103]: notice: get_node_name: Defaulting to uname -n for the local corosync node name
Feb 21 18:21:53 bl460g1n6 cib[9098]: notice: corosync_node_name: Unable to get node name for nodeid 3232261592
Feb 21 18:21:53 bl460g1n6 cib[9098]: notice: get_node_name: Defaulting to uname -n for the local corosync node name
Feb 21 18:21:53 bl460g1n6 crmd[9103]: info: do_started: Delaying start, Config not read (0000000000000040)
Feb 21 18:21:53 bl460g1n6 crmd[9103]: info: pcmk_cpg_membership: Joined[0.0] crmd.3232261592
Feb 21 18:21:53 bl460g1n6 crmd[9103]: info: pcmk_cpg_membership: Member[0.0] crmd.3232261592
Feb 21 18:21:53 bl460g1n6 cib[9098]: info: crm_client_new: Connecting 0xfd0b30 for uid=189 gid=189 pid=9101 id=7ffd74bf-124e-4533-896c-adc135794ceb
Feb 21 18:21:53 bl460g1n6 crmd[9103]: info: do_started: Delaying start, Config not read (0000000000000040)
Feb 21 18:21:53 bl460g1n6 cib[9098]: info: cib_process_request: Completed cib_query operation for section crm_config: OK (rc=0, origin=local/crmd/4, version=0.3.0)
Feb 21 18:21:53 bl460g1n6 crmd[9103]: info: qb_ipcs_us_publish: server name: crmd
Feb 21 18:21:53 bl460g1n6 crmd[9103]: notice: do_started: The local CRM is operational
Feb 21 18:21:53 bl460g1n6 crmd[9103]: info: do_log: FSA: Input I_PENDING from do_started() received in state S_STARTING
Feb 21 18:21:53 bl460g1n6 crmd[9103]: notice: do_state_transition: State transition S_STARTING -> S_PENDING [ input=I_PENDING cause=C_FSA_INTERNAL origin=do_started ]
Feb 21 18:21:53 bl460g1n6 cib[9098]: info: cib_process_request: Completed cib_modify operation for section nodes: OK (rc=0, origin=bl460g1n6/crmd/3, version=0.3.0)
Feb 21 18:21:53 bl460g1n6 cib[9098]: info: cib_process_request: Completed cib_slave operation for section 'all': OK (rc=0, origin=local/crmd/5, version=0.3.0)
Feb 21 18:21:53 bl460g1n6 attrd[9101]: info: attrd_cib_connect: Connected to the CIB after 2 attempts
Feb 21 18:21:53 bl460g1n6 attrd[9101]: info: main: CIB connection active
Feb 21 18:21:53 bl460g1n6 attrd[9101]: info: pcmk_cpg_membership: Joined[0.0] attrd.3232261592
Feb 21 18:21:53 bl460g1n6 attrd[9101]: info: pcmk_cpg_membership: Member[0.0] attrd.3232261592
Feb 21 18:21:55 bl460g1n6 stonith-ng[9099]: info: crm_client_new: Connecting 0xefc420 for uid=189 gid=189 pid=9103 id=4dd57f7e-fc76-44f1-9bbb-979368256ffe
Feb 21 18:21:55 bl460g1n6 stonith-ng[9099]: info: stonith_command: Processed register from crmd.9103: OK (0)
Feb 21 18:21:55 bl460g1n6 stonith-ng[9099]: info: stonith_command: Processed st_notify from crmd.9103: OK (0)
Feb 21 18:21:55 bl460g1n6 stonith-ng[9099]: info: stonith_command: Processed st_notify from crmd.9103: OK (0)
Feb 21 18:21:55 bl460g1n6 cib[9098]: info: crm_client_new: Connecting 0xfd1090 for uid=0 gid=0 pid=8614 id=54f61179-a1e1-4a6e-956c-05f99cfb996e
Feb 21 18:21:55 bl460g1n6 cib[9098]: info: cib_process_request: Completed cib_query operation for section 'all': OK (rc=0, origin=local/crm_mon/5, version=0.3.0)
Feb 21 18:22:14 bl460g1n6 crmd[9103]: info: crm_timer_popped: Election Trigger (I_DC_TIMEOUT) just popped (20000ms)
Feb 21 18:22:14 bl460g1n6 crmd[9103]: warning: do_log: FSA: Input I_DC_TIMEOUT from crm_timer_popped() received in state S_PENDING
Feb 21 18:22:14 bl460g1n6 crmd[9103]: info: do_state_transition: State transition S_PENDING -> S_ELECTION [ input=I_DC_TIMEOUT cause=C_TIMER_POPPED origin=crm_timer_popped ]
Feb 21 18:22:14 bl460g1n6 crmd[9103]: info: election_complete: Election election-0 complete
Feb 21 18:22:14 bl460g1n6 crmd[9103]: info: election_timeout_popped: Election failed: Declaring ourselves the winner
Feb 21 18:22:14 bl460g1n6 crmd[9103]: info: do_log: FSA: Input I_ELECTION_DC from election_timeout_popped() received in state S_ELECTION
Feb 21 18:22:14 bl460g1n6 crmd[9103]: notice: do_state_transition: State transition S_ELECTION -> S_INTEGRATION [ input=I_ELECTION_DC cause=C_TIMER_POPPED origin=election_timeout_popped ]
Feb 21 18:22:14 bl460g1n6 crmd[9103]: info: do_te_control: Registering TE UUID: 82c5fc6f-611d-4ba9-8a4f-13155b12536e
Feb 21 18:22:14 bl460g1n6 crmd[9103]: info: set_graph_functions: Setting custom graph functions
Feb 21 18:22:14 bl460g1n6 pengine[9102]: info: crm_client_new: Connecting 0x115a520 for uid=189 gid=189 pid=9103 id=a68f7020-b588-41cc-b50f-52a7b1417e98
Feb 21 18:22:14 bl460g1n6 crmd[9103]: info: do_dc_takeover: Taking over DC status for this partition
Feb 21 18:22:14 bl460g1n6 cib[9098]: info: cib_process_readwrite: We are now in R/W mode
Feb 21 18:22:14 bl460g1n6 cib[9098]: info: cib_process_request: Completed cib_master operation for section 'all': OK (rc=0, origin=local/crmd/6, version=0.3.0)
Feb 21 18:22:14 bl460g1n6 cib[9098]: info: cib_process_request: Forwarding cib_modify operation for section cib to master (origin=local/crmd/7)
Feb 21 18:22:14 bl460g1n6 cib[9098]: info: cib_process_request: Completed cib_query operation for section //cib/configuration/crm_config//cluster_property_set//nvpair[@name='dc-version']: OK (rc=0, origin=local/crmd/8, version=0.3.0)
Feb 21 18:22:14 bl460g1n6 cib[9098]: info: cib_process_request: Completed cib_modify operation for section cib: OK (rc=0, origin=bl460g1n6/crmd/7, version=0.3.0)
Feb 21 18:22:14 bl460g1n6 cib[9098]: info: cib_process_request: Forwarding cib_modify operation for section crm_config to master (origin=local/crmd/9)
Feb 21 18:22:14 bl460g1n6 cib[9098]: info: cib_process_request: Completed cib_query operation for section //cib/configuration/crm_config//cluster_property_set//nvpair[@name='cluster-infrastructure']: OK (rc=0, origin=local/crmd/10, version=0.3.0)
Feb 21 18:22:14 bl460g1n6 crmd[9103]: info: join_make_offer: Making join offers based on membership 16
Feb 21 18:22:14 bl460g1n6 crmd[9103]: info: join_make_offer: join-1: Sending offer to bl460g1n6
Feb 21 18:22:14 bl460g1n6 crmd[9103]: info: crm_update_peer_join: join_make_offer: Node bl460g1n6[3232261592] - join-1 phase 0 -> 1
Feb 21 18:22:14 bl460g1n6 crmd[9103]: info: do_dc_join_offer_all: join-1: Waiting on 1 outstanding join acks
Feb 21 18:22:14 bl460g1n6 crmd[9103]: warning: do_log: FSA: Input I_ELECTION_DC from do_election_check() received in state S_INTEGRATION
Feb 21 18:22:14 bl460g1n6 crmd[9103]: info: crm_update_peer_join: initialize_join: Node bl460g1n6[3232261592] - join-2 phase 1 -> 0
Feb 21 18:22:14 bl460g1n6 crmd[9103]: info: join_make_offer: join-2: Sending offer to bl460g1n6
Feb 21 18:22:14 bl460g1n6 crmd[9103]: info: crm_update_peer_join: join_make_offer: Node bl460g1n6[3232261592] - join-2 phase 0 -> 1
Feb 21 18:22:14 bl460g1n6 crmd[9103]: info: do_dc_join_offer_all: join-2: Waiting on 1 outstanding join acks
Feb 21 18:22:14 bl460g1n6 crmd[9103]: info: update_dc: Set DC to bl460g1n6 (3.0.9)
Feb 21 18:22:14 bl460g1n6 cib[9098]: info: cib_process_request: Completed cib_modify operation for section crm_config: OK (rc=0, origin=bl460g1n6/crmd/9, version=0.3.0)
Feb 21 18:22:14 bl460g1n6 cib[9098]: info: cib_process_request: Forwarding cib_modify operation for section crm_config to master (origin=local/crmd/11)
Feb 21 18:22:14 bl460g1n6 cib[9098]: info: cib_process_request: Completed cib_query operation for section crm_config: OK (rc=0, origin=local/crmd/12, version=0.3.0)
Feb 21 18:22:14 bl460g1n6 cib[9098]: info: cib_process_request: Completed cib_query operation for section 'all': OK (rc=0, origin=local/crmd/13, version=0.3.0)
Feb 21 18:22:14 bl460g1n6 cib[9098]: info: cib_process_request: Completed cib_modify operation for section crm_config: OK (rc=0, origin=bl460g1n6/crmd/11, version=0.3.0)
Feb 21 18:22:14 bl460g1n6 cib[9098]: info: cib_process_request: Completed cib_query operation for section 'all': OK (rc=0, origin=local/crmd/14, version=0.3.0)
Feb 21 18:22:14 bl460g1n6 crmd[9103]: info: crm_update_peer_join: do_dc_join_filter_offer: Node bl460g1n6[3232261592] - join-2 phase 1 -> 2
Feb 21 18:22:14 bl460g1n6 crmd[9103]: info: crm_update_peer_expected: do_dc_join_filter_offer: Node bl460g1n6[3232261592] - expected state is now member (was (null))
Feb 21 18:22:14 bl460g1n6 crmd[9103]: info: do_state_transition: State transition S_INTEGRATION -> S_FINALIZE_JOIN [ input=I_INTEGRATED cause=C_FSA_INTERNAL origin=check_join_state ]
Feb 21 18:22:14 bl460g1n6 crmd[9103]: info: crmd_join_phase_log: join-2: bl460g1n6=integrated
Feb 21 18:22:14 bl460g1n6 crmd[9103]: info: do_dc_join_finalize: join-2: Syncing our CIB to the rest of the cluster
Feb 21 18:22:14 bl460g1n6 cib[9098]: info: cib_process_request: Completed cib_sync operation for section 'all': OK (rc=0, origin=local/crmd/15, version=0.3.0)
Feb 21 18:22:14 bl460g1n6 crmd[9103]: info: crm_update_peer_join: finalize_join_for: Node bl460g1n6[3232261592] - join-2 phase 2 -> 3
Feb 21 18:22:14 bl460g1n6 cib[9098]: info: cib_process_replace: Digest matched on replace from bl460g1n6: 01e59cefb84e2f459fdbd36605891fcf
Feb 21 18:22:14 bl460g1n6 cib[9098]: info: cib_process_replace: Replaced 0.3.0 with 0.3.0 from bl460g1n6
Feb 21 18:22:14 bl460g1n6 cib[9098]: info: cib_process_request: Completed cib_replace operation for section 'all': OK (rc=0, origin=bl460g1n6/crmd/15, version=0.3.0)
Feb 21 18:22:14 bl460g1n6 cib[9098]: info: cib_process_request: Forwarding cib_modify operation for section nodes to master (origin=local/crmd/16)
Feb 21 18:22:14 bl460g1n6 cib[9098]: info: cib_process_request: Completed cib_modify operation for section nodes: OK (rc=0, origin=bl460g1n6/crmd/16, version=0.3.0)
Feb 21 18:22:14 bl460g1n6 crmd[9103]: info: erase_status_tag: Deleting xpath: //node_state[@uname='bl460g1n6']/transient_attributes
Feb 21 18:22:14 bl460g1n6 crmd[9103]: info: update_attrd_helper: Connecting to attrd... 5 retries remaining
Feb 21 18:22:14 bl460g1n6 cib[9098]: info: cib_process_request: Forwarding cib_delete operation for section //node_state[@uname='bl460g1n6']/transient_attributes to master (origin=local/crmd/17)
Feb 21 18:22:14 bl460g1n6 attrd[9101]: info: crm_client_new: Connecting 0x10cd250 for uid=189 gid=189 pid=9103 id=70aa5767-87f6-4352-a97b-e3063e92be59
Feb 21 18:22:14 bl460g1n6 attrd[9101]: info: attrd_client_message: Starting an election to determine the writer
Feb 21 18:22:14 bl460g1n6 crmd[9103]: info: crm_update_peer_join: do_dc_join_ack: Node bl460g1n6[3232261592] - join-2 phase 3 -> 4
Feb 21 18:22:14 bl460g1n6 crmd[9103]: info: do_dc_join_ack: join-2: Updating node state to member for bl460g1n6
Feb 21 18:22:14 bl460g1n6 crmd[9103]: info: erase_status_tag: Deleting xpath: //node_state[@uname='bl460g1n6']/lrm
Feb 21 18:22:14 bl460g1n6 cib[9098]: info: cib_process_request: Completed cib_delete operation for section //node_state[@uname='bl460g1n6']/transient_attributes: OK (rc=0, origin=bl460g1n6/crmd/17, version=0.3.0)
Feb 21 18:22:14 bl460g1n6 cib[9098]: info: cib_process_request: Forwarding cib_delete operation for section //node_state[@uname='bl460g1n6']/lrm to master (origin=local/crmd/18)
Feb 21 18:22:14 bl460g1n6 cib[9098]: info: cib_process_request: Forwarding cib_modify operation for section status to master (origin=local/crmd/19)
Feb 21 18:22:14 bl460g1n6 attrd[9101]: notice: corosync_node_name: Unable to get node name for nodeid 3232261592
Feb 21 18:22:14 bl460g1n6 attrd[9101]: notice: get_node_name: Defaulting to uname -n for the local corosync node name
Feb 21 18:22:14 bl460g1n6 attrd[9101]: info: attrd_client_message: Broadcasting terminate[bl460g1n6] = (null)
Feb 21 18:22:14 bl460g1n6 attrd[9101]: info: attrd_client_message: Broadcasting shutdown[bl460g1n6] = 0
Feb 21 18:22:14 bl460g1n6 cib[9098]: info: cib_process_request: Completed cib_delete operation for section //node_state[@uname='bl460g1n6']/lrm: OK (rc=0, origin=bl460g1n6/crmd/18, version=0.3.0)
Feb 21 18:22:14 bl460g1n6 cib[9098]: info: cib_perform_op: + @num_updates=1
Feb 21 18:22:14 bl460g1n6 cib[9098]: info: cib_perform_op: +
Feb 21 18:22:14 bl460g1n6 cib[9098]: info: cib_perform_op: +
Feb 21 18:22:14 bl460g1n6 cib[9098]: info: cib_perform_op: ++
Feb 21 18:22:14 bl460g1n6 cib[9098]: info: cib_perform_op: ++
Feb 21 18:22:14 bl460g1n6 cib[9098]: info: cib_perform_op: ++
Feb 21 18:22:14 bl460g1n6 cib[9098]: info: cib_perform_op: ++
Feb 21 18:22:14 bl460g1n6 cib[9098]: info: cib_perform_op: ++
Feb 21 18:22:14 bl460g1n6 cib[9098]: info: cib_perform_op: +
Feb 21 18:22:14 bl460g1n6 cib[9098]: info: cib_perform_op: +
Feb 21 18:22:14 bl460g1n6 attrd[9101]: info: election_complete: Election election-attrd complete
Feb 21 18:22:14 bl460g1n6 stonith-ng[9099]: info: xml_patch_version_check: Applying patch 0.3.1 to 0.3.0
Feb 21 18:22:14 bl460g1n6 cib[9098]: info: cib_process_request: Completed cib_modify operation for section status: OK (rc=0, origin=bl460g1n6/crmd/19, version=0.3.1)
Feb 21 18:22:14 bl460g1n6 attrd[9101]: notice: write_attribute: Sent update 2 with 1 changes for terminate, id=, set=(null)
Feb 21 18:22:14 bl460g1n6 cib[9098]: info: cib_process_request: Forwarding cib_modify operation for section status to master (origin=local/attrd/2)
Feb 21 18:22:14 bl460g1n6 attrd[9101]: notice: write_attribute: Sent update 3 with 1 changes for shutdown, id=, set=(null)
Feb 21 18:22:14 bl460g1n6 cib[9098]: info: cib_process_request: Forwarding cib_modify operation for section status to master (origin=local/attrd/3)
Feb 21 18:22:14 bl460g1n6 crmd[9103]: info: do_state_transition: State transition S_FINALIZE_JOIN -> S_POLICY_ENGINE [ input=I_FINALIZED cause=C_FSA_INTERNAL origin=check_join_state ]
Feb 21 18:22:14 bl460g1n6 crmd[9103]: info: abort_transition_graph: do_te_invoke:151 - Triggered transition abort (complete=1) : Peer Cancelled
Feb 21 18:22:14 bl460g1n6 cib[9098]: info: xml_log_changes: -- /cib/status/node_state[@id='3232261592']/transient_attributes[@id='3232261592']/instance_attributes[@id='status-3232261592']/nvpair[@id='status-3232261592-terminate']
Feb 21 18:22:14 bl460g1n6 cib[9098]: info: cib_perform_op: + @num_updates=2
Feb 21 18:22:14 bl460g1n6 attrd[9101]: notice: attrd_client_message: Updating all attributes
Feb 21 18:22:14 bl460g1n6 attrd[9101]: info: write_attribute: Write out of 'shutdown' delayed: update 3 in progress
Feb 21 18:22:14 bl460g1n6 attrd[9101]: info: write_attribute: Write out of 'terminate' delayed: update 2 in progress
Feb 21 18:22:14 bl460g1n6 cib[9098]: info: cib_perform_op: +
Feb 21 18:22:14 bl460g1n6 cib[9098]: info: cib_perform_op: +
Feb 21 18:22:14 bl460g1n6 cib[9098]: info: cib_perform_op: +
Feb 21 18:22:14 bl460g1n6 cib[9098]: info: cib_perform_op: ++
Feb 21 18:22:14 bl460g1n6 cib[9098]: info: cib_perform_op: ++
Feb 21 18:22:14 bl460g1n6 cib[9098]: info: cib_perform_op: ++
Feb 21 18:22:14 bl460g1n6 cib[9098]: info: cib_perform_op: +
Feb 21 18:22:14 bl460g1n6 cib[9098]: info: cib_perform_op: +
Feb 21 18:22:14 bl460g1n6 cib[9098]: info: cib_perform_op: +
Feb 21 18:22:14 bl460g1n6 crmd[9103]: info: abort_transition_graph: te_update_diff:382 - Triggered transition abort (complete=1, node=, tag=change, id=(null), magic=NA, cib=0.3.2) : Transient attribute change
Feb 21 18:22:14 bl460g1n6 crmd[9103]: info: abort_transition_graph: te_update_diff:382 - Triggered transition abort (complete=1, node=, tag=change, id=(null), magic=NA, cib=0.3.2) : Transient attribute change
Feb 21 18:22:14 bl460g1n6 stonith-ng[9099]: info: xml_patch_version_check: Applying patch 0.3.2 to 0.3.1
Feb 21 18:22:14 bl460g1n6 stonith-ng[9099]: notice: __xml_find_path: No match for /cib/status/node_state[@id='3232261592']/transient_attributes[@id='3232261592']/instance_attributes[@id='status-3232261592']/nvpair[@id='status-3232261592-terminate']
Feb 21 18:22:14 bl460g1n6 cib[9098]: info: cib_process_request: Completed cib_modify operation for section status: OK (rc=0, origin=bl460g1n6/attrd/2, version=0.3.2)
Feb 21 18:22:14 bl460g1n6 attrd[9101]: info: attrd_cib_callback: Update 2 for terminate: OK (0)
Feb 21 18:22:14 bl460g1n6 attrd[9101]: notice: attrd_cib_callback: Update 2 for terminate[bl460g1n6]=(null): OK (0)
Feb 21 18:22:14 bl460g1n6 cib[9098]: info: cib_perform_op: + @num_updates=3
Feb 21 18:22:14 bl460g1n6 cib[9098]: info: cib_perform_op: +
Feb 21 18:22:14 bl460g1n6 cib[9098]: info: cib_perform_op: +
Feb 21 18:22:14 bl460g1n6 cib[9098]: info: cib_perform_op: +
Feb 21 18:22:14 bl460g1n6 cib[9098]: info: cib_perform_op: +
Feb 21 18:22:14 bl460g1n6 cib[9098]: info: cib_perform_op: +
Feb 21 18:22:14 bl460g1n6 cib[9098]: info: cib_perform_op: ++
Feb 21 18:22:14 bl460g1n6 cib[9098]: info: cib_perform_op: +
Feb 21 18:22:14 bl460g1n6 cib[9098]: info: cib_perform_op: +
Feb 21 18:22:14 bl460g1n6 cib[9098]: info: cib_perform_op: +
Feb 21 18:22:14 bl460g1n6 cib[9098]: info: cib_perform_op: +
Feb 21 18:22:14 bl460g1n6 cib[9098]: info: cib_perform_op: +
Feb 21 18:22:14 bl460g1n6 crmd[9103]: info: abort_transition_graph: te_update_diff:382 - Triggered transition abort (complete=1, node=, tag=change, id=(null), magic=NA, cib=0.3.3) : Transient attribute change
Feb 21 18:22:14 bl460g1n6 cib[9098]: info: cib_process_request: Completed cib_modify operation for section status: OK (rc=0, origin=bl460g1n6/attrd/3, version=0.3.3)
Feb 21 18:22:14 bl460g1n6 cib[9098]: info: cib_process_request: Forwarding cib_modify operation for section nodes to master (origin=local/crmd/20)
Feb 21 18:22:14 bl460g1n6 attrd[9101]: info: attrd_cib_callback: Update 3 for shutdown: OK (0)
Feb 21 18:22:14 bl460g1n6 attrd[9101]: notice: attrd_cib_callback: Update 3 for shutdown[bl460g1n6]=0: OK (0)
Feb 21 18:22:14 bl460g1n6 cib[9098]: info: cib_process_request: Forwarding cib_modify operation for section status to master (origin=local/crmd/21)
Feb 21 18:22:14 bl460g1n6 cib[9098]: info: cib_process_request: Forwarding cib_modify operation for section cib to master (origin=local/crmd/22)
Feb 21 18:22:14 bl460g1n6 stonith-ng[9099]: info: xml_patch_version_check: Applying patch 0.3.3 to 0.3.2
Feb 21 18:22:14 bl460g1n6 cib[9098]: info: cib_process_request: Completed cib_modify operation for section nodes: OK (rc=0, origin=bl460g1n6/crmd/20, version=0.3.3)
Feb 21 18:22:14 bl460g1n6 cib[9098]: info: cib_perform_op: + @num_updates=4
Feb 21 18:22:14 bl460g1n6 cib[9098]: info: cib_perform_op: +
Feb 21 18:22:14 bl460g1n6 cib[9098]: info: cib_perform_op: +
Feb 21 18:22:14 bl460g1n6 cib[9098]: info: cib_perform_op: + @crm-debug-origin=do_state_transition
Feb 21 18:22:14 bl460g1n6 cib[9098]: info: cib_perform_op: +
Feb 21 18:22:14 bl460g1n6 cib[9098]: info: cib_perform_op: +
Feb 21 18:22:14 bl460g1n6 cib[9098]: info: cib_perform_op: +
Feb 21 18:22:14 bl460g1n6 cib[9098]: info: cib_perform_op: +
Feb 21 18:22:14 bl460g1n6 cib[9098]: info: cib_process_request: Completed cib_modify operation for section status: OK (rc=0, origin=bl460g1n6/crmd/21, version=0.3.4)
Feb 21 18:22:14 bl460g1n6 cib[9098]: info: cib_perform_op: + @num_updates=5
Feb 21 18:22:14 bl460g1n6 cib[9098]: info: cib_perform_op: ++ @dc-uuid=3232261592
Feb 21 18:22:14 bl460g1n6 cib[9098]: info: cib_perform_op: +
Feb 21 18:22:14 bl460g1n6 cib[9098]: info: cib_perform_op: +
Feb 21 18:22:14 bl460g1n6 stonith-ng[9099]: info: xml_patch_version_check: Applying patch 0.3.4 to 0.3.3
Feb 21 18:22:14 bl460g1n6 cib[9098]: info: cib_process_request: Completed cib_modify operation for section cib: OK (rc=0, origin=bl460g1n6/crmd/22, version=0.3.5)
Feb 21 18:22:14 bl460g1n6 stonith-ng[9099]: info: xml_patch_version_check: Applying patch 0.3.5 to 0.3.4
Feb 21 18:22:14 bl460g1n6 cib[9111]: info: write_cib_contents: Archived previous version as /var/lib/pacemaker/cib/cib-8.raw
Feb 21 18:22:14 bl460g1n6 cib[9111]: info: write_cib_contents: Wrote version 0.3.0 of the CIB to disk (digest: 27b3832d6d4016381c786825b263d7f8)
Feb 21 18:22:14 bl460g1n6 cib[9111]: info: retrieveCib: Reading cluster configuration from: /var/lib/pacemaker/cib/cib.3TAmZ4 (digest: /var/lib/pacemaker/cib/cib.IrbnVp)
Feb 21 18:22:16 bl460g1n6 crmd[9103]: info: crm_timer_popped: New Transition Timer (I_PE_CALC) just popped (2000ms)
Feb 21 18:22:16 bl460g1n6 cib[9098]: info: cib_process_request: Completed cib_query operation for section 'all': OK (rc=0, origin=local/crmd/23, version=0.3.5)
Feb 21 18:22:16 bl460g1n6 pengine[9102]: notice: unpack_config: On loss of CCM Quorum: Ignore
Feb 21 18:22:16 bl460g1n6 pengine[9102]: warning: handle_startup_fencing: Blind faith: not fencing unseen nodes
Feb 21 18:22:16 bl460g1n6 pengine[9102]: info: determine_online_status: Node bl460g1n6 is online
Feb 21 18:22:16 bl460g1n6 pengine[9102]: info: native_print: prmPing (ocf::pacemaker:ping): Stopped
Feb 21 18:22:16 bl460g1n6 pengine[9102]: info: RecurringOp: Start recurring monitor (30s) for prmPing on bl460g1n6
Feb 21 18:22:16 bl460g1n6 pengine[9102]: notice: LogActions: Start prmPing (bl460g1n6)
Feb 21 18:22:16 bl460g1n6 pengine[9102]: notice: process_pe_message: Calculated Transition 0: /var/lib/pacemaker/pengine/pe-input-11.bz2
Feb 21 18:22:16 bl460g1n6 crmd[9103]: info: do_state_transition: State transition S_POLICY_ENGINE -> S_TRANSITION_ENGINE [ input=I_PE_SUCCESS cause=C_IPC_MESSAGE origin=handle_response ]
Feb 21 18:22:16 bl460g1n6 crmd[9103]: info: do_te_invoke: Processing graph 0 (ref=pe_calc-dc-1392974536-9) derived from /var/lib/pacemaker/pengine/pe-input-11.bz2
Feb 21 18:22:16 bl460g1n6 crmd[9103]: notice: te_rsc_command: Initiating action 4: monitor prmPing_monitor_0 on bl460g1n6 (local)
Feb 21 18:22:16 bl460g1n6 lrmd[9100]: info: process_lrmd_get_rsc_info: Resource 'prmPing' not found (0 active resources)
Feb 21 18:22:16 bl460g1n6 lrmd[9100]: info: process_lrmd_rsc_register: Added 'prmPing' to the rsc list (1 active resources)
Feb 21 18:22:16 bl460g1n6 crmd[9103]: info: do_lrm_rsc_op: Performing key=4:0:7:82c5fc6f-611d-4ba9-8a4f-13155b12536e op=prmPing_monitor_0
Feb 21 18:22:16 bl460g1n6 crmd[9103]: info: services_os_action_execute: Managed ping_meta-data_0 process 9123 exited with rc=0
Feb 21 18:22:16 bl460g1n6 crmd[9103]: notice: process_lrm_event: LRM operation prmPing_monitor_0 (call=5, rc=7, cib-update=24, confirmed=true) not running
Feb 21 18:22:16 bl460g1n6 cib[9098]: info: cib_process_request: Forwarding cib_modify operation for section status to master (origin=local/crmd/24)
Feb 21 18:22:16 bl460g1n6 cib[9098]: info: cib_perform_op: + @num_updates=6
Feb 21 18:22:16 bl460g1n6 cib[9098]: info: cib_perform_op: +
Feb 21 18:22:16 bl460g1n6 cib[9098]: info: cib_perform_op: +
Feb 21 18:22:16 bl460g1n6 cib[9098]: info: cib_perform_op: + @crm-debug-origin=do_update_resource
Feb 21 18:22:16 bl460g1n6 cib[9098]: info: cib_perform_op: +
Feb 21 18:22:16 bl460g1n6 cib[9098]: info: cib_perform_op: +
Feb 21 18:22:16 bl460g1n6 cib[9098]: info: cib_perform_op: +
Feb 21 18:22:16 bl460g1n6 cib[9098]: info: cib_perform_op: ++
Feb 21 18:22:16 bl460g1n6 cib[9098]: info: cib_perform_op: ++
Feb 21 18:22:16 bl460g1n6 cib[9098]: info: cib_perform_op: +
Feb 21 18:22:16 bl460g1n6 cib[9098]: info: cib_perform_op: +
Feb 21 18:22:16 bl460g1n6 cib[9098]: info: cib_perform_op: +
Feb 21 18:22:16 bl460g1n6 cib[9098]: info: cib_perform_op: +
Feb 21 18:22:16 bl460g1n6 cib[9098]: info: cib_perform_op: +
Feb 21 18:22:16 bl460g1n6 cib[9098]: info: cib_process_request: Completed cib_modify operation for section status: OK (rc=0, origin=bl460g1n6/crmd/24, version=0.3.6)
Feb 21 18:22:16 bl460g1n6 crmd[9103]: info: match_graph_event: Action prmPing_monitor_0 (4) confirmed on bl460g1n6 (rc=0)
Feb 21 18:22:16 bl460g1n6 crmd[9103]: notice: te_rsc_command: Initiating action 3: probe_complete probe_complete on bl460g1n6 (local) - no waiting
Feb 21 18:22:16 bl460g1n6 crmd[9103]: info: te_rsc_command: Action 3 confirmed - no wait
Feb 21 18:22:16 bl460g1n6 crmd[9103]: notice: te_rsc_command: Initiating action 5: start prmPing_start_0 on bl460g1n6 (local)
Feb 21 18:22:16 bl460g1n6 attrd[9101]: info: attrd_client_message: Broadcasting probe_complete[bl460g1n6] = true (writer)
Feb 21 18:22:16 bl460g1n6 crmd[9103]: info: do_lrm_rsc_op: Performing key=5:0:0:82c5fc6f-611d-4ba9-8a4f-13155b12536e op=prmPing_start_0
Feb 21 18:22:16 bl460g1n6 stonith-ng[9099]: info: xml_patch_version_check: Applying patch 0.3.6 to 0.3.5
Feb 21 18:22:16 bl460g1n6 lrmd[9100]: info: log_execute: executing - rsc:prmPing action:start call_id:6
Feb 21 18:22:16 bl460g1n6 cib[9098]: info: cib_process_request: Forwarding cib_modify operation for section status to master (origin=local/attrd/4)
Feb 21 18:22:16 bl460g1n6 attrd[9101]: notice: write_attribute: Sent update 4 with 1 changes for probe_complete, id=, set=(null)
Feb 21 18:22:16 bl460g1n6 cib[9098]: info: cib_perform_op: + @num_updates=7
Feb 21 18:22:16 bl460g1n6 cib[9098]: info: cib_perform_op: +
Feb 21 18:22:16 bl460g1n6 cib[9098]: info: cib_perform_op: +
Feb 21 18:22:16 bl460g1n6 cib[9098]: info: cib_perform_op: +
Feb 21 18:22:16 bl460g1n6 cib[9098]: info: cib_perform_op: +
Feb 21 18:22:16 bl460g1n6 cib[9098]: info: cib_perform_op: +
Feb 21 18:22:16 bl460g1n6 cib[9098]: info: cib_perform_op: ++
Feb 21 18:22:16 bl460g1n6 cib[9098]: info: cib_perform_op: +
Feb 21 18:22:16 bl460g1n6 cib[9098]: info: cib_perform_op: +
Feb 21 18:22:16 bl460g1n6 cib[9098]: info: cib_perform_op: +
Feb 21 18:22:16 bl460g1n6 cib[9098]: info: cib_perform_op: +
Feb 21 18:22:16 bl460g1n6 cib[9098]: info: cib_perform_op: +
Feb 21 18:22:16 bl460g1n6 cib[9098]: info: cib_process_request: Completed cib_modify operation for section status: OK (rc=0, origin=bl460g1n6/attrd/4, version=0.3.7)
Feb 21 18:22:16 bl460g1n6 crmd[9103]: info: abort_transition_graph: te_update_diff:382 - Triggered transition abort (complete=0, node=, tag=change, id=(null), magic=NA, cib=0.3.7) : Transient attribute change
Feb 21 18:22:16 bl460g1n6 attrd[9101]: info: attrd_cib_callback: Update 4 for probe_complete: OK (0)
Feb 21 18:22:16 bl460g1n6 attrd[9101]: notice: attrd_cib_callback: Update 4 for probe_complete[bl460g1n6]=true: OK (0)
Feb 21 18:22:16 bl460g1n6 stonith-ng[9099]: info: xml_patch_version_check: Applying patch 0.3.7 to 0.3.6
Feb 21 18:22:18 bl460g1n6 attrd[9101]: info: crm_client_new: Connecting 0x10f3670 for uid=0 gid=0 pid=9152 id=41b2e813-5e46-4a9f-a820-dee7afc35008
Feb 21 18:22:18 bl460g1n6 attrd[9101]: info: attrd_client_message: Broadcasting default_ping_set[bl460g1n6] = 100 (writer)
Feb 21 18:22:18 bl460g1n6 lrmd[9100]: info: log_finished: finished - rsc:prmPing action:start call_id:6 pid:9130 exit-code:0 exec-time:2054ms queue-time:0ms
Feb 21 18:22:18 bl460g1n6 attrd[9101]: notice: write_attribute: Sent update 5 with 1 changes for default_ping_set, id=, set=(null)
Feb 21 18:22:18 bl460g1n6 attrd[9101]: info: crm_client_destroy: Destroying 0 events
Feb 21 18:22:18 bl460g1n6 cib[9098]: info: cib_process_request: Forwarding cib_modify operation for section status to master (origin=local/attrd/5)
Feb 21 18:22:18 bl460g1n6 crmd[9103]: notice: process_lrm_event: LRM operation prmPing_start_0 (call=6, rc=0, cib-update=25, confirmed=true) ok
Feb 21 18:22:18 bl460g1n6 cib[9098]: info: cib_perform_op: + @num_updates=8
Feb 21 18:22:18 bl460g1n6 cib[9098]: info: cib_perform_op: +
Feb 21 18:22:18 bl460g1n6 cib[9098]: info: cib_perform_op: +
Feb 21 18:22:18 bl460g1n6 cib[9098]: info: cib_perform_op: +
Feb 21 18:22:18 bl460g1n6 cib[9098]: info: cib_perform_op: +
Feb 21 18:22:18 bl460g1n6 cib[9098]: info: cib_perform_op: +
Feb 21 18:22:18 bl460g1n6 cib[9098]: info: cib_perform_op: ++
Feb 21 18:22:18 bl460g1n6 cib[9098]: info: cib_perform_op: +
Feb 21 18:22:18 bl460g1n6 cib[9098]: info: cib_perform_op: +
Feb 21 18:22:18 bl460g1n6 cib[9098]: info: cib_perform_op: +
Feb 21 18:22:18 bl460g1n6 cib[9098]: info: cib_perform_op: +
Feb 21 18:22:18 bl460g1n6 cib[9098]: info: cib_perform_op: +
Feb 21 18:22:18 bl460g1n6 crmd[9103]: info: abort_transition_graph: te_update_diff:382 - Triggered transition abort (complete=0, node=, tag=change, id=(null), magic=NA, cib=0.3.8) : Transient attribute change
Feb 21 18:22:18 bl460g1n6 stonith-ng[9099]: info: xml_patch_version_check: Applying patch 0.3.8 to 0.3.7
Feb 21 18:22:18 bl460g1n6 cib[9098]: info: cib_process_request: Completed cib_modify operation for section status: OK (rc=0, origin=bl460g1n6/attrd/5, version=0.3.8)
Feb 21 18:22:18 bl460g1n6 attrd[9101]: info: attrd_cib_callback: Update 5 for default_ping_set: OK (0)
Feb 21 18:22:18 bl460g1n6 attrd[9101]: notice: attrd_cib_callback: Update 5 for default_ping_set[bl460g1n6]=100: OK (0)
Feb 21 18:22:18 bl460g1n6 cib[9098]: info: cib_process_request: Forwarding cib_modify operation for section status to master (origin=local/crmd/25)
Feb 21 18:22:18 bl460g1n6 cib[9098]: info: cib_perform_op: + @num_updates=9
Feb 21 18:22:18 bl460g1n6 cib[9098]: info: cib_perform_op: +
Feb 21 18:22:18 bl460g1n6 cib[9098]: info: cib_perform_op: +
Feb 21 18:22:18 bl460g1n6 cib[9098]: info: cib_perform_op: +
Feb 21 18:22:18 bl460g1n6 cib[9098]: info: cib_perform_op: +
Feb 21 18:22:18 bl460g1n6 cib[9098]: info: cib_perform_op: +
Feb 21 18:22:18 bl460g1n6 cib[9098]: info: cib_perform_op: +
Feb 21 18:22:18 bl460g1n6 cib[9098]: info: cib_perform_op: + @operation_key=prmPing_start_0
Feb 21 18:22:18 bl460g1n6 cib[9098]: info: cib_perform_op: + @operation=start
Feb 21 18:22:18 bl460g1n6 cib[9098]: info: cib_perform_op: + @transition-key=5:0:0:82c5fc6f-611d-4ba9-8a4f-13155b12536e
Feb 21 18:22:18 bl460g1n6 cib[9098]: info: cib_perform_op: + @transition-magic=0:0;5:0:0:82c5fc6f-611d-4ba9-8a4f-13155b12536e
Feb 21 18:22:18 bl460g1n6 cib[9098]: info: cib_perform_op: + @call-id=6
Feb 21 18:22:18 bl460g1n6 cib[9098]: info: cib_perform_op: + @rc-code=0
Feb 21 18:22:18 bl460g1n6 cib[9098]: info: cib_perform_op: + @exec-time=2054
Feb 21 18:22:18 bl460g1n6 cib[9098]: info: cib_perform_op: +
Feb 21 18:22:18 bl460g1n6 cib[9098]: info: cib_perform_op: +
Feb 21 18:22:18 bl460g1n6 cib[9098]: info: cib_perform_op: +
Feb 21 18:22:18 bl460g1n6 cib[9098]: info: cib_perform_op: +
Feb 21 18:22:18 bl460g1n6 cib[9098]: info: cib_perform_op: +
Feb 21 18:22:18 bl460g1n6 cib[9098]: info: cib_perform_op: +
Feb 21 18:22:18 bl460g1n6 cib[9098]: info: cib_perform_op: +
Feb 21 18:22:18 bl460g1n6 cib[9098]: info: cib_process_request: Completed cib_modify operation for section status: OK (rc=0, origin=bl460g1n6/crmd/25, version=0.3.9)
Feb 21 18:22:18 bl460g1n6 crmd[9103]: info: match_graph_event: Action prmPing_start_0 (5) confirmed on bl460g1n6 (rc=0)
Feb 21 18:22:18 bl460g1n6 crmd[9103]: notice: run_graph: Transition 0 (Complete=4, Pending=0, Fired=0, Skipped=1, Incomplete=0, Source=/var/lib/pacemaker/pengine/pe-input-11.bz2): Stopped
Feb 21 18:22:20 bl460g1n6 crmd[9103]: info: crm_timer_popped: New Transition Timer (I_PE_CALC) just popped (2000ms)
Feb 21 18:22:20 bl460g1n6 crmd[9103]: info: do_state_transition: State transition S_TRANSITION_ENGINE -> S_POLICY_ENGINE [ input=I_PE_CALC cause=C_TIMER_POPPED origin=crm_timer_popped ]
Feb 21 18:22:20 bl460g1n6 crmd[9103]: info: do_state_transition: Progressed to state S_POLICY_ENGINE after C_TIMER_POPPED
Feb 21 18:22:20 bl460g1n6 cib[9098]: info: cib_process_request: Completed cib_query operation for section 'all': OK (rc=0, origin=local/crmd/26, version=0.3.9)
Feb 21 18:22:20 bl460g1n6 pengine[9102]: notice: unpack_config: On loss of CCM Quorum: Ignore
Feb 21 18:22:20 bl460g1n6 pengine[9102]: info: determine_online_status: Node bl460g1n6 is online
Feb 21 18:22:20 bl460g1n6 pengine[9102]: info: native_print: prmPing (ocf::pacemaker:ping): Started bl460g1n6
Feb 21 18:22:20 bl460g1n6 pengine[9102]: info: RecurringOp: Start recurring monitor (30s) for prmPing on bl460g1n6
Feb 21 18:22:20 bl460g1n6 pengine[9102]: info: LogActions: Leave prmPing (Started bl460g1n6)
Feb 21 18:22:20 bl460g1n6 pengine[9102]: notice: process_pe_message: Calculated Transition 1: /var/lib/pacemaker/pengine/pe-input-12.bz2
Feb 21 18:22:20 bl460g1n6 crmd[9103]: info: do_state_transition: State transition S_POLICY_ENGINE -> S_TRANSITION_ENGINE [ input=I_PE_SUCCESS cause=C_IPC_MESSAGE origin=handle_response ]
Feb 21 18:22:20 bl460g1n6 crmd[9103]: info: do_te_invoke: Processing graph 1 (ref=pe_calc-dc-1392974540-13) derived from /var/lib/pacemaker/pengine/pe-input-12.bz2
Feb 21 18:22:20 bl460g1n6 crmd[9103]: notice: te_rsc_command: Initiating action 6: monitor prmPing_monitor_30000 on bl460g1n6 (local)
Feb 21 18:22:20 bl460g1n6 crmd[9103]: info: do_lrm_rsc_op: Performing key=6:1:0:82c5fc6f-611d-4ba9-8a4f-13155b12536e op=prmPing_monitor_30000
Feb 21 18:22:22 bl460g1n6 attrd[9101]: info: crm_client_new: Connecting 0x10f3670 for uid=0 gid=0 pid=9175 id=4fb2c8d9-8307-47c9-a576-c2806c4c569d
Feb 21 18:22:22 bl460g1n6 attrd[9101]: info: attrd_client_message: Broadcasting default_ping_set[bl460g1n6] = 100 (writer)
Feb 21 18:22:22 bl460g1n6 attrd[9101]: info: crm_client_destroy: Destroying 0 events
Feb 21 18:22:22 bl460g1n6 crmd[9103]: notice: process_lrm_event: LRM operation prmPing_monitor_30000 (call=7, rc=0, cib-update=27, confirmed=false) ok
Feb 21 18:22:22 bl460g1n6 cib[9098]: info: cib_process_request: Forwarding cib_modify operation for section status to master (origin=local/crmd/27)
Feb 21 18:22:22 bl460g1n6 cib[9098]: info: cib_perform_op: + @num_updates=10
Feb 21 18:22:22 bl460g1n6 cib[9098]: info: cib_perform_op: +
Feb 21 18:22:22 bl460g1n6 cib[9098]: info: cib_perform_op: +
Feb 21 18:22:22 bl460g1n6 cib[9098]: info: cib_perform_op: +
Feb 21 18:22:22 bl460g1n6 cib[9098]: info: cib_perform_op: +
Feb 21 18:22:22 bl460g1n6 cib[9098]: info: cib_perform_op: +
Feb 21 18:22:22 bl460g1n6 cib[9098]: info: cib_perform_op: +
Feb 21 18:22:22 bl460g1n6 cib[9098]: info: cib_perform_op: ++
Feb 21 18:22:22 bl460g1n6 cib[9098]: info: cib_perform_op: +
Feb 21 18:22:22 bl460g1n6 cib[9098]: info: cib_perform_op: +
Feb 21 18:22:22 bl460g1n6 cib[9098]: info: cib_perform_op: +
Feb 21 18:22:22 bl460g1n6 cib[9098]: info: cib_perform_op: +
Feb 21 18:22:22 bl460g1n6 cib[9098]: info: cib_perform_op: +
Feb 21 18:22:22 bl460g1n6 cib[9098]: info: cib_perform_op: +
Feb 21 18:22:22 bl460g1n6 crmd[9103]: info: match_graph_event: Action prmPing_monitor_30000 (6) confirmed on bl460g1n6 (rc=0)
Feb 21 18:22:22 bl460g1n6 crmd[9103]: notice: run_graph: Transition 1 (Complete=1, Pending=0, Fired=0, Skipped=0, Incomplete=0, Source=/var/lib/pacemaker/pengine/pe-input-12.bz2): Complete
Feb 21 18:22:22 bl460g1n6 crmd[9103]: info: do_log: FSA: Input I_TE_SUCCESS from notify_crmd() received in state S_TRANSITION_ENGINE
Feb 21 18:22:22 bl460g1n6 crmd[9103]: notice: do_state_transition: State transition S_TRANSITION_ENGINE -> S_IDLE [ input=I_TE_SUCCESS cause=C_FSA_INTERNAL origin=notify_crmd ]
Feb 21 18:22:22 bl460g1n6 cib[9098]: info: cib_process_request: Completed cib_modify operation for section status: OK (rc=0, origin=bl460g1n6/crmd/27, version=0.3.10)
Feb 21 18:22:22 bl460g1n6 stonith-ng[9099]: info: xml_patch_version_check: Applying patch 0.3.10 to 0.3.9
Feb 21 18:22:23 bl460g1n6 crmd[9103]: info: throttle_send_command: Updated throttle state to 0000
Feb 21 18:22:27 bl460g1n6 cib[9098]: info: cib_process_ping: Reporting our current digest to bl460g1n6: f7ea097ad042500ba17194f381906742 (0)
Feb 21 18:22:27 bl460g1n6 cib[9098]: info: cib_process_request: Completed ping operation for section 'all': OK (rc=0, origin=bl460g1n6/bl460g1n6/(null), version=0.3.10)
Feb 21 18:22:52 bl460g1n6 pacemakerd[9094]: info: crm_signal_dispatch: Invoking handler for signal 15: Terminated
Feb 21 18:22:52 bl460g1n6 pacemakerd[9094]: notice: pcmk_shutdown_worker: Shuting down Pacemaker
Feb 21 18:22:52 bl460g1n6 pacemakerd[9094]: notice: stop_child: Stopping crmd: Sent -15 to process 9103
Feb 21 18:22:52 bl460g1n6 crmd[9103]: info: crm_signal_dispatch: Invoking handler for signal 15: Terminated
Feb 21 18:22:52 bl460g1n6 crmd[9103]: notice: crm_shutdown: Requesting shutdown, upper limit is 1200000ms
Feb 21 18:22:52 bl460g1n6 crmd[9103]: info: do_log: FSA: Input I_SHUTDOWN from crm_shutdown() received in state S_IDLE
Feb 21 18:22:52 bl460g1n6 crmd[9103]: notice: do_state_transition: State transition S_IDLE -> S_POLICY_ENGINE [ input=I_SHUTDOWN cause=C_SHUTDOWN origin=crm_shutdown ]
Feb 21 18:22:52 bl460g1n6 crmd[9103]: info: do_shutdown_req: Sending shutdown request to bl460g1n6
Feb 21 18:22:52 bl460g1n6 crmd[9103]: info: handle_shutdown_request: Creating shutdown request for bl460g1n6 (state=S_POLICY_ENGINE)
Feb 21 18:22:52 bl460g1n6 attrd[9101]: info: attrd_client_message: Broadcasting shutdown[bl460g1n6] = 1392974572 (writer)
Feb 21 18:22:52 bl460g1n6 attrd[9101]: info: attrd_peer_update: Setting shutdown[bl460g1n6]: 0 -> 1392974572 from bl460g1n6
Feb 21 18:22:52 bl460g1n6 attrd[9101]: notice: write_attribute: Sent update 6 with 1 changes for shutdown, id=, set=(null)
Feb 21 18:22:52 bl460g1n6 cib[9098]: info: cib_process_request: Forwarding cib_modify operation for section status to master (origin=local/attrd/6)
Feb 21 18:22:52 bl460g1n6 cib[9098]: info: cib_perform_op: + @num_updates=11
Feb 21 18:22:52 bl460g1n6 cib[9098]: info: cib_perform_op: +
Feb 21 18:22:52 bl460g1n6 cib[9098]: info: cib_perform_op: +
Feb 21 18:22:52 bl460g1n6 cib[9098]: info: cib_perform_op: +
Feb 21 18:22:52 bl460g1n6 cib[9098]: info: cib_perform_op: +
Feb 21 18:22:52 bl460g1n6 cib[9098]: info: cib_perform_op: +
Feb 21 18:22:52 bl460g1n6 cib[9098]: info: cib_perform_op: + @value=1392974572
Feb 21 18:22:52 bl460g1n6 cib[9098]: info: cib_perform_op: +
Feb 21 18:22:52 bl460g1n6 cib[9098]: info: cib_perform_op: +
Feb 21 18:22:52 bl460g1n6 cib[9098]: info: cib_perform_op: +
Feb 21 18:22:52 bl460g1n6 cib[9098]: info: cib_perform_op: +
Feb 21 18:22:52 bl460g1n6 cib[9098]: info: cib_perform_op: +
Feb 21 18:22:52 bl460g1n6 cib[9098]: info: cib_perform_op: +
Feb 21 18:22:52 bl460g1n6 cib[9098]: info: cib_process_request: Completed cib_modify operation for section status: OK (rc=0, origin=bl460g1n6/attrd/6, version=0.3.11)
Feb 21 18:22:52 bl460g1n6 attrd[9101]: info: attrd_cib_callback: Update 6 for shutdown: OK (0)
Feb 21 18:22:52 bl460g1n6 attrd[9101]: notice: attrd_cib_callback: Update 6 for shutdown[bl460g1n6]=1392974572: OK (0)
Feb 21 18:22:52 bl460g1n6 crmd[9103]: info: abort_transition_graph: te_update_diff:382 - Triggered transition abort (complete=1, node=, tag=change, id=(null), magic=NA, cib=0.3.11) : Transient attribute change
Feb 21 18:22:52 bl460g1n6 stonith-ng[9099]: info: xml_patch_version_check: Applying patch 0.3.11 to 0.3.10
Feb 21 18:22:54 bl460g1n6 crmd[9103]: info: crm_timer_popped: New Transition Timer (I_PE_CALC) just popped (2000ms)
Feb 21 18:22:54 bl460g1n6 cib[9098]: info: cib_process_request: Completed cib_query operation for section 'all': OK (rc=0, origin=local/crmd/28, version=0.3.11)
Feb 21 18:22:54 bl460g1n6 pengine[9102]: notice: unpack_config: On loss of CCM Quorum: Ignore
Feb 21 18:22:54 bl460g1n6 pengine[9102]: info: determine_online_status: Node bl460g1n6 is shutting down
Feb 21 18:22:54 bl460g1n6 pengine[9102]: info: native_print: prmPing (ocf::pacemaker:ping): Started bl460g1n6
Feb 21 18:22:54 bl460g1n6 pengine[9102]: info: native_color: Resource prmPing cannot run anywhere
Feb 21 18:22:54 bl460g1n6 pengine[9102]: notice: stage6: Scheduling Node bl460g1n6 for shutdown
Feb 21 18:22:54 bl460g1n6 pengine[9102]: notice: LogActions: Stop prmPing (bl460g1n6)
Feb 21 18:22:54 bl460g1n6 pengine[9102]: notice: process_pe_message: Calculated Transition 2: /var/lib/pacemaker/pengine/pe-input-13.bz2
Feb 21 18:22:54 bl460g1n6 crmd[9103]: info: do_state_transition: State transition S_POLICY_ENGINE -> S_TRANSITION_ENGINE [ input=I_PE_SUCCESS cause=C_IPC_MESSAGE origin=handle_response ]
Feb 21 18:22:54 bl460g1n6 crmd[9103]: info: do_te_invoke: Processing graph 2 (ref=pe_calc-dc-1392974574-17) derived from /var/lib/pacemaker/pengine/pe-input-13.bz2
Feb 21 18:22:54 bl460g1n6 crmd[9103]: notice: te_rsc_command: Initiating action 5: stop prmPing_stop_0 on bl460g1n6 (local)
Feb 21 18:22:54 bl460g1n6 lrmd[9100]: info: services_action_cancel: Cancelling op: prmPing_monitor_30000 will occur once operation completes
Feb 21 18:22:54 bl460g1n6 crmd[9103]: info: do_lrm_rsc_op: Performing key=5:2:0:82c5fc6f-611d-4ba9-8a4f-13155b12536e op=prmPing_stop_0
Feb 21 18:22:54 bl460g1n6 lrmd[9100]: info: log_execute: executing - rsc:prmPing action:stop call_id:9
Feb 21 18:22:54 bl460g1n6 attrd[9101]: info: crm_client_new: Connecting 0x10f3670 for uid=0 gid=0 pid=9219 id=98453126-f091-46a9-b8be-83f98d8ec9a9
Feb 21 18:22:54 bl460g1n6 attrd[9101]: info: attrd_client_message: Broadcasting default_ping_set[bl460g1n6] = (null) (writer)
Feb 21 18:22:54 bl460g1n6 attrd[9101]: info: crm_client_destroy: Destroying 0 events
Feb 21 18:22:54 bl460g1n6 attrd[9101]: info: attrd_peer_update: Setting default_ping_set[bl460g1n6]: 100 -> (null) from bl460g1n6
Feb 21 18:22:54 bl460g1n6 lrmd[9100]: info: log_finished: finished - rsc:prmPing action:stop call_id:9 pid:9207 exit-code:0 exec-time:36ms queue-time:0ms
Feb 21 18:22:54 bl460g1n6 lrmd[9100]: info: services_action_cancel: Cancelling op: prmPing_monitor_30000 will occur once operation completes
Feb 21 18:22:54 bl460g1n6 crmd[9103]: notice: process_lrm_event: LRM operation prmPing_stop_0 (call=9, rc=0, cib-update=29, confirmed=true) ok
Feb 21 18:22:54 bl460g1n6 cib[9098]: info: cib_process_request: Forwarding cib_modify operation for section status to master (origin=local/crmd/29)
Feb 21 18:22:54 bl460g1n6 cib[9098]: info: cib_perform_op: + @num_updates=12
Feb 21 18:22:54 bl460g1n6 cib[9098]: info: cib_perform_op: +
Feb 21 18:22:54 bl460g1n6 cib[9098]: info: cib_perform_op: +
Feb 21 18:22:54 bl460g1n6 cib[9098]: info: cib_perform_op: +
Feb 21 18:22:54 bl460g1n6 cib[9098]: info: cib_perform_op: +
Feb 21 18:22:54 bl460g1n6 cib[9098]: info: cib_perform_op: +
Feb 21 18:22:54 bl460g1n6 cib[9098]: info: cib_perform_op: +
Feb 21 18:22:54 bl460g1n6 cib[9098]: info: cib_perform_op: + @operation_key=prmPing_stop_0
Feb 21 18:22:54 bl460g1n6 cib[9098]: info: cib_perform_op: + @operation=stop
Feb 21 18:22:54 bl460g1n6 cib[9098]: info: cib_perform_op: + @transition-key=5:2:0:82c5fc6f-611d-4ba9-8a4f-13155b12536e
Feb 21 18:22:54 bl460g1n6 cib[9098]: info: cib_perform_op: + @transition-magic=0:0;5:2:0:82c5fc6f-611d-4ba9-8a4f-13155b12536e
Feb 21 18:22:54 bl460g1n6 cib[9098]: info: cib_perform_op: + @call-id=9
Feb 21 18:22:54 bl460g1n6 cib[9098]: info: cib_perform_op: + @last-run=1392974574
Feb 21 18:22:54 bl460g1n6 cib[9098]: info: cib_perform_op: + @last-rc-change=1392974574
Feb 21 18:22:54 bl460g1n6 cib[9098]: info: cib_perform_op: + @exec-time=36
Feb 21 18:22:54 bl460g1n6 cib[9098]: info: cib_perform_op: +
Feb 21 18:22:54 bl460g1n6 cib[9098]: info: cib_perform_op: +
Feb 21 18:22:54 bl460g1n6 cib[9098]: info: cib_perform_op: +
Feb 21 18:22:54 bl460g1n6 cib[9098]: info: cib_perform_op: +
Feb 21 18:22:54 bl460g1n6 cib[9098]: info: cib_perform_op: +
Feb 21 18:22:54 bl460g1n6 cib[9098]: info: cib_perform_op: +
Feb 21 18:22:54 bl460g1n6 cib[9098]: info: cib_perform_op: +
Feb 21 18:22:54 bl460g1n6 cib[9098]: info: cib_process_request: Completed cib_modify operation for section status: OK (rc=0, origin=bl460g1n6/crmd/29, version=0.3.12)
Feb 21 18:22:54 bl460g1n6 crmd[9103]: info: match_graph_event: Action prmPing_stop_0 (5) confirmed on bl460g1n6 (rc=0)
Feb 21 18:22:54 bl460g1n6 crmd[9103]: info: te_crm_command: Executing crm-event (7): do_shutdown on bl460g1n6
Feb 21 18:22:54 bl460g1n6 crmd[9103]: info: te_crm_command: crm-event (7) is a local shutdown
Feb 21 18:22:54 bl460g1n6 crmd[9103]: notice: run_graph: Transition 2 (Complete=3, Pending=0, Fired=0, Skipped=0, Incomplete=0, Source=/var/lib/pacemaker/pengine/pe-input-13.bz2): Complete
Feb 21 18:22:54 bl460g1n6 stonith-ng[9099]: info: xml_patch_version_check: Applying patch 0.3.12 to 0.3.11
Feb 21 18:22:54 bl460g1n6 crmd[9103]: info: do_log: FSA: Input I_STOP from notify_crmd() received in state S_TRANSITION_ENGINE
Feb 21 18:22:54 bl460g1n6 crmd[9103]: info: do_state_transition: State transition S_TRANSITION_ENGINE -> S_STOPPING [ input=I_STOP cause=C_FSA_INTERNAL origin=notify_crmd ]
Feb 21 18:22:54 bl460g1n6 crmd[9103]: info: do_dc_release: DC role released
Feb 21 18:22:54 bl460g1n6 crmd[9103]: info: crm_update_peer_expected: do_dc_release: Node bl460g1n6[3232261592] - expected state is now down (was member)
Feb 21 18:22:54 bl460g1n6 cib[9098]: info: cib_process_request: Forwarding cib_modify operation for section status to master (origin=local/crmd/30)
Feb 21 18:22:54 bl460g1n6 pengine[9102]: info: crm_client_destroy: Destroying 0 events
Feb 21 18:22:54 bl460g1n6 crmd[9103]: info: pe_ipc_destroy: Connection to the Policy Engine released
Feb 21 18:22:54 bl460g1n6 cib[9098]: info: cib_perform_op: + @num_updates=13
Feb 21 18:22:54 bl460g1n6 cib[9098]: info: cib_perform_op: +
Feb 21 18:22:54 bl460g1n6 cib[9098]: info: cib_perform_op: +
Feb 21 18:22:54 bl460g1n6 cib[9098]: info: cib_perform_op: + @crm-debug-origin=do_dc_release
Feb 21 18:22:54 bl460g1n6 cib[9098]: info: cib_perform_op: + @expected=down
Feb 21 18:22:54 bl460g1n6 cib[9098]: info: cib_perform_op: +
Feb 21 18:22:54 bl460g1n6 cib[9098]: info: cib_perform_op: +
Feb 21 18:22:54 bl460g1n6 cib[9098]: info: cib_perform_op: +
Feb 21 18:22:54 bl460g1n6 cib[9098]: info: cib_perform_op: +
Feb 21 18:22:54 bl460g1n6 stonith-ng[9099]: info: xml_patch_version_check: Applying patch 0.3.13 to 0.3.12
Feb 21 18:22:54 bl460g1n6 cib[9098]: info: cib_process_request: Completed cib_modify operation for section status: OK (rc=0, origin=bl460g1n6/crmd/30, version=0.3.13)
Feb 21 18:22:54 bl460g1n6 crmd[9103]: info: do_te_control: Transitioner is now inactive
Feb 21 18:22:54 bl460g1n6 crmd[9103]: info: do_shutdown: Disconnecting STONITH...
Feb 21 18:22:54 bl460g1n6 stonith-ng[9099]: info: crm_client_destroy: Destroying 0 events
Feb 21 18:22:54 bl460g1n6 crmd[9103]: info: tengine_stonith_connection_destroy: Fencing daemon disconnected
Feb 21 18:22:54 bl460g1n6 crmd[9103]: info: stop_recurring_actions: Cancelling op 7 for prmPing (prmPing:7)
Feb 21 18:22:54 bl460g1n6 crmd[9103]: notice: lrm_state_verify_stopped: Stopped 1 recurring operations at shutdown... waiting (0 ops remaining)
Feb 21 18:22:54 bl460g1n6 crmd[9103]: info: do_lrm_control: Disconnecting from the LRM
Feb 21 18:22:54 bl460g1n6 crmd[9103]: info: lrmd_api_disconnect: Disconnecting from lrmd service
Feb 21 18:22:54 bl460g1n6 lrmd[9100]: info: services_action_cancel: Cancelling op: prmPing_monitor_30000 will occur once operation completes
Feb 21 18:22:54 bl460g1n6 lrmd[9100]: info: crm_client_destroy: Destroying 0 events
Feb 21 18:22:54 bl460g1n6 crmd[9103]: info: lrmd_ipc_connection_destroy: IPC connection destroyed
Feb 21 18:22:54 bl460g1n6 crmd[9103]: info: lrm_connection_destroy: LRM Connection disconnected
Feb 21 18:22:54 bl460g1n6 crmd[9103]: info: lrmd_api_disconnect: Disconnecting from lrmd service
Feb 21 18:22:54 bl460g1n6 crmd[9103]: notice: do_lrm_control: Disconnected from the LRM
Feb 21 18:22:54 bl460g1n6 crmd[9103]: info: crm_cluster_disconnect: Disconnecting from cluster infrastructure: corosync
Feb 21 18:22:54 bl460g1n6 crmd[9103]: notice: terminate_cs_connection: Disconnecting from Corosync
Feb 21 18:22:54 bl460g1n6 crmd[9103]: info: crm_cluster_disconnect: Disconnected from corosync
Feb 21 18:22:54 bl460g1n6 crmd[9103]: info: do_ha_control: Disconnected from the cluster
Feb 21 18:22:54 bl460g1n6 crmd[9103]: info: do_cib_control: Disconnecting CIB
Feb 21 18:22:54 bl460g1n6 cib[9098]: info: cib_process_readwrite: We are now in R/O mode
Feb 21 18:22:54 bl460g1n6 cib[9098]: info: cib_process_request: Completed cib_slave operation for section 'all': OK (rc=0, origin=local/crmd/31, version=0.3.13)
Feb 21 18:22:54 bl460g1n6 cib[9098]: warning: qb_ipcs_event_sendv: new_event_notification (9098-9103-9): Broken pipe (32)
Feb 21 18:22:54 bl460g1n6 cib[9098]: warning: do_local_notify: A-Sync reply to crmd failed: No message of desired type
Feb 21 18:22:54 bl460g1n6 cib[9098]: info: crm_client_destroy: Destroying 1 events
Feb 21 18:22:54 bl460g1n6 crmd[9103]: info: crmd_cib_connection_destroy: Connection to the CIB terminated...
Feb 21 18:22:54 bl460g1n6 crmd[9103]: info: qb_ipcs_us_withdraw: withdrawing server sockets
Feb 21 18:22:54 bl460g1n6 crmd[9103]: info: do_exit: Performing A_EXIT_0 - gracefully exiting the CRMd
Feb 21 18:22:54 bl460g1n6 crmd[9103]: info: do_exit: [crmd] stopped (0)
Feb 21 18:22:54 bl460g1n6 attrd[9101]: info: crm_client_destroy: Destroying 0 events
Feb 21 18:22:54 bl460g1n6 crmd[9103]: info: crmd_exit: Dropping I_RELEASE_SUCCESS: [ state=S_STOPPING cause=C_FSA_INTERNAL origin=do_dc_release ]
Feb 21 18:22:54 bl460g1n6 crmd[9103]: info: crmd_exit: Dropping I_TERMINATE: [ state=S_STOPPING cause=C_FSA_INTERNAL origin=do_stop ]
Feb 21 18:22:54 bl460g1n6 crmd[9103]: info: crmd_quorum_destroy: connection closed
Feb 21 18:22:54 bl460g1n6 crmd[9103]: info: crmd_cs_destroy: connection closed
Feb 21 18:22:54 bl460g1n6 crmd[9103]: info: crmd_init: 9103 stopped: OK (0)
Feb 21 18:22:54 bl460g1n6 crmd[9103]: info: crm_xml_cleanup: Cleaning up memory from libxml2
Feb 21 18:22:54 bl460g1n6 pacemakerd[9094]: info: pcmk_child_exit: Child process crmd (9103) exited: OK (0)
Feb 21 18:22:54 bl460g1n6 pacemakerd[9094]: notice: stop_child: Stopping pengine: Sent -15 to process 9102
Feb 21 18:22:54 bl460g1n6 pengine[9102]: info: crm_signal_dispatch: Invoking handler for signal 15: Terminated
Feb 21 18:22:54 bl460g1n6 pengine[9102]: info: qb_ipcs_us_withdraw: withdrawing server sockets
Feb 21 18:22:54 bl460g1n6 pengine[9102]: info: crm_xml_cleanup: Cleaning up memory from libxml2
Feb 21 18:22:54 bl460g1n6 pacemakerd[9094]: info: pcmk_child_exit: Child process pengine (9102) exited: OK (0)
Feb 21 18:22:54 bl460g1n6 pacemakerd[9094]: notice: stop_child: Stopping attrd: Sent -15 to process 9101
Feb 21 18:22:54 bl460g1n6 attrd[9101]: info: crm_signal_dispatch: Invoking handler for signal 15: Terminated
Feb 21 18:22:54 bl460g1n6 attrd[9101]: info: attrd_shutdown: Shutting down
Feb 21 18:22:54 bl460g1n6 attrd[9101]: notice: main: Cleaning up before exit
Feb 21 18:22:54 bl460g1n6 attrd[9101]: info: qb_ipcs_us_withdraw: withdrawing server sockets
Feb 21 18:22:54 bl460g1n6 cib[9098]: info: crm_client_destroy: Destroying 0 events
Feb 21 18:22:54 bl460g1n6 attrd[9101]: info: attrd_cib_destroy_cb: Connection disconnection complete
Feb 21 18:22:54 bl460g1n6 attrd[9101]: info: crm_xml_cleanup: Cleaning up memory from libxml2
Feb 21 18:22:54 bl460g1n6 pacemakerd[9094]: info: pcmk_child_exit: Child process attrd (9101) exited: OK (0)
Feb 21 18:22:54 bl460g1n6 pacemakerd[9094]: notice: stop_child: Stopping lrmd: Sent -15 to process 9100
Feb 21 18:22:54 bl460g1n6 lrmd[9100]: info: crm_signal_dispatch: Invoking handler for signal 15: Terminated
Feb 21 18:22:54 bl460g1n6 lrmd[9100]: info: lrmd_shutdown: Terminating with 0 clients
Feb 21 18:22:54 bl460g1n6 lrmd[9100]: info: qb_ipcs_us_withdraw: withdrawing server sockets
Feb 21 18:22:54 bl460g1n6 lrmd[9100]: info: crm_xml_cleanup: Cleaning up memory from libxml2
Feb 21 18:22:54 bl460g1n6 pacemakerd[9094]: info: pcmk_child_exit: Child process lrmd (9100) exited: OK (0)
Feb 21 18:22:54 bl460g1n6 pacemakerd[9094]: notice: stop_child: Stopping stonith-ng: Sent -15 to process 9099
Feb 21 18:22:54 bl460g1n6 stonith-ng[9099]: info: crm_signal_dispatch: Invoking handler for signal 15: Terminated
Feb 21 18:22:54 bl460g1n6 stonith-ng[9099]: info: stonith_shutdown: Terminating with 0 clients
Feb 21 18:22:54 bl460g1n6 cib[9098]: info: crm_client_destroy: Destroying 0 events
Feb 21 18:22:54 bl460g1n6 stonith-ng[9099]: info: cib_connection_destroy: Connection to the CIB closed.
Feb 21 18:22:54 bl460g1n6 stonith-ng[9099]: info: qb_ipcs_us_withdraw: withdrawing server sockets
Feb 21 18:22:54 bl460g1n6 stonith-ng[9099]: info: main: Done
Feb 21 18:22:54 bl460g1n6 stonith-ng[9099]: info: crm_xml_cleanup: Cleaning up memory from libxml2
Feb 21 18:22:54 bl460g1n6 pacemakerd[9094]: info: pcmk_child_exit: Child process stonith-ng (9099) exited: OK (0)
Feb 21 18:22:54 bl460g1n6 pacemakerd[9094]: notice: stop_child: Stopping cib: Sent -15 to process 9098
Feb 21 18:22:54 bl460g1n6 cib[9098]: info: crm_signal_dispatch: Invoking handler for signal 15: Terminated
Feb 21 18:22:54 bl460g1n6 cib[9098]: info: crm_client_destroy: Destroying 0 events
Feb 21 18:22:54 bl460g1n6 cib[9098]: info: cib_shutdown: All clients disconnected (0)
Feb 21 18:22:54 bl460g1n6 cib[9098]: info: terminate_cib: initiate_exit: Disconnecting from cluster infrastructure
Feb 21 18:22:54 bl460g1n6 cib[9098]: info: crm_cluster_disconnect: Disconnecting from cluster infrastructure: corosync
Feb 21 18:22:54 bl460g1n6 cib[9098]: notice: terminate_cs_connection: Disconnecting from Corosync
Feb 21 18:22:54 bl460g1n6 cib[9098]: info: terminate_cs_connection: No Quorum connection
Feb 21 18:22:54 bl460g1n6 cib[9098]: info: crm_cluster_disconnect: Disconnected from corosync
Feb 21 18:22:54 bl460g1n6 cib[9098]: info: terminate_cib: initiate_exit: Exiting from mainloop...
Feb 21 18:22:54 bl460g1n6 cib[9098]: info: cib_shutdown: Disconnected 1 clients
Feb 21 18:22:54 bl460g1n6 cib[9098]: info: cib_shutdown: All clients disconnected (0)
Feb 21 18:22:54 bl460g1n6 cib[9098]: info: terminate_cib: initiate_exit: Disconnecting from cluster infrastructure
Feb 21 18:22:54 bl460g1n6 cib[9098]: info: crm_cluster_disconnect: Disconnecting from cluster infrastructure: corosync
Feb 21 18:22:54 bl460g1n6 cib[9098]: notice: terminate_cs_connection: Disconnecting from Corosync
Feb 21 18:22:54 bl460g1n6 cib[9098]: info: cluster_disconnect_cpg: No CPG connection
Feb 21 18:22:54 bl460g1n6 cib[9098]: info: terminate_cs_connection: No Quorum connection
Feb 21 18:22:54 bl460g1n6 cib[9098]: info: crm_cluster_disconnect: Disconnected from corosync
Feb 21 18:22:54 bl460g1n6 cib[9098]: info: terminate_cib: initiate_exit: Exiting from mainloop...
Feb 21 18:22:54 bl460g1n6 cib[9098]: info: qb_ipcs_us_withdraw: withdrawing server sockets
Feb 21 18:22:54 bl460g1n6 cib[9098]: info: qb_ipcs_us_withdraw: withdrawing server sockets
Feb 21 18:22:54 bl460g1n6 cib[9098]: info: qb_ipcs_us_withdraw: withdrawing server sockets
Feb 21 18:22:54 bl460g1n6 cib[9098]: info: crm_xml_cleanup: Cleaning up memory from libxml2
Feb 21 18:22:54 bl460g1n6 pacemakerd[9094]: info: pcmk_child_exit: Child process cib (9098) exited: OK (0)
Feb 21 18:22:54 bl460g1n6 pacemakerd[9094]: notice: pcmk_shutdown_worker: Shutdown complete
Feb 21 18:22:54 bl460g1n6 pacemakerd[9094]: info: qb_ipcs_us_withdraw: withdrawing server sockets
Feb 21 18:22:54 bl460g1n6 pacemakerd[9094]: info: main: Exiting pacemakerd
Feb 21 18:22:54 bl460g1n6 pacemakerd[9094]: info: crm_xml_cleanup: Cleaning up memory from libxml2
Feb 21 18:22:54 bl460g1n6 corosync[9083]: [MAIN ] Node was shut down by a signal
Feb 21 18:22:54 bl460g1n6 corosync[9083]: [SERV ] Unloading all Corosync service engines.
Feb 21 18:22:54 bl460g1n6 corosync[9083]: [QB ] withdrawing server sockets
Feb 21 18:22:54 bl460g1n6 corosync[9083]: [SERV ] Service engine unloaded: corosync vote quorum service v1.0
Feb 21 18:22:54 bl460g1n6 corosync[9083]: [QB ] withdrawing server sockets
Feb 21 18:22:54 bl460g1n6 corosync[9083]: [SERV ] Service engine unloaded: corosync configuration map access
Feb 21 18:22:54 bl460g1n6 corosync[9083]: [QB ] withdrawing server sockets
Feb 21 18:22:54 bl460g1n6 corosync[9083]: [SERV ] Service engine unloaded: corosync configuration service
Feb 21 18:22:54 bl460g1n6 corosync[9083]: [QB ] withdrawing server sockets
Feb 21 18:22:54 bl460g1n6 corosync[9083]: [SERV ] Service engine unloaded: corosync cluster closed process group service v1.01
Feb 21 18:22:55 bl460g1n6 corosync[9083]: [QB ] withdrawing server sockets
Feb 21 18:22:55 bl460g1n6 corosync[9083]: [SERV ] Service engine unloaded: corosync cluster quorum service v0.1
Feb 21 18:22:55 bl460g1n6 corosync[9083]: [SERV ] Service engine unloaded: corosync profile loading service
Feb 21 18:22:55 bl460g1n6 corosync[9083]: [WD ] magically closing the watchdog.
Feb 21 18:22:55 bl460g1n6 corosync[9083]: [SERV ] Service engine unloaded: corosync watchdog service
Feb 21 18:22:55 bl460g1n6 corosync[9083]: [MAIN ] Corosync Cluster Engine exiting normally
Feb 21 18:22:57 bl460g1n6 ping(prmPing)[9195]: WARNING: Could not update default_ping_set = 100: rc=141