------=_=-_OpenGroupware_org_NGMime-18019-1518771817.081391-1------
Content-Type: text/plain; charset=utf-8
Content-Transfer-Encoding: quoted-printable
Content-Length: 30830
Hi Maor,
sorry for the double post, I've change the email adress of my account a=
nd supposed that I'd need to re-post it.
And thank you for your time. Here are the logs. I added a vdisk to an e=
xisting VM : it no more migrates, needing to poweroff it after minutes.=
Then simply deleting the second disk makes migrate it in exactly 9s wi=
thout problem !=C2=A0
https://gist.github.com/fgth/4707446331d201eef574ac31b6e89561
https://gist.github.com/fgth/f8de9c22664aee53722af676bff8719d
--
Cordialement,
Frank Soyer=C2=A0Le Mercredi, F=C3=A9vrier 14, 2018 11:04 CET, Maor Lip=
chuk <mlipchuk(a)redhat.com> a =C3=A9crit:
=C2=A0Hi Frank,=C2=A0I already replied on your last email.Can you provi=
de the VDSM logs from the time of the migration failure for both hosts:=
=C2=A0=C2=A0ginger.local.systea.fr=C2=A0and=C2=A0victor.local.systea.fr=
=C2=A0Thanks,Maor=C2=A0On Wed, Feb 14, 2018 at 11:23 AM, fsoyer <fsoyer=
@systea.fr> wrote:
Hi all,
I discovered yesterday a problem when migrating VM with more than one v=
disk.
On our test servers (oVirt4.1, shared storage with Gluster), I created =
2 VMs needed for a test, from a template with a 20G vdisk. On this VMs =
I added a 100G vdisk (for this tests I didn't want to waste time to ext=
end the existing vdisks... But I lost time finally...). The VMs with th=
e 2 vdisks works well.
Now I saw some updates waiting on the host. I tried to put it in mainte=
nance... But it stopped on the two VM. They were marked "migrating", bu=
t no more accessible. Other (small) VMs with only 1 vdisk was migrated =
without problem at the same time.
I saw that a kvm process for the (big) VMs was launched on the source A=
ND destination host, but after tens of minutes, the migration and the V=
Ms was always freezed. I tried to cancel the migration for the VMs : fa=
iled. The only way to stop it was to poweroff the VMs : the kvm process=
died on the 2 hosts and the GUI alerted on a failed migration.
In doubt, I tried to delete the second vdisk on one of this VMs : it mi=
grates then without error ! And no access problem.
I tried to extend the first vdisk of the second VM, the delete the seco=
nd vdisk : it migrates now without problem !=C2=A0=C2=A0=C2=A0
So after another test with a VM with 2 vdisks, I can say that this bloc=
ked the migration process :(
In engine.log, for a VMs with 1 vdisk migrating well, we see :2018-02-1=
2 16:46:29,705+01 INFO =C2=A0[org.ovirt.engine.core.bll.MigrateVmToServ=
erCommand] (default task-28) [2f712024-5982-46a8-82c8-fd8293da5725] Loc=
k Acquired to object 'EngineLock:{exclusiveLocks=3D'[3f57e669-5e4c-4d10=
-85cc-d573004a099d=3DVM]', sharedLocks=3D''}'
2018-02-12 16:46:29,955+01 INFO =C2=A0[org.ovirt.engine.core.bll.Migrat=
eVmToServerCommand] (org.ovirt.thread.pool-6-thread-32) [2f712024-5982-=
46a8-82c8-fd8293da5725] Running command: MigrateVmToServerCommand inter=
nal: false. Entities affected : =C2=A0ID: 3f57e669-5e4c-4d10-85cc-d5730=
04a099d Type: VMAction group MIGRATE=5FVM with role type USER
2018-02-12 16:46:30,261+01 INFO =C2=A0[org.ovirt.engine.core.vdsbroker.=
MigrateVDSCommand] (org.ovirt.thread.pool-6-thread-32) [2f712024-5982-4=
6a8-82c8-fd8293da5725] START, MigrateVDSCommand( MigrateVDSCommandParam=
eters:{runAsync=3D'true', hostId=3D'ce3938b1-b23f-4d22-840a-f17d7cd87bb=
1', vmId=3D'3f57e669-5e4c-4d10-85cc-d573004a099d', srcHost=3D'192.168.0=
.6', dstVdsId=3D'd569c2dd-8f30-4878-8aea-858db285cf69', dstHost=3D'192.=
168.0.5:54321', migrationMethod=3D'ONLINE', tunnelMigration=3D'false',
=
migrationDowntime=3D'0', autoConverge=3D'true',
migrateCompressed=3D'fa=
lse', consoleAddress=3D'null', maxBandwidth=3D'500',
enableGuestEvents=3D=
'true', maxIncomingMigrations=3D'2', maxOutgoingMigrations=3D'2',
conve=
rgenceSchedule=3D'[init=3D[{name=3DsetDowntime, params=3D[100]}], stall=
ing=3D[{limit=3D1, action=3D{name=3DsetDowntime, params=3D[150]}}, {lim=
it=3D2, action=3D{name=3DsetDowntime, params=3D[200]}}, {limit=3D3, act=
ion=3D{name=3DsetDowntime, params=3D[300]}}, {limit=3D4, action=3D{name=
=3DsetDowntime, params=3D[400]}}, {limit=3D6, action=3D{name=3DsetDownt=
ime, params=3D[500]}}, {limit=3D-1, action=3D{name=3Dabort, params=3D[]=
}}]]'}), log id: 14f61ee0
2018-02-12 16:46:30,262+01 INFO =C2=A0[org.ovirt.engine.core.vdsbroker.=
vdsbroker.MigrateBrokerVDSCommand] (org.ovirt.thread.pool-6-thread-32) =
[2f712024-5982-46a8-82c8-fd8293da5725] START, MigrateBrokerVDSCommand(H=
ostName =3D victor.local.systea.fr, MigrateVDSCommandParameters:{runAsy=
nc=3D'true', hostId=3D'ce3938b1-b23f-4d22-840a-f17d7cd87bb1',
vmId=3D'3=
f57e669-5e4c-4d10-85cc-d573004a099d', srcHost=3D'192.168.0.6', dstVdsId=
=3D'd569c2dd-8f30-4878-8aea-858db285cf69', dstHost=3D'192.168.0.5:54321=
', migrationMethod=3D'ONLINE', tunnelMigration=3D'false',
migrationDown=
time=3D'0', autoConverge=3D'true', migrateCompressed=3D'false',
console=
Address=3D'null', maxBandwidth=3D'500',
enableGuestEvents=3D'true', max=
IncomingMigrations=3D'2', maxOutgoingMigrations=3D'2', convergenceSched=
ule=3D'[init=3D[{name=3DsetDowntime, params=3D[100]}], stalling=3D[{lim=
it=3D1, action=3D{name=3DsetDowntime, params=3D[150]}}, {limit=3D2, act=
ion=3D{name=3DsetDowntime, params=3D[200]}}, {limit=3D3, action=3D{name=
=3DsetDowntime, params=3D[300]}}, {limit=3D4, action=3D{name=3DsetDownt=
ime, params=3D[400]}}, {limit=3D6, action=3D{name=3DsetDowntime, params=
=3D[500]}}, {limit=3D-1, action=3D{name=3Dabort, params=3D[]}}]]'}), lo=
g id: 775cd381
2018-02-12 16:46:30,277+01 INFO =C2=A0[org.ovirt.engine.core.vdsbroker.=
vdsbroker.MigrateBrokerVDSCommand] (org.ovirt.thread.pool-6-thread-32) =
[2f712024-5982-46a8-82c8-fd8293da5725] FINISH, MigrateBrokerVDSCommand,=
log id: 775cd381
2018-02-12 16:46:30,285+01 INFO =C2=A0[org.ovirt.engine.core.vdsbroker.=
MigrateVDSCommand] (org.ovirt.thread.pool-6-thread-32) [2f712024-5982-4=
6a8-82c8-fd8293da5725] FINISH, MigrateVDSCommand, return: MigratingFrom=
, log id: 14f61ee0
2018-02-12 16:46:30,301+01 INFO =C2=A0[org.ovirt.engine.core.dal.dbbrok=
er.auditloghandling.AuditLogDirector] (org.ovirt.thread.pool-6-thread-3=
2) [2f712024-5982-46a8-82c8-fd8293da5725] EVENT=5FID: VM=5FMIGRATION=5F=
START(62), Correlation ID: 2f712024-5982-46a8-82c8-fd8293da5725, Job ID=
: 4bd19aa9-cc99-4d02-884e-5a1e857a7738, Call Stack: null, Custom ID: nu=
ll, Custom Event ID: -1, Message: Migration started (VM: Oracle=5FSECON=
DARY, Source: victor.local.systea.fr, Destination: ginger.local.systea.=
fr, User: admin@internal-authz).
2018-02-12 16:46:31,106+01 INFO =C2=A0[org.ovirt.engine.core.vdsbroker.=
vdsbroker.FullListVDSCommand] (DefaultQuartzScheduler9) [54a65b66] STAR=
T, FullListVDSCommand(HostName =3D victor.local.systea.fr, FullListVDSC=
ommandParameters:{runAsync=3D'true', hostId=3D'ce3938b1-b23f-4d22-840a-=
f17d7cd87bb1', vmIds=3D'[3f57e669-5e4c-4d10-85cc-d573004a099d]'}), log =
id: 54b4b435
2018-02-12 16:46:31,147+01 INFO =C2=A0[org.ovirt.engine.core.vdsbroker.=
vdsbroker.FullListVDSCommand] (DefaultQuartzScheduler9) [54a65b66] FINI=
SH, FullListVDSCommand, return: [{acpiEnable=3Dtrue, emulatedMachine=3D=
pc-i440fx-rhel7.3.0, tabletEnable=3Dtrue, pid=3D1493, guestDiskMapping=3D=
{0QEMU=5FQEMU=5FHARDDISK=5Fd890fa68-fba4-4f49-9=3D{name=3D/dev/sda}, QE=
MU=5FDVD-ROM=5FQM00003=3D{name=3D/dev/sr0}}, transparentHugePages=3Dtru=
e, timeOffset=3D0, cpuType=3DNehalem, smp=3D2, pauseCode=3DNOERR, guest=
NumaNodes=3D[Ljava.lang.Object;@1d9042cd, smartcardEnable=3Dfalse, cust=
om=3D{device=5Ffbddd528-7d93-49c6-a286-180e021cb274device=5F879c93ab-4d=
f1-435c-af02-565039fcc254=3DVmDevice:{id=3D'VmDeviceId:{deviceId=3D'879=
c93ab-4df1-435c-af02-565039fcc254', vmId=3D'3f57e669-5e4c-4d10-85cc-d57=
3004a099d'}', device=3D'unix', type=3D'CHANNEL',
bootOrder=3D'0', specP=
arams=3D'[]', address=3D'{bus=3D0, controller=3D0, type=3Dvirtio-serial=
, port=3D1}', managed=3D'false', plugged=3D'true',
readOnly=3D'false', =
deviceAlias=3D'channel0', customProperties=3D'[]',
snapshotId=3D'null',=
logicalName=3D'null', hostDevice=3D'null'}, device=5Ffbddd528-7d93-49c=
6-a286-180e021cb274device=5F879c93ab-4df1-435c-af02-565039fcc254device=5F=
8945f61a-abbe-4156-8485-a4aa6f1908dbdevice=5F017b5e59-01c4-4aac-bf0c-b5=
d9557284d6=3DVmDevice:{id=3D'VmDeviceId:{deviceId=3D'017b5e59-01c4-4aac=
-bf0c-b5d9557284d6', vmId=3D'3f57e669-5e4c-4d10-85cc-d573004a099d'}', d=
evice=3D'tablet', type=3D'UNKNOWN', bootOrder=3D'0',
specParams=3D'[]',=
address=3D'{bus=3D0, type=3Dusb, port=3D1}', managed=3D'false', plugge=
d=3D'true', readOnly=3D'false', deviceAlias=3D'input0',
customPropertie=
s=3D'[]', snapshotId=3D'null', logicalName=3D'null',
hostDevice=3D'null=
'}, device=5Ffbddd528-7d93-49c6-a286-180e021cb274=3DVmDevice:{id=3D'VmD=
eviceId:{deviceId=3D'fbddd528-7d93-49c6-a286-180e021cb274', vmId=3D'3f5=
7e669-5e4c-4d10-85cc-d573004a099d'}', device=3D'ide',
type=3D'CONTROLLE=
R', bootOrder=3D'0', specParams=3D'[]', address=3D'{slot=3D0x01,
bus=3D=
0x00, domain=3D0x0000, type=3Dpci, function=3D0x1}', managed=3D'false',=
plugged=3D'true', readOnly=3D'false', deviceAlias=3D'ide',
customPrope=
rties=3D'[]', snapshotId=3D'null', logicalName=3D'null',
hostDevice=3D'=
null'}, device=5Ffbddd528-7d93-49c6-a286-180e021cb274device=5F879c93ab-=
4df1-435c-af02-565039fcc254device=5F8945f61a-abbe-4156-8485-a4aa6f1908d=
b=3DVmDevice:{id=3D'VmDeviceId:{deviceId=3D'8945f61a-abbe-4156-8485-a4a=
a6f1908db', vmId=3D'3f57e669-5e4c-4d10-85cc-d573004a099d'}',
device=3D'=
unix', type=3D'CHANNEL', bootOrder=3D'0', specParams=3D'[]',
address=3D=
'{bus=3D0, controller=3D0, type=3Dvirtio-serial, port=3D2}', managed=3D=
'false', plugged=3D'true', readOnly=3D'false',
deviceAlias=3D'channel1'=
, customProperties=3D'[]', snapshotId=3D'null',
logicalName=3D'null', h=
ostDevice=3D'null'}}, vmType=3Dkvm, memSize=3D8192, smpCoresPerSocket=3D=
1, vmName=3DOracle=5FSECONDARY, nice=3D0, status=3DMigration Source, ma=
xMemSize=3D32768, bootMenuEnable=3Dfalse, vmId=3D3f57e669-5e4c-4d10-85c=
c-d573004a099d, numOfIoThreads=3D2, smpThreadsPerCore=3D1, memGuarantee=
dSize=3D8192, kvmEnable=3Dtrue, pitReinjection=3Dfalse, displayNetwork=3D=
ovirtmgmt, devices=3D[Ljava.lang.Object;@28ae66d7, display=3Dvnc, maxVC=
pus=3D16, clientIp=3D, statusTime=3D4299484520, maxMemSlots=3D16}], log=
id: 54b4b435
2018-02-12 16:46:31,150+01 INFO =C2=A0[org.ovirt.engine.core.vdsbroker.=
monitoring.VmsStatisticsFetcher] (DefaultQuartzScheduler1) [27fac647] F=
etched 3 VMs from VDS 'd569c2dd-8f30-4878-8aea-858db285cf69'
2018-02-12 16:46:31,151+01 INFO =C2=A0[org.ovirt.engine.core.vdsbroker.=
monitoring.VmDevicesMonitoring] (DefaultQuartzScheduler9) [54a65b66] Re=
ceived a vnc Device without an address when processing VM 3f57e669-5e4c=
-4d10-85cc-d573004a099d devices, skipping device: {device=3Dvnc, specPa=
rams=3D{displayNetwork=3Dovirtmgmt, keyMap=3Dfr, displayIp=3D192.168.0.=
6}, type=3Dgraphics, deviceId=3D813957b1-446a-4e88-9e40-9fe76d2c442d, p=
ort=3D5901}
2018-02-12 16:46:31,151+01 INFO =C2=A0[org.ovirt.engine.core.vdsbroker.=
monitoring.VmDevicesMonitoring] (DefaultQuartzScheduler9) [54a65b66] Re=
ceived a lease Device without an address when processing VM 3f57e669-5e=
4c-4d10-85cc-d573004a099d devices, skipping device: {lease=5Fid=3D3f57e=
669-5e4c-4d10-85cc-d573004a099d, sd=5Fid=3D1e51cecc-eb2e-47d0-b185-920f=
dc7afa16, deviceId=3D{uuid=3Da09949aa-5642-4b6d-94a4-8b0d04257be5}, off=
set=3D6291456, device=3Dlease, path=3D/rhev/data-center/mnt/glusterSD/1=
92.168.0.6:=5FDATA01/1e51cecc-eb2e-47d0-b185-920fdc7afa16/dom=5Fmd/xlea=
ses, type=3Dlease}
2018-02-12 16:46:31,152+01 INFO =C2=A0[org.ovirt.engine.core.vdsbroker.=
monitoring.VmAnalyzer] (DefaultQuartzScheduler1) [27fac647] VM '3f57e66=
9-5e4c-4d10-85cc-d573004a099d'(Oracle=5FSECONDARY) was unexpectedly det=
ected as 'MigratingTo' on VDS 'd569c2dd-8f30-4878-8aea-858db285cf69'(gi=
nger.local.systea.fr) (expected on 'ce3938b1-b23f-4d22-840a-f17d7cd87bb=
1')
2018-02-12 16:46:31,152+01 INFO =C2=A0[org.ovirt.engine.core.vdsbroker.=
monitoring.VmAnalyzer] (DefaultQuartzScheduler1) [27fac647] VM '3f57e66=
9-5e4c-4d10-85cc-d573004a099d' is migrating to VDS 'd569c2dd-8f30-4878-=
8aea-858db285cf69'(ginger.local.systea.fr) ignoring it in the refresh u=
ntil migration is done
....
2018-02-12 16:46:41,631+01 INFO =C2=A0[org.ovirt.engine.core.vdsbroker.=
monitoring.VmAnalyzer] (ForkJoinPool-1-worker-11) [] VM '3f57e669-5e4c-=
4d10-85cc-d573004a099d' was reported as Down on VDS 'ce3938b1-b23f-4d22=
-840a-f17d7cd87bb1'(victor.local.systea.fr)
2018-02-12 16:46:41,632+01 INFO =C2=A0[org.ovirt.engine.core.vdsbroker.=
vdsbroker.DestroyVDSCommand] (ForkJoinPool-1-worker-11) [] START, Destr=
oyVDSCommand(HostName =3D victor.local.systea.fr, DestroyVmVDSCommandPa=
rameters:{runAsync=3D'true', hostId=3D'ce3938b1-b23f-4d22-840a-f17d7cd8=
7bb1', vmId=3D'3f57e669-5e4c-4d10-85cc-d573004a099d', force=3D'false',
=
secondsToWait=3D'0', gracefully=3D'false', reason=3D'',
ignoreNoVm=3D't=
rue'}), log id: 560eca57
2018-02-12 16:46:41,650+01 INFO =C2=A0[org.ovirt.engine.core.vdsbroker.=
vdsbroker.DestroyVDSCommand] (ForkJoinPool-1-worker-11) [] FINISH, Dest=
royVDSCommand, log id: 560eca57
2018-02-12 16:46:41,650+01 INFO =C2=A0[org.ovirt.engine.core.vdsbroker.=
monitoring.VmAnalyzer] (ForkJoinPool-1-worker-11) [] VM '3f57e669-5e4c-=
4d10-85cc-d573004a099d'(Oracle=5FSECONDARY) moved from 'MigratingFrom' =
--> 'Down'
2018-02-12 16:46:41,651+01 INFO =C2=A0[org.ovirt.engine.core.vdsbroker.=
monitoring.VmAnalyzer] (ForkJoinPool-1-worker-11) [] Handing over VM '3=
f57e669-5e4c-4d10-85cc-d573004a099d'(Oracle=5FSECONDARY) to Host 'd569c=
2dd-8f30-4878-8aea-858db285cf69'. Setting VM to status 'MigratingTo'
2018-02-12 16:46:42,163+01 INFO =C2=A0[org.ovirt.engine.core.vdsbroker.=
monitoring.VmAnalyzer] (ForkJoinPool-1-worker-4) [] VM '3f57e669-5e4c-4=
d10-85cc-d573004a099d'(Oracle=5FSECONDARY) moved from 'MigratingTo' -->=
'Up'
2018-02-12 16:46:42,169+01 INFO =C2=A0[org.ovirt.engine.core.vdsbroker.=
vdsbroker.MigrateStatusVDSCommand] (ForkJoinPool-1-worker-4) [] START, =
MigrateStatusVDSCommand(HostName =3D ginger.local.systea.fr, MigrateSta=
tusVDSCommandParameters:{runAsync=3D'true', hostId=3D'd569c2dd-8f30-487=
8-8aea-858db285cf69', vmId=3D'3f57e669-5e4c-4d10-85cc-d573004a099d'}), =
log id: 7a25c281
2018-02-12 16:46:42,174+01 INFO =C2=A0[org.ovirt.engine.core.vdsbroker.=
vdsbroker.MigrateStatusVDSCommand] (ForkJoinPool-1-worker-4) [] FINISH,=
MigrateStatusVDSCommand, log id: 7a25c281
2018-02-12 16:46:42,194+01 INFO =C2=A0[org.ovirt.engine.core.dal.dbbrok=
er.auditloghandling.AuditLogDirector] (ForkJoinPool-1-worker-4) [] EVEN=
T=5FID: VM=5FMIGRATION=5FDONE(63), Correlation ID: 2f712024-5982-46a8-8=
2c8-fd8293da5725, Job ID: 4bd19aa9-cc99-4d02-884e-5a1e857a7738, Call St=
ack: null, Custom ID: null, Custom Event ID: -1, Message: Migration com=
pleted (VM: Oracle=5FSECONDARY, Source: victor.local.systea.fr, Destina=
tion: ginger.local.systea.fr, Duration: 11 seconds, Total: 11 seconds, =
Actual downtime: (N/A))
2018-02-12 16:46:42,201+01 INFO =C2=A0[org.ovirt.engine.core.bll.Migrat=
eVmToServerCommand] (ForkJoinPool-1-worker-4) [] Lock freed to object '=
EngineLock:{exclusiveLocks=3D'[3f57e669-5e4c-4d10-85cc-d573004a099d=3DV=
M]', sharedLocks=3D''}'
2018-02-12 16:46:42,203+01 INFO =C2=A0[org.ovirt.engine.core.vdsbroker.=
vdsbroker.FullListVDSCommand] (ForkJoinPool-1-worker-4) [] START, FullL=
istVDSCommand(HostName =3D ginger.local.systea.fr, FullListVDSCommandPa=
rameters:{runAsync=3D'true', hostId=3D'd569c2dd-8f30-4878-8aea-858db285=
cf69', vmIds=3D'[3f57e669-5e4c-4d10-85cc-d573004a099d]'}), log id: 7cc6=
5298
2018-02-12 16:46:42,254+01 INFO =C2=A0[org.ovirt.engine.core.vdsbroker.=
vdsbroker.FullListVDSCommand] (ForkJoinPool-1-worker-4) [] FINISH, Full=
ListVDSCommand, return: [{acpiEnable=3Dtrue, emulatedMachine=3Dpc-i440f=
x-rhel7.3.0, afterMigrationStatus=3D, tabletEnable=3Dtrue, pid=3D18748,=
guestDiskMapping=3D{}, transparentHugePages=3Dtrue, timeOffset=3D0, cp=
uType=3DNehalem, smp=3D2, guestNumaNodes=3D[Ljava.lang.Object;@760085fd=
, custom=3D{device=5Ffbddd528-7d93-49c6-a286-180e021cb274device=5F879c9=
3ab-4df1-435c-af02-565039fcc254=3DVmDevice:{id=3D'VmDeviceId:{deviceId=3D=
'879c93ab-4df1-435c-af02-565039fcc254', vmId=3D'3f57e669-5e4c-4d10-85cc=
-d573004a099d'}', device=3D'unix', type=3D'CHANNEL',
bootOrder=3D'0', s=
pecParams=3D'[]', address=3D'{bus=3D0, controller=3D0, type=3Dvirtio-se=
rial, port=3D1}', managed=3D'false', plugged=3D'true',
readOnly=3D'fals=
e', deviceAlias=3D'channel0', customProperties=3D'[]',
snapshotId=3D'nu=
ll', logicalName=3D'null', hostDevice=3D'null'},
device=5Ffbddd528-7d93=
-49c6-a286-180e021cb274device=5F879c93ab-4df1-435c-af02-565039fcc254dev=
ice=5F8945f61a-abbe-4156-8485-a4aa6f1908dbdevice=5F017b5e59-01c4-4aac-b=
f0c-b5d9557284d6=3DVmDevice:{id=3D'VmDeviceId:{deviceId=3D'017b5e59-01c=
4-4aac-bf0c-b5d9557284d6', vmId=3D'3f57e669-5e4c-4d10-85cc-d573004a099d=
'}', device=3D'tablet', type=3D'UNKNOWN', bootOrder=3D'0',
specParams=3D=
'[]', address=3D'{bus=3D0, type=3Dusb, port=3D1}',
managed=3D'false', p=
lugged=3D'true', readOnly=3D'false', deviceAlias=3D'input0',
customProp=
erties=3D'[]', snapshotId=3D'null', logicalName=3D'null',
hostDevice=3D=
'null'}, device=5Ffbddd528-7d93-49c6-a286-180e021cb274=3DVmDevice:{id=3D=
'VmDeviceId:{deviceId=3D'fbddd528-7d93-49c6-a286-180e021cb274', vmId=3D=
'3f57e669-5e4c-4d10-85cc-d573004a099d'}', device=3D'ide',
type=3D'CONTR=
OLLER', bootOrder=3D'0', specParams=3D'[]',
address=3D'{slot=3D0x01, bu=
s=3D0x00, domain=3D0x0000, type=3Dpci, function=3D0x1}', managed=3D'fal=
se', plugged=3D'true', readOnly=3D'false',
deviceAlias=3D'ide', customP=
roperties=3D'[]', snapshotId=3D'null', logicalName=3D'null',
hostDevice=
=3D'null'}, device=5Ffbddd528-7d93-49c6-a286-180e021cb274device=5F879c9=
3ab-4df1-435c-af02-565039fcc254device=5F8945f61a-abbe-4156-8485-a4aa6f1=
908db=3DVmDevice:{id=3D'VmDeviceId:{deviceId=3D'8945f61a-abbe-4156-8485=
-a4aa6f1908db', vmId=3D'3f57e669-5e4c-4d10-85cc-d573004a099d'}', device=
=3D'unix', type=3D'CHANNEL', bootOrder=3D'0',
specParams=3D'[]', addres=
s=3D'{bus=3D0, controller=3D0, type=3Dvirtio-serial, port=3D2}', manage=
d=3D'false', plugged=3D'true', readOnly=3D'false',
deviceAlias=3D'chann=
el1', customProperties=3D'[]', snapshotId=3D'null',
logicalName=3D'null=
', hostDevice=3D'null'}}, vmType=3Dkvm, memSize=3D8192, smpCoresPerSock=
et=3D1, vmName=3DOracle=5FSECONDARY, nice=3D0, status=3DUp, maxMemSize=3D=
32768, bootMenuEnable=3Dfalse, vmId=3D3f57e669-5e4c-4d10-85cc-d573004a0=
99d, numOfIoThreads=3D2, smpThreadsPerCore=3D1, smartcardEnable=3Dfalse=
, maxMemSlots=3D16, kvmEnable=3Dtrue, pitReinjection=3Dfalse, displayNe=
twork=3Dovirtmgmt, devices=3D[Ljava.lang.Object;@2e4d3dd3, memGuarantee=
dSize=3D8192, maxVCpus=3D16, clientIp=3D, statusTime=3D4304259600, disp=
lay=3Dvnc}], log id: 7cc65298
2018-02-12 16:46:42,257+01 INFO =C2=A0[org.ovirt.engine.core.vdsbroker.=
monitoring.VmDevicesMonitoring] (ForkJoinPool-1-worker-4) [] Received a=
vnc Device without an address when processing VM 3f57e669-5e4c-4d10-85=
cc-d573004a099d devices, skipping device: {device=3Dvnc, specParams=3D{=
displayNetwork=3Dovirtmgmt, keyMap=3Dfr, displayIp=3D192.168.0.5}, type=
=3Dgraphics, deviceId=3D813957b1-446a-4e88-9e40-9fe76d2c442d, port=3D59=
01}
2018-02-12 16:46:42,257+01 INFO =C2=A0[org.ovirt.engine.core.vdsbroker.=
monitoring.VmDevicesMonitoring] (ForkJoinPool-1-worker-4) [] Received a=
lease Device without an address when processing VM 3f57e669-5e4c-4d10-=
85cc-d573004a099d devices, skipping device: {lease=5Fid=3D3f57e669-5e4c=
-4d10-85cc-d573004a099d, sd=5Fid=3D1e51cecc-eb2e-47d0-b185-920fdc7afa16=
, deviceId=3D{uuid=3Da09949aa-5642-4b6d-94a4-8b0d04257be5}, offset=3D62=
91456, device=3Dlease, path=3D/rhev/data-center/mnt/glusterSD/192.168.0=
.6:=5FDATA01/1e51cecc-eb2e-47d0-b185-920fdc7afa16/dom=5Fmd/xleases, typ=
e=3Dlease}
2018-02-12 16:46:46,260+01 INFO =C2=A0[org.ovirt.engine.core.vdsbroker.=
vdsbroker.FullListVDSCommand] (DefaultQuartzScheduler5) [7fcb200a] FINI=
SH, FullListVDSCommand, return: [{acpiEnable=3Dtrue, emulatedMachine=3D=
pc-i440fx-rhel7.3.0, afterMigrationStatus=3D, tabletEnable=3Dtrue, pid=3D=
18748, guestDiskMapping=3D{0QEMU=5FQEMU=5FHARDDISK=5Fd890fa68-fba4-4f49=
-9=3D{name=3D/dev/sda}, QEMU=5FDVD-ROM=5FQM00003=3D{name=3D/dev/sr0}}, =
transparentHugePages=3Dtrue, timeOffset=3D0, cpuType=3DNehalem, smp=3D2=
, guestNumaNodes=3D[Ljava.lang.Object;@77951faf, custom=3D{device=5Ffbd=
dd528-7d93-49c6-a286-180e021cb274device=5F879c93ab-4df1-435c-af02-56503=
9fcc254=3DVmDevice:{id=3D'VmDeviceId:{deviceId=3D'879c93ab-4df1-435c-af=
02-565039fcc254', vmId=3D'3f57e669-5e4c-4d10-85cc-d573004a099d'}', devi=
ce=3D'unix', type=3D'CHANNEL', bootOrder=3D'0',
specParams=3D'[]', addr=
ess=3D'{bus=3D0, controller=3D0, type=3Dvirtio-serial, port=3D1}', mana=
ged=3D'false', plugged=3D'true', readOnly=3D'false',
deviceAlias=3D'cha=
nnel0', customProperties=3D'[]', snapshotId=3D'null',
logicalName=3D'nu=
ll', hostDevice=3D'null'}, device=5Ffbddd528-7d93-49c6-a286-180e021cb27=
4device=5F879c93ab-4df1-435c-af02-565039fcc254device=5F8945f61a-abbe-41=
56-8485-a4aa6f1908dbdevice=5F017b5e59-01c4-4aac-bf0c-b5d9557284d6=3DVmD=
evice:{id=3D'VmDeviceId:{deviceId=3D'017b5e59-01c4-4aac-bf0c-b5d9557284=
d6', vmId=3D'3f57e669-5e4c-4d10-85cc-d573004a099d'}',
device=3D'tablet'=
, type=3D'UNKNOWN', bootOrder=3D'0', specParams=3D'[]',
address=3D'{bus=
=3D0, type=3Dusb, port=3D1}', managed=3D'false', plugged=3D'true',
read=
Only=3D'false', deviceAlias=3D'input0', customProperties=3D'[]',
snapsh=
otId=3D'null', logicalName=3D'null', hostDevice=3D'null'},
device=5Ffbd=
dd528-7d93-49c6-a286-180e021cb274=3DVmDevice:{id=3D'VmDeviceId:{deviceI=
d=3D'fbddd528-7d93-49c6-a286-180e021cb274', vmId=3D'3f57e669-5e4c-4d10-=
85cc-d573004a099d'}', device=3D'ide', type=3D'CONTROLLER',
bootOrder=3D=
'0', specParams=3D'[]', address=3D'{slot=3D0x01, bus=3D0x00,
domain=3D0=
x0000, type=3Dpci, function=3D0x1}', managed=3D'false', plugged=3D'true=
', readOnly=3D'false', deviceAlias=3D'ide',
customProperties=3D'[]', sn=
apshotId=3D'null', logicalName=3D'null', hostDevice=3D'null'},
device=5F=
fbddd528-7d93-49c6-a286-180e021cb274device=5F879c93ab-4df1-435c-af02-56=
5039fcc254device=5F8945f61a-abbe-4156-8485-a4aa6f1908db=3DVmDevice:{id=3D=
'VmDeviceId:{deviceId=3D'8945f61a-abbe-4156-8485-a4aa6f1908db', vmId=3D=
'3f57e669-5e4c-4d10-85cc-d573004a099d'}', device=3D'unix',
type=3D'CHAN=
NEL', bootOrder=3D'0', specParams=3D'[]', address=3D'{bus=3D0,
controll=
er=3D0, type=3Dvirtio-serial, port=3D2}', managed=3D'false', plugged=3D=
'true', readOnly=3D'false', deviceAlias=3D'channel1',
customProperties=3D=
'[]', snapshotId=3D'null', logicalName=3D'null',
hostDevice=3D'null'}},=
vmType=3Dkvm, memSize=3D8192, smpCoresPerSocket=3D1, vmName=3DOracle=5F=
SECONDARY, nice=3D0, status=3DUp, maxMemSize=3D32768, bootMenuEnable=3D=
false, vmId=3D3f57e669-5e4c-4d10-85cc-d573004a099d, numOfIoThreads=3D2,=
smpThreadsPerCore=3D1, smartcardEnable=3Dfalse, maxMemSlots=3D16, kvmE=
nable=3Dtrue, pitReinjection=3Dfalse, displayNetwork=3Dovirtmgmt, devic=
es=3D[Ljava.lang.Object;@286410fd, memGuaranteedSize=3D8192, maxVCpus=3D=
16, clientIp=3D, statusTime=3D4304263620, display=3Dvnc}], log id: 58cd=
ef4c
2018-02-12 16:46:46,267+01 INFO =C2=A0[org.ovirt.engine.core.vdsbroker.=
monitoring.VmDevicesMonitoring] (DefaultQuartzScheduler5) [7fcb200a] Re=
ceived a vnc Device without an address when processing VM 3f57e669-5e4c=
-4d10-85cc-d573004a099d devices, skipping device: {device=3Dvnc, specPa=
rams=3D{displayNetwork=3Dovirtmgmt, keyMap=3Dfr, displayIp=3D192.168.0.=
5}, type=3Dgraphics, deviceId=3D813957b1-446a-4e88-9e40-9fe76d2c442d, p=
ort=3D5901}
2018-02-12 16:46:46,268+01 INFO =C2=A0[org.ovirt.engine.core.vdsbroker.=
monitoring.VmDevicesMonitoring] (DefaultQuartzScheduler5) [7fcb200a] Re=
ceived a lease Device without an address when processing VM 3f57e669-5e=
4c-4d10-85cc-d573004a099d devices, skipping device: {lease=5Fid=3D3f57e=
669-5e4c-4d10-85cc-d573004a099d, sd=5Fid=3D1e51cecc-eb2e-47d0-b185-920f=
dc7afa16, deviceId=3D{uuid=3Da09949aa-5642-4b6d-94a4-8b0d04257be5}, off=
set=3D6291456, device=3Dlease, path=3D/rhev/data-center/mnt/glusterSD/1=
92.168.0.6:=5FDATA01/1e51cecc-eb2e-47d0-b185-920fdc7afa16/dom=5Fmd/xlea=
ses, type=3Dlease}
=C2=A0
For the VM with 2 vdisks we see :
2018-02-12 16:49:06,112+01 INFO =C2=A0[org.ovirt.engine.core.bll.Migrat=
eVmToServerCommand] (default task-50) [92b5af33-cb87-4142-b8fe-8b838dd7=
458e] Lock Acquired to object 'EngineLock:{exclusiveLocks=3D'[f7d4ec12-=
627a-4b83-b59e-886400d55474=3DVM]', sharedLocks=3D''}'
2018-02-12 16:49:06,407+01 INFO =C2=A0[org.ovirt.engine.core.bll.Migrat=
eVmToServerCommand] (org.ovirt.thread.pool-6-thread-49) [92b5af33-cb87-=
4142-b8fe-8b838dd7458e] Running command: MigrateVmToServerCommand inter=
nal: false. Entities affected : =C2=A0ID: f7d4ec12-627a-4b83-b59e-88640=
0d55474 Type: VMAction group MIGRATE=5FVM with role type USER
2018-02-12 16:49:06,712+01 INFO =C2=A0[org.ovirt.engine.core.vdsbroker.=
MigrateVDSCommand] (org.ovirt.thread.pool-6-thread-49) [92b5af33-cb87-4=
142-b8fe-8b838dd7458e] START, MigrateVDSCommand( MigrateVDSCommandParam=
eters:{runAsync=3D'true', hostId=3D'd569c2dd-8f30-4878-8aea-858db285cf6=
9', vmId=3D'f7d4ec12-627a-4b83-b59e-886400d55474', srcHost=3D'192.168.0=
.5', dstVdsId=3D'ce3938b1-b23f-4d22-840a-f17d7cd87bb1', dstHost=3D'192.=
168.0.6:54321', migrationMethod=3D'ONLINE', tunnelMigration=3D'false',
=
migrationDowntime=3D'0', autoConverge=3D'true',
migrateCompressed=3D'fa=
lse', consoleAddress=3D'null', maxBandwidth=3D'500',
enableGuestEvents=3D=
'true', maxIncomingMigrations=3D'2', maxOutgoingMigrations=3D'2',
conve=
rgenceSchedule=3D'[init=3D[{name=3DsetDowntime, params=3D[100]}], stall=
ing=3D[{limit=3D1, action=3D{name=3DsetDowntime, params=3D[150]}}, {lim=
it=3D2, action=3D{name=3DsetDowntime, params=3D[200]}}, {limit=3D3, act=
ion=3D{name=3DsetDowntime, params=3D[300]}}, {limit=3D4, action=3D{name=
=3DsetDowntime, params=3D[400]}}, {limit=3D6, action=3D{name=3DsetDownt=
ime, params=3D[500]}}, {limit=3D-1, action=3D{name=3Dabort, params=3D[]=
}}]]'}), log id: 3702a9e0
2018-02-12 16:49:06,713+01 INFO =C2=A0[org.ovirt.engine.core.vdsbroker.=
vdsbroker.MigrateBrokerVDSCommand] (org.ovirt.thread.pool-6-thread-49) =
[92b5af33-cb87-4142-b8fe-8b838dd7458e] START, MigrateBrokerVDSCommand(H=
ostName =3D ginger.local.systea.fr, MigrateVDSCommandParameters:{runAsy=
nc=3D'true', hostId=3D'd569c2dd-8f30-4878-8aea-858db285cf69',
vmId=3D'f=
7d4ec12-627a-4b83-b59e-886400d55474', srcHost=3D'192.168.0.5', dstVdsId=
=3D'ce3938b1-b23f-4d22-840a-f17d7cd87bb1', dstHost=3D'192.168.0.6:54321=
', migrationMethod=3D'ONLINE', tunnelMigration=3D'false',
migrationDown=
time=3D'0', autoConverge=3D'true', migrateCompressed=3D'false',
console=
Address=3D'null', maxBandwidth=3D'500',
enableGuestEvents=3D'true', max=
IncomingMigrations=3D'2', maxOutgoingMigrations=3D'2', convergenceSched=
ule=3D'[init=3D[{name=3DsetDowntime, params=3D[100]}], stalling=3D[{lim=
it=3D1, action=3D{name=3DsetDowntime, params=3D[150]}}, {limit=3D2, act=
ion=3D{name=3DsetDowntime, params=3D[200]}}, {limit=3D3, action=3D{name=
=3DsetDowntime, params=3D[300]}}, {limit=3D4, action=3D{name=3DsetDownt=
ime, params=3D[400]}}, {limit=3D6, action=3D{name=3DsetDowntime, params=
=3D[500]}}, {limit=3D-1, action=3D{name=3Dabort, params=3D[]}}]]'}), lo=
g id: 1840069c
2018-02-12 16:49:06,724+01 INFO =C2=A0[org.ovirt.engine.core.vdsbroker.=
vdsbroker.MigrateBrokerVDSCommand] (org.ovirt.thread.pool-6-thread-49) =
[92b5af33-cb87-4142-b8fe-8b838dd7458e] FINISH, MigrateBrokerVDSCommand,=
log id: 1840069c
2018-02-12 16:49:06,732+01 INFO =C2=A0[org.ovirt.engine.core.vdsbroker.=
MigrateVDSCommand] (org.ovirt.thread.pool-6-thread-49) [92b5af33-cb87-4=
142-b8fe-8b838dd7458e] FINISH, MigrateVDSCommand, return: MigratingFrom=
, log id: 3702a9e0
2018-02-12 16:49:06,753+01 INFO =C2=A0[org.ovirt.engine.core.dal.dbbrok=
er.auditloghandling.AuditLogDirector] (org.ovirt.thread.pool-6-thread-4=
9) [92b5af33-cb87-4142-b8fe-8b838dd7458e] EVENT=5FID: VM=5FMIGRATION=5F=
START(62), Correlation ID: 92b5af33-cb87-4142-b8fe-8b838dd7458e, Job ID=
: f4f54054-f7c8-4481-8eda-d5a15c383061, Call Stack: null, Custom ID: nu=
ll, Custom Event ID: -1, Message: Migration started (VM: Oracle=5FPRIMA=
RY, Source: ginger.local.systea.fr, Destination: victor.local.systea.fr=
, User: admin@internal-authz).
...
2018-02-12 16:49:16,453+01 INFO =C2=A0[org.ovirt.engine.core.vdsbroker.=
monitoring.VmsStatisticsFetcher] (DefaultQuartzScheduler4) [162a5bc3] F=
etched 2 VMs from VDS 'ce3938b1-b23f-4d22-840a-f17d7cd87bb1'
2018-02-12 16:49:16,455+01 INFO =C2=A0[org.ovirt.engine.core.vdsbroker.=
monitoring.VmAnalyzer] (DefaultQuartzScheduler4) [162a5bc3] VM 'f7d4ec1=
2-627a-4b83-b59e-886400d55474'(Oracle=5FPRIMARY) was unexpectedly detec=
ted as 'MigratingTo' on VDS 'ce3938b1-b23f-4d22-840a-f17d7cd87bb1'(vict=
or.local.systea.fr) (expected on 'd569c2dd-8f30-4878-8aea-858db285cf69'=
)
2018-02-12 16:49:16,455+01 INFO =C2=A0[org.ovirt.engine.core.vdsbroker.=
monitoring.VmAnalyzer] (DefaultQuartzScheduler4) [162a5bc3] VM 'f7d4ec1=
2-627a-4b83-b59e-886400d55474' is migrating to VDS 'ce3938b1-b23f-4d22-=
840a-f17d7cd87bb1'(victor.local.systea.fr) ignoring it in the refresh u=
ntil migration is done
...
2018-02-12 16:49:31,484+01 INFO =C2=A0[org.ovirt.engine.core.vdsbroker.=
monitoring.VmAnalyzer] (DefaultQuartzScheduler5) [11a7619a] VM 'f7d4ec1=
2-627a-4b83-b59e-886400d55474'(Oracle=5FPRIMARY) was unexpectedly detec=
ted as 'MigratingTo' on VDS 'ce3938b1-b23f-4d22-840a-f17d7cd87bb1'(vict=
or.local.systea.fr) (expected on 'd569c2dd-8f30-4878-8aea-858db285cf69'=
)
2018-02-12 16:49:31,484+01 INFO =C2=A0[org.ovirt.engine.core.vdsbroker.=
monitoring.VmAnalyzer] (DefaultQuartzScheduler5) [11a7619a] VM 'f7d4ec1=
2-627a-4b83-b59e-886400d55474' is migrating to VDS 'ce3938b1-b23f-4d22-=
840a-f17d7cd87bb1'(victor.local.systea.fr) ignoring it in the refresh u=
ntil migration is done
=C2=A0
and so on, last lines repeated indefinitly for hours since we poweroff =
the VM...
Is this something known ? Any idea about that ?
Thanks
Ovirt 4.1.6, updated last at feb-13. Gluster 3.12.1.
--
Cordialement,
Frank Soyer
=5F=5F=5F=5F=5F=5F=5F=5F=5F=5F=5F=5F=5F=5F=5F=5F=5F=5F=5F=5F=5F=5F=5F=5F=
=5F=5F=5F=5F=5F=5F=5F=5F=5F=5F=5F=5F=5F=5F=5F=5F=5F=5F=5F=5F=5F=5F=5F
Users mailing list
Users(a)ovirt.org
http://lists.ovirt.org/mailman/listinfo/users
=C2=A0
=C2=A0
------=_=-_OpenGroupware_org_NGMime-18019-1518771817.081391-1------
Content-Type: text/html; charset=utf-8
Content-Transfer-Encoding: quoted-printable
Content-Length: 36783
<html>Hi Maor,<br />sorry for the double post, I've change the email ad=
ress of my account and supposed that I'd need to re-post it.<br />And t=
hank you for your time. Here are the logs. I added a vdisk to an existi=
ng VM : it no more migrates, needing to poweroff it after minutes. Then=
simply deleting the second disk makes migrate it in exactly 9s without=
problem ! <br />https://gist.github.com/fgth/4707446331d201eef574=
ac31b6e89561<br />https://gist.github.com/fgth/f8de9c22664aee53722af676=
bff8719d<br /><br />--<br /><style
type=3D"text/css">.Text1 {
color: black;
font-size:9pt;
font-family:Verdana;
}
.Text2 {
color: black;
font-size:7pt;
font-family:Verdana;
}</style><p class=3D"Text1">Cordialement,<br /><br
/><b>Frank Soyer=
</b></p>Le Mercredi, F=C3=A9vrier 14, 2018 11:04 CET, Maor Lipchu=
k &lt;mlipchuk(a)redhat.com&gt; a =C3=A9crit:<br /> <blockquote
type=
=3D"cite" cite=3D"CAJ1JNOcD3ZX6hYG4TJ0-=5FumSgw6-wtJoC=5F=5FHRbarc1io-Y=
-6Jw(a)mail.gmail.com"><div dir=3D"ltr">Hi
Frank,<div> </div><div>I =
already replied on your last email.</div><div><div>Can you provide the
=
VDSM logs from the time of the migration failure for both hosts:</div><=
div> <span
style=3D"font-family:monospace"> </span><a target=3D=
"=5Fblank" style=3D"font-family:monospace"
href=3D"http://ginger.local.=
systea.fr/">ginger.local.systea.f</a>r and <a
target=3D"=5Fbl=
ank" style=3D"font-family:monospace"
href=3D"http://victor.local.systea=
.fr/">v<wbr
/>ictor.local.systea.fr</a></div><div> </div><div>Than=
ks,</div><div>Maor</div></div></div><div
class=3D"gmail=5Fextra"> =
<div class=3D"gmail=5Fquote">On Wed, Feb 14, 2018 at 11:23 AM, fsoyer
<=
span dir=3D"ltr"><<a target=3D"=5Fblank"
href=3D"mailto:fsoyer@syste=
a.fr">fsoyer(a)systea.fr</a>&gt;</span> wrote:<blockquote
class=3D"gmail=5F=
quote" style=3D"margin:0 0 0 .8ex;border-left:1px #ccc solid;padding-le=
ft:1ex"><p>Hi all,<br />I discovered yesterday a problem when
migrating=
VM with more than one vdisk.<br />On our test servers (oVirt4.1, share=
d storage with Gluster), I created 2 VMs needed for a test, from a temp=
late with a 20G vdisk. On this VMs I added a 100G vdisk (for this tests=
I didn't want to waste time to extend the existing vdisks... But I los=
t time finally...). The VMs with the 2 vdisks works well.<br />Now I sa=
w some updates waiting on the host. I tried to put it in maintenance...=
But it stopped on the two VM. They were marked "migrating", but no mor=
e accessible. Other (small) VMs with only 1 vdisk was migrated without =
problem at the same time.<br />I saw that a kvm process for the (big) V=
Ms was launched on the source AND destination host, but after tens of m=
inutes, the migration and the VMs was always freezed. I tried to cancel=
the migration for the VMs : failed. The only way to stop it was to pow=
eroff the VMs : the kvm process died on the 2 hosts and the GUI alerted=
on a failed migration.<br />In doubt, I tried to delete the second vdi=
sk on one of this VMs : it migrates then without error ! And no access =
problem.<br />I tried to extend the first vdisk of the second VM, the d=
elete the second vdisk : it migrates now without problem ! &=
nbsp;<br /><br />So after another test with a VM with 2 vdisks, I can s=
ay that this blocked the migration process :(<br /><br />In engine.log,=
for a VMs with 1 vdisk migrating well, we see :</p><blockquote>2018-02=
-12 16:46:29,705+01 INFO [org.ovirt.engine.core.bll.<wbr />Migrat=
eVmToServerCommand] (default task-28) [2f712024-5982-46a8-82c8-<wbr />f=
d8293da5725] Lock Acquired to object 'EngineLock:{exclusiveLocks=3D'[<w=
br />3f57e669-5e4c-4d10-85cc-<wbr />d573004a099d=3DVM]', sharedLocks=3D=
''}'<br />2018-02-12 16:46:29,955+01 INFO
[org.ovirt.engine.core.=
bll.<wbr />MigrateVmToServerCommand] (org.ovirt.thread.pool-6-<wbr />th=
read-32) [2f712024-5982-46a8-82c8-<wbr />fd8293da5725] Running command:=
MigrateVmToServerCommand internal: false. Entities affected : ID=
: 3f57e669-5e4c-4d10-85cc-<wbr />d573004a099d Type: VMAction group MIGR=
ATE=5FVM with role type USER<br />2018-02-12 16:46:30,261+01 INFO  =
;[org.ovirt.engine.core.<wbr />vdsbroker.MigrateVDSCommand] (org.ovirt.=
thread.pool-6-<wbr />thread-32) [2f712024-5982-46a8-82c8-<wbr />fd8293d=
a5725] START, MigrateVDSCommand( MigrateVDSCommandParameters:{<wbr />ru=
nAsync=3D'true', hostId=3D'ce3938b1-b23f-4d22-<wbr
/>840a-f17d7cd87bb1'=
, vmId=3D'3f57e669-5e4c-4d10-85cc-<wbr />d573004a099d',
srcHost=3D'192.=
168.0.6', dstVdsId=3D'd569c2dd-8f30-4878-<wbr />8aea-858db285cf69',
dst=
Host=3D'<a target=3D"=5Fblank"
href=3D"http://192.168.0.5:54321">192.16=
8.0.5:54321</a>', migrationMethod=3D'ONLINE',
tunnelMigration=3D'false'=
, migrationDowntime=3D'0', autoConverge=3D'true',
migrateCompressed=3D'=
false', consoleAddress=3D'null', maxBandwidth=3D'500',
enableGuestEvent=
s=3D'true', maxIncomingMigrations=3D'2',
maxOutgoingMigrations=3D'2', c=
onvergenceSchedule=3D'[init=3D[{<wbr />name=3DsetDowntime, params=3D[10=
0]}], stalling=3D[{limit=3D1, action=3D{name=3DsetDowntime, params=3D[1=
50]}}, {limit=3D2, action=3D{name=3DsetDowntime, params=3D[200]}}, {lim=
it=3D3, action=3D{name=3DsetDowntime, params=3D[300]}}, {limit=3D4, act=
ion=3D{name=3DsetDowntime, params=3D[400]}}, {limit=3D6, action=3D{name=
=3DsetDowntime, params=3D[500]}}, {limit=3D-1, action=3D{name=3Dabort, =
params=3D[]}}]]'}), log id: 14f61ee0<br />2018-02-12 16:46:30,262+01 IN=
FO [org.ovirt.engine.core.<wbr />vdsbroker.vdsbroker.<wbr />Migra=
teBrokerVDSCommand] (org.ovirt.thread.pool-6-<wbr />thread-32) [2f71202=
4-5982-46a8-82c8-<wbr />fd8293da5725] START, MigrateBrokerVDSCommand(<w=
br />HostName =3D <a target=3D"=5Fblank"
href=3D"http://victor.local.sy=
stea.fr">victor.local.systea.fr</a>, MigrateVDSCommandParameters:{<wbr =
/>runAsync=3D'true', hostId=3D'ce3938b1-b23f-4d22-<wbr
/>840a-f17d7cd87=
bb1', vmId=3D'3f57e669-5e4c-4d10-85cc-<wbr />d573004a099d',
srcHost=3D'=
192.168.0.6', dstVdsId=3D'd569c2dd-8f30-4878-<wbr
/>8aea-858db285cf69',=
dstHost=3D'<a target=3D"=5Fblank"
href=3D"http://192.168.0.5:54321">19=
2.168.0.5:54321</a>', migrationMethod=3D'ONLINE',
tunnelMigration=3D'fa=
lse', migrationDowntime=3D'0', autoConverge=3D'true',
migrateCompressed=
=3D'false', consoleAddress=3D'null', maxBandwidth=3D'500',
enableGuestE=
vents=3D'true', maxIncomingMigrations=3D'2',
maxOutgoingMigrations=3D'2=
', convergenceSchedule=3D'[init=3D[{<wbr />name=3DsetDowntime, params=3D=
[100]}], stalling=3D[{limit=3D1, action=3D{name=3DsetDowntime, params=3D=
[150]}}, {limit=3D2, action=3D{name=3DsetDowntime, params=3D[200]}}, {l=
imit=3D3, action=3D{name=3DsetDowntime, params=3D[300]}}, {limit=3D4, a=
ction=3D{name=3DsetDowntime, params=3D[400]}}, {limit=3D6, action=3D{na=
me=3DsetDowntime, params=3D[500]}}, {limit=3D-1, action=3D{name=3Dabort=
, params=3D[]}}]]'}), log id: 775cd381<br />2018-02-12 16:46:30,277+01 =
INFO [org.ovirt.engine.core.<wbr />vdsbroker.vdsbroker.<wbr />Mig=
rateBrokerVDSCommand] (org.ovirt.thread.pool-6-<wbr />thread-32) [2f712=
024-5982-46a8-82c8-<wbr />fd8293da5725] FINISH, MigrateBrokerVDSCommand=
, log id: 775cd381<br />2018-02-12 16:46:30,285+01 INFO [org.ovir=
t.engine.core.<wbr />vdsbroker.MigrateVDSCommand] (org.ovirt.thread.poo=
l-6-<wbr />thread-32) [2f712024-5982-46a8-82c8-<wbr />fd8293da5725] FIN=
ISH, MigrateVDSCommand, return: MigratingFrom, log id: 14f61ee0<br />20=
18-02-12 16:46:30,301+01 INFO [org.ovirt.engine.core.dal.<wbr />d=
bbroker.auditloghandling.<wbr />AuditLogDirector] (org.ovirt.thread.poo=
l-6-<wbr />thread-32) [2f712024-5982-46a8-82c8-<wbr />fd8293da5725] EVE=
NT=5FID: VM=5FMIGRATION=5FSTART(62), Correlation ID: 2f712024-5982-46a8=
-82c8-<wbr />fd8293da5725, Job ID: 4bd19aa9-cc99-4d02-884e-<wbr />5a1e8=
57a7738, Call Stack: null, Custom ID: null, Custom Event ID: -1, Messag=
e: Migration started (VM: Oracle=5FSECONDARY, Source: <a target=3D"=5Fb=
lank"
href=3D"http://victor.local.systea.fr">victor.local.systea.fr</a>=
, Destination: <a target=3D"=5Fblank"
href=3D"http://ginger.local.syste=
a.fr">ginger.local.systea.fr</a>, User: admin(a)internal-authz).<br
/>201=
8-02-12 16:46:31,106+01 INFO [org.ovirt.engine.core.<wbr />vdsbro=
ker.vdsbroker.<wbr />FullListVDSCommand] (DefaultQuartzScheduler9) [54a=
65b66] START, FullListVDSCommand(HostName =3D <a target=3D"=5Fblank" hr=
ef=3D"http://victor.local.systea.fr">victor.local.systea.fr</a>,
FullLi=
stVDSCommandParameters:{<wbr />runAsync=3D'true',
hostId=3D'ce3938b1-b2=
3f-4d22-<wbr />840a-f17d7cd87bb1', vmIds=3D'[3f57e669-5e4c-4d10-<wbr
/>=
85cc-d573004a099d]'}), log id: 54b4b435<br />2018-02-12 16:46:31,147+01=
INFO [org.ovirt.engine.core.<wbr />vdsbroker.vdsbroker.<wbr />Fu=
llListVDSCommand] (DefaultQuartzScheduler9) [54a65b66] FINISH, FullList=
VDSCommand, return: [{acpiEnable=3Dtrue, emulatedMachine=3Dpc-i440fx-<w=
br />rhel7.3.0, tabletEnable=3Dtrue, pid=3D1493, guestDiskMapping=3D{0Q=
EMU=5FQEMU=5F<wbr />HARDDISK=5Fd890fa68-fba4-4f49-9=3D<wbr />{name=3D/d=
ev/sda}, QEMU=5FDVD-ROM=5FQM00003=3D{name=3D/<wbr />dev/sr0}}, transpar=
entHugePages=3Dtrue, timeOffset=3D0, cpuType=3DNehalem, smp=3D2, pauseC=
ode=3DNOERR, guestNumaNodes=3D[Ljava.lang.<wbr />Object;@1d9042cd, smar=
tcardEnable=3Dfalse, custom=3D{device=5Ffbddd528-7d93-<wbr />49c6-a286-=
180e021cb274device=5F<wbr />879c93ab-4df1-435c-af02-<wbr />565039fcc254=
=3DVmDevice:{id=3D'<wbr />VmDeviceId:{deviceId=3D'<wbr
/>879c93ab-4df1-=
435c-af02-<wbr />565039fcc254', vmId=3D'3f57e669-5e4c-4d10-85cc-<wbr
/>=
d573004a099d'}', device=3D'unix', type=3D'CHANNEL',
bootOrder=3D'0', sp=
ecParams=3D'[]', address=3D'{bus=3D0, controller=3D0, type=3Dvirtio-ser=
ial, port=3D1}', managed=3D'false', plugged=3D'true',
readOnly=3D'false=
', deviceAlias=3D'channel0', customProperties=3D'[]',
snapshotId=3D'nul=
l', logicalName=3D'null', hostDevice=3D'null'},
device=5Ffbddd528-7d93-=
49c6-<wbr />a286-180e021cb274device=5F<wbr />879c93ab-4df1-435c-af02-<w=
br />565039fcc254device=5F8945f61a-<wbr />abbe-4156-8485-<wbr />a4aa6f1=
908dbdevice=5F017b5e59-<wbr />01c4-4aac-bf0c-b5d9557284d6=3D<wbr />VmDe=
vice:{id=3D'VmDeviceId:{<wbr />deviceId=3D'017b5e59-01c4-4aac-<wbr
/>bf=
0c-b5d9557284d6', vmId=3D'3f57e669-5e4c-4d10-85cc-<wbr
/>d573004a099d'}=
', device=3D'tablet', type=3D'UNKNOWN', bootOrder=3D'0',
specParams=3D'=
[]', address=3D'{bus=3D0, type=3Dusb, port=3D1}', managed=3D'false',
pl=
ugged=3D'true', readOnly=3D'false', deviceAlias=3D'input0',
customPrope=
rties=3D'[]', snapshotId=3D'null', logicalName=3D'null',
hostDevice=3D'=
null'}, device=5Ffbddd528-7d93-49c6-<wbr />a286-180e021cb274=3DVmDevice=
:{<wbr />id=3D'VmDeviceId:{deviceId=3D'<wbr
/>fbddd528-7d93-49c6-a286-<=
wbr />180e021cb274', vmId=3D'3f57e669-5e4c-4d10-85cc-<wbr />d573004a099=
d'}', device=3D'ide', type=3D'CONTROLLER',
bootOrder=3D'0', specParams=3D=
'[]', address=3D'{slot=3D0x01, bus=3D0x00, domain=3D0x0000, type=3Dpci,=
function=3D0x1}', managed=3D'false', plugged=3D'true',
readOnly=3D'fal=
se', deviceAlias=3D'ide', customProperties=3D'[]',
snapshotId=3D'null',=
logicalName=3D'null', hostDevice=3D'null'}, device=5Ffbddd528-7d93-49c=
6-<wbr />a286-180e021cb274device=5F<wbr />879c93ab-4df1-435c-af02-<wbr =
/>565039fcc254device=5F8945f61a-<wbr />abbe-4156-8485-a4aa6f1908db=3D<w=
br />VmDevice:{id=3D'VmDeviceId:{<wbr />deviceId=3D'8945f61a-abbe-4156-=
<wbr />8485-a4aa6f1908db', vmId=3D'3f57e669-5e4c-4d10-85cc-<wbr
/>d5730=
04a099d'}', device=3D'unix', type=3D'CHANNEL',
bootOrder=3D'0', specPar=
ams=3D'[]', address=3D'{bus=3D0, controller=3D0, type=3Dvirtio-serial, =
port=3D2}', managed=3D'false', plugged=3D'true',
readOnly=3D'false', de=
viceAlias=3D'channel1', customProperties=3D'[]',
snapshotId=3D'null', l=
ogicalName=3D'null', hostDevice=3D'null'}}, vmType=3Dkvm, memSize=3D819=
2, smpCoresPerSocket=3D1, vmName=3DOracle=5FSECONDARY, nice=3D0, status=
=3DMigration Source, maxMemSize=3D32768, bootMenuEnable=3Dfalse, vmId=3D=
3f57e669-5e4c-4d10-85cc-<wbr />d573004a099d, numOfIoThreads=3D2, smpThr=
eadsPerCore=3D1, memGuaranteedSize=3D8192, kvmEnable=3Dtrue, pitReinjec=
tion=3Dfalse, displayNetwork=3Dovirtmgmt, devices=3D[Ljava.lang.Object;=
@<wbr />28ae66d7, display=3Dvnc, maxVCpus=3D16, clientIp=3D, statusTime=
=3D4299484520, maxMemSlots=3D16}], log id: 54b4b435<br />2018-02-12 16:=
46:31,150+01 INFO [org.ovirt.engine.core.<wbr />vdsbroker.monitor=
ing.<wbr />VmsStatisticsFetcher] (DefaultQuartzScheduler1) [27fac647] F=
etched 3 VMs from VDS 'd569c2dd-8f30-4878-8aea-<wbr />858db285cf69'<br =
/>2018-02-12 16:46:31,151+01 INFO [org.ovirt.engine.core.<wbr />v=
dsbroker.monitoring.<wbr />VmDevicesMonitoring] (DefaultQuartzScheduler=
9) [54a65b66] Received a vnc Device without an address when processing =
VM 3f57e669-5e4c-4d10-85cc-<wbr />d573004a099d devices, skipping device=
: {device=3Dvnc, specParams=3D{displayNetwork=3D<wbr />ovirtmgmt, keyMa=
p=3Dfr, displayIp=3D192.168.0.6}, type=3Dgraphics, deviceId=3D813957b1-=
446a-4e88-<wbr />9e40-9fe76d2c442d, port=3D5901}<br />2018-02-12 16:46:=
31,151+01 INFO [org.ovirt.engine.core.<wbr />vdsbroker.monitoring=
.<wbr />VmDevicesMonitoring] (DefaultQuartzScheduler9) [54a65b66] Recei=
ved a lease Device without an address when processing VM 3f57e669-5e4c-=
4d10-85cc-<wbr />d573004a099d devices, skipping device: {lease=5Fid=3D3=
f57e669-5e4c-4d10-<wbr />85cc-d573004a099d, sd=5Fid=3D1e51cecc-eb2e-47d=
0-b185-<wbr />920fdc7afa16, deviceId=3D{uuid=3Da09949aa-5642-<wbr />4b6=
d-94a4-8b0d04257be5}, offset=3D6291456, device=3Dlease, path=3D/rhev/da=
ta-center/mnt/<wbr />glusterSD/192.168.0.6:=5FDATA01/<wbr />1e51cecc-eb=
2e-47d0-b185-<wbr />920fdc7afa16/dom=5Fmd/xleases, type=3Dlease}<br />2=
018-02-12 16:46:31,152+01 INFO [org.ovirt.engine.core.<wbr />vdsb=
roker.monitoring.<wbr />VmAnalyzer] (DefaultQuartzScheduler1) [27fac647=
] VM '3f57e669-5e4c-4d10-85cc-<wbr />d573004a099d'(Oracle=5F<wbr
/>SECO=
NDARY) was unexpectedly detected as 'MigratingTo' on VDS 'd569c2dd-8f30=
-4878-8aea-<wbr />858db285cf69'(<a target=3D"=5Fblank"
href=3D"http://g=
inger.local.systea.fr">ginger.local.<wbr />systea.fr</a>) (expected on
=
'ce3938b1-b23f-4d22-840a-<wbr />f17d7cd87bb1')<br />2018-02-12
16:46:31=
,152+01 INFO [org.ovirt.engine.core.<wbr />vdsbroker.monitoring.<=
wbr />VmAnalyzer] (DefaultQuartzScheduler1) [27fac647] VM '3f57e669-5e4=
c-4d10-85cc-<wbr />d573004a099d' is migrating to VDS 'd569c2dd-8f30-487=
8-8aea-<wbr />858db285cf69'(<a target=3D"=5Fblank"
href=3D"http://ginge=
r.local.systea.fr">ginger.local.<wbr />systea.fr</a>) ignoring it in
th=
e refresh until migration is done<br />....<br />2018-02-12 16:46:41,63=
1+01 INFO [org.ovirt.engine.core.<wbr />vdsbroker.monitoring.<wbr=
/>VmAnalyzer] (ForkJoinPool-1-worker-11) [] VM '3f57e669-5e4c-4d10-85c=
c-<wbr />d573004a099d' was reported as Down on VDS 'ce3938b1-b23f-4d22-=
840a-<wbr />f17d7cd87bb1'(<a target=3D"=5Fblank"
href=3D"http://victor.=
local.systea.fr">victor.local.<wbr />systea.fr</a>)<br
/>2018-02-12 16:=
46:41,632+01 INFO [org.ovirt.engine.core.<wbr />vdsbroker.vdsbrok=
er.<wbr />DestroyVDSCommand] (ForkJoinPool-1-worker-11) [] START, Destr=
oyVDSCommand(HostName =3D <a target=3D"=5Fblank"
href=3D"http://victor.=
local.systea.fr">victor.local.systea.fr</a>, DestroyVmVDSCommandParamet=
ers:<wbr />{runAsync=3D'true', hostId=3D'ce3938b1-b23f-4d22-<wbr
/>840a=
-f17d7cd87bb1', vmId=3D'3f57e669-5e4c-4d10-85cc-<wbr />d573004a099d',
f=
orce=3D'false', secondsToWait=3D'0', gracefully=3D'false',
reason=3D'',=
ignoreNoVm=3D'true'}), log id: 560eca57<br />2018-02-12 16:46:41,650+0=
1 INFO [org.ovirt.engine.core.<wbr />vdsbroker.vdsbroker.<wbr />D=
estroyVDSCommand] (ForkJoinPool-1-worker-11) [] FINISH, DestroyVDSComma=
nd, log id: 560eca57<br />2018-02-12 16:46:41,650+01 INFO [org.ov=
irt.engine.core.<wbr />vdsbroker.monitoring.<wbr />VmAnalyzer] (ForkJoi=
nPool-1-worker-11) [] VM '3f57e669-5e4c-4d10-85cc-<wbr />d573004a099d'(=
Oracle=5F<wbr />SECONDARY) moved from 'MigratingFrom' -->
'Down'<br =
/>2018-02-12 16:46:41,651+01 INFO [org.ovirt.engine.core.<wbr />v=
dsbroker.monitoring.<wbr />VmAnalyzer] (ForkJoinPool-1-worker-11) [] Ha=
nding over VM '3f57e669-5e4c-4d10-85cc-<wbr />d573004a099d'(Oracle=5F<w=
br />SECONDARY) to Host 'd569c2dd-8f30-4878-8aea-<wbr />858db285cf69'. =
Setting VM to status 'MigratingTo'<br />2018-02-12 16:46:42,163+01 INFO=
[org.ovirt.engine.core.<wbr />vdsbroker.monitoring.<wbr />VmAnal=
yzer] (ForkJoinPool-1-worker-4) [] VM '3f57e669-5e4c-4d10-85cc-<wbr />d=
573004a099d'(Oracle=5F<wbr />SECONDARY) moved from 'MigratingTo'
-->=
'Up'<br />2018-02-12 16:46:42,169+01 INFO
[org.ovirt.engine.core=
.<wbr />vdsbroker.vdsbroker.<wbr />MigrateStatusVDSCommand] (ForkJoinPo=
ol-1-worker-4) [] START, MigrateStatusVDSCommand(<wbr />HostName =3D <a=
target=3D"=5Fblank"
href=3D"http://ginger.local.systea.fr">ginger.loca=
l.systea.fr</a>, MigrateStatusVDSCommandParamet<wbr />ers:{runAsync=3D'=
true', hostId=3D'd569c2dd-8f30-4878-<wbr />8aea-858db285cf69',
vmId=3D'=
3f57e669-5e4c-4d10-85cc-<wbr />d573004a099d'}), log id: 7a25c281<br />2=
018-02-12 16:46:42,174+01 INFO [org.ovirt.engine.core.<wbr />vdsb=
roker.vdsbroker.<wbr />MigrateStatusVDSCommand] (ForkJoinPool-1-worker-=
4) [] FINISH, MigrateStatusVDSCommand, log id: 7a25c281<br />2018-02-12=
16:46:42,194+01 INFO [org.ovirt.engine.core.dal.<wbr />dbbroker.=
auditloghandling.<wbr />AuditLogDirector] (ForkJoinPool-1-worker-4) [] =
EVENT=5FID: VM=5FMIGRATION=5FDONE(63), Correlation ID: 2f712024-5982-46=
a8-82c8-<wbr />fd8293da5725, Job ID: 4bd19aa9-cc99-4d02-884e-<wbr />5a1=
e857a7738, Call Stack: null, Custom ID: null, Custom Event ID: -1, Mess=
age: Migration completed (VM: Oracle=5FSECONDARY, Source: <a target=3D"=
=5Fblank"
href=3D"http://victor.local.systea.fr">victor.local.systea.fr=
</a>, Destination: <a target=3D"=5Fblank"
href=3D"http://ginger.local.s=
ystea.fr">ginger.local.systea.fr</a>, Duration: 11 seconds, Total: 11 s=
econds, Actual downtime: (N/A))<br />2018-02-12 16:46:42,201+01 INFO &n=
bsp;[org.ovirt.engine.core.bll.<wbr />MigrateVmToServerCommand] (ForkJo=
inPool-1-worker-4) [] Lock freed to object 'EngineLock:{exclusiveLocks=3D=
'[<wbr />3f57e669-5e4c-4d10-85cc-<wbr />d573004a099d=3DVM]',
sharedLock=
s=3D''}'<br />2018-02-12 16:46:42,203+01 INFO
[org.ovirt.engine.c=
ore.<wbr />vdsbroker.vdsbroker.<wbr />FullListVDSCommand] (ForkJoinPool=
-1-worker-4) [] START, FullListVDSCommand(HostName =3D <a target=3D"=5F=
blank"
href=3D"http://ginger.local.systea.fr">ginger.local.systea.fr</a=
, FullListVDSCommandParameters:{<wbr
/>runAsync=3D'true', hostId=3D'd5=
69c2dd-8f30-4878-<wbr
/>8aea-858db285cf69', vmIds=3D'[3f57e669-5e4c-4d1=
0-<wbr />85cc-d573004a099d]'}), log id: 7cc65298<br />2018-02-12 16:46:=
42,254+01 INFO [org.ovirt.engine.core.<wbr />vdsbroker.vdsbroker.=
<wbr />FullListVDSCommand] (ForkJoinPool-1-worker-4) [] FINISH, FullLis=
tVDSCommand, return: [{acpiEnable=3Dtrue, emulatedMachine=3Dpc-i440fx-<=
wbr />rhel7.3.0, afterMigrationStatus=3D, tabletEnable=3Dtrue, pid=3D18=
748, guestDiskMapping=3D{}, transparentHugePages=3Dtrue, timeOffset=3D0=
, cpuType=3DNehalem, smp=3D2, guestNumaNodes=3D[Ljava.lang.<wbr />Objec=
t;@760085fd, custom=3D{device=5Ffbddd528-7d93-<wbr />49c6-a286-180e021c=
b274device=5F<wbr />879c93ab-4df1-435c-af02-<wbr />565039fcc254=3DVmDev=
ice:{id=3D'<wbr />VmDeviceId:{deviceId=3D'<wbr
/>879c93ab-4df1-435c-af0=
2-<wbr />565039fcc254', vmId=3D'3f57e669-5e4c-4d10-85cc-<wbr
/>d573004a=
099d'}', device=3D'unix', type=3D'CHANNEL',
bootOrder=3D'0', specParams=
=3D'[]', address=3D'{bus=3D0, controller=3D0, type=3Dvirtio-serial, por=
t=3D1}', managed=3D'false', plugged=3D'true',
readOnly=3D'false', devic=
eAlias=3D'channel0', customProperties=3D'[]', snapshotId=3D'null',
logi=
calName=3D'null', hostDevice=3D'null'},
device=5Ffbddd528-7d93-49c6-<wb=
r />a286-180e021cb274device=5F<wbr />879c93ab-4df1-435c-af02-<wbr />565=
039fcc254device=5F8945f61a-<wbr />abbe-4156-8485-<wbr />a4aa6f1908dbdev=
ice=5F017b5e59-<wbr />01c4-4aac-bf0c-b5d9557284d6=3D<wbr />VmDevice:{id=
=3D'VmDeviceId:{<wbr />deviceId=3D'017b5e59-01c4-4aac-<wbr
/>bf0c-b5d95=
57284d6', vmId=3D'3f57e669-5e4c-4d10-85cc-<wbr />d573004a099d'}',
devic=
e=3D'tablet', type=3D'UNKNOWN', bootOrder=3D'0',
specParams=3D'[]', add=
ress=3D'{bus=3D0, type=3Dusb, port=3D1}', managed=3D'false', plugged=3D=
'true', readOnly=3D'false', deviceAlias=3D'input0',
customProperties=3D=
'[]', snapshotId=3D'null', logicalName=3D'null',
hostDevice=3D'null'}, =
device=5Ffbddd528-7d93-49c6-<wbr />a286-180e021cb274=3DVmDevice:{<wbr /=
id=3D'VmDeviceId:{deviceId=3D'<wbr
/>fbddd528-7d93-49c6-a286-<wbr />18=
0e021cb274',
vmId=3D'3f57e669-5e4c-4d10-85cc-<wbr />d573004a099d'}', de=
vice=3D'ide', type=3D'CONTROLLER', bootOrder=3D'0',
specParams=3D'[]', =
address=3D'{slot=3D0x01, bus=3D0x00, domain=3D0x0000, type=3Dpci, funct=
ion=3D0x1}', managed=3D'false', plugged=3D'true',
readOnly=3D'false', d=
eviceAlias=3D'ide', customProperties=3D'[]', snapshotId=3D'null',
logic=
alName=3D'null', hostDevice=3D'null'},
device=5Ffbddd528-7d93-49c6-<wbr=
/>a286-180e021cb274device=5F<wbr />879c93ab-4df1-435c-af02-<wbr />5650=
39fcc254device=5F8945f61a-<wbr />abbe-4156-8485-a4aa6f1908db=3D<wbr />V=
mDevice:{id=3D'VmDeviceId:{<wbr />deviceId=3D'8945f61a-abbe-4156-<wbr /=
8485-a4aa6f1908db', vmId=3D'3f57e669-5e4c-4d10-85cc-<wbr
/>d573004a099=
d'}', device=3D'unix', type=3D'CHANNEL',
bootOrder=3D'0', specParams=3D=
'[]', address=3D'{bus=3D0, controller=3D0, type=3Dvirtio-serial, port=3D=
2}', managed=3D'false', plugged=3D'true', readOnly=3D'false',
deviceAli=
as=3D'channel1', customProperties=3D'[]', snapshotId=3D'null',
logicalN=
ame=3D'null', hostDevice=3D'null'}}, vmType=3Dkvm, memSize=3D8192, smpC=
oresPerSocket=3D1, vmName=3DOracle=5FSECONDARY, nice=3D0, status=3DUp, =
maxMemSize=3D32768, bootMenuEnable=3Dfalse, vmId=3D3f57e669-5e4c-4d10-8=
5cc-<wbr />d573004a099d, numOfIoThreads=3D2, smpThreadsPerCore=3D1, sma=
rtcardEnable=3Dfalse, maxMemSlots=3D16, kvmEnable=3Dtrue, pitReinjectio=
n=3Dfalse, displayNetwork=3Dovirtmgmt, devices=3D[Ljava.lang.Object;@<w=
br />2e4d3dd3, memGuaranteedSize=3D8192, maxVCpus=3D16, clientIp=3D, st=
atusTime=3D<a value=3D"+14304259600" target=3D"=5Fblank"
href=3D"tel:(4=
30)%20425-9600">4304259600</a>, display=3Dvnc}], log id: 7cc65298<br
/>=
2018-02-12 16:46:42,257+01 INFO [org.ovirt.engine.core.<wbr />vds=
broker.monitoring.<wbr />VmDevicesMonitoring] (ForkJoinPool-1-worker-4)=
[] Received a vnc Device without an address when processing VM 3f57e66=
9-5e4c-4d10-85cc-<wbr />d573004a099d devices, skipping device: {device=3D=
vnc, specParams=3D{displayNetwork=3D<wbr />ovirtmgmt, keyMap=3Dfr, disp=
layIp=3D192.168.0.5}, type=3Dgraphics, deviceId=3D813957b1-446a-4e88-<w=
br />9e40-9fe76d2c442d, port=3D5901}<br />2018-02-12 16:46:42,257+01 IN=
FO [org.ovirt.engine.core.<wbr />vdsbroker.monitoring.<wbr />VmDe=
vicesMonitoring] (ForkJoinPool-1-worker-4) [] Received a lease Device w=
ithout an address when processing VM 3f57e669-5e4c-4d10-85cc-<wbr />d57=
3004a099d devices, skipping device: {lease=5Fid=3D3f57e669-5e4c-4d10-<w=
br />85cc-d573004a099d, sd=5Fid=3D1e51cecc-eb2e-47d0-b185-<wbr />920fdc=
7afa16, deviceId=3D{uuid=3Da09949aa-5642-<wbr />4b6d-94a4-8b0d04257be5}=
, offset=3D6291456, device=3Dlease, path=3D/rhev/data-center/mnt/<wbr /=
glusterSD/192.168.0.6:=5FDATA01/<wbr
/>1e51cecc-eb2e-47d0-b185-<wbr />=
920fdc7afa16/dom=5Fmd/xleases,
type=3Dlease}<br />2018-02-12 16:46:46,2=
60+01 INFO [org.ovirt.engine.core.<wbr />vdsbroker.vdsbroker.<wbr=
/>FullListVDSCommand] (DefaultQuartzScheduler5) [7fcb200a] FINISH, Ful=
lListVDSCommand, return: [{acpiEnable=3Dtrue, emulatedMachine=3Dpc-i440=
fx-<wbr />rhel7.3.0, afterMigrationStatus=3D, tabletEnable=3Dtrue, pid=3D=
18748, guestDiskMapping=3D{0QEMU=5FQEMU=5F<wbr />HARDDISK=5Fd890fa68-fb=
a4-4f49-9=3D<wbr />{name=3D/dev/sda}, QEMU=5FDVD-ROM=5FQM00003=3D{name=3D=
/<wbr />dev/sr0}}, transparentHugePages=3Dtrue, timeOffset=3D0, cpuType=
=3DNehalem, smp=3D2, guestNumaNodes=3D[Ljava.lang.<wbr />Object;@77951f=
af, custom=3D{device=5Ffbddd528-7d93-<wbr />49c6-a286-180e021cb274devic=
e=5F<wbr />879c93ab-4df1-435c-af02-<wbr />565039fcc254=3DVmDevice:{id=3D=
'<wbr />VmDeviceId:{deviceId=3D'<wbr />879c93ab-4df1-435c-af02-<wbr
/>5=
65039fcc254', vmId=3D'3f57e669-5e4c-4d10-85cc-<wbr
/>d573004a099d'}', d=
evice=3D'unix', type=3D'CHANNEL', bootOrder=3D'0',
specParams=3D'[]', a=
ddress=3D'{bus=3D0, controller=3D0, type=3Dvirtio-serial, port=3D1}', m=
anaged=3D'false', plugged=3D'true', readOnly=3D'false',
deviceAlias=3D'=
channel0', customProperties=3D'[]', snapshotId=3D'null',
logicalName=3D=
'null', hostDevice=3D'null'}, device=5Ffbddd528-7d93-49c6-<wbr
/>a286-1=
80e021cb274device=5F<wbr />879c93ab-4df1-435c-af02-<wbr />565039fcc254d=
evice=5F8945f61a-<wbr />abbe-4156-8485-<wbr />a4aa6f1908dbdevice=5F017b=
5e59-<wbr />01c4-4aac-bf0c-b5d9557284d6=3D<wbr />VmDevice:{id=3D'VmDevi=
ceId:{<wbr />deviceId=3D'017b5e59-01c4-4aac-<wbr />bf0c-b5d9557284d6',
=
vmId=3D'3f57e669-5e4c-4d10-85cc-<wbr />d573004a099d'}',
device=3D'table=
t', type=3D'UNKNOWN', bootOrder=3D'0', specParams=3D'[]',
address=3D'{b=
us=3D0, type=3Dusb, port=3D1}', managed=3D'false', plugged=3D'true',
re=
adOnly=3D'false', deviceAlias=3D'input0', customProperties=3D'[]',
snap=
shotId=3D'null', logicalName=3D'null', hostDevice=3D'null'},
device=5Ff=
bddd528-7d93-49c6-<wbr />a286-180e021cb274=3DVmDevice:{<wbr />id=3D'VmD=
eviceId:{deviceId=3D'<wbr />fbddd528-7d93-49c6-a286-<wbr />180e021cb274=
', vmId=3D'3f57e669-5e4c-4d10-85cc-<wbr />d573004a099d'}',
device=3D'id=
e', type=3D'CONTROLLER', bootOrder=3D'0', specParams=3D'[]',
address=3D=
'{slot=3D0x01, bus=3D0x00, domain=3D0x0000, type=3Dpci, function=3D0x1}=
', managed=3D'false', plugged=3D'true', readOnly=3D'false',
deviceAlias=
=3D'ide', customProperties=3D'[]', snapshotId=3D'null',
logicalName=3D'=
null', hostDevice=3D'null'}, device=5Ffbddd528-7d93-49c6-<wbr
/>a286-18=
0e021cb274device=5F<wbr />879c93ab-4df1-435c-af02-<wbr />565039fcc254de=
vice=5F8945f61a-<wbr />abbe-4156-8485-a4aa6f1908db=3D<wbr />VmDevice:{i=
d=3D'VmDeviceId:{<wbr />deviceId=3D'8945f61a-abbe-4156-<wbr
/>8485-a4aa=
6f1908db', vmId=3D'3f57e669-5e4c-4d10-85cc-<wbr />d573004a099d'}',
devi=
ce=3D'unix', type=3D'CHANNEL', bootOrder=3D'0',
specParams=3D'[]', addr=
ess=3D'{bus=3D0, controller=3D0, type=3Dvirtio-serial, port=3D2}', mana=
ged=3D'false', plugged=3D'true', readOnly=3D'false',
deviceAlias=3D'cha=
nnel1', customProperties=3D'[]', snapshotId=3D'null',
logicalName=3D'nu=
ll', hostDevice=3D'null'}}, vmType=3Dkvm, memSize=3D8192, smpCoresPerSo=
cket=3D1, vmName=3DOracle=5FSECONDARY, nice=3D0, status=3DUp, maxMemSiz=
e=3D32768, bootMenuEnable=3Dfalse, vmId=3D3f57e669-5e4c-4d10-85cc-<wbr =
/>d573004a099d, numOfIoThreads=3D2, smpThreadsPerCore=3D1, smartcardEna=
ble=3Dfalse, maxMemSlots=3D16, kvmEnable=3Dtrue, pitReinjection=3Dfalse=
, displayNetwork=3Dovirtmgmt, devices=3D[Ljava.lang.Object;@<wbr />2864=
10fd, memGuaranteedSize=3D8192, maxVCpus=3D16, clientIp=3D, statusTime=3D=
<a value=3D"+14304263620" target=3D"=5Fblank"
href=3D"tel:(430)%20426-3=
620">4304263620</a>, display=3Dvnc}], log id: 58cdef4c<br />2018-02-12
=
16:46:46,267+01 INFO [org.ovirt.engine.core.<wbr />vdsbroker.moni=
toring.<wbr />VmDevicesMonitoring] (DefaultQuartzScheduler5) [7fcb200a]=
Received a vnc Device without an address when processing VM 3f57e669-5=
e4c-4d10-85cc-<wbr />d573004a099d devices, skipping device: {device=3Dv=
nc, specParams=3D{displayNetwork=3D<wbr />ovirtmgmt, keyMap=3Dfr, displ=
ayIp=3D192.168.0.5}, type=3Dgraphics, deviceId=3D813957b1-446a-4e88-<wb=
r />9e40-9fe76d2c442d, port=3D5901}<br />2018-02-12 16:46:46,268+01 INF=
O [org.ovirt.engine.core.<wbr />vdsbroker.monitoring.<wbr />VmDev=
icesMonitoring] (DefaultQuartzScheduler5) [7fcb200a] Received a lease D=
evice without an address when processing VM 3f57e669-5e4c-4d10-85cc-<wb=
r />d573004a099d devices, skipping device: {lease=5Fid=3D3f57e669-5e4c-=
4d10-<wbr />85cc-d573004a099d, sd=5Fid=3D1e51cecc-eb2e-47d0-b185-<wbr /=
920fdc7afa16, deviceId=3D{uuid=3Da09949aa-5642-<wbr
/>4b6d-94a4-8b0d04=
257be5}, offset=3D6291456, device=3Dlease,
path=3D/rhev/data-center/mnt=
/<wbr />glusterSD/192.168.0.6:=5FDATA01/<wbr />1e51cecc-eb2e-47d0-b185-=
<wbr />920fdc7afa16/dom=5Fmd/xleases,
type=3Dlease}<p> </p></block=
quote><br />For the VM with 2 vdisks we see
:<blockquote><p>2018-02-12 =
16:49:06,112+01 INFO [org.ovirt.engine.core.bll.<wbr />MigrateVmT=
oServerCommand] (default task-50) [92b5af33-cb87-4142-b8fe-<wbr />8b838=
dd7458e] Lock Acquired to object 'EngineLock:{exclusiveLocks=3D'[<wbr /=
f7d4ec12-627a-4b83-b59e-<wbr />886400d55474=3DVM]',
sharedLocks=3D''}'=
<br />2018-02-12 16:49:06,407+01 INFO
[org.ovirt.engine.core.bll.=
<wbr />MigrateVmToServerCommand] (org.ovirt.thread.pool-6-<wbr />thread=
-49) [92b5af33-cb87-4142-b8fe-<wbr />8b838dd7458e] Running command: Mig=
rateVmToServerCommand internal: false. Entities affected : ID: f7=
d4ec12-627a-4b83-b59e-<wbr />886400d55474 Type: VMAction group MIGRATE=5F=
VM with role type USER<br />2018-02-12 16:49:06,712+01 INFO [org.=
ovirt.engine.core.<wbr />vdsbroker.MigrateVDSCommand] (org.ovirt.thread=
.pool-6-<wbr />thread-49) [92b5af33-cb87-4142-b8fe-<wbr />8b838dd7458e]=
START, MigrateVDSCommand( MigrateVDSCommandParameters:{<wbr />runAsync=
=3D'true', hostId=3D'd569c2dd-8f30-4878-<wbr />8aea-858db285cf69',
vmId=
=3D'f7d4ec12-627a-4b83-b59e-<wbr />886400d55474',
srcHost=3D'192.168.0.=
5', dstVdsId=3D'ce3938b1-b23f-4d22-<wbr />840a-f17d7cd87bb1',
dstHost=3D=
'<a target=3D"=5Fblank"
href=3D"http://192.168.0.6:54321">192.168.0.6:5=
4321</a>', migrationMethod=3D'ONLINE',
tunnelMigration=3D'false', migra=
tionDowntime=3D'0', autoConverge=3D'true',
migrateCompressed=3D'false',=
consoleAddress=3D'null', maxBandwidth=3D'500',
enableGuestEvents=3D'tr=
ue', maxIncomingMigrations=3D'2', maxOutgoingMigrations=3D'2',
converge=
nceSchedule=3D'[init=3D[{<wbr />name=3DsetDowntime, params=3D[100]}], s=
talling=3D[{limit=3D1, action=3D{name=3DsetDowntime, params=3D[150]}}, =
{limit=3D2, action=3D{name=3DsetDowntime, params=3D[200]}}, {limit=3D3,=
action=3D{name=3DsetDowntime, params=3D[300]}}, {limit=3D4, action=3D{=
name=3DsetDowntime, params=3D[400]}}, {limit=3D6, action=3D{name=3DsetD=
owntime, params=3D[500]}}, {limit=3D-1, action=3D{name=3Dabort, params=3D=
[]}}]]'}), log id: 3702a9e0<br />2018-02-12 16:49:06,713+01 INFO =
[org.ovirt.engine.core.<wbr />vdsbroker.vdsbroker.<wbr />MigrateBrokerV=
DSCommand] (org.ovirt.thread.pool-6-<wbr />thread-49) [92b5af33-cb87-41=
42-b8fe-<wbr />8b838dd7458e] START, MigrateBrokerVDSCommand(<wbr />Host=
Name =3D <a target=3D"=5Fblank"
href=3D"http://ginger.local.systea.fr">=
ginger.local.systea.fr</a>, MigrateVDSCommandParameters:{<wbr />runAsyn=
c=3D'true', hostId=3D'd569c2dd-8f30-4878-<wbr />8aea-858db285cf69',
vmI=
d=3D'f7d4ec12-627a-4b83-b59e-<wbr />886400d55474',
srcHost=3D'192.168.0=
.5', dstVdsId=3D'ce3938b1-b23f-4d22-<wbr />840a-f17d7cd87bb1',
dstHost=3D=
'<a target=3D"=5Fblank"
href=3D"http://192.168.0.6:54321">192.168.0.6:5=
4321</a>', migrationMethod=3D'ONLINE',
tunnelMigration=3D'false', migra=
tionDowntime=3D'0', autoConverge=3D'true',
migrateCompressed=3D'false',=
consoleAddress=3D'null', maxBandwidth=3D'500',
enableGuestEvents=3D'tr=
ue', maxIncomingMigrations=3D'2', maxOutgoingMigrations=3D'2',
converge=
nceSchedule=3D'[init=3D[{<wbr />name=3DsetDowntime, params=3D[100]}], s=
talling=3D[{limit=3D1, action=3D{name=3DsetDowntime, params=3D[150]}}, =
{limit=3D2, action=3D{name=3DsetDowntime, params=3D[200]}}, {limit=3D3,=
action=3D{name=3DsetDowntime, params=3D[300]}}, {limit=3D4, action=3D{=
name=3DsetDowntime, params=3D[400]}}, {limit=3D6, action=3D{name=3DsetD=
owntime, params=3D[500]}}, {limit=3D-1, action=3D{name=3Dabort, params=3D=
[]}}]]'}), log id: 1840069c<br />2018-02-12 16:49:06,724+01 INFO =
[org.ovirt.engine.core.<wbr />vdsbroker.vdsbroker.<wbr />MigrateBrokerV=
DSCommand] (org.ovirt.thread.pool-6-<wbr />thread-49) [92b5af33-cb87-41=
42-b8fe-<wbr />8b838dd7458e] FINISH, MigrateBrokerVDSCommand, log id: 1=
840069c<br />2018-02-12 16:49:06,732+01 INFO [org.ovirt.engine.co=
re.<wbr />vdsbroker.MigrateVDSCommand] (org.ovirt.thread.pool-6-<wbr />=
thread-49) [92b5af33-cb87-4142-b8fe-<wbr />8b838dd7458e] FINISH, Migrat=
eVDSCommand, return: MigratingFrom, log id: 3702a9e0<br />2018-02-12 16=
:49:06,753+01 INFO [org.ovirt.engine.core.dal.<wbr />dbbroker.aud=
itloghandling.<wbr />AuditLogDirector] (org.ovirt.thread.pool-6-<wbr />=
thread-49) [92b5af33-cb87-4142-b8fe-<wbr />8b838dd7458e] EVENT=5FID: VM=
=5FMIGRATION=5FSTART(62), Correlation ID: 92b5af33-cb87-4142-b8fe-<wbr =
/>8b838dd7458e, Job ID: f4f54054-f7c8-4481-8eda-<wbr />d5a15c383061, Ca=
ll Stack: null, Custom ID: null, Custom Event ID: -1, Message: Migratio=
n started (VM: Oracle=5FPRIMARY, Source: <a target=3D"=5Fblank" href=3D=
"http://ginger.local.systea.fr">ginger.local.systea.fr</a>,
Destination=
: <a target=3D"=5Fblank"
href=3D"http://victor.local.systea.fr">victor.=
local.systea.fr</a>, User: admin(a)internal-authz).<br />...<br
/>2018-02=
-12 16:49:16,453+01 INFO [org.ovirt.engine.core.<wbr />vdsbroker.=
monitoring.<wbr />VmsStatisticsFetcher] (DefaultQuartzScheduler4) [162a=
5bc3] Fetched 2 VMs from VDS 'ce3938b1-b23f-4d22-840a-<wbr />f17d7cd87b=
b1'<br />2018-02-12 16:49:16,455+01 INFO [org.ovirt.engine.core.<=
wbr />vdsbroker.monitoring.<wbr />VmAnalyzer] (DefaultQuartzScheduler4)=
[162a5bc3] VM 'f7d4ec12-627a-4b83-b59e-<wbr />886400d55474'(Oracle=5FP=
RIMARY) was unexpectedly detected as 'MigratingTo' on VDS 'ce3938b1-b23=
f-4d22-840a-<wbr />f17d7cd87bb1'(<a target=3D"=5Fblank"
href=3D"http://=
victor.local.systea.fr">victor.local.<wbr />systea.fr</a>) (expected
on=
'd569c2dd-8f30-4878-8aea-<wbr />858db285cf69')<br />2018-02-12
16:49:1=
6,455+01 INFO [org.ovirt.engine.core.<wbr />vdsbroker.monitoring.=
<wbr />VmAnalyzer] (DefaultQuartzScheduler4) [162a5bc3] VM 'f7d4ec12-62=
7a-4b83-b59e-<wbr />886400d55474' is migrating to VDS 'ce3938b1-b23f-4d=
22-840a-<wbr />f17d7cd87bb1'(<a target=3D"=5Fblank"
href=3D"http://vict=
or.local.systea.fr">victor.local.<wbr />systea.fr</a>) ignoring it in
t=
he refresh until migration is done<br />...<br />2018-02-12 16:49:31,48=
4+01 INFO [org.ovirt.engine.core.<wbr />vdsbroker.monitoring.<wbr=
/>VmAnalyzer] (DefaultQuartzScheduler5) [11a7619a] VM 'f7d4ec12-627a-4=
b83-b59e-<wbr />886400d55474'(Oracle=5FPRIMARY) was unexpectedly detect=
ed as 'MigratingTo' on VDS 'ce3938b1-b23f-4d22-840a-<wbr
/>f17d7cd87bb1=
'(<a target=3D"=5Fblank"
href=3D"http://victor.local.systea.fr">victor.=
local.<wbr />systea.fr</a>) (expected on 'd569c2dd-8f30-4878-8aea-<wbr
=
/>858db285cf69')<br />2018-02-12 16:49:31,484+01 INFO [org.ovirt.=
engine.core.<wbr />vdsbroker.monitoring.<wbr />VmAnalyzer] (DefaultQuar=
tzScheduler5) [11a7619a] VM 'f7d4ec12-627a-4b83-b59e-<wbr />886400d5547=
4' is migrating to VDS 'ce3938b1-b23f-4d22-840a-<wbr
/>f17d7cd87bb1'(<a=
target=3D"=5Fblank"
href=3D"http://victor.local.systea.fr">victor.loca=
l.<wbr />systea.fr</a>) ignoring it in the refresh until migration is d=
one<br /> </p></blockquote><br />and so on, last lines
repeated in=
definitly for hours since we poweroff the VM...<br />Is this something =
known ? Any idea about that ?<br /><br />Thanks<br /><br />Ovirt
4.1.6,=
updated last at feb-13. Gluster 3.12.1.<br /><br />--<p
class=3D"m=5F8=
587729722327689770Text1">Cordialement,<br /><br /><b>Frank Soyer
</b></=
p><br />=5F=5F=5F=5F=5F=5F=5F=5F=5F=5F=5F=5F=5F=5F=5F=5F=5F=5F=5F=5F=5F=
=5F=5F=5F=5F=5F=5F=5F=5F=5F<wbr />=5F=5F=5F=5F=5F=5F=5F=5F=5F=5F=5F=5F=5F=
=5F=5F=5F=5F<br />Users mailing list<br /><a
href=3D"mailto:Users@ovirt=
.org">Users(a)ovirt.org</a><br /><a rel=3D"noreferrer"
target=3D"=5Fblank=
"
href=3D"http://lists.ovirt.org/mailman/listinfo/users">http:...
virt.org/<wbr />mailman/listinfo/users</a><br
/> </blockquote></di=
v></div></blockquote><br /> </html>
------=_=-_OpenGroupware_org_NGMime-18019-1518771817.081391-1--------