[ClusterLabs] Question regarding logging during migration of a resource

[Detlef Gossrau]

Hi all !

I have a question regarding differences in logging during migration of a 
resource from one node to the other and vice-versa.

Following scenario:

Resource tomcat (resTOMCAT) is running on node 1 (apas5x1). I migrate 
the resource to node 2 (apas5x2) using the following command:

  crm_resource -f -Q -M --lifetime=PT1M -r resTOMCAT -N apas5x2

AFAIK for migration a constraint is set on the other node. But I didn't 
see this in the log (/var/log/messages) on the other node. Furthermore 
there are no messages logged regarding move of the resource. But 
nevertheless the migration is working.

During migration of the resource back to node 1 messages are written in 
the log (/var/log/messages) regarding removing and adding a constraint 
and regarding move of the ressource:


Apr 22 12:35:04 apas5x1 cib[22231]:     info: cib:diff: - <cib 
admin_epoch="0" epoch="10" num_updates="34" >
Apr 22 12:35:04 apas5x1 cib[22231]:     info: cib:diff: -   <configuration >
Apr 22 12:35:04 apas5x1 cib[22231]:     info: cib:diff: -     <constraints >
Apr 22 12:35:04 apas5x1 cib[22231]:     info: cib:diff: - <rsc_location 
id="cli-standby-resTOMCAT" >
Apr 22 12:35:04 apas5x1 cib[22231]:     info: cib:diff: -         <rule 
id="cli-standby-rule-resTOMCAT" >
Apr 22 12:35:04 apas5x1 cib[22231]:     info: cib:diff: - <expression 
value="apas5x1" id="cli-standby-expr-resTOMCAT" />
Apr 22 12:35:04 apas5x1 cib[22231]:     info: cib:diff: - 
<date_expression end="2015-04-22 10:24:01Z" 
id="cli-standby-lifetime-end-resTOMCAT" />
Apr 22 12:35:04 apas5x1 cib[22231]:     info: cib:diff: -         </rule>
Apr 22 12:35:04 apas5x1 cib[22231]:     info: cib:diff: - </rsc_location>
Apr 22 12:35:04 apas5x1 cib[22231]:     info: cib:diff: - <rsc_location 
id="cli-prefer-resTOMCAT" >
Apr 22 12:35:04 apas5x1 cib[22231]:     info: cib:diff: -         <rule 
id="cli-prefer-rule-resTOMCAT" >
Apr 22 12:35:04 apas5x1 cib[22231]:     info: cib:diff: - <expression 
value="apas5x2" id="cli-prefer-expr-resTOMCAT" />
Apr 22 12:35:04 apas5x1 crmd[22236]:     info: abort_transition_graph: 
te_update_diff:126 - Triggered transition abort (complete=1, tag=diff, 
id=(null), magic=NA, cib=0.11.1) : Non-status change
Apr 22 12:35:04 apas5x1 cib[22231]:     info: cib:diff: - 
<date_expression end="2015-04-22 10:24:01Z" 
id="cli-prefer-lifetime-end-resTOMCAT" />
Apr 22 12:35:04 apas5x1 crmd[22236]:   notice: do_state_transition: 
State transition S_IDLE -> S_POLICY_ENGINE [ input=I_PE_CALC 
cause=C_FSA_INTERNAL origin=abort_transition_graph ]
Apr 22 12:35:04 apas5x1 cib[22231]:     info: cib:diff: -         </rule>
Apr 22 12:35:04 apas5x1 cib[22231]:     info: cib:diff: - </rsc_location>
Apr 22 12:35:04 apas5x1 cib[22231]:     info: cib:diff: -     </constraints>
Apr 22 12:35:04 apas5x1 cib[22231]:     info: cib:diff: -   </configuration>
Apr 22 12:35:04 apas5x1 cib[22231]:     info: cib:diff: - </cib>
Apr 22 12:35:04 apas5x1 cib[22231]:     info: cib:diff: + <cib 
epoch="11" num_updates="1" admin_epoch="0" validate-with="pacemaker-1.2" 
crm_feature_set="3.0.6" update-origin="apas5x1" 
update-client="crm_resource" cib-last-written="Wed Apr 22 12:23:01 2015" 
have-quorum="1" dc-uuid="apas5x1" >
Apr 22 12:35:04 apas5x1 cib[22231]:     info: cib:diff: +   <configuration >
Apr 22 12:35:04 apas5x1 cib[22231]:     info: cib:diff: +     <constraints >
Apr 22 12:35:04 apas5x1 cib[22231]:     info: cib:diff: + <rsc_location 
id="cli-standby-resTOMCAT" rsc="resTOMCAT" >
Apr 22 12:35:04 apas5x1 cib[22231]:     info: cib:diff: +         <rule 
id="cli-standby-rule-resTOMCAT" score="-INFINITY" boolean-op="and" >
Apr 22 12:35:04 apas5x1 cib[22231]:     info: cib:diff: + <expression 
id="cli-standby-expr-resTOMCAT" attribute="#uname" operation="eq" 
value="apas5x2" type="string" />
Apr 22 12:35:04 apas5x1 cib[22231]:     info: cib:diff: + 
<date_expression id="cli-standby-lifetime-end-resTOMCAT" operation="lt" 
end="2015-04-22 10:36:04Z" />
Apr 22 12:35:04 apas5x1 cib[22231]:     info: cib:diff: +         </rule>
Apr 22 12:35:04 apas5x1 cib[22231]:     info: cib:diff: + </rsc_location>
Apr 22 12:35:04 apas5x1 cib[22231]:     info: cib:diff: + <rsc_location 
id="cli-prefer-resTOMCAT" rsc="resTOMCAT" >
Apr 22 12:35:04 apas5x1 cib[22231]:     info: cib:diff: +         <rule 
id="cli-prefer-rule-resTOMCAT" score="INFINITY" boolean-op="and" >
Apr 22 12:35:04 apas5x1 cib[22231]:     info: cib:diff: + <expression 
id="cli-prefer-expr-resTOMCAT" attribute="#uname" operation="eq" 
value="apas5x1" type="string" />
Apr 22 12:35:04 apas5x1 cib[22231]:     info: cib:diff: + 
<date_expression id="cli-prefer-lifetime-end-resTOMCAT" operation="lt" 
end="2015-04-22 10:36:04Z" />
Apr 22 12:35:04 apas5x1 cib[22231]:     info: cib:diff: +         </rule>
Apr 22 12:35:04 apas5x1 cib[22231]:     info: cib:diff: + </rsc_location>
Apr 22 12:35:04 apas5x1 cib[22231]:     info: cib:diff: +     </constraints>
Apr 22 12:35:04 apas5x1 cib[22231]:     info: cib:diff: +   </configuration>
Apr 22 12:35:04 apas5x1 cib[22231]:     info: cib:diff: + </cib>
Apr 22 12:35:04 apas5x1 cib[22231]:     info: cib_process_request: 
Operation complete: op cib_modify for section constraints 
(origin=apas5x2/crm_resource/3, version=0.11.1): ok (rc=0)
Apr 22 12:35:04 apas5x1 pengine[22235]:   notice: unpack_config: On loss 
of CCM Quorum: Ignore
...
Apr 22 12:35:04 apas5x1 pengine[22235]:   notice: LogActions: Move 
resTOMCAT#011(Started apas5x2 -> apas5x1)


The configuration of logging in /etc/corosync.d/corosync.conf is the 
same on both nodes, the configuration of rsyslogd.conf as well. No 
entries in /etc/sysconfig/pacemaker.

Where is my fault ? What could be the reason for the difference in 
logging ? Or is my understanding of the logging wrong ?

Test environment on both nodes:

OS         : Linux 2.6.32-504.8.1.el6.x86_64
Cluster SW : pacemaker-cluster-libs-1.1.7-6.el6.x86_64
              pacemaker-libs-1.1.7-6.el6.x86_64
              pacemaker-cli-1.1.7-6.el6.x86_64
              pacemaker-1.1.7-6.el6.x86_64
              corosynclib-1.4.1-17.el6.x86_64
              corosync-1.4.1-17.el6.x86_64

Thnx for any help !

Regards
Detlef