Attachment 1947287 Details for Bug 2174482 – 1:qemu log during migration failure

1:qemu log during migration failure

qemu-out.log (text/plain), 8.29 KB, created by smitterl on 2023-03-01 18:28:37 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: smitterl

Created: 2023-03-01 18:28:37 UTC

Size: 8.29 KB

patch

obsolete

>2023-03-01 17:16:32.178+0000: 24875: info : libvirt version: 9.0.0, package: 7.el9 (Red Hat, Inc. <http://bugzilla.redhat.com/bugzilla>, 2023-02-22-11:12:08, )
>2023-03-01 17:16:32.178+0000: 24875: info : hostname: s390x-kvm-virtqez5.lab.eng.rdu2.redhat.com
>2023-03-01 17:16:32.178+0000: 24875: debug : qemuMigrationSrcStoreDomainState:217 : Storing pre-migration state=1 domain=0x3ff402be820
>2023-03-01 17:16:32.178+0000: 24875: debug : qemuDomainObjEnterMonitorInternal:6122 : Entering monitor (mon=0x3ff400962f0 vm=0x3ff402be820 name=rhel9)
>2023-03-01 17:16:32.178+0000: 24875: debug : qemuMonitorGetBlockInfo:1924 : mon:0x3ff400962f0 vm:0x3ff402be820 fd:26
>2023-03-01 17:16:32.178+0000: 24875: info : qemuMonitorSend:861 : QEMU_MONITOR_SEND_MSG: mon=0x3ff400962f0 msg={"execute":"query-block","id":"libvirt-16"}
> fd=-1
>2023-03-01 17:16:32.178+0000: 25004: info : qemuMonitorIOWrite:366 : QEMU_MONITOR_IO_WRITE: mon=0x3ff400962f0 buf={"execute":"query-block","id":"libvirt-16"}
> len=45 ret=45 errno=0
>2023-03-01 17:16:32.179+0000: 25004: debug : qemuMonitorJSONIOProcessLine:189 : Line [{"return": [{"io-status": "ok", "device": "", "locked": false, "removable": false, "inserted": {"iops_rd": 0, "detect_zeroes": "off", "image": {"virtual-size": 5368709120, "filename": "/var/lib/libvirt/migrate/RHEL-9.2-s390x-latest.qcow2", "cluster-size": 65536, "format": "qcow2", "actual-size": 1451438080, "format-specific": {"type": "qcow2", "data": {"compat": "1.1", "compression-type": "zlib", "lazy-refcounts": true, "refcount-bits": 16, "corrupt": false, "extended-l2": false}}, "dirty-flag": false}, "iops_wr": 0, "ro": false, "node-name": "libvirt-1-format", "backing_file_depth": 0, "drv": "qcow2", "iops": 0, "bps_wr": 0, "write_threshold": 0, "encrypted": false, "bps": 0, "bps_rd": 0, "cache": {"no-flush": false, "direct": false, "writeback": true}, "file": "/var/lib/libvirt/migrate/RHEL-9.2-s390x-latest.qcow2"}, "qdev": "/machine/peripheral/virtio-disk0/virtio-backend", "type": "unknown"}], "id": "libvirt-16"}]
>2023-03-01 17:16:32.179+0000: 25004: info : qemuMonitorJSONIOProcessLine:208 : QEMU_MONITOR_RECV_REPLY: mon=0x3ff400962f0 reply={"return": [{"io-status": "ok", "device": "", "locked": false, "removable": false, "inserted": {"iops_rd": 0, "detect_zeroes": "off", "image": {"virtual-size": 5368709120, "filename": "/var/lib/libvirt/migrate/RHEL-9.2-s390x-latest.qcow2", "cluster-size": 65536, "format": "qcow2", "actual-size": 1451438080, "format-specific": {"type": "qcow2", "data": {"compat": "1.1", "compression-type": "zlib", "lazy-refcounts": true, "refcount-bits": 16, "corrupt": false, "extended-l2": false}}, "dirty-flag": false}, "iops_wr": 0, "ro": false, "node-name": "libvirt-1-format", "backing_file_depth": 0, "drv": "qcow2", "iops": 0, "bps_wr": 0, "write_threshold": 0, "encrypted": false, "bps": 0, "bps_rd": 0, "cache": {"no-flush": false, "direct": false, "writeback": true}, "file": "/var/lib/libvirt/migrate/RHEL-9.2-s390x-latest.qcow2"}, "qdev": "/machine/peripheral/virtio-disk0/virtio-backend", "type": "unknown"}], "id": "libvirt-16"}
>2023-03-01 17:16:32.179+0000: 24875: debug : qemuDomainObjExitMonitor:6151 : Exited monitor (mon=0x3ff400962f0 vm=0x3ff402be820 name=rhel9)
>2023-03-01 17:16:32.179+0000: 24875: debug : qemuMigrationSrcBeginPhase:2544 : driver=0x3ff400242f0, vm=0x3ff402be820, xmlin=<null>, dname=<null>, cookieout=0x3ff8dcfe790, cookieoutlen=0x3ff8dcfe784, nmigrate_disks=0, migrate_disks=(nil), flags=0x101
>2023-03-01 17:16:32.179+0000: 24875: debug : qemuDomainObjStartJobPhase:607 : Starting phase 'begin3' of 'migration out' job
>2023-03-01 17:16:32.179+0000: 24875: debug : qemuDomainObjSetJobPhase:577 : Setting 'migration out' phase to 'begin3'
>2023-03-01 17:16:32.179+0000: 24875: debug : qemuDomainObjEnterMonitorInternal:6122 : Entering monitor (mon=0x3ff400962f0 vm=0x3ff402be820 name=rhel9)
>2023-03-01 17:16:32.179+0000: 24875: debug : qemuMonitorGetMigrationBlockers:4319 : blockers=0x3ff8dcfe150
>2023-03-01 17:16:32.179+0000: 24875: debug : qemuMonitorGetMigrationBlockers:4321 : mon:0x3ff400962f0 vm:0x3ff402be820 fd:26
>2023-03-01 17:16:32.179+0000: 24875: info : qemuMonitorSend:861 : QEMU_MONITOR_SEND_MSG: mon=0x3ff400962f0 msg={"execute":"query-migrate","id":"libvirt-17"}
> fd=-1
>2023-03-01 17:16:32.179+0000: 25004: info : qemuMonitorIOWrite:366 : QEMU_MONITOR_IO_WRITE: mon=0x3ff400962f0 buf={"execute":"query-migrate","id":"libvirt-17"}
> len=47 ret=47 errno=0
>2023-03-01 17:16:32.179+0000: 25004: debug : qemuMonitorJSONIOProcessLine:189 : Line [{"return": {}, "id": "libvirt-17"}]
>2023-03-01 17:16:32.179+0000: 25004: info : qemuMonitorJSONIOProcessLine:208 : QEMU_MONITOR_RECV_REPLY: mon=0x3ff400962f0 reply={"return": {}, "id": "libvirt-17"}
>2023-03-01 17:16:32.179+0000: 24875: debug : qemuDomainObjExitMonitor:6151 : Exited monitor (mon=0x3ff400962f0 vm=0x3ff402be820 name=rhel9)
>2023-03-01 17:16:32.179+0000: 24875: error : virCPUCheckFeature:701 : this function is not supported by the connection driver: cannot check guest CPU feature for s390x architecture
>2023-03-01 17:16:32.179+0000: 24875: debug : qemuMigrationSrcIsSafe:1642 : QEMU supports flushing caches; migration is safe
>2023-03-01 17:16:32.179+0000: 24875: debug : qemuMigrationCookieFormat:1485 : cookielen=758 cookie=<qemu-migration>
>  <name>rhel9</name>
>  <uuid>5b46f4dc-e475-476f-8fb0-f3a0789d9de6</uuid>
>  <hostname>s390x-kvm-virtqez5.lab.eng.rdu2.redhat.com</hostname>
>  <hostuuid>60cedf1a-ed09-494e-ace3-183bb756374a</hostuuid>
>  <feature name='lockstate'/>
>  <capabilities>
>    <cap name='xbzrle' auto='no'/>
>    <cap name='auto-converge' auto='no'/>
>    <cap name='rdma-pin-all' auto='no'/>
>    <cap name='postcopy-ram' auto='no'/>
>    <cap name='compress' auto='no'/>
>    <cap name='pause-before-switchover' auto='yes'/>
>    <cap name='late-block-activate' auto='no'/>
>    <cap name='multifd' auto='no'/>
>    <cap name='dirty-bitmaps' auto='no'/>
>    <cap name='return-path' auto='no'/>
>    <cap name='zero-copy-send' auto='no'/>
>  </capabilities>
></qemu-migration>
>
>2023-03-01 17:16:32.180+0000: 24875: debug : qemuDomainAssignVirtioSerialAddresses:138 : Finished reserving existing ports
>2023-03-01 17:16:32.180+0000: 24875: debug : qemuDomainUSBAddressAddHubs:3010 : Found 0 USB devices and 0 provided USB ports; adding 0 hubs
>2023-03-01 17:16:32.180+0000: 24875: debug : qemuDomainAssignUSBAddresses:3191 : Existing USB addresses have been reserved
>2023-03-01 17:16:32.180+0000: 24875: debug : qemuDomainAssignUSBAddresses:3199 : Finished assigning USB ports
>2023-03-01 17:16:32.180+0000: 24875: debug : qemuDomainDefFormatBufInternal:6405 : Removing default pci-root from domain 'rhel9' for migration compatibility
>2023-03-01 17:16:32.180+0000: 24875: debug : qemuDomainCleanupAdd:7400 : vm=rhel9, cb=0x3ff8c33c4f0
>2023-03-01 17:16:32.180+0000: 24875: debug : qemuDomainObjReleaseAsyncJob:647 : Releasing ownership of 'migration out' async job
>2023-03-01 17:16:33.628+0000: 24879: debug : qemuMigrationSrcConfirm:4011 : vm=0x3ff402be820, flags=0x101, cancelled=1
>2023-03-01 17:16:33.628+0000: 24879: debug : qemuDomainObjStartJobPhase:607 : Starting phase 'confirm3_cancelled' of 'migration out' job
>2023-03-01 17:16:33.628+0000: 24879: debug : qemuDomainObjSetJobPhase:577 : Setting 'migration out' phase to 'confirm3_cancelled'
>2023-03-01 17:16:33.628+0000: 24879: debug : qemuDomainCleanupRemove:7421 : vm=rhel9, cb=0x3ff8c33c4f0
>2023-03-01 17:16:33.628+0000: 24879: debug : qemuMigrationSrcConfirmPhase:3909 : driver=0x3ff400242f0, vm=0x3ff402be820, cookiein=<null>, cookieinlen=0, flags=0x101, retcode=1
>2023-03-01 17:16:33.628+0000: 24879: debug : qemuDomainObjStartJobPhase:607 : Starting phase 'confirm3_cancelled' of 'migration out' job
>2023-03-01 17:16:33.628+0000: 24879: debug : qemuDomainObjSetJobPhase:577 : Setting 'migration out' phase to 'confirm3_cancelled'
>2023-03-01 17:16:33.629+0000: 24879: debug : qemuMigrationCookieParse:1511 : cookielen=0 cookie='<null>'
>2023-03-01 17:16:33.629+0000: 24879: debug : qemuMigrationSrcNBDCopyCancel:876 : Cancelling drive mirrors for domain rhel9
>2023-03-01 17:16:33.629+0000: 24879: debug : qemuMigrationSrcNBDCopyCancelled:801 : All disk mirrors are gone
>2023-03-01 17:16:33.629+0000: 24879: debug : qemuMigrationSrcRestoreDomainState:230 : driver=0x3ff400242f0, vm=0x3ff402be820, pre-mig-state=running, state=running, reason=booted
>2023-03-01 17:16:33.629+0000: 24879: debug : qemuMigrationParamsReset:1291 : Resetting migration parameters (nil), flags 0x101

Actions: View

Attachments on bug 2174482: 1947287