- Users - oVirt List Archives

Hosted-Engine environment, strange messages in event log
by Artem Tambovskiy 12 Nov '17

12 Nov '17

Any suggestion what can be the reason for those strange messages (repeating every hour) in the web gui event log: Nov 11, 2017 7:07:01 PM Status of host ovirt2.prod.env was set to Up. Nov 11, 2017 7:06:54 PM Failed to update OVF disks 94b7554b-4c18-4296-b795-98ca6c0fb251, 002af29c-58df-493d-a45c-5009d4dfc1de, OVF data isn't updated on those OVF stores (Data Center oVirtDC, Storage Domain oVirtMigration). Nov 11, 2017 7:06:53 PM Failed to update OVF disks c7bb37de-739b-4899-8d23-d9197f81b596, OVF data isn't updated on those OVF stores (Data Center oVirtDC, Storage Domain oVirtStorageData). Nov 11, 2017 7:06:53 PM Host ovirt2.prod.env is not responding. Host cannot be fenced automatically because power management for the host is disabled. Nov 11, 2017 6:06:57 PM Status of host ovirt2.prod.env was set to Up. Nov 11, 2017 6:06:51 PM Failed to update OVF disks 94b7554b-4c18-4296-b795-98ca6c0fb251, 002af29c-58df-493d-a45c-5009d4dfc1de, OVF data isn't updated on those OVF stores (Data Center oVirtDC, Storage Domain oVirtMigration). Nov 11, 2017 6:06:48 PM Failed to update OVF disks c7bb37de-739b-4899-8d23-d9197f81b596, OVF data isn't updated on those OVF stores (Data Center oVirtDC, Storage Domain oVirtStorageData). Nov 11, 2017 6:06:48 PM Host ovirt2.prod.env is not responding. Host cannot be fenced automatically because power management for the host is disabled. Power Management is not configured yet, plaqnning to test it on coming week. So far I have one host serving 5 VM's + hosted_engine. I was planning to add one more host to the cluster this week. All storage domains running on GlusterFS cluster. Regards, Artem

1 0

Can't add disks at all
by Neil 12 Nov '17

12 Nov '17

Hi guys, I've got a strange one. I'm running ovirt 3.5.x with NFS storage from two NAS's All my VM's are running fine, however if I try to add a new disk, I click OK and nothing happens. Nothing is logged in the ovirt logs or the vdsm logs either. I've tried different browsers, I've tried tried changing the disk type etc, but no matter what, I click the OK button to add the disk, and the button shows it's been clicked, but the page just sits there, eventually after waiting 5-10 minutes I have to click cancel. Nothing is logged under tasks, etc in the ovirt GUI too. I have tried restarting my ovirt engine completely. My engine logging is working as I do see logs when I click around in my ovirt GUI. One of my storage domains is 100% full(400MB free), and I get a warning about this in the event logs, however the domain I'm trying to add it from isn't the full one. Any ideas where I can start looking? Thank you. Regards. Neil Wilson.

2 1

Ovirt 4.0 and EL 7.4
by Alan Griffiths 12 Nov '17

12 Nov '17

Hi, Is 4.0 supported/known to work on CentOS 7.4? I've just tried to upgrade one of the hosts in my lab from 7.3 to 7.4 and now vdsm-network fails to start with vdsm-tool: libvirt: XML-RPC error : authentication failed: authentication failed To even get this far I had to exclude gluster packages as 7.4 introduces 3.8 but ovirt 4.0 repo is still on 3.7. So, more generally. If I'm on ovirt 4.0, gluster 3.7 and EL 7.3. What is the best ordering for getting to ovirt 4.1 and EL 7.4? Thanks, Alan

5 8

Problem to login virtengine on remote browser
by Stephen Liu 12 Nov '17

12 Nov '17

