[ClusterLabs] trouble with IPaddr2

Stefan Krueger Shadow_7 at gmx.net
Wed Oct 11 04:51:04 EDT 2017 

Hello,

I've a simple setup with just 3 resources (at the moment), the ZFS resource also works fine.BUT my IPaddr2 don't work, and I dont know why and how to resolve that.

my config:
conf sh
node 739272007: zfs-serv1
node 739272008: zfs-serv2
primitive HA_IP-Serv1 IPaddr2 \
        params ip=172.16.101.70 cidr_netmask=16 \
        op monitor interval=20 timeout=30 on-fail=restart nic=bond0 \
        meta target-role=Started
primitive HA_IP-Serv2 IPaddr2 \
        params ip=172.16.101.74 cidr_netmask=16 \
        op monitor interval=10s nic=bond0
primitive nc_storage ZFS \
        params pool=nc_storage importargs="-d /dev/disk/by-partlabel/"
location cli-prefer-HA_IP-Serv1 HA_IP-Serv1 role=Started inf: zfs-serv1
location cli-prefer-HA_IP-Serv2 HA_IP-Serv2 role=Started inf: zfs-serv2
location cli-prefer-nc_storage nc_storage role=Started inf: zfs-serv2
property cib-bootstrap-options: \
        have-watchdog=false \
        dc-version=1.1.16-94ff4df \
        cluster-infrastructure=corosync \
        cluster-name=debian \
        no-quorum-policy=ignore \
        default-resource-stickiness=100 \
        stonith-enabled=false \
        last-lrm-refresh=1507702403


command:
resource move HA_IP-Serv1 zfs-serv2


