source machine is clear, but the destination host (the updated)

Couldn't destroy incoming VM: Domain not found: no domain with matching uuid '77f85710-45e7-43ca

-b0f4-69f87766cc43' (vm:4054)

2023-12-14 12:35:44,492+0100 INFO  (libvirt/events) [virt.vm] (vmId='77f85710-45e7-43ca-b0f4-69f87766cc43') underlying process disconnected (vm:1144)
2023-12-14 12:35:44,492+0100 INFO  (libvirt/events) [virt.vm] (vmId='77f85710-45e7-43ca-b0f4-69f87766cc43') Release VM resources (vm:5331)
2023-12-14 12:35:44,492+0100 INFO  (libvirt/events) [virt.vm] (vmId='77f85710-45e7-43ca-b0f4-69f87766cc43') Stopping connection (guestagent:421)
2023-12-14 12:35:44,493+0100 INFO  (libvirt/events) [vdsm.api] START teardownImage(sdUUID='6cff1d5a-3188-407c-b217-ab33d7b92476', spUUID='a6ecdb7b-f2d0-44e1-856a-2cf1be90d7bf', imgUUID='b7f47420-25d7-4
27f-8925-55f0fe565606', volUUID=None) from=internal, task_id=caf7cf3f-10a7-42d9-a576-441eff750af7 (api:31)
2023-12-14 12:35:44,493+0100 INFO  (libvirt/events) [storage.storagedomain] Removing image run directory '/run/vdsm/storage/6cff1d5a-3188-407c-b217-ab33d7b92476/b7f47420-25d7-427f-8925-55f0fe565606' (b
lockSD:1373)
2023-12-14 12:35:44,493+0100 INFO  (libvirt/events) [storage.fileutils] Removing directory: /run/vdsm/storage/6cff1d5a-3188-407c-b217-ab33d7b92476/b7f47420-25d7-427f-8925-55f0fe565606 (fileUtils:195)
2023-12-14 12:35:44,528+0100 WARN  (vm/77f85710) [virt.vm] (vmId='77f85710-45e7-43ca-b0f4-69f87766cc43') Couldn't destroy incoming VM: Domain not found: no domain with matching uuid '77f85710-45e7-43ca
-b0f4-69f87766cc43' (vm:4054)
2023-12-14 12:35:44,529+0100 INFO  (vm/77f85710) [virt.vm] (vmId='77f85710-45e7-43ca-b0f4-69f87766cc43') Changed state to Down: VM destroyed during the startup (code=10) (vm:1744)
2023-12-14 12:35:44,530+0100 INFO  (vm/77f85710) [virt.vm] (vmId='77f85710-45e7-43ca-b0f4-69f87766cc43') Stopping connection (guestagent:421)
2023-12-14 12:35:44,535+0100 INFO  (jsonrpc/4) [api.virt] START destroy(gracefulAttempts=1) from=::ffff:10.36.191.25,58054, flow_id=67218183, vmId=77f85710-45e7-43ca-b0f4-69f87766cc43 (api:31)
2023-12-14 12:35:44,589+0100 INFO  (libvirt/events) [storage.lvm] Deactivating lvs: vg=6cff1d5a-3188-407c-b217-ab33d7b92476 lvs=['9102f9dd-157c-4233-ac33-004f6c11ff73'] (lvm:1850)
2023-12-14 12:35:44,702+0100 INFO  (libvirt/events) [vdsm.api] FINISH teardownImage return=None from=internal, task_id=caf7cf3f-10a7-42d9-a576-441eff750af7 (api:37)
2023-12-14 12:35:44,703+0100 INFO  (libvirt/events) [virt.vm] (vmId='77f85710-45e7-43ca-b0f4-69f87766cc43') Stopping connection (guestagent:421)
2023-12-14 12:35:44,704+0100 WARN  (libvirt/events) [root] Attempting to remove a non existing net user: ovirtmgmt/77f85710-45e7-43ca-b0f4-69f87766cc43 (libvirtnetwork:191)
2023-12-14 12:35:44,704+0100 INFO  (libvirt/events) [vdsm.api] START inappropriateDevices(thiefId='77f85710-45e7-43ca-b0f4-69f87766cc43') from=internal, task_id=5b362297-6aa7-4de3-8c49-3afaa0dc8cbe (ap
i:31)
2023-12-14 12:35:44,705+0100 INFO  (libvirt/events) [vdsm.api] FINISH inappropriateDevices return=None from=internal, task_id=5b362297-6aa7-4de3-8c49-3afaa0dc8cbe (api:37)
2023-12-14 12:35:44,706+0100 WARN  (libvirt/events) [virt.vm] (vmId='77f85710-45e7-43ca-b0f4-69f87766cc43') trying to set state to Down when already Down (vm:711)
2023-12-14 12:35:44,706+0100 INFO  (libvirt/events) [virt.vm] (vmId='77f85710-45e7-43ca-b0f4-69f87766cc43') Stopping connection (guestagent:421)
2023-12-14 12:35:44,706+0100 INFO  (jsonrpc/4) [virt.vm] (vmId='77f85710-45e7-43ca-b0f4-69f87766cc43') Can't undefine disconnected VM '77f85710-45e7-43ca-b0f4-69f87766cc43' (vm:2434)
2023-12-14 12:35:44,706+0100 INFO  (jsonrpc/4) [api.virt] FINISH destroy return={'status': {'code': 0, 'message': 'Machine destroyed'}} from=::ffff:10.36.191.25,58054, flow_id=67218183, vmId=77f85710-4
5e7-43ca-b0f4-69f87766cc43 (api:37)


When I tried to migrate it to the different host, it works. Unfortunetely no VM can be migrated to the affected host.

then i stopped vmId='77f85710-45e7-43ca-b0f4-69f87766cc43' and started it and it stared on affected host without any issue.


BUT when i tried to migrate out of the affected host and it fails as well, and there is the error message

2023-12-14 12:55:41,444+0100 INFO  (libvirt/events) [virt.vm] (vmId='77f85710-45e7-43ca-b0f4-69f87766cc43') CPU running: onResume (vm:6073)
2023-12-14 12:55:41,472+0100 ERROR (migsrc/77f85710) [virt.vm] (vmId='77f85710-45e7-43ca-b0f4-69f87766cc43') internal error: qemu unexpectedly closed the monitor: 2023-12-14T11:55:36.379991Z qemu-kvm:
-numa node,nodeid=0,cpus=0-15,mem=1024: warning: Parameter -numa node,mem is deprecated, use -numa node,memdev instead
2023-12-14T11:55:37.613045Z qemu-kvm: Missing section footer for 0000:00:01.3/piix4_pm
2023-12-14T11:55:37.613162Z qemu-kvm: load of migration failed: Invalid argument (migration:331)
2023-12-14 12:55:41,476+0100 INFO  (migsrc/77f85710) [virt.vm] (vmId='77f85710-45e7-43ca-b0f4-69f87766cc43') Switching from State.STARTED to State.FAILED (migration:229)
2023-12-14 12:55:41,476+0100 ERROR (migsrc/77f85710) [virt.vm] (vmId='77f85710-45e7-43ca-b0f4-69f87766cc43') Failed to migrate (migration:506)
Traceback (most recent call last):
  File "/usr/lib/python3.6/site-packages/vdsm/virt/migration.py", line 480, in _regular_run
    time.time(), machineParams
  File "/usr/lib/python3.6/site-packages/vdsm/virt/migration.py", line 580, in _startUnderlyingMigration
    self._perform_with_conv_schedule(duri, muri)
  File "/usr/lib/python3.6/site-packages/vdsm/virt/migration.py", line 700, in _perform_with_conv_schedule
    self._perform_migration(duri, muri)
  File "/usr/lib/python3.6/site-packages/vdsm/virt/migration.py", line 602, in _perform_migration
    self._dom.migrateToURI3(duri, params, flags)
  File "/usr/lib/python3.6/site-packages/vdsm/virt/virdomain.py", line 162, in call
    return getattr(self._vm._dom, name)(*a, **kw)
  File "/usr/lib/python3.6/site-packages/vdsm/virt/virdomain.py", line 104, in f
    ret = attr(*args, **kwargs)
  File "/usr/lib/python3.6/site-packages/vdsm/common/libvirtconnection.py", line 114, in wrapper
    ret = f(*args, **kwargs)
  File "/usr/lib/python3.6/site-packages/vdsm/common/function.py", line 78, in wrapper
    return func(inst, *args, **kwargs)
  File "/usr/lib64/python3.6/site-packages/libvirt.py", line 2126, in migrateToURI3
    raise libvirtError('virDomainMigrateToURI3() failed')
libvirt.libvirtError: internal error: qemu unexpectedly closed the monitor: 2023-12-14T11:55:36.379991Z qemu-kvm: -numa node,nodeid=0,cpus=0-15,mem=1024: warning: Parameter -numa node,mem is deprecated, use -numa node,memdev instead
2023-12-14T11:55:37.613045Z qemu-kvm: Missing section footer for 0000:00:01.3/piix4_pm
2023-12-14T11:55:37.613162Z qemu-kvm: load of migration failed: Invalid argument
2023-12-14 12:55:41,477+0100 INFO  (migsrc/77f85710) [virt.vm] (vmId='77f85710-45e7-43ca-b0f4-69f87766cc43') Enabling volume monitoring (thinp:72)
2023-12-14 12:55:41,485+0100 INFO  (jsonrpc/4) [api.virt] START getMigrationStatus() from=::ffff:10.36.191.25,58054, flow_id=59e26abb, vmId=77f85710-45e7-43ca-b0f4-69f87766cc43 (api:31)
2023-12-14 12:55:41,485+0100 INFO  (jsonrpc/4) [api.virt] FINISH getMigrationStatus return={'status': {'code': 0, 'message': 'Done'}, 'migrationStats': {'status': {'code': 12, 'message': 'Fatal error during migration'}, 'progress': 0}} from=::ffff:10.36.191.25,58054, flow_id=59e26abb, vmId=77f85710-45e7-43ca-b0f4-69f87766cc43 (api:37)

on the affected  node,

rpm -qa qemu-kvm
qemu-kvm-6.2.0-41.module_el8+690+3a5f4f4f.x86_64

and on the old nodes are

rpm -qa qemu-kvm
qemu-kvm-6.2.0-20.module_el8.7.0+1218+f626c2ff.1.x86_64

Jirka


On 14. 12. 23 11:21, Jean-Louis Dupond wrote:
Best to look in the vdsm logs on both source and destination.
Engine gives no clues :)