------=_Part_5299421_391508557.1510228651131 Content-Type: text/plain; charset=UTF-8 Content-Transfer-Encoding: quoted-printable Hi all, Problem to login virtengine on remote browser client is not authorized to request an authorization vm1 - ovirtengine on CentOS 7, Gnome desktop (internal IP 192.168.8.8) vm2 - ovirt node on CentOS 7, minimal (internal IP 192.168.8.5) Host - Ubuntu 16.04 Gnome desktop KVM vm1 =3D=3D=3D on Firefox running; ovirtengine.example.com I can login 'oVirt Open Virtualization Manager' on Firefox running; 192.168.8.5:9090 (ovirt node IP) I can login ovirt node as root Host =3D=3D=3D=3D on Firefox running; ovirtengine.example.com Server not found (Firewall of vm1 already stopped running; # systemctl stop firewalld ) if running; 192.168.8.8 (IP of vm1) I can connect 'oVirt Open Virtualization Manager' but I can't login with fo= llowing warning: 'The client is not authorized to request an authorization. It's required to= access the system using FQDN.' on Firefox running; 192.168.8.5:9090=C2=A0 (ovirt node IP) I can login ovirt node as root Please help.=C2=A0 Thanks in advice. Regards SL ------=_Part_5299421_391508557.1510228651131 Content-Type: text/html; charset=UTF-8 Content-Transfer-Encoding: quoted-printable <html><head></head><body><div style=3D"font-family:Helvetica Neue, Helvetic= a, Arial, sans-serif;font-size:16px;"><div>Hi all, Problem to login = virtengine on remote browser client is not authorized to request an auth= orization vm1 - ovirtengine on CentOS 7, Gnome desktop (internal IP = 192.168.8.8) vm2 - ovirt node on CentOS 7, minimal (internal IP 192.168.= 8.5) Host - Ubuntu 16.04 Gnome desktop KVM vm1 =3D=3D=3D<br= >on Firefox running; ovirtengine.example.com I can login 'oVirt Open = Virtualization Manager' on Firefox running; 192.168.8.5:9090 (ovi= rt node IP) I can login ovirt node as root Host =3D=3D=3D=3D<b= r>on Firefox running; ovirtengine.example.com Server not found (Fi= rewall of vm1 already stopped running; # systemctl stop firewalld ) <= br>if running; 192.168.8.8 (IP of vm1) I can connect 'oVirt Open Virt= ualization Manager' but I can't login with following warning: 'The clien= t is not authorized to request an authorization. It's required to access th= e system using FQDN.' on Firefox running; 192.168.8.5:9090  = (ovirt node IP) I can login ovirt node as root Please help.&n= bsp; Thanks in advice. Regards SL </div></div></bo= dy></html> ------=_Part_5299421_391508557.1510228651131--

2 5

Hi. I have a question about ovirt And redhat virtualization hypervisor 5.7
by Julio Cesar Bustamante 11 Nov '17

11 Nov '17

Can Ovirt engine 4.1 support Red hat enterprise virtualization hypervisor 5.7 as a host?

1 0

attach iso domain failed
by suporte＠logicworks.pt 10 Nov '17

10 Nov '17

------=_Part_903046_834258931.1510311699782 Content-Type: text/plain; charset=utf-8 Content-Transfer-Encoding: 7bit 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 ------=_Part_903046_834258931.1510311699782 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 data-marker=3D"__QUOTED_TEXT__"><div style=3D"font-famil= y: Times New Roman; font-size: 10pt; color: #000000"><div>Hi,</div> <div= >I have engine installed on one machine and host on another, running V= ersion 4.1.7.6-1.el7.centos</div><div>Everything looks ok except the iso do= main I cannot attach it to the data center. Message on the engine dashboard= :</div> <div>Failed to attach Storage Domain ISO_DOMAIN to Data Center D= efault. (User: admin@internal-authz)</div><div>VDSM command AttachStorageDo= mainVDS failed: Error in storage domain action: (u'sdUUID=3D875e1af4-ba14-4= 255-b6ad-c3031672df93, spUUID=3D5a0287ce-0233-0170-00a2-0000000001d8',)</di= v> <div>Checking the configuration everything looks ok:</div><div>[root@= engine ~]# grep iso /etc/ovirt-engine-setup.conf.d/20-setup-ovirt-post.conf= OVESETUP_CONFIG/isoDomainName=3Dstr:ISO_DOMAIN OVESETUP_CONFIG/isoDo= mainSdUuid=3Dstr:875e1af4-ba14-4255-b6ad-c3031672df93 OVESETUP_CONFIG/is= oDomainMountPoint=3Dstr:/home/iso OVESETUP_CONFIG/isoDomainExists=3Dbool= :True OVESETUP_CONFIG/isoDomainStorageDir=3Dstr:/home/iso/875e1af4-ba14-= 4255-b6ad-c3031672df93/images/11111111-1111-1111-1111-111111111111 </div= > <div>[root@engine ~]# ovirt-iso-uploader list Please provide the RE= ST API password for the admin@internal oVirt Engine user (CTRL+D to abort):= ISO Storage Domain Name | ISO Domain Status ISO_DOMAIN | ok </div= > <div>[root@node1 ~]# vdsClient -s 0 getStorageDomainsList 5eed853b-= 09ee-430d-bde2-c37394c1ff6c </div> <div>I can mount the export ma= nually</div> <div>Don't know where to look more. Any idea? thanks</d= iv> <div>vdsm log:</div><div>2017-11-10 10:51:00,839+0000 INFO (peri= odic/3) [vdsm.api] START repoStats(options=3DNone) from=3Dinternal, task_id= =3Df0f5161a-b3c1-4534-a33a-cf5d17ab8c9c (api:46) 2017-11-10 10:51:00,840= +0000 INFO (periodic/3) [vdsm.api] FINISH repoStats return=3D{u'5eed853b-09= ee-430d-bde2-c37394c1ff6c': {'code': 0, 'actual': True, 'version': 4, 'acqu= ired': True, 'delay': '0.000890471', 'lastCheck': '4.8', 'valid': True}} fr= om=3Dinternal, task_id=3Df0f5161a-b3c1-4534-a33a-cf5d17ab8c9c (api:52) 2= 017-11-10 10:51:05,323+0000 INFO (jsonrpc/7) [jsonrpc.JsonRpcServer] RPC ca= ll Host.getAllVmStats succeeded in 0.00 seconds (__init__:539) 2017-11-1= 0 10:51:07,277+0000 INFO (jsonrpc/4) [vdsm.api] START repoStats(options=3DN= one) from=3D::ffff:192.168.16.9,42836, flow_id=3D399705b5, task_id=3D998fe1= bc-eff0-4ba7-942c-458906c5e1f1 (api:46) 2017-11-10 10:51:07,277+0000 INF= O (jsonrpc/4) [vdsm.api] FINISH repoStats return=3D{u'5eed853b-09ee-430d-bd= e2-c37394c1ff6c': {'code': 0, 'actual': True, 'version': 4, 'acquired': Tru= e, 'delay': '0.000844547', 'lastCheck': '1.2', 'valid': True}} from=3D::fff= f:192.168.16.9,42836, flow_id=3D399705b5, task_id=3D998fe1bc-eff0-4ba7-942c= -458906c5e1f1 (api:52) 2017-11-10 10:51:07,285+0000 INFO (jsonrpc/4) [js= onrpc.JsonRpcServer] RPC call Host.getStats succeeded in 0.01 seconds (__in= it__:539) 2017-11-10 10:51:07,345+0000 INFO (jsonrpc/1) [vdsm.api] START= getSpmStatus(spUUID=3Du'5a0287ce-0233-0170-00a2-0000000001d8', options=3DN= one) from=3D::ffff:192.168.16.9,42836, flow_id=3D4b670edd, task_id=3Da6baf0= 12-6dbb-47d6-9f8e-457e42bbc7d7 (api:46) 2017-11-10 10:51:07,352+0000 INF= O (jsonrpc/1) [vdsm.api] FINISH getSpmStatus return=3D{'spm_st': {'spmId': = 1, 'spmStatus': 'SPM', 'spmLver': 2L}} from=3D::ffff:192.168.16.9,42836, fl= ow_id=3D4b670edd, task_id=3Da6baf012-6dbb-47d6-9f8e-457e42bbc7d7 (api:52)<b= r>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 getStoragePo= olInfo(spUUID=3Du'5a0287ce-0233-0170-00a2-0000000001d8', options=3DNone) fr= om=3D::ffff:192.168.16.9,42838, flow_id=3D4b670edd, task_id=3D4d623b87-105f= -47ef-8385-7d7391378906 (api:46) 2017-11-10 10:51:08,421+0000 INFO (json= rpc/2) [vdsm.api] FINISH getStoragePoolInfo return=3D{'info': {'name': 'No = Description', 'isoprefix': '', 'pool_status': 'connected', 'lver': 2L, 'dom= ains': u'5eed853b-09ee-430d-bde2-c37394c1ff6c:Active', 'master_uuid': u'5ee= d853b-09ee-430d-bde2-c37394c1ff6c', 'version': '4', 'spm_id': 1, 'type': 'G= LUSTERFS', 'master_ver': 1}, 'dominfo': {u'5eed853b-09ee-430d-bde2-c37394c1= ff6c': {'status': u'Active', 'diskfree': '912109731840', 'isoprefix': '', '= alerts': [], 'disktotal': '912151019520', 'version': 4}}} from=3D::ffff:192= .168.16.9,42838, flow_id=3D4b670edd, task_id=3D4d623b87-105f-47ef-8385-7d73= 91378906 (api:52) 2017-11-10 10:51:08,422+0000 INFO (jsonrpc/2) [jsonrpc= .JsonRpcServer] RPC call StoragePool.getInfo succeeded in 0.01 seconds (__i= nit__:539) 2017-11-10 10:51:11,159+0000 INFO (jsonrpc/3) [jsonrpc.JsonRp= cServer] RPC call Host.getAllVmStats succeeded in 0.00 seconds (__init__:53= 9) 2017-11-10 10:51:15,859+0000 INFO (periodic/0) [vdsm.api] START repoS= tats(options=3DNone) from=3Dinternal, task_id=3D8da65b6e-23ba-40fb-82fd-287= 645bb05cf (api:46) 2017-11-10 10:51:15,859+0000 INFO (periodic/0) [vdsm.= api] FINISH repoStats return=3D{u'5eed853b-09ee-430d-bde2-c37394c1ff6c': {'= code': 0, 'actual': True, 'version': 4, 'acquired': True, 'delay': '0.00071= 8255', 'lastCheck': '1.8', 'valid': True}} from=3Dinternal, task_id=3D8da65= b6e-23ba-40fb-82fd-287645bb05cf (api:52) 2017-11-10 10:51:19,434+0000 IN= FO (jsonrpc/0) [vdsm.api] START getSpmStatus(spUUID=3Du'5a0287ce-0233-0170-= 00a2-0000000001d8', options=3DNone) from=3D::ffff:192.168.16.9,42836, flow_= id=3D32cb4291, task_id=3Dc42d1ce1-d82c-47ad-874f-6d73142e49c6 (api:46) 2= 017-11-10 10:51:19,443+0000 INFO (jsonrpc/0) [vdsm.api] FINISH getSpmStatus= return=3D{'spm_st': {'spmId': 1, 'spmStatus': 'SPM', 'spmLver': 2L}} from= =3D::ffff:192.168.16.9,42836, flow_id=3D32cb4291, task_id=3Dc42d1ce1-d82c-4= 7ad-874f-6d73142e49c6 (api:52) 2017-11-10 10:51:19,444+0000 INFO (jsonrp= c/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=3Du'5a0287ce-0233-0170-00a2-0= 000000001d8', options=3DNone) from=3D::ffff:192.168.16.9,42838, flow_id=3D3= 2cb4291, task_id=3D72cdacd6-5133-4554-8ecc-86f11a094064 (api:46) 2017-11= -10 10:51:20,464+0000 INFO (jsonrpc/6) [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=3D32cb4291, task_i= d=3D72cdacd6-5133-4554-8ecc-86f11a094064 (api:52) 2017-11-10 10:51:20,46= 5+0000 INFO (jsonrpc/6) [jsonrpc.JsonRpcServer] RPC call StoragePool.getInf= o succeeded in 0.01 seconds (__init__:539) 2017-11-10 10:51:21,329+0000 = INFO (jsonrpc/5) [jsonrpc.JsonRpcServer] RPC call Host.getAllVmStats succee= ded in 0.00 seconds (__init__:539) 2017-11-10 10:51:22,360+0000 INFO (js= onrpc/7) [vdsm.api] START repoStats(options=3DNone) from=3D::ffff:192.168.1= 6.9,42836, flow_id=3D5eb26f2b, task_id=3Dc4320507-69ec-457d-a51b-e898702de2= 47 (api:46) 2017-11-10 10:51:22,360+0000 INFO (jsonrpc/7) [vdsm.api] FIN= ISH repoStats return=3D{u'5eed853b-09ee-430d-bde2-c37394c1ff6c': {'code': 0= , 'actual': True, 'version': 4, 'acquired': True, 'delay': '0.000718255', '= lastCheck': '6.3', 'valid': True}} from=3D::ffff:192.168.16.9,42836, flow_i= d=3D5eb26f2b, task_id=3Dc4320507-69ec-457d-a51b-e898702de247 (api:52) 20= 17-11-10 10:51:22,368+0000 INFO (jsonrpc/7) [jsonrpc.JsonRpcServer] RPC cal= l Host.getStats succeeded in 0.01 seconds (__init__:539) 2017-11-10 10:5= 1:26,178+0000 INFO (jsonrpc/4) [jsonrpc.JsonRpcServer] RPC call Host.getAll= VmStats succeeded in 0.00 seconds (__init__:539) 2017-11-10 10:51:30,878= +0000 INFO (periodic/2) [vdsm.api] START repoStats(options=3DNone) from=3Di= nternal, task_id=3Df62ad6de-75ad-4a99-bb4d-2854b5ff85c6 (api:46) 2017-11= -10 10:51:30,878+0000 INFO (periodic/2) [vdsm.api] FINISH repoStats return= =3D{u'5eed853b-09ee-430d-bde2-c37394c1ff6c': {'code': 0, 'actual': True, 'v= ersion': 4, 'acquired': True, 'delay': '0.000956708', 'lastCheck': '4.8', '= valid': True}} from=3Dinternal, task_id=3Df62ad6de-75ad-4a99-bb4d-2854b5ff8= 5c6 (api:52) 2017-11-10 10:51:31,436+0000 INFO (jsonrpc/1) [vdsm.api] ST= ART getSpmStatus(spUUID=3Du'5a0287ce-0233-0170-00a2-0000000001d8', options= =3DNone) from=3D::ffff:192.168.16.9,42836, flow_id=3D11ee2968, task_id=3D73= 8c51fb-387e-47b3-a1ad-03fc900f749f (api:46) 2017-11-10 10:51:31,444+0000= INFO (jsonrpc/1) [vdsm.api] FINISH getSpmStatus return=3D{'spm_st': {'spmI= d': 1, 'spmStatus': 'SPM', 'spmLver': 2L}} from=3D::ffff:192.168.16.9,42836= , flow_id=3D11ee2968, task_id=3D738c51fb-387e-47b3-a1ad-03fc900f749f (api:5= 2) 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 getStora= gePoolInfo(spUUID=3Du'5a0287ce-0233-0170-00a2-0000000001d8', options=3DNone= ) from=3D::ffff:192.168.16.9,42838, flow_id=3D11ee2968, task_id=3D4f010678-= 55ba-4ead-84bc-734763662964 (api:46) 2017-11-10 10:51:32,464+0000 INFO (= jsonrpc/2) [vdsm.api] FINISH getStoragePoolInfo return=3D{'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-c373= 94c1ff6c': {'status': u'Active', 'diskfree': '912109731840', 'isoprefix': '= ', 'alerts': [], 'disktotal': '912151019520', 'version': 4}}} from=3D::ffff= :192.168.16.9,42838, flow_id=3D11ee2968, task_id=3D4f010678-55ba-4ead-84bc-= 734763662964 (api:52) 2017-11-10 10:51:32,465+0000 INFO (jsonrpc/2) [jso= nrpc.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=3D1, spUUID=3Du'00000000-0000-0000-0000-= 000000000000', conList=3D[{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=3DNone) from=3D::ffff:1= 92.168.16.9,42836, flow_id=3D1cccae15, task_id=3D37c76c14-2e74-4c12-99d7-6b= 3f2397348e (api:46) 2017-11-10 10:51:33,951+0000 INFO (jsonrpc/3) [IOPro= cessClient] Starting client ioprocess-706 (__init__:330) 2017-11-10 10:5= 1: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=3D{'statuslist': [{'status': 0, 'id': u'ba9e9b7= 2-cade-4e16-82fe-237ff7143b3b'}]} from=3D::ffff:192.168.16.9,42836, flow_id= =3D1cccae15, task_id=3D37c76c14-2e74-4c12-99d7-6b3f2397348e (api:52) 201= 7-11-10 10:51:33,965+0000 INFO (jsonrpc/3) [jsonrpc.JsonRpcServer] RPC call= StoragePool.connectStorageServer succeeded in 0.01 seconds (__init__:539)<= br>2017-11-10 10:51:34,974+0000 INFO (jsonrpc/0) [vdsm.api] START attachSto= rageDomain(sdUUID=3Du'875e1af4-ba14-4255-b6ad-c3031672df93', spUUID=3Du'5a0= 287ce-0233-0170-00a2-0000000001d8', options=3DNone) from=3D::ffff:192.168.1= 6.9,42838, flow_id=3D05e7dbb5-bad3-484f-b364-ca635fe1f20b, task_id=3D472c9b= fe-0751-4240-b5c4-e867cb3d0cfb (api:46) 2017-11-10 10:51:34,975+0000 INF= O (jsonrpc/0) [storage.StoragePool] sdUUID=3D875e1af4-ba14-4255-b6ad-c30316= 72df93 spUUID=3D5a0287ce-0233-0170-00a2-0000000001d8 (sp:942) 2017-11-10= 10:51:35,102+0000 INFO (itmap/0) [IOProcessClient] Starting client ioproce= ss-707 (__init__:330) 2017-11-10 10:51:35,113+0000 INFO (itmap/1) [IOPro= cessClient] Starting client ioprocess-708 (__init__:330) 2017-11-10 10:5= 1: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+000= 0 INFO (ioprocess/20660) [IOProcess] Starting ioprocess (__init__:452) 2= 017-11-10 10:51:35,139+0000 INFO (ioprocess/20667) [IOProcess] Starting iop= rocess (__init__:452) 2017-11-10 10:51:35,141+0000 INFO (jsonrpc/0) [vds= m.api] FINISH attachStorageDomain error=3D'VERSION' from=3D::ffff:192.168.1= 6.9,42838, flow_id=3D05e7dbb5-bad3-484f-b364-ca635fe1f20b, task_id=3D472c9b= fe-0751-4240-b5c4-e867cb3d0cfb (api:50) 2017-11-10 10:51:35,141+0000 ERR= OR (jsonrpc/0) [storage.TaskManager.Task] (Task=3D'472c9bfe-0751-4240-b5c4-= e867cb3d0cfb') Unexpected error (task:872) Traceback (most recent call l= ast): File "/usr/share/vdsm/storage/task.py", line 879, in _run ret= urn fn(*args, **kargs) File "<string>", line 2, in attachStorageD= omain File "/usr/lib/python2.7/site-packages/vdsm/common/api.py", line = 48, in method ret =3D func(*args, **kwargs) File "/usr/share/vdsm/s= torage/hsm.py", line 1152, in attachStorageDomain pool.attachSD(sdUUID)= File "/usr/lib/python2.7/site-packages/vdsm/storage/securable.py", lin= e 79, in wrapper return method(self, *args, **kwargs) File "/usr/sh= are/vdsm/storage/sp.py", line 949, in attachSD dom =3D 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) Fil= e "/usr/share/vdsm/storage/sdc.py", line 136, in _realProduce domain = =3D self._findDomain(sdUUID) File "/usr/share/vdsm/storage/sdc.py", lin= e 153, in _findDomain return findMethod(sdUUID) File "/usr/share/vd= sm/storage/nfsSD.py", line 126, in findDomain return NfsStorageDomain(N= fsStorageDomain.findDomainPath(sdUUID)) File "/usr/share/vdsm/storage/f= ileSD.py", line 364, in __init__ manifest =3D self.manifestClass(domain= Path) File "/usr/share/vdsm/storage/fileSD.py", line 171, in __init__<b= r> sd.StorageDomainManifest.__init__(self, sdUUID, domaindir, metadata) = File "/usr/share/vdsm/storage/sd.py", line 332, in __init__ self._doma= inLock =3D self._makeDomainLock() File "/usr/share/vdsm/storage/sd.py",= line 526, in _makeDomainLock domVersion =3D self.getVersion() File= "/usr/share/vdsm/storage/sd.py", line 403, in getVersion return self.g= etMetaParam(DMDK_VERSION) File "/usr/share/vdsm/storage/sd.py", line 40= 0, in getMetaParam return self._metadata[key] File "/usr/lib/python= 2.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)<b= r>KeyError: 'VERSION' 2017-11-10 10:51:35,142+0000 INFO (jsonrpc/0) [sto= rage.TaskManager.Task] (Task=3D'472c9bfe-0751-4240-b5c4-e867cb3d0cfb') abor= ting: Task is aborted: u"'VERSION'" - code 100 (task:1177) 2017-11-10 10= :51:35,143+0000 ERROR (jsonrpc/0) [storage.Dispatcher] FINISH attachStorage= Domain error=3D'VERSION' (dispatcher:85) Traceback (most recent call las= t): File "/usr/share/vdsm/storage/dispatcher.py", line 72, in wrapper<b= r> result =3D ctask.prepare(func, *args, **kwargs) File "/usr/share/vds= m/storage/task.py", line 105, in wrapper return m(self, *a, **kw) F= ile "/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 (json= rpc/6) [jsonrpc.JsonRpcServer] RPC call Host.getAllVmStats succeeded in 0.0= 0 seconds (__init__:539) 2017-11-10 10: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= ) 2017-11-10 10:51:38,388+0000 INFO (jsonrpc/5) [vdsm.api] FINISH repoSt= ats 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:192.168.16.9,42836, flow_id=3D564f78= 8f, task_id=3D0c08c128-7ec8-4981-95a8-5bfeeed126d6 (api:52) 2017-11-10 1= 0:51:38,396+0000 INFO (jsonrpc/5) [jsonrpc.JsonRpcServer] RPC call Host.get= Stats succeeded in 0.01 seconds (__init__:539) 2017-11-10 10:51:41,198+0= 000 INFO (jsonrpc/7) [jsonrpc.JsonRpcServer] RPC call Host.getAllVmStats su= cceeded in 0.00 seconds (__init__:539) 2017-11-10 10:51:43,474+0000 INFO= (jsonrpc/4) [vdsm.api] START getSpmStatus(spUUID=3Du'5a0287ce-0233-0170-00= a2-0000000001d8', options=3DNone) from=3D::ffff:192.168.16.9,42836, flow_id= =3D14f15c4b, task_id=3D63fb7235-56eb-4acd-8bf6-e04a701e8d83 (api:46) 201= 7-11-10 10:51:43,483+0000 INFO (jsonrpc/4) [vdsm.api] FINISH getSpmStatus r= eturn=3D{'spm_st': {'spmId': 1, 'spmStatus': 'SPM', 'spmLver': 2L}} from=3D= ::ffff:192.168.16.9,42836, flow_id=3D14f15c4b, task_id=3D63fb7235-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=3Du'5a0287ce-0233-0170-00a2-0000= 000001d8', options=3DNone) from=3D::ffff:192.168.16.9,42838, flow_id=3D14f1= 5c4b, task_id=3De0fe10ef-cc9c-4e3c-a22a-1f9ca042e75e (api:46) 2017-11-10= 10:51:44,504+0000 INFO (jsonrpc/1) [vdsm.api] FINISH getStoragePoolInfo re= turn=3D{'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'5ee= d853b-09ee-430d-bde2-c37394c1ff6c': {'status': u'Active', 'diskfree': '9121= 09731840', 'isoprefix': '', 'alerts': [], 'disktotal': '912151019520', 'ver= sion': 4}}} from=3D::ffff:192.168.16.9,42838, flow_id=3D14f15c4b, task_id= =3De0fe10ef-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 I= NFO (periodic/1) [vdsm.api] START repoStats(options=3DNone) from=3Dinternal= , task_id=3Dc0283b39-b3d9-4745-ae24-791e6bddde42 (api:46) 2017-11-10 10:= 51:45,897+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.00066142', 'lastCheck': '1.9', 'valid': T= rue}} from=3Dinternal, task_id=3Dc0283b39-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) 2= 017-11-10 10:51:54,433+0000 INFO (jsonrpc/3) [vdsm.api] START repoStats(opt= ions=3DNone) from=3D::ffff:192.168.16.9,42836, flow_id=3D27d75b74, task_id= =3Dcd61efd3-ec82-4de5-a83f-65e0f4ac48f6 (api:46) 2017-11-10 10:51:54,433= +0000 INFO (jsonrpc/3) [vdsm.api] FINISH repoStats return=3D{u'5eed853b-09e= e-430d-bde2-c37394c1ff6c': {'code': 0, 'actual': True, 'version': 4, 'acqui= red': True, 'delay': '0.000928362', 'lastCheck': '0.4', 'valid': True}} fro= m=3D::ffff:192.168.16.9,42836, flow_id=3D27d75b74, task_id=3Dcd61efd3-ec82-= 4de5-a83f-65e0f4ac48f6 (api:52) 2017-11-10 10:51:54,441+0000 INFO (jsonr= pc/3) [jsonrpc.JsonRpcServer] RPC call Host.getStats succeeded in 0.01 seco= nds (__init__:539) 2017-11-10 10:51:55,515+0000 INFO (jsonrpc/0) [vdsm.a= pi] START getSpmStatus(spUUID=3Du'5a0287ce-0233-0170-00a2-0000000001d8', op= tions=3DNone) from=3D::ffff:192.168.16.9,42836, flow_id=3D87d184a, task_id= =3D045078a6-36c6-4869-9408-d1126925aad3 (api:46) 2017-11-10 10:51:55,523= +0000 INFO (jsonrpc/0) [vdsm.api] FINISH getSpmStatus return=3D{'spm_st': {= 'spmId': 1, 'spmStatus': 'SPM', 'spmLver': 2L}} from=3D::ffff:192.168.16.9,= 42836, flow_id=3D87d184a, task_id=3D045078a6-36c6-4869-9408-d1126925aad3 (a= pi:52) 2017-11-10 10:51:55,524+0000 INFO (jsonrpc/0) [jsonrpc.JsonRpcSer= ver] RPC call StoragePool.getSpmStatus succeeded in 0.01 seconds (__init__:= 539) 2017-11-10 10:51:56,217+0000 INFO (jsonrpc/6) [jsonrpc.JsonRpcServe= r] 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 getStoragePo= olInfo(spUUID=3Du'5a0287ce-0233-0170-00a2-0000000001d8', options=3DNone) fr= om=3D::ffff:192.168.16.9,42838, flow_id=3D87d184a, task_id=3Df7efc4cc-14a7-= 4e50-8070-3119d1bb79b3 (api:46) 2017-11-10 10:51:56,543+0000 INFO (jsonr= pc/5) [vdsm.api] FINISH getStoragePoolInfo return=3D{'info': {'name': 'No D= escription', 'isoprefix': '', 'pool_status': 'connected', 'lver': 2L, 'doma= ins': u'5eed853b-09ee-430d-bde2-c37394c1ff6c:Active', 'master_uuid': u'5eed= 853b-09ee-430d-bde2-c37394c1ff6c', 'version': '4', 'spm_id': 1, 'type': 'GL= USTERFS', 'master_ver': 1}, 'dominfo': {u'5eed853b-09ee-430d-bde2-c37394c1f= f6c': {'status': u'Active', 'diskfree': '912109731840', 'isoprefix': '', 'a= lerts': [], 'disktotal': '912151019520', 'version': 4}}} from=3D::ffff:192.= 168.16.9,42838, flow_id=3D87d184a, task_id=3Df7efc4cc-14a7-4e50-8070-3119d1= bb79b3 (api:52) 2017-11-10 10:51:56,544+0000 INFO (jsonrpc/5) [jsonrpc.J= sonRpcServer] RPC call StoragePool.getInfo succeeded in 0.01 seconds (__ini= t__:539) 2017-11-10 10:52:00,908+0000 INFO (periodic/1) [vdsm.api] START= repoStats(options=3DNone) from=3Dinternal, task_id=3D110fbcb2-6cde-4e95-a3= 81-79881fb17ac0 (api:46) 2017-11-10 10:52:00,909+0000 INFO (periodic/1) = [vdsm.api] FINISH repoStats return=3D{u'5eed853b-09ee-430d-bde2-c37394c1ff6= c': {'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) 2017-11-10 10:52:07,557= +0000 INFO (jsonrpc/7) [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=3D55253850, task_id=3D5e78c011-2d1b-4f50-9445-62fbc4628ae1 (api:= 46) 2017-11-10 10:52:07,566+0000 INFO (jsonrpc/7) [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=3D55253850, task_id=3D5e78c011= -2d1b-4f50-9445-62fbc4628ae1 (api:52) 2017-11-10 10:52:07,567+0000 INFO = (jsonrpc/7) [jsonrpc.JsonRpcServer] RPC call StoragePool.getSpmStatus succe= eded in 0.02 seconds (__init__:539) 2017-11-10 10:52:08,576+0000 INFO (j= sonrpc/4) [vdsm.api] START getStoragePoolInfo(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) = 2017-11-10 10:52:08,587+0000 INFO (jsonrpc/4) [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=3D55253850,= task_id=3D6a4f93ec-0ed1-4017-b808-5f246a7b55cd (api:52) 2017-11-10 10:5= 2: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,34= 8+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 I= NFO (jsonrpc/2) [vdsm.api] START repoStats(options=3DNone) from=3D::ffff:19= 2.168.16.9,42836, flow_id=3D3144af03, task_id=3D2f5a4461-b4f7-4cd6-95db-b15= 8cd082aae (api:46) 2017-11-10 10:52:10,460+0000 INFO (jsonrpc/2) [vdsm.a= pi] FINISH repoStats return=3D{u'5eed853b-09ee-430d-bde2-c37394c1ff6c': {'c= ode': 0, 'actual': True, 'version': 4, 'acquired': True, 'delay': '0.000946= 99', 'lastCheck': '4.4', 'valid': True}} from=3D::ffff:192.168.16.9,42836, = flow_id=3D3144af03, task_id=3D2f5a4461-b4f7-4cd6-95db-b158cd082aae (api:52)= 2017-11-10 10:52:10,468+0000 INFO (jsonrpc/2) [jsonrpc.JsonRpcServer] R= PC call Host.getStats succeeded in 0.01 seconds (__init__:539) 2017-11-1= 0 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=3DNone) fr= om=3Dinternal, task_id=3Df1359cba-4f75-410f-8aeb-a3297af27422 (api:46) 2= 017-11-10 10:52:15,928+0000 INFO (periodic/0) [vdsm.api] FINISH repoStats r= eturn=3D{u'5eed853b-09ee-430d-bde2-c37394c1ff6c': {'code': 0, 'actual': Tru= e, 'version': 4, 'acquired': True, 'delay': '0.000780232', 'lastCheck': '1.= 9', 'valid': True}} from=3Dinternal, task_id=3Df1359cba-4f75-410f-8aeb-a329= 7af27422 (api:52) 2017-11-10 10:52:19,351+0000 INFO (jsonrpc/0) [vdsm.ap= i] START getSpmStatus(spUUID=3Du'5a0287ce-0233-0170-00a2-0000000001d8', opt= ions=3DNone) from=3D::ffff:192.168.16.9,42836, flow_id=3D47e88296, task_id= =3D441cdb4e-2752-4ccf-832f-a6c295f2f793 (api:46) 2017-11-10 10:52:19,359= +0000 INFO (jsonrpc/0) [vdsm.api] FINISH getSpmStatus 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) 2017-11-10 10:52:19,359+0000 INFO (jsonrpc/0) [jsonrpc.JsonRpcSe= rver] 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 get= StoragePoolInfo(spUUID=3Du'5a0287ce-0233-0170-00a2-0000000001d8', options= =3DNone) from=3D::ffff:192.168.16.9,42838, flow_id=3D47e88296, task_id=3D32= a0366d-c576-4400-97e5-26bfd3b9561a (api:46) 2017-11-10 10:52:20,378+0000= INFO (jsonrpc/6) [vdsm.api] FINISH getStoragePoolInfo return=3D{'info': {'= name': 'No Description', 'isoprefix': '', 'pool_status': 'connected', 'lver= ': 2L, 'domains': u'5eed853b-09ee-430d-bde2-c37394c1ff6c:Active', 'master_u= uid': u'5eed853b-09ee-430d-bde2-c37394c1ff6c', 'version': '4', 'spm_id': 1,= 'type': 'GLUSTERFS', 'master_ver': 1}, 'dominfo': {u'5eed853b-09ee-430d-bd= e2-c37394c1ff6c': {'status': u'Active', 'diskfree': '912109731840', 'isopre= fix': '', 'alerts': [], 'disktotal': '912151019520', 'version': 4}}} from= =3D::ffff:192.168.16.9,42838, flow_id=3D47e88296, task_id=3D32a0366d-c576-4= 400-97e5-26bfd3b9561a (api:52) 2017-11-10 10:52:20,379+0000 INFO (jsonrp= c/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) [j= sonrpc.JsonRpcServer] RPC call Host.getAllVmStats succeeded in 0.00 seconds= (__init__:539) 2017-11-10 10:52:26,259+0000 INFO (jsonrpc/7) [jsonrpc.J= sonRpcServer] 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 r= epoStats(options=3DNone) from=3D::ffff:192.168.16.9,42836, flow_id=3D47536c= d8, task_id=3D33cbd1f4-d8b8-44da-809b-776802d6f9cd (api:46) 2017-11-10 1= 0:52:26,493+0000 INFO (jsonrpc/4) [vdsm.api] FINISH repoStats return=3D{u'5= eed853b-09ee-430d-bde2-c37394c1ff6c': {'code': 0, 'actual': True, 'version'= : 4, 'acquired': True, 'delay': '0.000919276', 'lastCheck': '0.4', 'valid':= True}} from=3D::ffff:192.168.16.9,42836, flow_id=3D47536cd8, task_id=3D33c= bd1f4-d8b8-44da-809b-776802d6f9cd (api:52) 2017-11-10 10:52:26,500+0000 = INFO (jsonrpc/4) [jsonrpc.JsonRpcServer] RPC call Host.getStats succeeded i= n 0.01 seconds (__init__:539) [root@node1 vdsm]# </div> <div>e= ngine log:</div><div>2017-11-10 10:51:32,474Z INFO [org.ovirt.engine.core.b= ll.storage.domain.AttachStorageDomainToPoolCommand] (default task-4) [05e7d= bb5-bad3-484f-b364-ca635fe1f20b] Lock Acquired to object 'EngineLock:{exclu= siveLocks=3D'[875e1af4-ba14-4255-b6ad-c3031672df93=3DSTORAGE]', sharedLocks= =3D''}' 2017-11-10 10:51:32,504Z INFO [org.ovirt.engine.core.bll.storage= .domain.AttachStorageDomainToPoolCommand] (org.ovirt.thread.pool-6-thread-4= 5) [05e7dbb5-bad3-484f-b364-ca635fe1f20b] Running command: AttachStorageDom= ainToPoolCommand internal: false. Entities affected : ID: 875e1af4-ba14-425= 5-b6ad-c3031672df93 Type: StorageAction group MANIPULATE_STORAGE_DOMAIN wit= h role type ADMIN, ID: 5a0287ce-0233-0170-00a2-0000000001d8 Type: StoragePo= olAction group MANIPULATE_STORAGE_DOMAIN with role type ADMIN 2017-11-10= 10:51:32,520Z INFO [org.ovirt.engine.core.bll.storage.connection.ConnectSt= orageToVdsCommand] (org.ovirt.thread.pool-6-thread-46) [1cccae15] Running c= ommand: ConnectStorageToVdsCommand internal: true. Entities affected : ID: = aaa00000-0000-0000-0000-123456789aaa Type: SystemAction group CREATE_STORAG= E_DOMAIN with role type ADMIN 2017-11-10 10:51:32,523Z INFO [org.ovirt.e= ngine.core.vdsbroker.vdsbroker.ConnectStorageServerVDSCommand] (org.ovirt.t= hread.pool-6-thread-46) [1cccae15] START, ConnectStorageServerVDSCommand(Ho= stName =3D node1, StorageServerConnectionManagementVDSParameters:{runAsync= =3D'true', hostId=3D'630b72af-880c-4d08-9e03-68fc16a6aadd', storagePoolId= =3D'00000000-0000-0000-0000-000000000000', storageType=3D'NFS', connectionL= ist=3D'[StorageServerConnections:{id=3D'ba9e9b72-cade-4e16-82fe-237ff7143b3= b', 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'null'}]'}), log id: 67e= d8236 2017-11-10 10:51:33,546Z INFO [org.ovirt.engine.core.vdsbroker.vds= broker.ConnectStorageServerVDSCommand] (org.ovirt.thread.pool-6-thread-46) = [1cccae15] FINISH, ConnectStorageServerVDSCommand, return: {ba9e9b72-cade-4= e16-82fe-237ff7143b3b=3D0}, log id: 67ed8236 2017-11-10 10:51:33,549Z IN= FO [org.ovirt.engine.core.vdsbroker.irsbroker.AttachStorageDomainVDSCommand= ] (org.ovirt.thread.pool-6-thread-45) [05e7dbb5-bad3-484f-b364-ca635fe1f20b= ] START, AttachStorageDomainVDSCommand( AttachStorageDomainVDSCommandParame= ters:{runAsync=3D'true', storagePoolId=3D'5a0287ce-0233-0170-00a2-000000000= 1d8', ignoreFailoverLimit=3D'false', storageDomainId=3D'875e1af4-ba14-4255-= b6ad-c3031672df93'}), log id: 2f387159 2017-11-10 10:51:34,725Z ERROR [o= rg.ovirt.engine.core.vdsbroker.irsbroker.AttachStorageDomainVDSCommand] (or= g.ovirt.thread.pool-6-thread-45) [05e7dbb5-bad3-484f-b364-ca635fe1f20b] Fai= led in 'AttachStorageDomainVDS' method 2017-11-10 10:51:34,740Z ERROR [o= rg.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (org.o= virt.thread.pool-6-thread-45) [05e7dbb5-bad3-484f-b364-ca635fe1f20b] EVENT_= ID: IRS_BROKER_COMMAND_FAILURE(10,803), Correlation ID: null, Call Stack: n= ull, Custom ID: null, Custom Event ID: -1, Message: VDSM command AttachStor= ageDomainVDS failed: Error in storage domain action: (u'sdUUID=3D875e1af4-b= a14-4255-b6ad-c3031672df93, spUUID=3D5a0287ce-0233-0170-00a2-0000000001d8',= ) 2017-11-10 10:51:34,740Z ERROR [org.ovirt.engine.core.vdsbroker.irsbro= ker.AttachStorageDomainVDSCommand] (org.ovirt.thread.pool-6-thread-45) [05e= 7dbb5-bad3-484f-b364-ca635fe1f20b] Command 'AttachStorageDomainVDSCommand( = AttachStorageDomainVDSCommandParameters:{runAsync=3D'true', storagePoolId= =3D'5a0287ce-0233-0170-00a2-0000000001d8', ignoreFailoverLimit=3D'false', s= torageDomainId=3D'875e1af4-ba14-4255-b6ad-c3031672df93'})' execution failed= : IRSGenericException: IRSErrorException: Failed to AttachStorageDomainVDS,= error =3D Error in storage domain action: (u'sdUUID=3D875e1af4-ba14-4255-b= 6ad-c3031672df93, spUUID=3D5a0287ce-0233-0170-00a2-0000000001d8',), code = =3D 350 2017-11-10 10:51:34,740Z INFO [org.ovirt.engine.core.vdsbroker.i= rsbroker.AttachStorageDomainVDSCommand] (org.ovirt.thread.pool-6-thread-45)= [05e7dbb5-bad3-484f-b364-ca635fe1f20b] FINISH, AttachStorageDomainVDSComma= nd, log id: 2f387159 2017-11-10 10:51:34,740Z ERROR [org.ovirt.engine.co= re.bll.storage.domain.AttachStorageDomainToPoolCommand] (org.ovirt.thread.p= ool-6-thread-45) [05e7dbb5-bad3-484f-b364-ca635fe1f20b] Command 'org.ovirt.= engine.core.bll.storage.domain.AttachStorageDomainToPoolCommand' failed: En= gineException: org.ovirt.engine.core.vdsbroker.irsbroker.IrsOperationFailed= NoFailoverException: IRSGenericException: IRSErrorException: Failed to Atta= chStorageDomainVDS, error =3D Error in storage domain action: (u'sdUUID=3D8= 75e1af4-ba14-4255-b6ad-c3031672df93, spUUID=3D5a0287ce-0233-0170-00a2-00000= 00001d8',), code =3D 350 (Failed with error StorageDomainActionError and co= de 350) 2017-11-10 10:51:34,741Z INFO [org.ovirt.engine.core.bll.storage= .domain.AttachStorageDomainToPoolCommand] (org.ovirt.thread.pool-6-thread-4= 5) [05e7dbb5-bad3-484f-b364-ca635fe1f20b] Command [id=3Da392af75-7ebe-499a-= b2ed-765651834557]: Compensating NEW_ENTITY_ID of org.ovirt.engine.core.com= mon.businessentities.StoragePoolIsoMap; snapshot: StoragePoolIsoMapId:{stor= agePoolId=3D'5a0287ce-0233-0170-00a2-0000000001d8', storageId=3D'875e1af4-b= a14-4255-b6ad-c3031672df93'}. 2017-11-10 10:51:34,746Z ERROR [org.ovirt.= engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (org.ovirt.thre= ad.pool-6-thread-45) [05e7dbb5-bad3-484f-b364-ca635fe1f20b] EVENT_ID: USER_= ATTACH_STORAGE_DOMAIN_TO_POOL_FAILED(963), Correlation ID: 05e7dbb5-bad3-48= 4f-b364-ca635fe1f20b, Job ID: 7f3f27ce-4d45-4b3b-a9ee-c25795658ee4, Call St= ack: null, Custom ID: null, Custom Event ID: -1, Message: Failed to attach = Storage Domain ISO_DOMAIN to Data Center Default. (User: admin@internal-aut= hz) 2017-11-10 10:51:34,748Z INFO [org.ovirt.engine.core.bll.storage.dom= ain.AttachStorageDomainToPoolCommand] (org.ovirt.thread.pool-6-thread-45) [= 05e7dbb5-bad3-484f-b364-ca635fe1f20b] Lock freed to object 'EngineLock:{exc= lusiveLocks=3D'[875e1af4-ba14-4255-b6ad-c3031672df93=3DSTORAGE]', sharedLoc= ks=3D''}' </div> <div><hr style=3D"width: 100%; height: 2px;">Jose Fe= rradeira http://www.logicworks.pt</div></div> </div></div></body></ht= ml> ------=_Part_903046_834258931.1510311699782--

1 1

Re: [ovirt-users] Hosted Engine installation + GlusterFS cluster
by Artem Tambovskiy 10 Nov '17

10 Nov '17

One more thing is - firewall rules. For 3 gluster bricks I have configured following: firewall-cmd --zone=public --add-port=24007-24009/tcp --add-port=49152-49664/tcp --permanent and this seems not enough. have to stop the firewall in order to make the cluster working. I have noticed 490xx being used by gluster, any ideas on that documented range? lsof -i | grep gluster | grep "490" glusterfs 32301 root 10u IPv4 148985 0t0 TCP ovirt1:49159->ovirt1:49099 (ESTABLISHED) glusterfs 32301 root 17u IPv4 153084 0t0 TCP ovirt1:49159->ovirt2:49096 (ESTABLISHED) glusterfs 46346 root 17u IPv4 156437 0t0 TCP ovirt1:49161->ovirt1:49093 (ESTABLISHED) glusterfs 46346 root 18u IPv4 149985 0t0 TCP ovirt1:49161->ovirt2:49090 (ESTABLISHED) glusterfs 46380 root 8u IPv4 151389 0t0 TCP ovirt1:49090->ovirt3:49161 (ESTABLISHED) glusterfs 46380 root 11u IPv4 148986 0t0 TCP ovirt1:49091->ovirt2:49161 (ESTABLISHED) glusterfs 46380 root 21u IPv4 153074 0t0 TCP ovirt1:49099->ovirt1:49159 (ESTABLISHED) glusterfs 46380 root 25u IPv4 153075 0t0 TCP ovirt1:49097->ovirt2:49160 (ESTABLISHED) glusterfs 46380 root 26u IPv4 153076 0t0 TCP ovirt1:49095->ovirt3:49159 (ESTABLISHED) glusterfs 46380 root 27u IPv4 153077 0t0 TCP ovirt1:49093->ovirt1:49161 (ESTABLISHED) Regards, Artem On Thu, Nov 9, 2017 at 3:56 PM, Artem Tambovskiy <artem.tambovskiy(a)gmail.com > wrote: > Hi, > > Just realized that I probably went in the wrong way. Reinstalled > everything from the scratch added 4 volumes (hosted_engine, data, export, > iso). All looks good so far. > But if go to the Cluster properties and tick the checkbox "Enable Cluster > Service" - the host will be marked as Non-Operational. Am I messing up the > things? > Or I'm just fine as long as I already have a Data (Master) Storage Domain > over GlusterFS? > > Regards, > Artem > > On Thu, Nov 9, 2017 at 2:46 PM, Fred Rolland <frolland(a)redhat.com> wrote: > >> Hi, >> >> The steps for this kind of setup are described in [1]. >> However it seems you have already succeeded in installing, so maybe you >> need some additional steps [2] >> Did you add a storage domain that will act as Master Domain? It is >> needed, then the initial Storage Domain should be imported automatically. >> >> >> [1] https://www.ovirt.org/blog/2017/04/up-and-running-with-ovirt >> -4.1-and-gluster-storage/ >> [2] https://www.ovirt.org/documentation/gluster-hyperconverged/ >> chap-Additional_Steps/ >> >> On Thu, Nov 9, 2017 at 10:50 AM, Artem Tambovskiy < >> artem.tambovskiy(a)gmail.com> wrote: >> >>> Another yet attempt to get a help on hosted-engine deployment with >>> glusterfs cluster. >>> I already spend a day trying to get bring such a setup to work with no >>> luck. >>> >>> The hosted engine being successfully deployed but I can't activate the >>> host, the storage domain for the host is missing and I can't even add it. >>> So either something went wrong during deployment or my glusterfs cluster >>> doesn't configured properly. >>> >>> That are the prerequisites for this? >>> >>> - glusterfs cluster of 3 nodes with replica 3 volume >>> - Any specific volume configs? >>> - how many volumes should I prepare for hosted engine deployment? >>> >>> Any other thoughts? >>> >>> Regards, >>> Artem >>> >>> _______________________________________________ >>> Users mailing list >>> Users(a)ovirt.org >>> http://lists.ovirt.org/mailman/listinfo/users >>> >>> >> >

2 1

ovirt viewer mouse not working
by Foo, Steven 10 Nov '17

10 Nov '17

--_000_0A873EDB0DC23A489A95F988B9F7CDF83CFEAFCAPENMAIL1apacads_ Content-Type: text/plain; charset="us-ascii" Content-Transfer-Encoding: quoted-printable Hi, We are testing the KVM for first time. RHEV-M Host: Version 4.1.6.2-1.el7.centos RHEV-H Node: ovirt-node-ng-installer-ovirt-4.1-2017102306 Noteboook OS: Windows 10 Broswer: Chrome / Firefox Viewer: virt-viewer-x64-6.0 After installing the above and setup DC, Domain Storage/ISO, Host and insta= ll a test linux centos 7 VM. Installation goes find. It was able to boot up after install. We face the issue where by the console screen mouse just get hang and keybo= ard is not responding. Our setting for console is as below: Graphics Protocol: SPICE Video Type: QXL We tried other setting as well, however the console mouse and keyboard is n= ot working. Any idea? Best Regards Steven Foo --_000_0A873EDB0DC23A489A95F988B9F7CDF83CFEAFCAPENMAIL1apacads_ Content-Type: text/html; charset="us-ascii" Content-Transfer-Encoding: quoted-printable <html xmlns:v=3D"urn:schemas-microsoft-com:vml" xmlns:o=3D"urn:schemas-micr= osoft-com:office:office" xmlns:w=3D"urn:schemas-microsoft-com:office:word" = xmlns:m=3D"http://schemas.microsoft.com/office/2004/12/omml" xmlns=3D"http:= //www.w3.org/TR/REC-html40"> <head> <meta http-equiv=3D"Content-Type" content=3D"text/html; charset=3Dus-ascii"= > <meta name=3D"Generator" content=3D"Microsoft Word 15 (filtered medium)"> <style></style> </head> <body lang=3D"EN-MY" link=3D"#0563C1" vlink=3D"#954F72"> <div class=3D"WordSection1"> Hi,<o:p></o:p> <o:p> </o:p> We are testing the KVM for first time.<o:p></o:p></p= > <o:p> </o:p> RHEV-M Host: Version 4.1.6.2-1.el7.centos<o:p></o:p>= RHEV-H Node: ovirt-node-ng-installer-ovirt-4.1-20171= 02306<o:p></o:p> <o:p> </o:p> Noteboook<o:p></o:p> OS: Windows 10<o:p></o:p> Broswer: Chrome / Firefox<o:p></o:p> Viewer: virt-viewer-x64-6.0<o:p></o:p> <o:p> </o:p> After installing the above and setup DC, Domain Stor= age/ISO, Host and install a test linux centos 7 VM.<o:p></o:p> <o:p> </o:p> Installation goes find. It was able to boot up after= install. <o:p></o:p> <o:p> </o:p> We face the issue where by the console screen mouse = just get hang and keyboard is not responding.<o:p></o:p> <o:p> </o:p> Our setting for console is as below:<o:p></o:p> Graphics Protocol: SPICE<o:p></o:p> Video Type: QXL<o:p></o:p> <o:p> </o:p> We tried other setting as well, however the console = mouse and keyboard is not working.<o:p></o:p> <o:p> </o:p> Any idea?<o:p></o:p> <o:p> </o:p> Bes= t Regards Steven Foo <o:p></o:p> <o:p> </o:p> </div> </body> </html> --_000_0A873EDB0DC23A489A95F988B9F7CDF83CFEAFCAPENMAIL1apacads_--

3 4

engine FQDN
by 董青龙 09 Nov '17

09 Nov '17

------=_Part_25585_1240810320.1510190806301 Content-Type: text/plain; charset=GBK Content-Transfer-Encoding: base64 SGksYWxsCiAgICBJIGhhdmUgYW4gZW52aXJvbm1lbnQgb2Ygb3ZpcnQgNC4xLjIuMiwgYW5kIHRo ZSBlbmdpbmUgaXMgaG9zdGVkIGVuZ2luZS4gSSB1c2VkIGEgRlFETiB3aGljaCBjb3VsZCBiZSBv bmx5IHJlc29sdmVkIGxvY2FsbHkgd2hlbiB0aGUgZW52aXJvbm1lbnQgd2FzIGRlcGxveWVkLiBB IHJlY29yZCBvZiB0aGUgZW5naW5lIEZRRE4gd2FzIGFkZGVkIGluIC9ldGMvaG9zdHMgb2YgYWxs IGhvc3RzIGFuZCBlbmdpbmUuICBOb3cgSSB3YW50IHRvIGNoYW5nZSBlbmdpbmUgRlFETiB0byBh bm90aGVyIG9uZSB3aGljaCBjYW4gYmUgcmVzb2x2ZWQgb24gaW50ZXJuZXQuIFdoYXQgc2hvdWxk IEkgZG8/IEkgZm91bmQgdGhhdCB0aGUgZW5naW5lIGNvdWxkIG5vdCBiZSBhY2Nlc3NlZCBieSBJ UC4KICAgIEFueW9uZSBjYW4gaGVscD8gVGhhbmtzIQ== ------=_Part_25585_1240810320.1510190806301 Content-Type: text/html; charset=GBK Content-Transfer-Encoding: base64 PGRpdiBzdHlsZT0ibGluZS1oZWlnaHQ6MS43O2NvbG9yOiMwMDAwMDA7Zm9udC1zaXplOjE0cHg7 Zm9udC1mYW1pbHk6QXJpYWwiPjxkaXY+SGksYWxsPC9kaXY+PGRpdj4mbmJzcDsgJm5ic3A7IEkg aGF2ZSBhbiBlbnZpcm9ubWVudCBvZiBvdmlydCA0LjEuMi4yLCBhbmQgdGhlIGVuZ2luZSBpcyBo b3N0ZWQgZW5naW5lLiBJIHVzZWQgYSBGUUROIHdoaWNoIGNvdWxkIGJlIG9ubHkmbmJzcDs8c3Bh biBzdHlsZT0iYmFja2dyb3VuZC1jb2xvcjogcmdiKDI0OSwgMjUxLCAyNTIpOyBjb2xvcjogcmdi KDQ2LCA0OCwgNTEpOyBmb250LWZhbWlseTogQXJpYWwsICZxdW90O01pY3Jvc29mdCBZYUhlaSZx dW90OywgzqLI7dHFutosIMvOzOUsICZxdW90O01hbGd1biBHb3RoaWMmcXVvdDssIE1laXJ5bywg c2Fucy1zZXJpZjsgZm9udC1zaXplOiAxM3B4OyI+cmVzb2x2ZWQgbG9jYWxseSB3aGVuIHRoZSZu YnNwOzwvc3Bhbj5lbnZpcm9ubWVudCZuYnNwO3dhcyBkZXBsb3llZC4gQSByZWNvcmQgb2YgdGhl IGVuZ2luZSBGUUROIHdhcyBhZGRlZCBpbiAvZXRjL2hvc3RzIG9mIGFsbCBob3N0cyBhbmQgZW5n aW5lLiAmbmJzcDtOb3cgSSB3YW50IHRvIGNoYW5nZSBlbmdpbmUgRlFETiB0byBhbm90aGVyIG9u ZSB3aGljaCBjYW4gYmUgcmVzb2x2ZWQgb24gaW50ZXJuZXQuIFdoYXQgc2hvdWxkIEkgZG8/IEkg Zm91bmQgdGhhdCB0aGUgZW5naW5lIGNvdWxkIG5vdCBiZSBhY2Nlc3NlZCBieSBJUC48L2Rpdj48 ZGl2PiZuYnNwOyAmbmJzcDsgQW55b25lIGNhbiBoZWxwPyBUaGFua3MhPC9kaXY+PC9kaXY+PGJy Pjxicj48c3BhbiB0aXRsZT0ibmV0ZWFzZWZvb3RlciI+PHA+Jm5ic3A7PC9wPjwvc3Bhbj4= ------=_Part_25585_1240810320.1510190806301--

3 3

Fixed ovirt-node-ng packages
by Lev Veyde 09 Nov '17

09 Nov '17

Unfortunately shortly after the oVirt 4.1.7 release we discovered a critical issue with ovirt-node-ng. The issue was promptly handled, and fixed packages were pushed into the repo. Also the ovirt-node-ng-installer ISO was rebuilt. Please make sure to update your repo and use the fixed ovirt-node-ng-installer ISO: http://plain.resources.ovirt.org/pub/ovirt-4.1/iso/ovirt-node-ng-installer-… -- Lev Veyde Software Engineer, RHCE | RHCVA | MCITP Red Hat Israel <https://www.redhat.com> lev(a)redhat.com | lveyde(a)redhat.com <https://red.ht/sig> TRIED. TESTED. TRUSTED. <https://redhat.com/trusted>

2 3