Follow the content of file /var/log/ovirt-engine/engine.log when I'm trying to attach
ISO Domain to Datacenter:
2019-11-18 13:32:16,146-03 INFO
[org.ovirt.engine.core.bll.storage.domain.AttachStorageDomainToPoolCommand] (default
task-26) [f5555798-4334-40ca-ba43-e97e4be396db] Lock Acquired to object
'EngineLock:{exclusiveLocks='[e6b34c42-0ca6-41f4-be3e-3c9b2af1747b=STORAGE]',
sharedLocks=''}'
2019-11-18 13:32:16,241-03 INFO
[org.ovirt.engine.core.bll.storage.domain.AttachStorageDomainToPoolCommand]
(EE-ManagedThreadFactory-engine-Thread-178210) [f5555798-4334-40ca-ba43-e97e4be396db]
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-18 13:32:16,261-03 INFO
[org.ovirt.engine.core.bll.storage.connection.ConnectStorageToVdsCommand]
(EE-ManagedThreadFactory-engine-Thread-178211) [20e44128] 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-18 13:32:16,261-03 INFO
[org.ovirt.engine.core.bll.storage.connection.ConnectStorageToVdsCommand]
(EE-ManagedThreadFactory-engine-Thread-178212) [a7f5e93] 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-18 13:32:16,266-03 INFO
[org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStorageServerVDSCommand]
(EE-ManagedThreadFactory-engine-Thread-178212) [a7f5e93] 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: 2c81e6d5
2019-11-18 13:32:16,266-03 INFO
[org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStorageServerVDSCommand]
(EE-ManagedThreadFactory-engine-Thread-178211) [20e44128] 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: 482f56e6
2019-11-18 13:32:16,385-03 INFO
[org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStorageServerVDSCommand]
(EE-ManagedThreadFactory-engine-Thread-178211) [20e44128] FINISH,
ConnectStorageServerVDSCommand, return: {ff0b050c-1b20-41fb-8373-32d9991a327e=0}, log id:
482f56e6
2019-11-18 13:32:16,385-03 INFO
[org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStorageServerVDSCommand]
(EE-ManagedThreadFactory-engine-Thread-178212) [a7f5e93] FINISH,
ConnectStorageServerVDSCommand, return: {ff0b050c-1b20-41fb-8373-32d9991a327e=0}, log id:
2c81e6d5
2019-11-18 13:32:16,387-03 INFO
[org.ovirt.engine.core.vdsbroker.irsbroker.AttachStorageDomainVDSCommand]
(EE-ManagedThreadFactory-engine-Thread-178210) [f5555798-4334-40ca-ba43-e97e4be396db]
START, AttachStorageDomainVDSCommand(
AttachStorageDomainVDSCommandParameters:{storagePoolId='dd2a11c0-f450-11e9-8e3b-0050568ac2b9',
ignoreFailoverLimit='false',
storageDomainId='e6b34c42-0ca6-41f4-be3e-3c9b2af1747b'}), log id: 2263565b
2019-11-18 13:32:16,801-03 ERROR
[org.ovirt.engine.core.vdsbroker.irsbroker.AttachStorageDomainVDSCommand]
(EE-ManagedThreadFactory-engine-Thread-178210) [f5555798-4334-40ca-ba43-e97e4be396db]
Failed in 'AttachStorageDomainVDS' method
2019-11-18 13:32:16,838-03 ERROR
[org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector]
(EE-ManagedThreadFactory-engine-Thread-178210) [f5555798-4334-40ca-ba43-e97e4be396db]
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-18 13:32:16,838-03 ERROR
[org.ovirt.engine.core.vdsbroker.irsbroker.AttachStorageDomainVDSCommand]
(EE-ManagedThreadFactory-engine-Thread-178210) [f5555798-4334-40ca-ba43-e97e4be396db]
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-18 13:32:16,838-03 INFO
[org.ovirt.engine.core.vdsbroker.irsbroker.AttachStorageDomainVDSCommand]
(EE-ManagedThreadFactory-engine-Thread-178210) [f5555798-4334-40ca-ba43-e97e4be396db]
FINISH, AttachStorageDomainVDSCommand, return: , log id: 2263565b
2019-11-18 13:32:16,838-03 ERROR
[org.ovirt.engine.core.bll.storage.domain.AttachStorageDomainToPoolCommand]
(EE-ManagedThreadFactory-engine-Thread-178210) [f5555798-4334-40ca-ba43-e97e4be396db]
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-18 13:32:16,839-03 INFO [org.ovirt.engine.core.bll.CommandCompensator]
(EE-ManagedThreadFactory-engine-Thread-178210) [f5555798-4334-40ca-ba43-e97e4be396db]
Command [id=e38e591b-f48d-4aff-959e-e3470d73cead]: 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-18 13:32:16,844-03 ERROR
[org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector]
(EE-ManagedThreadFactory-engine-Thread-178210) [f5555798-4334-40ca-ba43-e97e4be396db]
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-18 13:32:16,847-03 INFO
[org.ovirt.engine.core.bll.storage.domain.AttachStorageDomainToPoolCommand]
(EE-ManagedThreadFactory-engine-Thread-178210) [f5555798-4334-40ca-ba43-e97e4be396db] Lock
freed to object
'EngineLock:{exclusiveLocks='[e6b34c42-0ca6-41f4-be3e-3c9b2af1747b=STORAGE]',
sharedLocks=''}'
In other hand, file /var/log/vdsm/vdsm.log didn't show anything related to this
issue:
2019-11-18 13:32:15,014-0300 INFO (periodic/0) [vdsm.api] START repoStats(domains=())
from=internal, task_id=73ae4a01-397e-4b66-a481-a422ccd570b4 (api:48)
2019-11-18 13:32:15,014-0300 INFO (periodic/0) [vdsm.api] FINISH repoStats return={}
from=internal, task_id=73ae4a01-397e-4b66-a481-a422ccd570b4 (api:54)
2019-11-18 13:32:20,604-0300 INFO (jsonrpc/7) [api.host] START getAllVmStats()
from=::ffff:127.0.0.1,46370 (api:48)
2019-11-18 13:32:20,605-0300 INFO (jsonrpc/7) [api.host] FINISH getAllVmStats
return={'status': {'message': 'Done', 'code': 0},
'statsList': (suppressed)} from=::ffff:127.0.0.1,46370 (api:54)
2019-11-18 13:32:20,605-0300 INFO (jsonrpc/7) [jsonrpc.JsonRpcServer] RPC call
Host.getAllVmStats succeeded in 0.00 seconds (__init__:312)