[Linux-HA] Re: CRM vs. no CRM
Jiann-Ming Su
sujiannming at gmail.com
Mon Aug 7 09:26:16 MDT 2006
On 8/4/06, Lars Marowsky-Bree <lmb at suse.de> wrote:
> On 2006-08-02T23:58:12, Jiann-Ming Su <sujiannming at gmail.com> wrote:
> > Also, I could not get CRM to failover to the passive node. It did a
> > great job of restarting my dhcp process on the active node. But, when
> > I took down the active node, the passive node just sat there.
>
> Logs + configuration would be needed.
>
I think part of my initial problem with CRM was that on the second node, the cib.xml file wasn't owned by the hacluster user. Here are my current CRM testing configs:
/etc/ha.d/ha.cf:
logfacility daemon
debug 1
keepalive 1
deadtime 10
warntime 5
initdead 120 # depend on your hardware
udpport 694
ping 192.168.111.1
bcast eth0
auto_failback off
node dhcptest1
node dhcptest2
use_logd yes
compression bz2
compression_threshold 2
crm yes
/var/lib/heartbeat/crm/cib.xml: see attachment
Without CRM, I was able to use iptables to drop ICMP packets destined for 192.168.111.1 to simulate a network outage. This would result in a failover to the passive node.
With CRM, though, simulating a network outage this way did not cause a failover. Heartbeat on the primary node complained about not being able to ping:
Aug 7 11:14:03 dhcptest1 heartbeat: [2026]: ERROR: glib: Error sending packet: Operation not permitted
Aug 7 11:14:03 dhcptest1 heartbeat: [2026]: ERROR: write failure on ping 192.168.111.1.: Operation not permitted
Heartbeat on the passive node didn't even notice. I had to power down the active node to have heartbeat failover to the passive node with CRM enabled. The attached /var/log/messages file is from the passive node as heartbeat was failing over.
--
Jiann-Ming Su
"I have to decide between two equally frightening options.
If I wanted to do that, I'd vote." --Duckman
"The system's broke, Hank. The election baby has peed in
the bath water. You got to throw 'em both out." --Dale Gribble
-------------- next part --------------
A non-text attachment was scrubbed...
Name: cib.xml
Type: text/xml
Size: 3732 bytes
Desc: not available
Url : http://lists.community.tummy.com/pipermail/linux-ha/attachments/20060807/d0694d9d/cib-0001.bin
-------------- next part --------------
Aug 7 11:14:16 dhcptest2 kernel: drbd0: PingAck did not arrive in time.
Aug 7 11:14:16 dhcptest2 kernel: drbd0: drbd0_asender [1954]: cstate Connected --> NetworkFailure
Aug 7 11:14:16 dhcptest2 kernel: drbd0: asender terminated
Aug 7 11:14:16 dhcptest2 kernel: drbd0: drbd0_receiver [1944]: cstate NetworkFailure --> BrokenPipe
Aug 7 11:14:16 dhcptest2 kernel: drbd0: short read expecting header on sock: r=-512
Aug 7 11:14:16 dhcptest2 kernel: drbd0: worker terminated
Aug 7 11:14:16 dhcptest2 kernel: drbd0: drbd0_receiver [1944]: cstate BrokenPipe --> Unconnected
Aug 7 11:14:16 dhcptest2 kernel: drbd0: Connection lost.
Aug 7 11:14:16 dhcptest2 kernel: drbd0: drbd0_receiver [1944]: cstate Unconnected --> WFConnection
Aug 7 11:14:17 dhcptest2 ntpd[1898]: synchronized to 163.246.16.100, stratum 2
Aug 7 11:14:20 dhcptest2 heartbeat: [1988]: WARN: node dhcptest1: is dead
Aug 7 11:14:20 dhcptest2 heartbeat: [1988]: info: Link dhcptest1:eth0 dead.
Aug 7 11:14:20 dhcptest2 cib: [2129]: info: mem_handle_event: Got an event OC_EV_MS_INVALID from ccm
Aug 7 11:14:20 dhcptest2 cib: [2129]: info: mem_handle_event: no mbr_track info
Aug 7 11:14:20 dhcptest2 ccm: [2128]: info: n_member=1, nodecount=2
Aug 7 11:14:20 dhcptest2 ccm: [2128]: info: Break tie for 2 nodes cluster
Aug 7 11:14:21 dhcptest2 cib: [2129]: info: mem_handle_event: Got an event OC_EV_MS_NEW_MEMBERSHIP from ccm
Aug 7 11:14:21 dhcptest2 cib: [2129]: info: mem_handle_event: instance=3, nodes=1, new=0, lost=1, n_idx=0, new_idx=1, old_idx=3
Aug 7 11:14:21 dhcptest2 cib: [2129]: info: mask(callbacks.c:cib_ccm_msg_callback): LOST: dhcptest1
Aug 7 11:14:21 dhcptest2 crmd: [2133]: info: mem_handle_event: Got an event OC_EV_MS_INVALID from ccm
Aug 7 11:14:21 dhcptest2 crmd: [2133]: info: mem_handle_event: no mbr_track info
Aug 7 11:14:21 dhcptest2 crmd: [2133]: info: mem_handle_event: Got an event OC_EV_MS_NEW_MEMBERSHIP from ccm
Aug 7 11:14:21 dhcptest2 crmd: [2133]: info: mem_handle_event: instance=3, nodes=1, new=0, lost=1, n_idx=0, new_idx=1, old_idx=3
Aug 7 11:14:21 dhcptest2 crmd: [2133]: info: mask(callbacks.c:crmd_ccm_msg_callback): Quorum (re)attained after event=NEW MEMBERSHIP (id=3)
Aug 7 11:14:21 dhcptest2 crmd: [2133]: info: mask(ccm.c:ccm_event_detail): NEW MEMBERSHIP: trans=3, nodes=1, new=0, lost=1 n_idx=0, new_idx=1, old_idx=3
Aug 7 11:14:21 dhcptest2 crmd: [2133]: info: mask(ccm.c:ccm_event_detail): CURRENT: dhcptest2 [nodeid=1, born=3]
Aug 7 11:14:21 dhcptest2 crmd: [2133]: info: mask(ccm.c:ccm_event_detail): LOST: dhcptest1 [nodeid=0, born=2]
Aug 7 11:14:21 dhcptest2 ccm: [2128]: WARN: ccm_state_joined: received message with unknown cookie, just dropping
Aug 7 11:14:21 dhcptest2 cib: [2129]: info: mask(notify.c:cib_diff_notify): Local-only Change (client:2133, call: 31): 0.1.26 (ok)
Aug 7 11:14:21 dhcptest2 tengine: [2474]: info: mask(callbacks.c:te_update_diff): Processing diff (cib_update): 0.1.26 -> 0.1.26
Aug 7 11:14:21 dhcptest2 tengine: [2474]: WARN: mask(events.c:match_down_event): No match for shutdown action on bdcf4187-52b7-41d3-acaa-ae30284807c1
Aug 7 11:14:21 dhcptest2 tengine: [2474]: info: mask(events.c:extract_event): Stonith/shutdown event not matched
Aug 7 11:14:21 dhcptest2 tengine: [2474]: info: mask(utils.c:update_abort_priority): Abort priority upgraded to 1000000
Aug 7 11:14:21 dhcptest2 crmd: [2133]: info: mask(fsa.c:do_state_transition): dhcptest2: State transition S_IDLE -> S_POLICY_ENGINE [ input=I_PE_CALC cause=C_IPC_MESSAGE origin=do_msg_route ]
Aug 7 11:14:21 dhcptest2 crmd: [2133]: info: mask(fsa.c:do_state_transition): All 1 cluster nodes are eligable to run resources.
Aug 7 11:14:21 dhcptest2 pengine: [2475]: info: mask(process_pe_message): [generation] <cib admin_epoch="0" epoch="1" num_updates="26" have_quorum="true" generated="true" num_peers="2" ccm_transition="3" cib_feature_revision="1.3" dc_uuid="9aac8a89-b42c-4a51-8f5a-0af159939d6d" crm-debug-origin="create_node_entry"/>
Aug 7 11:14:21 dhcptest2 pengine: [2475]: info: mask(unpack.c:unpack_config): Default stickiness: 0
Aug 7 11:14:21 dhcptest2 pengine: [2475]: WARN: mask(unpack.c:unpack_config): No value specified for cluster preference: default_resource_failure_stickiness
Aug 7 11:14:21 dhcptest2 pengine: [2475]: info: mask(unpack.c:unpack_config): Default failure stickiness: 0
Aug 7 11:14:21 dhcptest2 pengine: [2475]: info: mask(unpack.c:unpack_config): STONITH of failed nodes is disabled
Aug 7 11:14:21 dhcptest2 pengine: [2475]: WARN: mask(unpack.c:unpack_config): No value specified for cluster preference: stonith_action
Aug 7 11:14:21 dhcptest2 pengine: [2475]: info: mask(unpack.c:unpack_config): STONITH will reboot nodes
Aug 7 11:14:21 dhcptest2 pengine: [2475]: info: mask(unpack.c:unpack_config): Cluster is symmetric - resources can run anywhere by default
Aug 7 11:14:21 dhcptest2 pengine: [2475]: info: mask(unpack.c:unpack_config): Using short resource names: true
Aug 7 11:14:21 dhcptest2 pengine: [2475]: info: mask(unpack.c:unpack_config): On loss of CCM Quorum: Stop ALL resources
Aug 7 11:14:21 dhcptest2 pengine: [2475]: info: mask(unpack.c:unpack_config): Orphan resources are ignored
Aug 7 11:14:21 dhcptest2 pengine: [2475]: info: mask(unpack.c:unpack_config): Orphan resource actions are stopped
Aug 7 11:14:21 dhcptest2 pengine: [2475]: info: mask(unpack.c:unpack_config): Stopped resources are removed from the status section: false
Aug 7 11:14:21 dhcptest2 pengine: [2475]: info: mask(unpack.c:unpack_config): By default resources are managed
Aug 7 11:14:21 dhcptest2 pengine: [2475]: info: Resource Group: group_1
Aug 7 11:14:21 dhcptest2 pengine: [2475]: info: drbddisk_1 (heartbeat:drbddisk): Stopped
Aug 7 11:14:21 dhcptest2 pengine: [2475]: info: Filesystem_2 (heartbeat::ocf:Filesystem): Stopped
Aug 7 11:14:21 dhcptest2 pengine: [2475]: info: IPaddr_3 (heartbeat::ocf:IPaddr): Stopped
Aug 7 11:14:21 dhcptest2 pengine: [2475]: info: dhcpd_4 (lsb:dhcpd): Stopped
Aug 7 11:14:21 dhcptest2 pengine: [2475]: notice: mask(native.c:StartRsc): dhcptest2 Start drbddisk_1
Aug 7 11:14:21 dhcptest2 pengine: [2475]: notice: mask(native.c:Recurring): dhcptest2 drbddisk_1_monitor_120000
Aug 7 11:14:21 dhcptest2 kernel: drbd0: Secondary/Unknown --> Primary/Unknown
Aug 7 11:14:21 dhcptest2 pengine: [2475]: notice: mask(native.c:StartRsc): dhcptest2 Start Filesystem_2
Aug 7 11:14:21 dhcptest2 pengine: [2475]: notice: mask(native.c:Recurring): dhcptest2 Filesystem_2_monitor_120000
Aug 7 11:14:21 dhcptest2 pengine: [2475]: notice: mask(native.c:StartRsc): dhcptest2 Start IPaddr_3
Aug 7 11:14:21 dhcptest2 pengine: [2475]: notice: mask(native.c:Recurring): dhcptest2 IPaddr_3_monitor_5000
Aug 7 11:14:21 dhcptest2 pengine: [2475]: notice: mask(native.c:StartRsc): dhcptest2 Start dhcpd_4
Aug 7 11:14:21 dhcptest2 pengine: [2475]: notice: mask(native.c:Recurring): dhcptest2 dhcpd_4_monitor_120000
Aug 7 11:14:21 dhcptest2 pengine: [2475]: notice: mask(stages.c:stage8): Created transition graph 3.
Aug 7 11:14:21 dhcptest2 pengine: [2475]: WARN: mask(pengine.c:process_pe_message): No value specified for cluster preference: pe-input-series-max
Aug 7 11:14:21 dhcptest2 pengine: [2475]: info: mask(pengine.c:process_pe_message): Transition 3: PEngine Input stored in: /var/lib/heartbeat/pengine/pe-input-3.bz2
Aug 7 11:14:21 dhcptest2 crmd: [2133]: info: mask(fsa.c:do_state_transition): dhcptest2: State transition S_POLICY_ENGINE -> S_TRANSITION_ENGINE [ input=I_PE_SUCCESS cause=C_IPC_MESSAGE origin=do_msg_route ]
Aug 7 11:14:21 dhcptest2 tengine: [2474]: info: mask(unpack.c:unpack_graph): Unpacked transition 3: 10 actions in 10 synapses
Aug 7 11:14:21 dhcptest2 crmd: [2133]: info: mask(lrm.c:do_lrm_rsc_op): Performing op start on drbddisk_1 (interval=0ms, key=3:c53e3984-bc77-410a-b346-4aba2fad66df)
Aug 7 11:14:21 dhcptest2 tengine: [2474]: info: mask(actions.c:te_pseudo_action): Pseudo action 9 confirmed
Aug 7 11:14:21 dhcptest2 tengine: [2474]: info: mask(actions.c:send_rsc_command): Initiating action 1: drbddisk_1_start_0 on dhcptest2
Aug 7 11:14:21 dhcptest2 cib: [2648]: info: mask(io.c:write_cib_contents): Wrote version 0.1.26 of the CIB to disk (digest: d1128f89348b34f8fb0685f736a7874c)
Aug 7 11:14:21 dhcptest2 crmd: [2133]: info: mask(lrm.c:process_lrm_event): LRM operation (6) start_0 on drbddisk_1 complete
Aug 7 11:14:21 dhcptest2 cib: [2129]: info: mask(notify.c:cib_diff_notify): Update (client: 2133, call:33): 0.1.26 -> 0.1.27 (ok)
Aug 7 11:14:21 dhcptest2 tengine: [2474]: info: mask(callbacks.c:te_update_diff): Processing diff (cib_update): 0.1.26 -> 0.1.27
Aug 7 11:14:21 dhcptest2 tengine: [2474]: info: mask(events.c:match_graph_event): Action drbddisk_1_start_0 (1) confirmed
Aug 7 11:14:21 dhcptest2 tengine: [2474]: info: mask(actions.c:send_rsc_command): Initiating action 2: drbddisk_1_monitor_120000 on dhcptest2
Aug 7 11:14:21 dhcptest2 tengine: [2474]: info: mask(actions.c:send_rsc_command): Initiating action 3: Filesystem_2_start_0 on dhcptest2
Aug 7 11:14:21 dhcptest2 crmd: [2133]: info: mask(lrm.c:do_lrm_rsc_op): Performing op monitor on drbddisk_1 (interval=120000ms, key=3:c53e3984-bc77-410a-b346-4aba2fad66df)
Aug 7 11:14:22 dhcptest2 crmd: [2133]: info: mask(lrm.c:do_lrm_rsc_op): Performing op start on Filesystem_2 (interval=0ms, key=3:c53e3984-bc77-410a-b346-4aba2fad66df)
Aug 7 11:14:22 dhcptest2 cib: [2659]: info: mask(io.c:write_cib_contents): Wrote version 0.1.27 of the CIB to disk (digest: 0f7ff6d98c514e06256dd68aba9edeee)
Aug 7 11:14:22 dhcptest2 crmd: [2133]: info: mask(lrm.c:process_lrm_event): LRM operation (7) monitor_120000 on drbddisk_1 complete
Aug 7 11:14:22 dhcptest2 cib: [2129]: info: mask(notify.c:cib_diff_notify): Update (client: 2133, call:34): 0.1.27 -> 0.1.28 (ok)
Aug 7 11:14:22 dhcptest2 tengine: [2474]: info: mask(callbacks.c:te_update_diff): Processing diff (cib_update): 0.1.27 -> 0.1.28
Aug 7 11:14:22 dhcptest2 tengine: [2474]: info: mask(events.c:match_graph_event): Action drbddisk_1_monitor_120000 (2) confirmed
Aug 7 11:14:22 dhcptest2 cib: [2669]: info: mask(io.c:write_cib_contents): Wrote version 0.1.28 of the CIB to disk (digest: 4114bda9bb15347c50475c0fe3141e38)
Aug 7 11:14:22 dhcptest2 kernel: kjournald starting. Commit interval 5 seconds
Aug 7 11:14:22 dhcptest2 kernel: EXT3 FS on drbd0, internal journal
Aug 7 11:14:22 dhcptest2 kernel: EXT3-fs: recovery complete.
Aug 7 11:14:22 dhcptest2 kernel: EXT3-fs: mounted filesystem with ordered data mode.
Aug 7 11:14:22 dhcptest2 kernel: SELinux: initialized (dev drbd0, type ext3), uses xattr
Aug 7 11:14:22 dhcptest2 crmd: [2133]: info: mask(lrm.c:process_lrm_event): LRM operation (8) start_0 on Filesystem_2 complete
Aug 7 11:14:22 dhcptest2 cib: [2129]: info: mask(notify.c:cib_diff_notify): Update (client: 2133, call:35): 0.1.28 -> 0.1.29 (ok)
Aug 7 11:14:22 dhcptest2 tengine: [2474]: info: mask(callbacks.c:te_update_diff): Processing diff (cib_update): 0.1.28 -> 0.1.29
Aug 7 11:14:22 dhcptest2 tengine: [2474]: info: mask(events.c:match_graph_event): Action Filesystem_2_start_0 (3) confirmed
Aug 7 11:14:22 dhcptest2 tengine: [2474]: info: mask(actions.c:send_rsc_command): Initiating action 4: Filesystem_2_monitor_120000 on dhcptest2
Aug 7 11:14:22 dhcptest2 tengine: [2474]: info: mask(actions.c:send_rsc_command): Initiating action 5: IPaddr_3_start_0 on dhcptest2
Aug 7 11:14:22 dhcptest2 crmd: [2133]: info: mask(lrm.c:do_lrm_rsc_op): Performing op monitor on Filesystem_2 (interval=120000ms, key=3:c53e3984-bc77-410a-b346-4aba2fad66df)
Aug 7 11:14:22 dhcptest2 crmd: [2133]: info: mask(lrm.c:do_lrm_rsc_op): Performing op start on IPaddr_3 (interval=0ms, key=3:c53e3984-bc77-410a-b346-4aba2fad66df)
Aug 7 11:14:22 dhcptest2 crmd: [2133]: info: mask(lrm.c:process_lrm_event): LRM operation (9) monitor_120000 on Filesystem_2 complete
Aug 7 11:14:22 dhcptest2 cib: [2129]: info: mask(notify.c:cib_diff_notify): Update (client: 2133, call:36): 0.1.29 -> 0.1.30 (ok)
Aug 7 11:14:22 dhcptest2 tengine: [2474]: info: mask(callbacks.c:te_update_diff): Processing diff (cib_update): 0.1.29 -> 0.1.30
Aug 7 11:14:22 dhcptest2 tengine: [2474]: info: mask(events.c:match_graph_event): Action Filesystem_2_monitor_120000 (4) confirmed
Aug 7 11:14:22 dhcptest2 cib: [2678]: info: mask(io.c:write_cib_contents): Wrote version 0.1.29 of the CIB to disk (digest: d970090cdcc8753c88cc625b57675258)
Aug 7 11:14:22 dhcptest2 cib: [2690]: info: mask(io.c:write_cib_contents): Wrote version 0.1.30 of the CIB to disk (digest: 5a152013dcf74aa2215036a3ad170527)
Aug 7 11:14:22 dhcptest2 IPaddr[2679]: [2730]: INFO: /sbin/ifconfig eth0:0 192.168.111.2 netmask 255.255.255.0 broadcast 192.168.111.255
Aug 7 11:14:22 dhcptest2 IPaddr[2679]: [2735]: INFO: Sending Gratuitous Arp for 192.168.111.2 on eth0:0 [eth0]
Aug 7 11:14:22 dhcptest2 IPaddr[2679]: [2736]: INFO: /usr/lib/heartbeat/send_arp -i 500 -r 10 -p /var/run/heartbeat/rsctmp/send_arp/send_arp-192.168.111.2 eth0 192.168.111.2 auto 192.168.111.2 ffffffffffff
Aug 7 11:14:22 dhcptest2 crmd: [2133]: info: mask(lrm.c:process_lrm_event): LRM operation (10) start_0 on IPaddr_3 complete
Aug 7 11:14:23 dhcptest2 cib: [2129]: info: mask(notify.c:cib_diff_notify): Update (client: 2133, call:37): 0.1.30 -> 0.1.31 (ok)
Aug 7 11:14:23 dhcptest2 tengine: [2474]: info: mask(callbacks.c:te_update_diff): Processing diff (cib_update): 0.1.30 -> 0.1.31
Aug 7 11:14:23 dhcptest2 tengine: [2474]: info: mask(events.c:match_graph_event): Action IPaddr_3_start_0 (5) confirmed
Aug 7 11:14:23 dhcptest2 tengine: [2474]: info: mask(actions.c:send_rsc_command): Initiating action 6: IPaddr_3_monitor_5000 on dhcptest2
Aug 7 11:14:23 dhcptest2 tengine: [2474]: info: mask(actions.c:send_rsc_command): Initiating action 7: dhcpd_4_start_0 on dhcptest2
Aug 7 11:14:23 dhcptest2 crmd: [2133]: info: mask(lrm.c:do_lrm_rsc_op): Performing op monitor on IPaddr_3 (interval=5000ms, key=3:c53e3984-bc77-410a-b346-4aba2fad66df)
Aug 7 11:14:23 dhcptest2 crmd: [2133]: info: mask(lrm.c:do_lrm_rsc_op): Performing op start on dhcpd_4 (interval=0ms, key=3:c53e3984-bc77-410a-b346-4aba2fad66df)
Aug 7 11:14:23 dhcptest2 lrmd: [2747]: WARN: For LSB init script, no additional parameters are needed.
Aug 7 11:14:23 dhcptest2 cib: [2745]: info: mask(io.c:write_cib_contents): Wrote version 0.1.31 of the CIB to disk (digest: 55ffc80a144988dd874b0dd65f5bb3d7)
Aug 7 11:14:23 dhcptest2 lrmd: [2130]: info: RA output: (dhcpd_4:start:stdout) Starting dhcpd:
Aug 7 11:14:23 dhcptest2 dhcpd: Internet Systems Consortium DHCP Server V3.0.3
Aug 7 11:14:23 dhcptest2 dhcpd: Internet Systems Consortium DHCP Server V3.0.3
Aug 7 11:14:23 dhcptest2 dhcpd:
Aug 7 11:14:23 dhcptest2 dhcpd: Copyright 2004-2005 Internet Systems Consortium.
Aug 7 11:14:23 dhcptest2 dhcpd: All rights reserved.
Aug 7 11:14:23 dhcptest2 dhcpd: For info, please visit http://www.isc.org/sw/dhcp/
Aug 7 11:14:23 dhcptest2 dhcpd: Copyright 2004-2005 Internet Systems Consortium.
Aug 7 11:14:23 dhcptest2 dhcpd: All rights reserved.
Aug 7 11:14:23 dhcptest2 dhcpd: For info, please visit http://www.isc.org/sw/dhcp/
Aug 7 11:14:23 dhcptest2 crmd: [2133]: info: mask(lrm.c:process_lrm_event): LRM operation (11) monitor_5000 on IPaddr_3 complete
Aug 7 11:14:23 dhcptest2 cib: [2129]: info: mask(notify.c:cib_diff_notify): Update (client: 2133, call:38): 0.1.31 -> 0.1.32 (ok)
Aug 7 11:14:23 dhcptest2 tengine: [2474]: info: mask(callbacks.c:te_update_diff): Processing diff (cib_update): 0.1.31 -> 0.1.32
Aug 7 11:14:23 dhcptest2 tengine: [2474]: info: mask(events.c:match_graph_event): Action IPaddr_3_monitor_5000 (6) confirmed
Aug 7 11:14:23 dhcptest2 cib: [2773]: info: mask(io.c:write_cib_contents): Wrote version 0.1.32 of the CIB to disk (digest: 2d95a964bf567827437c4dc4794d19ee)
Aug 7 11:14:23 dhcptest2 kernel: audit(1154963663.562:2): avc: denied { search } for pid=2763 comm="dhcpd" name="/" dev=drbd0 ino=2 scontext=user_u:system_r:dhcpd_t tcontext=system_u:object_r:file_t tclass=dir
Aug 7 11:14:23 dhcptest2 kernel: audit(1154963663.563:3): avc: denied { read } for pid=2763 comm="dhcpd" name="dhcpd.leases" dev=drbd0 ino=12 scontext=user_u:system_r:dhcpd_t tcontext=user_u:object_r:file_t tclass=file
Aug 7 11:14:23 dhcptest2 kernel: audit(1154963663.566:4): avc: denied { append } for pid=2763 comm="dhcpd" name="dhcpd.leases" dev=drbd0 ino=12 scontext=user_u:system_r:dhcpd_t tcontext=user_u:object_r:file_t tclass=file
Aug 7 11:14:23 dhcptest2 kernel: audit(1154963663.568:5): avc: denied { getattr } for pid=2763 comm="dhcpd" name="dhcpd.leases" dev=drbd0 ino=12 scontext=user_u:system_r:dhcpd_t tcontext=user_u:object_r:file_t tclass=file
Aug 7 11:14:23 dhcptest2 kernel: audit(1154963663.571:6): avc: denied { write } for pid=2763 comm="dhcpd" name="/" dev=drbd0 ino=2 scontext=user_u:system_r:dhcpd_t tcontext=system_u:object_r:file_t tclass=dir
Aug 7 11:14:23 dhcptest2 kernel: audit(1154963663.572:7): avc: denied { add_name } for pid=2763 comm="dhcpd" name="dhcpd.leases.1154963663" scontext=user_u:system_r:dhcpd_t tcontext=system_u:object_r:file_t tclass=dir
Aug 7 11:14:23 dhcptest2 kernel: audit(1154963663.572:8): avc: denied { create } for pid=2763 comm="dhcpd" name="dhcpd.leases.1154963663" scontext=user_u:system_r:dhcpd_t tcontext=user_u:object_r:file_t tclass=file
Aug 7 11:14:23 dhcptest2 dhcpd: Wrote 2 leases to leases file.
Aug 7 11:14:23 dhcptest2 dhcpd: Wrote 2 leases to leases file.
Aug 7 11:14:23 dhcptest2 dhcpd:
Aug 7 11:14:23 dhcptest2 kernel: audit(1154963663.581:9): avc: denied { write } for pid=2763 comm="dhcpd" name="dhcpd.leases.1154963663" dev=drbd0 ino=14 scontext=user_u:system_r:dhcpd_t tcontext=user_u:object_r:file_t tclass=file
Aug 7 11:14:23 dhcptest2 kernel: audit(1154963663.591:10): avc: denied { remove_name } for pid=2763 comm="dhcpd" name="dhcpd.leases~" dev=drbd0 ino=15 scontext=user_u:system_r:dhcpd_t tcontext=system_u:object_r:file_t tclass=dir
Aug 7 11:14:23 dhcptest2 kernel: audit(1154963663.591:11): avc: denied { unlink } for pid=2763 comm="dhcpd" name="dhcpd.leases~" dev=drbd0 ino=15 scontext=user_u:system_r:dhcpd_t tcontext=user_u:object_r:file_t tclass=file
Aug 7 11:14:23 dhcptest2 kernel: audit(1154963663.593:12): avc: denied { link } for pid=2763 comm="dhcpd" name="dhcpd.leases" dev=drbd0 ino=12 scontext=user_u:system_r:dhcpd_t tcontext=user_u:object_r:file_t tclass=file
Aug 7 11:14:23 dhcptest2 kernel: audit(1154963663.594:13): avc: denied { rename } for pid=2763 comm="dhcpd" name="dhcpd.leases.1154963663" dev=drbd0 ino=14 scontext=user_u:system_r:dhcpd_t tcontext=user_u:object_r:file_t tclass=file
Aug 7 11:14:23 dhcptest2 dhcpd:
Aug 7 11:14:23 dhcptest2 dhcpd:
Aug 7 11:14:23 dhcptest2 dhcpd: No subnet declaration for eth2 (10.168.111.2).
Aug 7 11:14:23 dhcptest2 dhcpd: No subnet declaration for eth2 (10.168.111.2).
Aug 7 11:14:23 dhcptest2 dhcpd:
Aug 7 11:14:23 dhcptest2 dhcpd: ** Ignoring requests on eth2. If this is not what
Aug 7 11:14:23 dhcptest2 dhcpd: ** Ignoring requests on eth2. If this is not what
Aug 7 11:14:23 dhcptest2 dhcpd:
Aug 7 11:14:23 dhcptest2 dhcpd: you want, please write a subnet declaration
Aug 7 11:14:23 dhcptest2 dhcpd: you want, please write a subnet declaration
Aug 7 11:14:23 dhcptest2 dhcpd: in your dhcpd.conf file for the network segment
Aug 7 11:14:23 dhcptest2 dhcpd:
Aug 7 11:14:23 dhcptest2 dhcpd: in your dhcpd.conf file for the network segment
Aug 7 11:14:23 dhcptest2 dhcpd: to which interface eth2 is attached. **
Aug 7 11:14:23 dhcptest2 dhcpd:
Aug 7 11:14:23 dhcptest2 dhcpd: to which interface eth2 is attached. **
Aug 7 11:14:23 dhcptest2 dhcpd:
Aug 7 11:14:23 dhcptest2 dhcpd:
Aug 7 11:14:23 dhcptest2 dhcpd: No subnet declaration for eth1 (163.246.242.241).
Aug 7 11:14:23 dhcptest2 dhcpd:
Aug 7 11:14:23 dhcptest2 dhcpd: ** Ignoring requests on eth1. If this is not what
Aug 7 11:14:23 dhcptest2 dhcpd: No subnet declaration for eth1 (163.246.242.241).
Aug 7 11:14:23 dhcptest2 dhcpd: you want, please write a subnet declaration
Aug 7 11:14:23 dhcptest2 dhcpd: ** Ignoring requests on eth1. If this is not what
Aug 7 11:14:23 dhcptest2 dhcpd: in your dhcpd.conf file for the network segment
Aug 7 11:14:23 dhcptest2 dhcpd: you want, please write a subnet declaration
Aug 7 11:14:23 dhcptest2 dhcpd: to which interface eth1 is attached. **
Aug 7 11:14:23 dhcptest2 dhcpd: in your dhcpd.conf file for the network segment
Aug 7 11:14:23 dhcptest2 dhcpd:
Aug 7 11:14:23 dhcptest2 dhcpd: to which interface eth1 is attached. **
Aug 7 11:14:23 dhcptest2 dhcpd:
Aug 7 11:14:23 dhcptest2 dhcpd: Listening on LPF/eth0/00:0c:29:6e:50:91/192.168.111/24
Aug 7 11:14:23 dhcptest2 dhcpd: Listening on LPF/eth0/00:0c:29:6e:50:91/192.168.111/24
Aug 7 11:14:23 dhcptest2 dhcpd: Sending on LPF/eth0/00:0c:29:6e:50:91/192.168.111/24
Aug 7 11:14:23 dhcptest2 dhcpd:
Aug 7 11:14:23 dhcptest2 dhcpd: Sending on Socket/fallback/fallback-net
Aug 7 11:14:23 dhcptest2 dhcpd: Sending on LPF/eth0/00:0c:29:6e:50:91/192.168.111/24
Aug 7 11:14:23 dhcptest2 dhcpd: Sending on Socket/fallback/fallback-net
Aug 7 11:14:23 dhcptest2 dhcpd: dhcpd startup succeeded
Aug 7 11:14:23 dhcptest2 lrmd: [2130]: info: RA output: (dhcpd_4:start:stdout) [
Aug 7 11:14:23 dhcptest2 lrmd: [2130]: info: RA output: (dhcpd_4:start:stdout) OK
Aug 7 11:14:23 dhcptest2 lrmd: [2130]: info: RA output: (dhcpd_4:start:stdout) ]
Aug 7 11:14:23 dhcptest2 lrmd: [2130]: info: RA output: (dhcpd_4:start:stdout)
Aug 7 11:14:23 dhcptest2 lrmd: [2130]: info: RA output: (dhcpd_4:start:stdout)
Aug 7 11:14:23 dhcptest2 dhcpd: dhcpd startup succeeded
Aug 7 11:14:23 dhcptest2 crmd: [2133]: info: mask(lrm.c:process_lrm_event): LRM operation (12) start_0 on dhcpd_4 complete
Aug 7 11:14:23 dhcptest2 cib: [2129]: info: mask(notify.c:cib_diff_notify): Update (client: 2133, call:39): 0.1.32 -> 0.1.33 (ok)
Aug 7 11:14:23 dhcptest2 tengine: [2474]: info: mask(callbacks.c:te_update_diff): Processing diff (cib_update): 0.1.32 -> 0.1.33
Aug 7 11:14:23 dhcptest2 tengine: [2474]: info: mask(events.c:match_graph_event): Action dhcpd_4_start_0 (7) confirmed
Aug 7 11:14:23 dhcptest2 tengine: [2474]: info: mask(actions.c:te_pseudo_action): Pseudo action 10 confirmed
Aug 7 11:14:23 dhcptest2 tengine: [2474]: info: mask(actions.c:send_rsc_command): Initiating action 8: dhcpd_4_monitor_120000 on dhcptest2
Aug 7 11:14:23 dhcptest2 crmd: [2133]: info: mask(lrm.c:do_lrm_rsc_op): Performing op monitor on dhcpd_4 (interval=120000ms, key=3:c53e3984-bc77-410a-b346-4aba2fad66df)
Aug 7 11:14:24 dhcptest2 cib: [2779]: info: mask(io.c:write_cib_contents): Wrote version 0.1.33 of the CIB to disk (digest: a6b94eef97d331f3a219bece91331e7e)
Aug 7 11:14:24 dhcptest2 crmd: [2133]: info: mask(lrm.c:process_lrm_event): LRM operation (13) monitor_120000 on dhcpd_4 complete
Aug 7 11:14:24 dhcptest2 cib: [2129]: info: mask(notify.c:cib_diff_notify): Update (client: 2133, call:40): 0.1.33 -> 0.1.34 (ok)
Aug 7 11:14:24 dhcptest2 tengine: [2474]: info: mask(callbacks.c:te_update_diff): Processing diff (cib_update): 0.1.33 -> 0.1.34
Aug 7 11:14:24 dhcptest2 tengine: [2474]: info: mask(events.c:match_graph_event): Action dhcpd_4_monitor_120000 (8) confirmed
Aug 7 11:14:24 dhcptest2 crmd: [2133]: info: mask(fsa.c:do_state_transition): dhcptest2: State transition S_TRANSITION_ENGINE -> S_IDLE [ input=I_TE_SUCCESS cause=C_IPC_MESSAGE origin=do_msg_route ]
Aug 7 11:14:24 dhcptest2 tengine: [2474]: info: mask(graph.c:run_graph): Transition 3: (Complete=10, Pending=0, Fired=0, Skipped=0, Incomplete=0)
Aug 7 11:14:24 dhcptest2 tengine: [2474]: info: mask(actions.c:notify_crmd): Transition 3 status: te_complete - (null)
Aug 7 11:14:24 dhcptest2 cib: [2784]: info: mask(io.c:write_cib_contents): Wrote version 0.1.34 of the CIB to disk (digest: 65edda56979febdcf5e69dfa5f154182)
Aug 7 11:15:26 dhcptest2 ntpd[1898]: synchronized to 163.246.48.100, stratum 2
Aug 7 11:15:26 dhcptest2 ntpd[1898]: synchronized to 163.246.16.100, stratum 2
More information about the Linux-HA
mailing list