When trying to attach ISO Domain to Datacenter, the following lines are populated on file
/var/log/ovirt-engine/engine.log:
2019-11-17 15:02:44,269-03 INFO
[org.ovirt.engine.core.bll.storage.domain.AttachStorageDomainToPoolCommand] (default
task-15) [f3795b36-ac1b-4115-9cfd-69e1b13783c9] Lock Acquired to object
'EngineLock:{exclusiveLocks='[e6b34c42-0ca6-41f4-be3e-3c9b2af1747b=STORAGE]',
sharedLocks=''}'
2019-11-17 15:02:44,301-03 INFO
[org.ovirt.engine.core.bll.storage.domain.AttachStorageDomainToPoolCommand]
(EE-ManagedThreadFactory-engine-Thread-138287) [f3795b36-ac1b-4115-9cfd-69e1b13783c9]
Running command: AttachStorageDomainToPoolCommand internal: false. Entities affected :
ID: e6b34c42-0ca6-41f4-be3e-3c9b2af1747b Type: StorageAction group
MANIPULATE_STORAGE_DOMAIN with role type ADMIN, ID: dd2a11c0-f450-11e9-8e3b-0050568ac2b9
Type: StoragePoolAction group MANIPULATE_STORAGE_DOMAIN with role type ADMIN
2019-11-17 15:02:44,315-03 INFO
[org.ovirt.engine.core.bll.storage.connection.ConnectStorageToVdsCommand]
(EE-ManagedThreadFactory-engine-Thread-138289) [2c0665e4] Running command:
ConnectStorageToVdsCommand internal: true. Entities affected : ID:
aaa00000-0000-0000-0000-123456789aaa Type: SystemAction group CREATE_STORAGE_DOMAIN with
role type ADMIN
2019-11-17 15:02:44,315-03 INFO
[org.ovirt.engine.core.bll.storage.connection.ConnectStorageToVdsCommand]
(EE-ManagedThreadFactory-engine-Thread-138291) [40c539bc] Running command:
ConnectStorageToVdsCommand internal: true. Entities affected : ID:
aaa00000-0000-0000-0000-123456789aaa Type: SystemAction group CREATE_STORAGE_DOMAIN with
role type ADMIN
2019-11-17 15:02:44,318-03 INFO
[org.ovirt.engine.core.bll.storage.connection.ConnectStorageToVdsCommand]
(EE-ManagedThreadFactory-engine-Thread-138290) [7b359087] Running command:
ConnectStorageToVdsCommand internal: true. Entities affected : ID:
aaa00000-0000-0000-0000-123456789aaa Type: SystemAction group CREATE_STORAGE_DOMAIN with
role type ADMIN
2019-11-17 15:02:44,319-03 INFO
[org.ovirt.engine.core.bll.storage.connection.ConnectStorageToVdsCommand]
(EE-ManagedThreadFactory-engine-Thread-138288) [505dd498] Running command:
ConnectStorageToVdsCommand internal: true. Entities affected : ID:
aaa00000-0000-0000-0000-123456789aaa Type: SystemAction group CREATE_STORAGE_DOMAIN with
role type ADMIN
2019-11-17 15:02:44,321-03 INFO
[org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStorageServerVDSCommand]
(EE-ManagedThreadFactory-engine-Thread-138291) [40c539bc] START,
ConnectStorageServerVDSCommand(HostName = ZeLele,
StorageServerConnectionManagementVDSParameters:{hostId='86198321-38ff-4596-8c24-2f9442928009',
storagePoolId='00000000-0000-0000-0000-000000000000', storageType='NFS',
connectionList='[StorageServerConnections:{id='ff0b050c-1b20-41fb-8373-32d9991a327e',
connection='nholau:/storage/iso', iqn='null', vfsType='null',
mountOptions='null', nfsVersion='AUTO', nfsRetrans='null',
nfsTimeo='null', iface='null', netIfaceName='null'}]',
sendNetworkEventOnFailure='true'}), log id: 742a0d4d
2019-11-17 15:02:44,338-03 INFO
[org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStorageServerVDSCommand]
(EE-ManagedThreadFactory-engine-Thread-138289) [2c0665e4] START,
ConnectStorageServerVDSCommand(HostName = ChicoBento,
StorageServerConnectionManagementVDSParameters:{hostId='c5018534-42b6-497a-8107-91493193d088',
storagePoolId='00000000-0000-0000-0000-000000000000', storageType='NFS',
connectionList='[StorageServerConnections:{id='ff0b050c-1b20-41fb-8373-32d9991a327e',
connection='nholau:/storage/iso', iqn='null', vfsType='null',
mountOptions='null', nfsVersion='AUTO', nfsRetrans='null',
nfsTimeo='null', iface='null', netIfaceName='null'}]',
sendNetworkEventOnFailure='true'}), log id: 65f576b9
2019-11-17 15:02:44,339-03 INFO
[org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStorageServerVDSCommand]
(EE-ManagedThreadFactory-engine-Thread-138290) [7b359087] START,
ConnectStorageServerVDSCommand(HostName = NhoLau,
StorageServerConnectionManagementVDSParameters:{hostId='0fc23e51-3e84-46df-9fd6-e97d83d32040',
storagePoolId='00000000-0000-0000-0000-000000000000', storageType='NFS',
connectionList='[StorageServerConnections:{id='ff0b050c-1b20-41fb-8373-32d9991a327e',
connection='nholau:/storage/iso', iqn='null', vfsType='null',
mountOptions='null', nfsVersion='AUTO', nfsRetrans='null',
nfsTimeo='null', iface='null', netIfaceName='null'}]',
sendNetworkEventOnFailure='true'}), log id: 6ed52cef
2019-11-17 15:02:44,344-03 INFO
[org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStorageServerVDSCommand]
(EE-ManagedThreadFactory-engine-Thread-138288) [505dd498] START,
ConnectStorageServerVDSCommand(HostName = Rosinha,
StorageServerConnectionManagementVDSParameters:{hostId='a838a3d5-8f52-44cd-a293-e530692814a3',
storagePoolId='00000000-0000-0000-0000-000000000000', storageType='NFS',
connectionList='[StorageServerConnections:{id='ff0b050c-1b20-41fb-8373-32d9991a327e',
connection='nholau:/storage/iso', iqn='null', vfsType='null',
mountOptions='null', nfsVersion='AUTO', nfsRetrans='null',
nfsTimeo='null', iface='null', netIfaceName='null'}]',
sendNetworkEventOnFailure='true'}), log id: 796b2168
2019-11-17 15:02:44,348-03 INFO
[org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStorageServerVDSCommand]
(EE-ManagedThreadFactory-engine-Thread-138291) [40c539bc] FINISH,
ConnectStorageServerVDSCommand, return: {ff0b050c-1b20-41fb-8373-32d9991a327e=0}, log id:
742a0d4d
2019-11-17 15:02:44,363-03 INFO
[org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStorageServerVDSCommand]
(EE-ManagedThreadFactory-engine-Thread-138289) [2c0665e4] FINISH,
ConnectStorageServerVDSCommand, return: {ff0b050c-1b20-41fb-8373-32d9991a327e=0}, log id:
65f576b9
2019-11-17 15:02:44,365-03 INFO
[org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStorageServerVDSCommand]
(EE-ManagedThreadFactory-engine-Thread-138290) [7b359087] FINISH,
ConnectStorageServerVDSCommand, return: {ff0b050c-1b20-41fb-8373-32d9991a327e=0}, log id:
6ed52cef
2019-11-17 15:02:44,368-03 INFO
[org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStorageServerVDSCommand]
(EE-ManagedThreadFactory-engine-Thread-138288) [505dd498] FINISH,
ConnectStorageServerVDSCommand, return: {ff0b050c-1b20-41fb-8373-32d9991a327e=0}, log id:
796b2168
2019-11-17 15:02:44,371-03 INFO
[org.ovirt.engine.core.vdsbroker.irsbroker.AttachStorageDomainVDSCommand]
(EE-ManagedThreadFactory-engine-Thread-138287) [f3795b36-ac1b-4115-9cfd-69e1b13783c9]
START, AttachStorageDomainVDSCommand(
AttachStorageDomainVDSCommandParameters:{storagePoolId='dd2a11c0-f450-11e9-8e3b-0050568ac2b9',
ignoreFailoverLimit='false',
storageDomainId='e6b34c42-0ca6-41f4-be3e-3c9b2af1747b'}), log id: 133801f9
2019-11-17 15:02:44,766-03 ERROR
[org.ovirt.engine.core.vdsbroker.irsbroker.AttachStorageDomainVDSCommand]
(EE-ManagedThreadFactory-engine-Thread-138287) [f3795b36-ac1b-4115-9cfd-69e1b13783c9]
Failed in 'AttachStorageDomainVDS' method
2019-11-17 15:02:44,813-03 ERROR
[org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector]
(EE-ManagedThreadFactory-engine-Thread-138287) [f3795b36-ac1b-4115-9cfd-69e1b13783c9]
EVENT_ID: IRS_BROKER_COMMAND_FAILURE(10,803), VDSM command AttachStorageDomainVDS failed:
Cannot obtain lock: u"id=e6b34c42-0ca6-41f4-be3e-3c9b2af1747b, rc=1, out=[],
err=['setsid: failed to execute /usr/bin/ionice: Permission denied']"
2019-11-17 15:02:44,813-03 ERROR
[org.ovirt.engine.core.vdsbroker.irsbroker.AttachStorageDomainVDSCommand]
(EE-ManagedThreadFactory-engine-Thread-138287) [f3795b36-ac1b-4115-9cfd-69e1b13783c9]
Command 'AttachStorageDomainVDSCommand(
AttachStorageDomainVDSCommandParameters:{storagePoolId='dd2a11c0-f450-11e9-8e3b-0050568ac2b9',
ignoreFailoverLimit='false',
storageDomainId='e6b34c42-0ca6-41f4-be3e-3c9b2af1747b'})' execution failed:
IRSGenericException: IRSErrorException: Failed to AttachStorageDomainVDS, error = Cannot
obtain lock: u"id=e6b34c42-0ca6-41f4-be3e-3c9b2af1747b, rc=1, out=[],
err=['setsid: failed to execute /usr/bin/ionice: Permission denied']", code =
651
2019-11-17 15:02:44,813-03 INFO
[org.ovirt.engine.core.vdsbroker.irsbroker.AttachStorageDomainVDSCommand]
(EE-ManagedThreadFactory-engine-Thread-138287) [f3795b36-ac1b-4115-9cfd-69e1b13783c9]
FINISH, AttachStorageDomainVDSCommand, return: , log id: 133801f9
2019-11-17 15:02:44,813-03 ERROR
[org.ovirt.engine.core.bll.storage.domain.AttachStorageDomainToPoolCommand]
(EE-ManagedThreadFactory-engine-Thread-138287) [f3795b36-ac1b-4115-9cfd-69e1b13783c9]
Command
'org.ovirt.engine.core.bll.storage.domain.AttachStorageDomainToPoolCommand'
failed: EngineException:
org.ovirt.engine.core.vdsbroker.irsbroker.IrsOperationFailedNoFailoverException:
IRSGenericException: IRSErrorException: Failed to AttachStorageDomainVDS, error = Cannot
obtain lock: u"id=e6b34c42-0ca6-41f4-be3e-3c9b2af1747b, rc=1, out=[],
err=['setsid: failed to execute /usr/bin/ionice: Permission denied']", code =
651 (Failed with error AcquireLockFailure and code 651)
2019-11-17 15:02:44,815-03 INFO [org.ovirt.engine.core.bll.CommandCompensator]
(EE-ManagedThreadFactory-engine-Thread-138287) [f3795b36-ac1b-4115-9cfd-69e1b13783c9]
Command [id=37e3914f-d199-4578-8ba5-278adfd0f06f]: Compensating NEW_ENTITY_ID of
org.ovirt.engine.core.common.businessentities.StoragePoolIsoMap; snapshot:
StoragePoolIsoMapId:{storagePoolId='dd2a11c0-f450-11e9-8e3b-0050568ac2b9',
storageId='e6b34c42-0ca6-41f4-be3e-3c9b2af1747b'}.
2019-11-17 15:02:44,819-03 ERROR
[org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector]
(EE-ManagedThreadFactory-engine-Thread-138287) [f3795b36-ac1b-4115-9cfd-69e1b13783c9]
EVENT_ID: USER_ATTACH_STORAGE_DOMAIN_TO_POOL_FAILED(963), Failed to attach Storage Domain
iso-wise to Data Center Wise. (User: ivan@wct.local(a)wct.local-authz)
2019-11-17 15:02:44,823-03 INFO
[org.ovirt.engine.core.bll.storage.domain.AttachStorageDomainToPoolCommand]
(EE-ManagedThreadFactory-engine-Thread-138287) [f3795b36-ac1b-4115-9cfd-69e1b13783c9] Lock
freed to object
'EngineLock:{exclusiveLocks='[e6b34c42-0ca6-41f4-be3e-3c9b2af1747b=STORAGE]',
sharedLocks=''}'
About /var/log/vdsm.vdsm.log, nothing special is logged besides these lines:
2019-11-17 15:04:37,304-0300 INFO (periodic/1) [vdsm.api] START repoStats(domains=())
from=internal, task_id=b298b588-c81e-4c50-804d-3ae24fc74380 (api:48)
2019-11-17 15:04:37,304-0300 INFO (periodic/1) [vdsm.api] FINISH repoStats return={}
from=internal, task_id=b298b588-c81e-4c50-804d-3ae24fc74380 (api:54)
2019-11-17 15:04:38,949-0300 INFO (jsonrpc/6) [api.host] START getAllVmStats()
from=::ffff:127.0.0.1,46370 (api:48)
2019-11-17 15:04:38,949-0300 INFO (jsonrpc/6) [api.host] FINISH getAllVmStats
return={'status': {'message': 'Done', 'code': 0},
'statsList': (suppressed)} from=::ffff:127.0.0.1,46370 (api:54)