pacemaker log from zfs-serv2:
Oct 11 08:19:32 [23933] zfs-serv2        cib:     info: cib_process_request:	Completed cib_delete operation for section constraints: OK (rc=0, origin=zfs-serv1/crm_resource/3, version=0.82.44)
Oct 11 08:19:32 [23933] zfs-serv2        cib:     info: cib_perform_op:	Diff: --- 0.82.44 2
Oct 11 08:19:32 [23933] zfs-serv2        cib:     info: cib_perform_op:	Diff: +++ 0.83.0 (null)
Oct 11 08:19:32 [23933] zfs-serv2        cib:     info: cib_perform_op:	+  /cib:  @epoch=83, @num_updates=0
Oct 11 08:19:32 [23933] zfs-serv2        cib:     info: cib_perform_op:	+  /cib/configuration/constraints/rsc_location[@id='cli-prefer-HA_IP-Serv1']:  @node=zfs-serv2
Oct 11 08:19:32 [23933] zfs-serv2        cib:     info: cib_process_request:	Completed cib_modify operation for section constraints: OK (rc=0, origin=zfs-serv1/crm_resource/4, version=0.83.0)
Oct 11 08:19:32 [23933] zfs-serv2        cib:     info: cib_perform_op:	Diff: --- 0.83.0 2
Oct 11 08:19:32 [23933] zfs-serv2        cib:     info: cib_perform_op:	Diff: +++ 0.83.1 (null)
Oct 11 08:19:32 [23933] zfs-serv2        cib:     info: cib_perform_op:	+  /cib:  @num_updates=1
Oct 11 08:19:32 [23933] zfs-serv2        cib:     info: cib_perform_op:	+  /cib/status/node_state[@id='739272007']/lrm[@id='739272007']/lrm_resources/lrm_resource[@id='HA_IP-Serv1']/lrm_rsc_op[@id='HA_IP-Serv1_last_0']:  @operation_key=HA_IP-Serv1_stop_0, @operation=stop, @crm-debug-origin=do_update_resource, @transition-key=8:1574:0:d4b03c3c-1a4e-4609-86ca-675fa4a2ec8f, @transition-magic=0:0;8:1574:0:d4b03c3c-1a4e-4609-86ca-675fa4a2ec8f, @call-id=55, @last-run=1507702772, @last-rc-change=1507702772, @exec
Oct 11 08:19:32 [23933] zfs-serv2        cib:     info: cib_process_request:	Completed cib_modify operation for section status: OK (rc=0, origin=zfs-serv1/crmd/1853, version=0.83.1)
Oct 11 08:19:32 [23938] zfs-serv2       crmd:     info: do_lrm_rsc_op:	Performing key=9:1574:0:d4b03c3c-1a4e-4609-86ca-675fa4a2ec8f op=HA_IP-Serv1_start_0
Oct 11 08:19:32 [23935] zfs-serv2       lrmd:     info: log_execute:	executing - rsc:HA_IP-Serv1 action:start call_id:17
Oct 11 08:19:32 [23933] zfs-serv2        cib:     info: cib_file_backup:	Archived previous version as /var/lib/pacemaker/cib/cib-39.raw
Oct 11 08:19:32 [23935] zfs-serv2       lrmd:     info: log_finished:	finished - rsc:HA_IP-Serv1 action:start call_id:17 pid:27672 exit-code:0 exec-time:48ms queue-time:0ms
Oct 11 08:19:32 [23938] zfs-serv2       crmd:     info: action_synced_wait:	Managed IPaddr2_meta-data_0 process 27735 exited with rc=0
Oct 11 08:19:32 [23938] zfs-serv2       crmd:   notice: process_lrm_event:	Result of start operation for HA_IP-Serv1 on zfs-serv2: 0 (ok) | call=17 key=HA_IP-Serv1_start_0 confirmed=true cib-update=15
Oct 11 08:19:32 [23933] zfs-serv2        cib:     info: cib_process_request:	Forwarding cib_modify operation for section status to all (origin=local/crmd/15)
Oct 11 08:19:32 [23933] zfs-serv2        cib:     info: cib_perform_op:	Diff: --- 0.83.1 2
Oct 11 08:19:32 [23933] zfs-serv2        cib:     info: cib_perform_op:	Diff: +++ 0.83.2 (null)
Oct 11 08:19:32 [23933] zfs-serv2        cib:     info: cib_perform_op:	+  /cib:  @num_updates=2
Oct 11 08:19:32 [23933] zfs-serv2        cib:     info: cib_perform_op:	+  /cib/status/node_state[@id='739272008']/lrm[@id='739272008']/lrm_resources/lrm_resource[@id='HA_IP-Serv1']/lrm_rsc_op[@id='HA_IP-Serv1_last_0']:  @operation_key=HA_IP-Serv1_start_0, @operation=start, @transition-key=9:1574:0:d4b03c3c-1a4e-4609-86ca-675fa4a2ec8f, @transition-magic=0:0;9:1574:0:d4b03c3c-1a4e-4609-86ca-675fa4a2ec8f, @call-id=17, @rc-code=0, @last-run=1507702772, @last-rc-change=1507702772, @exec-time=48
Oct 11 08:19:32 [23933] zfs-serv2        cib:     info: cib_process_request:	Completed cib_modify operation for section status: OK (rc=0, origin=zfs-serv2/crmd/15, version=0.83.2)
Oct 11 08:19:32 [23938] zfs-serv2       crmd:     info: do_lrm_rsc_op:	Performing key=10:1574:0:d4b03c3c-1a4e-4609-86ca-675fa4a2ec8f op=HA_IP-Serv1_monitor_10000
Oct 11 08:19:32 [23938] zfs-serv2       crmd:     info: process_lrm_event:	Result of monitor operation for HA_IP-Serv1 on zfs-serv2: 7 (not running) | call=18 key=HA_IP-Serv1_monitor_10000 confirmed=false cib-update=16
Oct 11 08:19:32 [23933] zfs-serv2        cib:     info: cib_process_request:	Forwarding cib_modify operation for section status to all (origin=local/crmd/16)
Oct 11 08:19:32 [23933] zfs-serv2        cib:     info: cib_perform_op:	Diff: --- 0.83.2 2
Oct 11 08:19:32 [23933] zfs-serv2        cib:     info: cib_perform_op:	Diff: +++ 0.83.3 (null)
Oct 11 08:19:32 [23933] zfs-serv2        cib:     info: cib_perform_op:	+  /cib:  @num_updates=3
Oct 11 08:19:32 [23933] zfs-serv2        cib:     info: cib_perform_op:	++ /cib/status/node_state[@id='739272008']/lrm[@id='739272008']/lrm_resources/lrm_resource[@id='HA_IP-Serv1']:  <lrm_rsc_op id="HA_IP-Serv1_last_failure_0" operation_key="HA_IP-Serv1_monitor_10000" operation="monitor" crm-debug-origin="do_update_resource" crm_feature_set="3.0.11" transition-key="10:1574:0:d4b03c3c-1a4e-4609-86ca-675fa4a2ec8f" transition-magic="0:7;10:1574:0:d4b03c3c-1a4e-4609-86ca-675fa4a2ec8f" on_node="zfs-serv2" call
Oct 11 08:19:32 [23933] zfs-serv2        cib:     info: cib_process_request:	Completed cib_modify operation for section status: OK (rc=0, origin=zfs-serv2/crmd/16, version=0.83.3)
Oct 11 08:19:32 [23936] zfs-serv2      attrd:     info: attrd_peer_update:	Setting fail-count-HA_IP-Serv1[zfs-serv2]: (null) -> 1 from zfs-serv1
Oct 11 08:19:32 [23936] zfs-serv2      attrd:     info: attrd_peer_update:	Setting last-failure-HA_IP-Serv1[zfs-serv2]: (null) -> 1507702772 from zfs-serv1
Oct 11 08:19:32 [23933] zfs-serv2        cib:     info: cib_perform_op:	Diff: --- 0.83.3 2
Oct 11 08:19:32 [23933] zfs-serv2        cib:     info: cib_perform_op:	Diff: +++ 0.83.4 (null)
Oct 11 08:19:32 [23933] zfs-serv2        cib:     info: cib_perform_op:	+  /cib:  @num_updates=4
Oct 11 08:19:32 [23933] zfs-serv2        cib:     info: cib_perform_op:	++ /cib/status/node_state[@id='739272008']/transient_attributes[@id='739272008']/instance_attributes[@id='status-739272008']:  <nvpair id="status-739272008-fail-count-HA_IP-Serv1" name="fail-count-HA_IP-Serv1" value="1"/>
Oct 11 08:19:32 [23933] zfs-serv2        cib:     info: cib_process_request:	Completed cib_modify operation for section status: OK (rc=0, origin=zfs-serv1/attrd/912, version=0.83.4)
Oct 11 08:19:32 [23933] zfs-serv2        cib:     info: cib_perform_op:	Diff: --- 0.83.4 2
Oct 11 08:19:32 [23933] zfs-serv2        cib:     info: cib_perform_op:	Diff: +++ 0.83.5 (null)
Oct 11 08:19:32 [23933] zfs-serv2        cib:     info: cib_perform_op:	+  /cib:  @num_updates=5
Oct 11 08:19:32 [23933] zfs-serv2        cib:     info: cib_perform_op:	++ /cib/status/node_state[@id='739272008']/transient_attributes[@id='739272008']/instance_attributes[@id='status-739272008']:  <nvpair id="status-739272008-last-failure-HA_IP-Serv1" name="last-failure-HA_IP-Serv1" value="1507702772"/>
Oct 11 08:19:32 [23933] zfs-serv2        cib:     info: cib_process_request:	Completed cib_modify operation for section status: OK (rc=0, origin=zfs-serv1/attrd/913, version=0.83.5)
Oct 11 08:19:32 [23933] zfs-serv2        cib:     info: cib_file_write_with_digest:	Wrote version 0.83.0 of the CIB to disk (digest: 268117dddecb878f1ddc928a082f0895)
Oct 11 08:19:32 [23933] zfs-serv2        cib:     info: cib_file_write_with_digest:	Reading cluster configuration file /var/lib/pacemaker/cib/cib.hyrqet (digest: /var/lib/pacemaker/cib/cib.C5oeAv)
Oct 11 08:19:32 [23935] zfs-serv2       lrmd:     info: cancel_recurring_action:	Cancelling ocf operation HA_IP-Serv1_monitor_10000
Oct 11 08:19:32 [23938] zfs-serv2       crmd:     info: do_lrm_rsc_op:	Performing key=3:1576:0:d4b03c3c-1a4e-4609-86ca-675fa4a2ec8f op=HA_IP-Serv1_stop_0
Oct 11 08:19:32 [23935] zfs-serv2       lrmd:     info: log_execute:	executing - rsc:HA_IP-Serv1 action:stop call_id:20
Oct 11 08:19:32 [23938] zfs-serv2       crmd:     info: process_lrm_event:	Result of monitor operation for HA_IP-Serv1 on zfs-serv2: Cancelled | call=18 key=HA_IP-Serv1_monitor_10000 confirmed=true
Oct 11 08:19:32 [23935] zfs-serv2       lrmd:     info: log_finished:	finished - rsc:HA_IP-Serv1 action:stop call_id:20 pid:27780 exit-code:0 exec-time:41ms queue-time:0ms
Oct 11 08:19:32 [23938] zfs-serv2       crmd:   notice: process_lrm_event:	Result of stop operation for HA_IP-Serv1 on zfs-serv2: 0 (ok) | call=20 key=HA_IP-Serv1_stop_0 confirmed=true cib-update=17
Oct 11 08:19:32 [23933] zfs-serv2        cib:     info: cib_process_request:	Forwarding cib_modify operation for section status to all (origin=local/crmd/17)
Oct 11 08:19:32 [23933] zfs-serv2        cib:     info: cib_perform_op:	Diff: --- 0.83.5 2
Oct 11 08:19:32 [23933] zfs-serv2        cib:     info: cib_perform_op:	Diff: +++ 0.83.6 (null)
Oct 11 08:19:32 [23933] zfs-serv2        cib:     info: cib_perform_op:	+  /cib:  @num_updates=6
Oct 11 08:19:32 [23933] zfs-serv2        cib:     info: cib_perform_op:	+  /cib/status/node_state[@id='739272008']/lrm[@id='739272008']/lrm_resources/lrm_resource[@id='HA_IP-Serv1']/lrm_rsc_op[@id='HA_IP-Serv1_last_0']:  @operation_key=HA_IP-Serv1_stop_0, @operation=stop, @transition-key=3:1576:0:d4b03c3c-1a4e-4609-86ca-675fa4a2ec8f, @transition-magic=0:0;3:1576:0:d4b03c3c-1a4e-4609-86ca-675fa4a2ec8f, @call-id=20, @exec-time=41
Oct 11 08:19:32 [23933] zfs-serv2        cib:     info: cib_process_request:	Completed cib_modify operation for section status: OK (rc=0, origin=zfs-serv2/crmd/17, version=0.83.6)
Oct 11 08:19:32 [23938] zfs-serv2       crmd:     info: do_lrm_rsc_op:	Performing key=9:1576:0:d4b03c3c-1a4e-4609-86ca-675fa4a2ec8f op=HA_IP-Serv1_start_0
Oct 11 08:19:32 [23935] zfs-serv2       lrmd:     info: log_execute:	executing - rsc:HA_IP-Serv1 action:start call_id:21
Oct 11 08:19:32 [23935] zfs-serv2       lrmd:     info: log_finished:	finished - rsc:HA_IP-Serv1 action:start call_id:21 pid:27833 exit-code:0 exec-time:46ms queue-time:0ms
Oct 11 08:19:32 [23938] zfs-serv2       crmd:     info: action_synced_wait:	Managed IPaddr2_meta-data_0 process 27896 exited with rc=0
Oct 11 08:19:32 [23938] zfs-serv2       crmd:   notice: process_lrm_event:	Result of start operation for HA_IP-Serv1 on zfs-serv2: 0 (ok) | call=21 key=HA_IP-Serv1_start_0 confirmed=true cib-update=18
Oct 11 08:19:32 [23933] zfs-serv2        cib:     info: cib_process_request:	Forwarding cib_modify operation for section status to all (origin=local/crmd/18)
Oct 11 08:19:32 [23933] zfs-serv2        cib:     info: cib_perform_op:	Diff: --- 0.83.6 2
Oct 11 08:19:32 [23933] zfs-serv2        cib:     info: cib_perform_op:	Diff: +++ 0.83.7 (null)
Oct 11 08:19:32 [23933] zfs-serv2        cib:     info: cib_perform_op:	+  /cib:  @num_updates=7
Oct 11 08:19:32 [23933] zfs-serv2        cib:     info: cib_perform_op:	+  /cib/status/node_state[@id='739272008']/lrm[@id='739272008']/lrm_resources/lrm_resource[@id='HA_IP-Serv1']/lrm_rsc_op[@id='HA_IP-Serv1_last_0']:  @operation_key=HA_IP-Serv1_start_0, @operation=start, @transition-key=9:1576:0:d4b03c3c-1a4e-4609-86ca-675fa4a2ec8f, @transition-magic=0:0;9:1576:0:d4b03c3c-1a4e-4609-86ca-675fa4a2ec8f, @call-id=21, @exec-time=46
Oct 11 08:19:32 [23933] zfs-serv2        cib:     info: cib_process_request:	Completed cib_modify operation for section status: OK (rc=0, origin=zfs-serv2/crmd/18, version=0.83.7)
Oct 11 08:19:32 [23938] zfs-serv2       crmd:     info: do_lrm_rsc_op:	Performing key=2:1576:0:d4b03c3c-1a4e-4609-86ca-675fa4a2ec8f op=HA_IP-Serv1_monitor_10000
Oct 11 08:19:32 [23938] zfs-serv2       crmd:     info: process_lrm_event:	Result of monitor operation for HA_IP-Serv1 on zfs-serv2: 7 (not running) | call=22 key=HA_IP-Serv1_monitor_10000 confirmed=false cib-update=19
Oct 11 08:19:32 [23933] zfs-serv2        cib:     info: cib_process_request:	Forwarding cib_modify operation for section status to all (origin=local/crmd/19)
Oct 11 08:19:32 [23933] zfs-serv2        cib:     info: cib_perform_op:	Diff: --- 0.83.7 2
Oct 11 08:19:32 [23933] zfs-serv2        cib:     info: cib_perform_op:	Diff: +++ 0.83.8 (null)
Oct 11 08:19:32 [23933] zfs-serv2        cib:     info: cib_perform_op:	+  /cib:  @num_updates=8
Oct 11 08:19:32 [23933] zfs-serv2        cib:     info: cib_perform_op:	+  /cib/status/node_state[@id='739272008']/lrm[@id='739272008']/lrm_resources/lrm_resource[@id='HA_IP-Serv1']/lrm_rsc_op[@id='HA_IP-Serv1_last_failure_0']:  @transition-key=2:1576:0:d4b03c3c-1a4e-4609-86ca-675fa4a2ec8f, @transition-magic=0:7;2:1576:0:d4b03c3c-1a4e-4609-86ca-675fa4a2ec8f, @call-id=22, @exec-time=28
Oct 11 08:19:32 [23933] zfs-serv2        cib:     info: cib_process_request:	Completed cib_modify operation for section status: OK (rc=0, origin=zfs-serv2/crmd/19, version=0.83.8)
Oct 11 08:19:32 [23936] zfs-serv2      attrd:     info: attrd_peer_update:	Setting fail-count-HA_IP-Serv1[zfs-serv2]: 1 -> 2 from zfs-serv1
Oct 11 08:19:32 [23933] zfs-serv2        cib:     info: cib_perform_op:	Diff: --- 0.83.8 2
Oct 11 08:19:32 [23933] zfs-serv2        cib:     info: cib_perform_op:	Diff: +++ 0.83.9 (null)
Oct 11 08:19:32 [23933] zfs-serv2        cib:     info: cib_perform_op:	+  /cib:  @num_updates=9
Oct 11 08:19:32 [23933] zfs-serv2        cib:     info: cib_perform_op:	+  /cib/status/node_state[@id='739272008']/transient_attributes[@id='739272008']/instance_attributes[@id='status-739272008']/nvpair[@id='status-739272008-fail-count-HA_IP-Serv1']:  @value=2
Oct 11 08:19:32 [23933] zfs-serv2        cib:     info: cib_process_request:	Completed cib_modify operation for section status: OK (rc=0, origin=zfs-serv1/attrd/914, version=0.83.9)
Oct 11 08:19:32 [23935] zfs-serv2       lrmd:     info: cancel_recurring_action:	Cancelling ocf operation HA_IP-Serv1_monitor_10000
Oct 11 08:19:32 [23938] zfs-serv2       crmd:     info: do_lrm_rsc_op:	Performing key=3:1578:0:d4b03c3c-1a4e-4609-86ca-675fa4a2ec8f op=HA_IP-Serv1_stop_0
Oct 11 08:19:32 [23935] zfs-serv2       lrmd:     info: log_execute:	executing - rsc:HA_IP-Serv1 action:stop call_id:24
Oct 11 08:19:32 [23938] zfs-serv2       crmd:     info: process_lrm_event:	Result of monitor operation for HA_IP-Serv1 on zfs-serv2: Cancelled | call=22 key=HA_IP-Serv1_monitor_10000 confirmed=true
Oct 11 08:19:32 [23935] zfs-serv2       lrmd:     info: log_finished:	finished - rsc:HA_IP-Serv1 action:stop call_id:24 pid:27942 exit-code:0 exec-time:42ms queue-time:0ms
Oct 11 08:19:32 [23938] zfs-serv2       crmd:   notice: process_lrm_event:	Result of stop operation for HA_IP-Serv1 on zfs-serv2: 0 (ok) | call=24 key=HA_IP-Serv1_stop_0 confirmed=true cib-update=20
Oct 11 08:19:32 [23933] zfs-serv2        cib:     info: cib_process_request:	Forwarding cib_modify operation for section status to all (origin=local/crmd/20)
Oct 11 08:19:32 [23933] zfs-serv2        cib:     info: cib_perform_op:	Diff: --- 0.83.9 2
Oct 11 08:19:32 [23933] zfs-serv2        cib:     info: cib_perform_op:	Diff: +++ 0.83.10 (null)
Oct 11 08:19:32 [23933] zfs-serv2        cib:     info: cib_perform_op:	+  /cib:  @num_updates=10
Oct 11 08:19:32 [23933] zfs-serv2        cib:     info: cib_perform_op:	+  /cib/status/node_state[@id='739272008']/lrm[@id='739272008']/lrm_resources/lrm_resource[@id='HA_IP-Serv1']/lrm_rsc_op[@id='HA_IP-Serv1_last_0']:  @operation_key=HA_IP-Serv1_stop_0, @operation=stop, @transition-key=3:1578:0:d4b03c3c-1a4e-4609-86ca-675fa4a2ec8f, @transition-magic=0:0;3:1578:0:d4b03c3c-1a4e-4609-86ca-675fa4a2ec8f, @call-id=24, @exec-time=42
Oct 11 08:19:32 [23933] zfs-serv2        cib:     info: cib_process_request:	Completed cib_modify operation for section status: OK (rc=0, origin=zfs-serv2/crmd/20, version=0.83.10)
Oct 11 08:19:32 [23938] zfs-serv2       crmd:     info: do_lrm_rsc_op:	Performing key=9:1578:0:d4b03c3c-1a4e-4609-86ca-675fa4a2ec8f op=HA_IP-Serv1_start_0
Oct 11 08:19:32 [23935] zfs-serv2       lrmd:     info: log_execute:	executing - rsc:HA_IP-Serv1 action:start call_id:25
Oct 11 08:19:32 [23935] zfs-serv2       lrmd:     info: log_finished:	finished - rsc:HA_IP-Serv1 action:start call_id:25 pid:27995 exit-code:0 exec-time:45ms queue-time:0ms
Oct 11 08:19:32 [23938] zfs-serv2       crmd:     info: action_synced_wait:	Managed IPaddr2_meta-data_0 process 28058 exited with rc=0
Oct 11 08:19:32 [23938] zfs-serv2       crmd:   notice: process_lrm_event:	Result of start operation for HA_IP-Serv1 on zfs-serv2: 0 (ok) | call=25 key=HA_IP-Serv1_start_0 confirmed=true cib-update=21
Oct 11 08:19:32 [23933] zfs-serv2        cib:     info: cib_process_request:	Forwarding cib_modify operation for section status to all (origin=local/crmd/21)
Oct 11 08:19:32 [23933] zfs-serv2        cib:     info: cib_perform_op:	Diff: --- 0.83.10 2
Oct 11 08:19:32 [23933] zfs-serv2        cib:     info: cib_perform_op:	Diff: +++ 0.83.11 (null)
Oct 11 08:19:32 [23933] zfs-serv2        cib:     info: cib_perform_op:	+  /cib:  @num_updates=11
Oct 11 08:19:32 [23933] zfs-serv2        cib:     info: cib_perform_op:	+  /cib/status/node_state[@id='739272008']/lrm[@id='739272008']/lrm_resources/lrm_resource[@id='HA_IP-Serv1']/lrm_rsc_op[@id='HA_IP-Serv1_last_0']:  @operation_key=HA_IP-Serv1_start_0, @operation=start, @transition-key=9:1578:0:d4b03c3c-1a4e-4609-86ca-675fa4a2ec8f, @transition-magic=0:0;9:1578:0:d4b03c3c-1a4e-4609-86ca-675fa4a2ec8f, @call-id=25, @exec-time=45
Oct 11 08:19:32 [23933] zfs-serv2        cib:     info: cib_process_request:	Completed cib_modify operation for section status: OK (rc=0, origin=zfs-serv2/crmd/21, version=0.83.11)
Oct 11 08:19:32 [23938] zfs-serv2       crmd:     info: do_lrm_rsc_op:	Performing key=2:1578:0:d4b03c3c-1a4e-4609-86ca-675fa4a2ec8f op=HA_IP-Serv1_monitor_10000
Oct 11 08:19:32 [23938] zfs-serv2       crmd:     info: process_lrm_event:	Result of monitor operation for HA_IP-Serv1 on zfs-serv2: 7 (not running) | call=26 key=HA_IP-Serv1_monitor_10000 confirmed=false cib-update=22
Oct 11 08:19:32 [23933] zfs-serv2        cib:     info: cib_process_request:	Forwarding cib_modify operation for section status to all (origin=local/crmd/22)
Oct 11 08:19:32 [23933] zfs-serv2        cib:     info: cib_perform_op:	Diff: --- 0.83.11 2
Oct 11 08:19:32 [23933] zfs-serv2        cib:     info: cib_perform_op:	Diff: +++ 0.83.12 (null)
Oct 11 08:19:32 [23933] zfs-serv2        cib:     info: cib_perform_op:	+  /cib:  @num_updates=12
Oct 11 08:19:32 [23933] zfs-serv2        cib:     info: cib_perform_op:	+  /cib/status/node_state[@id='739272008']/lrm[@id='739272008']/lrm_resources/lrm_resource[@id='HA_IP-Serv1']/lrm_rsc_op[@id='HA_IP-Serv1_last_failure_0']:  @transition-key=2:1578:0:d4b03c3c-1a4e-4609-86ca-675fa4a2ec8f, @transition-magic=0:7;2:1578:0:d4b03c3c-1a4e-4609-86ca-675fa4a2ec8f, @call-id=26, @exec-time=26
Oct 11 08:19:32 [23933] zfs-serv2        cib:     info: cib_process_request:	Completed cib_modify operation for section status: OK (rc=0, origin=zfs-serv2/crmd/22, version=0.83.12)
Oct 11 08:19:32 [23936] zfs-serv2      attrd:     info: attrd_peer_update:	Setting fail-count-HA_IP-Serv1[zfs-serv2]: 2 -> 3 from zfs-serv1
Oct 11 08:19:32 [23933] zfs-serv2        cib:     info: cib_perform_op:	Diff: --- 0.83.12 2
Oct 11 08:19:32 [23933] zfs-serv2        cib:     info: cib_perform_op:	Diff: +++ 0.83.13 (null)
Oct 11 08:19:32 [23933] zfs-serv2        cib:     info: cib_perform_op:	+  /cib:  @num_updates=13
Oct 11 08:19:32 [23933] zfs-serv2        cib:     info: cib_perform_op:	+  /cib/status/node_state[@id='739272008']/transient_attributes[@id='739272008']/instance_attributes[@id='status-739272008']/nvpair[@id='status-739272008-fail-count-HA_IP-Serv1']:  @value=3
Oct 11 08:19:32 [23933] zfs-serv2        cib:     info: cib_process_request:	Completed cib_modify operation for section status: OK (rc=0, origin=zfs-serv1/attrd/915, version=0.83.13)
Oct 11 08:19:32 [23935] zfs-serv2       lrmd:     info: cancel_recurring_action:	Cancelling ocf operation HA_IP-Serv1_monitor_10000
Oct 11 08:19:32 [23938] zfs-serv2       crmd:     info: do_lrm_rsc_op:	Performing key=3:1580:0:d4b03c3c-1a4e-4609-86ca-675fa4a2ec8f op=HA_IP-Serv1_stop_0
Oct 11 08:19:32 [23935] zfs-serv2       lrmd:     info: log_execute:	executing - rsc:HA_IP-Serv1 action:stop call_id:28
Oct 11 08:19:32 [23938] zfs-serv2       crmd:     info: process_lrm_event:	Result of monitor operation for HA_IP-Serv1 on zfs-serv2: Cancelled | call=26 key=HA_IP-Serv1_monitor_10000 confirmed=true
Oct 11 08:19:32 [23935] zfs-serv2       lrmd:     info: log_finished:	finished - rsc:HA_IP-Serv1 action:stop call_id:28 pid:28103 exit-code:0 exec-time:42ms queue-time:0ms
Oct 11 08:19:32 [23938] zfs-serv2       crmd:   notice: process_lrm_event:	Result of stop operation for HA_IP-Serv1 on zfs-serv2: 0 (ok) | call=28 key=HA_IP-Serv1_stop_0 confirmed=true cib-update=23
Oct 11 08:19:32 [23933] zfs-serv2        cib:     info: cib_process_request:	Forwarding cib_modify operation for section status to all (origin=local/crmd/23)
Oct 11 08:19:32 [23933] zfs-serv2        cib:     info: cib_perform_op:	Diff: --- 0.83.13 2
Oct 11 08:19:32 [23933] zfs-serv2        cib:     info: cib_perform_op:	Diff: +++ 0.83.14 (null)
Oct 11 08:19:32 [23933] zfs-serv2        cib:     info: cib_perform_op:	+  /cib:  @num_updates=14
Oct 11 08:19:32 [23933] zfs-serv2        cib:     info: cib_perform_op:	+  /cib/status/node_state[@id='739272008']/lrm[@id='739272008']/lrm_resources/lrm_resource[@id='HA_IP-Serv1']/lrm_rsc_op[@id='HA_IP-Serv1_last_0']:  @operation_key=HA_IP-Serv1_stop_0, @operation=stop, @transition-key=3:1580:0:d4b03c3c-1a4e-4609-86ca-675fa4a2ec8f, @transition-magic=0:0;3:1580:0:d4b03c3c-1a4e-4609-86ca-675fa4a2ec8f, @call-id=28, @exec-time=42
Oct 11 08:19:32 [23933] zfs-serv2        cib:     info: cib_process_request:	Completed cib_modify operation for section status: OK (rc=0, origin=zfs-serv2/crmd/23, version=0.83.14)
Oct 11 08:19:32 [23938] zfs-serv2       crmd:     info: do_lrm_rsc_op:	Performing key=9:1580:0:d4b03c3c-1a4e-4609-86ca-675fa4a2ec8f op=HA_IP-Serv1_start_0
Oct 11 08:19:32 [23935] zfs-serv2       lrmd:     info: log_execute:	executing - rsc:HA_IP-Serv1 action:start call_id:29
Oct 11 08:19:32 [23935] zfs-serv2       lrmd:     info: log_finished:	finished - rsc:HA_IP-Serv1 action:start call_id:29 pid:28156 exit-code:0 exec-time:45ms queue-time:0ms
Oct 11 08:19:32 [23938] zfs-serv2       crmd:     info: action_synced_wait:	Managed IPaddr2_meta-data_0 process 28219 exited with rc=0
Oct 11 08:19:32 [23938] zfs-serv2       crmd:   notice: process_lrm_event:	Result of start operation for HA_IP-Serv1 on zfs-serv2: 0 (ok) | call=29 key=HA_IP-Serv1_start_0 confirmed=true cib-update=24
Oct 11 08:19:32 [23933] zfs-serv2        cib:     info: cib_process_request:	Forwarding cib_modify operation for section status to all (origin=local/crmd/24)
Oct 11 08:19:32 [23933] zfs-serv2        cib:     info: cib_perform_op:	Diff: --- 0.83.14 2
Oct 11 08:19:32 [23933] zfs-serv2        cib:     info: cib_perform_op:	Diff: +++ 0.83.15 (null)
Oct 11 08:19:32 [23933] zfs-serv2        cib:     info: cib_perform_op:	+  /cib:  @num_updates=15
Oct 11 08:19:32 [23933] zfs-serv2        cib:     info: cib_perform_op:	+  /cib/status/node_state[@id='739272008']/lrm[@id='739272008']/lrm_resources/lrm_resource[@id='HA_IP-Serv1']/lrm_rsc_op[@id='HA_IP-Serv1_last_0']:  @operation_key=HA_IP-Serv1_start_0, @operation=start, @transition-key=9:1580:0:d4b03c3c-1a4e-4609-86ca-675fa4a2ec8f, @transition-magic=0:0;9:1580:0:d4b03c3c-1a4e-4609-86ca-675fa4a2ec8f, @call-id=29, @exec-time=45
Oct 11 08:19:32 [23933] zfs-serv2        cib:     info: cib_process_request:	Completed cib_modify operation for section status: OK (rc=0, origin=zfs-serv2/crmd/24, version=0.83.15)
Oct 11 08:19:32 [23938] zfs-serv2       crmd:     info: do_lrm_rsc_op:	Performing key=2:1580:0:d4b03c3c-1a4e-4609-86ca-675fa4a2ec8f op=HA_IP-Serv1_monitor_10000
Oct 11 08:19:32 [23938] zfs-serv2       crmd:     info: process_lrm_event:	Result of monitor operation for HA_IP-Serv1 on zfs-serv2: 7 (not running) | call=30 key=HA_IP-Serv1_monitor_10000 confirmed=false cib-update=25
Oct 11 08:19:32 [23933] zfs-serv2        cib:     info: cib_process_request:	Forwarding cib_modify operation for section status to all (origin=local/crmd/25)
Oct 11 08:19:32 [23933] zfs-serv2        cib:     info: cib_perform_op:	Diff: --- 0.83.15 2
Oct 11 08:19:32 [23933] zfs-serv2        cib:     info: cib_perform_op:	Diff: +++ 0.83.16 (null)
Oct 11 08:19:32 [23933] zfs-serv2        cib:     info: cib_perform_op:	+  /cib:  @num_updates=16
Oct 11 08:19:32 [23933] zfs-serv2        cib:     info: cib_perform_op:	+  /cib/status/node_state[@id='739272008']/lrm[@id='739272008']/lrm_resources/lrm_resource[@id='HA_IP-Serv1']/lrm_rsc_op[@id='HA_IP-Serv1_last_failure_0']:  @transition-key=2:1580:0:d4b03c3c-1a4e-4609-86ca-675fa4a2ec8f, @transition-magic=0:7;2:1580:0:d4b03c3c-1a4e-4609-86ca-675fa4a2ec8f, @call-id=30, @exec-time=27
Oct 11 08:19:32 [23933] zfs-serv2        cib:     info: cib_process_request:	Completed cib_modify operation for section status: OK (rc=0, origin=zfs-serv2/crmd/25, version=0.83.16)
Oct 11 08:19:32 [23936] zfs-serv2      attrd:     info: attrd_peer_update:	Setting fail-count-HA_IP-Serv1[zfs-serv2]: 3 -> 4 from zfs-serv1
Oct 11 08:19:32 [23933] zfs-serv2        cib:     info: cib_perform_op:	Diff: --- 0.83.16 2
Oct 11 08:19:32 [23933] zfs-serv2        cib:     info: cib_perform_op:	Diff: +++ 0.83.17 (null)
Oct 11 08:19:32 [23933] zfs-serv2        cib:     info: cib_perform_op:	+  /cib:  @num_updates=17
Oct 11 08:19:32 [23933] zfs-serv2        cib:     info: cib_perform_op:	+  /cib/status/node_state[@id='739272008']/transient_attributes[@id='739272008']/instance_attributes[@id='status-739272008']/nvpair[@id='status-739272008-fail-count-HA_IP-Serv1']:  @value=4
Oct 11 08:19:32 [23933] zfs-serv2        cib:     info: cib_process_request:	Completed cib_modify operation for section status: OK (rc=0, origin=zfs-serv1/attrd/916, version=0.83.17)
Oct 11 08:19:32 [23935] zfs-serv2       lrmd:     info: cancel_recurring_action:	Cancelling ocf operation HA_IP-Serv1_monitor_10000
Oct 11 08:19:32 [23938] zfs-serv2       crmd:     info: do_lrm_rsc_op:	Performing key=3:1582:0:d4b03c3c-1a4e-4609-86ca-675fa4a2ec8f op=HA_IP-Serv1_stop_0
Oct 11 08:19:32 [23935] zfs-serv2       lrmd:     info: log_execute:	executing - rsc:HA_IP-Serv1 action:stop call_id:32
Oct 11 08:19:32 [23938] zfs-serv2       crmd:     info: process_lrm_event:	Result of monitor operation for HA_IP-Serv1 on zfs-serv2: Cancelled | call=30 key=HA_IP-Serv1_monitor_10000 confirmed=true
Oct 11 08:19:32 [23935] zfs-serv2       lrmd:     info: log_finished:	finished - rsc:HA_IP-Serv1 action:stop call_id:32 pid:28264 exit-code:0 exec-time:41ms queue-time:0ms
Oct 11 08:19:32 [23938] zfs-serv2       crmd:   notice: process_lrm_event:	Result of stop operation for HA_IP-Serv1 on zfs-serv2: 0 (ok) | call=32 key=HA_IP-Serv1_stop_0 confirmed=true cib-update=26
Oct 11 08:19:32 [23933] zfs-serv2        cib:     info: cib_process_request:	Forwarding cib_modify operation for section status to all (origin=local/crmd/26)
Oct 11 08:19:32 [23933] zfs-serv2        cib:     info: cib_perform_op:	Diff: --- 0.83.17 2
Oct 11 08:19:32 [23933] zfs-serv2        cib:     info: cib_perform_op:	Diff: +++ 0.83.18 (null)
Oct 11 08:19:32 [23933] zfs-serv2        cib:     info: cib_perform_op:	+  /cib:  @num_updates=18
Oct 11 08:19:32 [23933] zfs-serv2        cib:     info: cib_perform_op:	+  /cib/status/node_state[@id='739272008']/lrm[@id='739272008']/lrm_resources/lrm_resource[@id='HA_IP-Serv1']/lrm_rsc_op[@id='HA_IP-Serv1_last_0']:  @operation_key=HA_IP-Serv1_stop_0, @operation=stop, @transition-key=3:1582:0:d4b03c3c-1a4e-4609-86ca-675fa4a2ec8f, @transition-magic=0:0;3:1582:0:d4b03c3c-1a4e-4609-86ca-675fa4a2ec8f, @call-id=32, @exec-time=41
Oct 11 08:19:32 [23933] zfs-serv2        cib:     info: cib_process_request:	Completed cib_modify operation for section status: OK (rc=0, origin=zfs-serv2/crmd/26, version=0.83.18)
Oct 11 08:19:32 [23938] zfs-serv2       crmd:     info: do_lrm_rsc_op:	Performing key=9:1582:0:d4b03c3c-1a4e-4609-86ca-675fa4a2ec8f op=HA_IP-Serv1_start_0
Oct 11 08:19:32 [23935] zfs-serv2       lrmd:     info: log_execute:	executing - rsc:HA_IP-Serv1 action:start call_id:33


