Re: [ovirt-users] ovirt - can't attach master domain II

[p...@email.cz]

prereq: 2KVM12-P2 = master domain
---------------------------------------------------------
YES - I'm using gluster.fuse NFS
localhost:/2KVM12-P2 on 
/rhev/data-center/mnt/glusterSD/localhost:_2KVM12-P2 type fuse.glusterfs 
(rw,relatime,user_id=0,group_id=0,default_permissions,allow_other,max_read=131072)
---------------------------------------------------------
Healing
======
# gluster volume heal 2KVM12-P2 info
Brick 16.0.0.164:/STORAGES/g1r5p2/GFS
Number of entries: 0
Brick 16.0.0.163:/STORAGES/g1r5p2/GFS
Number of entries: 0

# while true; do for vol in `gluster volume list`; do gluster volume 
heal $vol info | sort | grep "Number of entries" | awk -F: '{tot+=$2} 
END { printf("Heal entries for '"$vol"': %d\n", $tot);}'; done; sleep 
120; echo -e "\n==================\n"; done
Heal entries for 1KVM12-BCK: 1
Heal entries for 1KVM12-P1: 1
Heal entries for 1KVM12-P2: 0
Heal entries for 1KVM12-P3: 0
Heal entries for 1KVM12-P4: 0
Heal entries for 1KVM12-P5: 0
Heal entries for 2KVM12-P1: 1
Heal entries for 2KVM12-P2: 0
Heal entries for 2KVM12-P3: 0
Heal entries for 2KVM12-P5: 0
Heal entries for 2KVM12_P4: 1

# gluster volume heal 1KVM12-BCK info split-brain
    Brick 16.0.0.161:/STORAGES/g2r5p1/GFS
    /0fcad888-d573-47be-bef3-0bc0b7a99fb7/dom_md/ids
    Number of entries in split-brain: 1

    Brick 16.0.0.162:/STORAGES/g2r5p1/GFS
    /0fcad888-d573-47be-bef3-0bc0b7a99fb7/dom_md/ids
    Number of entries in split-brain: 1

# gluster volume heal 1KVM12-P1 info split-brain
    Brick 16.0.0.161:/STORAGES/g1r5p1/GFS
    /__DIRECT_IO_TEST__
    Number of entries in split-brain: 1

    Brick 16.0.0.162:/STORAGES/g1r5p1/GFS
    /__DIRECT_IO_TEST__
    Number of entries in split-brain: 1

etc......


YES - in split brain , but NOT master domain ( will solve later, after 
master - if possible  )

---------------------------------------------------------------
vdsm.log
=========