Thanks

On 14/12/2023 11:12, Jirka Simon wrote:

Hello there,

after today's update I have  problem with live migration to this host.  with message


2023-12-14 10:00:01,089+01 INFO  [org.ovirt.engine.core.vdsbroker.monitoring.VmAnalyzer] (ForkJoinPool-1-worker-11) [67218183] VM '77f85710-45e7-43ca-b0f4-69f87766cc43'(ca1.access.prod.hq.sldev.cz) was
unexpectedly detected as 'Down' on VDS '044b7175-ca36-49b2-b01b-0253f9af7e4f'(ovirt3.corp.sldev.cz) (expected on '858b8951-9b5a-4b8f-994e-4e11788c34d6')
2023-12-14 10:00:01,090+01 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.DestroyVDSCommand] (ForkJoinPool-1-worker-11) [67218183] START, DestroyVDSCommand(HostName = ovirt3.corp.sldev.cz, DestroyVmV
DSCommandParameters:{hostId='044b7175-ca36-49b2-b01b-0253f9af7e4f', vmId='77f85710-45e7-43ca-b0f4-69f87766cc43', secondsToWait='0', gracefully='false', reason='', ignoreNoVm='true'}), log id: 696e7f0e
2023-12-14 10:00:01,336+01 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.DestroyVDSCommand] (ForkJoinPool-1-worker-11) [67218183] FINISH, DestroyVDSCommand, return: , log id: 696e7f0e
2023-12-14 10:00:01,337+01 INFO  [org.ovirt.engine.core.vdsbroker.monitoring.VmAnalyzer] (ForkJoinPool-1-worker-11) [67218183] VM '77f85710-45e7-43ca-b0f4-69f87766cc43'(ca1.access.prod.hq.sldev.cz) was
unexpectedly detected as 'Down' on VDS '044b7175-ca36-49b2-b01b-0253f9af7e4f'(ovirt3.corp.sldev.cz) (expected on '858b8951-9b5a-4b8f-994e-4e11788c34d6')
2023-12-14 10:00:01,337+01 ERROR [org.ovirt.engine.core.vdsbroker.monitoring.VmAnalyzer] (ForkJoinPool-1-worker-11) [67218183] Migration of VM 'ca1.access.prod.hq.sldev.cz' to host 'ovirt3.corp.sldev.c
z' failed: VM destroyed during the startup.

When I stop a VM and start it again it starts on affected without any problem, but migration doesn't work.


thank you for any help.


Jirka


_______________________________________________
Users mailing list -- users@ovirt.org
To unsubscribe send an email to users-leave@ovirt.org
Privacy Statement: https://www.ovirt.org/privacy-policy.html
oVirt Code of Conduct: https://www.ovirt.org/community/about/community-guidelines/
List Archives: https://lists.ovirt.org/archives/list/users@ovirt.org/message/GGMEGFI4OGZ5QRIVE57HQZKF7V7YVNDN/