manager: set new request_start state for services freshly added to HA

[pve-ha-manager.git] / src / test / test-crs-static3 / log.expect

info      0     hardware: starting simulation
info     20      cmdlist: execute power node1 on
info     20    node1/crm: status change startup => wait_for_quorum
info     20    node1/lrm: status change startup => wait_for_agent_lock
info     20      cmdlist: execute power node2 on
info     20    node2/crm: status change startup => wait_for_quorum
info     20    node2/lrm: status change startup => wait_for_agent_lock
info     20      cmdlist: execute power node3 on
info     20    node3/crm: status change startup => wait_for_quorum
info     20    node3/lrm: status change startup => wait_for_agent_lock
info     20    node1/crm: got lock 'ha_manager_lock'
info     20    node1/crm: status change wait_for_quorum => master
info     20    node1/crm: using scheduler mode 'static'
info     20    node1/crm: node 'node1': state changed from 'unknown' => 'online'
info     20    node1/crm: node 'node2': state changed from 'unknown' => 'online'
info     20    node1/crm: node 'node3': state changed from 'unknown' => 'online'
info     20    node1/crm: adding new service 'vm:100' on node 'node1'
info     20    node1/crm: adding new service 'vm:101' on node 'node1'
info     20    node1/crm: adding new service 'vm:102' on node 'node1'
info     20    node1/crm: adding new service 'vm:103' on node 'node1'
info     20    node1/crm: adding new service 'vm:104' on node 'node1'
info     20    node1/crm: adding new service 'vm:105' on node 'node1'
info     20    node1/crm: adding new service 'vm:106' on node 'node1'
info     20    node1/crm: adding new service 'vm:107' on node 'node1'
info     20    node1/crm: adding new service 'vm:108' on node 'node1'
info     20    node1/crm: adding new service 'vm:109' on node 'node1'
info     20    node1/crm: service 'vm:101': state changed from 'request_start' to 'started'  (node = node1)
info     20    node1/crm: service 'vm:102': state changed from 'request_start' to 'started'  (node = node1)
info     20    node1/crm: service 'vm:103': state changed from 'request_start' to 'started'  (node = node1)
info     20    node1/crm: service 'vm:104': state changed from 'request_start' to 'started'  (node = node1)
info     20    node1/crm: service 'vm:105': state changed from 'request_start' to 'started'  (node = node1)
info     20    node1/crm: service 'vm:106': state changed from 'request_start' to 'started'  (node = node1)
info     20    node1/crm: service 'vm:107': state changed from 'request_start' to 'started'  (node = node1)
info     20    node1/crm: service 'vm:108': state changed from 'request_start' to 'started'  (node = node1)
info     20    node1/crm: service 'vm:109': state changed from 'request_start' to 'started'  (node = node1)
info     21    node1/lrm: got lock 'ha_agent_node1_lock'
info     21    node1/lrm: status change wait_for_agent_lock => active
info     21    node1/lrm: starting service vm:101
info     21    node1/lrm: service status vm:101 started
info     21    node1/lrm: starting service vm:102
info     21    node1/lrm: service status vm:102 started
info     21    node1/lrm: starting service vm:103
info     21    node1/lrm: service status vm:103 started
info     21    node1/lrm: starting service vm:104
info     21    node1/lrm: service status vm:104 started
info     21    node1/lrm: starting service vm:105
info     21    node1/lrm: service status vm:105 started
info     21    node1/lrm: starting service vm:106
info     21    node1/lrm: service status vm:106 started
info     21    node1/lrm: starting service vm:107
info     21    node1/lrm: service status vm:107 started
info     21    node1/lrm: starting service vm:108
info     21    node1/lrm: service status vm:108 started
info     21    node1/lrm: starting service vm:109
info     21    node1/lrm: service status vm:109 started
info     22    node2/crm: status change wait_for_quorum => slave
info     24    node3/crm: status change wait_for_quorum => slave
info     40    node1/crm: service 'vm:100': state changed from 'request_stop' to 'stopped'
info    120      cmdlist: execute shutdown node1
info    120    node1/lrm: got shutdown request with shutdown policy 'migrate'
info    120    node1/lrm: shutdown LRM, doing maintenance, removing this node from active list
info    120    node1/crm: node 'node1': state changed from 'online' => 'maintenance'
info    120    node1/crm: migrate service 'vm:101' to node 'node2' (running)
info    120    node1/crm: service 'vm:101': state changed from 'started' to 'migrate'  (node = node1, target = node2)
info    120    node1/crm: migrate service 'vm:102' to node 'node2' (running)
info    120    node1/crm: service 'vm:102': state changed from 'started' to 'migrate'  (node = node1, target = node2)
info    120    node1/crm: migrate service 'vm:103' to node 'node3' (running)
info    120    node1/crm: service 'vm:103': state changed from 'started' to 'migrate'  (node = node1, target = node3)
info    120    node1/crm: migrate service 'vm:104' to node 'node2' (running)
info    120    node1/crm: service 'vm:104': state changed from 'started' to 'migrate'  (node = node1, target = node2)
info    120    node1/crm: migrate service 'vm:105' to node 'node2' (running)
info    120    node1/crm: service 'vm:105': state changed from 'started' to 'migrate'  (node = node1, target = node2)
info    120    node1/crm: migrate service 'vm:106' to node 'node3' (running)
info    120    node1/crm: service 'vm:106': state changed from 'started' to 'migrate'  (node = node1, target = node3)
info    120    node1/crm: migrate service 'vm:107' to node 'node2' (running)
info    120    node1/crm: service 'vm:107': state changed from 'started' to 'migrate'  (node = node1, target = node2)
info    120    node1/crm: migrate service 'vm:108' to node 'node2' (running)
info    120    node1/crm: service 'vm:108': state changed from 'started' to 'migrate'  (node = node1, target = node2)
info    120    node1/crm: migrate service 'vm:109' to node 'node3' (running)
info    120    node1/crm: service 'vm:109': state changed from 'started' to 'migrate'  (node = node1, target = node3)
info    121    node1/lrm: status change active => maintenance
info    121    node1/lrm: service vm:101 - start migrate to node 'node2'
info    121    node1/lrm: service vm:101 - end migrate to node 'node2'
info    121    node1/lrm: service vm:102 - start migrate to node 'node2'
info    121    node1/lrm: service vm:102 - end migrate to node 'node2'
info    121    node1/lrm: service vm:103 - start migrate to node 'node3'
info    121    node1/lrm: service vm:103 - end migrate to node 'node3'
info    121    node1/lrm: service vm:104 - start migrate to node 'node2'
info    121    node1/lrm: service vm:104 - end migrate to node 'node2'
info    121    node1/lrm: service vm:105 - start migrate to node 'node2'
info    121    node1/lrm: service vm:105 - end migrate to node 'node2'
info    121    node1/lrm: service vm:106 - start migrate to node 'node3'
info    121    node1/lrm: service vm:106 - end migrate to node 'node3'
info    121    node1/lrm: service vm:107 - start migrate to node 'node2'
info    121    node1/lrm: service vm:107 - end migrate to node 'node2'
info    121    node1/lrm: service vm:108 - start migrate to node 'node2'
info    121    node1/lrm: service vm:108 - end migrate to node 'node2'
info    121    node1/lrm: service vm:109 - start migrate to node 'node3'
info    121    node1/lrm: service vm:109 - end migrate to node 'node3'
info    140    node1/crm: service 'vm:101': state changed from 'migrate' to 'started'  (node = node2)
info    140    node1/crm: service 'vm:102': state changed from 'migrate' to 'started'  (node = node2)
info    140    node1/crm: service 'vm:103': state changed from 'migrate' to 'started'  (node = node3)
info    140    node1/crm: service 'vm:104': state changed from 'migrate' to 'started'  (node = node2)
info    140    node1/crm: service 'vm:105': state changed from 'migrate' to 'started'  (node = node2)
info    140    node1/crm: service 'vm:106': state changed from 'migrate' to 'started'  (node = node3)
info    140    node1/crm: service 'vm:107': state changed from 'migrate' to 'started'  (node = node2)
info    140    node1/crm: service 'vm:108': state changed from 'migrate' to 'started'  (node = node2)
info    140    node1/crm: service 'vm:109': state changed from 'migrate' to 'started'  (node = node3)
info    142    node1/lrm: exit (loop end)
info    142     shutdown: execute crm node1 stop
info    141    node1/crm: server received shutdown request
info    143    node2/lrm: got lock 'ha_agent_node2_lock'
info    143    node2/lrm: status change wait_for_agent_lock => active
info    143    node2/lrm: starting service vm:101
info    143    node2/lrm: service status vm:101 started
info    143    node2/lrm: starting service vm:102
info    143    node2/lrm: service status vm:102 started
info    143    node2/lrm: starting service vm:104
info    143    node2/lrm: service status vm:104 started
info    143    node2/lrm: starting service vm:105
info    143    node2/lrm: service status vm:105 started
info    143    node2/lrm: starting service vm:107
info    143    node2/lrm: service status vm:107 started
info    143    node2/lrm: starting service vm:108
info    143    node2/lrm: service status vm:108 started
info    145    node3/lrm: got lock 'ha_agent_node3_lock'
info    145    node3/lrm: status change wait_for_agent_lock => active
info    145    node3/lrm: starting service vm:103
info    145    node3/lrm: service status vm:103 started
info    145    node3/lrm: starting service vm:106
info    145    node3/lrm: service status vm:106 started
info    145    node3/lrm: starting service vm:109
info    145    node3/lrm: service status vm:109 started
info    160    node1/crm: voluntary release CRM lock
info    161    node1/crm: exit (loop end)
info    161     shutdown: execute power node1 off
info    161    node2/crm: got lock 'ha_manager_lock'
info    161    node2/crm: status change slave => master
info    161    node2/crm: using scheduler mode 'static'
info    720     hardware: exit simulation - done