7:01 a.m.
Hello,
I'm on 4.3.9
I have created a VM with 4vcpu, 16gb mem and a nic and a thin provisioned
disk of 120Gb
Installed nothing on it, only defined.
Now I'm trying to make template from it but I get error.
I leave the prefille value of raw for format
Target storage domain has 900 Gb free, almost empty
In events pane:
Creation of Template ocp_node from VM ocp_node_template was initiated by
admin@internal-authz.
3/25/20 12:42:35 PM
Then I get error in events pane is:
VDSM ovirt.example.local command HSMGetAllTasksStatusesVDS failed: low
level Image copy failed: ("Command ['/usr/bin/qemu-img', 'convert',
'-p',
'-t', 'none', '-T', 'none', '-f', 'raw',
u'/rhev/data-center/mnt/glusterSD/ovirtst.example.storage:_vmstore/81b97244-4b69-4d49-84c4-c822387adc6a/images/61689cb2-fdce-41a5-a6d9-7d06aefeb636/30009efb-83ed-4b0d-b243-3160195ae46e',
'-O', 'qcow2', '-o', 'compat=1.1',
u'/rhev/data-center/mnt/glusterSD/ovirtst.example.storage:_vmstore/81b97244-4b69-4d49-84c4-c822387adc6a/images/5642b52f-d7e8-48a8-adf9-f79022ce4594/982dd5cc-5f8f-41cb-b2e7-3cbdf2a656cf']
failed with rc=1 out='' err=bytearray(b'qemu-img: error while reading
sector 18620412: No such file or directory\\n')",)
3/25/20 12:43:39 PM
Is there a problem templating a thin provisioned disk in gluster?
In vdsm.log
2020-03-25 12:42:40,331+0100 ERROR (tasks/9) [storage.Image] conversion
failure for volume 30009efb-83ed-4b0d-b243-3160195ae46e (image:836)
Traceback (most recent call last):
File "/usr/lib/python2.7/site-packages/vdsm/storage/image.py", line 831,
in copyCollapsed
self._run_qemuimg_operation(operation)
File "/usr/lib/python2.7/site-packages/vdsm/storage/image.py", line 98,
in _run_qemuimg_operation
operation.run()
File "/usr/lib/python2.7/site-packages/vdsm/storage/qemuimg.py", line
343, in run
for data in self._operation.watch():
File "/usr/lib/python2.7/site-packages/vdsm/storage/operation.py", line
106, in watch
self._finalize(b"", err)
File "/usr/lib/python2.7/site-packages/vdsm/storage/operation.py", line
179, in _finalize
raise cmdutils.Error(self._cmd, rc, out, err)
Error: Command ['/usr/bin/qemu-img', 'convert', '-p',
'-t', 'none', '-T',
'none', '-f', 'raw',
u'/rhev/data-center/mnt/glusterSD/ovirtst.example.storage:_vmstore/81b97244-4b69-4d49-84c4-c822387adc6a/images/61689cb2-fdce-41a5-a6d9-7d06aefeb636/30009efb-83ed-4b0d-b243-3160195ae46e',
'-O', 'raw',
u'/rhev/data-center/mnt/glusterSD/ovirtst.example.storage:_vmstore/81b97244-4b69-4d49-84c4-c822387adc6a/images/bb167b28-94fa-434c-8fb6-c4bedfc06c62/53d3ab96-e5d1-453a-9989-2f858e6a9e0a']
failed with rc=1 out='' err=bytearray(b'qemu-img: error while reading
sector 22028283: No data available\n')
2020-03-25 12:42:40,331+0100 ERROR (tasks/9) [storage.Image] Unexpected
error (image:849)
Traceback (most recent call last):
File "/usr/lib/python2.7/site-packages/vdsm/storage/image.py", line 837,
in copyCollapsed
raise se.CopyImageError(str(e))
CopyImageError: low level Image copy failed: ("Command
['/usr/bin/qemu-img', 'convert', '-p', '-t',
'none', '-T', 'none', '-f',
'raw',
u'/rhev/data-center/mnt/glusterSD/ovirtst.example.storage:_vmstore/81b97244-4b69-4d49-84c4-c822387adc6a/images/61689cb2-fdce-41a5-a6d9-7d06aefeb636/30009efb-83ed-4b0d-b243-3160195ae46e',
'-O', 'raw',
u'/rhev/data-center/mnt/glusterSD/ovirtst.example.storage:_vmstore/81b97244-4b69-4d49-84c4-c822387adc6a/images/bb167b28-94fa-434c-8fb6-c4bedfc06c62/53d3ab96-e5d1-453a-9989-2f858e6a9e0a']
failed with rc=1 out='' err=bytearray(b'qemu-img: error while reading
sector 22028283: No data available\\n')",)
2020-03-25 12:42:40,332+0100 ERROR (tasks/9) [storage.TaskManager.Task]
(Task='48476954-310f-45b6-9e1b-921d94e5495b') Unexpected error (task:875)
Traceback (most recent call last):
File "/usr/lib/python2.7/site-packages/vdsm/storage/task.py", line 882,
in _run
return fn(*args, **kargs)
File "/usr/lib/python2.7/site-packages/vdsm/storage/task.py", line 336,
in run
return self.cmd(*self.argslist, **self.argsdict)
File "/usr/lib/python2.7/site-packages/vdsm/storage/securable.py", line
79, in wrapper
return method(self, *args, **kwargs)
File "/usr/lib/python2.7/site-packages/vdsm/storage/sp.py", line 1633, in
copyImage
postZero, force, discard)
File "/usr/lib/python2.7/site-packages/vdsm/storage/image.py", line 837,
in copyCollapsed
raise se.CopyImageError(str(e))
CopyImageError: low level Image copy failed: ("Command
['/usr/bin/qemu-img', 'convert', '-p', '-t',
'none', '-T', 'none', '-f',
'raw',
u'/rhev/data-center/mnt/glusterSD/ovirtst.example.storage:_vmstore/81b97244-4b69-4d49-84c4-c822387adc6a/images/61689cb2-fdce-41a5-a6d9-7d06aefeb636/30009efb-83ed-4b0d-b243-3160195ae46e',
'-O', 'raw',
u'/rhev/data-center/mnt/glusterSD/ovirtst.example.storage:_vmstore/81b97244-4b69-4d49-84c4-c822387adc6a/images/bb167b28-94fa-434c-8fb6-c4bedfc06c62/53d3ab96-e5d1-453a-9989-2f858e6a9e0a']
failed with rc=1 out='' err=bytearray(b'qemu-img: error while reading
sector 22028283: No data available\\n')",)
2020-03-25 12:42:40,384+0100 INFO (tasks/9) [storage.Volume]
createVolumeRollback:
repoPath=/rhev/data-center/9ce6ed92-6c6c-11ea-a971-00163e0acd5c
sdUUID=81b97244-4b69-4d49-84c4-c822387adc6a
imgUUID=bb167b28-94fa-434c-8fb6-c4bedfc06c62
volUUID=53d3ab96-e5d1-453a-9989-2f858e6a9e0a
imageDir=/rhev/data-center/mnt/glusterSD/ovirtst.example.storage:_vmstore/81b97244-4b69-4d49-84c4-c822387adc6a/images/bb167b28-94fa-434c-8fb6-c4bedfc06c62
(volume:1119)
2020-03-25 12:42:40,387+0100 INFO (tasks/9) [storage.Volume] Request to
delete volume 53d3ab96-e5d1-453a-9989-2f858e6a9e0a (fileVolume:555)
...
2020-03-25 12:43:38,161+0100 INFO (jsonrpc/3) [jsonrpc.JsonRpcServer] RPC
call VM.getStats succeede
d in 0.00 seconds (__init__:312)
2020-03-25 12:43:39,778+0100 INFO (jsonrpc/4) [vdsm.api] START
getAllTasksStatuses(spUUID=None, opt
ions=None) from=::ffff:172.16.0.31,40506,
task_id=1ec1f913-641c-44c4-8eb6-06a398af7b0c (api:48)
2020-03-25 12:43:39,778+0100 INFO (jsonrpc/4) [vdsm.api] FINISH
getAllTasksStatuses return={'allTas
ksStatus': {'013f3fc3-d9e8-4acd-8e95-aef4001255a1': {'code': 261,
'message': 'low level Image copy f
ailed: ("Command [\'/usr/bin/qemu-img\', \'convert\', \'-p\',
\'-t\',
\'none\', \'-T\', \'none\', \'
-f\', \'raw\',
u\'/rhev/data-center/mnt/glusterSD/ovirtst.example.storage:_vmstore/81b97244-4b69-4d4
9-84c4-c822387adc6a/images/61689cb2-fdce-41a5-a6d9-7d06aefeb636/30009efb-83ed-4b0d-b243-3160195ae46e
\', \'-O\', \'qcow2\', \'-o\', \'compat=1.1\',
u\'/rhev/data-center/mnt/glusterSD/ovirtst.example.st
orage:_vmstore/81b97244-4b69-4d49-84c4-c822387adc6a/images/5642b52f-d7e8-48a8-adf9-f79022ce4594/982d
d5cc-5f8f-41cb-b2e7-3cbdf2a656cf\'] failed with rc=1 out=\'\'
err=bytearray(b\'qemu-img: error while
reading sector 18620412: No such file or directory\\\\n\')",)',
'taskState': 'finished', 'taskResul
t': 'cleanSuccess', 'taskID':
'013f3fc3-d9e8-4acd-8e95-aef4001255a1'},
'48476954-310f-45b6-9e1b-921d
94e5495b': {'code': 261, 'message': 'low level Image copy failed:
("Command
[\'/usr/bin/qemu-img\',
\'convert\', \'-p\', \'-t\', \'none\', \'-T\',
\'none\', \'-f\', \'raw\',
u\'/rhev/data-center/mnt/g
lusterSD/ovirtst.example.storage:_vmstore/81b97244-4b69-4d49-84c4-c822387adc6a/images/61689cb2-fdce-
41a5-a6d9-7d06aefeb636/30009efb-83ed-4b0d-b243-3160195ae46e\', \'-O\',
\'raw\', u\'/rhev/data-center
/mnt/glusterSD/ovirtst.example.storage:_vmstore/81b97244-4b69-4d49-84c4-c822387adc6a/images/bb167b28-94fa-434c-8fb6-c4bedfc06c62/53d3ab96-e5d1-453a-9989-2f858e6a9e0a\']
failed with rc=1 out=\'\' err=bytearray(b\'qemu-img: error while reading
sector 22028283: No data available\\\\n\')",)', 'taskState':
'finished',
'taskResult': 'cleanSuccess', 'taskID':
'48476954-310f-45b6-9e1b-921d94e5495b'}}} from=::ffff:172.16.0.31,40506,
task_id=1ec1f913-641c-44c4-8eb6-06a398af7b0c (api:54)
2020-03-25 12:43:39,778+0100 INFO (jsonrpc/4) [jsonrpc.JsonRpcServer] RPC
call Host.getAllTasksStatuses succeeded in 0.00 seconds (__init__:312)
2020-03-25 12:43:39,851+0100 INFO (jsonrpc/0) [vdsm.api] START
deleteImage(sdUUID=u'81b97244-4b69-4d49-84c4-c822387adc6a',
spUUID=u'9ce6ed92-6c6c-11ea-a971-00163e0acd5c',
imgUUID=u'bb167b28-94fa-434c-8fb6-c4bedfc06c62', postZero=u'false',
force=u'false', discard=False) from=::ffff:172.16.0.31,40512,
flow_id=3540fed2, task_id=5f048634-f07f-4643-8aeb-88a6c562f038 (api:48)
2020-03-25 12:43:39,856+0100 ERROR (jsonrpc/0) [storage.HSM] Empty or not
found image bb167b28-94fa-434c-8fb6-c4bedfc06c62 in SD
81b97244-4b69-4d49-84c4-c822387adc6a.
{u'2f48ba54-11a5-4fc3-a8da-eba41c345c0e':
ImgsPar(imgs=(u'55d7c95e-8f54-41c8-8422-3daac542a917',), parent=None),
u'54c43445-7066-423f-9c07-2082d1442c78':
ImgsPar(imgs=(u'08238512-aa7a-4ebb-82e4-6831e8805516',), parent=None),
u'30009efb-83ed-4b0d-b243-3160195ae46e':
ImgsPar(imgs=(u'61689cb2-fdce-41a5-a6d9-7d06aefeb636',), parent=None)}
(hsm:1507)
2020-03-25 12:43:39,856+0100 INFO (jsonrpc/0) [vdsm.api] FINISH
deleteImage error=Image does not exist in domain:
u'image=bb167b28-94fa-434c-8fb6-c4bedfc06c62,
domain=81b97244-4b69-4d49-84c4-c822387adc6a' from=::ffff:172.16.0.31,40512,
flow_id=3540fed2, task_id=5f048634-f07f-4643-8aeb-88a6c562f038 (api:52)
2020-03-25 12:43:39,856+0100 ERROR (jsonrpc/0) [storage.TaskManager.Task]
(Task='5f048634-f07f-4643-8aeb-88a6c562f038') Unexpected error (task:875)
Traceback (most recent call last):
File "/usr/lib/python2.7/site-packages/vdsm/storage/task.py", line 882,
in _run
return fn(*args, **kargs)
File "<string>", line 2, in deleteImage
File "/usr/lib/python2.7/site-packages/vdsm/common/api.py", line 50, in
method
ret = func(*args, **kwargs)
File "/usr/lib/python2.7/site-packages/vdsm/storage/hsm.py", line 1508,
in deleteImage
raise se.ImageDoesNotExistInSD(imgUUID, sdUUID)
ImageDoesNotExistInSD: Image does not exist in domain:
u'image=bb167b28-94fa-434c-8fb6-c4bedfc06c62,
domain=81b97244-4b69-4d49-84c4-c822387adc6a'
2020-03-25 12:43:39,856+0100 INFO (jsonrpc/0) [storage.TaskManager.Task]
(Task='5f048634-f07f-4643-8aeb-88a6c562f038') aborting: Task is aborted:
"Image does not exist in domain:
u'image=bb167b28-94fa-434c-8fb6-c4bedfc06c62,
domain=81b97244-4b69-4d49-84c4-c822387adc6a'" - code 268 (task:1181)
2020-03-25 12:43:39,857+0100 ERROR (jsonrpc/0) [storage.Dispatcher] FINISH
deleteImage error=Image does not exist in domain:
u'image=bb167b28-94fa-434c-8fb6-c4bedfc06c62,
domain=81b97244-4b69-4d49-84c4-c822387adc6a' (dispatcher:83)
engine.log
2020-03-25 12:43:39,778+01 INFO
[org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (EE-ManagedThreadFac
tory-engineScheduled-Thread-35) [] Task id
'48476954-310f-45b6-9e1b-921d94e5495b' has passed pre-pol
ling period time and should be polled. Pre-polling period is 60000 millis.
2020-03-25 12:43:39,778+01 INFO
[org.ovirt.engine.core.bll.tasks.AsyncTaskManager] (EE-ManagedThrea
dFactory-engineScheduled-Thread-35) [] Polling and updating Async Tasks: 2
tasks, 1 tasks to poll no
w
2020-03-25 12:43:39,778+01 INFO
[org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (EE-ManagedThreadFac
tory-engineScheduled-Thread-35) [] Task id
'48476954-310f-45b6-9e1b-921d94e5495b' has passed pre-pol
ling period time and should be polled. Pre-polling period is 60000 millis.
2020-03-25 12:43:39,781+01 ERROR
[org.ovirt.engine.core.vdsbroker.vdsbroker.HSMGetAllTasksStatusesVDSCommand]
(EE-ManagedThreadFactory-engineScheduled-Thread-35) [] Failed in
'HSMGetAllTasksStatusesVDS' method
2020-03-25 12:43:39,784+01 ERROR
[org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector]
(EE-ManagedThreadFactory-engineScheduled-Thread-35) [] EVENT_ID:
VDS_BROKER_COMMAND_FAILURE(10,802), VDSM ovirt.example.com command
HSMGetAllTasksStatusesVDS failed: low level Image copy failed: ("Command
['/usr/bin/qemu-img', 'convert', '-p', '-t',
'none', '-T', 'none', '-f',
'raw',
u'/rhev/data-center/mnt/glusterSD/ovirtst.example.storage:_vmstore/81b97244-4b69-4d49-84c4-c822387adc6a/images/61689cb2-fdce-41a5-a6d9-7d06aefeb636/30009efb-83ed-4b0d-b243-3160195ae46e',
'-O', 'qcow2', '-o', 'compat=1.1',
u'/rhev/data-center/mnt/glusterSD/ovirtst.example.storage:_vmstore/81b97244-4b69-4d49-84c4-c822387adc6a/images/5642b52f-d7e8-48a8-adf9-f79022ce4594/982dd5cc-5f8f-41cb-b2e7-3cbdf2a656cf']
failed with rc=1 out='' err=bytearray(b'qemu-img: error while reading
sector 18620412: No such file or directory\\n')",)
2020-03-25 12:43:39,784+01 ERROR
[org.ovirt.engine.core.vdsbroker.vdsbroker.HSMGetAllTasksStatusesVDSCommand]
(EE-ManagedThreadFactory-engineScheduled-Thread-35) [] Failed in
'HSMGetAllTasksStatusesVDS' method
2020-03-25 12:43:39,785+01 INFO
[org.ovirt.engine.core.bll.tasks.SPMAsyncTask]
(EE-ManagedThreadFactory-engineScheduled-Thread-35) [] Task id
'48476954-310f-45b6-9e1b-921d94e5495b' has passed pre-polling period time
and should be polled. Pre-polling period is 60000 millis.
2020-03-25 12:43:39,785+01 INFO
[org.ovirt.engine.core.bll.tasks.SPMAsyncTask]
(EE-ManagedThreadFactory-engineScheduled-Thread-35) []
SPMAsyncTask::PollTask: Polling task '48476954-310f-45b6-9e1b-921d94e5495b'
(Parent Command 'CreateAllTemplateDisks', Parameters Type
'org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters') returned
status 'finished', result 'cleanSuccess'.
2020-03-25 12:43:39,787+01 ERROR
[org.ovirt.engine.core.bll.tasks.SPMAsyncTask]
(EE-ManagedThreadFactory-engineScheduled-Thread-35) []
BaseAsyncTask::logEndTaskFailure: Task
'48476954-310f-45b6-9e1b-921d94e5495b' (Parent Command
'CreateAllTemplateDisks', Parameters Type
'org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters') ended with
failure:
-- Result: 'cleanSuccess'
-- Message: 'VDSGenericException: VDSErrorException: Failed to
HSMGetAllTasksStatusesVDS, error = low level Image copy failed: ("Command
['/usr/bin/qemu-img', 'convert', '-p', '-t',
'none', '-T', 'none', '-f',
'raw',
u'/rhev/data-center/mnt/glusterSD/ovirtst.example.storage:_vmstore/81b97244-4b69-4d49-84c4-c822387adc6a/images/61689cb2-fdce-41a5-a6d9-7d06aefeb636/30009efb-83ed-4b0d-b243-3160195ae46e',
'-O', 'raw',
u'/rhev/data-center/mnt/glusterSD/ovirtst.example.storage:_vmstore/81b97244-4b69-4d49-84c4-c822387adc6a/images/bb167b28-94fa-434c-8fb6-c4bedfc06c62/53d3ab96-e5d1-453a-9989-2f858e6a9e0a']
failed with rc=1 out='' err=bytearray(b'qemu-img: error while reading
sector 22028283: No data a
vailable\\n')",), code = 261',
-- Exception: 'VDSGenericException: VDSErrorException: Failed to
HSMGetAllTasksStatusesVDS, error = low level Image copy failed: ("Command
['/usr/bin/qemu-img', 'convert', '-p', '-t',
'none', '-T', 'none', '-f',
'raw',
u'/rhev/data-center/mnt/glusterSD/ovirtst.example.storage:_vmstore/81b97244-4b69-4d49-84c4-c822387adc6a/images/61689cb2-fdce-41a5-a6d9-7d06aefeb636/30009efb-83ed-4b0d-b243-3160195ae46e',
'-O', 'raw',
u'/rhev/data-center/mnt/glusterSD/ovirtst.example.storage:_vmstore/81b97244-4b69-4d49-84c4-c822387adc6a/images/bb167b28-94fa-434c-8fb6-c4bedfc06c62/53d3ab96-e5d1-453a-9989-2f858e6a9e0a']
failed with rc=1 out='' err=bytearray(b'qemu-img: error while reading
sector 22028283: No data available\\n')",), code = 261'
2020-03-25 12:43:39,793+01 INFO
[org.ovirt.engine.core.bll.tasks.CommandAsyncTask]
(EE-ManagedThreadFactory-engineScheduled-Thread-35) []
CommandAsyncTask::endActionIfNecessary: All tasks of command
'76c06966-36b5-4e06-9426-f3187c5a07b9' has ended -> executing
'endAction'
2020-03-25 12:43:39,793+01 INFO
[org.ovirt.engine.core.bll.tasks.CommandAsyncTask]
(EE-ManagedThreadFactory-engineScheduled-Thread-35) []
CommandAsyncTask::endAction: Ending action for '1' tasks (command ID:
'76c06966-36b5-4e06-9426-f3187c5a07b9'): calling endAction '.
2020-03-25 12:43:39,793+01 INFO
[org.ovirt.engine.core.bll.tasks.CommandAsyncTask]
(EE-ManagedThreadFactory-engine-Thread-9168) []
CommandAsyncTask::endCommandAction [within thread] context: Attempting to
endAction 'CreateAllTemplateDisks',
2020-03-25 12:43:39,805+01 ERROR
[org.ovirt.engine.core.bll.storage.disk.CreateAllTemplateDisksCommand]
(EE-ManagedThreadFactory-engine-Thread-9168)
[76349650-8bc6-4769-a011-49d4c2324052] Ending command
'org.ovirt.engine.core.bll.storage.disk.CreateAllTemplateDisksCommand' with
failure.
2020-03-25 12:43:39,810+01 ERROR
[org.ovirt.engine.core.bll.storage.disk.image.CreateImageTemplateCommand]
(EE-ManagedThreadFactory-engine-Thread-9168)
[76349650-8bc6-4769-a011-49d4c2324052] Ending command
'org.ovirt.engine.core.bll.storage.disk.image.CreateImageTemplateCommand'
with failure.
...
Thanks,
Gianluca