I'm trying to get live migration working in heartbeat, but somehow heartbeat 
will only do stop/start, and not try migrate_to/migrate_from.

Both systems are running centos 5.1:

heartbeat-pils-2.1.2-3.el5.centos
heartbeat-stonith-2.1.2-3.el5.centos
heartbeat-2.1.2-3.el5.centos

The logs and the pe-input have been attached. Does anybody have any clue what 
I'm doing wrong, and how I can get live migration to work?

The config is:

<group id="group_xen_sysman001">
         <primitive class="ocf" id="sysman001" provider="heartbeat" 
type="drbdlvmxen">
           <operations>
             <op id="sysman001_mon" interval="10s" name="monitor" 
timeout="30s"/>
           </operations>
           <instance_attributes id="sysman001_iattr">
             <attributes>
               <nvpair id="sysman001_iattr_0" name="drbd" value="drbd3"/>
               <nvpair id="sysman001_iattr_1" name="vg" 
value="raid5_logging"/>
               <nvpair id="sysman001_iattr_2" name="xmfile" 
value="/etc/xen/sysman001.cfg"/>
             </attributes>
           </instance_attributes>
           <meta_attributes id="sysman001_mattr">
             <attributes>
               <nvpair id="sysman001_mattr_0" name="allow_migrate" 
value="true"/>
             </attributes>
           </meta_attributes>
         </primitive>
       </group>


Anyother question I have is about the order of the migrate_to/migrate_from 
commands. Are they both issued at the same time, or is the migrate_to command 
issued first?

Thanks,

Niels de Carpentier
Jan  3 11:29:46 store002 crmd: [10155]: info: do_state_transition: State 
transition S_IDLE -> S_POLICY_ENGINE [ input=I_PE_CALC cause=C_IPC_MESSAGE 
origin=route_message ]
Jan  3 11:29:46 store002 crmd: [10155]: info: do_state_transition: All 2 
cluster nodes are eligible to run resources.
Jan  3 11:29:46 store002 pengine: [32306]: notice: cluster_option: Using 
default value '-1' for cluster option 'pe-error-series-max'
Jan  3 11:29:46 store002 pengine: [32306]: notice: cluster_option: Using 
default value '-1' for cluster option 'pe-warn-series-max'
Jan  3 11:29:46 store002 pengine: [32306]: notice: cluster_option: Using 
default value '-1' for cluster option 'pe-input-series-max'
Jan  3 11:29:46 store002 pengine: [32306]: notice: cluster_option: Using 
default value 'true' for cluster option 'startup-fencing'
Jan  3 11:29:46 store002 pengine: [32306]: info: unpack_nodes: Node store002 is 
in standby-mode
Jan  3 11:29:46 store002 pengine: [32306]: info: determine_online_status: Node 
store001 is online
Jan  3 11:29:46 store002 pengine: [32306]: info: determine_online_status: Node 
store002 is standby
Jan  3 11:29:46 store002 pengine: [32306]: info: group_print: Resource Group: 
group_xen_database001
Jan  3 11:29:46 store002 pengine: [32306]: info: native_print:     database001  
(heartbeat::ocf:drbdlvmxen):    Started store001
Jan  3 11:29:46 store002 pengine: [32306]: info: group_print: Resource Group: 
group_xen_sysman001
Jan  3 11:29:46 store002 pengine: [32306]: info: native_print:     sysman001    
(heartbeat::ocf:drbdlvmxen):    Started store002
Jan  3 11:29:46 store002 pengine: [32306]: info: group_print: Resource Group: 
group_iscsi
Jan  3 11:29:46 store002 cib: [31773]: info: write_cib_contents: Wrote version 
0.28.1 of the CIB to disk (digest: 2dce33bfa3280f6f06e574def46456ad)
Jan  3 11:29:46 store002 pengine: [32306]: info: native_print:     
IPaddr_10_40_99_1    (heartbeat::ocf:IPaddr):        Started store002
Jan  3 11:29:46 store002 pengine: [32306]: info: native_print:     iscsi        
(heartbeat::ocf:iscsi.tgt):     Started store002
Jan  3 11:29:46 store002 pengine: [32306]: info: group_print: Resource Group: 
group_xen_backup001
Jan  3 11:29:46 store002 pengine: [32306]: info: native_print:     backup001    
(heartbeat::ocf:drbdlvmxen):    Started store001
Jan  3 11:29:46 store002 pengine: [32306]: notice: NoRoleChange: Leave resource 
database001     (store001)
Jan  3 11:29:46 store002 pengine: [32306]: notice: NoRoleChange: Move  resource 
sysman001       (store002 -> store001)
Jan  3 11:29:46 store002 pengine: [32306]: notice: StopRsc:   store002        
Stop sysman001
Jan  3 11:29:46 store002 pengine: [32306]: notice: StartRsc:  store001        
Start sysman001
Jan  3 11:29:46 store002 pengine: [32306]: notice: RecurringOp: store001        
 iscsi_monitor_10000
