5:45 a.m.
Hi,
I have a pb when i try to snapshot a VM.
Ovirt engine self hosted 3.4. Two node (host01 and host02).
my engine.log :
2014-04-04 12:30:03,013 INFO
[org.ovirt.engine.core.bll.CreateAllSnapshotsFromVmCommand]
(org.ovirt.thread.pool-6-thread-24) Ending command successfully:
org.ovirt.engine.core.bll.CreateAllSnapshotsFromVmCommand
2014-04-04 12:30:03,028 INFO
[org.ovirt.engine.core.vdsbroker.vdsbroker.SnapshotVDSCommand]
(org.ovirt.thread.pool-6-thread-24) START, SnapshotVDSCommand(HostName =
host01, HostId = fcb9a5cf-2064-42a5-99fe-dc56ea39ed81,
vmId=cb038ccf-6c6f-475c-872f-ea812ff795a1), log id: 36463977
2014-04-04 12:30:03,075 ERROR
[org.ovirt.engine.core.vdsbroker.vdsbroker.SnapshotVDSCommand]
(org.ovirt.thread.pool-6-thread-24) Failed in SnapshotVDS method
2014-04-04 12:30:03,076 INFO
[org.ovirt.engine.core.vdsbroker.vdsbroker.SnapshotVDSCommand]
(org.ovirt.thread.pool-6-thread-24) Command
org.ovirt.engine.core.vdsbroker.vdsbroker.SnapshotVDSCommand return value
StatusOnlyReturnForXmlRpc [mStatus=StatusForXmlRpc [mCode=48,
mMessage=Snapshot failed]]
2014-04-04 12:30:03,077 INFO
[org.ovirt.engine.core.vdsbroker.vdsbroker.SnapshotVDSCommand]
(org.ovirt.thread.pool-6-thread-24) HostName = host01
2014-04-04 12:30:03,078 ERROR
[org.ovirt.engine.core.vdsbroker.vdsbroker.SnapshotVDSCommand]
(org.ovirt.thread.pool-6-thread-24) Command SnapshotVDSCommand(HostName =
host01, HostId = fcb9a5cf-2064-42a5-99fe-dc56ea39ed81,
vmId=cb038ccf-6c6f-475c-872f-ea812ff795a1) execution failed. Exception:
VDSErrorException: VDSGenericException: VDSErrorException: Failed to
SnapshotVDS, error = Snapshot failed, code = 48
2014-04-04 12:30:03,080 INFO
[org.ovirt.engine.core.vdsbroker.vdsbroker.SnapshotVDSCommand]
(org.ovirt.thread.pool-6-thread-24) FINISH, SnapshotVDSCommand, log id:
36463977
2014-04-04 12:30:03,083 WARN
[org.ovirt.engine.core.bll.CreateAllSnapshotsFromVmCommand]
(org.ovirt.thread.pool-6-thread-24) Wasnt able to live snapshot due to
error: VdcBLLException: VdcBLLException:
org.ovirt.engine.core.vdsbroker.vdsbroker.VDSErrorException:
VDSGenericException: VDSErrorException: Failed to SnapshotVDS, error =
Snapshot failed, code = 48 (Failed with error SNAPSHOT_FAILED and code 48).
VM will still be configured to the new created snapshot
2014-04-04 12:30:03,097 INFO
[org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector]
(org.ovirt.thread.pool-6-thread-24) Correlation ID: 5650b99f, Job ID:
c1b2d861-2a52-49f1-9eaa-1b63aa8b4fba, Call Stack:
org.ovirt.engine.core.common.errors.VdcBLLException: VdcBLLException:
org.ovirt.engine.core.vdsbroker.vdsbroker.VDSErrorException:
VDSGenericException: VDSErrorException: Failed to SnapshotVDS, error =
Snapshot failed, code = 48 (Failed with error SNAPSHOT_FAILED and code 48)
My /var/log/messages
Apr 4 12:30:04 host01 vdsm vm.Vm ERROR
vmId=`cb038ccf-6c6f-475c-872f-ea812ff795a1`::The base volume doesn't exist:
{'device': 'disk', 'domainID':
'5ae613a4-44e4-42cb-89fc-7b5d34c1f30f',
'volumeID': '3b6cbb5d-beed-428d-ac66-9db3dd002e2f', 'imageID':
'646df162-5c6d-44b1-bc47-b63c3fdab0e2'}
My /var/log/libvirt/libvirt.log
2014-04-04 10:40:13.886+0000: 8234: debug : qemuMonitorIOWrite:462 :
QEMU_MONITOR_IO_WRITE: mon=0x7f77ec0ccce0
buf={"execute":"query-blockstats","id":"libvirt-20842"}
len=53 ret=53 errno=11
2014-04-04 10:40:13.888+0000: 8234: debug : qemuMonitorIOProcess:354 :
QEMU_MONITOR_IO_PROCESS: mon=0x7f77ec0ccce0 buf={"return":
[{"device":
"drive-ide0-1-0", "parent": {"stats":
{"flush_total_time_ns": 0,
"wr_highest_offset": 0, "wr_total_time_ns": 0, "wr_bytes":
0,
"rd_total_time_ns": 0, "flush_operations": 0,
"wr_operations": 0,
"rd_bytes": 0, "rd_operations": 0}}, "stats":
{"flush_total_time_ns": 0,
"wr_highest_offset": 0, "wr_total_time_ns": 0, "wr_bytes":
0,
"rd_total_time_ns": 11929902, "flush_operations": 0,
"wr_operations": 0,
"rd_bytes": 135520, "rd_operations": 46}}, {"device":
"drive-virtio-disk0",
"parent": {"stats": {"flush_total_time_ns": 0,
"wr_highest_offset":
22184332800, "wr_total_time_ns": 0, "wr_bytes": 0,
"rd_total_time_ns": 0,
"flush_operations": 0, "wr_operations": 0, "rd_bytes": 0,
"rd_operations":
0}}, "stats": {"flush_total_time_ns": 34786515034,
"wr_highest_offset":
22184332800, "wr_total_time_ns": 5131205369094, "wr_bytes":
5122065408,
"rd_total_time_ns": 12987633373, "flush_operations": 285398,
"wr_operations": 401232, "rd_bytes": 392342016,
"rd_operations": 15069}}],
"id": "libvirt-20842"}
len=1021
2014-04-04 10:40:13.888+0000: 8263: debug :
qemuMonitorGetBlockStatsInfo:1478 : mon=0x7f77ec0ccce0 dev=ide0-1-0
2014-04-04 10:40:13.889+0000: 8263: debug : qemuMonitorSend:904 :
QEMU_MONITOR_SEND_MSG: mon=0x7f77ec0ccce0
msg={"execute":"query-blockstats","id":"libvirt-20843"}
/var/log/vdsm/vdsm.log
Thread-4732::DEBUG::2014-04-04
12:43:34,439::BindingXMLRPC::1067::vds::(wrapper) client
[192.168.99.104]::call vmSnapshot with
('cb038ccf-6c6f-475c-872f-ea812ff795a1', [{'baseVolumeID':
'b62232fc-4e02-41ce-ae10-5dff9e2f7bbe', 'domainID':
'5ae613a4-44e4-42cb-89fc-7b5d34c1f30f', 'volumeID':
'f5fc4fed-4acd-46e8-9980-90a9c3985840', 'imageID':
'646df162-5c6d-44b1-bc47-b63c3fdab0e2'}],
'5ae613a4-44e4-42cb-89fc-7b5d34c1f30f,00000002-0002-0002-0002-000000000076,4fb31c32-8467-4d4a-b817-977643a462e3,ceb881f3-9a46-4ebc-b82e-c4c91035f807,2c06b4da-2743-4422-ba94-74da2c709188,02804da9-34f8-438f-9e8a-9689bc94790c')
{}
Thread-4732::ERROR::2014-04-04 12:43:34,440::vm::3910::vm.Vm::(snapshot)
vmId=`cb038ccf-6c6f-475c-872f-ea812ff795a1`::The base volume doesn't exist:
{'device': 'disk', 'domainID':
'5ae613a4-44e4-42cb-89fc-7b5d34c1f30f',
'volumeID': 'b62232fc-4e02-41ce-ae10-5dff9e2f7bbe', 'imageID':
'646df162-5c6d-44b1-bc47-b63c3fdab0e2'}
Thread-4732::DEBUG::2014-04-04
12:43:34,440::BindingXMLRPC::1074::vds::(wrapper) return vmSnapshot with
{'status': {'message': 'Snapshot failed', 'code': 48}}
Thread-299::DEBUG::2014-04-04
12:43:35,423::fileSD::225::Storage.Misc.excCmd::(getReadDelay) '/bin/dd
iflag=direct
if=/rhev/data-center/mnt/host01.ovirt.lan:_home_export/ff98d346-4515-4349-8437-fb2f5e9eaadf/dom_md/metadata
bs=4096 count=1' (cwd None)
Thx;)