logd[14615]: 2007/10/19_11:27:07 info: logd started with /etc/logd.cf.
logd[14616]: 2007/10/19_11:27:07 info: G_main_add_SignalHandler: Added signal handler for signal 15
logd[14615]: 2007/10/19_11:27:07 info: G_main_add_SignalHandler: Added signal handler for signal 15
heartbeat[14636]: 2007/10/19_11:27:07 info: Enabling logging daemon 
heartbeat[14636]: 2007/10/19_11:27:07 info: logfile and debug file are those specified in logd config file (default /etc/logd.cf)
heartbeat[14636]: 2007/10/19_11:27:07 debug: add_option(keepalive,2)
heartbeat[14636]: 2007/10/19_11:27:07 debug: add_option(deadtime,30)
heartbeat[14636]: 2007/10/19_11:27:07 debug: add_option(initdead,30)
heartbeat[14636]: 2007/10/19_11:27:07 debug: add_option(warntime,20)
heartbeat[14636]: 2007/10/19_11:27:07 debug: add_option(udpport,694)
heartbeat[14636]: 2007/10/19_11:27:07 debug: add_option(bcast,eth2)
heartbeat[14636]: 2007/10/19_11:27:07 debug: add_option(node,prec370d)
heartbeat[14636]: 2007/10/19_11:27:07 debug: add_option(node,prec370e)
heartbeat[14636]: 2007/10/19_11:27:07 debug: uid=hacluster, gid=<null>
heartbeat[14636]: 2007/10/19_11:27:07 debug: uid=hacluster, gid=<null>
heartbeat[14636]: 2007/10/19_11:27:07 debug: uid=<null>, gid=haclient
heartbeat[14636]: 2007/10/19_11:27:07 debug: uid=root, gid=<null>
heartbeat[14636]: 2007/10/19_11:27:07 debug: uid=<null>, gid=haclient
heartbeat[14636]: 2007/10/19_11:27:07 debug: Beginning authentication parsing
heartbeat[14636]: 2007/10/19_11:27:07 debug: 16 max authentication methods
heartbeat[14636]: 2007/10/19_11:27:07 debug: Keyfile opened
heartbeat[14636]: 2007/10/19_11:27:07 debug: Keyfile perms OK
heartbeat[14636]: 2007/10/19_11:27:07 debug: 16 max authentication methods
heartbeat[14636]: 2007/10/19_11:27:07 debug: Found authentication method [sha1]
heartbeat[14636]: 2007/10/19_11:27:07 info: AUTH: i=1: key = 0x5d92f8, auth=0x2a96103900, authname=sha1
heartbeat[14636]: 2007/10/19_11:27:07 debug: Outbound signing method is 1
heartbeat[14636]: 2007/10/19_11:27:07 debug: Authentication parsing complete [1]
heartbeat[14636]: 2007/10/19_11:27:07 debug: add_option(cluster,linux-ha)
heartbeat[14636]: 2007/10/19_11:27:07 debug: add_option(hopfudge,1)
heartbeat[14636]: 2007/10/19_11:27:07 debug: add_option(baud,19200)
heartbeat[14636]: 2007/10/19_11:27:07 debug: add_option(auto_failback,legacy)
heartbeat[14636]: 2007/10/19_11:27:07 debug: add_option(hbgenmethod,file)
heartbeat[14636]: 2007/10/19_11:27:07 debug: add_option(realtime,true)
heartbeat[14636]: 2007/10/19_11:27:07 debug: add_option(msgfmt,classic)
heartbeat[14636]: 2007/10/19_11:27:07 debug: add_option(conn_logd_time,60)
heartbeat[14636]: 2007/10/19_11:27:07 debug: add_option(log_badpack,true)
heartbeat[14636]: 2007/10/19_11:27:07 debug: add_option(syslogmsgfmt,false)
heartbeat[14636]: 2007/10/19_11:27:07 debug: add_option(coredumps,true)
heartbeat[14636]: 2007/10/19_11:27:07 debug: add_option(autojoin,none)
heartbeat[14636]: 2007/10/19_11:27:07 debug: add_option(uuidfrom,file)
heartbeat[14636]: 2007/10/19_11:27:07 debug: add_option(compression,zlib)
heartbeat[14636]: 2007/10/19_11:27:07 debug: add_option(compression_threshold,2)
heartbeat[14636]: 2007/10/19_11:27:07 debug: add_option(traditional_compression,no)
heartbeat[14636]: 2007/10/19_11:27:07 debug: add_option(max_rexmit_delay,250)
heartbeat[14636]: 2007/10/19_11:27:07 debug: Setting max_rexmit_delay to 250 ms
heartbeat[14636]: 2007/10/19_11:27:07 debug: add_option(record_config_changes,on)
heartbeat[14636]: 2007/10/19_11:27:07 debug: add_option(record_pengine_inputs,on)
heartbeat[14636]: 2007/10/19_11:27:07 debug: add_option(enable_config_writes,on)
heartbeat[14636]: 2007/10/19_11:27:07 debug: add_option(memreserve,6500)
heartbeat[14636]: 2007/10/19_11:27:07 info: **************************
heartbeat[14636]: 2007/10/19_11:27:07 info: Configuration validated. Starting heartbeat 2.1.3
heartbeat[14636]: 2007/10/19_11:27:07 debug: HA configuration OK.  Heartbeat starting.
heartbeat[14637]: 2007/10/19_11:27:07 info: heartbeat: version 2.1.3
heartbeat[14637]: 2007/10/19_11:27:07 info: Heartbeat generation: 894
heartbeat[14637]: 2007/10/19_11:27:07 debug: uuid is:6a42b4b7-7a31-4ae7-b241-ed4ad4267ec6
heartbeat[14637]: 2007/10/19_11:27:07 info: G_main_add_TriggerHandler: Added signal manual handler
heartbeat[14637]: 2007/10/19_11:27:07 info: G_main_add_TriggerHandler: Added signal manual handler
heartbeat[14637]: 2007/10/19_11:27:07 info: Removing /var/run/heartbeat/rsctmp failed, recreating.
heartbeat[14637]: 2007/10/19_11:27:07 debug: opening bcast eth2 (UDP/IP broadcast)
heartbeat[14637]: 2007/10/19_11:27:07 debug: FIFO process pid: 14641
heartbeat[14637]: 2007/10/19_11:27:07 debug: glib: SO_BINDTODEVICE(r) set for device eth2
heartbeat[14637]: 2007/10/19_11:27:07 info: glib: UDP Broadcast heartbeat started on port 694 (694) interface eth2
heartbeat[14637]: 2007/10/19_11:27:07 debug: write process pid: 14642
heartbeat[14637]: 2007/10/19_11:27:07 debug: read child process pid: 14643
heartbeat[14637]: 2007/10/19_11:27:07 info: glib: UDP Broadcast heartbeat closed on port 694 interface eth2 - Status: 1
heartbeat[14637]: 2007/10/19_11:27:07 info: G_main_add_SignalHandler: Added signal handler for signal 17
heartbeat[14637]: 2007/10/19_11:27:07 debug: Limiting CPU: 42 CPU seconds every 60000 milliseconds
heartbeat[14637]: 2007/10/19_11:27:07 debug: pid 14637 locked in memory.
heartbeat[14637]: 2007/10/19_11:27:07 debug: Waiting for child processes to start
heartbeat[14637]: 2007/10/19_11:27:07 info: Local status now set to: 'up'
heartbeat[14637]: 2007/10/19_11:27:07 debug: All your child process are belong to us
heartbeat[14637]: 2007/10/19_11:27:07 debug: Starting local status message @ 2000 ms intervals
heartbeat[14641]: 2007/10/19_11:27:08 debug: pid 14641 locked in memory.
heartbeat[14641]: 2007/10/19_11:27:08 debug: Limiting CPU: 6 CPU seconds every 60000 milliseconds
heartbeat[14642]: 2007/10/19_11:27:08 debug: pid 14642 locked in memory.
heartbeat[14642]: 2007/10/19_11:27:08 debug: Limiting CPU: 24 CPU seconds every 60000 milliseconds
heartbeat[14643]: 2007/10/19_11:27:08 debug: pid 14643 locked in memory.
heartbeat[14643]: 2007/10/19_11:27:08 debug: Limiting CPU: 6 CPU seconds every 60000 milliseconds
heartbeat[14637]: 2007/10/19_11:27:08 info: Link prec370d:eth2 up.
heartbeat[14637]: 2007/10/19_11:27:09 info: Link prec370e:eth2 up.
heartbeat[14637]: 2007/10/19_11:27:09 debug: sending reqnodes msg to node prec370e
heartbeat[14637]: 2007/10/19_11:27:09 info: Status update for node prec370e: status up
heartbeat[14637]: 2007/10/19_11:27:09 debug: Status seqno: 2 msgtime: 1192760828
heartbeat[14637]: 2007/10/19_11:27:09 debug: Get a reqnodes message from prec370e
heartbeat[14637]: 2007/10/19_11:27:09 debug: get_delnodelist: delnodelist= 
heartbeat[14637]: 2007/10/19_11:27:09 debug: Get a repnodes msg from prec370e
heartbeat[14637]: 2007/10/19_11:27:09 debug: nodelist received:prec370d prec370e 
heartbeat[14637]: 2007/10/19_11:27:09 info: Comm_now_up(): updating status to active
heartbeat[14637]: 2007/10/19_11:27:09 info: Local status now set to: 'active'
heartbeat[14637]: 2007/10/19_11:27:09 info: Starting child client "/usr/lib64/heartbeat/ccm" (90,90)
heartbeat[14637]: 2007/10/19_11:27:09 info: Starting child client "/usr/lib64/heartbeat/cib" (90,90)
heartbeat[14637]: 2007/10/19_11:27:09 info: Starting child client "/usr/lib64/heartbeat/lrmd -r" (0,0)
heartbeat[14637]: 2007/10/19_11:27:09 info: Starting child client "/usr/lib64/heartbeat/stonithd" (0,0)
heartbeat[14637]: 2007/10/19_11:27:09 info: Starting child client "/usr/lib64/heartbeat/attrd" (90,90)
heartbeat[14637]: 2007/10/19_11:27:09 info: Starting child client "/usr/lib64/heartbeat/crmd" (90,90)
heartbeat[14637]: 2007/10/19_11:27:09 info: Starting child client "/usr/lib64/heartbeat/mgmtd -v" (0,0)
heartbeat[14637]: 2007/10/19_11:27:09 info: Status update for node prec370e: status active
heartbeat[14637]: 2007/10/19_11:27:09 debug: Status seqno: 6 msgtime: 1192760829
heartbeat[14646]: 2007/10/19_11:27:09 info: Starting "/usr/lib64/heartbeat/ccm" as uid 90  gid 90 (pid 14646)
heartbeat[14647]: 2007/10/19_11:27:09 info: Starting "/usr/lib64/heartbeat/cib" as uid 90  gid 90 (pid 14647)
heartbeat[14648]: 2007/10/19_11:27:09 info: Starting "/usr/lib64/heartbeat/lrmd -r" as uid 0  gid 0 (pid 14648)
heartbeat[14649]: 2007/10/19_11:27:09 info: Starting "/usr/lib64/heartbeat/stonithd" as uid 0  gid 0 (pid 14649)
heartbeat[14650]: 2007/10/19_11:27:09 info: Starting "/usr/lib64/heartbeat/attrd" as uid 90  gid 90 (pid 14650)
heartbeat[14651]: 2007/10/19_11:27:09 info: Starting "/usr/lib64/heartbeat/crmd" as uid 90  gid 90 (pid 14651)
heartbeat[14652]: 2007/10/19_11:27:09 info: Starting "/usr/lib64/heartbeat/mgmtd -v" as uid 0  gid 0 (pid 14652)
ccm[14646]: 2007/10/19_11:27:09 debug: Signing in with Heartbeat
heartbeat[14637]: 2007/10/19_11:27:09 debug: APIregistration_dispatch() {
heartbeat[14637]: 2007/10/19_11:27:09 debug: process_registerevent() {
heartbeat[14637]: 2007/10/19_11:27:09 debug: client->gsource = 0x5ef968
heartbeat[14637]: 2007/10/19_11:27:09 debug: }/*process_registerevent*/;
heartbeat[14637]: 2007/10/19_11:27:09 debug: }/*APIregistration_dispatch*/;
heartbeat[14637]: 2007/10/19_11:27:09 debug: Checking client authorization for client ccm (90:90)
heartbeat[14637]: 2007/10/19_11:27:09 debug: create_seq_snapshot_table:no missing packets found for node prec370d
heartbeat[14637]: 2007/10/19_11:27:09 debug: create_seq_snapshot_table:no missing packets found for node prec370e
heartbeat[14637]: 2007/10/19_11:27:09 debug: Signing on API client 14646 (ccm)
lrmd[14648]: 2007/10/19_11:27:09 info: G_main_add_SignalHandler: Added signal handler for signal 15
cib[14647]: 2007/10/19_11:27:09 info: G_main_add_SignalHandler: Added signal handler for signal 15
cib[14647]: 2007/10/19_11:27:09 info: G_main_add_TriggerHandler: Added signal manual handler
cib[14647]: 2007/10/19_11:27:09 info: G_main_add_SignalHandler: Added signal handler for signal 17
cib[14647]: 2007/10/19_11:27:09 info: main: Retrieval of a per-action CIB: disabled
cib[14647]: 2007/10/19_11:27:09 info: retrieveCib: Reading cluster configuration from: /var/lib/heartbeat/crm/cib.xml (digest: /var/lib/heartbeat/crm/cib.xml.sig)
cib[14647]: 2007/10/19_11:27:09 WARN: validate_cib_digest: No on-disk digest present
cib[14647]: 2007/10/19_11:27:09 info: log_data_element: readCibXmlFile: [on-disk] <cib admin_epoch="0" epoch="1" num_updates="1" have_quorum="false" cib_feature_revision="1.3" generated="false">
cib[14647]: 2007/10/19_11:27:09 info: log_data_element: readCibXmlFile: [on-disk]   <configuration>
cib[14647]: 2007/10/19_11:27:09 info: log_data_element: readCibXmlFile: [on-disk]     <crm_config>
cib[14647]: 2007/10/19_11:27:09 info: log_data_element: readCibXmlFile: [on-disk]       <cluster_property_set id="idCluseterPropertySet">
cib[14647]: 2007/10/19_11:27:09 info: log_data_element: readCibXmlFile: [on-disk]         <attributes>
cib[14647]: 2007/10/19_11:27:09 info: log_data_element: readCibXmlFile: [on-disk]           <nvpair id="symmetric-cluster" name="symmetric-cluster" value="true"/>
cib[14647]: 2007/10/19_11:27:09 info: log_data_element: readCibXmlFile: [on-disk]           <nvpair id="no-quorum-policy" name="no-quorum-policy" value="ignore"/>
cib[14647]: 2007/10/19_11:27:09 info: log_data_element: readCibXmlFile: [on-disk]           <nvpair id="stonith-enabled" name="stonith-enabled" value="false"/>
cib[14647]: 2007/10/19_11:27:09 info: log_data_element: readCibXmlFile: [on-disk]           <nvpair id="short-resource-names" name="short_resource_names" value="true"/>
cib[14647]: 2007/10/19_11:27:09 info: log_data_element: readCibXmlFile: [on-disk]           <nvpair id="is-managed-default" name="is-managed-default" value="true"/>
lrmd[14648]: 2007/10/19_11:27:09 debug: LRM debug level set to 1
cib[14647]: 2007/10/19_11:27:09 info: log_data_element: readCibXmlFile: [on-disk]           <nvpair id="default-resource-stickiness" name="default-resource-stickiness" value="INFINITY"/>
cib[14647]: 2007/10/19_11:27:09 info: log_data_element: readCibXmlFile: [on-disk]           <nvpair id="stop-orphan-resources" name="stop-orphan-resources" value="true"/>
cib[14647]: 2007/10/19_11:27:09 info: log_data_element: readCibXmlFile: [on-disk]           <nvpair id="stop-orphan-actions" name="stop-orphan-actions" value="true"/>
cib[14647]: 2007/10/19_11:27:09 info: log_data_element: readCibXmlFile: [on-disk]           <nvpair id="remove-after-stop" name="remove-after-stop" value="false"/>
cib[14647]: 2007/10/19_11:27:09 info: log_data_element: readCibXmlFile: [on-disk]           <nvpair id="default-resource-failure-stickiness" name="default-resource-failure-stickiness" value="-INFINITY"/>
cib[14647]: 2007/10/19_11:27:09 info: log_data_element: readCibXmlFile: [on-disk]           <nvpair id="stonith-action" name="stonith-action" value="reboot"/>
cib[14647]: 2007/10/19_11:27:09 info: log_data_element: readCibXmlFile: [on-disk]           <nvpair id="default-action-timeout" name="default-action-timeout" value="120s"/>
cib[14647]: 2007/10/19_11:27:09 info: log_data_element: readCibXmlFile: [on-disk]           <nvpair id="dc_deadtime" name="dc_deadtime" value="10s"/>
cib[14647]: 2007/10/19_11:27:09 info: log_data_element: readCibXmlFile: [on-disk]           <nvpair id="cluster_recheck_interval" name="cluster_recheck_interval" value="0"/>
cib[14647]: 2007/10/19_11:27:09 info: log_data_element: readCibXmlFile: [on-disk]           <nvpair id="election_timeout" name="election_timeout" value="2min"/>
cib[14647]: 2007/10/19_11:27:09 info: log_data_element: readCibXmlFile: [on-disk]           <nvpair id="shutdown_escalation" name="shutdown_escalation" value="20min"/>
cib[14647]: 2007/10/19_11:27:09 info: log_data_element: readCibXmlFile: [on-disk]           <nvpair id="crmd-integration-timeout" name="crmd-integration-timeout" value="3min"/>
cib[14647]: 2007/10/19_11:27:09 info: log_data_element: readCibXmlFile: [on-disk]           <nvpair id="crmd-finalization-timeout" name="crmd-finalization-timeout" value="10min"/>
cib[14647]: 2007/10/19_11:27:09 info: log_data_element: readCibXmlFile: [on-disk]           <nvpair id="cluster-delay" name="cluster-delay" value="120s"/>
cib[14647]: 2007/10/19_11:27:09 info: log_data_element: readCibXmlFile: [on-disk]           <nvpair id="pe-error-series-max" name="pe-error-series-max" value="-1"/>
cib[14647]: 2007/10/19_11:27:09 info: log_data_element: readCibXmlFile: [on-disk]           <nvpair id="pe-warn-series-max" name="pe-warn-series-max" value="-1"/>
cib[14647]: 2007/10/19_11:27:09 info: log_data_element: readCibXmlFile: [on-disk]           <nvpair id="pe-input-series-max" name="pe-input-series-max" value="-1"/>
cib[14647]: 2007/10/19_11:27:09 info: log_data_element: readCibXmlFile: [on-disk]           <nvpair id="startup-fencing" name="startup-fencing" value="true"/>
cib[14647]: 2007/10/19_11:27:09 info: log_data_element: readCibXmlFile: [on-disk]         </attributes>
cib[14647]: 2007/10/19_11:27:09 info: log_data_element: readCibXmlFile: [on-disk]       </cluster_property_set>
cib[14647]: 2007/10/19_11:27:09 info: log_data_element: readCibXmlFile: [on-disk]     </crm_config>
cib[14647]: 2007/10/19_11:27:09 info: log_data_element: readCibXmlFile: [on-disk]     <nodes/>
cib[14647]: 2007/10/19_11:27:09 info: log_data_element: readCibXmlFile: [on-disk]     <resources>
cib[14647]: 2007/10/19_11:27:09 info: log_data_element: readCibXmlFile: [on-disk]       <group id="grpDummy">
cib[14647]: 2007/10/19_11:27:09 info: log_data_element: readCibXmlFile: [on-disk]         <primitive id="prmDummyA" class="ocf" type="Dummy" provider="heartbeat">
cib[14647]: 2007/10/19_11:27:09 info: log_data_element: readCibXmlFile: [on-disk]           <operations>
cib[14647]: 2007/10/19_11:27:09 info: log_data_element: readCibXmlFile: [on-disk]             <op id="opDummyAStart" name="start" timeout="60s" on_fail="restart"/>
cib[14647]: 2007/10/19_11:27:09 info: log_data_element: readCibXmlFile: [on-disk]             <op id="opDummyAMonitor" name="monitor" interval="10s" timeout="10s" on_fail="restart"/>
cib[14647]: 2007/10/19_11:27:09 info: log_data_element: readCibXmlFile: [on-disk]             <op id="opDummyAStop" name="stop" timeout="60s" on_fail="restart"/>
cib[14647]: 2007/10/19_11:27:09 info: log_data_element: readCibXmlFile: [on-disk]           </operations>
cib[14647]: 2007/10/19_11:27:09 info: log_data_element: readCibXmlFile: [on-disk]         </primitive>
cib[14647]: 2007/10/19_11:27:09 info: log_data_element: readCibXmlFile: [on-disk]         <primitive id="prmDummyB" class="ocf" type="Dummy" provider="heartbeat">
cib[14647]: 2007/10/19_11:27:09 info: log_data_element: readCibXmlFile: [on-disk]           <operations>
cib[14647]: 2007/10/19_11:27:09 info: log_data_element: readCibXmlFile: [on-disk]             <op id="opDummyBStart" name="start" timeout="60s" on_fail="restart"/>
cib[14647]: 2007/10/19_11:27:09 info: log_data_element: readCibXmlFile: [on-disk]             <op id="opDummyBMonitor" name="monitor" interval="10s" timeout="10s" on_fail="restart"/>
cib[14647]: 2007/10/19_11:27:09 info: log_data_element: readCibXmlFile: [on-disk]             <op id="opDummyBStop" name="stop" timeout="60s" on_fail="restart"/>
cib[14647]: 2007/10/19_11:27:09 info: log_data_element: readCibXmlFile: [on-disk]           </operations>
cib[14647]: 2007/10/19_11:27:09 info: log_data_element: readCibXmlFile: [on-disk]         </primitive>
cib[14647]: 2007/10/19_11:27:09 info: log_data_element: readCibXmlFile: [on-disk]       </group>
cib[14647]: 2007/10/19_11:27:09 info: log_data_element: readCibXmlFile: [on-disk]     </resources>
cib[14647]: 2007/10/19_11:27:09 info: log_data_element: readCibXmlFile: [on-disk]     <constraints>
cib[14647]: 2007/10/19_11:27:09 info: log_data_element: readCibXmlFile: [on-disk]       <rsc_location id="rlcDummy" rsc="grpDummy">
cib[14647]: 2007/10/19_11:27:09 info: log_data_element: readCibXmlFile: [on-disk]         <rule score="200" id="rulNode1">
cib[14647]: 2007/10/19_11:27:09 info: log_data_element: readCibXmlFile: [on-disk]           <expression value="prec370d" attribute="#uname" operation="eq" id="expNode1"/>
cib[14647]: 2007/10/19_11:27:09 info: log_data_element: readCibXmlFile: [on-disk]         </rule>
cib[14647]: 2007/10/19_11:27:09 info: log_data_element: readCibXmlFile: [on-disk]         <rule score="300" id="rulNode2">
cib[14647]: 2007/10/19_11:27:09 info: log_data_element: readCibXmlFile: [on-disk]           <expression value="prec370e" attribute="#uname" operation="eq" id="expNode2"/>
cib[14647]: 2007/10/19_11:27:09 info: log_data_element: readCibXmlFile: [on-disk]         </rule>
cib[14647]: 2007/10/19_11:27:09 info: log_data_element: readCibXmlFile: [on-disk]       </rsc_location>
cib[14647]: 2007/10/19_11:27:09 info: log_data_element: readCibXmlFile: [on-disk]       <rsc_location id="ping:disconn" rsc="grpDummy">
cib[14647]: 2007/10/19_11:27:09 info: log_data_element: readCibXmlFile: [on-disk]         <rule id="ping1:disconn:rule" score="-INFINITY" boolean_op="and">
cib[14647]: 2007/10/19_11:27:09 info: log_data_element: readCibXmlFile: [on-disk]           <expression id="ping1:disconn:expr:defined" attribute="default_ping_set" operation="defined"/>
cib[14647]: 2007/10/19_11:27:09 info: log_data_element: readCibXmlFile: [on-disk]           <expression id="ping1:disconn:expr:positive" attribute="default_ping_set" operation="lt" value="100"/>
lrmd[14648]: 2007/10/19_11:27:09 info: G_main_add_SignalHandler: Added signal handler for signal 17
lrmd[14648]: 2007/10/19_11:27:09 debug: Enabling coredumps
cib[14647]: 2007/10/19_11:27:09 info: log_data_element: readCibXmlFile: [on-disk]         </rule>
cib[14647]: 2007/10/19_11:27:09 info: log_data_element: readCibXmlFile: [on-disk]       </rsc_location>
cib[14647]: 2007/10/19_11:27:09 info: log_data_element: readCibXmlFile: [on-disk]     </constraints>
cib[14647]: 2007/10/19_11:27:09 info: log_data_element: readCibXmlFile: [on-disk]   </configuration>
lrmd[14648]: 2007/10/19_11:27:09 info: G_main_add_SignalHandler: Added signal handler for signal 10
lrmd[14648]: 2007/10/19_11:27:09 info: G_main_add_SignalHandler: Added signal handler for signal 12
lrmd[14648]: 2007/10/19_11:27:09 debug: main: run the loop...
lrmd[14648]: 2007/10/19_11:27:09 info: Started.
cib[14647]: 2007/10/19_11:27:09 info: log_data_element: readCibXmlFile: [on-disk]   <status/>
cib[14647]: 2007/10/19_11:27:09 info: log_data_element: readCibXmlFile: [on-disk] </cib>
stonithd[14649]: 2007/10/19_11:27:09 info: G_main_add_SignalHandler: Added signal handler for signal 10
stonithd[14649]: 2007/10/19_11:27:09 info: G_main_add_SignalHandler: Added signal handler for signal 12
cib[14647]: 2007/10/19_11:27:09 notice: readCibXmlFile: Enabling DTD validation on the existing (sane) configuration
stonithd[14649]: 2007/10/19_11:27:09 debug: pid 14649 locked in memory.
heartbeat[14637]: 2007/10/19_11:27:09 debug: APIregistration_dispatch() {
heartbeat[14637]: 2007/10/19_11:27:09 debug: process_registerevent() {
heartbeat[14637]: 2007/10/19_11:27:09 debug: client->gsource = 0x5f0da8
heartbeat[14637]: 2007/10/19_11:27:09 debug: }/*process_registerevent*/;
heartbeat[14637]: 2007/10/19_11:27:09 debug: }/*APIregistration_dispatch*/;
heartbeat[14637]: 2007/10/19_11:27:09 debug: Checking client authorization for client stonithd (0:0)
stonithd[14649]: 2007/10/19_11:27:09 info: Signing in with heartbeat.
heartbeat[14637]: 2007/10/19_11:27:09 debug: create_seq_snapshot_table:no missing packets found for node prec370d
stonithd[14649]: 2007/10/19_11:27:09 debug: Setting message filter mode
heartbeat[14637]: 2007/10/19_11:27:09 debug: create_seq_snapshot_table:no missing packets found for node prec370e
heartbeat[14637]: 2007/10/19_11:27:09 debug: Signing on API client 14649 (stonithd)
ccm[14646]: 2007/10/19_11:27:09 info: Hostname: prec370d
cib[14647]: 2007/10/19_11:27:09 info: startCib: CIB Initialization completed successfully
cib[14647]: 2007/10/19_11:27:09 info: cib_register_ha: Signing in with Heartbeat
heartbeat[14637]: 2007/10/19_11:27:09 debug: APIregistration_dispatch() {
heartbeat[14637]: 2007/10/19_11:27:09 debug: process_registerevent() {
cib[14647]: 2007/10/19_11:27:09 info: cib_register_ha: FSA Hostname: prec370d
heartbeat[14637]: 2007/10/19_11:27:09 debug: client->gsource = 0x5f1138
heartbeat[14637]: 2007/10/19_11:27:09 debug: }/*process_registerevent*/;
heartbeat[14637]: 2007/10/19_11:27:09 debug: }/*APIregistration_dispatch*/;
cib[14647]: 2007/10/19_11:27:09 info: ccm_connect: Registering with CCM...
heartbeat[14637]: 2007/10/19_11:27:09 debug: Checking client authorization for client cib (90:90)
cib[14647]: 2007/10/19_11:27:09 WARN: ccm_connect: CCM Activation failed
heartbeat[14637]: 2007/10/19_11:27:09 debug: create_seq_snapshot_table:no missing packets found for node prec370d
cib[14647]: 2007/10/19_11:27:09 WARN: ccm_connect: CCM Connection failed 1 times (30 max)
heartbeat[14637]: 2007/10/19_11:27:09 debug: create_seq_snapshot_table:no missing packets found for node prec370e
heartbeat[14637]: 2007/10/19_11:27:09 debug: Signing on API client 14647 (cib)
stonithd[14649]: 2007/10/19_11:27:09 debug: apichan=0x519238
stonithd[14649]: 2007/10/19_11:27:09 debug: callback_chan=0x5197d8
stonithd[14649]: 2007/10/19_11:27:09 notice: /usr/lib64/heartbeat/stonithd start up successfully.
stonithd[14649]: 2007/10/19_11:27:09 info: G_main_add_SignalHandler: Added signal handler for signal 17
attrd[14650]: 2007/10/19_11:27:09 info: G_main_add_SignalHandler: Added signal handler for signal 15
attrd[14650]: 2007/10/19_11:27:09 debug: register_with_ha: Signing in with Heartbeat
heartbeat[14637]: 2007/10/19_11:27:09 debug: APIregistration_dispatch() {
heartbeat[14637]: 2007/10/19_11:27:09 debug: process_registerevent() {
attrd[14650]: 2007/10/19_11:27:09 info: register_with_ha: Hostname: prec370d
heartbeat[14637]: 2007/10/19_11:27:09 debug: client->gsource = 0x5f2138
heartbeat[14637]: 2007/10/19_11:27:09 debug: }/*process_registerevent*/;
heartbeat[14637]: 2007/10/19_11:27:09 debug: }/*APIregistration_dispatch*/;
heartbeat[14637]: 2007/10/19_11:27:09 debug: Checking client authorization for client attrd (90:90)
heartbeat[14637]: 2007/10/19_11:27:09 debug: create_seq_snapshot_table:no missing packets found for node prec370d
heartbeat[14637]: 2007/10/19_11:27:09 debug: create_seq_snapshot_table:no missing packets found for node prec370e
heartbeat[14637]: 2007/10/19_11:27:09 debug: Signing on API client 14650 (attrd)
mgmtd[14652]: 2007/10/19_11:27:09 info: G_main_add_SignalHandler: Added signal handler for signal 15
mgmtd[14652]: 2007/10/19_11:27:09 debug: Enabling coredumps
mgmtd[14652]: 2007/10/19_11:27:09 info: G_main_add_SignalHandler: Added signal handler for signal 10
mgmtd[14652]: 2007/10/19_11:27:09 info: G_main_add_SignalHandler: Added signal handler for signal 12
heartbeat[14637]: 2007/10/19_11:27:09 debug: APIregistration_dispatch() {
heartbeat[14637]: 2007/10/19_11:27:09 debug: process_registerevent() {
heartbeat[14637]: 2007/10/19_11:27:09 debug: client->gsource = 0x5f5548
heartbeat[14637]: 2007/10/19_11:27:09 debug: }/*process_registerevent*/;
lrmd[14648]: 2007/10/19_11:27:09 debug: on_msg_register:client mgmtd [14652] registered
heartbeat[14637]: 2007/10/19_11:27:09 debug: }/*APIregistration_dispatch*/;
heartbeat[14637]: 2007/10/19_11:27:09 debug: Checking client authorization for client mgmtd (0:0)
mgmtd[14652]: 2007/10/19_11:27:09 info: init_crm
heartbeat[14637]: 2007/10/19_11:27:09 debug: create_seq_snapshot_table:no missing packets found for node prec370d
heartbeat[14637]: 2007/10/19_11:27:09 debug: create_seq_snapshot_table:no missing packets found for node prec370e
heartbeat[14637]: 2007/10/19_11:27:09 debug: Signing on API client 14652 (mgmtd)
attrd[14650]: 2007/10/19_11:27:09 info: register_with_ha: UUID: 6a42b4b7-7a31-4ae7-b241-ed4ad4267ec6
attrd[14650]: 2007/10/19_11:27:09 debug: main: CIB signon attempt 0
crmd[14651]: 2007/10/19_11:27:09 info: main: CRM Hg Version: 2cff5374f61b tip

