4:58 p.m.
I had added a couple new hosts in my cluster (hyp16,hyp17) both followed the same
procedure but at the moment to start vms all of them goes to hyp16, if I tried to migrate
a vm to hyp 17 the migration task fails.
Here is the log trying to do migration:
2020-07-22 17:19:12,352-04 INFO [org.ovirt.engine.core.bll.MigrateVmToServerCommand]
(default task-2378) [35ab9442-856d-4540-a463-d72d1211867f] Running command:
MigrateVmToServerCommand internal: false. Entities affected : ID:
24939bc1-5359-4f9d-b742-b32143c02eb1 Type: VMAction group MIGRATE_VM with role type USER
2020-07-22 17:19:12,421-04 INFO [org.ovirt.engine.core.vdsbroker.MigrateVDSCommand]
(default task-2378) [35ab9442-856d-4540-a463-d72d1211867f] START, MigrateVDSCommand(
MigrateVDSCommandParameters:{hostId='15466e1a-1f44-472c-94fc-84b132ff1c7d',
vmId='24939bc1-5359-4f9d-b742-b32143c02eb1', srcHost='172.16.99.12',
dstVdsId='3663f46b-61db-4b4c-a6c0-03fedc90edf0',
dstHost='172.16.99.19:54321', migrationMethod='ONLINE',
tunnelMigration='false', migrationDowntime='0',
autoConverge='true', migrateCompressed='false',
consoleAddress='null', maxBandwidth='625',
enableGuestEvents='true', maxIncomingMigrations='2',
maxOutgoingMigrations='2', convergenceSchedule='[init=[{name=setDowntime,
params=[100]}], stalling=[{limit=1, action={name=setDowntime, params=[150]}}, {limit=2,
action={name=setDowntime, params=[200]}}, {limit=3, action={name=setDowntime,
params=[300]}}, {limit=4, action={name=setDowntime, params=[400]}}, {limit=6,
action={name=setDowntime, params=[500]}}, {limit=-1, action={name=ab
ort, params=[]}}]]', dstQemu='172.16.99.19'}), log id: 4f624ae2
2020-07-22 17:19:12,423-04 INFO
[org.ovirt.engine.core.vdsbroker.vdsbroker.MigrateBrokerVDSCommand] (default task-2378)
[35ab9442-856d-4540-a463-d72d1211867f] START, MigrateBrokerVDSCommand(HostName =
hyp10.infra,
MigrateVDSCommandParameters:{hostId='15466e1a-1f44-472c-94fc-84b132ff1c7d',
vmId='24939bc1-5359-4f9d-b742-b32143c02eb1', srcHost='172.16.99.12',
dstVdsId='3663f46b-61db-4b4c-a6c0-03fedc90edf0',
dstHost='172.16.99.19:54321', migrationMethod='ONLINE',
tunnelMigration='false', migrationDowntime='0',
autoConverge='true', migrateCompressed='false',
consoleAddress='null', maxBandwidth='625',
enableGuestEvents='true', maxIncomingMigrations='2',
maxOutgoingMigrations='2', convergenceSchedule='[init=[{name=setDowntime,
params=[100]}], stalling=[{limit=1, action={name=setDowntime, params=[150]}}, {limit=2,
action={name=setDowntime, params=[200]}}, {limit=3, action={name=setDowntime,
params=[300]}}, {limit=4, action={name=setDowntime, params=[400]}}, {limit=6,
action={name=setDowntim
e, params=[500]}}, {limit=-1, action={name=abort, params=[]}}]]',
dstQemu='172.16.99.19'}), log id: 7ead484c
2020-07-22 17:19:12,587-04 INFO
[org.ovirt.engine.core.vdsbroker.vdsbroker.MigrateBrokerVDSCommand] (default task-2378)
[35ab9442-856d-4540-a463-d72d1211867f] FINISH, MigrateBrokerVDSCommand, return: , log id:
7ead484c
2020-07-22 17:19:12,589-04 INFO [org.ovirt.engine.core.vdsbroker.MigrateVDSCommand]
(default task-2378) [35ab9442-856d-4540-a463-d72d1211867f] FINISH, MigrateVDSCommand,
return: MigratingFrom, log id: 4f624ae2
2020-07-22 17:19:12,597-04 INFO
[org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (default task-2378)
[35ab9442-856d-4540-a463-d72d1211867f] EVENT_ID: VM_MIGRATION_START(62), Migration started
(VM: Test, Source: hyp10.infra, Destination: hyp17.infra, User: Miguel.Garcia@).
2020-07-22 17:19:14,050-04 INFO [org.ovirt.engine.core.sso.servlets.OAuthRevokeServlet]
(default task-2378) [] User antonio.acosta@ successfully logged out
2020-07-22 17:19:14,069-04 INFO
[org.ovirt.engine.core.bll.aaa.TerminateSessionsForTokenCommand] (default task-2380)
[27d07a90] Running command: TerminateSessionsForTokenCommand internal: true.
2020-07-22 17:19:14,405-04 INFO [org.ovirt.engine.core.vdsbroker.monitoring.VmAnalyzer]
(ForkJoinPool-1-worker-12) [] VM '24939bc1-5359-4f9d-b742-b32143c02eb1' was
reported as Down on VDS '3663f46b-61db-4b4c-a6c0-03fedc90edf0'(hyp17.infra)
2020-07-22 17:19:14,406-04 INFO
[org.ovirt.engine.core.vdsbroker.vdsbroker.DestroyVDSCommand] (ForkJoinPool-1-worker-12)
[] START, DestroyVDSCommand(HostName = hyp17.infra,
DestroyVmVDSCommandParameters:{hostId='3663f46b-61db-4b4c-a6c0-03fedc90edf0',
vmId='24939bc1-5359-4f9d-b742-b32143c02eb1', secondsToWait='0',
gracefully='false', reason='', ignoreNoVm='true'}), log id:
7d54e8da
2020-07-22 17:19:14,823-04 INFO
[org.ovirt.engine.core.vdsbroker.vdsbroker.DestroyVDSCommand] (ForkJoinPool-1-worker-12)
[] Failed to destroy VM '24939bc1-5359-4f9d-b742-b32143c02eb1' because VM does not
exist, ignoring
2020-07-22 17:19:14,823-04 INFO
[org.ovirt.engine.core.vdsbroker.vdsbroker.DestroyVDSCommand] (ForkJoinPool-1-worker-12)
[] FINISH, DestroyVDSCommand, return: , log id: 7d54e8da
2020-07-22 17:19:14,824-04 INFO [org.ovirt.engine.core.vdsbroker.monitoring.VmAnalyzer]
(ForkJoinPool-1-worker-12) [] VM '24939bc1-5359-4f9d-b742-b32143c02eb1'(Test) was
unexpectedly detected as 'Down' on VDS
'3663f46b-61db-4b4c-a6c0-03fedc90edf0'(hyp17.infra) (expected on
'15466e1a-1f44-472c-94fc-84b132ff1c7d')
2020-07-22 17:19:14,824-04 ERROR [org.ovirt.engine.core.vdsbroker.monitoring.VmAnalyzer]
(ForkJoinPool-1-worker-12) [] Migration of VM 'Test' to host 'hyp17.infra'
failed: VM destroyed during the startup.
2020-07-22 17:19:14,824-04 WARN
[org.ovirt.engine.core.vdsbroker.monitoring.VmsMonitoring] (ForkJoinPool-1-worker-3) []
skipping VM '24939bc1-5359-4f9d-b742-b32143c02eb1' from this monitoring cycle -
the VM data has changed since fetching the data
2020-07-22 17:19:17,224-04 INFO [org.ovirt.engine.core.vdsbroker.monitoring.VmAnalyzer]
(EE-ManagedThreadFactory-engineScheduled-Thread-76) [] VM
'24939bc1-5359-4f9d-b742-b32143c02eb1'(Test) moved from 'MigratingFrom'
--> 'Up'
2020-07-22 17:19:17,224-04 INFO [org.ovirt.engine.core.vdsbroker.monitoring.VmAnalyzer]
(EE-ManagedThreadFactory-engineScheduled-Thread-76) [] Adding VM
'24939bc1-5359-4f9d-b742-b32143c02eb1'(Test) to re-run list
2020-07-22 17:19:17,229-04 ERROR
[org.ovirt.engine.core.vdsbroker.monitoring.VmsMonitoring]
(EE-ManagedThreadFactory-engineScheduled-Thread-76) [] Rerun VM
'24939bc1-5359-4f9d-b742-b32143c02eb1'. Called from VDS 'hyp10.infra'
2020-07-22 17:19:17,310-04 INFO
[org.ovirt.engine.core.vdsbroker.vdsbroker.MigrateStatusVDSCommand]
(EE-ManagedThreadFactory-engine-Thread-12994) [] START, MigrateStatusVDSCommand(HostName =
hyp10.infra,
MigrateStatusVDSCommandParameters:{hostId='15466e1a-1f44-472c-94fc-84b132ff1c7d',
vmId='24939bc1-5359-4f9d-b742-b32143c02eb1'}), log id: 74cf526d
2020-07-22 17:19:17,533-04 INFO
[org.ovirt.engine.core.vdsbroker.vdsbroker.MigrateStatusVDSCommand]
(EE-ManagedThreadFactory-engine-Thread-12994) [] FINISH, MigrateStatusVDSCommand, return:
, log id: 74cf526d
2020-07-22 17:19:17,587-04 ERROR
[org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector]
(EE-ManagedThreadFactory-engine-Thread-12994) [] EVENT_ID:
VM_MIGRATION_TO_SERVER_FAILED(120), Migration failed (VM: Test, Source: hyp10.infra,
Destination: hyp17.infra).
2020-07-22 17:19:17,591-04 INFO [org.ovirt.engine.core.bll.MigrateVmToServerCommand]
(EE-ManagedThreadFactory-engine-Thread-12994) [] Lock freed to object
'EngineLock:{exclusiveLocks='[24939bc1-5359-4f9d-b742-b32143c02eb1=VM]',
sharedLocks=''}'