Thread-461::DEBUG::2016-02-24 
11:12:45,328::fileSD::262::Storage.Misc.excCmd::(getReadDelay) SUCCESS: 
<err> = '0+1 records in\n0+1 records out\n333 bytes (333 B) copied, 
0.000724379 s, 460 kB/s\n'; <rc> = 0
Thread-461::INFO::2016-02-24 
11:12:45,331::clusterlock::219::Storage.SANLock::(acquireHostId) 
Acquiring host id for domain 88adbd49-62d6-45b1-9992-b04464a04112 (id: 3)
Thread-461::DEBUG::2016-02-24 
11:12:45,331::clusterlock::237::Storage.SANLock::(acquireHostId) Host id 
for domain 88adbd49-62d6-45b1-9992-b04464a04112 successfully acquired 
(id: 3)
Thread-33186::DEBUG::2016-02-24 
11:12:46,067::__init__::481::jsonrpc.JsonRpcServer::(_serveRequest) 
Calling 'GlusterVolume.list' in bridge with {}
Thread-33186::DEBUG::2016-02-24 
11:12:46,204::__init__::514::jsonrpc.JsonRpcServer::(_serveRequest) 
Return 'GlusterVolume.list' in bridge with {'volumes': {'2KVM12-P5': 
{'transportType': ['TCP'], 'uuid': 
'4a6d775d-4a51-4f6c-9bfa-f7ef57f3ca1d', 'bricks': 
['16.0.0.164:/STORAGES/g1r5p5/GFS', '16.0.0.163:/STORAGES/g1r5p5/GFS'], 
'volumeName': '2KVM12-P5', 'volumeType': 'REPLICATE', 'replicaCount': 
'2', 'brickCount': '2', 'distCount': '2', 'volumeStatus': 'ONLINE', 
'stripeCount': '1', 'bricksInfo': [{'name': 
'16.0.0.164:/STORAGES/g1r5p5/GFS', 'hostUuid': 
'06854ac0-2ef1-4c12-bb8d-56cf9bf95ec9'}, {'name': 
'16.0.0.163:/STORAGES/g1r5p5/GFS', 'hostUuid': 
'6482ae32-25ac-41b5-b41d-b7ddf49bac2c'}], 'options': 
{'cluster.server-quorum-type': 'none', 'cluster.eager-lock': 'enable', 
'performance.stat-prefetch': 'off', 'cluster.quorum-type': 'fixed', 
'performance.quick-read': 'off', 'network.remote-dio': 'enable', 
'cluster.quorum-count': '1', 'performance.io-cache': 'off', 
'storage.owner-uid': '36', 'performance.read-ahead': 'off', 
'storage.owner-gid': '36'}}, '2KVM12_P4': {'transportType': ['TCP'], 
'uuid': '18310aeb-639f-4b6d-9ef4-9ef560d6175c', 'bricks': 
['16.0.0.163:/STORAGES/g1r5p4/GFS', '16.0.0.164:/STORAGES/g1r5p4/GFS'], 
'volumeName': '2KVM12_P4', 'volumeType': 'REPLICATE', 'replicaCount': 
'2', 'brickCount': '2', 'distCount': '2', 'volumeStatus': 'ONLINE', 
'stripeCount': '1', 'bricksInfo': [{'name': 
'16.0.0.163:/STORAGES/g1r5p4/GFS', 'hostUuid': 
'6482ae32-25ac-41b5-b41d-b7ddf49bac2c'}, {'name': 
'16.0.0.164:/STORAGES/g1r5p4/GFS', 'hostUuid': 
'06854ac0-2ef1-4c12-bb8d-56cf9bf95ec9'}], 'options': 
{'cluster.server-quorum-type': 'none', 'cluster.eager-lock': 'enable', 
'performance.io-cache': 'off', 'performance.stat-prefetch': 'off', 
'cluster.quorum-type': 'fixed', 'performance.quick-read': 'off', 
'network.remote-dio': 'enable', 'cluster.quorum-count': '1', 
'storage.owner-uid': '36', 'performance.read-ahead': 'off', 
'storage.owner-gid': '36'}}, '2KVM12-P1': {'transportType': ['TCP'], 
'uuid': 'cbf142f8-a40b-4cf4-ad29-2243c81d30c1', 'bricks': 
['16.0.0.163:/STORAGES/g1r5p1/GFS', '16.0.0.164:/STORAGES/g1r5p1/GFS'], 
'volumeName': '2KVM12-P1', 'volumeType': 'REPLICATE', 'replicaCount': 
'2', 'brickCount': '2', 'distCount': '2', 'volumeStatus': 'ONLINE', 
'stripeCount': '1', 'bricksInfo': [{'name': 
'16.0.0.163:/STORAGES/g1r5p1/GFS', 'hostUuid': 
'6482ae32-25ac-41b5-b41d-b7ddf49bac2c'}, {'name': 
'16.0.0.164:/STORAGES/g1r5p1/GFS', 'hostUuid': 
'06854ac0-2ef1-4c12-bb8d-56cf9bf95ec9'}], 'options': 
{'cluster.server-quorum-type': 'none', 'cluster.eager-lock': 'enable', 
'performance.io-cache': 'off', 'performance.stat-prefetch': 'off', 
'cluster.quorum-type': 'fixed', 'performance.quick-read': 'off', 
'network.remote-dio': 'enable', 'cluster.quorum-count': '1', 
'storage.owner-uid': '36', 'performance.read-ahead': 'off', 
'storage.owner-gid': '36'}}, '2KVM12-P3': {'transportType': ['TCP'], 
'uuid': '25a5ec22-660e-42a0-aa00-45211d341738', 'bricks': 
['16.0.0.163:/STORAGES/g1r5p3/GFS', '16.0.0.164:/STORAGES/g1r5p3/GFS'], 
'volumeName': '2KVM12-P3', 'volumeType': 'REPLICATE', 'replicaCount': 
'2', 'brickCount': '2', 'distCount': '2', 'volumeStatus': 'ONLINE', 
'stripeCount': '1', 'bricksInfo': [{'name': 
'16.0.0.163:/STORAGES/g1r5p3/GFS', 'hostUuid': 
'6482ae32-25ac-41b5-b41d-b7ddf49bac2c'}, {'name': 
'16.0.0.164:/STORAGES/g1r5p3/GFS', 'hostUuid': 
'06854ac0-2ef1-4c12-bb8d-56cf9bf95ec9'}], 'options': 
{'cluster.server-quorum-type': 'none', 'cluster.eager-lock': 'enable', 
'performance.io-cache': 'off', 'performance.stat-prefetch': 'off', 
'cluster.quorum-type': 'fixed', 'performance.quick-read': 'off', 
'network.remote-dio': 'enable', 'cluster.quorum-count': '1', 
'storage.owner-uid': '36', 'performance.read-ahead': 'off', 
'storage.owner-gid': '36'}}, '2KVM12-P2': {'transportType': ['TCP'], 
'uuid': '9745551f-4696-4a6c-820a-619e359a61fd', 'bricks': 
['16.0.0.164:/STORAGES/g1r5p2/GFS', '16.0.0.163:/STORAGES/g1r5p2/GFS'], 
'volumeName': '2KVM12-P2', 'volumeType': 'REPLICATE', 'replicaCount': 
'2', 'brickCount': '2', 'distCount': '2', 'volumeStatus': 'ONLINE', 
'stripeCount': '1', 'bricksInfo': [{'name': 
'16.0.0.164:/STORAGES/g1r5p2/GFS', 'hostUuid': 
'06854ac0-2ef1-4c12-bb8d-56cf9bf95ec9'}, {'name': 
'16.0.0.163:/STORAGES/g1r5p2/GFS', 'hostUuid': 
'6482ae32-25ac-41b5-b41d-b7ddf49bac2c'}], 'options': 
{'cluster.server-quorum-type': 'none', 'cluster.eager-lock': 'enable', 
'performance.stat-prefetch': 'off', 'cluster.quorum-type': 'fixed', 
'performance.quick-read': 'off', 'network.remote-dio': 'enable', 
'cluster.quorum-count': '1', 'performance.io-cache': 'off', 
'storage.owner-uid': '36', 'performance.read-ahead': 'off', 
'storage.owner-gid': '36'}}, '1KVM12-P4': {'transportType': ['TCP'], 
'uuid': 'b4356604-4404-428a-9da6-f1636115e2fd', 'bricks': 
['16.0.0.161:/STORAGES/g1r5p4/GFS', '16.0.0.162:/STORAGES/g1r5p4/GFS'], 
'volumeName': '1KVM12-P4', 'volumeType': 'REPLICATE', 'replicaCount': 
'2', 'brickCount': '2', 'distCount': '2', 'volumeStatus': 'ONLINE', 
'stripeCount': '1', 'bricksInfo': [{'name': 
'16.0.0.161:/STORAGES/g1r5p4/GFS', 'hostUuid': 
'194a16db-4924-4324-b1d4-97eeacb4f100'}, {'name': 
'16.0.0.162:/STORAGES/g1r5p4/GFS', 'hostUuid': 
'bfb9cc69-0c57-4905-8043-66953368e713'}], 'options': 
{'cluster.server-quorum-type': 'none', 'cluster.eager-lock': 'enable', 
'diagnostics.count-fop-hits': 'on', 'performance.stat-prefetch': 'off', 
'cluster.quorum-type': 'fixed', 'performance.quick-read': 'off', 
'network.remote-dio': 'enable', 'cluster.quorum-count': '1', 
'performance.io-cache': 'off', 'storage.owner-uid': '36', 
'performance.read-ahead': 'off', 'storage.owner-gid': '36', 
'diagnostics.latency-measurement': 'on'}}, '1KVM12-BCK': 
{'transportType': ['TCP'], 'uuid': 
'62c89345-fd61-4b67-b8b4-69296eb7d217', 'bricks': 
['16.0.0.161:/STORAGES/g2r5p1/GFS', '16.0.0.162:/STORAGES/g2r5p1/GFS'], 
'volumeName': '1KVM12-BCK', 'volumeType': 'REPLICATE', 'replicaCount': 
'2', 'brickCount': '2', 'distCount': '2', 'volumeStatus': 'ONLINE', 
'stripeCount': '1', 'bricksInfo': [{'name': 
'16.0.0.161:/STORAGES/g2r5p1/GFS', 'hostUuid': 
'194a16db-4924-4324-b1d4-97eeacb4f100'}, {'name': 
'16.0.0.162:/STORAGES/g2r5p1/GFS', 'hostUuid': 
'bfb9cc69-0c57-4905-8043-66953368e713'}], 'options': 
{'cluster.server-quorum-type': 'none', 'cluster.eager-lock': 'enable', 
'performance.io-cache': 'off', 'performance.stat-prefetch': 'off', 
'cluster.quorum-type': 'fixed', 'performance.quick-read': 'off', 
'network.remote-dio': 'enable', 'cluster.quorum-count': '1', 
'storage.owner-uid': '36', 'performance.read-ahead': 'off', 
'storage.owner-gid': '36'}}, '1KVM12-P2': {'transportType': ['TCP'], 
'uuid': 'aa2d607d-3c6c-4f13-8205-aae09dcc9d35', 'bricks': 
['16.0.0.161:/STORAGES/g1r5p2/GFS', '16.0.0.162:/STORAGES/g1r5p2/GFS'], 
'volumeName': '1KVM12-P2', 'volumeType': 'REPLICATE', 'replicaCount': 
'2', 'brickCount': '2', 'distCount': '2', 'volumeStatus': 'ONLINE', 
'stripeCount': '1', 'bricksInfo': [{'name': 
'16.0.0.161:/STORAGES/g1r5p2/GFS', 'hostUuid': 
'194a16db-4924-4324-b1d4-97eeacb4f100'}, {'name': 
'16.0.0.162:/STORAGES/g1r5p2/GFS', 'hostUuid': 
'bfb9cc69-0c57-4905-8043-66953368e713'}], 'options': 
{'cluster.server-quorum-type': 'none', 'cluster.eager-lock': 'enable', 
'performance.io-cache': 'off', 'diagnostics.count-fop-hits': 'on', 
'performance.stat-prefetch': 'off', 'cluster.quorum-type': 'fixed', 
'performance.quick-read': 'off', 'network.remote-dio': 'enable', 
'cluster.quorum-count': '1', 'storage.owner-uid': '36', 
'performance.read-ahead': 'off', 'storage.owner-gid': '36', 
'diagnostics.latency-measurement': 'on'}}, '1KVM12-P3': 
{'transportType': ['TCP'], 'uuid': 
'6060ff77-d552-4d94-97bf-5a32982e7d8a', 'bricks': 
['16.0.0.161:/STORAGES/g1r5p3/GFS', '16.0.0.162:/STORAGES/g1r5p3/GFS'], 
'volumeName': '1KVM12-P3', 'volumeType': 'REPLICATE', 'replicaCount': 
'2', 'brickCount': '2', 'distCount': '2', 'volumeStatus': 'ONLINE', 
'stripeCount': '1', 'bricksInfo': [{'name': 
'16.0.0.161:/STORAGES/g1r5p3/GFS', 'hostUuid': 
'194a16db-4924-4324-b1d4-97eeacb4f100'}, {'name': 
'16.0.0.162:/STORAGES/g1r5p3/GFS', 'hostUuid': 
'bfb9cc69-0c57-4905-8043-66953368e713'}], 'options': 
{'cluster.server-quorum-type': 'none', 'cluster.eager-lock': 'enable', 
'performance.io-cache': 'off', 'diagnostics.count-fop-hits': 'on', 
'performance.stat-prefetch': 'off', 'cluster.quorum-type': 'fixed', 
'performance.quick-read': 'off', 'network.remote-dio': 'enable', 
'cluster.quorum-count': '1', 'storage.owner-uid': '36', 
'performance.read-ahead': 'off', 'storage.owner-gid': '36', 
'diagnostics.latency-measurement': 'on'}}, '1KVM12-P1': 
{'transportType': ['TCP'], 'uuid': 
'f410c6a9-9a51-42b3-89bb-c20ac72a0461', 'bricks': 
['16.0.0.161:/STORAGES/g1r5p1/GFS', '16.0.0.162:/STORAGES/g1r5p1/GFS'], 
'volumeName': '1KVM12-P1', 'volumeType': 'REPLICATE', 'replicaCount': 
'2', 'brickCount': '2', 'distCount': '2', 'volumeStatus': 'ONLINE', 
'stripeCount': '1', 'bricksInfo': [{'name': 
'16.0.0.161:/STORAGES/g1r5p1/GFS', 'hostUuid': 
'194a16db-4924-4324-b1d4-97eeacb4f100'}, {'name': 
'16.0.0.162:/STORAGES/g1r5p1/GFS', 'hostUuid': 
'bfb9cc69-0c57-4905-8043-66953368e713'}], 'options': 
{'cluster.server-quorum-type': 'none', 'cluster.eager-lock': 'enable', 
'performance.io-cache': 'off', 'diagnostics.count-fop-hits': 'on', 
'performance.stat-prefetch': 'off', 'cluster.quorum-type': 'fixed', 
'performance.quick-read': 'off', 'network.remote-dio': 'enable', 
'cluster.quorum-count': '1', 'storage.owner-uid': '36', 
'performance.read-ahead': 'off', 'storage.owner-gid': '36', 
'diagnostics.latency-measurement': 'on'}}, '1KVM12-P5': 
{'transportType': ['TCP'], 'uuid': 
'420fa218-60bc-47e4-89a8-ce39b7da885e', 'bricks': 
['16.0.0.161:/STORAGES/g1r5p5/GFS', '16.0.0.162:/STORAGES/g1r5p5/GFS'], 
'volumeName': '1KVM12-P5', 'volumeType': 'REPLICATE', 'replicaCount': 
'2', 'brickCount': '2', 'distCount': '2', 'volumeStatus': 'ONLINE', 
'stripeCount': '1', 'bricksInfo': [{'name': 
'16.0.0.161:/STORAGES/g1r5p5/GFS', 'hostUuid': 
'194a16db-4924-4324-b1d4-97eeacb4f100'}, {'name': 
'16.0.0.162:/STORAGES/g1r5p5/GFS', 'hostUuid': 
'bfb9cc69-0c57-4905-8043-66953368e713'}], 'options': 
{'cluster.server-quorum-type': 'none', 'cluster.eager-lock': 'enable', 
'performance.io-cache': 'off', 'performance.stat-prefetch': 'off', 
'cluster.quorum-type': 'fixed', 'performance.quick-read': 'off', 
'network.remote-dio': 'enable', 'cluster.quorum-count': '1', 
'storage.owner-uid': '36', 'performance.read-ahead': 'off', 
'storage.owner-gid': '36'}}}}
Thread-431::DEBUG::2016-02-24 
11:12:47,729::fileSD::262::Storage.Misc.excCmd::(getReadDelay) 
/usr/bin/dd 
if=/rhev/data-center/mnt/glusterSD/localhost:_2KVM12-P2/ff71b47b-0f72-4528-9bfe-c3da888e47f0/dom_md/metadata 
iflag=direct of=/dev/null bs=4096 count=1 (cwd None)
Thread-431::DEBUG::2016-02-24 
11:12:47,743::fileSD::262::Storage.Misc.excCmd::(getReadDelay) SUCCESS: 
<err> = '0+1 records in\n0+1 records out\n997 bytes (997 B) copied, 
0.000569374 s, 1.8 MB/s\n'; <rc> = 0
Thread-431::INFO::2016-02-24 
11:12:47,751::clusterlock::219::Storage.SANLock::(acquireHostId) 
Acquiring host id for domain ff71b47b-0f72-4528-9bfe-c3da888e47f0 (id: 3)
Thread-431::DEBUG::2016-02-24 
11:12:47,751::clusterlock::237::Storage.SANLock::(acquireHostId) Host id 
for domain ff71b47b-0f72-4528-9bfe-c3da888e47f0 successfully acquired 
(id: 3)
--------------------------------