crmd[14651]: 2007/10/19_11:27:09 info: crmd_init: Starting crmd
crmd[14651]: 2007/10/19_11:27:09 debug: register_fsa_input_adv: crmd_init appended FSA input 1 (I_STARTUP) (cause=C_STARTUP) without data
crmd[14651]: 2007/10/19_11:27:09 debug: s_crmd_fsa: Processing I_STARTUP: [ state=S_STARTING cause=C_STARTUP origin=crmd_init ]
crmd[14651]: 2007/10/19_11:27:09 debug: do_fsa_action: actions:trace: 	// A_LOG   
crmd[14651]: 2007/10/19_11:27:09 debug: do_fsa_action: actions:trace: 	// A_STARTUP
crmd[14651]: 2007/10/19_11:27:09 debug: do_startup: Registering Signal Handlers
crmd[14651]: 2007/10/19_11:27:09 info: G_main_add_SignalHandler: Added signal handler for signal 15
crmd[14651]: 2007/10/19_11:27:09 info: G_main_add_TriggerHandler: Added signal manual handler
crmd[14651]: 2007/10/19_11:27:09 debug: do_startup: Creating CIB and LRM objects
crmd[14651]: 2007/10/19_11:27:09 debug: do_startup: Init server comms
crmd[14651]: 2007/10/19_11:27:09 info: G_main_add_SignalHandler: Added signal handler for signal 17
crmd[14651]: 2007/10/19_11:27:09 debug: do_fsa_action: actions:trace: 	// A_CIB_START
cib[14647]: 2007/10/19_11:27:10 info: ccm_connect: Registering with CCM...
cib[14647]: 2007/10/19_11:27:10 WARN: ccm_connect: CCM Activation failed
cib[14647]: 2007/10/19_11:27:10 WARN: ccm_connect: CCM Connection failed 2 times (30 max)
cib[14647]: 2007/10/19_11:27:11 info: ccm_connect: Registering with CCM...
cib[14647]: 2007/10/19_11:27:11 WARN: ccm_connect: CCM Activation failed
cib[14647]: 2007/10/19_11:27:11 WARN: ccm_connect: CCM Connection failed 3 times (30 max)
ccm[14646]: 2007/10/19_11:27:12 debug: node state CCM_STATE_NONE -> CCM_STATE_NONE
ccm[14646]: 2007/10/19_11:27:12 debug: node state CCM_STATE_NONE -> CCM_STATE_NONE
ccm[14646]: 2007/10/19_11:27:12 info: G_main_add_SignalHandler: Added signal handler for signal 15
cib[14647]: 2007/10/19_11:27:12 info: ccm_connect: Registering with CCM...
cib[14647]: 2007/10/19_11:27:12 debug: ccm_connect: CCM Activation passed... all set to go!
cib[14647]: 2007/10/19_11:27:13 debug: Delaying cstatus request for 168 ms
cib[14647]: 2007/10/19_11:27:13 info: cib_init: Starting cib mainloop
cib[14653]: 2007/10/19_11:27:13 debug: write_cib_contents: Archiving current version
cib[14653]: 2007/10/19_11:27:13 WARN: validate_cib_digest: No on-disk digest present
cib[14653]: 2007/10/19_11:27:13 info: retrieveCib: Reading cluster configuration from: /var/lib/heartbeat/crm/cib.xml (digest: /var/lib/heartbeat/crm/cib.xml.sig)
cib[14653]: 2007/10/19_11:27:13 WARN: validate_cib_digest: No on-disk digest present
cib[14653]: 2007/10/19_11:27:13 debug: archive_file: /var/lib/heartbeat/crm/cib.xml archived as /var/lib/heartbeat/crm/cib.xml.last
cib[14653]: 2007/10/19_11:27:13 info: retrieveCib: Reading cluster configuration from: /var/lib/heartbeat/crm/cib.xml (digest: /var/lib/heartbeat/crm/cib.xml.sig)
cib[14653]: 2007/10/19_11:27:13 WARN: validate_cib_digest: No on-disk digest present
cib[14653]: 2007/10/19_11:27:13 info: retrieveCib: Reading cluster configuration from: /var/lib/heartbeat/crm/cib.xml.last (digest: /var/lib/heartbeat/crm/cib.xml.sig.last)
cib[14653]: 2007/10/19_11:27:13 WARN: validate_cib_digest: No on-disk digest present
cib[14653]: 2007/10/19_11:27:13 debug: write_cib_contents: Verified CIB archive
cib[14647]: 2007/10/19_11:27:13 info: cib_null_callback: Setting cib_diff_notify callbacks for mgmtd: on
attrd[14650]: 2007/10/19_11:27:13 debug: cib_native_signon: Connection to CIB successful
crmd[14651]: 2007/10/19_11:27:13 debug: cib_native_signon: Connection to CIB successful
cib[14647]: 2007/10/19_11:27:13 info: cib_null_callback: Setting cib_refresh_notify callbacks for crmd: on
crmd[14651]: 2007/10/19_11:27:13 info: do_cib_control: CIB connection established
crmd[14651]: 2007/10/19_11:27:13 debug: do_fsa_action: actions:trace: 	// A_HA_CONNECT
crmd[14651]: 2007/10/19_11:27:13 debug: register_with_ha: Signing in with Heartbeat
heartbeat[14637]: 2007/10/19_11:27:13 debug: APIregistration_dispatch() {
heartbeat[14637]: 2007/10/19_11:27:13 debug: process_registerevent() {
heartbeat[14637]: 2007/10/19_11:27:13 debug: client->gsource = 0x5feab8
crmd[14651]: 2007/10/19_11:27:13 info: register_with_ha: Hostname: prec370d
heartbeat[14637]: 2007/10/19_11:27:13 debug: }/*process_registerevent*/;
heartbeat[14637]: 2007/10/19_11:27:13 debug: }/*APIregistration_dispatch*/;
heartbeat[14637]: 2007/10/19_11:27:13 debug: Checking client authorization for client crmd (90:90)
heartbeat[14637]: 2007/10/19_11:27:13 debug: create_seq_snapshot_table:no missing packets found for node prec370d
heartbeat[14637]: 2007/10/19_11:27:13 debug: create_seq_snapshot_table:no missing packets found for node prec370e
heartbeat[14637]: 2007/10/19_11:27:13 debug: Signing on API client 14651 (crmd)
cib[14647]: 2007/10/19_11:27:13 info: cib_client_status_callback: Status update: Client prec370d/cib now has status [join]
cib[14647]: 2007/10/19_11:27:13 debug: set_connected_peers: We now have 1 active peers
cib[14647]: 2007/10/19_11:27:13 info: cib_client_status_callback: Status update: Client prec370d/cib now has status [online]
cib[14653]: 2007/10/19_11:27:13 debug: write_cib_contents: Wrote CIB to disk
cib[14653]: 2007/10/19_11:27:13 info: write_cib_contents: Wrote version 0.1.1 of the CIB to disk (digest: becd2844a316c3777538690bef8fce7a)
cib[14653]: 2007/10/19_11:27:13 debug: write_cib_contents: Wrote digest to disk
cib[14653]: 2007/10/19_11:27:13 info: retrieveCib: Reading cluster configuration from: /var/lib/heartbeat/crm/cib.xml (digest: /var/lib/heartbeat/crm/cib.xml.sig)
cib[14653]: 2007/10/19_11:27:13 info: retrieveCib: Reading cluster configuration from: /var/lib/heartbeat/crm/cib.xml.last (digest: /var/lib/heartbeat/crm/cib.xml.sig.last)
cib[14653]: 2007/10/19_11:27:13 WARN: validate_cib_digest: No on-disk digest present
cib[14653]: 2007/10/19_11:27:13 debug: write_cib_contents: Wrote and verified CIB
cib[14647]: 2007/10/19_11:27:13 info: cib_client_status_callback: Status update: Client prec370e/cib now has status [online]
cib[14647]: 2007/10/19_11:27:13 debug: set_connected_peers: We now have 2 active peers
crmd[14651]: 2007/10/19_11:27:13 info: register_with_ha: UUID: 6a42b4b7-7a31-4ae7-b241-ed4ad4267ec6
crmd[14651]: 2007/10/19_11:27:13 debug: Delaying cstatus request for 7 ms
ccm[14646]: 2007/10/19_11:27:13 debug: recv msg hbapi-clstat from prec370d, status:join
crmd[14651]: 2007/10/19_11:27:13 info: populate_cib_nodes: Requesting the list of configured nodes
heartbeat[14637]: 2007/10/19_11:27:14 WARN: 1 lost packet(s) for [prec370e] [16:18]
heartbeat[14637]: 2007/10/19_11:27:14 info: No pkts missing from prec370e!
ccm[14646]: 2007/10/19_11:27:14 debug: send msg CCM_TYPE_PROTOVERSION to cluster, status:[null]
ccm[14646]: 2007/10/19_11:27:14 debug: node state CCM_STATE_NONE -> CCM_STATE_VERSION_REQUEST
ccm[14646]: 2007/10/19_11:27:14 debug: recv msg CCM_TYPE_PROTOVERSION from prec370d, status:[null ptr]
mgmtd[14652]: 2007/10/19_11:27:15 debug: main: run the loop...
mgmtd[14652]: 2007/10/19_11:27:15 info: Started.
crmd[14651]: 2007/10/19_11:27:15 notice: populate_cib_nodes: Node: prec370e (uuid: 9d9ca527-cea9-470c-9e03-e49fe5630bba)
heartbeat[14637]: 2007/10/19_11:27:15 WARN: 1 lost packet(s) for [prec370e] [21:23]
heartbeat[14637]: 2007/10/19_11:27:15 info: No pkts missing from prec370e!
ccm[14646]: 2007/10/19_11:27:15 debug: recv msg CCM_TYPE_PROTOVERSION from prec370e, status:[null ptr]
crmd[14651]: 2007/10/19_11:27:15 notice: populate_cib_nodes: Node: prec370d (uuid: 6a42b4b7-7a31-4ae7-b241-ed4ad4267ec6)
crmd[14651]: 2007/10/19_11:27:15 info: do_ha_control: Connected to Heartbeat
lrmd[14648]: 2007/10/19_11:27:15 debug: on_msg_register:client crmd [14651] registered
crmd[14651]: 2007/10/19_11:27:15 debug: do_fsa_action: actions:trace: 	// A_READCONFIG
crmd[14651]: 2007/10/19_11:27:15 debug: do_fsa_action: actions:trace: 	// A_LRM_CONNECT
crmd[14651]: 2007/10/19_11:27:15 debug: do_lrm_control: Connecting to the LRM
crmd[14651]: 2007/10/19_11:27:15 debug: do_lrm_control: LRM connection established
crmd[14651]: 2007/10/19_11:27:15 debug: do_fsa_action: actions:trace: 	// A_CCM_CONNECT
crmd[14651]: 2007/10/19_11:27:15 info: do_ccm_control: CCM connection established... waiting for first callback
crmd[14651]: 2007/10/19_11:27:15 debug: do_fsa_action: actions:trace: 	// A_STARTED
crmd[14651]: 2007/10/19_11:27:15 info: do_started: Delaying start, CCM (0000000000100000) not connected
crmd[14651]: 2007/10/19_11:27:15 debug: register_fsa_input_adv: do_started prepended FSA input 2 (I_WAIT_FOR_EVENT) (cause=C_FSA_INTERNAL) without data
crmd[14651]: 2007/10/19_11:27:15 debug: register_fsa_input_adv: Stalling the FSA pending further input: cause=C_FSA_INTERNAL
crmd[14651]: 2007/10/19_11:27:15 debug: s_crmd_fsa: Exiting the FSA: queue=0, fsa_actions=0x2, stalled=true
crmd[14651]: 2007/10/19_11:27:15 debug: fsa_dump_inputs: Added input: 0000000000000100 (R_CIB_CONNECTED)
crmd[14651]: 2007/10/19_11:27:15 debug: fsa_dump_inputs: Added input: 0000000000000800 (R_LRM_CONNECTED)
crmd[14651]: 2007/10/19_11:27:15 info: crmd_init: Starting crmd's mainloop
crmd[14651]: 2007/10/19_11:27:15 notice: crmd_client_status_callback: Status update: Client prec370d/crmd now has status [online]
mgmtd[14652]: 2007/10/19_11:27:15 debug: update cib finished
cib[14654]: 2007/10/19_11:27:15 debug: write_cib_contents: Archiving current version
crmd[14651]: 2007/10/19_11:27:15 notice: crmd_client_status_callback: Status update: Client prec370e/crmd now has status [online]
cib[14654]: 2007/10/19_11:27:15 info: retrieveCib: Reading cluster configuration from: /var/lib/heartbeat/crm/cib.xml (digest: /var/lib/heartbeat/crm/cib.xml.sig)
cib[14647]: 2007/10/19_11:27:15 debug: update_cib_object: done
cib[14654]: 2007/10/19_11:27:15 debug: archive_file: /var/lib/heartbeat/crm/cib.xml archived as /var/lib/heartbeat/crm/cib.xml.last
cib[14654]: 2007/10/19_11:27:15 debug: archive_file: /var/lib/heartbeat/crm/cib.xml.sig archived as /var/lib/heartbeat/crm/cib.xml.sig.last
cib[14654]: 2007/10/19_11:27:15 info: retrieveCib: Reading cluster configuration from: /var/lib/heartbeat/crm/cib.xml (digest: /var/lib/heartbeat/crm/cib.xml.sig)
cib[14654]: 2007/10/19_11:27:15 info: retrieveCib: Reading cluster configuration from: /var/lib/heartbeat/crm/cib.xml.last (digest: /var/lib/heartbeat/crm/cib.xml.sig.last)
cib[14654]: 2007/10/19_11:27:15 debug: write_cib_contents: Verified CIB archive
mgmtd[14652]: 2007/10/19_11:27:15 debug: update cib finished
cib[14654]: 2007/10/19_11:27:15 debug: write_cib_contents: Wrote CIB to disk
cib[14654]: 2007/10/19_11:27:15 info: write_cib_contents: Wrote version 0.1.1 of the CIB to disk (digest: 5833cf657d2605eca6ecdd0fe27ce18f)
cib[14654]: 2007/10/19_11:27:15 debug: write_cib_contents: Wrote digest to disk
cib[14654]: 2007/10/19_11:27:15 info: retrieveCib: Reading cluster configuration from: /var/lib/heartbeat/crm/cib.xml (digest: /var/lib/heartbeat/crm/cib.xml.sig)
cib[14654]: 2007/10/19_11:27:15 info: retrieveCib: Reading cluster configuration from: /var/lib/heartbeat/crm/cib.xml.last (digest: /var/lib/heartbeat/crm/cib.xml.sig.last)
cib[14654]: 2007/10/19_11:27:15 debug: write_cib_contents: Wrote and verified CIB
ccm[14646]: 2007/10/19_11:27:16 debug: recv msg CCM_TYPE_PROTOVERSION_RESP from prec370e, status:[null ptr]
ccm[14646]: 2007/10/19_11:27:16 debug: send msg CCM_TYPE_ALIVE to cluster, status:[null]
ccm[14646]: 2007/10/19_11:27:16 debug: node state CCM_STATE_VERSION_REQUEST -> CCM_STATE_NEW_NODE_WAIT_FOR_MEM_LIST
crmd[14651]: 2007/10/19_11:27:16 notice: crmd_client_status_callback: Status update: Client prec370d/crmd now has status [online]
cib[14647]: 2007/10/19_11:27:16 debug: update_cib_object: done
mgmtd[14652]: 2007/10/19_11:27:16 debug: update cib finished
ccm[14646]: 2007/10/19_11:27:16 debug: recv msg CCM_TYPE_ALIVE from prec370d, status:[null ptr]
cib[14647]: 2007/10/19_11:27:16 debug: update_cib_object: done
crmd[14651]: 2007/10/19_11:27:16 notice: crmd_client_status_callback: Status update: Client prec370e/crmd now has status [online]
cib[14647]: 2007/10/19_11:27:16 debug: update_cib_object: done
crmd[14651]: 2007/10/19_11:27:16 debug: do_fsa_action: actions:trace: 	// A_STARTED
crmd[14651]: 2007/10/19_11:27:16 info: do_started: Delaying start, CCM (0000000000100000) not connected
crmd[14651]: 2007/10/19_11:27:16 debug: register_fsa_input_adv: do_started prepended FSA input 3 (I_WAIT_FOR_EVENT) (cause=C_FSA_INTERNAL) without data
crmd[14651]: 2007/10/19_11:27:16 debug: register_fsa_input_adv: Stalling the FSA pending further input: cause=C_FSA_INTERNAL
crmd[14651]: 2007/10/19_11:27:16 debug: s_crmd_fsa: Exiting the FSA: queue=0, fsa_actions=0x2, stalled=true
crmd[14651]: 2007/10/19_11:27:16 debug: config_query_callback: Call 4 : Parsing CIB options
crmd[14651]: 2007/10/19_11:27:16 debug: do_fsa_action: actions:trace: 	// A_STARTED
crmd[14651]: 2007/10/19_11:27:16 info: do_started: Delaying start, CCM (0000000000100000) not connected
crmd[14651]: 2007/10/19_11:27:16 debug: register_fsa_input_adv: do_started prepended FSA input 4 (I_WAIT_FOR_EVENT) (cause=C_FSA_INTERNAL) without data
crmd[14651]: 2007/10/19_11:27:16 debug: register_fsa_input_adv: Stalling the FSA pending further input: cause=C_FSA_INTERNAL
crmd[14651]: 2007/10/19_11:27:16 debug: s_crmd_fsa: Exiting the FSA: queue=0, fsa_actions=0x2, stalled=true
ccm[14646]: 2007/10/19_11:27:17 debug: recv msg CCM_TYPE_MEM_LIST from prec370e, status:[null ptr]
ccm[14646]: 2007/10/19_11:27:17 debug: node state CCM_STATE_NEW_NODE_WAIT_FOR_MEM_LIST -> CCM_STATE_JOINED
ccm[14646]: 2007/10/19_11:27:17 debug: dump current membership 0x2a959cf028
ccm[14646]: 2007/10/19_11:27:17 debug: 	leader=prec370e
ccm[14646]: 2007/10/19_11:27:17 debug: 	transition=2
cib[14647]: 2007/10/19_11:27:17 info: mem_handle_event: Got an event OC_EV_MS_NEW_MEMBERSHIP from ccm
ccm[14646]: 2007/10/19_11:27:17 debug: 	status=CCM_STATE_JOINED
cib[14647]: 2007/10/19_11:27:17 info: mem_handle_event: instance=2, nodes=2, new=2, lost=0, n_idx=0, new_idx=0, old_idx=4
ccm[14646]: 2007/10/19_11:27:17 debug: 	has_quorum=1
cib[14647]: 2007/10/19_11:27:17 debug: cib_ccm_msg_callback: Process CCM event=NEW MEMBERSHIP (id=2)
ccm[14646]: 2007/10/19_11:27:17 debug: 	nodename=prec370d bornon=2
cib[14647]: 2007/10/19_11:27:17 debug: set_transition: CCM transition: old=(null), new=2
ccm[14646]: 2007/10/19_11:27:17 debug: 	nodename=prec370e bornon=1
cib[14647]: 2007/10/19_11:27:17 debug: cib_ccm_msg_callback: Quorum (re)attained after event=NEW MEMBERSHIP (id=2)
ccm[14646]: 2007/10/19_11:27:17 debug: quorum is 1
cib[14647]: 2007/10/19_11:27:17 info: cib_ccm_msg_callback: PEER: prec370e
ccm[14646]: 2007/10/19_11:27:17 debug: delivering new membership to 2 clients: 
cib[14647]: 2007/10/19_11:27:17 info: cib_ccm_msg_callback: PEER: prec370d
ccm[14646]: 2007/10/19_11:27:17 debug: client: pid =14651
ccm[14646]: 2007/10/19_11:27:17 debug: client: pid =14647
crmd[14651]: 2007/10/19_11:27:17 info: mem_handle_event: Got an event OC_EV_MS_NEW_MEMBERSHIP from ccm
crmd[14651]: 2007/10/19_11:27:17 info: mem_handle_event: instance=2, nodes=2, new=2, lost=0, n_idx=0, new_idx=0, old_idx=4
crmd[14651]: 2007/10/19_11:27:17 info: crmd_ccm_msg_callback: Quorum (re)attained after event=NEW MEMBERSHIP (id=2)
crmd[14651]: 2007/10/19_11:27:17 debug: register_fsa_input_adv: crmd_ccm_msg_callback prepended FSA input 5 (I_CCM_EVENT) (cause=C_CCM_CALLBACK) with data
crmd[14651]: 2007/10/19_11:27:17 debug: s_crmd_fsa: Processing I_CCM_EVENT: [ state=S_STARTING cause=C_CCM_CALLBACK origin=crmd_ccm_msg_callback ]
crmd[14651]: 2007/10/19_11:27:17 debug: do_fsa_action: actions:trace: 	// A_CCM_UPDATE_CACHE
crmd[14651]: 2007/10/19_11:27:17 debug: do_ccm_update_cache: Updating cache after CCM event 2 (NEW MEMBERSHIP).
crmd[14651]: 2007/10/19_11:27:17 debug: do_fsa_action: actions:trace: 	// A_CCM_EVENT
crmd[14651]: 2007/10/19_11:27:17 info: ccm_event_detail: NEW MEMBERSHIP: trans=2, nodes=2, new=2, lost=0 n_idx=0, new_idx=0, old_idx=4
crmd[14651]: 2007/10/19_11:27:17 info: ccm_event_detail: 	CURRENT: prec370e [nodeid=1, born=1]
crmd[14651]: 2007/10/19_11:27:17 info: ccm_event_detail: 	CURRENT: prec370d [nodeid=0, born=2]
crmd[14651]: 2007/10/19_11:27:17 info: ccm_event_detail: 	NEW:     prec370e [nodeid=1, born=1]
crmd[14651]: 2007/10/19_11:27:17 info: ccm_event_detail: 	NEW:     prec370d [nodeid=0, born=2]
crmd[14651]: 2007/10/19_11:27:17 debug: do_fsa_action: actions:trace: 	// A_STARTED
crmd[14651]: 2007/10/19_11:27:17 info: do_started: The local CRM is operational
crmd[14651]: 2007/10/19_11:27:17 debug: register_fsa_input_adv: do_started appended FSA input 6 (I_PENDING) (cause=C_CCM_CALLBACK) without data
crmd[14651]: 2007/10/19_11:27:17 debug: s_crmd_fsa: Processing I_PENDING: [ state=S_STARTING cause=C_CCM_CALLBACK origin=do_started ]
crmd[14651]: 2007/10/19_11:27:17 debug: do_fsa_action: actions:trace: 	// A_LOG   
crmd[14651]: 2007/10/19_11:27:17 info: do_state_transition: State transition S_STARTING -> S_PENDING [ input=I_PENDING cause=C_CCM_CALLBACK origin=do_started ]
crmd[14651]: 2007/10/19_11:27:17 info: update_dc: Set DC to <null> (<null>)
crmd[14651]: 2007/10/19_11:27:17 debug: do_fsa_action: actions:trace: 	// A_INTEGRATE_TIMER_STOP
crmd[14651]: 2007/10/19_11:27:17 debug: do_fsa_action: actions:trace: 	// A_FINALIZE_TIMER_STOP
crmd[14651]: 2007/10/19_11:27:17 debug: do_fsa_action: actions:trace: 	// A_CL_JOIN_QUERY
mgmtd[14652]: 2007/10/19_11:27:17 debug: update cib finished
crmd[14651]: 2007/10/19_11:27:18 debug: do_cl_join_query: Querying for a DC
crmd[14651]: 2007/10/19_11:27:18 debug: do_fsa_action: actions:trace: 	// A_DC_TIMER_START
crmd[14651]: 2007/10/19_11:27:18 debug: crm_timer_start: Started Election Trigger (I_DC_TIMEOUT:10000ms), src=10
crmd[14651]: 2007/10/19_11:27:18 debug: fsa_dump_inputs: Added input: 0000000000100000 (R_CCM_DATA)
crmd[14651]: 2007/10/19_11:27:18 debug: ccm_node_update_complete: Node update 9 complete
attrd[14650]: 2007/10/19_11:27:18 info: main: Starting mainloop...
crmd[14651]: 2007/10/19_11:27:28 info: crm_timer_popped: Election Trigger (I_DC_TIMEOUT) just popped!
crmd[14651]: 2007/10/19_11:27:28 debug: crm_timer_stop: Stopping Election Trigger (I_DC_TIMEOUT:10000ms), src=10
crmd[14651]: 2007/10/19_11:27:28 debug: register_fsa_input_adv: crm_timer_popped appended FSA input 7 (I_DC_TIMEOUT) (cause=C_TIMER_POPPED) without data
crmd[14651]: 2007/10/19_11:27:28 debug: s_crmd_fsa: Processing I_DC_TIMEOUT: [ state=S_PENDING cause=C_TIMER_POPPED origin=crm_timer_popped ]
crmd[14651]: 2007/10/19_11:27:28 debug: do_fsa_action: actions:trace: 	// A_WARN  
crmd[14651]: 2007/10/19_11:27:28 WARN: do_log: [[FSA]] Input I_DC_TIMEOUT from crm_timer_popped() received in state (S_PENDING)
crmd[14651]: 2007/10/19_11:27:28 info: do_state_transition: State transition S_PENDING -> S_ELECTION [ input=I_DC_TIMEOUT cause=C_TIMER_POPPED origin=crm_timer_popped ]
crmd[14651]: 2007/10/19_11:27:28 info: update_dc: Set DC to <null> (<null>)
crmd[14651]: 2007/10/19_11:27:28 debug: do_fsa_action: actions:trace: 	// A_DC_TIMER_STOP
crmd[14651]: 2007/10/19_11:27:28 debug: do_fsa_action: actions:trace: 	// A_INTEGRATE_TIMER_STOP
crmd[14651]: 2007/10/19_11:27:28 debug: do_fsa_action: actions:trace: 	// A_FINALIZE_TIMER_STOP
crmd[14651]: 2007/10/19_11:27:28 debug: do_fsa_action: actions:trace: 	// A_ELECTION_VOTE
crmd[14651]: 2007/10/19_11:27:28 debug: do_election_vote: Destroying voted hash
crmd[14651]: 2007/10/19_11:27:28 debug: crm_timer_start: Started Election Timeout (I_ELECTION_DC:120000ms), src=11
crmd[14651]: 2007/10/19_11:27:28 debug: register_fsa_input_adv: handle_request appended FSA input 8 (I_NULL) (cause=C_HA_MESSAGE) with data
crmd[14651]: 2007/10/19_11:27:28 debug: do_fsa_action: actions:trace: 	// A_ELECTION_COUNT
crmd[14651]: 2007/10/19_11:27:28 debug: do_election_count_vote: Created voted hash
crmd[14651]: 2007/10/19_11:27:28 debug: do_election_count_vote: Election 2, owner: 6a42b4b7-7a31-4ae7-b241-ed4ad4267ec6
crmd[14651]: 2007/10/19_11:27:28 info: do_election_count_vote: Updated voted hash for prec370d to vote
crmd[14651]: 2007/10/19_11:27:28 info: do_election_count_vote: Election ignore: our vote (prec370d)
crmd[14651]: 2007/10/19_11:27:28 debug: do_fsa_action: actions:trace: 	// A_ELECTION_CHECK
crmd[14651]: 2007/10/19_11:27:28 info: do_election_check: Still waiting on 1 non-votes (2 total)
crmd[14651]: 2007/10/19_11:27:28 debug: register_fsa_input_adv: handle_request appended FSA input 9 (I_NULL) (cause=C_HA_MESSAGE) with data
crmd[14651]: 2007/10/19_11:27:28 debug: do_fsa_action: actions:trace: 	// A_ELECTION_COUNT
crmd[14651]: 2007/10/19_11:27:28 debug: do_election_count_vote: Election 2, owner: 9d9ca527-cea9-470c-9e03-e49fe5630bba
crmd[14651]: 2007/10/19_11:27:28 info: do_election_count_vote: Election check: vote from prec370e
crmd[14651]: 2007/10/19_11:27:28 debug: do_election_count_vote: Election fail: born_on
crmd[14651]: 2007/10/19_11:27:28 info: update_dc: Set DC to <null> (<null>)
crmd[14651]: 2007/10/19_11:27:28 debug: crm_timer_stop: Stopping Election Timeout (I_ELECTION_DC:120000ms), src=11
crmd[14651]: 2007/10/19_11:27:28 debug: do_election_count_vote: Election lost to prec370e (9d9ca527-cea9-470c-9e03-e49fe5630bba/2)
crmd[14651]: 2007/10/19_11:27:28 debug: register_fsa_input_adv: do_election_count_vote appended FSA input 10 (I_PENDING) (cause=C_FSA_INTERNAL) without data
crmd[14651]: 2007/10/19_11:27:28 debug: do_fsa_action: actions:trace: 	// A_ELECTION_CHECK
crmd[14651]: 2007/10/19_11:27:28 info: do_election_check: Still waiting on 1 non-votes (2 total)
crmd[14651]: 2007/10/19_11:27:28 debug: s_crmd_fsa: Processing I_PENDING: [ state=S_ELECTION cause=C_FSA_INTERNAL origin=do_election_count_vote ]
crmd[14651]: 2007/10/19_11:27:28 info: do_state_transition: State transition S_ELECTION -> S_PENDING [ input=I_PENDING cause=C_FSA_INTERNAL origin=do_election_count_vote ]
crmd[14651]: 2007/10/19_11:27:28 info: update_dc: Set DC to <null> (<null>)
crmd[14651]: 2007/10/19_11:27:28 debug: do_fsa_action: actions:trace: 	// A_DC_TIMER_STOP
crmd[14651]: 2007/10/19_11:27:28 debug: do_fsa_action: actions:trace: 	// A_INTEGRATE_TIMER_STOP
crmd[14651]: 2007/10/19_11:27:28 debug: do_fsa_action: actions:trace: 	// A_FINALIZE_TIMER_STOP
crmd[14651]: 2007/10/19_11:27:28 debug: do_fsa_action: actions:trace: 	// A_DC_RELEASE
crmd[14651]: 2007/10/19_11:27:28 debug: do_dc_release: Releasing the role of DC
crmd[14651]: 2007/10/19_11:27:28 debug: do_fsa_action: actions:trace: 	// A_DC_RELEASED
crmd[14651]: 2007/10/19_11:27:28 info: do_dc_release: DC role released
crmd[14651]: 2007/10/19_11:27:28 debug: register_fsa_input_adv: do_dc_release appended FSA input 11 (I_RELEASE_SUCCESS) (cause=C_FSA_INTERNAL) without data
crmd[14651]: 2007/10/19_11:27:28 debug: do_fsa_action: actions:trace: 	// A_PE_STOP
crmd[14651]: 2007/10/19_11:27:28 debug: do_fsa_action: actions:trace: 	// A_TE_STOP
crmd[14651]: 2007/10/19_11:27:28 debug: s_crmd_fsa: Processing I_RELEASE_SUCCESS: [ state=S_PENDING cause=C_FSA_INTERNAL origin=do_dc_release ]
crmd[14651]: 2007/10/19_11:27:28 debug: do_fsa_action: actions:trace: 	// A_LOG   
crmd[14651]: 2007/10/19_11:27:29 debug: register_fsa_input_adv: handle_request appended FSA input 12 (I_NULL) (cause=C_HA_MESSAGE) with data
crmd[14651]: 2007/10/19_11:27:29 debug: do_fsa_action: actions:trace: 	// A_ELECTION_COUNT
crmd[14651]: 2007/10/19_11:27:29 debug: do_election_count_vote: Election 3, owner: 9d9ca527-cea9-470c-9e03-e49fe5630bba
crmd[14651]: 2007/10/19_11:27:29 info: do_election_count_vote: Election check: vote from prec370e
crmd[14651]: 2007/10/19_11:27:29 debug: do_election_count_vote: Election fail: born_on
crmd[14651]: 2007/10/19_11:27:29 info: update_dc: Set DC to <null> (<null>)
crmd[14651]: 2007/10/19_11:27:29 debug: do_election_count_vote: Election lost to prec370e (9d9ca527-cea9-470c-9e03-e49fe5630bba/3)
crmd[14651]: 2007/10/19_11:27:29 debug: register_fsa_input_adv: do_election_count_vote appended FSA input 13 (I_PENDING) (cause=C_FSA_INTERNAL) without data
crmd[14651]: 2007/10/19_11:27:29 debug: do_fsa_action: actions:trace: 	// A_ELECTION_CHECK
crmd[14651]: 2007/10/19_11:27:29 debug: do_election_check: Ignore election check: we not in an election
crmd[14651]: 2007/10/19_11:27:29 debug: s_crmd_fsa: Processing I_PENDING: [ state=S_PENDING cause=C_FSA_INTERNAL origin=do_election_count_vote ]
crmd[14651]: 2007/10/19_11:27:29 debug: do_fsa_action: actions:trace: 	// A_LOG   
crmd[14651]: 2007/10/19_11:27:30 debug: handle_request: Raising I_JOIN_OFFER: join-1
crmd[14651]: 2007/10/19_11:27:30 debug: register_fsa_input_adv: route_message appended FSA input 14 (I_JOIN_OFFER) (cause=C_HA_MESSAGE) with data
crmd[14651]: 2007/10/19_11:27:30 debug: s_crmd_fsa: Processing I_JOIN_OFFER: [ state=S_PENDING cause=C_HA_MESSAGE origin=route_message ]
crmd[14651]: 2007/10/19_11:27:30 debug: do_fsa_action: actions:trace: 	// A_DC_TIMER_STOP
crmd[14651]: 2007/10/19_11:27:30 debug: do_fsa_action: actions:trace: 	// A_CL_JOIN_REQUEST
crmd[14651]: 2007/10/19_11:27:30 info: update_dc: Set DC to prec370e (2.0)
crmd[14651]: 2007/10/19_11:27:30 debug: do_cl_join_offer_respond: do_cl_join_offer_respond added action A_DC_TIMER_STOP to the FSA
crmd[14651]: 2007/10/19_11:27:30 debug: do_fsa_action: actions:trace: 	// A_DC_TIMER_STOP
mgmtd[14652]: 2007/10/19_11:27:30 debug: update cib finished
mgmtd[14652]: 2007/10/19_11:27:30 debug: update cib finished
cib[14657]: 2007/10/19_11:27:30 debug: write_cib_contents: Archiving current version
crmd[14651]: 2007/10/19_11:27:30 debug: join_query_callback: Respond to join offer join-1
crmd[14651]: 2007/10/19_11:27:30 debug: join_query_callback: Acknowledging prec370e as our DC
cib[14657]: 2007/10/19_11:27:30 info: retrieveCib: Reading cluster configuration from: /var/lib/heartbeat/crm/cib.xml (digest: /var/lib/heartbeat/crm/cib.xml.sig)
cib[14657]: 2007/10/19_11:27:30 debug: archive_file: /var/lib/heartbeat/crm/cib.xml archived as /var/lib/heartbeat/crm/cib.xml.last
cib[14657]: 2007/10/19_11:27:30 debug: archive_file: /var/lib/heartbeat/crm/cib.xml.sig archived as /var/lib/heartbeat/crm/cib.xml.sig.last
cib[14657]: 2007/10/19_11:27:30 info: retrieveCib: Reading cluster configuration from: /var/lib/heartbeat/crm/cib.xml (digest: /var/lib/heartbeat/crm/cib.xml.sig)
cib[14657]: 2007/10/19_11:27:30 info: retrieveCib: Reading cluster configuration from: /var/lib/heartbeat/crm/cib.xml.last (digest: /var/lib/heartbeat/crm/cib.xml.sig.last)
cib[14657]: 2007/10/19_11:27:30 debug: write_cib_contents: Verified CIB archive
cib[14657]: 2007/10/19_11:27:30 debug: write_cib_contents: Wrote CIB to disk
cib[14657]: 2007/10/19_11:27:30 info: write_cib_contents: Wrote version 0.2.1 of the CIB to disk (digest: bef7dd0009b73e4181a9268139ada6f4)
cib[14657]: 2007/10/19_11:27:30 debug: write_cib_contents: Wrote digest to disk
cib[14657]: 2007/10/19_11:27:30 info: retrieveCib: Reading cluster configuration from: /var/lib/heartbeat/crm/cib.xml (digest: /var/lib/heartbeat/crm/cib.xml.sig)
cib[14657]: 2007/10/19_11:27:30 info: retrieveCib: Reading cluster configuration from: /var/lib/heartbeat/crm/cib.xml.last (digest: /var/lib/heartbeat/crm/cib.xml.sig.last)
cib[14657]: 2007/10/19_11:27:30 debug: write_cib_contents: Wrote and verified CIB
crmd[14651]: 2007/10/19_11:27:31 debug: handle_request: Raising I_JOIN_RESULT: join-1
crmd[14651]: 2007/10/19_11:27:31 debug: register_fsa_input_adv: route_message appended FSA input 15 (I_JOIN_RESULT) (cause=C_HA_MESSAGE) with data
crmd[14651]: 2007/10/19_11:27:31 debug: s_crmd_fsa: Processing I_JOIN_RESULT: [ state=S_PENDING cause=C_HA_MESSAGE origin=route_message ]
crmd[14651]: 2007/10/19_11:27:31 debug: do_fsa_action: actions:trace: 	// A_CL_JOIN_RESULT
crmd[14651]: 2007/10/19_11:27:31 info: update_dc: Set DC to prec370e (2.0)
crmd[14651]: 2007/10/19_11:27:31 debug: do_cl_join_finalize_respond: Confirming join join-1: join_ack_nack
cib[14647]: 2007/10/19_11:27:31 info: cib_replace_notify: Local-only Replace: 0.2.1 from <null>
crmd[14651]: 2007/10/19_11:27:31 debug: do_cl_join_finalize_respond: join-1: Join complete.  Sending local LRM status.
crmd[14651]: 2007/10/19_11:27:31 debug: register_fsa_input_adv: do_cl_join_finalize_respond prepended FSA input 16 (I_NOT_DC) (cause=C_HA_MESSAGE) without data
crmd[14651]: 2007/10/19_11:27:31 debug: s_crmd_fsa: Processing I_NOT_DC: [ state=S_PENDING cause=C_HA_MESSAGE origin=do_cl_join_finalize_respond ]
crmd[14651]: 2007/10/19_11:27:31 debug: do_fsa_action: actions:trace: 	// A_LOG   
crmd[14651]: 2007/10/19_11:27:31 info: do_state_transition: State transition S_PENDING -> S_NOT_DC [ input=I_NOT_DC cause=C_HA_MESSAGE origin=do_cl_join_finalize_respond ]
crmd[14651]: 2007/10/19_11:27:31 debug: do_fsa_action: actions:trace: 	// A_DC_TIMER_STOP
crmd[14651]: 2007/10/19_11:27:31 debug: do_fsa_action: actions:trace: 	// A_INTEGRATE_TIMER_STOP
crmd[14651]: 2007/10/19_11:27:31 debug: do_fsa_action: actions:trace: 	// A_FINALIZE_TIMER_STOP
crmd[14651]: 2007/10/19_11:27:31 debug: do_cib_replaced: Updating the CIB after a replace
crmd[14651]: 2007/10/19_11:27:31 info: populate_cib_nodes: Requesting the list of configured nodes
mgmtd[14652]: 2007/10/19_11:27:31 debug: update cib finished
mgmtd[14652]: 2007/10/19_11:27:31 debug: update cib finished
mgmtd[14652]: 2007/10/19_11:27:31 debug: update cib finished
mgmtd[14652]: 2007/10/19_11:27:31 debug: update cib finished
crmd[14651]: 2007/10/19_11:27:32 notice: populate_cib_nodes: Node: prec370e (uuid: 9d9ca527-cea9-470c-9e03-e49fe5630bba)
mgmtd[14652]: 2007/10/19_11:27:32 debug: update cib finished
mgmtd[14652]: 2007/10/19_11:27:32 debug: update cib finished
mgmtd[14652]: 2007/10/19_11:27:32 debug: update cib finished
mgmtd[14652]: 2007/10/19_11:27:32 debug: update cib finished
mgmtd[14652]: 2007/10/19_11:27:32 debug: update cib finished
crmd[14651]: 2007/10/19_11:27:33 notice: populate_cib_nodes: Node: prec370d (uuid: 6a42b4b7-7a31-4ae7-b241-ed4ad4267ec6)
crmd[14651]: 2007/10/19_11:27:33 debug: get_lrm_resource: Adding rsc prmDummyA before operation
lrmd[14648]: 2007/10/19_11:27:33 debug: on_msg_add_rsc:client [14651] adds resource prmDummyA
crmd[14651]: 2007/10/19_11:27:33 info: do_lrm_rsc_op: Performing op=prmDummyA_monitor_0 key=6:0:32efb8b1-869d-45bf-a401-30b577f14ead)
lrmd[14648]: 2007/10/19_11:27:33 debug: on_msg_perform_op:2278: copying parameters for rsc prmDummyA
lrmd[14648]: 2007/10/19_11:27:33 debug: on_msg_perform_op: add an operation operation monitor[2] on ocf::Dummy::prmDummyA for client 14651, its parameters: CRM_meta_op_target_rc=[7] CRM_meta_timeout=[120000] crm_feature_set=[2.0]  to the operation list.
crmd[14651]: 2007/10/19_11:27:33 debug: do_lrm_rsc_op: Recording pending op: 2 - prmDummyA_monitor_0 prmDummyA:2
lrmd[14648]: 2007/10/19_11:27:33 info: rsc:prmDummyA: monitor
crmd[14651]: 2007/10/19_11:27:33 debug: get_lrm_resource: Adding rsc prmDummyB before operation
lrmd[14648]: 2007/10/19_11:27:33 debug: on_msg_add_rsc:client [14651] adds resource prmDummyB
crmd[14651]: 2007/10/19_11:27:33 info: do_lrm_rsc_op: Performing op=prmDummyB_monitor_0 key=7:0:32efb8b1-869d-45bf-a401-30b577f14ead)
lrmd[14648]: 2007/10/19_11:27:33 debug: on_msg_perform_op:2278: copying parameters for rsc prmDummyB
lrmd[14648]: 2007/10/19_11:27:33 debug: on_msg_perform_op: add an operation operation monitor[3] on ocf::Dummy::prmDummyB for client 14651, its parameters: CRM_meta_op_target_rc=[7] CRM_meta_timeout=[120000] crm_feature_set=[2.0]  to the operation list.
lrmd[14648]: 2007/10/19_11:27:33 info: rsc:prmDummyB: monitor
crmd[14651]: 2007/10/19_11:27:33 debug: do_lrm_rsc_op: Recording pending op: 3 - prmDummyB_monitor_0 prmDummyB:3
crmd[14651]: 2007/10/19_11:27:33 debug: ccm_node_update_complete: Node update 16 complete
Dummy[14659][14671]: 2007/10/19_11:27:33 DEBUG: prmDummyB monitor : 7
Dummy[14658][14670]: 2007/10/19_11:27:33 DEBUG: prmDummyA monitor : 7
lrmd[14648]: 2007/10/19_11:27:33 WARN: Exiting prmDummyB:monitor process 14659 returned rc 7.
crmd[14651]: 2007/10/19_11:27:33 info: process_lrm_event: LRM operation prmDummyB_monitor_0 (call=3, rc=7) complete 
crmd[14651]: 2007/10/19_11:27:33 debug: build_operation_update: Calculated digest f2317cad3d54cec5d7d7aa7d0bf35cf8 for prmDummyB_monitor_0 (0:7;7:0:32efb8b1-869d-45bf-a401-30b577f14ead)

