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