Thread-349::ERROR::2016-02-24 
11:18:20,040::domainMonitor::256::Storage.DomainMonitorThread::(_monitorDomain) 
Error while collecting domain ef010d08-aed1-41c4-ba9a-e6d9bdecb4b4 
monitoring information
Traceback (most recent call last):
  File "/usr/share/vdsm/storage/domainMonitor.py", line 221, in 
_monitorDomain
    self.domain = sdCache.produce(self.sdUUID)
  File "/usr/share/vdsm/storage/sdc.py", line 98, in produce
    domain.getRealDomain()
  File "/usr/share/vdsm/storage/sdc.py", line 52, in getRealDomain
    return self._cache._realProduce(self._sdUUID)
  File "/usr/share/vdsm/storage/sdc.py", line 122, in _realProduce
    domain = self._findDomain(sdUUID)
  File "/usr/share/vdsm/storage/sdc.py", line 141, in _findDomain
    dom = findMethod(sdUUID)
  File "/usr/share/vdsm/storage/glusterSD.py", line 32, in findDomain
    return 
GlusterStorageDomain(GlusterStorageDomain.findDomainPath(sdUUID))
  File "/usr/share/vdsm/storage/fileSD.py", line 160, in __init__
    validateFileSystemFeatures(sdUUID, self.mountpoint)
  File "/usr/share/vdsm/storage/fileSD.py", line 89, in 