crmd[14651]: 2007/10/19_11:27:33 debug: log_data_element: build_operation_update: digest:source <parameters/>
crmd[14651]: 2007/10/19_11:27:33 debug: do_update_resource: Sent resource state update message: 17
crmd[14651]: 2007/10/19_11:27:33 debug: process_lrm_event: Op prmDummyB_monitor_0 (call=3): Confirmed
lrmd[14648]: 2007/10/19_11:27:33 WARN: Exiting prmDummyA:monitor process 14658 returned rc 7.
crmd[14651]: 2007/10/19_11:27:33 info: process_lrm_event: LRM operation prmDummyA_monitor_0 (call=2, rc=7) complete 
crmd[14651]: 2007/10/19_11:27:33 debug: build_operation_update: Calculated digest f2317cad3d54cec5d7d7aa7d0bf35cf8 for prmDummyA_monitor_0 (0:7;6:0:32efb8b1-869d-45bf-a401-30b577f14ead)

crmd[14651]: 2007/10/19_11:27:33 debug: log_data_element: build_operation_update: digest:source <parameters/>
crmd[14651]: 2007/10/19_11:27:33 debug: do_update_resource: Sent resource state update message: 18
crmd[14651]: 2007/10/19_11:27:33 debug: process_lrm_event: Op prmDummyA_monitor_0 (call=2): Confirmed
mgmtd[14652]: 2007/10/19_11:27:34 debug: update cib finished
mgmtd[14652]: 2007/10/19_11:27:34 debug: update cib finished
crmd[14651]: 2007/10/19_11:27:34 debug: cib_rsc_callback: Resource update 17 complete
crmd[14651]: 2007/10/19_11:27:34 debug: cib_rsc_callback: Resource update 18 complete
mgmtd[14652]: 2007/10/19_11:27:35 debug: update cib finished
mgmtd[14652]: 2007/10/19_11:27:35 debug: update cib finished
mgmtd[14652]: 2007/10/19_11:27:35 debug: update cib finished
mgmtd[14652]: 2007/10/19_11:27:35 debug: update cib finished
mgmtd[14652]: 2007/10/19_11:27:35 debug: update cib finished
mgmtd[14652]: 2007/10/19_11:27:36 debug: update cib finished
crmd[14651]: 2007/10/19_11:28:58 debug: get_lrm_resource: Adding rsc prmDummyC before operation
lrmd[14648]: 2007/10/19_11:28:58 debug: on_msg_add_rsc:client [14651] adds resource prmDummyC
crmd[14651]: 2007/10/19_11:28:58 info: do_lrm_rsc_op: Performing op=prmDummyC_monitor_0 key=7:3:32efb8b1-869d-45bf-a401-30b577f14ead)
lrmd[14648]: 2007/10/19_11:28:58 debug: on_msg_perform_op:2278: copying parameters for rsc prmDummyC
lrmd[14648]: 2007/10/19_11:28:58 debug: on_msg_perform_op: add an operation operation monitor[4] on ocf::Dummy::prmDummyC for client 14651, its parameters: CRM_meta_op_target_rc=[7] CRM_meta_timeout=[120000] crm_feature_set=[2.0]  to the operation list.
lrmd[14648]: 2007/10/19_11:28:58 info: rsc:prmDummyC: monitor
crmd[14651]: 2007/10/19_11:28:58 debug: do_lrm_rsc_op: Recording pending op: 4 - prmDummyC_monitor_0 prmDummyC:4
mgmtd[14652]: 2007/10/19_11:28:58 debug: update cib finished
mgmtd[14652]: 2007/10/19_11:28:58 debug: update cib finished
Dummy[14672][14678]: 2007/10/19_11:28:58 DEBUG: prmDummyC monitor : 7
lrmd[14648]: 2007/10/19_11:28:58 WARN: Exiting prmDummyC:monitor process 14672 returned rc 7.
crmd[14651]: 2007/10/19_11:28:58 info: process_lrm_event: LRM operation prmDummyC_monitor_0 (call=4, rc=7) complete 
crmd[14651]: 2007/10/19_11:28:58 debug: build_operation_update: Calculated digest f2317cad3d54cec5d7d7aa7d0bf35cf8 for prmDummyC_monitor_0 (0:7;7:3:32efb8b1-869d-45bf-a401-30b577f14ead)

