2017-11-10 10:51:22,360+0000 INFO (jsonrpc/7) [vdsm.api] START repoStats(o=
2017-11-10 10:51:32,464+0000 INFO (jsonrpc/2) [vdsm.api] FINISH getStorage= PoolInfo return=3D{'info': {'name': 'No Description', 'isoprefix': '', 'poo= l_status': 'connected', 'lver': 2L, 'domains': u'5eed853b-09ee-430d-bde2-c3= 7394c1ff6c:Active', 'master_uuid': u'5eed853b-09ee-430d-bde2-c37394c1ff6c',= 'version': '4', 'spm_id': 1, 'type': 'GLUSTERFS', 'master_ver': 1}, 'domin= fo': {u'5eed853b-09ee-430d-bde2-c37394c1ff6c': {'status': u'Active', 'diskf= ree': '912109731840', 'isoprefix': '', 'alerts': [], 'disktotal': '91215101= 9520', 'version': 4}}} from=3D::ffff:192.168.16.9,42838, flow_id=3D11ee2968= , task_id=3D4f010678-55ba-4ead-84bc-734763662964 (api:52)<br>2017-11-10 10:= 51:32,465+0000 INFO (jsonrpc/2) [jsonrpc.JsonRpcServer] RPC call StoragePoo= l.getInfo succeeded in 0.01 seconds (__init__:539)<br>2017-11-10 10:51:33,9= 48+0000 INFO (jsonrpc/3) [vdsm.api] START connectStorageServer(domType=3D1,= spUUID=3Du'00000000-0000-0000-0000-000000000000', conList=3D[{u'id': u'ba9= e9b72-cade-4e16-82fe-237ff7143b3b', u'connection': u'engine.futurcargo.com:= /home/iso', u'iqn': u'', u'user': u'', u'password': '********', u'port': u'= '}], options=3DNone) from=3D::ffff:192.168.16.9,42836, flow_id=3D1cccae15, = task_id=3D37c76c14-2e74-4c12-99d7-6b3f2397348e (api:46)<br>2017-11-10 10:51= :33,951+0000 INFO (jsonrpc/3) [IOProcessClient] Starting client ioprocess-7= 06 (__init__:330)<br>2017-11-10 10:51:33,962+0000 INFO (ioprocess/20640) [I= OProcess] Starting ioprocess (__init__:452)<br>2017-11-10 10:51:33,964+0000= INFO (jsonrpc/3) [vdsm.api] FINISH connectStorageServer return=3D{'statusl= ist': [{'status': 0, 'id': u'ba9e9b72-cade-4e16-82fe-237ff7143b3b'}]} from= =3D::ffff:192.168.16.9,42836, flow_id=3D1cccae15, task_id=3D37c76c14-2e74-4= c12-99d7-6b3f2397348e (api:52)<br>2017-11-10 10:51:33,965+0000 INFO (jsonrp= c/3) [jsonrpc.JsonRpcServer] RPC call StoragePool.connectStorageServer succ= eeded in 0.01 seconds (__init__:539)<br>2017-11-10 10:51:34,974+0000 INFO (= jsonrpc/0) [vdsm.api] START attachStorageDomain(sdUUID=3Du'875e1af4-ba14-42= 55-b6ad-c3031672df93', spUUID=3Du'5a0287ce-0233-0170-00a2-0000000001d8', op= tions=3DNone) from=3D::ffff:192.168.16.9,42838, flow_id=3D05e7dbb5-bad3-484= f-b364-ca635fe1f20b, task_id=3D472c9bfe-0751-4240-b5c4-e867cb3d0cfb (api:46= )<br>2017-11-10 10:51:34,975+0000 INFO (jsonrpc/0) [storage.StoragePool] sd= UUID=3D875e1af4-ba14-4255-b6ad-c3031672df93 spUUID=3D5a0287ce-0233-0170-00a= 2-0000000001d8 (sp:942)<br>2017-11-10 10:51:35,102+0000 INFO (itmap/0) [IOP= rocessClient] Starting client ioprocess-707 (__init__:330)<br>2017-11-10 10= :51:35,113+0000 INFO (itmap/1) [IOProcessClient] Starting client ioprocess-= 708 (__init__:330)<br>2017-11-10 10:51:35,123+0000 INFO (ioprocess/20654) [= IOProcess] Starting ioprocess (__init__:452)<br>2017-11-10 10:51:35,127+000= 0 INFO (jsonrpc/0) [IOProcessClient] Starting client ioprocess-709 (__init_= _:330)<br>2017-11-10 10:51:35,137+0000 INFO (ioprocess/20660) [IOProcess] S= tarting ioprocess (__init__:452)<br>2017-11-10 10:51:35,139+0000 INFO (iopr= ocess/20667) [IOProcess] Starting ioprocess (__init__:452)<br>2017-11-10 10= :51:35,141+0000 INFO (jsonrpc/0) [vdsm.api] FINISH attachStorageDomain erro= r=3D'VERSION' from=3D::ffff:192.168.16.9,42838, flow_id=3D05e7dbb5-bad3-484= f-b364-ca635fe1f20b, task_id=3D472c9bfe-0751-4240-b5c4-e867cb3d0cfb (api:50= )<br>2017-11-10 10:51:35,141+0000 ERROR (jsonrpc/0) [storage.TaskManager.Ta= sk] (Task=3D'472c9bfe-0751-4240-b5c4-e867cb3d0cfb') Unexpected error (task:= 872)<br>Traceback (most recent call last):<br> File "/usr/share/vdsm/storag= e/task.py", line 879, in _run<br> return fn(*args, **kargs)<br> File "<s=
2017-11-10 10:52:10,460+0000 INFO (jsonrpc/2) [vdsm.api] START repoStats(o=
------=_Part_1147272_908603913.1510346638388 Content-Type: text/plain; charset=utf-8 Content-Transfer-Encoding: 7bit I just Destroy the ISO Domain, remove the content of the directory. Create a new iso domain, and it worked. De: suporte@logicworks.pt Para: users@ovirt.org Enviadas: Sexta-feira, 10 De Novembro de 2017 11:01:39 Assunto: [ovirt-users] attach iso domain failed Hi, I have engine installed on one machine and host on another, running Version 4.1.7.6-1.el7.centos Everything looks ok except the iso domain I cannot attach it to the data center. Message on the engine dashboard: Failed to attach Storage Domain ISO_DOMAIN to Data Center Default. (User: admin@internal-authz) VDSM command AttachStorageDomainVDS failed: Error in storage domain action: (u'sdUUID=875e1af4-ba14-4255-b6ad-c3031672df93, spUUID=5a0287ce-0233-0170-00a2-0000000001d8',) Checking the configuration everything looks ok: [root@engine ~]# grep iso /etc/ovirt-engine-setup.conf.d/20-setup-ovirt-post.conf OVESETUP_CONFIG/isoDomainName=str:ISO_DOMAIN OVESETUP_CONFIG/isoDomainSdUuid=str:875e1af4-ba14-4255-b6ad-c3031672df93 OVESETUP_CONFIG/isoDomainMountPoint=str:/home/iso OVESETUP_CONFIG/isoDomainExists=bool:True OVESETUP_CONFIG/isoDomainStorageDir=str:/home/iso/875e1af4-ba14-4255-b6ad-c3031672df93/images/11111111-1111-1111-1111-111111111111 [root@engine ~]# ovirt-iso-uploader list Please provide the REST API password for the admin@internal oVirt Engine user (CTRL+D to abort): ISO Storage Domain Name | ISO Domain Status ISO_DOMAIN | ok [root@node1 ~]# vdsClient -s 0 getStorageDomainsList 5eed853b-09ee-430d-bde2-c37394c1ff6c I can mount the export manually Don't know where to look more. Any idea? thanks vdsm log: 2017-11-10 10:51:00,839+0000 INFO (periodic/3) [vdsm.api] START repoStats(options=None) from=internal, task_id=f0f5161a-b3c1-4534-a33a-cf5d17ab8c9c (api:46) 2017-11-10 10:51:00,840+0000 INFO (periodic/3) [vdsm.api] FINISH repoStats return={u'5eed853b-09ee-430d-bde2-c37394c1ff6c': {'code': 0, 'actual': True, 'version': 4, 'acquired': True, 'delay': '0.000890471', 'lastCheck': '4.8', 'valid': True}} from=internal, task_id=f0f5161a-b3c1-4534-a33a-cf5d17ab8c9c (api:52) 2017-11-10 10:51:05,323+0000 INFO (jsonrpc/7) [jsonrpc.JsonRpcServer] RPC call Host.getAllVmStats succeeded in 0.00 seconds (__init__:539) 2017-11-10 10:51:07,277+0000 INFO (jsonrpc/4) [vdsm.api] START repoStats(options=None) from=::ffff:192.168.16.9,42836, flow_id=399705b5, task_id=998fe1bc-eff0-4ba7-942c-458906c5e1f1 (api:46) 2017-11-10 10:51:07,277+0000 INFO (jsonrpc/4) [vdsm.api] FINISH repoStats return={u'5eed853b-09ee-430d-bde2-c37394c1ff6c': {'code': 0, 'actual': True, 'version': 4, 'acquired': True, 'delay': '0.000844547', 'lastCheck': '1.2', 'valid': True}} from=::ffff:192.168.16.9,42836, flow_id=399705b5, task_id=998fe1bc-eff0-4ba7-942c-458906c5e1f1 (api:52) 2017-11-10 10:51:07,285+0000 INFO (jsonrpc/4) [jsonrpc.JsonRpcServer] RPC call Host.getStats succeeded in 0.01 seconds (__init__:539) 2017-11-10 10:51:07,345+0000 INFO (jsonrpc/1) [vdsm.api] START getSpmStatus(spUUID=u'5a0287ce-0233-0170-00a2-0000000001d8', options=None) from=::ffff:192.168.16.9,42836, flow_id=4b670edd, task_id=a6baf012-6dbb-47d6-9f8e-457e42bbc7d7 (api:46) 2017-11-10 10:51:07,352+0000 INFO (jsonrpc/1) [vdsm.api] FINISH getSpmStatus return={'spm_st': {'spmId': 1, 'spmStatus': 'SPM', 'spmLver': 2L}} from=::ffff:192.168.16.9,42836, flow_id=4b670edd, task_id=a6baf012-6dbb-47d6-9f8e-457e42bbc7d7 (api:52) 2017-11-10 10:51:07,352+0000 INFO (jsonrpc/1) [jsonrpc.JsonRpcServer] RPC call StoragePool.getSpmStatus succeeded in 0.01 seconds (__init__:539) 2017-11-10 10:51:08,410+0000 INFO (jsonrpc/2) [vdsm.api] START getStoragePoolInfo(spUUID=u'5a0287ce-0233-0170-00a2-0000000001d8', options=None) from=::ffff:192.168.16.9,42838, flow_id=4b670edd, task_id=4d623b87-105f-47ef-8385-7d7391378906 (api:46) 2017-11-10 10:51:08,421+0000 INFO (jsonrpc/2) [vdsm.api] FINISH getStoragePoolInfo return={'info': {'name': 'No Description', 'isoprefix': '', 'pool_status': 'connected', 'lver': 2L, 'domains': u'5eed853b-09ee-430d-bde2-c37394c1ff6c:Active', 'master_uuid': u'5eed853b-09ee-430d-bde2-c37394c1ff6c', 'version': '4', 'spm_id': 1, 'type': 'GLUSTERFS', 'master_ver': 1}, 'dominfo': {u'5eed853b-09ee-430d-bde2-c37394c1ff6c': {'status': u'Active', 'diskfree': '912109731840', 'isoprefix': '', 'alerts': [], 'disktotal': '912151019520', 'version': 4}}} from=::ffff:192.168.16.9,42838, flow_id=4b670edd, task_id=4d623b87-105f-47ef-8385-7d7391378906 (api:52) 2017-11-10 10:51:08,422+0000 INFO (jsonrpc/2) [jsonrpc.JsonRpcServer] RPC call StoragePool.getInfo succeeded in 0.01 seconds (__init__:539) 2017-11-10 10:51:11,159+0000 INFO (jsonrpc/3) [jsonrpc.JsonRpcServer] RPC call Host.getAllVmStats succeeded in 0.00 seconds (__init__:539) 2017-11-10 10:51:15,859+0000 INFO (periodic/0) [vdsm.api] START repoStats(options=None) from=internal, task_id=8da65b6e-23ba-40fb-82fd-287645bb05cf (api:46) 2017-11-10 10:51:15,859+0000 INFO (periodic/0) [vdsm.api] FINISH repoStats return={u'5eed853b-09ee-430d-bde2-c37394c1ff6c': {'code': 0, 'actual': True, 'version': 4, 'acquired': True, 'delay': '0.000718255', 'lastCheck': '1.8', 'valid': True}} from=internal, task_id=8da65b6e-23ba-40fb-82fd-287645bb05cf (api:52) 2017-11-10 10:51:19,434+0000 INFO (jsonrpc/0) [vdsm.api] START getSpmStatus(spUUID=u'5a0287ce-0233-0170-00a2-0000000001d8', options=None) from=::ffff:192.168.16.9,42836, flow_id=32cb4291, task_id=c42d1ce1-d82c-47ad-874f-6d73142e49c6 (api:46) 2017-11-10 10:51:19,443+0000 INFO (jsonrpc/0) [vdsm.api] FINISH getSpmStatus return={'spm_st': {'spmId': 1, 'spmStatus': 'SPM', 'spmLver': 2L}} from=::ffff:192.168.16.9,42836, flow_id=32cb4291, task_id=c42d1ce1-d82c-47ad-874f-6d73142e49c6 (api:52) 2017-11-10 10:51:19,444+0000 INFO (jsonrpc/0) [jsonrpc.JsonRpcServer] RPC call StoragePool.getSpmStatus succeeded in 0.01 seconds (__init__:539) 2017-11-10 10:51:20,453+0000 INFO (jsonrpc/6) [vdsm.api] START getStoragePoolInfo(spUUID=u'5a0287ce-0233-0170-00a2-0000000001d8', options=None) from=::ffff:192.168.16.9,42838, flow_id=32cb4291, task_id=72cdacd6-5133-4554-8ecc-86f11a094064 (api:46) 2017-11-10 10:51:20,464+0000 INFO (jsonrpc/6) [vdsm.api] FINISH getStoragePoolInfo return={'info': {'name': 'No Description', 'isoprefix': '', 'pool_status': 'connected', 'lver': 2L, 'domains': u'5eed853b-09ee-430d-bde2-c37394c1ff6c:Active', 'master_uuid': u'5eed853b-09ee-430d-bde2-c37394c1ff6c', 'version': '4', 'spm_id': 1, 'type': 'GLUSTERFS', 'master_ver': 1}, 'dominfo': {u'5eed853b-09ee-430d-bde2-c37394c1ff6c': {'status': u'Active', 'diskfree': '912109731840', 'isoprefix': '', 'alerts': [], 'disktotal': '912151019520', 'version': 4}}} from=::ffff:192.168.16.9,42838, flow_id=32cb4291, task_id=72cdacd6-5133-4554-8ecc-86f11a094064 (api:52) 2017-11-10 10:51:20,465+0000 INFO (jsonrpc/6) [jsonrpc.JsonRpcServer] RPC call StoragePool.getInfo succeeded in 0.01 seconds (__init__:539) 2017-11-10 10:51:21,329+0000 INFO (jsonrpc/5) [jsonrpc.JsonRpcServer] RPC call Host.getAllVmStats succeeded in 0.00 seconds (__init__:539) 2017-11-10 10:51:22,360+0000 INFO (jsonrpc/7) [vdsm.api] START repoStats(options=None) from=::ffff:192.168.16.9,42836, flow_id=5eb26f2b, task_id=c4320507-69ec-457d-a51b-e898702de247 (api:46) 2017-11-10 10:51:22,360+0000 INFO (jsonrpc/7) [vdsm.api] FINISH repoStats return={u'5eed853b-09ee-430d-bde2-c37394c1ff6c': {'code': 0, 'actual': True, 'version': 4, 'acquired': True, 'delay': '0.000718255', 'lastCheck': '6.3', 'valid': True}} from=::ffff:192.168.16.9,42836, flow_id=5eb26f2b, task_id=c4320507-69ec-457d-a51b-e898702de247 (api:52) 2017-11-10 10:51:22,368+0000 INFO (jsonrpc/7) [jsonrpc.JsonRpcServer] RPC call Host.getStats succeeded in 0.01 seconds (__init__:539) 2017-11-10 10:51:26,178+0000 INFO (jsonrpc/4) [jsonrpc.JsonRpcServer] RPC call Host.getAllVmStats succeeded in 0.00 seconds (__init__:539) 2017-11-10 10:51:30,878+0000 INFO (periodic/2) [vdsm.api] START repoStats(options=None) from=internal, task_id=f62ad6de-75ad-4a99-bb4d-2854b5ff85c6 (api:46) 2017-11-10 10:51:30,878+0000 INFO (periodic/2) [vdsm.api] FINISH repoStats return={u'5eed853b-09ee-430d-bde2-c37394c1ff6c': {'code': 0, 'actual': True, 'version': 4, 'acquired': True, 'delay': '0.000956708', 'lastCheck': '4.8', 'valid': True}} from=internal, task_id=f62ad6de-75ad-4a99-bb4d-2854b5ff85c6 (api:52) 2017-11-10 10:51:31,436+0000 INFO (jsonrpc/1) [vdsm.api] START getSpmStatus(spUUID=u'5a0287ce-0233-0170-00a2-0000000001d8', options=None) from=::ffff:192.168.16.9,42836, flow_id=11ee2968, task_id=738c51fb-387e-47b3-a1ad-03fc900f749f (api:46) 2017-11-10 10:51:31,444+0000 INFO (jsonrpc/1) [vdsm.api] FINISH getSpmStatus return={'spm_st': {'spmId': 1, 'spmStatus': 'SPM', 'spmLver': 2L}} from=::ffff:192.168.16.9,42836, flow_id=11ee2968, task_id=738c51fb-387e-47b3-a1ad-03fc900f749f (api:52) 2017-11-10 10:51:31,445+0000 INFO (jsonrpc/1) [jsonrpc.JsonRpcServer] RPC call StoragePool.getSpmStatus succeeded in 0.01 seconds (__init__:539) 2017-11-10 10:51:32,454+0000 INFO (jsonrpc/2) [vdsm.api] START getStoragePoolInfo(spUUID=u'5a0287ce-0233-0170-00a2-0000000001d8', options=None) from=::ffff:192.168.16.9,42838, flow_id=11ee2968, task_id=4f010678-55ba-4ead-84bc-734763662964 (api:46) 2017-11-10 10:51:32,464+0000 INFO (jsonrpc/2) [vdsm.api] FINISH getStoragePoolInfo return={'info': {'name': 'No Description', 'isoprefix': '', 'pool_status': 'connected', 'lver': 2L, 'domains': u'5eed853b-09ee-430d-bde2-c37394c1ff6c:Active', 'master_uuid': u'5eed853b-09ee-430d-bde2-c37394c1ff6c', 'version': '4', 'spm_id': 1, 'type': 'GLUSTERFS', 'master_ver': 1}, 'dominfo': {u'5eed853b-09ee-430d-bde2-c37394c1ff6c': {'status': u'Active', 'diskfree': '912109731840', 'isoprefix': '', 'alerts': [], 'disktotal': '912151019520', 'version': 4}}} from=::ffff:192.168.16.9,42838, flow_id=11ee2968, task_id=4f010678-55ba-4ead-84bc-734763662964 (api:52) 2017-11-10 10:51:32,465+0000 INFO (jsonrpc/2) [jsonrpc.JsonRpcServer] RPC call StoragePool.getInfo succeeded in 0.01 seconds (__init__:539) 2017-11-10 10:51:33,948+0000 INFO (jsonrpc/3) [vdsm.api] START connectStorageServer(domType=1, spUUID=u'00000000-0000-0000-0000-000000000000', conList=[{u'id': u'ba9e9b72-cade-4e16-82fe-237ff7143b3b', u'connection': u'engine.futurcargo.com:/home/iso', u'iqn': u'', u'user': u'', u'password': '********', u'port': u''}], options=None) from=::ffff:192.168.16.9,42836, flow_id=1cccae15, task_id=37c76c14-2e74-4c12-99d7-6b3f2397348e (api:46) 2017-11-10 10:51:33,951+0000 INFO (jsonrpc/3) [IOProcessClient] Starting client ioprocess-706 (__init__:330) 2017-11-10 10:51:33,962+0000 INFO (ioprocess/20640) [IOProcess] Starting ioprocess (__init__:452) 2017-11-10 10:51:33,964+0000 INFO (jsonrpc/3) [vdsm.api] FINISH connectStorageServer return={'statuslist': [{'status': 0, 'id': u'ba9e9b72-cade-4e16-82fe-237ff7143b3b'}]} from=::ffff:192.168.16.9,42836, flow_id=1cccae15, task_id=37c76c14-2e74-4c12-99d7-6b3f2397348e (api:52) 2017-11-10 10:51:33,965+0000 INFO (jsonrpc/3) [jsonrpc.JsonRpcServer] RPC call StoragePool.connectStorageServer succeeded in 0.01 seconds (__init__:539) 2017-11-10 10:51:34,974+0000 INFO (jsonrpc/0) [vdsm.api] START attachStorageDomain(sdUUID=u'875e1af4-ba14-4255-b6ad-c3031672df93', spUUID=u'5a0287ce-0233-0170-00a2-0000000001d8', options=None) from=::ffff:192.168.16.9,42838, flow_id=05e7dbb5-bad3-484f-b364-ca635fe1f20b, task_id=472c9bfe-0751-4240-b5c4-e867cb3d0cfb (api:46) 2017-11-10 10:51:34,975+0000 INFO (jsonrpc/0) [storage.StoragePool] sdUUID=875e1af4-ba14-4255-b6ad-c3031672df93 spUUID=5a0287ce-0233-0170-00a2-0000000001d8 (sp:942) 2017-11-10 10:51:35,102+0000 INFO (itmap/0) [IOProcessClient] Starting client ioprocess-707 (__init__:330) 2017-11-10 10:51:35,113+0000 INFO (itmap/1) [IOProcessClient] Starting client ioprocess-708 (__init__:330) 2017-11-10 10:51:35,123+0000 INFO (ioprocess/20654) [IOProcess] Starting ioprocess (__init__:452) 2017-11-10 10:51:35,127+0000 INFO (jsonrpc/0) [IOProcessClient] Starting client ioprocess-709 (__init__:330) 2017-11-10 10:51:35,137+0000 INFO (ioprocess/20660) [IOProcess] Starting ioprocess (__init__:452) 2017-11-10 10:51:35,139+0000 INFO (ioprocess/20667) [IOProcess] Starting ioprocess (__init__:452) 2017-11-10 10:51:35,141+0000 INFO (jsonrpc/0) [vdsm.api] FINISH attachStorageDomain error='VERSION' from=::ffff:192.168.16.9,42838, flow_id=05e7dbb5-bad3-484f-b364-ca635fe1f20b, task_id=472c9bfe-0751-4240-b5c4-e867cb3d0cfb (api:50) 2017-11-10 10:51:35,141+0000 ERROR (jsonrpc/0) [storage.TaskManager.Task] (Task='472c9bfe-0751-4240-b5c4-e867cb3d0cfb') Unexpected error (task:872) Traceback (most recent call last): File "/usr/share/vdsm/storage/task.py", line 879, in _run return fn(*args, **kargs) File "<string>", line 2, in attachStorageDomain File "/usr/lib/python2.7/site-packages/vdsm/common/api.py", line 48, in method ret = func(*args, **kwargs) File "/usr/share/vdsm/storage/hsm.py", line 1152, in attachStorageDomain pool.attachSD(sdUUID) File "/usr/lib/python2.7/site-packages/vdsm/storage/securable.py", line 79, in wrapper return method(self, *args, **kwargs) File "/usr/share/vdsm/storage/sp.py", line 949, in attachSD dom = sdCache.produce(sdUUID) File "/usr/share/vdsm/storage/sdc.py", line 112, in produce domain.getRealDomain() File "/usr/share/vdsm/storage/sdc.py", line 53, in getRealDomain return self._cache._realProduce(self._sdUUID) File "/usr/share/vdsm/storage/sdc.py", line 136, in _realProduce domain = self._findDomain(sdUUID) File "/usr/share/vdsm/storage/sdc.py", line 153, in _findDomain return findMethod(sdUUID) File "/usr/share/vdsm/storage/nfsSD.py", line 126, in findDomain return NfsStorageDomain(NfsStorageDomain.findDomainPath(sdUUID)) File "/usr/share/vdsm/storage/fileSD.py", line 364, in __init__ manifest = self.manifestClass(domainPath) File "/usr/share/vdsm/storage/fileSD.py", line 171, in __init__ sd.StorageDomainManifest.__init__(self, sdUUID, domaindir, metadata) File "/usr/share/vdsm/storage/sd.py", line 332, in __init__ self._domainLock = self._makeDomainLock() File "/usr/share/vdsm/storage/sd.py", line 526, in _makeDomainLock domVersion = self.getVersion() File "/usr/share/vdsm/storage/sd.py", line 403, in getVersion return self.getMetaParam(DMDK_VERSION) File "/usr/share/vdsm/storage/sd.py", line 400, in getMetaParam return self._metadata[key] File "/usr/lib/python2.7/site-packages/vdsm/storage/persistent.py", line 91, in __getitem__ return dec(self._dict[key]) File "/usr/lib/python2.7/site-packages/vdsm/storage/persistent.py", line 203, in __getitem__ raise KeyError(key) KeyError: 'VERSION' 2017-11-10 10:51:35,142+0000 INFO (jsonrpc/0) [storage.TaskManager.Task] (Task='472c9bfe-0751-4240-b5c4-e867cb3d0cfb') aborting: Task is aborted: u"'VERSION'" - code 100 (task:1177) 2017-11-10 10:51:35,143+0000 ERROR (jsonrpc/0) [storage.Dispatcher] FINISH attachStorageDomain error='VERSION' (dispatcher:85) Traceback (most recent call last): File "/usr/share/vdsm/storage/dispatcher.py", line 72, in wrapper result = ctask.prepare(func, *args, **kwargs) File "/usr/share/vdsm/storage/task.py", line 105, in wrapper return m(self, *a, **kw) File "/usr/share/vdsm/storage/task.py", line 1185, in prepare raise self.error KeyError: 'VERSION' 2017-11-10 10:51:35,144+0000 INFO (jsonrpc/0) [jsonrpc.JsonRpcServer] RPC call StorageDomain.attach failed (error 350) in 0.17 seconds (__init__:539) 2017-11-10 10:51:37,336+0000 INFO (jsonrpc/6) [jsonrpc.JsonRpcServer] RPC call Host.getAllVmStats succeeded in 0.00 seconds (__init__:539) 2017-11-10 10:51:38,387+0000 INFO (jsonrpc/5) [vdsm.api] START repoStats(options=None) from=::ffff:192.168.16.9,42836, flow_id=564f788f, task_id=0c08c128-7ec8-4981-95a8-5bfeeed126d6 (api:46) 2017-11-10 10:51:38,388+0000 INFO (jsonrpc/5) [vdsm.api] FINISH repoStats return={u'5eed853b-09ee-430d-bde2-c37394c1ff6c': {'code': 0, 'actual': True, 'version': 4, 'acquired': True, 'delay': '0.000855589', 'lastCheck': '2.3', 'valid': True}} from=::ffff:192.168.16.9,42836, flow_id=564f788f, task_id=0c08c128-7ec8-4981-95a8-5bfeeed126d6 (api:52) 2017-11-10 10:51:38,396+0000 INFO (jsonrpc/5) [jsonrpc.JsonRpcServer] RPC call Host.getStats succeeded in 0.01 seconds (__init__:539) 2017-11-10 10:51:41,198+0000 INFO (jsonrpc/7) [jsonrpc.JsonRpcServer] RPC call Host.getAllVmStats succeeded in 0.00 seconds (__init__:539) 2017-11-10 10:51:43,474+0000 INFO (jsonrpc/4) [vdsm.api] START getSpmStatus(spUUID=u'5a0287ce-0233-0170-00a2-0000000001d8', options=None) from=::ffff:192.168.16.9,42836, flow_id=14f15c4b, task_id=63fb7235-56eb-4acd-8bf6-e04a701e8d83 (api:46) 2017-11-10 10:51:43,483+0000 INFO (jsonrpc/4) [vdsm.api] FINISH getSpmStatus return={'spm_st': {'spmId': 1, 'spmStatus': 'SPM', 'spmLver': 2L}} from=::ffff:192.168.16.9,42836, flow_id=14f15c4b, task_id=63fb7235-56eb-4acd-8bf6-e04a701e8d83 (api:52) 2017-11-10 10:51:43,484+0000 INFO (jsonrpc/4) [jsonrpc.JsonRpcServer] RPC call StoragePool.getSpmStatus succeeded in 0.01 seconds (__init__:539) 2017-11-10 10:51:44,493+0000 INFO (jsonrpc/1) [vdsm.api] START getStoragePoolInfo(spUUID=u'5a0287ce-0233-0170-00a2-0000000001d8', options=None) from=::ffff:192.168.16.9,42838, flow_id=14f15c4b, task_id=e0fe10ef-cc9c-4e3c-a22a-1f9ca042e75e (api:46) 2017-11-10 10:51:44,504+0000 INFO (jsonrpc/1) [vdsm.api] FINISH getStoragePoolInfo return={'info': {'name': 'No Description', 'isoprefix': '', 'pool_status': 'connected', 'lver': 2L, 'domains': u'5eed853b-09ee-430d-bde2-c37394c1ff6c:Active', 'master_uuid': u'5eed853b-09ee-430d-bde2-c37394c1ff6c', 'version': '4', 'spm_id': 1, 'type': 'GLUSTERFS', 'master_ver': 1}, 'dominfo': {u'5eed853b-09ee-430d-bde2-c37394c1ff6c': {'status': u'Active', 'diskfree': '912109731840', 'isoprefix': '', 'alerts': [], 'disktotal': '912151019520', 'version': 4}}} from=::ffff:192.168.16.9,42838, flow_id=14f15c4b, task_id=e0fe10ef-cc9c-4e3c-a22a-1f9ca042e75e (api:52) 2017-11-10 10:51:44,505+0000 INFO (jsonrpc/1) [jsonrpc.JsonRpcServer] RPC call StoragePool.getInfo succeeded in 0.01 seconds (__init__:539) 2017-11-10 10:51:45,897+0000 INFO (periodic/1) [vdsm.api] START repoStats(options=None) from=internal, task_id=c0283b39-b3d9-4745-ae24-791e6bddde42 (api:46) 2017-11-10 10:51:45,897+0000 INFO (periodic/1) [vdsm.api] FINISH repoStats return={u'5eed853b-09ee-430d-bde2-c37394c1ff6c': {'code': 0, 'actual': True, 'version': 4, 'acquired': True, 'delay': '0.00066142', 'lastCheck': '1.9', 'valid': True}} from=internal, task_id=c0283b39-b3d9-4745-ae24-791e6bddde42 (api:52) 2017-11-10 10:51:53,342+0000 INFO (jsonrpc/2) [jsonrpc.JsonRpcServer] RPC call Host.getAllVmStats succeeded in 0.00 seconds (__init__:539) 2017-11-10 10:51:54,433+0000 INFO (jsonrpc/3) [vdsm.api] START repoStats(options=None) from=::ffff:192.168.16.9,42836, flow_id=27d75b74, task_id=cd61efd3-ec82-4de5-a83f-65e0f4ac48f6 (api:46) 2017-11-10 10:51:54,433+0000 INFO (jsonrpc/3) [vdsm.api] FINISH repoStats return={u'5eed853b-09ee-430d-bde2-c37394c1ff6c': {'code': 0, 'actual': True, 'version': 4, 'acquired': True, 'delay': '0.000928362', 'lastCheck': '0.4', 'valid': True}} from=::ffff:192.168.16.9,42836, flow_id=27d75b74, task_id=cd61efd3-ec82-4de5-a83f-65e0f4ac48f6 (api:52) 2017-11-10 10:51:54,441+0000 INFO (jsonrpc/3) [jsonrpc.JsonRpcServer] RPC call Host.getStats succeeded in 0.01 seconds (__init__:539) 2017-11-10 10:51:55,515+0000 INFO (jsonrpc/0) [vdsm.api] START getSpmStatus(spUUID=u'5a0287ce-0233-0170-00a2-0000000001d8', options=None) from=::ffff:192.168.16.9,42836, flow_id=87d184a, task_id=045078a6-36c6-4869-9408-d1126925aad3 (api:46) 2017-11-10 10:51:55,523+0000 INFO (jsonrpc/0) [vdsm.api] FINISH getSpmStatus return={'spm_st': {'spmId': 1, 'spmStatus': 'SPM', 'spmLver': 2L}} from=::ffff:192.168.16.9,42836, flow_id=87d184a, task_id=045078a6-36c6-4869-9408-d1126925aad3 (api:52) 2017-11-10 10:51:55,524+0000 INFO (jsonrpc/0) [jsonrpc.JsonRpcServer] RPC call StoragePool.getSpmStatus succeeded in 0.01 seconds (__init__:539) 2017-11-10 10:51:56,217+0000 INFO (jsonrpc/6) [jsonrpc.JsonRpcServer] RPC call Host.getAllVmStats succeeded in 0.01 seconds (__init__:539) 2017-11-10 10:51:56,532+0000 INFO (jsonrpc/5) [vdsm.api] START getStoragePoolInfo(spUUID=u'5a0287ce-0233-0170-00a2-0000000001d8', options=None) from=::ffff:192.168.16.9,42838, flow_id=87d184a, task_id=f7efc4cc-14a7-4e50-8070-3119d1bb79b3 (api:46) 2017-11-10 10:51:56,543+0000 INFO (jsonrpc/5) [vdsm.api] FINISH getStoragePoolInfo return={'info': {'name': 'No Description', 'isoprefix': '', 'pool_status': 'connected', 'lver': 2L, 'domains': u'5eed853b-09ee-430d-bde2-c37394c1ff6c:Active', 'master_uuid': u'5eed853b-09ee-430d-bde2-c37394c1ff6c', 'version': '4', 'spm_id': 1, 'type': 'GLUSTERFS', 'master_ver': 1}, 'dominfo': {u'5eed853b-09ee-430d-bde2-c37394c1ff6c': {'status': u'Active', 'diskfree': '912109731840', 'isoprefix': '', 'alerts': [], 'disktotal': '912151019520', 'version': 4}}} from=::ffff:192.168.16.9,42838, flow_id=87d184a, task_id=f7efc4cc-14a7-4e50-8070-3119d1bb79b3 (api:52) 2017-11-10 10:51:56,544+0000 INFO (jsonrpc/5) [jsonrpc.JsonRpcServer] RPC call StoragePool.getInfo succeeded in 0.01 seconds (__init__:539) 2017-11-10 10:52:00,908+0000 INFO (periodic/1) [vdsm.api] START repoStats(options=None) from=internal, task_id=110fbcb2-6cde-4e95-a381-79881fb17ac0 (api:46) 2017-11-10 10:52:00,909+0000 INFO (periodic/1) [vdsm.api] FINISH repoStats return={u'5eed853b-09ee-430d-bde2-c37394c1ff6c': {'code': 0, 'actual': True, 'version': 4, 'acquired': True, 'delay': '0.000928362', 'lastCheck': '4.8', 'valid': True}} from=internal, task_id=110fbcb2-6cde-4e95-a381-79881fb17ac0 (api:52) 2017-11-10 10:52:07,557+0000 INFO (jsonrpc/7) [vdsm.api] START getSpmStatus(spUUID=u'5a0287ce-0233-0170-00a2-0000000001d8', options=None) from=::ffff:192.168.16.9,42836, flow_id=55253850, task_id=5e78c011-2d1b-4f50-9445-62fbc4628ae1 (api:46) 2017-11-10 10:52:07,566+0000 INFO (jsonrpc/7) [vdsm.api] FINISH getSpmStatus return={'spm_st': {'spmId': 1, 'spmStatus': 'SPM', 'spmLver': 2L}} from=::ffff:192.168.16.9,42836, flow_id=55253850, task_id=5e78c011-2d1b-4f50-9445-62fbc4628ae1 (api:52) 2017-11-10 10:52:07,567+0000 INFO (jsonrpc/7) [jsonrpc.JsonRpcServer] RPC call StoragePool.getSpmStatus succeeded in 0.02 seconds (__init__:539) 2017-11-10 10:52:08,576+0000 INFO (jsonrpc/4) [vdsm.api] START getStoragePoolInfo(spUUID=u'5a0287ce-0233-0170-00a2-0000000001d8', options=None) from=::ffff:192.168.16.9,42838, flow_id=55253850, task_id=6a4f93ec-0ed1-4017-b808-5f246a7b55cd (api:46) 2017-11-10 10:52:08,587+0000 INFO (jsonrpc/4) [vdsm.api] FINISH getStoragePoolInfo return={'info': {'name': 'No Description', 'isoprefix': '', 'pool_status': 'connected', 'lver': 2L, 'domains': u'5eed853b-09ee-430d-bde2-c37394c1ff6c:Active', 'master_uuid': u'5eed853b-09ee-430d-bde2-c37394c1ff6c', 'version': '4', 'spm_id': 1, 'type': 'GLUSTERFS', 'master_ver': 1}, 'dominfo': {u'5eed853b-09ee-430d-bde2-c37394c1ff6c': {'status': u'Active', 'diskfree': '912109731840', 'isoprefix': '', 'alerts': [], 'disktotal': '912151019520', 'version': 4}}} from=::ffff:192.168.16.9,42838, flow_id=55253850, task_id=6a4f93ec-0ed1-4017-b808-5f246a7b55cd (api:52) 2017-11-10 10:52:08,588+0000 INFO (jsonrpc/4) [jsonrpc.JsonRpcServer] RPC call StoragePool.getInfo succeeded in 0.02 seconds (__init__:539) 2017-11-10 10:52:09,348+0000 INFO (jsonrpc/1) [jsonrpc.JsonRpcServer] RPC call Host.getAllVmStats succeeded in 0.00 seconds (__init__:539) 2017-11-10 10:52:10,460+0000 INFO (jsonrpc/2) [vdsm.api] START repoStats(options=None) from=::ffff:192.168.16.9,42836, flow_id=3144af03, task_id=2f5a4461-b4f7-4cd6-95db-b158cd082aae (api:46) 2017-11-10 10:52:10,460+0000 INFO (jsonrpc/2) [vdsm.api] FINISH repoStats return={u'5eed853b-09ee-430d-bde2-c37394c1ff6c': {'code': 0, 'actual': True, 'version': 4, 'acquired': True, 'delay': '0.00094699', 'lastCheck': '4.4', 'valid': True}} from=::ffff:192.168.16.9,42836, flow_id=3144af03, task_id=2f5a4461-b4f7-4cd6-95db-b158cd082aae (api:52) 2017-11-10 10:52:10,468+0000 INFO (jsonrpc/2) [jsonrpc.JsonRpcServer] RPC call Host.getStats succeeded in 0.01 seconds (__init__:539) 2017-11-10 10:52:11,240+0000 INFO (jsonrpc/3) [jsonrpc.JsonRpcServer] RPC call Host.getAllVmStats succeeded in 0.00 seconds (__init__:539) 2017-11-10 10:52:15,927+0000 INFO (periodic/0) [vdsm.api] START repoStats(options=None) from=internal, task_id=f1359cba-4f75-410f-8aeb-a3297af27422 (api:46) 2017-11-10 10:52:15,928+0000 INFO (periodic/0) [vdsm.api] FINISH repoStats return={u'5eed853b-09ee-430d-bde2-c37394c1ff6c': {'code': 0, 'actual': True, 'version': 4, 'acquired': True, 'delay': '0.000780232', 'lastCheck': '1.9', 'valid': True}} from=internal, task_id=f1359cba-4f75-410f-8aeb-a3297af27422 (api:52) 2017-11-10 10:52:19,351+0000 INFO (jsonrpc/0) [vdsm.api] START getSpmStatus(spUUID=u'5a0287ce-0233-0170-00a2-0000000001d8', options=None) from=::ffff:192.168.16.9,42836, flow_id=47e88296, task_id=441cdb4e-2752-4ccf-832f-a6c295f2f793 (api:46) 2017-11-10 10:52:19,359+0000 INFO (jsonrpc/0) [vdsm.api] FINISH getSpmStatus return={'spm_st': {'spmId': 1, 'spmStatus': 'SPM', 'spmLver': 2L}} from=::ffff:192.168.16.9,42836, flow_id=47e88296, task_id=441cdb4e-2752-4ccf-832f-a6c295f2f793 (api:52) 2017-11-10 10:52:19,359+0000 INFO (jsonrpc/0) [jsonrpc.JsonRpcServer] RPC call StoragePool.getSpmStatus succeeded in 0.01 seconds (__init__:539) 2017-11-10 10:52:20,367+0000 INFO (jsonrpc/6) [vdsm.api] START getStoragePoolInfo(spUUID=u'5a0287ce-0233-0170-00a2-0000000001d8', options=None) from=::ffff:192.168.16.9,42838, flow_id=47e88296, task_id=32a0366d-c576-4400-97e5-26bfd3b9561a (api:46) 2017-11-10 10:52:20,378+0000 INFO (jsonrpc/6) [vdsm.api] FINISH getStoragePoolInfo return={'info': {'name': 'No Description', 'isoprefix': '', 'pool_status': 'connected', 'lver': 2L, 'domains': u'5eed853b-09ee-430d-bde2-c37394c1ff6c:Active', 'master_uuid': u'5eed853b-09ee-430d-bde2-c37394c1ff6c', 'version': '4', 'spm_id': 1, 'type': 'GLUSTERFS', 'master_ver': 1}, 'dominfo': {u'5eed853b-09ee-430d-bde2-c37394c1ff6c': {'status': u'Active', 'diskfree': '912109731840', 'isoprefix': '', 'alerts': [], 'disktotal': '912151019520', 'version': 4}}} from=::ffff:192.168.16.9,42838, flow_id=47e88296, task_id=32a0366d-c576-4400-97e5-26bfd3b9561a (api:52) 2017-11-10 10:52:20,379+0000 INFO (jsonrpc/6) [jsonrpc.JsonRpcServer] RPC call StoragePool.getInfo succeeded in 0.02 seconds (__init__:539) 2017-11-10 10:52:25,354+0000 INFO (jsonrpc/5) [jsonrpc.JsonRpcServer] RPC call Host.getAllVmStats succeeded in 0.00 seconds (__init__:539) 2017-11-10 10:52:26,259+0000 INFO (jsonrpc/7) [jsonrpc.JsonRpcServer] RPC call Host.getAllVmStats succeeded in 0.00 seconds (__init__:539) 2017-11-10 10:52:26,492+0000 INFO (jsonrpc/4) [vdsm.api] START repoStats(options=None) from=::ffff:192.168.16.9,42836, flow_id=47536cd8, task_id=33cbd1f4-d8b8-44da-809b-776802d6f9cd (api:46) 2017-11-10 10:52:26,493+0000 INFO (jsonrpc/4) [vdsm.api] FINISH repoStats return={u'5eed853b-09ee-430d-bde2-c37394c1ff6c': {'code': 0, 'actual': True, 'version': 4, 'acquired': True, 'delay': '0.000919276', 'lastCheck': '0.4', 'valid': True}} from=::ffff:192.168.16.9,42836, flow_id=47536cd8, task_id=33cbd1f4-d8b8-44da-809b-776802d6f9cd (api:52) 2017-11-10 10:52:26,500+0000 INFO (jsonrpc/4) [jsonrpc.JsonRpcServer] RPC call Host.getStats succeeded in 0.01 seconds (__init__:539) [root@node1 vdsm]# engine log: 2017-11-10 10:51:32,474Z INFO [org.ovirt.engine.core.bll.storage.domain.AttachStorageDomainToPoolCommand] (default task-4) [05e7dbb5-bad3-484f-b364-ca635fe1f20b] Lock Acquired to object 'EngineLock:{exclusiveLocks='[875e1af4-ba14-4255-b6ad-c3031672df93=STORAGE]', sharedLocks=''}' 2017-11-10 10:51:32,504Z INFO [org.ovirt.engine.core.bll.storage.domain.AttachStorageDomainToPoolCommand] (org.ovirt.thread.pool-6-thread-45) [05e7dbb5-bad3-484f-b364-ca635fe1f20b] Running command: AttachStorageDomainToPoolCommand internal: false. Entities affected : ID: 875e1af4-ba14-4255-b6ad-c3031672df93 Type: StorageAction group MANIPULATE_STORAGE_DOMAIN with role type ADMIN, ID: 5a0287ce-0233-0170-00a2-0000000001d8 Type: StoragePoolAction group MANIPULATE_STORAGE_DOMAIN with role type ADMIN 2017-11-10 10:51:32,520Z INFO [org.ovirt.engine.core.bll.storage.connection.ConnectStorageToVdsCommand] (org.ovirt.thread.pool-6-thread-46) [1cccae15] Running command: ConnectStorageToVdsCommand internal: true. Entities affected : ID: aaa00000-0000-0000-0000-123456789aaa Type: SystemAction group CREATE_STORAGE_DOMAIN with role type ADMIN 2017-11-10 10:51:32,523Z INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStorageServerVDSCommand] (org.ovirt.thread.pool-6-thread-46) [1cccae15] START, ConnectStorageServerVDSCommand(HostName = node1, StorageServerConnectionManagementVDSParameters:{runAsync='true', hostId='630b72af-880c-4d08-9e03-68fc16a6aadd', storagePoolId='00000000-0000-0000-0000-000000000000', storageType='NFS', connectionList='[StorageServerConnections:{id='ba9e9b72-cade-4e16-82fe-237ff7143b3b', connection='engine.futurcargo.com:/home/iso', iqn='null', vfsType='null', mountOptions='null', nfsVersion='null', nfsRetrans='null', nfsTimeo='null', iface='null', netIfaceName='null'}]'}), log id: 67ed8236 2017-11-10 10:51:33,546Z INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStorageServerVDSCommand] (org.ovirt.thread.pool-6-thread-46) [1cccae15] FINISH, ConnectStorageServerVDSCommand, return: {ba9e9b72-cade-4e16-82fe-237ff7143b3b=0}, log id: 67ed8236 2017-11-10 10:51:33,549Z INFO [org.ovirt.engine.core.vdsbroker.irsbroker.AttachStorageDomainVDSCommand] (org.ovirt.thread.pool-6-thread-45) [05e7dbb5-bad3-484f-b364-ca635fe1f20b] START, AttachStorageDomainVDSCommand( AttachStorageDomainVDSCommandParameters:{runAsync='true', storagePoolId='5a0287ce-0233-0170-00a2-0000000001d8', ignoreFailoverLimit='false', storageDomainId='875e1af4-ba14-4255-b6ad-c3031672df93'}), log id: 2f387159 2017-11-10 10:51:34,725Z ERROR [org.ovirt.engine.core.vdsbroker.irsbroker.AttachStorageDomainVDSCommand] (org.ovirt.thread.pool-6-thread-45) [05e7dbb5-bad3-484f-b364-ca635fe1f20b] Failed in 'AttachStorageDomainVDS' method 2017-11-10 10:51:34,740Z ERROR [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (org.ovirt.thread.pool-6-thread-45) [05e7dbb5-bad3-484f-b364-ca635fe1f20b] EVENT_ID: IRS_BROKER_COMMAND_FAILURE(10,803), Correlation ID: null, Call Stack: null, Custom ID: null, Custom Event ID: -1, Message: VDSM command AttachStorageDomainVDS failed: Error in storage domain action: (u'sdUUID=875e1af4-ba14-4255-b6ad-c3031672df93, spUUID=5a0287ce-0233-0170-00a2-0000000001d8',) 2017-11-10 10:51:34,740Z ERROR [org.ovirt.engine.core.vdsbroker.irsbroker.AttachStorageDomainVDSCommand] (org.ovirt.thread.pool-6-thread-45) [05e7dbb5-bad3-484f-b364-ca635fe1f20b] Command 'AttachStorageDomainVDSCommand( AttachStorageDomainVDSCommandParameters:{runAsync='true', storagePoolId='5a0287ce-0233-0170-00a2-0000000001d8', ignoreFailoverLimit='false', storageDomainId='875e1af4-ba14-4255-b6ad-c3031672df93'})' execution failed: IRSGenericException: IRSErrorException: Failed to AttachStorageDomainVDS, error = Error in storage domain action: (u'sdUUID=875e1af4-ba14-4255-b6ad-c3031672df93, spUUID=5a0287ce-0233-0170-00a2-0000000001d8',), code = 350 2017-11-10 10:51:34,740Z INFO [org.ovirt.engine.core.vdsbroker.irsbroker.AttachStorageDomainVDSCommand] (org.ovirt.thread.pool-6-thread-45) [05e7dbb5-bad3-484f-b364-ca635fe1f20b] FINISH, AttachStorageDomainVDSCommand, log id: 2f387159 2017-11-10 10:51:34,740Z ERROR [org.ovirt.engine.core.bll.storage.domain.AttachStorageDomainToPoolCommand] (org.ovirt.thread.pool-6-thread-45) [05e7dbb5-bad3-484f-b364-ca635fe1f20b] Command 'org.ovirt.engine.core.bll.storage.domain.AttachStorageDomainToPoolCommand' failed: EngineException: org.ovirt.engine.core.vdsbroker.irsbroker.IrsOperationFailedNoFailoverException: IRSGenericException: IRSErrorException: Failed to AttachStorageDomainVDS, error = Error in storage domain action: (u'sdUUID=875e1af4-ba14-4255-b6ad-c3031672df93, spUUID=5a0287ce-0233-0170-00a2-0000000001d8',), code = 350 (Failed with error StorageDomainActionError and code 350) 2017-11-10 10:51:34,741Z INFO [org.ovirt.engine.core.bll.storage.domain.AttachStorageDomainToPoolCommand] (org.ovirt.thread.pool-6-thread-45) [05e7dbb5-bad3-484f-b364-ca635fe1f20b] Command [id=a392af75-7ebe-499a-b2ed-765651834557]: Compensating NEW_ENTITY_ID of org.ovirt.engine.core.common.businessentities.StoragePoolIsoMap; snapshot: StoragePoolIsoMapId:{storagePoolId='5a0287ce-0233-0170-00a2-0000000001d8', storageId='875e1af4-ba14-4255-b6ad-c3031672df93'}. 2017-11-10 10:51:34,746Z ERROR [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (org.ovirt.thread.pool-6-thread-45) [05e7dbb5-bad3-484f-b364-ca635fe1f20b] EVENT_ID: USER_ATTACH_STORAGE_DOMAIN_TO_POOL_FAILED(963), Correlation ID: 05e7dbb5-bad3-484f-b364-ca635fe1f20b, Job ID: 7f3f27ce-4d45-4b3b-a9ee-c25795658ee4, Call Stack: null, Custom ID: null, Custom Event ID: -1, Message: Failed to attach Storage Domain ISO_DOMAIN to Data Center Default. (User: admin@internal-authz) 2017-11-10 10:51:34,748Z INFO [org.ovirt.engine.core.bll.storage.domain.AttachStorageDomainToPoolCommand] (org.ovirt.thread.pool-6-thread-45) [05e7dbb5-bad3-484f-b364-ca635fe1f20b] Lock freed to object 'EngineLock:{exclusiveLocks='[875e1af4-ba14-4255-b6ad-c3031672df93=STORAGE]', sharedLocks=''}' Jose Ferradeira http://www.logicworks.pt _______________________________________________ Users mailing list Users@ovirt.org http://lists.ovirt.org/mailman/listinfo/users ------=_Part_1147272_908603913.1510346638388 Content-Type: text/html; charset=utf-8 Content-Transfer-Encoding: quoted-printable <html><body><div style=3D"font-family: Times New Roman; font-size: 10pt; co= lor: #000000"><div>I just Destroy the ISO Domain, remove the content of the= directory.<br></div><div>Create a new iso domain, and it worked.<br data-m= ce-bogus=3D"1"></div><div><br></div><hr id=3D"zwchr" data-marker=3D"__DIVID= ER__"><div data-marker=3D"__HEADERS__"><b>De: </b>suporte@logicworks.pt<br>= <b>Para: </b>users@ovirt.org<br><b>Enviadas: </b>Sexta-feira, 10 De Novembr= o de 2017 11:01:39<br><b>Assunto: </b>[ovirt-users] attach iso domain faile= d<br></div><div><br></div><div data-marker=3D"__QUOTED_TEXT__"><div style= =3D"font-family: Times New Roman; font-size: 10pt; color: #000000;" data-mc= e-style=3D"font-family: Times New Roman; font-size: 10pt; color: #000000;">= <div><div style=3D"font-family: Times New Roman; font-size: 10pt; color: #0= 00000;" data-mce-style=3D"font-family: Times New Roman; font-size: 10pt; co= lor: #000000;"><div>Hi,</div><br><div>I have engine installed on one machin= e and host on another, running Version 4.1.7.6-1.el7.centos</div><div>= Everything looks ok except the iso domain I cannot attach it to the data ce= nter. Message on the engine dashboard:</div><br><div>Failed to attach Stora= ge Domain ISO_DOMAIN to Data Center Default. (User: admin@internal-authz)</= div><div>VDSM command AttachStorageDomainVDS failed: Error in storage domai= n action: (u'sdUUID=3D875e1af4-ba14-4255-b6ad-c3031672df93, spUUID=3D5a0287= ce-0233-0170-00a2-0000000001d8',)</div><br><div>Checking the configuration = everything looks ok:</div><div>[root@engine ~]# grep iso /etc/ovirt-engine-= setup.conf.d/20-setup-ovirt-post.conf<br>OVESETUP_CONFIG/isoDomainName=3Dst= r:ISO_DOMAIN<br>OVESETUP_CONFIG/isoDomainSdUuid=3Dstr:875e1af4-ba14-4255-b6= ad-c3031672df93<br>OVESETUP_CONFIG/isoDomainMountPoint=3Dstr:/home/iso<br>O= VESETUP_CONFIG/isoDomainExists=3Dbool:True<br>OVESETUP_CONFIG/isoDomainStor= ageDir=3Dstr:/home/iso/875e1af4-ba14-4255-b6ad-c3031672df93/images/11111111= -1111-1111-1111-111111111111<br></div><br><div>[root@engine ~]# ovirt-iso-u= ploader list<br>Please provide the REST API password for the admin@internal= oVirt Engine user (CTRL+D to abort):<br>ISO Storage Domain Name | ISO Doma= in Status<br>ISO_DOMAIN | ok<br></div><br><div>[root@node1 ~]# vdsClient -s= 0 getStorageDomainsList<br>5eed853b-09ee-430d-bde2-c37394c1ff6c<br></div><= br><br><div>I can mount the export manually</div><br><br><div>Don't know wh= ere to look more. Any idea? thanks</div><br><br><div>vdsm log:</div><div>20= 17-11-10 10:51:00,839+0000 INFO (periodic/3) [vdsm.api] START repoStats(opt= ions=3DNone) from=3Dinternal, task_id=3Df0f5161a-b3c1-4534-a33a-cf5d17ab8c9= c (api:46)<br>2017-11-10 10:51:00,840+0000 INFO (periodic/3) [vdsm.api] FIN= ISH repoStats return=3D{u'5eed853b-09ee-430d-bde2-c37394c1ff6c': {'code': 0= , 'actual': True, 'version': 4, 'acquired': True, 'delay': '0.000890471', '= lastCheck': '4.8', 'valid': True}} from=3Dinternal, task_id=3Df0f5161a-b3c1= -4534-a33a-cf5d17ab8c9c (api:52)<br>2017-11-10 10:51:05,323+0000 INFO (json= rpc/7) [jsonrpc.JsonRpcServer] RPC call Host.getAllVmStats succeeded in 0.0= 0 seconds (__init__:539)<br>2017-11-10 10:51:07,277+0000 INFO (jsonrpc/4) [= vdsm.api] START repoStats(options=3DNone) from=3D::ffff:192.168.16.9,42836,= flow_id=3D399705b5, task_id=3D998fe1bc-eff0-4ba7-942c-458906c5e1f1 (api:46= )<br>2017-11-10 10:51:07,277+0000 INFO (jsonrpc/4) [vdsm.api] FINISH repoSt= ats return=3D{u'5eed853b-09ee-430d-bde2-c37394c1ff6c': {'code': 0, 'actual'= : True, 'version': 4, 'acquired': True, 'delay': '0.000844547', 'lastCheck'= : '1.2', 'valid': True}} from=3D::ffff:192.168.16.9,42836, flow_id=3D399705= b5, task_id=3D998fe1bc-eff0-4ba7-942c-458906c5e1f1 (api:52)<br>2017-11-10 1= 0:51:07,285+0000 INFO (jsonrpc/4) [jsonrpc.JsonRpcServer] RPC call Host.get= Stats succeeded in 0.01 seconds (__init__:539)<br>2017-11-10 10:51:07,345+0= 000 INFO (jsonrpc/1) [vdsm.api] START getSpmStatus(spUUID=3Du'5a0287ce-0233= -0170-00a2-0000000001d8', options=3DNone) from=3D::ffff:192.168.16.9,42836,= flow_id=3D4b670edd, task_id=3Da6baf012-6dbb-47d6-9f8e-457e42bbc7d7 (api:46= )<br>2017-11-10 10:51:07,352+0000 INFO (jsonrpc/1) [vdsm.api] FINISH getSpm= Status return=3D{'spm_st': {'spmId': 1, 'spmStatus': 'SPM', 'spmLver': 2L}}= from=3D::ffff:192.168.16.9,42836, flow_id=3D4b670edd, task_id=3Da6baf012-6= dbb-47d6-9f8e-457e42bbc7d7 (api:52)<br>2017-11-10 10:51:07,352+0000 INFO (j= sonrpc/1) [jsonrpc.JsonRpcServer] RPC call StoragePool.getSpmStatus succeed= ed in 0.01 seconds (__init__:539)<br>2017-11-10 10:51:08,410+0000 INFO (jso= nrpc/2) [vdsm.api] START getStoragePoolInfo(spUUID=3Du'5a0287ce-0233-0170-0= 0a2-0000000001d8', options=3DNone) from=3D::ffff:192.168.16.9,42838, flow_i= d=3D4b670edd, task_id=3D4d623b87-105f-47ef-8385-7d7391378906 (api:46)<br>20= 17-11-10 10:51:08,421+0000 INFO (jsonrpc/2) [vdsm.api] FINISH getStoragePoo= lInfo return=3D{'info': {'name': 'No Description', 'isoprefix': '', 'pool_s= tatus': 'connected', 'lver': 2L, 'domains': u'5eed853b-09ee-430d-bde2-c3739= 4c1ff6c:Active', 'master_uuid': u'5eed853b-09ee-430d-bde2-c37394c1ff6c', 'v= ersion': '4', 'spm_id': 1, 'type': 'GLUSTERFS', 'master_ver': 1}, 'dominfo'= : {u'5eed853b-09ee-430d-bde2-c37394c1ff6c': {'status': u'Active', 'diskfree= ': '912109731840', 'isoprefix': '', 'alerts': [], 'disktotal': '91215101952= 0', 'version': 4}}} from=3D::ffff:192.168.16.9,42838, flow_id=3D4b670edd, t= ask_id=3D4d623b87-105f-47ef-8385-7d7391378906 (api:52)<br>2017-11-10 10:51:= 08,422+0000 INFO (jsonrpc/2) [jsonrpc.JsonRpcServer] RPC call StoragePool.g= etInfo succeeded in 0.01 seconds (__init__:539)<br>2017-11-10 10:51:11,159+= 0000 INFO (jsonrpc/3) [jsonrpc.JsonRpcServer] RPC call Host.getAllVmStats s= ucceeded in 0.00 seconds (__init__:539)<br>2017-11-10 10:51:15,859+0000 INF= O (periodic/0) [vdsm.api] START repoStats(options=3DNone) from=3Dinternal, = task_id=3D8da65b6e-23ba-40fb-82fd-287645bb05cf (api:46)<br>2017-11-10 10:51= :15,859+0000 INFO (periodic/0) [vdsm.api] FINISH repoStats return=3D{u'5eed= 853b-09ee-430d-bde2-c37394c1ff6c': {'code': 0, 'actual': True, 'version': 4= , 'acquired': True, 'delay': '0.000718255', 'lastCheck': '1.8', 'valid': Tr= ue}} from=3Dinternal, task_id=3D8da65b6e-23ba-40fb-82fd-287645bb05cf (api:5= 2)<br>2017-11-10 10:51:19,434+0000 INFO (jsonrpc/0) [vdsm.api] START getSpm= Status(spUUID=3Du'5a0287ce-0233-0170-00a2-0000000001d8', options=3DNone) fr= om=3D::ffff:192.168.16.9,42836, flow_id=3D32cb4291, task_id=3Dc42d1ce1-d82c= -47ad-874f-6d73142e49c6 (api:46)<br>2017-11-10 10:51:19,443+0000 INFO (json= rpc/0) [vdsm.api] FINISH getSpmStatus return=3D{'spm_st': {'spmId': 1, 'spm= Status': 'SPM', 'spmLver': 2L}} from=3D::ffff:192.168.16.9,42836, flow_id= =3D32cb4291, task_id=3Dc42d1ce1-d82c-47ad-874f-6d73142e49c6 (api:52)<br>201= 7-11-10 10:51:19,444+0000 INFO (jsonrpc/0) [jsonrpc.JsonRpcServer] RPC call= StoragePool.getSpmStatus succeeded in 0.01 seconds (__init__:539)<br>2017-= 11-10 10:51:20,453+0000 INFO (jsonrpc/6) [vdsm.api] START getStoragePoolInf= o(spUUID=3Du'5a0287ce-0233-0170-00a2-0000000001d8', options=3DNone) from=3D= ::ffff:192.168.16.9,42838, flow_id=3D32cb4291, task_id=3D72cdacd6-5133-4554= -8ecc-86f11a094064 (api:46)<br>2017-11-10 10:51:20,464+0000 INFO (jsonrpc/6= ) [vdsm.api] FINISH getStoragePoolInfo return=3D{'info': {'name': 'No Descr= iption', 'isoprefix': '', 'pool_status': 'connected', 'lver': 2L, 'domains'= : u'5eed853b-09ee-430d-bde2-c37394c1ff6c:Active', 'master_uuid': u'5eed853b= -09ee-430d-bde2-c37394c1ff6c', 'version': '4', 'spm_id': 1, 'type': 'GLUSTE= RFS', 'master_ver': 1}, 'dominfo': {u'5eed853b-09ee-430d-bde2-c37394c1ff6c'= : {'status': u'Active', 'diskfree': '912109731840', 'isoprefix': '', 'alert= s': [], 'disktotal': '912151019520', 'version': 4}}} from=3D::ffff:192.168.= 16.9,42838, flow_id=3D32cb4291, task_id=3D72cdacd6-5133-4554-8ecc-86f11a094= 064 (api:52)<br>2017-11-10 10:51:20,465+0000 INFO (jsonrpc/6) [jsonrpc.Json= RpcServer] RPC call StoragePool.getInfo succeeded in 0.01 seconds (__init__= :539)<br>2017-11-10 10:51:21,329+0000 INFO (jsonrpc/5) [jsonrpc.JsonRpcServ= er] RPC call Host.getAllVmStats succeeded in 0.00 seconds (__init__:539)<br= ptions=3DNone) from=3D::ffff:192.168.16.9,42836, flow_id=3D5eb26f2b, task_i= d=3Dc4320507-69ec-457d-a51b-e898702de247 (api:46)<br>2017-11-10 10:51:22,36= 0+0000 INFO (jsonrpc/7) [vdsm.api] FINISH repoStats return=3D{u'5eed853b-09= ee-430d-bde2-c37394c1ff6c': {'code': 0, 'actual': True, 'version': 4, 'acqu= ired': True, 'delay': '0.000718255', 'lastCheck': '6.3', 'valid': True}} fr= om=3D::ffff:192.168.16.9,42836, flow_id=3D5eb26f2b, task_id=3Dc4320507-69ec= -457d-a51b-e898702de247 (api:52)<br>2017-11-10 10:51:22,368+0000 INFO (json= rpc/7) [jsonrpc.JsonRpcServer] RPC call Host.getStats succeeded in 0.01 sec= onds (__init__:539)<br>2017-11-10 10:51:26,178+0000 INFO (jsonrpc/4) [jsonr= pc.JsonRpcServer] RPC call Host.getAllVmStats succeeded in 0.00 seconds (__= init__:539)<br>2017-11-10 10:51:30,878+0000 INFO (periodic/2) [vdsm.api] ST= ART repoStats(options=3DNone) from=3Dinternal, task_id=3Df62ad6de-75ad-4a99= -bb4d-2854b5ff85c6 (api:46)<br>2017-11-10 10:51:30,878+0000 INFO (periodic/= 2) [vdsm.api] FINISH repoStats return=3D{u'5eed853b-09ee-430d-bde2-c37394c1= ff6c': {'code': 0, 'actual': True, 'version': 4, 'acquired': True, 'delay':= '0.000956708', 'lastCheck': '4.8', 'valid': True}} from=3Dinternal, task_i= d=3Df62ad6de-75ad-4a99-bb4d-2854b5ff85c6 (api:52)<br>2017-11-10 10:51:31,43= 6+0000 INFO (jsonrpc/1) [vdsm.api] START getSpmStatus(spUUID=3Du'5a0287ce-0= 233-0170-00a2-0000000001d8', options=3DNone) from=3D::ffff:192.168.16.9,428= 36, flow_id=3D11ee2968, task_id=3D738c51fb-387e-47b3-a1ad-03fc900f749f (api= :46)<br>2017-11-10 10:51:31,444+0000 INFO (jsonrpc/1) [vdsm.api] FINISH get= SpmStatus return=3D{'spm_st': {'spmId': 1, 'spmStatus': 'SPM', 'spmLver': 2= L}} from=3D::ffff:192.168.16.9,42836, flow_id=3D11ee2968, task_id=3D738c51f= b-387e-47b3-a1ad-03fc900f749f (api:52)<br>2017-11-10 10:51:31,445+0000 INFO= (jsonrpc/1) [jsonrpc.JsonRpcServer] RPC call StoragePool.getSpmStatus succ= eeded in 0.01 seconds (__init__:539)<br>2017-11-10 10:51:32,454+0000 INFO (= jsonrpc/2) [vdsm.api] START getStoragePoolInfo(spUUID=3Du'5a0287ce-0233-017= 0-00a2-0000000001d8', options=3DNone) from=3D::ffff:192.168.16.9,42838, flo= w_id=3D11ee2968, task_id=3D4f010678-55ba-4ead-84bc-734763662964 (api:46)<br= tring>", line 2, in attachStorageDomain<br> File "/usr/lib/python2.7/sit= e-packages/vdsm/common/api.py", line 48, in method<br> ret =3D func(*args, = **kwargs)<br> File "/usr/share/vdsm/storage/hsm.py", line 1152, in attachSt= orageDomain<br> pool.attachSD(sdUUID)<br> File "/usr/lib/python2.7/site-pac= kages/vdsm/storage/securable.py", line 79, in wrapper<br> return method(sel= f, *args, **kwargs)<br> File "/usr/share/vdsm/storage/sp.py", line 949, in = attachSD<br> dom =3D sdCache.produce(sdUUID)<br> File "/usr/share/vdsm/stor= age/sdc.py", line 112, in produce<br> domain.getRealDomain()<br> File "/usr= /share/vdsm/storage/sdc.py", line 53, in getRealDomain<br> return self._cac= he._realProduce(self._sdUUID)<br> File "/usr/share/vdsm/storage/sdc.py", li= ne 136, in _realProduce<br> domain =3D self._findDomain(sdUUID)<br> File "/= usr/share/vdsm/storage/sdc.py", line 153, in _findDomain<br> return findMet= hod(sdUUID)<br> File "/usr/share/vdsm/storage/nfsSD.py", line 126, in findD= omain<br> return NfsStorageDomain(NfsStorageDomain.findDomainPath(sdUUID))<= br> File "/usr/share/vdsm/storage/fileSD.py", line 364, in __init__<br> man= ifest =3D self.manifestClass(domainPath)<br> File "/usr/share/vdsm/storage/= fileSD.py", line 171, in __init__<br> sd.StorageDomainManifest.__init__(sel= f, sdUUID, domaindir, metadata)<br> File "/usr/share/vdsm/storage/sd.py", l= ine 332, in __init__<br> self._domainLock =3D self._makeDomainLock()<br> Fi= le "/usr/share/vdsm/storage/sd.py", line 526, in _makeDomainLock<br> domVer= sion =3D self.getVersion()<br> File "/usr/share/vdsm/storage/sd.py", line 4= 03, in getVersion<br> return self.getMetaParam(DMDK_VERSION)<br> File "/usr= /share/vdsm/storage/sd.py", line 400, in getMetaParam<br> return self._meta= data[key]<br> File "/usr/lib/python2.7/site-packages/vdsm/storage/persisten= t.py", line 91, in __getitem__<br> return dec(self._dict[key])<br> File "/u= sr/lib/python2.7/site-packages/vdsm/storage/persistent.py", line 203, in __= getitem__<br> raise KeyError(key)<br>KeyError: 'VERSION'<br>2017-11-10 10:5= 1:35,142+0000 INFO (jsonrpc/0) [storage.TaskManager.Task] (Task=3D'472c9bfe= -0751-4240-b5c4-e867cb3d0cfb') aborting: Task is aborted: u"'VERSION'" - co= de 100 (task:1177)<br>2017-11-10 10:51:35,143+0000 ERROR (jsonrpc/0) [stora= ge.Dispatcher] FINISH attachStorageDomain error=3D'VERSION' (dispatcher:85)= <br>Traceback (most recent call last):<br> File "/usr/share/vdsm/storage/di= spatcher.py", line 72, in wrapper<br> result =3D ctask.prepare(func, *args,= **kwargs)<br> File "/usr/share/vdsm/storage/task.py", line 105, in wrapper= <br> return m(self, *a, **kw)<br> File "/usr/share/vdsm/storage/task.py", l= ine 1185, in prepare<br> raise self.error<br>KeyError: 'VERSION'<br>2017-11= -10 10:51:35,144+0000 INFO (jsonrpc/0) [jsonrpc.JsonRpcServer] RPC call Sto= rageDomain.attach failed (error 350) in 0.17 seconds (__init__:539)<br>2017= -11-10 10:51:37,336+0000 INFO (jsonrpc/6) [jsonrpc.JsonRpcServer] RPC call = Host.getAllVmStats succeeded in 0.00 seconds (__init__:539)<br>2017-11-10 1= 0:51:38,387+0000 INFO (jsonrpc/5) [vdsm.api] START repoStats(options=3DNone= ) from=3D::ffff:192.168.16.9,42836, flow_id=3D564f788f, task_id=3D0c08c128-= 7ec8-4981-95a8-5bfeeed126d6 (api:46)<br>2017-11-10 10:51:38,388+0000 INFO (= jsonrpc/5) [vdsm.api] FINISH repoStats return=3D{u'5eed853b-09ee-430d-bde2-= c37394c1ff6c': {'code': 0, 'actual': True, 'version': 4, 'acquired': True, = 'delay': '0.000855589', 'lastCheck': '2.3', 'valid': True}} from=3D::ffff:1= 92.168.16.9,42836, flow_id=3D564f788f, task_id=3D0c08c128-7ec8-4981-95a8-5b= feeed126d6 (api:52)<br>2017-11-10 10:51:38,396+0000 INFO (jsonrpc/5) [jsonr= pc.JsonRpcServer] RPC call Host.getStats succeeded in 0.01 seconds (__init_= _:539)<br>2017-11-10 10:51:41,198+0000 INFO (jsonrpc/7) [jsonrpc.JsonRpcSer= ver] RPC call Host.getAllVmStats succeeded in 0.00 seconds (__init__:539)<b= r>2017-11-10 10:51:43,474+0000 INFO (jsonrpc/4) [vdsm.api] START getSpmStat= us(spUUID=3Du'5a0287ce-0233-0170-00a2-0000000001d8', options=3DNone) from= =3D::ffff:192.168.16.9,42836, flow_id=3D14f15c4b, task_id=3D63fb7235-56eb-4= acd-8bf6-e04a701e8d83 (api:46)<br>2017-11-10 10:51:43,483+0000 INFO (jsonrp= c/4) [vdsm.api] FINISH getSpmStatus return=3D{'spm_st': {'spmId': 1, 'spmSt= atus': 'SPM', 'spmLver': 2L}} from=3D::ffff:192.168.16.9,42836, flow_id=3D1= 4f15c4b, task_id=3D63fb7235-56eb-4acd-8bf6-e04a701e8d83 (api:52)<br>2017-11= -10 10:51:43,484+0000 INFO (jsonrpc/4) [jsonrpc.JsonRpcServer] RPC call Sto= ragePool.getSpmStatus succeeded in 0.01 seconds (__init__:539)<br>2017-11-1= 0 10:51:44,493+0000 INFO (jsonrpc/1) [vdsm.api] START getStoragePoolInfo(sp= UUID=3Du'5a0287ce-0233-0170-00a2-0000000001d8', options=3DNone) from=3D::ff= ff:192.168.16.9,42838, flow_id=3D14f15c4b, task_id=3De0fe10ef-cc9c-4e3c-a22= a-1f9ca042e75e (api:46)<br>2017-11-10 10:51:44,504+0000 INFO (jsonrpc/1) [v= dsm.api] FINISH getStoragePoolInfo return=3D{'info': {'name': 'No Descripti= on', 'isoprefix': '', 'pool_status': 'connected', 'lver': 2L, 'domains': u'= 5eed853b-09ee-430d-bde2-c37394c1ff6c:Active', 'master_uuid': u'5eed853b-09e= e-430d-bde2-c37394c1ff6c', 'version': '4', 'spm_id': 1, 'type': 'GLUSTERFS'= , 'master_ver': 1}, 'dominfo': {u'5eed853b-09ee-430d-bde2-c37394c1ff6c': {'= status': u'Active', 'diskfree': '912109731840', 'isoprefix': '', 'alerts': = [], 'disktotal': '912151019520', 'version': 4}}} from=3D::ffff:192.168.16.9= ,42838, flow_id=3D14f15c4b, task_id=3De0fe10ef-cc9c-4e3c-a22a-1f9ca042e75e = (api:52)<br>2017-11-10 10:51:44,505+0000 INFO (jsonrpc/1) [jsonrpc.JsonRpcS= erver] RPC call StoragePool.getInfo succeeded in 0.01 seconds (__init__:539= )<br>2017-11-10 10:51:45,897+0000 INFO (periodic/1) [vdsm.api] START repoSt= ats(options=3DNone) from=3Dinternal, task_id=3Dc0283b39-b3d9-4745-ae24-791e= 6bddde42 (api:46)<br>2017-11-10 10:51:45,897+0000 INFO (periodic/1) [vdsm.a= pi] FINISH repoStats return=3D{u'5eed853b-09ee-430d-bde2-c37394c1ff6c': {'c= ode': 0, 'actual': True, 'version': 4, 'acquired': True, 'delay': '0.000661= 42', 'lastCheck': '1.9', 'valid': True}} from=3Dinternal, task_id=3Dc0283b3= 9-b3d9-4745-ae24-791e6bddde42 (api:52)<br>2017-11-10 10:51:53,342+0000 INFO= (jsonrpc/2) [jsonrpc.JsonRpcServer] RPC call Host.getAllVmStats succeeded = in 0.00 seconds (__init__:539)<br>2017-11-10 10:51:54,433+0000 INFO (jsonrp= c/3) [vdsm.api] START repoStats(options=3DNone) from=3D::ffff:192.168.16.9,= 42836, flow_id=3D27d75b74, task_id=3Dcd61efd3-ec82-4de5-a83f-65e0f4ac48f6 (= api:46)<br>2017-11-10 10:51:54,433+0000 INFO (jsonrpc/3) [vdsm.api] FINISH = repoStats return=3D{u'5eed853b-09ee-430d-bde2-c37394c1ff6c': {'code': 0, 'a= ctual': True, 'version': 4, 'acquired': True, 'delay': '0.000928362', 'last= Check': '0.4', 'valid': True}} from=3D::ffff:192.168.16.9,42836, flow_id=3D= 27d75b74, task_id=3Dcd61efd3-ec82-4de5-a83f-65e0f4ac48f6 (api:52)<br>2017-1= 1-10 10:51:54,441+0000 INFO (jsonrpc/3) [jsonrpc.JsonRpcServer] RPC call Ho= st.getStats succeeded in 0.01 seconds (__init__:539)<br>2017-11-10 10:51:55= ,515+0000 INFO (jsonrpc/0) [vdsm.api] START getSpmStatus(spUUID=3Du'5a0287c= e-0233-0170-00a2-0000000001d8', options=3DNone) from=3D::ffff:192.168.16.9,= 42836, flow_id=3D87d184a, task_id=3D045078a6-36c6-4869-9408-d1126925aad3 (a= pi:46)<br>2017-11-10 10:51:55,523+0000 INFO (jsonrpc/0) [vdsm.api] FINISH g= etSpmStatus return=3D{'spm_st': {'spmId': 1, 'spmStatus': 'SPM', 'spmLver':= 2L}} from=3D::ffff:192.168.16.9,42836, flow_id=3D87d184a, task_id=3D045078= a6-36c6-4869-9408-d1126925aad3 (api:52)<br>2017-11-10 10:51:55,524+0000 INF= O (jsonrpc/0) [jsonrpc.JsonRpcServer] RPC call StoragePool.getSpmStatus suc= ceeded in 0.01 seconds (__init__:539)<br>2017-11-10 10:51:56,217+0000 INFO = (jsonrpc/6) [jsonrpc.JsonRpcServer] RPC call Host.getAllVmStats succeeded i= n 0.01 seconds (__init__:539)<br>2017-11-10 10:51:56,532+0000 INFO (jsonrpc= /5) [vdsm.api] START getStoragePoolInfo(spUUID=3Du'5a0287ce-0233-0170-00a2-= 0000000001d8', options=3DNone) from=3D::ffff:192.168.16.9,42838, flow_id=3D= 87d184a, task_id=3Df7efc4cc-14a7-4e50-8070-3119d1bb79b3 (api:46)<br>2017-11= -10 10:51:56,543+0000 INFO (jsonrpc/5) [vdsm.api] FINISH getStoragePoolInfo= return=3D{'info': {'name': 'No Description', 'isoprefix': '', 'pool_status= ': 'connected', 'lver': 2L, 'domains': u'5eed853b-09ee-430d-bde2-c37394c1ff= 6c:Active', 'master_uuid': u'5eed853b-09ee-430d-bde2-c37394c1ff6c', 'versio= n': '4', 'spm_id': 1, 'type': 'GLUSTERFS', 'master_ver': 1}, 'dominfo': {u'= 5eed853b-09ee-430d-bde2-c37394c1ff6c': {'status': u'Active', 'diskfree': '9= 12109731840', 'isoprefix': '', 'alerts': [], 'disktotal': '912151019520', '= version': 4}}} from=3D::ffff:192.168.16.9,42838, flow_id=3D87d184a, task_id= =3Df7efc4cc-14a7-4e50-8070-3119d1bb79b3 (api:52)<br>2017-11-10 10:51:56,544= +0000 INFO (jsonrpc/5) [jsonrpc.JsonRpcServer] RPC call StoragePool.getInfo= succeeded in 0.01 seconds (__init__:539)<br>2017-11-10 10:52:00,908+0000 I= NFO (periodic/1) [vdsm.api] START repoStats(options=3DNone) from=3Dinternal= , task_id=3D110fbcb2-6cde-4e95-a381-79881fb17ac0 (api:46)<br>2017-11-10 10:= 52:00,909+0000 INFO (periodic/1) [vdsm.api] FINISH repoStats return=3D{u'5e= ed853b-09ee-430d-bde2-c37394c1ff6c': {'code': 0, 'actual': True, 'version':= 4, 'acquired': True, 'delay': '0.000928362', 'lastCheck': '4.8', 'valid': = True}} from=3Dinternal, task_id=3D110fbcb2-6cde-4e95-a381-79881fb17ac0 (api= :52)<br>2017-11-10 10:52:07,557+0000 INFO (jsonrpc/7) [vdsm.api] START getS= pmStatus(spUUID=3Du'5a0287ce-0233-0170-00a2-0000000001d8', options=3DNone) = from=3D::ffff:192.168.16.9,42836, flow_id=3D55253850, task_id=3D5e78c011-2d= 1b-4f50-9445-62fbc4628ae1 (api:46)<br>2017-11-10 10:52:07,566+0000 INFO (js= onrpc/7) [vdsm.api] FINISH getSpmStatus return=3D{'spm_st': {'spmId': 1, 's= pmStatus': 'SPM', 'spmLver': 2L}} from=3D::ffff:192.168.16.9,42836, flow_id= =3D55253850, task_id=3D5e78c011-2d1b-4f50-9445-62fbc4628ae1 (api:52)<br>201= 7-11-10 10:52:07,567+0000 INFO (jsonrpc/7) [jsonrpc.JsonRpcServer] RPC call= StoragePool.getSpmStatus succeeded in 0.02 seconds (__init__:539)<br>2017-= 11-10 10:52:08,576+0000 INFO (jsonrpc/4) [vdsm.api] START getStoragePoolInf= o(spUUID=3Du'5a0287ce-0233-0170-00a2-0000000001d8', options=3DNone) from=3D= ::ffff:192.168.16.9,42838, flow_id=3D55253850, task_id=3D6a4f93ec-0ed1-4017= -b808-5f246a7b55cd (api:46)<br>2017-11-10 10:52:08,587+0000 INFO (jsonrpc/4= ) [vdsm.api] FINISH getStoragePoolInfo return=3D{'info': {'name': 'No Descr= iption', 'isoprefix': '', 'pool_status': 'connected', 'lver': 2L, 'domains'= : u'5eed853b-09ee-430d-bde2-c37394c1ff6c:Active', 'master_uuid': u'5eed853b= -09ee-430d-bde2-c37394c1ff6c', 'version': '4', 'spm_id': 1, 'type': 'GLUSTE= RFS', 'master_ver': 1}, 'dominfo': {u'5eed853b-09ee-430d-bde2-c37394c1ff6c'= : {'status': u'Active', 'diskfree': '912109731840', 'isoprefix': '', 'alert= s': [], 'disktotal': '912151019520', 'version': 4}}} from=3D::ffff:192.168.= 16.9,42838, flow_id=3D55253850, task_id=3D6a4f93ec-0ed1-4017-b808-5f246a7b5= 5cd (api:52)<br>2017-11-10 10:52:08,588+0000 INFO (jsonrpc/4) [jsonrpc.Json= RpcServer] RPC call StoragePool.getInfo succeeded in 0.02 seconds (__init__= :539)<br>2017-11-10 10:52:09,348+0000 INFO (jsonrpc/1) [jsonrpc.JsonRpcServ= er] RPC call Host.getAllVmStats succeeded in 0.00 seconds (__init__:539)<br= ptions=3DNone) from=3D::ffff:192.168.16.9,42836, flow_id=3D3144af03, task_i= d=3D2f5a4461-b4f7-4cd6-95db-b158cd082aae (api:46)<br>2017-11-10 10:52:10,46= 0+0000 INFO (jsonrpc/2) [vdsm.api] FINISH repoStats return=3D{u'5eed853b-09= ee-430d-bde2-c37394c1ff6c': {'code': 0, 'actual': True, 'version': 4, 'acqu= ired': True, 'delay': '0.00094699', 'lastCheck': '4.4', 'valid': True}} fro= m=3D::ffff:192.168.16.9,42836, flow_id=3D3144af03, task_id=3D2f5a4461-b4f7-= 4cd6-95db-b158cd082aae (api:52)<br>2017-11-10 10:52:10,468+0000 INFO (jsonr= pc/2) [jsonrpc.JsonRpcServer] RPC call Host.getStats succeeded in 0.01 seco= nds (__init__:539)<br>2017-11-10 10:52:11,240+0000 INFO (jsonrpc/3) [jsonrp= c.JsonRpcServer] RPC call Host.getAllVmStats succeeded in 0.00 seconds (__i= nit__:539)<br>2017-11-10 10:52:15,927+0000 INFO (periodic/0) [vdsm.api] STA= RT repoStats(options=3DNone) from=3Dinternal, task_id=3Df1359cba-4f75-410f-= 8aeb-a3297af27422 (api:46)<br>2017-11-10 10:52:15,928+0000 INFO (periodic/0= ) [vdsm.api] FINISH repoStats return=3D{u'5eed853b-09ee-430d-bde2-c37394c1f= f6c': {'code': 0, 'actual': True, 'version': 4, 'acquired': True, 'delay': = '0.000780232', 'lastCheck': '1.9', 'valid': True}} from=3Dinternal, task_id= =3Df1359cba-4f75-410f-8aeb-a3297af27422 (api:52)<br>2017-11-10 10:52:19,351= +0000 INFO (jsonrpc/0) [vdsm.api] START getSpmStatus(spUUID=3Du'5a0287ce-02= 33-0170-00a2-0000000001d8', options=3DNone) from=3D::ffff:192.168.16.9,4283= 6, flow_id=3D47e88296, task_id=3D441cdb4e-2752-4ccf-832f-a6c295f2f793 (api:= 46)<br>2017-11-10 10:52:19,359+0000 INFO (jsonrpc/0) [vdsm.api] FINISH getS= pmStatus return=3D{'spm_st': {'spmId': 1, 'spmStatus': 'SPM', 'spmLver': 2L= }} from=3D::ffff:192.168.16.9,42836, flow_id=3D47e88296, task_id=3D441cdb4e= -2752-4ccf-832f-a6c295f2f793 (api:52)<br>2017-11-10 10:52:19,359+0000 INFO = (jsonrpc/0) [jsonrpc.JsonRpcServer] RPC call StoragePool.getSpmStatus succe= eded in 0.01 seconds (__init__:539)<br>2017-11-10 10:52:20,367+0000 INFO (j= sonrpc/6) [vdsm.api] START getStoragePoolInfo(spUUID=3Du'5a0287ce-0233-0170= -00a2-0000000001d8', options=3DNone) from=3D::ffff:192.168.16.9,42838, flow= _id=3D47e88296, task_id=3D32a0366d-c576-4400-97e5-26bfd3b9561a (api:46)<br>= 2017-11-10 10:52:20,378+0000 INFO (jsonrpc/6) [vdsm.api] FINISH getStorageP= oolInfo return=3D{'info': {'name': 'No Description', 'isoprefix': '', 'pool= _status': 'connected', 'lver': 2L, 'domains': u'5eed853b-09ee-430d-bde2-c37= 394c1ff6c:Active', 'master_uuid': u'5eed853b-09ee-430d-bde2-c37394c1ff6c', = 'version': '4', 'spm_id': 1, 'type': 'GLUSTERFS', 'master_ver': 1}, 'dominf= o': {u'5eed853b-09ee-430d-bde2-c37394c1ff6c': {'status': u'Active', 'diskfr= ee': '912109731840', 'isoprefix': '', 'alerts': [], 'disktotal': '912151019= 520', 'version': 4}}} from=3D::ffff:192.168.16.9,42838, flow_id=3D47e88296,= task_id=3D32a0366d-c576-4400-97e5-26bfd3b9561a (api:52)<br>2017-11-10 10:5= 2:20,379+0000 INFO (jsonrpc/6) [jsonrpc.JsonRpcServer] RPC call StoragePool= .getInfo succeeded in 0.02 seconds (__init__:539)<br>2017-11-10 10:52:25,35= 4+0000 INFO (jsonrpc/5) [jsonrpc.JsonRpcServer] RPC call Host.getAllVmStats= succeeded in 0.00 seconds (__init__:539)<br>2017-11-10 10:52:26,259+0000 I= NFO (jsonrpc/7) [jsonrpc.JsonRpcServer] RPC call Host.getAllVmStats succeed= ed in 0.00 seconds (__init__:539)<br>2017-11-10 10:52:26,492+0000 INFO (jso= nrpc/4) [vdsm.api] START repoStats(options=3DNone) from=3D::ffff:192.168.16= .9,42836, flow_id=3D47536cd8, task_id=3D33cbd1f4-d8b8-44da-809b-776802d6f9c= d (api:46)<br>2017-11-10 10:52:26,493+0000 INFO (jsonrpc/4) [vdsm.api] FINI= SH repoStats return=3D{u'5eed853b-09ee-430d-bde2-c37394c1ff6c': {'code': 0,= 'actual': True, 'version': 4, 'acquired': True, 'delay': '0.000919276', 'l= astCheck': '0.4', 'valid': True}} from=3D::ffff:192.168.16.9,42836, flow_id= =3D47536cd8, task_id=3D33cbd1f4-d8b8-44da-809b-776802d6f9cd (api:52)<br>201= 7-11-10 10:52:26,500+0000 INFO (jsonrpc/4) [jsonrpc.JsonRpcServer] RPC call= Host.getStats succeeded in 0.01 seconds (__init__:539)<br>[root@node1 vdsm= ]#<br></div><br><br><div>engine log:</div><div>2017-11-10 10:51:32,474Z INF= O [org.ovirt.engine.core.bll.storage.domain.AttachStorageDomainToPoolComman= d] (default task-4) [05e7dbb5-bad3-484f-b364-ca635fe1f20b] Lock Acquired to= object 'EngineLock:{exclusiveLocks=3D'[875e1af4-ba14-4255-b6ad-c3031672df9= 3=3DSTORAGE]', sharedLocks=3D''}'<br>2017-11-10 10:51:32,504Z INFO [org.ovi= rt.engine.core.bll.storage.domain.AttachStorageDomainToPoolCommand] (org.ov= irt.thread.pool-6-thread-45) [05e7dbb5-bad3-484f-b364-ca635fe1f20b] Running= command: AttachStorageDomainToPoolCommand internal: false. Entities affect= ed : ID: 875e1af4-ba14-4255-b6ad-c3031672df93 Type: StorageAction group MAN= IPULATE_STORAGE_DOMAIN with role type ADMIN, ID: 5a0287ce-0233-0170-00a2-00= 00000001d8 Type: StoragePoolAction group MANIPULATE_STORAGE_DOMAIN with rol= e type ADMIN<br>2017-11-10 10:51:32,520Z INFO [org.ovirt.engine.core.bll.st= orage.connection.ConnectStorageToVdsCommand] (org.ovirt.thread.pool-6-threa= d-46) [1cccae15] Running command: ConnectStorageToVdsCommand internal: true= . Entities affected : ID: aaa00000-0000-0000-0000-123456789aaa Type: System= Action group CREATE_STORAGE_DOMAIN with role type ADMIN<br>2017-11-10 10:51= :32,523Z INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStorageServ= erVDSCommand] (org.ovirt.thread.pool-6-thread-46) [1cccae15] START, Connect= StorageServerVDSCommand(HostName =3D node1, StorageServerConnectionManageme= ntVDSParameters:{runAsync=3D'true', hostId=3D'630b72af-880c-4d08-9e03-68fc1= 6a6aadd', storagePoolId=3D'00000000-0000-0000-0000-000000000000', storageTy= pe=3D'NFS', connectionList=3D'[StorageServerConnections:{id=3D'ba9e9b72-cad= e-4e16-82fe-237ff7143b3b', connection=3D'engine.futurcargo.com:/home/iso', = iqn=3D'null', vfsType=3D'null', mountOptions=3D'null', nfsVersion=3D'null',= nfsRetrans=3D'null', nfsTimeo=3D'null', iface=3D'null', netIfaceName=3D'nu= ll'}]'}), log id: 67ed8236<br>2017-11-10 10:51:33,546Z INFO [org.ovirt.engi= ne.core.vdsbroker.vdsbroker.ConnectStorageServerVDSCommand] (org.ovirt.thre= ad.pool-6-thread-46) [1cccae15] FINISH, ConnectStorageServerVDSCommand, ret= urn: {ba9e9b72-cade-4e16-82fe-237ff7143b3b=3D0}, log id: 67ed8236<br>2017-1= 1-10 10:51:33,549Z INFO [org.ovirt.engine.core.vdsbroker.irsbroker.AttachSt= orageDomainVDSCommand] (org.ovirt.thread.pool-6-thread-45) [05e7dbb5-bad3-4= 84f-b364-ca635fe1f20b] START, AttachStorageDomainVDSCommand( AttachStorageD= omainVDSCommandParameters:{runAsync=3D'true', storagePoolId=3D'5a0287ce-023= 3-0170-00a2-0000000001d8', ignoreFailoverLimit=3D'false', storageDomainId= =3D'875e1af4-ba14-4255-b6ad-c3031672df93'}), log id: 2f387159<br>2017-11-10= 10:51:34,725Z ERROR [org.ovirt.engine.core.vdsbroker.irsbroker.AttachStora= geDomainVDSCommand] (org.ovirt.thread.pool-6-thread-45) [05e7dbb5-bad3-484f= -b364-ca635fe1f20b] Failed in 'AttachStorageDomainVDS' method<br>2017-11-10= 10:51:34,740Z ERROR [org.ovirt.engine.core.dal.dbbroker.auditloghandling.A= uditLogDirector] (org.ovirt.thread.pool-6-thread-45) [05e7dbb5-bad3-484f-b3= 64-ca635fe1f20b] EVENT_ID: IRS_BROKER_COMMAND_FAILURE(10,803), Correlation = ID: null, Call Stack: null, Custom ID: null, Custom Event ID: -1, Message: = VDSM command AttachStorageDomainVDS failed: Error in storage domain action:= (u'sdUUID=3D875e1af4-ba14-4255-b6ad-c3031672df93, spUUID=3D5a0287ce-0233-0= 170-00a2-0000000001d8',)<br>2017-11-10 10:51:34,740Z ERROR [org.ovirt.engin= e.core.vdsbroker.irsbroker.AttachStorageDomainVDSCommand] (org.ovirt.thread= .pool-6-thread-45) [05e7dbb5-bad3-484f-b364-ca635fe1f20b] Command 'AttachSt= orageDomainVDSCommand( AttachStorageDomainVDSCommandParameters:{runAsync=3D= 'true', storagePoolId=3D'5a0287ce-0233-0170-00a2-0000000001d8', ignoreFailo= verLimit=3D'false', storageDomainId=3D'875e1af4-ba14-4255-b6ad-c3031672df93= '})' execution failed: IRSGenericException: IRSErrorException: Failed to At= tachStorageDomainVDS, error =3D Error in storage domain action: (u'sdUUID= =3D875e1af4-ba14-4255-b6ad-c3031672df93, spUUID=3D5a0287ce-0233-0170-00a2-0= 000000001d8',), code =3D 350<br>2017-11-10 10:51:34,740Z INFO [org.ovirt.en= gine.core.vdsbroker.irsbroker.AttachStorageDomainVDSCommand] (org.ovirt.thr= ead.pool-6-thread-45) [05e7dbb5-bad3-484f-b364-ca635fe1f20b] FINISH, Attach= StorageDomainVDSCommand, log id: 2f387159<br>2017-11-10 10:51:34,740Z ERROR= [org.ovirt.engine.core.bll.storage.domain.AttachStorageDomainToPoolCommand= ] (org.ovirt.thread.pool-6-thread-45) [05e7dbb5-bad3-484f-b364-ca635fe1f20b= ] Command 'org.ovirt.engine.core.bll.storage.domain.AttachStorageDomainToPo= olCommand' failed: EngineException: org.ovirt.engine.core.vdsbroker.irsbrok= er.IrsOperationFailedNoFailoverException: IRSGenericException: IRSErrorExce= ption: Failed to AttachStorageDomainVDS, error =3D Error in storage domain = action: (u'sdUUID=3D875e1af4-ba14-4255-b6ad-c3031672df93, spUUID=3D5a0287ce= -0233-0170-00a2-0000000001d8',), code =3D 350 (Failed with error StorageDom= ainActionError and code 350)<br>2017-11-10 10:51:34,741Z INFO [org.ovirt.en= gine.core.bll.storage.domain.AttachStorageDomainToPoolCommand] (org.ovirt.t= hread.pool-6-thread-45) [05e7dbb5-bad3-484f-b364-ca635fe1f20b] Command [id= =3Da392af75-7ebe-499a-b2ed-765651834557]: Compensating NEW_ENTITY_ID of org= .ovirt.engine.core.common.businessentities.StoragePoolIsoMap; snapshot: Sto= ragePoolIsoMapId:{storagePoolId=3D'5a0287ce-0233-0170-00a2-0000000001d8', s= torageId=3D'875e1af4-ba14-4255-b6ad-c3031672df93'}.<br>2017-11-10 10:51:34,= 746Z ERROR [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDir= ector] (org.ovirt.thread.pool-6-thread-45) [05e7dbb5-bad3-484f-b364-ca635fe= 1f20b] EVENT_ID: USER_ATTACH_STORAGE_DOMAIN_TO_POOL_FAILED(963), Correlatio= n ID: 05e7dbb5-bad3-484f-b364-ca635fe1f20b, Job ID: 7f3f27ce-4d45-4b3b-a9ee= -c25795658ee4, Call Stack: null, Custom ID: null, Custom Event ID: -1, Mess= age: Failed to attach Storage Domain ISO_DOMAIN to Data Center Default. (Us= er: admin@internal-authz)<br>2017-11-10 10:51:34,748Z INFO [org.ovirt.engin= e.core.bll.storage.domain.AttachStorageDomainToPoolCommand] (org.ovirt.thre= ad.pool-6-thread-45) [05e7dbb5-bad3-484f-b364-ca635fe1f20b] Lock freed to o= bject 'EngineLock:{exclusiveLocks=3D'[875e1af4-ba14-4255-b6ad-c3031672df93= =3DSTORAGE]', sharedLocks=3D''}'<br></div><br><div><hr style=3D"width: 100%= ; height: 2px;" data-mce-style=3D"width: 100%; height: 2px;">Jose Ferradeir= a<br>http://www.logicworks.pt</div></div><br></div></div><br>______________= _________________________________<br>Users mailing list<br>Users@ovirt.org<= br>http://lists.ovirt.org/mailman/listinfo/users<br></div></div></body></ht= ml> ------=_Part_1147272_908603913.1510346638388--