validateFileSystemFeatures
    oop.getProcessPool(sdUUID).directTouch(testFilePath)
  File "/usr/share/vdsm/storage/outOfProcess.py", line 351, in directTouch
    ioproc.touch(path, flags, mode)
  File "/usr/lib/python2.7/site-packages/ioprocess/__init__.py", line 
543, in touch
    self.timeout)
  File "/usr/lib/python2.7/site-packages/ioprocess/__init__.py", line 
427, in _sendCommand
    raise OSError(errcode, errstr)
OSError: [Errno 5] Input/output error
Thread-453::ERROR::2016-02-24 
11:18:20,043::domainMonitor::256::Storage.DomainMonitorThread::(_monitorDomain) 
Error while collecting domain 300e9ac8-3c2f-4703-9bb1-1df2130c7c97 
monitoring information
Traceback (most recent call last):
  File "/usr/share/vdsm/storage/domainMonitor.py", line 221, in 
_monitorDomain
    self.domain = sdCache.produce(self.sdUUID)
  File "/usr/share/vdsm/storage/sdc.py", line 98, in produce
    domain.getRealDomain()
  File "/usr/share/vdsm/storage/sdc.py", line 52, in getRealDomain
    return self._cache._realProduce(self._sdUUID)
  File "/usr/share/vdsm/storage/sdc.py", line 122, in _realProduce
    domain = self._findDomain(sdUUID)
  File "/usr/share/vdsm/storage/sdc.py", line 141, in _findDomain
    dom = findMethod(sdUUID)
  File "/usr/share/vdsm/storage/glusterSD.py", line 32, in findDomain
    return 