crmd[14651]: 2007/10/19_11:28:58 debug: log_data_element: build_operation_update: digest:source <parameters/>
crmd[14651]: 2007/10/19_11:28:58 debug: do_update_resource: Sent resource state update message: 23
crmd[14651]: 2007/10/19_11:28:58 debug: process_lrm_event: Op prmDummyC_monitor_0 (call=4): Confirmed
mgmtd[14652]: 2007/10/19_11:28:58 debug: update cib finished
mgmtd[14652]: 2007/10/19_11:28:58 debug: update cib finished
mgmtd[14652]: 2007/10/19_11:28:58 debug: update cib finished
cib[14679]: 2007/10/19_11:28:58 debug: write_cib_contents: Archiving current version
cib[14679]: 2007/10/19_11:28:58 info: retrieveCib: Reading cluster configuration from: /var/lib/heartbeat/crm/cib.xml (digest: /var/lib/heartbeat/crm/cib.xml.sig)
cib[14679]: 2007/10/19_11:28:58 debug: archive_file: /var/lib/heartbeat/crm/cib.xml archived as /var/lib/heartbeat/crm/cib.xml.last
cib[14679]: 2007/10/19_11:28:58 debug: archive_file: /var/lib/heartbeat/crm/cib.xml.sig archived as /var/lib/heartbeat/crm/cib.xml.sig.last
cib[14679]: 2007/10/19_11:28:58 info: retrieveCib: Reading cluster configuration from: /var/lib/heartbeat/crm/cib.xml (digest: /var/lib/heartbeat/crm/cib.xml.sig)
cib[14679]: 2007/10/19_11:28:58 info: retrieveCib: Reading cluster configuration from: /var/lib/heartbeat/crm/cib.xml.last (digest: /var/lib/heartbeat/crm/cib.xml.sig.last)
cib[14679]: 2007/10/19_11:28:58 debug: write_cib_contents: Verified CIB archive
cib[14679]: 2007/10/19_11:28:58 debug: write_cib_contents: Wrote CIB to disk
cib[14679]: 2007/10/19_11:28:58 info: write_cib_contents: Wrote version 0.3.5 of the CIB to disk (digest: 6b42c6d0c5612d844d17296afe7c4a7b)
cib[14679]: 2007/10/19_11:28:58 debug: write_cib_contents: Wrote digest to disk
cib[14679]: 2007/10/19_11:28:58 info: retrieveCib: Reading cluster configuration from: /var/lib/heartbeat/crm/cib.xml (digest: /var/lib/heartbeat/crm/cib.xml.sig)
cib[14679]: 2007/10/19_11:28:58 info: retrieveCib: Reading cluster configuration from: /var/lib/heartbeat/crm/cib.xml.last (digest: /var/lib/heartbeat/crm/cib.xml.sig.last)
cib[14679]: 2007/10/19_11:28:58 debug: write_cib_contents: Wrote and verified CIB
mgmtd[14652]: 2007/10/19_11:29:00 debug: update cib finished
mgmtd[14652]: 2007/10/19_11:29:00 debug: update cib finished
mgmtd[14652]: 2007/10/19_11:29:00 debug: update cib finished
mgmtd[14652]: 2007/10/19_11:29:00 debug: update cib finished
crmd[14651]: 2007/10/19_11:29:00 debug: cib_rsc_callback: Resource update 23 complete
mgmtd[14652]: 2007/10/19_11:29:01 debug: update cib finished
