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 tousers-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/GGMEGFI4OGZ5QR...