GlusterStorageDomain(GlusterStorageDomain.findDomainPath(sdUUID))
  File "/usr/share/vdsm/storage/fileSD.py", line 160, in __init__
    validateFileSystemFeatures(sdUUID, self.mountpoint)
  File "/usr/share/vdsm/storage/fileSD.py", line 89, in 
validateFileSystemFeatures
    oop.getProcessPool(sdUUID).directTouch(testFilePath)
  File "/usr/share/vdsm/storage/outOfProcess.py", line 351, in directTouch
    ioproc.touch(path, flags, mode)
  File "/usr/lib/python2.7/site-packages/ioprocess/__init__.py", line 
543, in touch
    self.timeout)
  File "/usr/lib/python2.7/site-packages/ioprocess/__init__.py", line 
427, in _sendCommand
    raise OSError(errcode, errstr)
OSError: [Errno 5] Input/output error
Thread-431::DEBUG::2016-02-24 
11:18:20,109::fileSD::262::Storage.Misc.excCmd::(getReadDelay) 
/usr/bin/dd 
if=/rhev/data-center/mnt/glusterSD/localhost:_2KVM12-P2/ff71b47b-0f72-4528-9bfe-c3da888e47f0/dom_md/metadata 
iflag=direct of=/dev/null bs=4096 count=1 (cwd None)
Thread-431::DEBUG::2016-02-24 
11:18:20,122::fileSD::262::Storage.Misc.excCmd::(getReadDelay) SUCCESS: 
<err> = '0+1 records in\n0+1 records out\n997 bytes (997 B) copied, 
0.000444081 s, 2.2 MB/s\n'; <rc> = 0
Thread-431::INFO::2016-02-24 
11:18:20,128::clusterlock::219::Storage.SANLock::(acquireHostId) 
Acquiring host id for domain ff71b47b-0f72-4528-9bfe-c3da888e47f0 (id: 3)
Thread-431::DEBUG::2016-02-24 
11:18:20,129::clusterlock::237::Storage.SANLock::(acquireHostId) Host id 
for domain ff71b47b-0f72-4528-9bfe-c3da888e47f0 successfully acquired 
(id: 3)
Thread-35631::DEBUG::2016-02-24 
11:18:20,690::__init__::481::jsonrpc.JsonRpcServer::(_serveRequest) 
Calling 'Task.getStatus' in bridge with {u'taskID': 
u'ea8c684e-fedb-44f2-84c5-4f1407f24660'}
Thread-35631::DEBUG::2016-02-24 
11:18:20,692::task::595::Storage.TaskManager.Task::(_updateState) 
Task=`8e893d78-e9dd-4af3-9ddc-5eb5c1006cfc`::moving from state init -> 
state preparing
Thread-35631::INFO::2016-02-24 
11:18:20,692::logUtils::44::dispatcher::(wrapper) Run and protect: 
getTaskStatus(taskID=u'ea8c684e-fedb-44f2-84c5-4f1407f24660', 
spUUID=None, options=None)
Thread-35631::DEBUG::2016-02-24 
11:18:20,693::taskManager::103::Storage.TaskManager::(getTaskStatus) 
Entry. taskID: ea8c684e-fedb-44f2-84c5-4f1407f24660
Thread-35631::DEBUG::2016-02-24 
11:18:20,693::taskManager::106::Storage.TaskManager::(getTaskStatus) 
Return. Response: {'code': 661, 'message': 'Cannot acquire host id', 
'taskState': 'finished', 'taskResult': 'cleanSuccess', 'taskID': 
'ea8c684e-fedb-44f2-84c5-4f1407f24660'}
Thread-35631::INFO::2016-02-24 
11:18:20,693::logUtils::47::dispatcher::(wrapper) Run and protect: 
getTaskStatus, Return response: {'taskStatus': {'code': 661, 'message': 
'Cannot acquire host id', 'taskState': 'finished', 'taskResult': 
'cleanSuccess', 'taskID': 'ea8c684e-fedb-44f2-84c5-4f1407f24660'}}
Thread-35631::DEBUG::2016-02-24 
11:18:20,693::task::1191::Storage.TaskManager.Task::(prepare) 
Task=`8e893d78-e9dd-4af3-9ddc-5eb5c1006cfc`::finished: {'taskStatus': 
{'code': 661, 'message': 'Cannot acquire host id', 'taskState': 
'finished', 'taskResult': 'cleanSuccess', 'taskID': 
'ea8c684e-fedb-44f2-84c5-4f1407f24660'}}
Thread-35631::DEBUG::2016-02-24 
11:18:20,693::task::595::Storage.TaskManager.Task::(_updateState) 
Task=`8e893d78-e9dd-4af3-9ddc-5eb5c1006cfc`::moving from state preparing 
-> state finished
Thread-35631::DEBUG::2016-02-24 
11:18:20,694::resourceManager::940::Storage.ResourceManager.Owner::(releaseAll) 
Owner.releaseAll requests {} resources {}
Thread-35631::DEBUG::2016-02-24 
11:18:20,694::resourceManager::977::Storage.ResourceManager.Owner::(cancelAll) 
Owner.cancelAll requests {}
Thread-35631::DEBUG::2016-02-24 
11:18:20,694::task::993::Storage.TaskManager.Task::(_decref) 
Task=`8e893d78-e9dd-4af3-9ddc-5eb5c1006cfc`::ref 0 aborting False
Thread-35631::DEBUG::2016-02-24 
11:18:20,694::__init__::514::jsonrpc.JsonRpcServer::(_serveRequest) 
Return 'Task.getStatus' in bridge with {'code': 661, 'message': 'Cannot 
acquire host id', 'taskState': 'finished', 'taskResult': 'cleanSuccess', 
'taskID': 'ea8c684e-fedb-44f2-84c5-4f1407f24660'}
Thread-35632::DEBUG::2016-02-24 
11:18:20,699::__init__::481::jsonrpc.JsonRpcServer::(_serveRequest) 
Calling 'StoragePool.getSpmStatus' in bridge with {u'storagepoolID': 
u'00000002-0002-0002-0002-00000000021e'}
Thread-35632::DEBUG::2016-02-24 
11:18:20,700::task::595::Storage.TaskManager.Task::(_updateState) 
Task=`3a303728-a737-4ec4-8468-c5f142964689`::moving from state init -> 
state preparing
Thread-35632::INFO::2016-02-24 
11:18:20,700::logUtils::44::dispatcher::(wrapper) Run and protect: 
getSpmStatus(spUUID=u'00000002-0002-0002-0002-00000000021e', options=None)
Thread-35632::INFO::2016-02-24 
11:18:20,707::logUtils::47::dispatcher::(wrapper) Run and protect: 
getSpmStatus, Return response: {'spm_st': {'spmId': -1, 'spmStatus': 
'Free', 'spmLver': -1}}
Thread-35632::DEBUG::2016-02-24 
11:18:20,707::task::1191::Storage.TaskManager.Task::(prepare) 
Task=`3a303728-a737-4ec4-8468-c5f142964689`::finished: {'spm_st': 
{'spmId': -1, 'spmStatus': 'Free', 'spmLver': -1}}
Thread-35632::DEBUG::2016-02-24 
11:18:20,707::task::595::Storage.TaskManager.Task::(_updateState) 
Task=`3a303728-a737-4ec4-8468-c5f142964689`::moving from state preparing 
-> state finished
Thread-35632::DEBUG::2016-02-24 
11:18:20,708::resourceManager::940::Storage.ResourceManager.Owner::(releaseAll) 
Owner.releaseAll requests {} resources {}
Thread-35632::DEBUG::2016-02-24 
11:18:20,708::resourceManager::977::Storage.ResourceManager.Owner::(cancelAll) 
Owner.cancelAll requests {}
Thread-35632::DEBUG::2016-02-24 
11:18:20,708::task::993::Storage.TaskManager.Task::(_decref) 
Task=`3a303728-a737-4ec4-8468-c5f142964689`::ref 0 aborting False
Thread-35632::DEBUG::2016-02-24 
11:18:20,708::__init__::514::jsonrpc.JsonRpcServer::(_serveRequest) 
Return 'StoragePool.getSpmStatus' in bridge with {'spmId': -1, 
'spmStatus': 'Free', 'spmLver': -1}
Thread-35633::DEBUG::2016-02-24 
11:18:20,737::__init__::481::jsonrpc.JsonRpcServer::(_serveRequest) 
Calling 'Task.clear' in bridge with {u'taskID': 
u'ea8c684e-fedb-44f2-84c5-4f1407f24660'}
Thread-35633::DEBUG::2016-02-24 
11:18:20,738::task::595::Storage.TaskManager.Task::(_updateState) 
Task=`6164a1ff-67b3-4e35-bce0-f0dc9657bf94`::moving from state init -> 
state preparing
Thread-35633::INFO::2016-02-24 
11:18:20,738::logUtils::44::dispatcher::(wrapper) Run and protect: 
clearTask(taskID=u'ea8c684e-fedb-44f2-84c5-4f1407f24660', spUUID=None, 
options=None)
Thread-35633::DEBUG::2016-02-24 
11:18:20,739::taskManager::171::Storage.TaskManager::(clearTask) Entry. 
taskID: ea8c684e-fedb-44f2-84c5-4f1407f24660
Thread-35633::DEBUG::2016-02-24 
11:18:20,739::taskManager::176::Storage.TaskManager::(clearTask) Return.
Thread-35633::INFO::2016-02-24 
11:18:20,739::logUtils::47::dispatcher::(wrapper) Run and protect: 
clearTask, Return response: None
Thread-35633::DEBUG::2016-02-24 
11:18:20,739::task::1191::Storage.TaskManager.Task::(prepare) 
Task=`6164a1ff-67b3-4e35-bce0-f0dc9657bf94`::finished: None
Thread-35633::DEBUG::2016-02-24 
11:18:20,740::task::595::Storage.TaskManager.Task::(_updateState) 
Task=`6164a1ff-67b3-4e35-bce0-f0dc9657bf94`::moving from state preparing 
-> state finished
Thread-35633::DEBUG::2016-02-24 
11:18:20,740::resourceManager::940::Storage.ResourceManager.Owner::(releaseAll) 
Owner.releaseAll requests {} resources {}
Thread-35633::DEBUG::2016-02-24 
11:18:20,740::resourceManager::977::Storage.ResourceManager.Owner::(cancelAll) 
Owner.cancelAll requests {}
Thread-35633::DEBUG::2016-02-24 
11:18:20,740::task::993::Storage.TaskManager.Task::(_decref) 
Task=`6164a1ff-67b3-4e35-bce0-f0dc9657bf94`::ref 0 aborting False
Thread-35633::DEBUG::2016-02-24 
11:18:20,740::__init__::514::jsonrpc.JsonRpcServer::(_serveRequest) 
Return 'Task.clear' in bridge with True
Thread-35634::DEBUG::2016-02-24 
11:18:20,859::__init__::481::jsonrpc.JsonRpcServer::(_serveRequest) 
Calling 'StoragePool.getSpmStatus' in bridge with {u'storagepoolID': 
u'00000002-0002-0002-0002-00000000021e'}
Thread-35634::DEBUG::2016-02-24 
11:18:20,860::task::595::Storage.TaskManager.Task::(_updateState) 
Task=`e887fd8b-6961-40f1-b3a0-917ffbea25c0`::moving from state init -> 
state preparing
Thread-35634::INFO::2016-02-24 
11:18:20,860::logUtils::44::dispatcher::(wrapper) Run and protect: 
getSpmStatus(spUUID=u'00000002-0002-0002-0002-00000000021e', options=None)
Thread-35634::INFO::2016-02-24 
11:18:20,867::logUtils::47::dispatcher::(wrapper) Run and protect: 
getSpmStatus, Return response: {'spm_st': {'spmId': -1, 'spmStatus': 
'Free', 'spmLver': -1}}
Thread-35634::DEBUG::2016-02-24 
11:18:20,867::task::1191::Storage.TaskManager.Task::(prepare) 
Task=`e887fd8b-6961-40f1-b3a0-917ffbea25c0`::finished: {'spm_st': 
{'spmId': -1, 'spmStatus': 'Free', 'spmLver': -1}}
Thread-35634::DEBUG::2016-02-24 
11:18:20,867::task::595::Storage.TaskManager.Task::(_updateState) 
Task=`e887fd8b-6961-40f1-b3a0-917ffbea25c0`::moving from state preparing 
-> state finished
Thread-35634::DEBUG::2016-02-24 
11:18:20,867::resourceManager::940::Storage.ResourceManager.Owner::(releaseAll) 
Owner.releaseAll requests {} resources {}
Thread-35634::DEBUG::2016-02-24 
11:18:20,867::resourceManager::977::Storage.ResourceManager.Owner::(cancelAll) 
Owner.cancelAll requests {}
Thread-35634::DEBUG::2016-02-24 
11:18:20,867::task::993::Storage.TaskManager.Task::(_decref) 
Task=`e887fd8b-6961-40f1-b3a0-917ffbea25c0`::ref 0 aborting False
---------------------------------------