Jan  3 11:29:46 store002 pengine: [32306]: notice: NoRoleChange: Leave resource 
backup001       (store001)
Jan  3 11:29:46 store002 pengine: [32306]: info: native_migrate_reload: 
sysman001: start depends on group_xen_sysman001_start_0
Jan  3 11:29:46 store002 crmd: [10155]: info: do_state_transition: State 
transition S_POLICY_ENGINE -> S_TRANSITION_ENGINE [ input=I_PE_SUCCESS 
cause=C_IPC_MESSAGE origin=route_message ]
Jan  3 11:29:46 store002 tengine: [32305]: info: unpack_graph: Unpacked 
transition 31: 17 actions in 17 synapses
Jan  3 11:29:46 store002 tengine: [32305]: info: te_pseudo_action: Pseudo 
action 20 fired and confirmed
Jan  3 11:29:46 store002 tengine: [32305]: info: send_rsc_command: Initiating 
action 15: sysman001_stop_0 on store002
Jan  3 11:29:46 store002 tengine: [32305]: info: te_pseudo_action: Pseudo 
action 30 fired and confirmed
Jan  3 11:29:46 store002 tengine: [32305]: info: send_rsc_command: Initiating 
action 25: iscsi_stop_0 on store002
Jan  3 11:29:46 store002 pengine: [32306]: info: process_pe_message: Transition 
31: PEngine Input stored in: /var/lib/heartbeat/pengine/pe-input-70.bz2
Jan  3 11:29:46 store002 crmd: [10155]: info: do_lrm_rsc_op: Performing 
op=sysman001_stop_0 key=15:31:51b01913-602e-483e-830d-73a6ad726024)
Jan  3 11:29:47 store002 crmd: [10155]: info: do_lrm_rsc_op: Performing 
op=iscsi_stop_0 key=25:31:51b01913-602e-483e-830d-73a6ad726024)
Jan  3 11:29:47 store002 crmd: [10155]: info: process_lrm_event: LRM operation 
sysman001_monitor_10000 (call=61, rc=-2) Cancelled
Jan  3 11:29:47 store002 crmd: [10155]: info: process_lrm_event: LRM operation 
iscsi_monitor_10000 (call=58, rc=-2) Cancelled
Jan  3 11:29:47 store002 lrmd: [10152]: info: RA output: (iscsi:stop:stdout) 
stopping iscsi
Jan  3 11:29:57 store002 lrmd: [10152]: info: RA output: (iscsi:stop:stderr) 
File descriptor 3 left open File descriptor 4 left open File descriptor 5 left 
open File descriptor 6 left open File descriptor 7 left open File descriptor 8 
left open File descriptor 9 left open File descriptor 10 left open File 
descriptor 12 left open
Jan  3 11:29:59 store002 crmd: [10155]: info: process_lrm_event: LRM operation 
iscsi_stop_0 (call=63, rc=0) complete
Jan  3 11:29:59 store002 tengine: [32305]: info: match_graph_event: Action 
iscsi_stop_0 (25) confirmed on store002
Jan  3 11:29:59 store002 tengine: [32305]: info: send_rsc_command: Initiating 
action 22: IPaddr_10_40_99_1_stop_0 on store002
Jan  3 11:29:59 store002 crmd: [10155]: info: do_lrm_rsc_op: Performing 
op=IPaddr_10_40_99_1_stop_0 key=22:31:51b01913-602e-483e-830d-73a6ad726024)
Jan  3 11:29:59 store002 crmd: [10155]: info: process_lrm_event: LRM operation 
IPaddr_10_40_99_1_monitor_5000 (call=56, rc=-2) Cancelled
Jan  3 11:29:59 store002 lrmd: [10152]: info: RA output: 
(IPaddr_10_40_99_1:stop:stdout) In IP Stop
Jan  3 11:29:59 store002 lrmd: [10152]: info: RA output: 
(IPaddr_10_40_99_1:stop:stderr) SIOCDELRT: No such process
Jan  3 11:29:59 store002 IPaddr[32368]: [32404]: INFO: ifconfig xenbr1:0 down
Jan  3 11:29:59 store002 crmd: [10155]: info: process_lrm_event: LRM operation 
IPaddr_10_40_99_1_stop_0 (call=64, rc=0) complete
Jan  3 11:29:59 store002 tengine: [32305]: info: match_graph_event: Action 
IPaddr_10_40_99_1_stop_0 (22) confirmed on store002
Jan  3 11:29:59 store002 tengine: [32305]: info: te_pseudo_action: Pseudo 
action 31 fired and confirmed
Jan  3 11:29:59 store002 tengine: [32305]: info: te_pseudo_action: Pseudo 
action 28 fired and confirmed
Jan  3 11:29:59 store002 tengine: [32305]: info: send_rsc_command: Initiating 
action 23: IPaddr_10_40_99_1_start_0 on store001
Jan  3 11:30:00 store002 tengine: [32305]: info: match_graph_event: Action 
IPaddr_10_40_99_1_start_0 (23) confirmed on store001
Jan  3 11:30:00 store002 tengine: [32305]: info: send_rsc_command: Initiating 
action 24: IPaddr_10_40_99_1_monitor_5000 on store001
Jan  3 11:30:00 store002 tengine: [32305]: info: send_rsc_command: Initiating 
action 26: iscsi_start_0 on store001
Jan  3 11:30:01 store002 tengine: [32305]: info: match_graph_event: Action 
IPaddr_10_40_99_1_monitor_5000 (24) confirmed on store001
Jan  3 11:30:04 store002 tengine: [32305]: info: match_graph_event: Action 
iscsi_start_0 (26) confirmed on store001
Jan  3 11:30:04 store002 tengine: [32305]: info: te_pseudo_action: Pseudo 
action 29 fired and confirmed
Jan  3 11:30:04 store002 tengine: [32305]: info: send_rsc_command: Initiating 
action 27: iscsi_monitor_10000 on store001
Jan  3 11:30:06 store002 tengine: [32305]: info: match_graph_event: Action 
iscsi_monitor_10000 (27) confirmed on store001
Jan  3 11:30:12 store002 lrmd: [10152]: info: RA output: 
(sysman001:stop:stderr) File descriptor 3 left open File descriptor 4 left open 
File descriptor 5 left open File descriptor 6 left open File descriptor 7 left 
open File descriptor 8 left open File descriptor 9 left open
Jan  3 11:30:12 store002 lrmd: [10152]: info: RA output: (sysman001:stop:stderr)
Jan  3 11:30:12 store002 lrmd: [10152]: info: RA output: 
(sysman001:stop:stderr) /dev/drbd2: open failed: Wrong medium type
Jan  3 11:30:12 store002 lrmd: [10152]: info: RA output: 
(sysman001:stop:stderr) File descriptor 3 left open
Jan  3 11:30:12 store002 lrmd: [10152]: info: RA output: 
(sysman001:stop:stderr) File descriptor 4 left open File descriptor 5 left open 
File descriptor 6 left open File descriptor 7 left open File descriptor 8 left 
open File descriptor 9 left open
Jan  3 11:30:12 store002 crmd: [10155]: info: process_lrm_event: LRM operation 
sysman001_stop_0 (call=62, rc=0) complete
Jan  3 11:30:12 store002 tengine: [32305]: info: match_graph_event: Action 
sysman001_stop_0 (15) confirmed on store002
Jan  3 11:30:12 store002 tengine: [32305]: info: te_pseudo_action: Pseudo 
action 21 fired and confirmed
Jan  3 11:30:12 store002 tengine: [32305]: info: te_pseudo_action: Pseudo 
action 18 fired and confirmed
Jan  3 11:30:12 store002 tengine: [32305]: info: send_rsc_command: Initiating 
action 16: sysman001_start_0 on store001
Jan  3 11:30:16 store002 tengine: [32305]: info: match_graph_event: Action 
sysman001_start_0 (16) confirmed on store001
Jan  3 11:30:16 store002 tengine: [32305]: info: te_pseudo_action: Pseudo 
action 19 fired and confirmed
Jan  3 11:30:16 store002 tengine: [32305]: info: send_rsc_command: Initiating 
action 17: sysman001_monitor_10000 on store001
Jan  3 11:30:18 store002 tengine: [32305]: info: match_graph_event: Action 
sysman001_monitor_10000 (17) confirmed on store001
Jan  3 11:30:18 store002 crmd: [10155]: info: do_state_transition: State 
transition S_TRANSITION_ENGINE -> S_IDLE [ input=I_TE_SUCCESS 
cause=C_IPC_MESSAGE origin=route_message ]
Jan  3 11:30:18 store002 tengine: [32305]: info: run_graph: Transition 31: 
(Complete=17, Pending=0, Fired=0, Skipped=0, Incomplete=0)
Jan  3 11:30:18 store002 tengine: [32305]: info: notify_crmd: Transition 31 
status: te_complete - <null>

Attachment: pe-input-70.bz2
Description: BZip2 compressed data

_______________________________________________
Linux-HA mailing list
[email protected]
http://lists.linux-ha.org/mailman/listinfo/linux-ha
See also: http://linux-ha.org/ReportingProblems

Reply via email to