syslog from zfs-serv2:
Oct 11 08:19:32 zfs-serv2 IPaddr2(HA_IP-Serv1)[27672]: INFO: Adding inet address 172.16.101.70/16 with broadcast address 172.16.255.255 to device enp4s0f0
Oct 11 08:19:32 zfs-serv2 IPaddr2(HA_IP-Serv1)[27672]: INFO: Bringing device enp4s0f0 up
Oct 11 08:19:32 zfs-serv2 IPaddr2(HA_IP-Serv1)[27672]: INFO: /usr/lib/heartbeat/send_arp -i 200 -r 5 -p /var/run/resource-agents/send_arp-172.16.101.70 enp4s0f0 172.16.101.70 auto not_used not_used
Oct 11 08:19:32 zfs-serv2 crmd[23938]:   notice: Result of start operation for HA_IP-Serv1 on zfs-serv2: 0 (ok)
Oct 11 08:19:32 zfs-serv2 IPaddr2(HA_IP-Serv1)[27780]: INFO: killed previously running send_arp for 172.16.101.70
Oct 11 08:19:32 zfs-serv2 IPaddr2(HA_IP-Serv1)[27780]: INFO: IP status = ok, IP_CIP=
Oct 11 08:19:32 zfs-serv2 lldpd[2164]: removal request for address of 172.16.101.70%2, but no knowledge of it
Oct 11 08:19:32 zfs-serv2 lldpd[2152]: 2017-10-11T08:19:32 [INFO/netlink] removal request for address of 172.16.101.70%2, but no knowledge of it
Oct 11 08:19:32 zfs-serv2 crmd[23938]:   notice: Result of stop operation for HA_IP-Serv1 on zfs-serv2: 0 (ok)
Oct 11 08:19:32 zfs-serv2 IPaddr2(HA_IP-Serv1)[27833]: INFO: Adding inet address 172.16.101.70/16 with broadcast address 172.16.255.255 to device enp4s0f0
Oct 11 08:19:32 zfs-serv2 IPaddr2(HA_IP-Serv1)[27833]: INFO: Bringing device enp4s0f0 up
Oct 11 08:19:32 zfs-serv2 IPaddr2(HA_IP-Serv1)[27833]: INFO: /usr/lib/heartbeat/send_arp -i 200 -r 5 -p /var/run/resource-agents/send_arp-172.16.101.70 enp4s0f0 172.16.101.70 auto not_used not_used
Oct 11 08:19:32 zfs-serv2 crmd[23938]:   notice: Result of start operation for HA_IP-Serv1 on zfs-serv2: 0 (ok)
Oct 11 08:19:32 zfs-serv2 IPaddr2(HA_IP-Serv1)[27942]: INFO: killed previously running send_arp for 172.16.101.70
Oct 11 08:19:32 zfs-serv2 IPaddr2(HA_IP-Serv1)[27942]: INFO: IP status = ok, IP_CIP=
Oct 11 08:19:32 zfs-serv2 lldpd[2164]: removal request for address of 172.16.101.70%2, but no knowledge of it
Oct 11 08:19:32 zfs-serv2 lldpd[2152]: 2017-10-11T08:19:32 [INFO/netlink] removal request for address of 172.16.101.70%2, but no knowledge of it
Oct 11 08:19:32 zfs-serv2 crmd[23938]:   notice: Result of stop operation for HA_IP-Serv1 on zfs-serv2: 0 (ok)
Oct 11 08:19:32 zfs-serv2 IPaddr2(HA_IP-Serv1)[27995]: INFO: Adding inet address 172.16.101.70/16 with broadcast address 172.16.255.255 to device enp4s0f0
Oct 11 08:19:32 zfs-serv2 IPaddr2(HA_IP-Serv1)[27995]: INFO: Bringing device enp4s0f0 up
Oct 11 08:19:32 zfs-serv2 IPaddr2(HA_IP-Serv1)[27995]: INFO: /usr/lib/heartbeat/send_arp -i 200 -r 5 -p /var/run/resource-agents/send_arp-172.16.101.70 enp4s0f0 172.16.101.70 auto not_used not_used
Oct 11 08:19:32 zfs-serv2 crmd[23938]:   notice: Result of start operation for HA_IP-Serv1 on zfs-serv2: 0 (ok)
Oct 11 08:19:32 zfs-serv2 IPaddr2(HA_IP-Serv1)[28103]: INFO: killed previously running send_arp for 172.16.101.70
Oct 11 08:19:32 zfs-serv2 IPaddr2(HA_IP-Serv1)[28103]: INFO: IP status = ok, IP_CIP=
Oct 11 08:19:32 zfs-serv2 lldpd[2164]: removal request for address of 172.16.101.70%2, but no knowledge of it
Oct 11 08:19:32 zfs-serv2 lldpd[2152]: 2017-10-11T08:19:32 [INFO/netlink] removal request for address of 172.16.101.70%2, but no knowledge of it
Oct 11 08:19:32 zfs-serv2 crmd[23938]:   notice: Result of stop operation for HA_IP-Serv1 on zfs-serv2: 0 (ok)
Oct 11 08:19:32 zfs-serv2 IPaddr2(HA_IP-Serv1)[28156]: INFO: Adding inet address 172.16.101.70/16 with broadcast address 172.16.255.255 to device enp4s0f0
Oct 11 08:19:32 zfs-serv2 IPaddr2(HA_IP-Serv1)[28156]: INFO: Bringing device enp4s0f0 up
Oct 11 08:19:32 zfs-serv2 IPaddr2(HA_IP-Serv1)[28156]: INFO: /usr/lib/heartbeat/send_arp -i 200 -r 5 -p /var/run/resource-agents/send_arp-172.16.101.70 enp4s0f0 172.16.101.70 auto not_used not_used
Oct 11 08:19:32 zfs-serv2 crmd[23938]:   notice: Result of start operation for HA_IP-Serv1 on zfs-serv2: 0 (ok)
Oct 11 08:19:32 zfs-serv2 IPaddr2(HA_IP-Serv1)[28264]: INFO: killed previously running send_arp for 172.16.101.70
Oct 11 08:19:32 zfs-serv2 IPaddr2(HA_IP-Serv1)[28264]: INFO: IP status = ok, IP_CIP=
Oct 11 08:19:32 zfs-serv2 lldpd[2164]: removal request for address of 172.16.101.70%2, but no knowledge of it
Oct 11 08:19:32 zfs-serv2 lldpd[2152]: 2017-10-11T08:19:32 [INFO/netlink] removal request for address of 172.16.101.70%2, but no knowledge of it
Oct 11 08:19:32 zfs-serv2 crmd[23938]:   notice: Result of stop operation for HA_IP-Serv1 on zfs-serv2: 0 (ok)
Oct 11 08:19:32 zfs-serv2 IPaddr2(HA_IP-Serv1)[28317]: INFO: Adding inet address 172.16.101.70/16 with broadcast address 172.16.255.255 to device enp4s0f0
Oct 11 08:19:32 zfs-serv2 IPaddr2(HA_IP-Serv1)[28317]: INFO: Bringing device enp4s0f0 up


thanks in advance for help!
best regards
Stefan

More information about the Users mailing list