these blocks generated in cycle for each domain

Any IDEA ??
regs.
Pa.



On 24.2.2016 10:54, Ravishankar N wrote:
Hi,

On 02/24/2016 06:43 AM, p...@email.cz wrote:
Hi,
I found the main ( maybe ) problem with IO error ( -5 ) for "ids" 
file access
This file is not accessable via NFS, locally yes
How is NFS coming into the picture? Are you not using gluster fuse mount?
.
How can I fix it ??
Can you run `gluster volume heal volname info` and `gluster volume 
heal volname info split-brain` to see if the "ids" file is in 
split-brain? A file in split-brain returns EIO when accessed from the 
mount.
Regards,
Ravi


regs.
Pavel

# sanlock client log_dump
....
0 flags 1 timeout 0
2016-02-24 02:01:10+0100 3828 [12111]: s1316 lockspace 
88adbd49-62d6-45b1-9992-b04464a04112:1:/rhev/data-center/mnt/glusterSD/localhost:_1KVM12-P2/88adbd49-62d6-45b1-9992-b04464a04112/dom_md/ids:0
2016-02-24 02:01:10+0100 3828 [12111]: cmd_add_lockspace 4,15 async 
done 0
2016-02-24 02:01:10+0100 3828 [19556]: s1316 delta_acquire begin 
88adbd49-62d6-45b1-9992-b04464a04112:1
2016-02-24 02:01:10+0100 3828 [19556]: 88adbd49 aio collect 0 
0x7fe4580008c0:0x7fe4580008d0:0x7fe458101000 result -5:0 match res
2016-02-24 02:01:10+0100 3828 [19556]: read_sectors delta_leader 
offset 0 rv -5 
/rhev/data-center/mnt/glusterSD/localhost:_1KVM12-P2/88adbd49-62d6-45b1-9992-b04464a04112/dom_md/ids
2016-02-24 02:01:10+0100 3828 [19556]: s1316 delta_acquire 
leader_read1 error -5
2016-02-24 02:01:11+0100 3829 [12111]: s1316 add_lockspace fail result -5
2016-02-24 02:01:12+0100 3831 [12116]: cmd_add_lockspace 4,15 
7f52b697-c199-4f58-89aa-102d44327124:1:/rhev/data-center/mnt/glusterSD/localhost:_1KVM12-P4/7f52b697-c199-4f58-89aa-102d44327124/dom_md/ids:0 
flags 1 timeout 0
2016-02-24 02:01:12+0100 3831 [12116]: s1317 lockspace 
7f52b697-c199-4f58-89aa-102d44327124:1:/rhev/data-center/mnt/glusterSD/localhost:_1KVM12-P4/7f52b697-c199-4f58-89aa-102d44327124/dom_md/ids:0
2016-02-24 02:01:12+0100 3831 [12116]: cmd_add_lockspace 4,15 async 
done 0
2016-02-24 02:01:12+0100 3831 [19562]: s1317 delta_acquire begin 
7f52b697-c199-4f58-89aa-102d44327124:1
2016-02-24 02:01:12+0100 3831 [19562]: 7f52b697 aio collect 0 
0x7fe4580008c0:0x7fe4580008d0:0x7fe458101000 result -5:0 match res
2016-02-24 02:01:12+0100 3831 [19562]: read_sectors delta_leader 
offset 0 rv -5 
/rhev/data-center/mnt/glusterSD/localhost:_1KVM12-P4/7f52b697-c199-4f58-89aa-102d44327124/dom_md/ids
2016-02-24 02:01:12+0100 3831 [19562]: s1317 delta_acquire 
leader_read1 error -5
2016-02-24 02:01:13+0100 3831 [1321]: cmd_add_lockspace 4,15 
0fcad888-d573-47be-bef3-0bc0b7a99fb7:1:/rhev/data-center/mnt/glusterSD/localhost:_1KVM12-BCK/0fcad888-d573-47be-bef3-0bc0b7a99fb7/dom_md/ids:0 
flags 1 timeout 0
2016-02-24 02:01:13+0100 3831 [1321]: s1318 lockspace 
0fcad888-d573-47be-bef3-0bc0b7a99fb7:1:/rhev/data-center/mnt/glusterSD/localhost:_1KVM12-BCK/0fcad888-d573-47be-bef3-0bc0b7a99fb7/dom_md/ids:0
2016-02-24 02:01:13+0100 3831 [1321]: cmd_add_lockspace 4,15 async done 0
2016-02-24 02:01:13+0100 3831 [19564]: s1318 delta_acquire begin 
0fcad888-d573-47be-bef3-0bc0b7a99fb7:1
2016-02-24 02:01:13+0100 3831 [19564]: 0fcad888 aio collect 0 
0x7fe4580008c0:0x7fe4580008d0:0x7fe458201000 result -5:0 match res
2016-02-24 02:01:13+0100 3831 [19564]: read_sectors delta_leader 
offset 0 rv -5 
/rhev/data-center/mnt/glusterSD/localhost:_1KVM12-BCK/0fcad888-d573-47be-bef3-0bc0b7a99fb7/dom_md/ids
2016-02-24 02:01:13+0100 3831 [19564]: s1318 delta_acquire 
leader_read1 error -5
2016-02-24 02:01:13+0100 3832 [12116]: s1317 add_lockspace fail result -5
2016-02-24 02:01:14+0100 3832 [1321]: s1318 add_lockspace fail result -5
2016-02-24 02:01:19+0100 3838 [12106]: cmd_add_lockspace 4,15 
3da46e07-d1ea-4f10-9250-6cbbb7b94d80:1:/rhev/data-center/mnt/glusterSD/localhost:_1KVM12-P5/3da46e07-d1ea-4f10-9250-6cbbb7b94d80/dom_md/ids:0 
flags 1 timeout 0
2016-02-24 02:01:19+0100 3838 [12106]: s1319 lockspace 
3da46e07-d1ea-4f10-9250-6cbbb7b94d80:1:/rhev/data-center/mnt/glusterSD/localhost:_1KVM12-P5/3da46e07-d1ea-4f10-9250-6cbbb7b94d80/dom_md/ids:0
2016-02-24 02:01:19+0100 3838 [12106]: cmd_add_lockspace 4,15 async 
done 0
2016-02-24 02:01:19+0100 3838 [19638]: s1319 delta_acquire begin 
3da46e07-d1ea-4f10-9250-6cbbb7b94d80:1
2016-02-24 02:01:19+0100 3838 [19638]: 3da46e07 aio collect 0 
0x7fe4580008c0:0x7fe4580008d0:0x7fe458101000 result -5:0 match res
2016-02-24 02:01:19+0100 3838 [19638]: read_sectors delta_leader 
offset 0 rv -5 
/rhev/data-center/mnt/glusterSD/localhost:_1KVM12-P5/3da46e07-d1ea-4f10-9250-6cbbb7b94d80/dom_md/ids
2016-02-24 02:01:19+0100 3838 [19638]: s1319 delta_acquire 
leader_read1 error -5
2016-02-24 02:01:20+0100 3839 [12106]: s1319 add_lockspace fail result -5
2016-02-24 02:01:20+0100 3839 [1320]: cmd_add_lockspace 4,15 
88adbd49-62d6-45b1-9992-b04464a04112:1:/rhev/data-center/mnt/glusterSD/localhost:_1KVM12-P2/88adbd49-62d6-45b1-9992-b04464a04112/dom_md/ids:0 
flags 1 timeout 0
2016-02-24 02:01:20+0100 3839 [1320]: s1320 lockspace 
88adbd49-62d6-45b1-9992-b04464a04112:1:/rhev/data-center/mnt/glusterSD/localhost:_1KVM12-P2/88adbd49-62d6-45b1-9992-b04464a04112/dom_md/ids:0
2016-02-24 02:01:20+0100 3839 [1320]: cmd_add_lockspace 4,15 async done 0
2016-02-24 02:01:20+0100 3839 [19658]: s1320 delta_acquire begin 
88adbd49-62d6-45b1-9992-b04464a04112:1
2016-02-24 02:01:20+0100 3839 [19658]: 88adbd49 aio collect 0 
0x7fe4580008c0:0x7fe4580008d0:0x7fe458101000 result -5:0 match res
2016-02-24 02:01:20+0100 3839 [19658]: read_sectors delta_leader 
offset 0 rv -5 
/rhev/data-center/mnt/glusterSD/localhost:_1KVM12-P2/88adbd49-62d6-45b1-9992-b04464a04112/dom_md/ids
2016-02-24 02:01:20+0100 3839 [19658]: s1320 delta_acquire 
leader_read1 error -5
2016-02-24 02:01:21+0100 3840 [1320]: s1320 add_lockspace fail result -5



_______________________________________________
Users mailing list
Users@ovirt.org
http://lists.ovirt.org/mailman/listinfo/users



_______________________________________________
Users mailing list
Users@ovirt.org
http://lists.ovirt.org/mailman/listinfo/users