11:05 p.m.
--_000_EE5DB373DE433D4A87A60EE0284C92F926421EFDRISXMBX03adsuhn_
Content-Type: text/plain; charset="us-ascii"
Content-Transfer-Encoding: quoted-printable
Hello all,
I'm exploring using oVirt 3.5 as the management engine for a new cluster I =
am building. I don't really need high availability and I have 3 servers wit=
h fast SSDs on local storage I'd like to use, preferably using ZFS to ensur=
e reliability of the storage.
I set up a brand new CentOS 6.6 minimal install and have been able to get o=
Virt up using the all-in-one plugin. I can use local storage (the default E=
XT4 LVM filesystem CentOS sets up) to setup a local data center for each of=
the servers but have run into issues when I try to provision a ZFS filesys=
tem as a local storage domain. I'm using ZFS on Linux 0.6.3.
I first had multipath.conf issues, which prevented me from even setting up =
a zpool using the local disks. After blacklisting the local disks in /etc/m=
ultipath.conf, I was able to get zfs up and running and create the local do=
mains, but I get errors when I try to create a storage Domain of type: Data=
/ Local on Host and point at the ZFS path (/vmstore/isos) (PS: I'm not try=
ing to create an ISO domain, it's just a directory name isos)
The error message that comes up is "Error while executing action New Local =
Storage Domain: Storage Domain target is unsupported".
Has anyone tried this before? I am able to set up an NFS export of the ZFS =
folder as a Storage Domain (using directions here: http://virt.guru/2014/02=
/25/installing-ovirt-with-shared-local-storage/) but if possible, I'd like =
to skip the NFS layer if I can and just go directly to the hardware.
The supervdsm.log shows:
MainProcess|Thread-1734::DEBUG::2014-11-17 15:50:31,350::supervdsmServer::1=
01::SuperVdsm.ServerCallback::(wrapper) call validateAccess with ('qemu', (=
'qemu', 'kvm'), u'/vmstore/isos', 5) {}
MainProcess|Thread-1734::DEBUG::2014-11-17 15:50:31,356::supervdsmServer::1=
08::SuperVdsm.ServerCallback::(wrapper) return validateAccess with None
MainProcess|Thread-1735::DEBUG::2014-11-17 15:50:31,436::supervdsmServer::1=
01::SuperVdsm.ServerCallback::(wrapper) call validateAccess with ('qemu', (=
'qemu', 'kvm'), u'/vmstore/isos', 5) {}
MainProcess|Thread-1735::DEBUG::2014-11-17 15:50:31,441::supervdsmServer::1=
08::SuperVdsm.ServerCallback::(wrapper) return validateAccess with None
MainProcess|Thread-1736::DEBUG::2014-11-17 15:50:31,519::supervdsmServer::1=
01::SuperVdsm.ServerCallback::(wrapper) call hbaRescan with () {}
MainProcess|Thread-1736::INFO::2014-11-17 15:50:31,520::hba::54::Storage.HB=
A::(rescan) Rescanning HBAs
MainProcess|Thread-1736::DEBUG::2014-11-17 15:50:31,520::supervdsmServer::1=
08::SuperVdsm.ServerCallback::(wrapper) return hbaRescan with None
MainProcess|Thread-1736::DEBUG::2014-11-17 15:50:32,100::supervdsmServer::1=
01::SuperVdsm.ServerCallback::(wrapper) call validateAccess with ('qemu', (=
'qemu', 'kvm'), u'/rhev/data-center/mnt/_vmstore_isos', 5) {}
MainProcess|Thread-1736::DEBUG::2014-11-17 15:50:32,105::supervdsmServer::1=
08::SuperVdsm.ServerCallback::(wrapper) return validateAccess with None
MainProcess|Thread-1744::DEBUG::2014-11-17 15:50:32,278::supervdsmServer::1=
01::SuperVdsm.ServerCallback::(wrapper) call hbaRescan with () {}
MainProcess|Thread-1744::INFO::2014-11-17 15:50:32,279::hba::54::Storage.HB=
A::(rescan) Rescanning HBAs
MainProcess|Thread-1744::DEBUG::2014-11-17 15:50:32,279::supervdsmServer::1=
08::SuperVdsm.ServerCallback::(wrapper) return hbaRescan with None
And the vdsm.log shows:
Thread-1732::DEBUG::2014-11-17 15:50:31,032::task::595::Storage.TaskManager=
.Task::(_updateState) Task=3D`82d29b1b-0c33-4078-887b-476d95f4b1a1`::moving=
from state init -> state preparing
Thread-1732::INFO::2014-11-17 15:50:31,032::logUtils::44::dispatcher::(wrap=
per) Run and protect: repoStats(options=3DNone)
Thread-1732::INFO::2014-11-17 15:50:31,033::logUtils::47::dispatcher::(wrap=
per) Run and protect: repoStats, Return response: {u'158ef830-da69-48b4-95b=
0-3615d6fb5b00': {'code': 0, 'version': 3, 'acquired': True,
'delay': '0.00=
0627117', 'lastCheck': '8.2', 'valid': True}}
Thread-1732::DEBUG::2014-11-17 15:50:31,033::task::1191::Storage.TaskManage=
r.Task::(prepare) Task=3D`82d29b1b-0c33-4078-887b-476d95f4b1a1`::finished: =
{u'158ef830-da69-48b4-95b0-3615d6fb5b00': {'code': 0, 'version':
3, 'acquir=
ed': True, 'delay': '0.000627117', 'lastCheck': '8.2',
'valid': True}}
Thread-1732::DEBUG::2014-11-17 15:50:31,033::task::595::Storage.TaskManager=
.Task::(_updateState) Task=3D`82d29b1b-0c33-4078-887b-476d95f4b1a1`::moving=
from state preparing -> state finished
Thread-1732::DEBUG::2014-11-17 15:50:31,034::resourceManager::940::Storage.=
ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources =
{}
Thread-1732::DEBUG::2014-11-17 15:50:31,034::resourceManager::977::Storage.=
ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {}
Thread-1732::DEBUG::2014-11-17 15:50:31,034::task::993::Storage.TaskManager=
.Task::(_decref) Task=3D`82d29b1b-0c33-4078-887b-476d95f4b1a1`::ref 0 abort=
ing False
Thread-1732::DEBUG::2014-11-17 15:50:31,042::stompReactor::163::yajsonrpc.S=
tompServer::(send) Sending response
JsonRpc (StompReactor)::DEBUG::2014-11-17 15:50:31,060::stompReactor::98::B=
roker.StompAdapter::(handle_frame) Handling message <StompFrame command=3D'=
SEND' JsonRpcServer::DEBUG::2014-11-17
15:50:31,062::__init__::504::jsonrpc.JsonR=
pcServer::(serve_requests) Waiting for request
Thread-1733::DEBUG::2014-11-17 15:50:31,066::stompReactor::163::yajsonrpc.S=
tompServer::(send) Sending response
JsonRpc (StompReactor)::DEBUG::2014-11-17 15:50:31,338::stompReactor::98::B=
roker.StompAdapter::(handle_frame) Handling message <StompFrame command=3D'=
SEND' JsonRpcServer::DEBUG::2014-11-17
15:50:31,341::__init__::504::jsonrpc.JsonR=
pcServer::(serve_requests) Waiting for request
Thread-1734::DEBUG::2014-11-17 15:50:31,341::__init__::467::jsonrpc.JsonRpc=
Server::(_serveRequest) Calling 'StoragePool.connectStorageServer' in bridg=
e with {u'connectionParams': [{u'id':
u'00000000-0000-0000-0000-00000000000=
0', u'connection': u'/vmstore/isos', u'iqn': u'',
u'user': u'', u'tpgt': u'=
1', u'password': u'', u'port': u''}],
u'storagepoolID': u'00000000-0000-000=
0-0000-000000000000', u'domainType': 4}
Thread-1734::DEBUG::2014-11-17 15:50:31,344::task::595::Storage.TaskManager=
.Task::(_updateState) Task=3D`4088bef4-489c-41a4-bcd4-d5f1906378b9`::moving=
from state init -> state preparing
Thread-1734::INFO::2014-11-17 15:50:31,345::logUtils::44::dispatcher::(wrap=
per) Run and protect: connectStorageServer(domType=3D4, spUUID=3Du'00000000=
-0000-0000-0000-000000000000', conList=3D[{u'connection':
u'/vmstore/isos',=
u'iqn': u'', u'user': u'', u'tpgt': u'1',
u'password': '******', u'id': u'=
00000000-0000-0000-0000-000000000000', u'port': u''}],
options=3DNone)
ioprocess communication (5073)::DEBUG::2014-11-17 15:50:31,346::__init__::3=
75::IOProcess::(_processLogs) Receiving request...
ioprocess communication (5073)::DEBUG::2014-11-17 15:50:31,346::__init__::3=
75::IOProcess::(_processLogs) Queuing request in the thread pool...
ioprocess communication (5073)::DEBUG::2014-11-17 15:50:31,347::__init__::3=
75::IOProcess::(_processLogs) Extracting request information...
ioprocess communication (5073)::DEBUG::2014-11-17 15:50:31,347::__init__::3=
75::IOProcess::(_processLogs) (2321) Got request for method 'access'
ioprocess communication (5073)::DEBUG::2014-11-17 15:50:31,347::__init__::3=
75::IOProcess::(_processLogs) (2321) Queuing response
Thread-1734::DEBUG::2014-11-17 15:50:31,357::hsm::2389::Storage.HSM::(__pre=
fetchDomains) local _path: /vmstore/isos
Thread-1734::DEBUG::2014-11-17 15:50:31,358::hsm::2396::Storage.HSM::(__pre=
fetchDomains) Found SD uuids: ()
Thread-1734::DEBUG::2014-11-17 15:50:31,358::hsm::2452::Storage.HSM::(conne=
ctStorageServer) knownSDs: {158ef830-da69-48b4-95b0-3615d6fb5b00: storage.l=
ocalFsSD.findDomain, ac821c1f-b7ca-4534-a10f-9b98c325a070: storage.nfsSD.fi=
ndDomain}
Thread-1734::INFO::2014-11-17 15:50:31,358::logUtils::47::dispatcher::(wrap=
per) Run and protect: connectStorageServer, Return response: {'statuslist':=
[{'status': 0, 'id': u'00000000-0000-0000-0000-000000000000'}]}
Thread-1734::DEBUG::2014-11-17 15:50:31,359::task::1191::Storage.TaskManage=
r.Task::(prepare) Task=3D`4088bef4-489c-41a4-bcd4-d5f1906378b9`::finished: =
{'statuslist': [{'status': 0, 'id':
u'00000000-0000-0000-0000-000000000000'=
}]}
Thread-1734::DEBUG::2014-11-17 15:50:31,359::task::595::Storage.TaskManager=
.Task::(_updateState) Task=3D`4088bef4-489c-41a4-bcd4-d5f1906378b9`::moving=
from state preparing -> state finished
Thread-1734::DEBUG::2014-11-17 15:50:31,359::resourceManager::940::Storage.=
ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources =
{}
Thread-1734::DEBUG::2014-11-17 15:50:31,359::resourceManager::977::Storage.=
ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {}
Thread-1734::DEBUG::2014-11-17 15:50:31,360::task::993::Storage.TaskManager=
.Task::(_decref) Task=3D`4088bef4-489c-41a4-bcd4-d5f1906378b9`::ref 0 abort=
ing False
Thread-1734::DEBUG::2014-11-17 15:50:31,360::__init__::498::jsonrpc.JsonRpc=
Server::(_serveRequest) Return 'StoragePool.connectStorageServer' in bridge=
with [{'status': 0, 'id':
u'00000000-0000-0000-0000-000000000000'}]
Thread-1734::DEBUG::2014-11-17 15:50:31,361::stompReactor::163::yajsonrpc.S=
tompServer::(send) Sending response
JsonRpc (StompReactor)::DEBUG::2014-11-17 15:50:31,425::stompReactor::98::B=
roker.StompAdapter::(handle_frame) Handling message <StompFrame command=3D'=
SEND' JsonRpcServer::DEBUG::2014-11-17
15:50:31,428::__init__::504::jsonrpc.JsonR=
pcServer::(serve_requests) Waiting for request
Thread-1735::DEBUG::2014-11-17 15:50:31,428::__init__::467::jsonrpc.JsonRpc=
Server::(_serveRequest) Calling 'StoragePool.connectStorageServer' in bridg=
e with {u'connectionParams': [{u'id':
u'deb5a580-6994-4db9-9899-cd05c39c2ef=
a', u'connection': u'/vmstore/isos', u'iqn': u'',
u'user': u'', u'tpgt': u'=
1', u'password': u'', u'port': u''}],
u'storagepoolID': u'00000000-0000-000=
0-0000-000000000000', u'domainType': 4}
Thread-1735::DEBUG::2014-11-17 15:50:31,431::task::595::Storage.TaskManager=
.Task::(_updateState) Task=3D`1cdc582f-bca2-49c1-a48d-4463f53f0481`::moving=
from state init -> state preparing
Thread-1735::INFO::2014-11-17 15:50:31,431::logUtils::44::dispatcher::(wrap=
per) Run and protect: connectStorageServer(domType=3D4, spUUID=3Du'00000000=
-0000-0000-0000-000000000000', conList=3D[{u'connection':
u'/vmstore/isos',=
u'iqn': u'', u'user': u'', u'tpgt': u'1',
u'password': '******', u'id': u'=
deb5a580-6994-4db9-9899-cd05c39c2efa', u'port': u''}],
options=3DNone)
ioprocess communication (5073)::DEBUG::2014-11-17 15:50:31,433::__init__::3=
75::IOProcess::(_processLogs) Receiving request...
ioprocess communication (5073)::DEBUG::2014-11-17 15:50:31,433::__init__::3=
75::IOProcess::(_processLogs) Queuing request in the thread pool...
ioprocess communication (5073)::DEBUG::2014-11-17 15:50:31,433::__init__::3=
75::IOProcess::(_processLogs) Extracting request information...
ioprocess communication (5073)::DEBUG::2014-11-17 15:50:31,433::__init__::3=
75::IOProcess::(_processLogs) (2322) Got request for method 'access'
ioprocess communication (5073)::DEBUG::2014-11-17 15:50:31,434::__init__::3=
75::IOProcess::(_processLogs) (2322) Queuing response
Thread-1735::DEBUG::2014-11-17 15:50:31,442::hsm::2389::Storage.HSM::(__pre=
fetchDomains) local _path: /vmstore/isos
Thread-1735::DEBUG::2014-11-17 15:50:31,442::hsm::2396::Storage.HSM::(__pre=
fetchDomains) Found SD uuids: ()
Thread-1735::DEBUG::2014-11-17 15:50:31,443::hsm::2452::Storage.HSM::(conne=
ctStorageServer) knownSDs: {158ef830-da69-48b4-95b0-3615d6fb5b00: storage.l=
ocalFsSD.findDomain, ac821c1f-b7ca-4534-a10f-9b98c325a070: storage.nfsSD.fi=
ndDomain}
Thread-1735::INFO::2014-11-17 15:50:31,443::logUtils::47::dispatcher::(wrap=
per) Run and protect: connectStorageServer, Return response: {'statuslist':=
[{'status': 0, 'id': u'deb5a580-6994-4db9-9899-cd05c39c2efa'}]}
Thread-1735::DEBUG::2014-11-17 15:50:31,443::task::1191::Storage.TaskManage=
r.Task::(prepare) Task=3D`1cdc582f-bca2-49c1-a48d-4463f53f0481`::finished: =
{'statuslist': [{'status': 0, 'id':
u'deb5a580-6994-4db9-9899-cd05c39c2efa'=
}]}
Thread-1735::DEBUG::2014-11-17 15:50:31,444::task::595::Storage.TaskManager=
.Task::(_updateState) Task=3D`1cdc582f-bca2-49c1-a48d-4463f53f0481`::moving=
from state preparing -> state finished
Thread-1735::DEBUG::2014-11-17 15:50:31,444::resourceManager::940::Storage.=
ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources =
{}
Thread-1735::DEBUG::2014-11-17 15:50:31,444::resourceManager::977::Storage.=
ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {}
Thread-1735::DEBUG::2014-11-17 15:50:31,444::task::993::Storage.TaskManager=
.Task::(_decref) Task=3D`1cdc582f-bca2-49c1-a48d-4463f53f0481`::ref 0 abort=
ing False
Thread-1735::DEBUG::2014-11-17 15:50:31,445::__init__::498::jsonrpc.JsonRpc=
Server::(_serveRequest) Return 'StoragePool.connectStorageServer' in bridge=
with [{'status': 0, 'id':
u'deb5a580-6994-4db9-9899-cd05c39c2efa'}]
Thread-1735::DEBUG::2014-11-17 15:50:31,445::stompReactor::163::yajsonrpc.S=
tompServer::(send) Sending response
JsonRpc (StompReactor)::DEBUG::2014-11-17 15:50:31,451::stompReactor::98::B=
roker.StompAdapter::(handle_frame) Handling message <StompFrame command=3D'=
SEND' Thread-1736::DEBUG::2014-11-17
15:50:31,454::__init__::467::jsonrpc.JsonRpc=
Server::(_serveRequest) Calling 'StorageDomain.create' in bridge with {u'na=
me': u'test', u'domainType': 4, u'domainClass': 1,
u'typeArgs': u'/vmstore/=
isos', u'version': u'3', u'storagedomainID':
u'c46aa2c4-c405-45eb-b7fd-71f6=
27d1c546'}
JsonRpcServer::DEBUG::2014-11-17 15:50:31,454::__init__::504::jsonrpc.JsonR=
pcServer::(serve_requests) Waiting for request
Thread-1736::DEBUG::2014-11-17 15:50:31,459::task::595::Storage.TaskManager=
.Task::(_updateState) Task=3D`54f143dd-2229-4bc1-b639-9017ea3ecdd0`::moving=
from state init -> state preparing
Thread-1736::INFO::2014-11-17 15:50:31,459::logUtils::44::dispatcher::(wrap=
per) Run and protect: createStorageDomain(storageType=3D4, sdUUID=3Du'c46aa=
2c4-c405-45eb-b7fd-71f627d1c546', domainName=3Du'test', typeSpecificArg=3Du=
'/vmstore/isos', domClass=3D1, domVersion=3Du'3', options=3DNone)
Thread-1736::DEBUG::2014-11-17 15:50:31,459::misc::741::Storage.SamplingMet=
hod::(__call__) Trying to enter sampling method (storage.sdc.refreshStorage=
)
Thread-1736::DEBUG::2014-11-17 15:50:31,460::misc::743::Storage.SamplingMet=
hod::(__call__) Got in to sampling method
Thread-1736::DEBUG::2014-11-17 15:50:31,460::misc::741::Storage.SamplingMet=
hod::(__call__) Trying to enter sampling method (storage.iscsi.rescan)
Thread-1736::DEBUG::2014-11-17 15:50:31,460::misc::743::Storage.SamplingMet=
hod::(__call__) Got in to sampling method
Thread-1736::DEBUG::2014-11-17 15:50:31,461::iscsi::403::Storage.ISCSI::(re=
scan) Performing SCSI scan, this will take up to 30 seconds
Thread-1736::DEBUG::2014-11-17 15:50:31,461::iscsiadm::92::Storage.Misc.exc=
Cmd::(_runCmd) /usr/bin/sudo -n /sbin/iscsiadm -m session -R (cwd None)
Thread-1736::DEBUG::2014-11-17 15:50:31,517::misc::751::Storage.SamplingMet=
hod::(__call__) Returning last result
Thread-1736::DEBUG::2014-11-17 15:50:31,521::multipath::110::Storage.Misc.e=
xcCmd::(rescan) /usr/bin/sudo -n /sbin/multipath (cwd None)
Thread-1736::DEBUG::2014-11-17 15:50:31,705::multipath::110::Storage.Misc.e=
xcCmd::(rescan) SUCCESS: <err> =3D ''; <rc> =3D 0
Thread-1736::DEBUG::2014-11-17 15:50:31,706::lvm::489::Storage.OperationMut=
ex::(_invalidateAllPvs) Operation 'lvm invalidate operation' got the operat=
ion mutex
Thread-1736::DEBUG::2014-11-17 15:50:31,707::lvm::491::Storage.OperationMut=
ex::(_invalidateAllPvs) Operation 'lvm invalidate operation' released the o=
peration mutex
Thread-1736::DEBUG::2014-11-17 15:50:31,707::lvm::500::Storage.OperationMut=
ex::(_invalidateAllVgs) Operation 'lvm invalidate operation' got the operat=
ion mutex
Thread-1736::DEBUG::2014-11-17 15:50:31,708::lvm::502::Storage.OperationMut=
ex::(_invalidateAllVgs) Operation 'lvm invalidate operation' released the o=
peration mutex
Thread-1736::DEBUG::2014-11-17 15:50:31,708::lvm::520::Storage.OperationMut=
ex::(_invalidateAllLvs) Operation 'lvm invalidate operation' got the operat=
ion mutex
Thread-1736::DEBUG::2014-11-17 15:50:31,708::lvm::522::Storage.OperationMut=
ex::(_invalidateAllLvs) Operation 'lvm invalidate operation' released the o=
peration mutex
Thread-1736::DEBUG::2014-11-17 15:50:31,709::misc::751::Storage.SamplingMet=
hod::(__call__) Returning last result
Thread-1736::ERROR::2014-11-17 15:50:31,709::sdc::137::Storage.StorageDomai=
nCache::(_findDomain) looking for unfetched domain c46aa2c4-c405-45eb-b7fd-=
71f627d1c546
Thread-1736::ERROR::2014-11-17 15:50:31,709::sdc::154::Storage.StorageDomai=
nCache::(_findUnfetchedDomain) looking for domain c46aa2c4-c405-45eb-b7fd-7=
1f627d1c546
Thread-1736::DEBUG::2014-11-17 15:50:31,710::lvm::365::Storage.OperationMut=
ex::(_reloadvgs) Operation 'lvm reload operation' got the operation mutex
Thread-1736::DEBUG::2014-11-17 15:50:31,712::lvm::288::Storage.Misc.excCmd:=
:(cmd) /usr/bin/sudo -n /sbin/lvm vgs --config ' devices { preferred_names =
=3D ["^/dev/mapper/"] ignore_suspended_devices=3D1 write_cache_state=3D0 di=
sable_after_error_count=3D3 obtain_device_list_from_udev=3D0 filter =3D [ '=
\''r|.*|'\'' ] } global { locking_type=3D1
prioritise_write_locks=3D1 w=
ait_for_locks=3D1 use_lvmetad=3D0 } backup { retain_min =3D 50 retain_d=
ays =3D 0 } ' --noheadings --units b --nosuffix --separator '|' --ignoreski=
ppedcluster -o uuid,name,attr,size,free,extent_size,extent_count,free_count=
,tags,vg_mda_size,vg_mda_free,lv_count,pv_count,pv_name c46aa2c4-c405-45eb-=
b7fd-71f627d1c546 (cwd None)
Thread-1736::DEBUG::2014-11-17 15:50:32,049::lvm::288::Storage.Misc.excCmd:=
:(cmd) FAILED: <err> =3D ' Volume group
"c46aa2c4-c405-45eb-b7fd-71f627d1c=
546" not found\n Skipping volume group c46aa2c4-c405-45eb-b7fd-71f627d1c54=
6\n'; <rc> =3D 5
Thread-1736::WARNING::2014-11-17 15:50:32,052::lvm::370::Storage.LVM::(_rel=
oadvgs) lvm vgs failed: 5 [] [' Volume group "c46aa2c4-c405-45eb-b7fd-71f6=
27d1c546" not found', ' Skipping volume group c46aa2c4-c405-45eb-b7fd-71f6=
27d1c546']
Thread-1736::DEBUG::2014-11-17 15:50:32,052::lvm::407::Storage.OperationMut=
ex::(_reloadvgs) Operation 'lvm reload operation' released the operation mu=
tex
ioprocess communication (5073)::DEBUG::2014-11-17 15:50:32,060::__init__::3=
75::IOProcess::(_processLogs) Receiving request...
ioprocess communication (5073)::DEBUG::2014-11-17 15:50:32,061::__init__::3=
75::IOProcess::(_processLogs) Queuing request in the thread pool...
ioprocess communication (5073)::DEBUG::2014-11-17 15:50:32,062::__init__::3=
75::IOProcess::(_processLogs) Extracting request information...
ioprocess communication (5073)::DEBUG::2014-11-17 15:50:32,062::__init__::3=
75::IOProcess::(_processLogs) (2323) Got request for method 'glob'
ioprocess communication (5073)::DEBUG::2014-11-17 15:50:32,062::__init__::3=
75::IOProcess::(_processLogs) (2323) Queuing response
ioprocess communication (5073)::DEBUG::2014-11-17 15:50:32,064::__init__::3=
75::IOProcess::(_processLogs) Receiving request...
ioprocess communication (5073)::DEBUG::2014-11-17 15:50:32,065::__init__::3=
75::IOProcess::(_processLogs) Queuing request in the thread pool...
ioprocess communication (5073)::DEBUG::2014-11-17 15:50:32,065::__init__::3=
75::IOProcess::(_processLogs) Extracting request information...
ioprocess communication (5073)::DEBUG::2014-11-17 15:50:32,065::__init__::3=
75::IOProcess::(_processLogs) (2324) Got request for method 'glob'
ioprocess communication (5073)::DEBUG::2014-11-17 15:50:32,066::__init__::3=
75::IOProcess::(_processLogs) (2324) Queuing response
ioprocess communication (5073)::DEBUG::2014-11-17 15:50:32,067::__init__::3=
75::IOProcess::(_processLogs) Receiving request...
ioprocess communication (5073)::DEBUG::2014-11-17 15:50:32,068::__init__::3=
75::IOProcess::(_processLogs) Queuing request in the thread pool...
ioprocess communication (5073)::DEBUG::2014-11-17 15:50:32,069::__init__::3=
75::IOProcess::(_processLogs) Extracting request information...
ioprocess communication (5073)::DEBUG::2014-11-17 15:50:32,069::__init__::3=
75::IOProcess::(_processLogs) (2325) Got request for method 'glob'
ioprocess communication (5073)::DEBUG::2014-11-17 15:50:32,069::__init__::3=
75::IOProcess::(_processLogs) (2325) Queuing response
ioprocess communication (5073)::DEBUG::2014-11-17 15:50:32,079::__init__::3=
75::IOProcess::(_processLogs) Receiving request...
ioprocess communication (5073)::DEBUG::2014-11-17 15:50:32,082::__init__::3=
75::IOProcess::(_processLogs) Queuing request in the thread pool...
ioprocess communication (5073)::DEBUG::2014-11-17 15:50:32,082::__init__::3=
75::IOProcess::(_processLogs) Extracting request information...
ioprocess communication (5073)::DEBUG::2014-11-17 15:50:32,083::__init__::3=
75::IOProcess::(_processLogs) (2326) Got request for method 'glob'
ioprocess communication (5073)::DEBUG::2014-11-17 15:50:32,083::__init__::3=
75::IOProcess::(_processLogs) (2326) Queuing response
ioprocess communication (5073)::DEBUG::2014-11-17 15:50:32,085::__init__::3=
75::IOProcess::(_processLogs) Receiving request...
ioprocess communication (5073)::DEBUG::2014-11-17 15:50:32,086::__init__::3=
75::IOProcess::(_processLogs) Queuing request in the thread pool...
ioprocess communication (5073)::DEBUG::2014-11-17 15:50:32,086::__init__::3=
75::IOProcess::(_processLogs) Extracting request information...
ioprocess communication (5073)::DEBUG::2014-11-17 15:50:32,087::__init__::3=
75::IOProcess::(_processLogs) (2327) Got request for method 'glob'
ioprocess communication (5073)::DEBUG::2014-11-17 15:50:32,087::__init__::3=
75::IOProcess::(_processLogs) (2327) Queuing response
ioprocess communication (5073)::DEBUG::2014-11-17 15:50:32,089::__init__::3=
75::IOProcess::(_processLogs) Receiving request...
ioprocess communication (5073)::DEBUG::2014-11-17 15:50:32,089::__init__::3=
75::IOProcess::(_processLogs) Queuing request in the thread pool...
ioprocess communication (5073)::DEBUG::2014-11-17 15:50:32,090::__init__::3=
75::IOProcess::(_processLogs) Extracting request information...
ioprocess communication (5073)::DEBUG::2014-11-17 15:50:32,090::__init__::3=
75::IOProcess::(_processLogs) (2328) Got request for method 'glob'
ioprocess communication (5073)::DEBUG::2014-11-17 15:50:32,090::__init__::3=
75::IOProcess::(_processLogs) (2328) Queuing response
ioprocess communication (5073)::DEBUG::2014-11-17 15:50:32,092::__init__::3=
75::IOProcess::(_processLogs) Receiving request...
ioprocess communication (5073)::DEBUG::2014-11-17 15:50:32,093::__init__::3=
75::IOProcess::(_processLogs) Queuing request in the thread pool...
ioprocess communication (5073)::DEBUG::2014-11-17 15:50:32,093::__init__::3=
75::IOProcess::(_processLogs) Extracting request information...
ioprocess communication (5073)::DEBUG::2014-11-17 15:50:32,093::__init__::3=
75::IOProcess::(_processLogs) (2329) Got request for method 'glob'
ioprocess communication (5073)::DEBUG::2014-11-17 15:50:32,093::__init__::3=
75::IOProcess::(_processLogs) (2329) Queuing response
Thread-1736::ERROR::2014-11-17 15:50:32,095::sdc::143::Storage.StorageDomai=
nCache::(_findDomain) domain c46aa2c4-c405-45eb-b7fd-71f627d1c546 not found
Traceback (most recent call last):
File "/usr/share/vdsm/storage/sdc.py", line 141, in _findDomain
dom =3D findMethod(sdUUID)
File "/usr/share/vdsm/storage/sdc.py", line 171, in _findUnfetchedDomain
raise se.StorageDomainDoesNotExist(sdUUID)
StorageDomainDoesNotExist: Storage domain does not exist: (u'c46aa2c4-c405-=
45eb-b7fd-71f627d1c546',)
Thread-1736::INFO::2014-11-17 15:50:32,096::localFsSD::73::Storage.StorageD=
omain::(create) sdUUID=3Dc46aa2c4-c405-45eb-b7fd-71f627d1c546 domainName=3D=
test remotePath=3D/vmstore/isos domClass=3D1
ioprocess communication (5073)::DEBUG::2014-11-17 15:50:32,097::__init__::3=
75::IOProcess::(_processLogs) Receiving request...
ioprocess communication (5073)::DEBUG::2014-11-17 15:50:32,098::__init__::3=
75::IOProcess::(_processLogs) Queuing request in the thread pool...
ioprocess communication (5073)::DEBUG::2014-11-17 15:50:32,098::__init__::3=
75::IOProcess::(_processLogs) Extracting request information...
ioprocess communication (5073)::DEBUG::2014-11-17 15:50:32,098::__init__::3=
75::IOProcess::(_processLogs) (2330) Got request for method 'access'
ioprocess communication (5073)::DEBUG::2014-11-17 15:50:32,099::__init__::3=
75::IOProcess::(_processLogs) (2330) Queuing response
ioprocess communication (5073)::DEBUG::2014-11-17 15:50:32,107::__init__::3=
75::IOProcess::(_processLogs) Receiving request...
ioprocess communication (5073)::DEBUG::2014-11-17 15:50:32,107::__init__::3=
75::IOProcess::(_processLogs) Queuing request in the thread pool...
ioprocess communication (5073)::DEBUG::2014-11-17 15:50:32,108::__init__::3=
75::IOProcess::(_processLogs) Extracting request information...
ioprocess communication (5073)::DEBUG::2014-11-17 15:50:32,108::__init__::3=
75::IOProcess::(_processLogs) (2331) Got request for method 'touch'
ioprocess communication (5073)::DEBUG::2014-11-17 15:50:32,108::__init__::3=
75::IOProcess::(_processLogs) (2331) Queuing response
Thread-1736::ERROR::2014-11-17 15:50:32,109::fileSD::92::Storage.fileSD::(v=
alidateFileSystemFeatures) Underlying file system doesn't supportdirect IO
Thread-1736::ERROR::2014-11-17 15:50:32,109::task::866::Storage.TaskManager=
.Task::(_setError) Task=3D`54f143dd-2229-4bc1-b639-9017ea3ecdd0`::Unexpecte=
d error
Traceback (most recent call last):
File "/usr/share/vdsm/storage/task.py", line 873, in _run
return fn(*args, **kargs)
File "/usr/share/vdsm/logUtils.py", line 45, in wrapper
res =3D f(*args, **kwargs)
File "/usr/share/vdsm/storage/hsm.py", line 2683, in createStorageDomain
domVersion)
File "/usr/share/vdsm/storage/localFsSD.py", line 84, in create
cls._preCreateValidation(sdUUID, mntPoint, remotePath, version)
File "/usr/share/vdsm/storage/localFsSD.py", line 51, in _preCreateValida=
tion
fileSD.validateFileSystemFeatures(sdUUID, domPath)
File "/usr/share/vdsm/storage/fileSD.py", line 94, in validateFileSystemF=
eatures
raise se.StorageDomainTargetUnsupported()
StorageDomainTargetUnsupported: Storage Domain target is unsupported: ()
Thread-1736::DEBUG::2014-11-17 15:50:32,110::task::885::Storage.TaskManager=
.Task::(_run) Task=3D`54f143dd-2229-4bc1-b639-9017ea3ecdd0`::Task._run: 54f=
143dd-2229-4bc1-b639-9017ea3ecdd0 (4, u'c46aa2c4-c405-45eb-b7fd-71f627d1c54=
6', u'test', u'/vmstore/isos', 1, u'3') {} failed - stopping
task
Thread-1736::DEBUG::2014-11-17 15:50:32,110::task::1217::Storage.TaskManage=
r.Task::(stop) Task=3D`54f143dd-2229-4bc1-b639-9017ea3ecdd0`::stopping in s=
tate preparing (force False)
Thread-1736::DEBUG::2014-11-17 15:50:32,111::task::993::Storage.TaskManager=
.Task::(_decref) Task=3D`54f143dd-2229-4bc1-b639-9017ea3ecdd0`::ref 1 abort=
ing True
Thread-1736::INFO::2014-11-17 15:50:32,111::task::1171::Storage.TaskManager=
.Task::(prepare) Task=3D`54f143dd-2229-4bc1-b639-9017ea3ecdd0`::aborting: T=
ask is aborted: 'Storage Domain target is unsupported' - code 399
Thread-1736::DEBUG::2014-11-17 15:50:32,111::task::1176::Storage.TaskManage=
r.Task::(prepare) Task=3D`54f143dd-2229-4bc1-b639-9017ea3ecdd0`::Prepare: a=
borted: Storage Domain target is unsupported
Thread-1736::DEBUG::2014-11-17 15:50:32,111::task::993::Storage.TaskManager=
.Task::(_decref) Task=3D`54f143dd-2229-4bc1-b639-9017ea3ecdd0`::ref 0 abort=
ing True
Thread-1736::DEBUG::2014-11-17 15:50:32,112::task::928::Storage.TaskManager=
.Task::(_doAbort) Task=3D`54f143dd-2229-4bc1-b639-9017ea3ecdd0`::Task._doAb=
ort: force False
Thread-1736::DEBUG::2014-11-17 15:50:32,112::resourceManager::977::Storage.=
ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {}
Thread-1736::DEBUG::2014-11-17 15:50:32,112::task::595::Storage.TaskManager=
.Task::(_updateState) Task=3D`54f143dd-2229-4bc1-b639-9017ea3ecdd0`::moving=
from state preparing -> state aborting
Thread-1736::DEBUG::2014-11-17 15:50:32,113::task::550::Storage.TaskManager=
.Task::(__state_aborting) Task=3D`54f143dd-2229-4bc1-b639-9017ea3ecdd0`::_a=
borting: recover policy none
Thread-1736::DEBUG::2014-11-17 15:50:32,113::task::595::Storage.TaskManager=
.Task::(_updateState) Task=3D`54f143dd-2229-4bc1-b639-9017ea3ecdd0`::moving=
from state aborting -> state failed
Thread-1736::DEBUG::2014-11-17 15:50:32,113::resourceManager::940::Storage.=
ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources =
{}
Thread-1736::DEBUG::2014-11-17 15:50:32,113::resourceManager::977::Storage.=
ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {}
Thread-1736::ERROR::2014-11-17 15:50:32,114::dispatcher::76::Storage.Dispat=
cher::(wrapper) {'status': {'message': 'Storage Domain target is
unsupporte=
d: ()', 'code': 399}}
Thread-1736::DEBUG::2014-11-17 15:50:32,114::stompReactor::163::yajsonrpc.S=
tompServer::(send) Sending response
JsonRpc (StompReactor)::DEBUG::2014-11-17 15:50:32,212::stompReactor::98::B=
roker.StompAdapter::(handle_frame) Handling message <StompFrame command=3D'=
SEND' Thread-1744::DEBUG::2014-11-17
15:50:32,215::__init__::467::jsonrpc.JsonRpc=
Server::(_serveRequest) Calling 'StoragePool.disconnectStorageServer' in br=
idge with {u'connectionParams': [{u'id':
u'deb5a580-6994-4db9-9899-cd05c39c=
2efa', u'connection': u'/vmstore/isos', u'iqn': u'',
u'user': u'', u'tpgt':=
u'1', u'password': u'', u'port': u''}],
u'storagepoolID': u'00000000-0000-=
0000-0000-000000000000', u'domainType': 4}
JsonRpcServer::DEBUG::2014-11-17 15:50:32,215::__init__::504::jsonrpc.JsonR=
pcServer::(serve_requests) Waiting for request
Thread-1744::DEBUG::2014-11-17 15:50:32,220::task::595::Storage.TaskManager=
.Task::(_updateState) Task=3D`11fde6d7-ad39-4b40-bb40-00816fe2c131`::moving=
from state init -> state preparing
Thread-1744::INFO::2014-11-17 15:50:32,221::logUtils::44::dispatcher::(wrap=
per) Run and protect: disconnectStorageServer(domType=3D4, spUUID=3Du'00000=
000-0000-0000-0000-000000000000', conList=3D[{u'connection':
u'/vmstore/iso=
s', u'iqn': u'', u'user': u'', u'tpgt':
u'1', u'password': '******', u'id':=
u'deb5a580-6994-4db9-9899-cd05c39c2efa', u'port': u''}],
options=3DNone)
Thread-1744::DEBUG::2014-11-17 15:50:32,221::misc::741::Storage.SamplingMet=
hod::(__call__) Trying to enter sampling method (storage.sdc.refreshStorage=
)
Thread-1744::DEBUG::2014-11-17 15:50:32,222::misc::743::Storage.SamplingMet=
hod::(__call__) Got in to sampling method
Thread-1744::DEBUG::2014-11-17 15:50:32,222::misc::741::Storage.SamplingMet=
hod::(__call__) Trying to enter sampling method (storage.iscsi.rescan)
Thread-1744::DEBUG::2014-11-17 15:50:32,222::misc::743::Storage.SamplingMet=
hod::(__call__) Got in to sampling method
Thread-1744::DEBUG::2014-11-17 15:50:32,222::iscsi::403::Storage.ISCSI::(re=
scan) Performing SCSI scan, this will take up to 30 seconds
Thread-1744::DEBUG::2014-11-17 15:50:32,223::iscsiadm::92::Storage.Misc.exc=
Cmd::(_runCmd) /usr/bin/sudo -n /sbin/iscsiadm -m session -R (cwd None)
Thread-1744::DEBUG::2014-11-17 15:50:32,276::misc::751::Storage.SamplingMet=
hod::(__call__) Returning last result
Thread-1744::DEBUG::2014-11-17 15:50:32,280::multipath::110::Storage.Misc.e=
xcCmd::(rescan) /usr/bin/sudo -n /sbin/multipath (cwd None)
Thread-1744::DEBUG::2014-11-17 15:50:32,409::multipath::110::Storage.Misc.e=
xcCmd::(rescan) SUCCESS: <err> =3D ''; <rc> =3D 0
Thread-1744::DEBUG::2014-11-17 15:50:32,410::lvm::489::Storage.OperationMut=
ex::(_invalidateAllPvs) Operation 'lvm invalidate operation' got the operat=
ion mutex
Thread-1744::DEBUG::2014-11-17 15:50:32,410::lvm::491::Storage.OperationMut=
ex::(_invalidateAllPvs) Operation 'lvm invalidate operation' released the o=
peration mutex
Thread-1744::DEBUG::2014-11-17 15:50:32,411::lvm::500::Storage.OperationMut=
ex::(_invalidateAllVgs) Operation 'lvm invalidate operation' got the operat=
ion mutex
Thread-1744::DEBUG::2014-11-17 15:50:32,411::lvm::502::Storage.OperationMut=
ex::(_invalidateAllVgs) Operation 'lvm invalidate operation' released the o=
peration mutex
Thread-1744::DEBUG::2014-11-17 15:50:32,412::lvm::520::Storage.OperationMut=
ex::(_invalidateAllLvs) Operation 'lvm invalidate operation' got the operat=
ion mutex
Thread-1744::DEBUG::2014-11-17 15:50:32,412::lvm::522::Storage.OperationMut=
ex::(_invalidateAllLvs) Operation 'lvm invalidate operation' released the o=
peration mutex
Thread-1744::DEBUG::2014-11-17 15:50:32,412::misc::751::Storage.SamplingMet=
hod::(__call__) Returning last result
Thread-1744::INFO::2014-11-17 15:50:32,413::logUtils::47::dispatcher::(wrap=
per) Run and protect: disconnectStorageServer, Return response: {'statuslis=
t': [{'status': 0, 'id':
u'deb5a580-6994-4db9-9899-cd05c39c2efa'}]}
Thread-1744::DEBUG::2014-11-17 15:50:32,413::task::1191::Storage.TaskManage=
r.Task::(prepare) Task=3D`11fde6d7-ad39-4b40-bb40-00816fe2c131`::finished: =
{'statuslist': [{'status': 0, 'id':
u'deb5a580-6994-4db9-9899-cd05c39c2efa'=
}]}
Thread-1744::DEBUG::2014-11-17 15:50:32,414::task::595::Storage.TaskManager=
.Task::(_updateState) Task=3D`11fde6d7-ad39-4b40-bb40-00816fe2c131`::moving=
from state preparing -> state finished
Thread-1744::DEBUG::2014-11-17 15:50:32,414::resourceManager::940::Storage.=
ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources =
{}
Thread-1744::DEBUG::2014-11-17 15:50:32,414::resourceManager::977::Storage.=
ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {}
Thread-1744::DEBUG::2014-11-17 15:50:32,414::task::993::Storage.TaskManager=
.Task::(_decref) Task=3D`11fde6d7-ad39-4b40-bb40-00816fe2c131`::ref 0 abort=
ing False
Thread-1744::DEBUG::2014-11-17 15:50:32,415::__init__::498::jsonrpc.JsonRpc=
Server::(_serveRequest) Return 'StoragePool.disconnectStorageServer' in bri=
dge with [{'status': 0, 'id':
u'deb5a580-6994-4db9-9899-cd05c39c2efa'}]
Thread-1744::DEBUG::2014-11-17 15:50:32,416::stompReactor::163::yajsonrpc.S=
tompServer::(send) Sending response
--_000_EE5DB373DE433D4A87A60EE0284C92F926421EFDRISXMBX03adsuhn_
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 14 (filtered medium)" <style><!--
/* Font Definitions */
@font-face
{font-family:Calibri;
panose-1:2 15 5 2 2 2 4 3 2 4;}
/* Style Definitions */
p.MsoNormal, li.MsoNormal, div.MsoNormal
{margin:0cm;
margin-bottom:.0001pt;
font-size:11.0pt;
font-family:"Calibri","sans-serif";}
a:link, span.MsoHyperlink
{mso-style-priority:99;
color:blue;
text-decoration:underline;}
a:visited, span.MsoHyperlinkFollowed
{mso-style-priority:99;
color:purple;
text-decoration:underline;}
span.EmailStyle17
{mso-style-type:personal-compose;
font-family:"Calibri","sans-serif";
color:windowtext;}
.MsoChpDefault
{mso-style-type:export-only;
font-family:"Calibri","sans-serif";}
@page WordSection1
{size:612.0pt 792.0pt;
margin:72.0pt 72.0pt 72.0pt 72.0pt;}
div.WordSection1
{page:WordSection1;}
--></style><!--[if gte mso 9]><xml
<o:shapedefaults v:ext=3D"edit" spidmax=3D"1026" / </xml><![endif]--><!--[if gte mso
9]><xml <o:shapelayout
v:ext=3D"edit" <o:idmap v:ext=3D"edit"
data=3D"1" /
</o:shapelayout></xml><![endif]--
</head <body lang=3D"EN-US"
link=3D"blue" vlink=3D"purple" <div
class=3D"WordSection1" <p
class=3D"MsoNormal">Hello all,<o:p></o:p></p <p
class=3D"MsoNormal"><o:p> </o:p></p <p class=3D"MsoNormal">I’m exploring
using oVirt 3.5 as the managemen=
t engine for a new cluster I am building. I don’t really need high av=
ailability and I have 3 servers with fast SSDs on local storage I’d l=
ike to use, preferably using ZFS to ensure reliability
of the storage.<o:p></o:p></p <p
class=3D"MsoNormal"><o:p> </o:p></p <p class=3D"MsoNormal">I set up a brand new
CentOS 6.6 minimal install and =
have been able to get oVirt up using the all-in-one plugin. I can use local=
storage (the default EXT4 LVM filesystem CentOS sets up) to setup a local =
data center for each of the servers
but have run into issues when I try to provision a ZFS filesystem as a loc=
al storage domain. I’m using ZFS on Linux
0.6.3.<o:p></o:p></p <p
class=3D"MsoNormal"><o:p> </o:p></p <p class=3D"MsoNormal">I first had
multipath.conf issues, which prevented m=
e from even setting up a zpool using the local disks. After blacklisting th=
e local disks in /etc/multipath.conf, I was able to get zfs up and running =
and create the local domains, but
I get errors when I try to create a storage Domain of type: Data / Local o=
n Host and point at the ZFS path (/vmstore/isos) (PS: I’m not trying =
to create an ISO domain, it’s just a directory name
isos)<o:p></o:p><=
/p <p
class=3D"MsoNormal"><o:p> </o:p></p <p class=3D"MsoNormal">The error message that
comes up is “Error whil=
e executing action New Local Storage Domain: Storage Domain target is unsup=
ported”.<o:p></o:p></p <p
class=3D"MsoNormal"><o:p> </o:p></p <p class=3D"MsoNormal">Has anyone tried this
before? I am able to set up an=
NFS export of the ZFS folder as a Storage Domain (using directions here:
<a href=3D"http://virt.guru/2014/02/25/installing-ovirt-with-shared-local-s=
torage/"
http://virt.guru/2014/02/25/installing-ovirt-with-shared-local-storage/&l...
) but if possible, I’d like to skip the NFS layer if I can and just g=
o directly to the hardware.<o:p></o:p></p <p
class=3D"MsoNormal"><o:p> </o:p></p <p class=3D"MsoNormal">The supervdsm.log
shows:<o:p></o:p></p <p
class=3D"MsoNormal">MainProcess|Thread-1734::DEBUG::2014-11-17 15:50:31,=
350::supervdsmServer::101::SuperVdsm.ServerCallback::(wrapper) call validat=
eAccess with ('qemu', ('qemu', 'kvm'), u'/vmstore/isos',
5) {}<o:p></o:p></=
p <p
class=3D"MsoNormal">MainProcess|Thread-1734::DEBUG::2014-11-17 15:50:31,=
356::supervdsmServer::108::SuperVdsm.ServerCallback::(wrapper) return valid=
ateAccess with None<o:p></o:p></p <p
class=3D"MsoNormal">MainProcess|Thread-1735::DEBUG::2014-11-17 15:50:31,=
436::supervdsmServer::101::SuperVdsm.ServerCallback::(wrapper) call validat=
eAccess with ('qemu', ('qemu', 'kvm'), u'/vmstore/isos',
5) {}<o:p></o:p></=
p <p
class=3D"MsoNormal">MainProcess|Thread-1735::DEBUG::2014-11-17 15:50:31,=
441::supervdsmServer::108::SuperVdsm.ServerCallback::(wrapper) return valid=
ateAccess with None<o:p></o:p></p <p
class=3D"MsoNormal">MainProcess|Thread-1736::DEBUG::2014-11-17 15:50:31,=
519::supervdsmServer::101::SuperVdsm.ServerCallback::(wrapper) call hbaResc=
an with () {}<o:p></o:p></p <p
class=3D"MsoNormal">MainProcess|Thread-1736::INFO::2014-11-17 15:50:31,5=
20::hba::54::Storage.HBA::(rescan) Rescanning HBAs<o:p></o:p></p <p
class=3D"MsoNormal">MainProcess|Thread-1736::DEBUG::2014-11-17 15:50:31,=
520::supervdsmServer::108::SuperVdsm.ServerCallback::(wrapper) return hbaRe=
scan with None<o:p></o:p></p <p
class=3D"MsoNormal">MainProcess|Thread-1736::DEBUG::2014-11-17 15:50:32,=
100::supervdsmServer::101::SuperVdsm.ServerCallback::(wrapper) call validat=
eAccess with ('qemu', ('qemu', 'kvm'),
u'/rhev/data-center/mnt/_vmstore_iso=
s', 5) {}<o:p></o:p></p <p
class=3D"MsoNormal">MainProcess|Thread-1736::DEBUG::2014-11-17 15:50:32,=
105::supervdsmServer::108::SuperVdsm.ServerCallback::(wrapper) return valid=
ateAccess with None<o:p></o:p></p <p
class=3D"MsoNormal">MainProcess|Thread-1744::DEBUG::2014-11-17 15:50:32,=
278::supervdsmServer::101::SuperVdsm.ServerCallback::(wrapper) call hbaResc=
an with () {}<o:p></o:p></p <p
class=3D"MsoNormal">MainProcess|Thread-1744::INFO::2014-11-17 15:50:32,2=
79::hba::54::Storage.HBA::(rescan) Rescanning HBAs<o:p></o:p></p <p
class=3D"MsoNormal">MainProcess|Thread-1744::DEBUG::2014-11-17 15:50:32,=
279::supervdsmServer::108::SuperVdsm.ServerCallback::(wrapper) return hbaRe=
scan with None<o:p></o:p></p <p
class=3D"MsoNormal"><o:p> </o:p></p <p class=3D"MsoNormal">And the vdsm.log
shows:<o:p></o:p></p <p
class=3D"MsoNormal">Thread-1732::DEBUG::2014-11-17 15:50:31,032::task::5=
95::Storage.TaskManager.Task::(_updateState) Task=3D`82d29b1b-0c33-4078-887=
b-476d95f4b1a1`::moving from state init -> state
preparing<o:p></o:p></p=
<p
class=3D"MsoNormal">Thread-1732::INFO::2014-11-17 15:50:31,032::logUtils=
::44::dispatcher::(wrapper) Run and protect: repoStats(options=3DNone)<o:p>=
</o:p></p <p
class=3D"MsoNormal">Thread-1732::INFO::2014-11-17 15:50:31,033::logUtils=
::47::dispatcher::(wrapper) Run and protect: repoStats, Return response: {u=
'158ef830-da69-48b4-95b0-3615d6fb5b00': {'code': 0, 'version': 3,
'acquired=
': True, 'delay': '0.000627117', 'lastCheck':
'8.2', 'valid': True}}<o:p></o:p></p <p
class=3D"MsoNormal">Thread-1732::DEBUG::2014-11-17 15:50:31,033::task::1=
191::Storage.TaskManager.Task::(prepare) Task=3D`82d29b1b-0c33-4078-887b-47=
6d95f4b1a1`::finished: {u'158ef830-da69-48b4-95b0-3615d6fb5b00': {'code':
0=
, 'version': 3, 'acquired': True, 'delay':
'0.000627117', 'lastCheck': '8.2', 'valid':
True}}<o:p></o:p></p <p
class=3D"MsoNormal">Thread-1732::DEBUG::2014-11-17 15:50:31,033::task::5=
95::Storage.TaskManager.Task::(_updateState) Task=3D`82d29b1b-0c33-4078-887=
b-476d95f4b1a1`::moving from state preparing -> state finished<o:p></o:p=
></p <p
class=3D"MsoNormal">Thread-1732::DEBUG::2014-11-17 15:50:31,034::resourc=
eManager::940::Storage.ResourceManager.Owner::(releaseAll) Owner.releaseAll=
requests {} resources {}<o:p></o:p></p <p
class=3D"MsoNormal">Thread-1732::DEBUG::2014-11-17 15:50:31,034::resourc=
eManager::977::Storage.ResourceManager.Owner::(cancelAll) Owner.cancelAll r=
equests {}<o:p></o:p></p <p
class=3D"MsoNormal">Thread-1732::DEBUG::2014-11-17 15:50:31,034::task::9=
93::Storage.TaskManager.Task::(_decref) Task=3D`82d29b1b-0c33-4078-887b-476=
d95f4b1a1`::ref 0 aborting False<o:p></o:p></p <p
class=3D"MsoNormal">Thread-1732::DEBUG::2014-11-17 15:50:31,042::stompRe=
actor::163::yajsonrpc.StompServer::(send) Sending
response<o:p></o:p></p <p
class=3D"MsoNormal">JsonRpc (StompReactor)::DEBUG::2014-11-17 15:50:31,0=
60::stompReactor::98::Broker.StompAdapter::(handle_frame) Handling message =
<StompFrame command=3D'SEND'><o:p></o:p></p <p
class=3D"MsoNormal">JsonRpcServer::DEBUG::2014-11-17 15:50:31,062::__ini=
t__::504::jsonrpc.JsonRpcServer::(serve_requests) Waiting for request<o:p><=
/o:p></p <p
class=3D"MsoNormal">Thread-1733::DEBUG::2014-11-17 15:50:31,066::stompRe=
actor::163::yajsonrpc.StompServer::(send) Sending
response<o:p></o:p></p <p
class=3D"MsoNormal">JsonRpc (StompReactor)::DEBUG::2014-11-17 15:50:31,3=
38::stompReactor::98::Broker.StompAdapter::(handle_frame) Handling message =
<StompFrame command=3D'SEND'><o:p></o:p></p <p
class=3D"MsoNormal">JsonRpcServer::DEBUG::2014-11-17 15:50:31,341::__ini=
t__::504::jsonrpc.JsonRpcServer::(serve_requests) Waiting for request<o:p><=
/o:p></p <p
class=3D"MsoNormal">Thread-1734::DEBUG::2014-11-17 15:50:31,341::__init_=
_::467::jsonrpc.JsonRpcServer::(_serveRequest) Calling 'StoragePool.connect=
StorageServer' in bridge with {u'connectionParams': [{u'id':
u'00000000-000=
0-0000-0000-000000000000', u'connection':
u'/vmstore/isos', u'iqn': u'', u'user': u'',
u'tpgt': u'1', u'password': u=
'', u'port': u''}], u'storagepoolID':
u'00000000-0000-0000-0000-00000000000=
0', u'domainType': 4}<o:p></o:p></p <p
class=3D"MsoNormal">Thread-1734::DEBUG::2014-11-17 15:50:31,344::task::5=
95::Storage.TaskManager.Task::(_updateState) Task=3D`4088bef4-489c-41a4-bcd=
4-d5f1906378b9`::moving from state init -> state
preparing<o:p></o:p></p=
<p
class=3D"MsoNormal">Thread-1734::INFO::2014-11-17 15:50:31,345::logUtils=
::44::dispatcher::(wrapper) Run and protect: connectStorageServer(domType=
=3D4, spUUID=3Du'00000000-0000-0000-0000-000000000000', conList=3D[{u'conne=
ction': u'/vmstore/isos', u'iqn': u'', u'user':
u'', u'tpgt': u'1', u'password': '******',
u'id': u'00000000-0000-0000-000=
0-000000000000', u'port': u''}],
options=3DNone)<o:p></o:p></p <p
class=3D"MsoNormal">ioprocess communication (5073)::DEBUG::2014-11-17 15=
:50:31,346::__init__::375::IOProcess::(_processLogs) Receiving request...<o=
:p></o:p></p <p
class=3D"MsoNormal">ioprocess communication (5073)::DEBUG::2014-11-17 15=
:50:31,346::__init__::375::IOProcess::(_processLogs) Queuing request in the=
thread pool...<o:p></o:p></p <p
class=3D"MsoNormal">ioprocess communication (5073)::DEBUG::2014-11-17 15=
:50:31,347::__init__::375::IOProcess::(_processLogs) Extracting request inf=
ormation...<o:p></o:p></p <p
class=3D"MsoNormal">ioprocess communication (5073)::DEBUG::2014-11-17 15=
:50:31,347::__init__::375::IOProcess::(_processLogs) (2321) Got request for=
method 'access'<o:p></o:p></p <p
class=3D"MsoNormal">ioprocess communication (5073)::DEBUG::2014-11-17 15=
:50:31,347::__init__::375::IOProcess::(_processLogs) (2321) Queuing respons=
e<o:p></o:p></p <p
class=3D"MsoNormal">Thread-1734::DEBUG::2014-11-17 15:50:31,357::hsm::23=
89::Storage.HSM::(__prefetchDomains) local _path:
/vmstore/isos<o:p></o:p><=
/p <p
class=3D"MsoNormal">Thread-1734::DEBUG::2014-11-17 15:50:31,358::hsm::23=
96::Storage.HSM::(__prefetchDomains) Found SD uuids: ()<o:p></o:p></p <p
class=3D"MsoNormal">Thread-1734::DEBUG::2014-11-17 15:50:31,358::hsm::24=
52::Storage.HSM::(connectStorageServer) knownSDs: {158ef830-da69-48b4-95b0-=
3615d6fb5b00: storage.localFsSD.findDomain, ac821c1f-b7ca-4534-a10f-9b98c32=
5a070: storage.nfsSD.findDomain}<o:p></o:p></p <p
class=3D"MsoNormal">Thread-1734::INFO::2014-11-17 15:50:31,358::logUtils=
::47::dispatcher::(wrapper) Run and protect: connectStorageServer, Return r=
esponse: {'statuslist': [{'status': 0, 'id':
u'00000000-0000-0000-0000-0000=
00000000'}]}<o:p></o:p></p <p
class=3D"MsoNormal">Thread-1734::DEBUG::2014-11-17 15:50:31,359::task::1=
191::Storage.TaskManager.Task::(prepare) Task=3D`4088bef4-489c-41a4-bcd4-d5=
f1906378b9`::finished: {'statuslist': [{'status': 0, 'id':
u'00000000-0000-=
0000-0000-000000000000'}]}<o:p></o:p></p <p
class=3D"MsoNormal">Thread-1734::DEBUG::2014-11-17 15:50:31,359::task::5=
95::Storage.TaskManager.Task::(_updateState) Task=3D`4088bef4-489c-41a4-bcd=
4-d5f1906378b9`::moving from state preparing -> state finished<o:p></o:p=
></p <p
class=3D"MsoNormal">Thread-1734::DEBUG::2014-11-17 15:50:31,359::resourc=
eManager::940::Storage.ResourceManager.Owner::(releaseAll) Owner.releaseAll=
requests {} resources {}<o:p></o:p></p <p
class=3D"MsoNormal">Thread-1734::DEBUG::2014-11-17 15:50:31,359::resourc=
eManager::977::Storage.ResourceManager.Owner::(cancelAll) Owner.cancelAll r=
equests {}<o:p></o:p></p <p
class=3D"MsoNormal">Thread-1734::DEBUG::2014-11-17 15:50:31,360::task::9=
93::Storage.TaskManager.Task::(_decref) Task=3D`4088bef4-489c-41a4-bcd4-d5f=
1906378b9`::ref 0 aborting False<o:p></o:p></p <p
class=3D"MsoNormal">Thread-1734::DEBUG::2014-11-17 15:50:31,360::__init_=
_::498::jsonrpc.JsonRpcServer::(_serveRequest) Return 'StoragePool.connectS=
torageServer' in bridge with [{'status': 0, 'id':
u'00000000-0000-0000-0000=
-000000000000'}]<o:p></o:p></p <p
class=3D"MsoNormal">Thread-1734::DEBUG::2014-11-17 15:50:31,361::stompRe=
actor::163::yajsonrpc.StompServer::(send) Sending
response<o:p></o:p></p <p
class=3D"MsoNormal">JsonRpc (StompReactor)::DEBUG::2014-11-17 15:50:31,4=
25::stompReactor::98::Broker.StompAdapter::(handle_frame) Handling message =
<StompFrame command=3D'SEND'><o:p></o:p></p <p
class=3D"MsoNormal">JsonRpcServer::DEBUG::2014-11-17 15:50:31,428::__ini=
t__::504::jsonrpc.JsonRpcServer::(serve_requests) Waiting for request<o:p><=
/o:p></p <p
class=3D"MsoNormal">Thread-1735::DEBUG::2014-11-17 15:50:31,428::__init_=
_::467::jsonrpc.JsonRpcServer::(_serveRequest) Calling 'StoragePool.connect=
StorageServer' in bridge with {u'connectionParams': [{u'id':
u'deb5a580-699=
4-4db9-9899-cd05c39c2efa', u'connection':
u'/vmstore/isos', u'iqn': u'', u'user': u'',
u'tpgt': u'1', u'password': u=
'', u'port': u''}], u'storagepoolID':
u'00000000-0000-0000-0000-00000000000=
0', u'domainType': 4}<o:p></o:p></p <p
class=3D"MsoNormal">Thread-1735::DEBUG::2014-11-17 15:50:31,431::task::5=
95::Storage.TaskManager.Task::(_updateState) Task=3D`1cdc582f-bca2-49c1-a48=
d-4463f53f0481`::moving from state init -> state
preparing<o:p></o:p></p=
<p
class=3D"MsoNormal">Thread-1735::INFO::2014-11-17 15:50:31,431::logUtils=
::44::dispatcher::(wrapper) Run and protect: connectStorageServer(domType=
=3D4, spUUID=3Du'00000000-0000-0000-0000-000000000000', conList=3D[{u'conne=
ction': u'/vmstore/isos', u'iqn': u'', u'user':
u'', u'tpgt': u'1', u'password': '******',
u'id': u'deb5a580-6994-4db9-989=
9-cd05c39c2efa', u'port': u''}],
options=3DNone)<o:p></o:p></p <p
class=3D"MsoNormal">ioprocess communication (5073)::DEBUG::2014-11-17 15=
:50:31,433::__init__::375::IOProcess::(_processLogs) Receiving request...<o=
:p></o:p></p <p
class=3D"MsoNormal">ioprocess communication (5073)::DEBUG::2014-11-17 15=
:50:31,433::__init__::375::IOProcess::(_processLogs) Queuing request in the=
thread pool...<o:p></o:p></p <p
class=3D"MsoNormal">ioprocess communication (5073)::DEBUG::2014-11-17 15=
:50:31,433::__init__::375::IOProcess::(_processLogs) Extracting request inf=
ormation...<o:p></o:p></p <p
class=3D"MsoNormal">ioprocess communication (5073)::DEBUG::2014-11-17 15=
:50:31,433::__init__::375::IOProcess::(_processLogs) (2322) Got request for=
method 'access'<o:p></o:p></p <p
class=3D"MsoNormal">ioprocess communication (5073)::DEBUG::2014-11-17 15=
:50:31,434::__init__::375::IOProcess::(_processLogs) (2322) Queuing respons=
e<o:p></o:p></p <p
class=3D"MsoNormal">Thread-1735::DEBUG::2014-11-17 15:50:31,442::hsm::23=
89::Storage.HSM::(__prefetchDomains) local _path:
/vmstore/isos<o:p></o:p><=
/p <p
class=3D"MsoNormal">Thread-1735::DEBUG::2014-11-17 15:50:31,442::hsm::23=
96::Storage.HSM::(__prefetchDomains) Found SD uuids: ()<o:p></o:p></p <p
class=3D"MsoNormal">Thread-1735::DEBUG::2014-11-17 15:50:31,443::hsm::24=
52::Storage.HSM::(connectStorageServer) knownSDs: {158ef830-da69-48b4-95b0-=
3615d6fb5b00: storage.localFsSD.findDomain, ac821c1f-b7ca-4534-a10f-9b98c32=
5a070: storage.nfsSD.findDomain}<o:p></o:p></p <p
class=3D"MsoNormal">Thread-1735::INFO::2014-11-17 15:50:31,443::logUtils=
::47::dispatcher::(wrapper) Run and protect: connectStorageServer, Return r=
esponse: {'statuslist': [{'status': 0, 'id':
u'deb5a580-6994-4db9-9899-cd05=
c39c2efa'}]}<o:p></o:p></p <p
class=3D"MsoNormal">Thread-1735::DEBUG::2014-11-17 15:50:31,443::task::1=
191::Storage.TaskManager.Task::(prepare) Task=3D`1cdc582f-bca2-49c1-a48d-44=
63f53f0481`::finished: {'statuslist': [{'status': 0, 'id':
u'deb5a580-6994-=
4db9-9899-cd05c39c2efa'}]}<o:p></o:p></p <p
class=3D"MsoNormal">Thread-1735::DEBUG::2014-11-17 15:50:31,444::task::5=
95::Storage.TaskManager.Task::(_updateState) Task=3D`1cdc582f-bca2-49c1-a48=
d-4463f53f0481`::moving from state preparing -> state finished<o:p></o:p=
></p <p
class=3D"MsoNormal">Thread-1735::DEBUG::2014-11-17 15:50:31,444::resourc=
eManager::940::Storage.ResourceManager.Owner::(releaseAll) Owner.releaseAll=
requests {} resources {}<o:p></o:p></p <p
class=3D"MsoNormal">Thread-1735::DEBUG::2014-11-17 15:50:31,444::resourc=
eManager::977::Storage.ResourceManager.Owner::(cancelAll) Owner.cancelAll r=
equests {}<o:p></o:p></p <p
class=3D"MsoNormal">Thread-1735::DEBUG::2014-11-17 15:50:31,444::task::9=
93::Storage.TaskManager.Task::(_decref) Task=3D`1cdc582f-bca2-49c1-a48d-446=
3f53f0481`::ref 0 aborting False<o:p></o:p></p <p
class=3D"MsoNormal">Thread-1735::DEBUG::2014-11-17 15:50:31,445::__init_=
_::498::jsonrpc.JsonRpcServer::(_serveRequest) Return 'StoragePool.connectS=
torageServer' in bridge with [{'status': 0, 'id':
u'deb5a580-6994-4db9-9899=
-cd05c39c2efa'}]<o:p></o:p></p <p
class=3D"MsoNormal">Thread-1735::DEBUG::2014-11-17 15:50:31,445::stompRe=
actor::163::yajsonrpc.StompServer::(send) Sending
response<o:p></o:p></p <p
class=3D"MsoNormal">JsonRpc (StompReactor)::DEBUG::2014-11-17 15:50:31,4=
51::stompReactor::98::Broker.StompAdapter::(handle_frame) Handling message =
<StompFrame command=3D'SEND'><o:p></o:p></p <p
class=3D"MsoNormal">Thread-1736::DEBUG::2014-11-17 15:50:31,454::__init_=
_::467::jsonrpc.JsonRpcServer::(_serveRequest) Calling 'StorageDomain.creat=
e' in bridge with {u'name': u'test', u'domainType': 4,
u'domainClass': 1, u=
'typeArgs': u'/vmstore/isos', u'version':
u'3', u'storagedomainID':
u'c46aa2c4-c405-45eb-b7fd-71f627d1c546'}<o:p></o=
:p></p <p
class=3D"MsoNormal">JsonRpcServer::DEBUG::2014-11-17 15:50:31,454::__ini=
t__::504::jsonrpc.JsonRpcServer::(serve_requests) Waiting for request<o:p><=
/o:p></p <p
class=3D"MsoNormal">Thread-1736::DEBUG::2014-11-17 15:50:31,459::task::5=
95::Storage.TaskManager.Task::(_updateState) Task=3D`54f143dd-2229-4bc1-b63=
9-9017ea3ecdd0`::moving from state init -> state
preparing<o:p></o:p></p=
<p
class=3D"MsoNormal">Thread-1736::INFO::2014-11-17 15:50:31,459::logUtils=
::44::dispatcher::(wrapper) Run and protect: createStorageDomain(storageTyp=
e=3D4, sdUUID=3Du'c46aa2c4-c405-45eb-b7fd-71f627d1c546', domainName=3Du'tes=
t', typeSpecificArg=3Du'/vmstore/isos', domClass=3D1,
domVersion=3Du'3', options=3DNone)<o:p></o:p></p <p
class=3D"MsoNormal">Thread-1736::DEBUG::2014-11-17 15:50:31,459::misc::7=
41::Storage.SamplingMethod::(__call__) Trying to enter sampling method (sto=
rage.sdc.refreshStorage)<o:p></o:p></p <p
class=3D"MsoNormal">Thread-1736::DEBUG::2014-11-17 15:50:31,460::misc::7=
43::Storage.SamplingMethod::(__call__) Got in to sampling method<o:p></o:p>=
</p <p
class=3D"MsoNormal">Thread-1736::DEBUG::2014-11-17 15:50:31,460::misc::7=
41::Storage.SamplingMethod::(__call__) Trying to enter sampling method (sto=
rage.iscsi.rescan)<o:p></o:p></p <p
class=3D"MsoNormal">Thread-1736::DEBUG::2014-11-17 15:50:31,460::misc::7=
43::Storage.SamplingMethod::(__call__) Got in to sampling method<o:p></o:p>=
</p <p
class=3D"MsoNormal">Thread-1736::DEBUG::2014-11-17 15:50:31,461::iscsi::=
403::Storage.ISCSI::(rescan) Performing SCSI scan, this will take up to 30 =
seconds<o:p></o:p></p <p
class=3D"MsoNormal">Thread-1736::DEBUG::2014-11-17 15:50:31,461::iscsiad=
m::92::Storage.Misc.excCmd::(_runCmd) /usr/bin/sudo -n /sbin/iscsiadm -m se=
ssion -R (cwd None)<o:p></o:p></p <p
class=3D"MsoNormal">Thread-1736::DEBUG::2014-11-17 15:50:31,517::misc::7=
51::Storage.SamplingMethod::(__call__) Returning last
result<o:p></o:p></p <p
class=3D"MsoNormal">Thread-1736::DEBUG::2014-11-17 15:50:31,521::multipa=
th::110::Storage.Misc.excCmd::(rescan) /usr/bin/sudo -n /sbin/multipath (cw=
d None)<o:p></o:p></p <p
class=3D"MsoNormal">Thread-1736::DEBUG::2014-11-17 15:50:31,705::multipa=
th::110::Storage.Misc.excCmd::(rescan) SUCCESS: <err> =3D '';
<rc&=
gt; =3D 0<o:p></o:p></p <p
class=3D"MsoNormal">Thread-1736::DEBUG::2014-11-17 15:50:31,706::lvm::48=
9::Storage.OperationMutex::(_invalidateAllPvs) Operation 'lvm invalidate op=
eration' got the operation mutex<o:p></o:p></p <p
class=3D"MsoNormal">Thread-1736::DEBUG::2014-11-17 15:50:31,707::lvm::49=
1::Storage.OperationMutex::(_invalidateAllPvs) Operation 'lvm invalidate op=
eration' released the operation mutex<o:p></o:p></p <p
class=3D"MsoNormal">Thread-1736::DEBUG::2014-11-17 15:50:31,707::lvm::50=
0::Storage.OperationMutex::(_invalidateAllVgs) Operation 'lvm invalidate op=
eration' got the operation mutex<o:p></o:p></p <p
class=3D"MsoNormal">Thread-1736::DEBUG::2014-11-17 15:50:31,708::lvm::50=
2::Storage.OperationMutex::(_invalidateAllVgs) Operation 'lvm invalidate op=
eration' released the operation mutex<o:p></o:p></p <p
class=3D"MsoNormal">Thread-1736::DEBUG::2014-11-17 15:50:31,708::lvm::52=
0::Storage.OperationMutex::(_invalidateAllLvs) Operation 'lvm invalidate op=
eration' got the operation mutex<o:p></o:p></p <p
class=3D"MsoNormal">Thread-1736::DEBUG::2014-11-17 15:50:31,708::lvm::52=
2::Storage.OperationMutex::(_invalidateAllLvs) Operation 'lvm invalidate op=
eration' released the operation mutex<o:p></o:p></p <p
class=3D"MsoNormal">Thread-1736::DEBUG::2014-11-17 15:50:31,709::misc::7=
51::Storage.SamplingMethod::(__call__) Returning last
result<o:p></o:p></p <p
class=3D"MsoNormal">Thread-1736::ERROR::2014-11-17 15:50:31,709::sdc::13=
7::Storage.StorageDomainCache::(_findDomain) looking for unfetched domain c=
46aa2c4-c405-45eb-b7fd-71f627d1c546<o:p></o:p></p <p
class=3D"MsoNormal">Thread-1736::ERROR::2014-11-17 15:50:31,709::sdc::15=
4::Storage.StorageDomainCache::(_findUnfetchedDomain) looking for domain c4=
6aa2c4-c405-45eb-b7fd-71f627d1c546<o:p></o:p></p <p
class=3D"MsoNormal">Thread-1736::DEBUG::2014-11-17 15:50:31,710::lvm::36=
5::Storage.OperationMutex::(_reloadvgs) Operation 'lvm reload operation' go=
t the operation mutex<o:p></o:p></p <p
class=3D"MsoNormal">Thread-1736::DEBUG::2014-11-17 15:50:31,712::lvm::28=
8::Storage.Misc.excCmd::(cmd) /usr/bin/sudo -n /sbin/lvm vgs --config ' dev=
ices { preferred_names =3D ["^/dev/mapper/"] ignore_suspended_dev=
ices=3D1 write_cache_state=3D0 disable_after_error_count=3D3
obtain_device_list_from_udev=3D0 filter =3D [ '\''r|.*|'\'' ]
} glob=
al { locking_type=3D1 prioritise_write_locks=3D1 wait_for=
_locks=3D1 use_lvmetad=3D0 } backup { retain_min =3D
50&n=
bsp; retain_days =3D 0 } ' --noheadings --units b --nosuffix --separator '|=
' --ignoreskippedcluster
-o uuid,name,attr,size,free,extent_size,extent_count,free_count,tags,vg_md=
a_size,vg_mda_free,lv_count,pv_count,pv_name c46aa2c4-c405-45eb-b7fd-71f627=
d1c546 (cwd None)<o:p></o:p></p <p
class=3D"MsoNormal">Thread-1736::DEBUG::2014-11-17 15:50:32,049::lvm::28=
8::Storage.Misc.excCmd::(cmd) FAILED: <err> =3D ' Volume group
=
"c46aa2c4-c405-45eb-b7fd-71f627d1c546" not found\n Skipping=
volume group c46aa2c4-c405-45eb-b7fd-71f627d1c546\n'; <rc> =3D
5<o:p></o:p></p <p
class=3D"MsoNormal">Thread-1736::WARNING::2014-11-17 15:50:32,052::lvm::=
370::Storage.LVM::(_reloadvgs) lvm vgs failed: 5 [] [' Volume group &=
quot;c46aa2c4-c405-45eb-b7fd-71f627d1c546" not found', '
Skippin=
g volume group c46aa2c4-c405-45eb-b7fd-71f627d1c546']<o:p></o:p></p <p
class=3D"MsoNormal">Thread-1736::DEBUG::2014-11-17 15:50:32,052::lvm::40=
7::Storage.OperationMutex::(_reloadvgs) Operation 'lvm reload operation' re=
leased the operation mutex<o:p></o:p></p <p
class=3D"MsoNormal">ioprocess communication (5073)::DEBUG::2014-11-17 15=
:50:32,060::__init__::375::IOProcess::(_processLogs) Receiving request...<o=
:p></o:p></p <p
class=3D"MsoNormal">ioprocess communication (5073)::DEBUG::2014-11-17 15=
:50:32,061::__init__::375::IOProcess::(_processLogs) Queuing request in the=
thread pool...<o:p></o:p></p <p
class=3D"MsoNormal">ioprocess communication (5073)::DEBUG::2014-11-17 15=
:50:32,062::__init__::375::IOProcess::(_processLogs) Extracting request inf=
ormation...<o:p></o:p></p <p
class=3D"MsoNormal">ioprocess communication (5073)::DEBUG::2014-11-17 15=
:50:32,062::__init__::375::IOProcess::(_processLogs) (2323) Got request for=
method 'glob'<o:p></o:p></p <p
class=3D"MsoNormal">ioprocess communication (5073)::DEBUG::2014-11-17 15=
:50:32,062::__init__::375::IOProcess::(_processLogs) (2323) Queuing respons=
e<o:p></o:p></p <p
class=3D"MsoNormal">ioprocess communication (5073)::DEBUG::2014-11-17 15=
:50:32,064::__init__::375::IOProcess::(_processLogs) Receiving request...<o=
:p></o:p></p <p
class=3D"MsoNormal">ioprocess communication (5073)::DEBUG::2014-11-17 15=
:50:32,065::__init__::375::IOProcess::(_processLogs) Queuing request in the=
thread pool...<o:p></o:p></p <p
class=3D"MsoNormal">ioprocess communication (5073)::DEBUG::2014-11-17 15=
:50:32,065::__init__::375::IOProcess::(_processLogs) Extracting request inf=
ormation...<o:p></o:p></p <p
class=3D"MsoNormal">ioprocess communication (5073)::DEBUG::2014-11-17 15=
:50:32,065::__init__::375::IOProcess::(_processLogs) (2324) Got request for=
method 'glob'<o:p></o:p></p <p
class=3D"MsoNormal">ioprocess communication (5073)::DEBUG::2014-11-17 15=
:50:32,066::__init__::375::IOProcess::(_processLogs) (2324) Queuing respons=
e<o:p></o:p></p <p
class=3D"MsoNormal">ioprocess communication (5073)::DEBUG::2014-11-17 15=
:50:32,067::__init__::375::IOProcess::(_processLogs) Receiving request...<o=
:p></o:p></p <p
class=3D"MsoNormal">ioprocess communication (5073)::DEBUG::2014-11-17 15=
:50:32,068::__init__::375::IOProcess::(_processLogs) Queuing request in the=
thread pool...<o:p></o:p></p <p
class=3D"MsoNormal">ioprocess communication (5073)::DEBUG::2014-11-17 15=
:50:32,069::__init__::375::IOProcess::(_processLogs) Extracting request inf=
ormation...<o:p></o:p></p <p
class=3D"MsoNormal">ioprocess communication (5073)::DEBUG::2014-11-17 15=
:50:32,069::__init__::375::IOProcess::(_processLogs) (2325) Got request for=
method 'glob'<o:p></o:p></p <p
class=3D"MsoNormal">ioprocess communication (5073)::DEBUG::2014-11-17 15=
:50:32,069::__init__::375::IOProcess::(_processLogs) (2325) Queuing respons=
e<o:p></o:p></p <p
class=3D"MsoNormal">ioprocess communication (5073)::DEBUG::2014-11-17 15=
:50:32,079::__init__::375::IOProcess::(_processLogs) Receiving request...<o=
:p></o:p></p <p
class=3D"MsoNormal">ioprocess communication (5073)::DEBUG::2014-11-17 15=
:50:32,082::__init__::375::IOProcess::(_processLogs) Queuing request in the=
thread pool...<o:p></o:p></p <p
class=3D"MsoNormal">ioprocess communication (5073)::DEBUG::2014-11-17 15=
:50:32,082::__init__::375::IOProcess::(_processLogs) Extracting request inf=
ormation...<o:p></o:p></p <p
class=3D"MsoNormal">ioprocess communication (5073)::DEBUG::2014-11-17 15=
:50:32,083::__init__::375::IOProcess::(_processLogs) (2326) Got request for=
method 'glob'<o:p></o:p></p <p
class=3D"MsoNormal">ioprocess communication (5073)::DEBUG::2014-11-17 15=
:50:32,083::__init__::375::IOProcess::(_processLogs) (2326) Queuing respons=
e<o:p></o:p></p <p
class=3D"MsoNormal">ioprocess communication (5073)::DEBUG::2014-11-17 15=
:50:32,085::__init__::375::IOProcess::(_processLogs) Receiving request...<o=
:p></o:p></p <p
class=3D"MsoNormal">ioprocess communication (5073)::DEBUG::2014-11-17 15=
:50:32,086::__init__::375::IOProcess::(_processLogs) Queuing request in the=
thread pool...<o:p></o:p></p <p
class=3D"MsoNormal">ioprocess communication (5073)::DEBUG::2014-11-17 15=
:50:32,086::__init__::375::IOProcess::(_processLogs) Extracting request inf=
ormation...<o:p></o:p></p <p
class=3D"MsoNormal">ioprocess communication (5073)::DEBUG::2014-11-17 15=
:50:32,087::__init__::375::IOProcess::(_processLogs) (2327) Got request for=
method 'glob'<o:p></o:p></p <p
class=3D"MsoNormal">ioprocess communication (5073)::DEBUG::2014-11-17 15=
:50:32,087::__init__::375::IOProcess::(_processLogs) (2327) Queuing respons=
e<o:p></o:p></p <p
class=3D"MsoNormal">ioprocess communication (5073)::DEBUG::2014-11-17 15=
:50:32,089::__init__::375::IOProcess::(_processLogs) Receiving request...<o=
:p></o:p></p <p
class=3D"MsoNormal">ioprocess communication (5073)::DEBUG::2014-11-17 15=
:50:32,089::__init__::375::IOProcess::(_processLogs) Queuing request in the=
thread pool...<o:p></o:p></p <p
class=3D"MsoNormal">ioprocess communication (5073)::DEBUG::2014-11-17 15=
:50:32,090::__init__::375::IOProcess::(_processLogs) Extracting request inf=
ormation...<o:p></o:p></p <p
class=3D"MsoNormal">ioprocess communication (5073)::DEBUG::2014-11-17 15=
:50:32,090::__init__::375::IOProcess::(_processLogs) (2328) Got request for=
method 'glob'<o:p></o:p></p <p
class=3D"MsoNormal">ioprocess communication (5073)::DEBUG::2014-11-17 15=
:50:32,090::__init__::375::IOProcess::(_processLogs) (2328) Queuing respons=
e<o:p></o:p></p <p
class=3D"MsoNormal">ioprocess communication (5073)::DEBUG::2014-11-17 15=
:50:32,092::__init__::375::IOProcess::(_processLogs) Receiving request...<o=
:p></o:p></p <p
class=3D"MsoNormal">ioprocess communication (5073)::DEBUG::2014-11-17 15=
:50:32,093::__init__::375::IOProcess::(_processLogs) Queuing request in the=
thread pool...<o:p></o:p></p <p
class=3D"MsoNormal">ioprocess communication (5073)::DEBUG::2014-11-17 15=
:50:32,093::__init__::375::IOProcess::(_processLogs) Extracting request inf=
ormation...<o:p></o:p></p <p
class=3D"MsoNormal">ioprocess communication (5073)::DEBUG::2014-11-17 15=
:50:32,093::__init__::375::IOProcess::(_processLogs) (2329) Got request for=
method 'glob'<o:p></o:p></p <p
class=3D"MsoNormal">ioprocess communication (5073)::DEBUG::2014-11-17 15=
:50:32,093::__init__::375::IOProcess::(_processLogs) (2329) Queuing respons=
e<o:p></o:p></p <p
class=3D"MsoNormal">Thread-1736::ERROR::2014-11-17 15:50:32,095::sdc::14=
3::Storage.StorageDomainCache::(_findDomain) domain c46aa2c4-c405-45eb-b7fd=
-71f627d1c546 not found<o:p></o:p></p <p
class=3D"MsoNormal">Traceback (most recent call
last):<o:p></o:p></p <p
class=3D"MsoNormal"> File
"/usr/share/vdsm/storage/sdc.py&quo=
t;, line 141, in _findDomain<o:p></o:p></p <p
class=3D"MsoNormal"> dom =3D
findMethod(sdUUID)<o:p></=
o:p></p <p
class=3D"MsoNormal"> File
"/usr/share/vdsm/storage/sdc.py&quo=
t;, line 171, in _findUnfetchedDomain<o:p></o:p></p <p
class=3D"MsoNormal"> raise
se.StorageDomainDoesNotExis=
t(sdUUID)<o:p></o:p></p <p
class=3D"MsoNormal">StorageDomainDoesNotExist: Storage domain does not e=
xist: (u'c46aa2c4-c405-45eb-b7fd-71f627d1c546',)<o:p></o:p></p <p
class=3D"MsoNormal">Thread-1736::INFO::2014-11-17 15:50:32,096::localFsS=
D::73::Storage.StorageDomain::(create) sdUUID=3Dc46aa2c4-c405-45eb-b7fd-71f=
627d1c546 domainName=3Dtest remotePath=3D/vmstore/isos domClass=3D1<o:p></o=
:p></p <p
class=3D"MsoNormal">ioprocess communication (5073)::DEBUG::2014-11-17 15=
:50:32,097::__init__::375::IOProcess::(_processLogs) Receiving request...<o=
:p></o:p></p <p
class=3D"MsoNormal">ioprocess communication (5073)::DEBUG::2014-11-17 15=
:50:32,098::__init__::375::IOProcess::(_processLogs) Queuing request in the=
thread pool...<o:p></o:p></p <p
class=3D"MsoNormal">ioprocess communication (5073)::DEBUG::2014-11-17 15=
:50:32,098::__init__::375::IOProcess::(_processLogs) Extracting request inf=
ormation...<o:p></o:p></p <p
class=3D"MsoNormal">ioprocess communication (5073)::DEBUG::2014-11-17 15=
:50:32,098::__init__::375::IOProcess::(_processLogs) (2330) Got request for=
method 'access'<o:p></o:p></p <p
class=3D"MsoNormal">ioprocess communication (5073)::DEBUG::2014-11-17 15=
:50:32,099::__init__::375::IOProcess::(_processLogs) (2330) Queuing respons=
e<o:p></o:p></p <p
class=3D"MsoNormal">ioprocess communication (5073)::DEBUG::2014-11-17 15=
:50:32,107::__init__::375::IOProcess::(_processLogs) Receiving request...<o=
:p></o:p></p <p
class=3D"MsoNormal">ioprocess communication (5073)::DEBUG::2014-11-17 15=
:50:32,107::__init__::375::IOProcess::(_processLogs) Queuing request in the=
thread pool...<o:p></o:p></p <p
class=3D"MsoNormal">ioprocess communication (5073)::DEBUG::2014-11-17 15=
:50:32,108::__init__::375::IOProcess::(_processLogs) Extracting request inf=
ormation...<o:p></o:p></p <p
class=3D"MsoNormal">ioprocess communication (5073)::DEBUG::2014-11-17 15=
:50:32,108::__init__::375::IOProcess::(_processLogs) (2331) Got request for=
method 'touch'<o:p></o:p></p <p
class=3D"MsoNormal">ioprocess communication (5073)::DEBUG::2014-11-17 15=
:50:32,108::__init__::375::IOProcess::(_processLogs) (2331) Queuing respons=
e<o:p></o:p></p <p
class=3D"MsoNormal">Thread-1736::ERROR::2014-11-17 15:50:32,109::fileSD:=
:92::Storage.fileSD::(validateFileSystemFeatures) Underlying file system do=
esn't supportdirect IO<o:p></o:p></p <p
class=3D"MsoNormal">Thread-1736::ERROR::2014-11-17 15:50:32,109::task::8=
66::Storage.TaskManager.Task::(_setError) Task=3D`54f143dd-2229-4bc1-b639-9=
017ea3ecdd0`::Unexpected error<o:p></o:p></p <p
class=3D"MsoNormal">Traceback (most recent call
last):<o:p></o:p></p <p
class=3D"MsoNormal"> File
"/usr/share/vdsm/storage/task.py&qu=
ot;, line 873, in _run<o:p></o:p></p <p
class=3D"MsoNormal"> return fn(*args,
**kargs)<o:p></o=
:p></p <p
class=3D"MsoNormal"> File
"/usr/share/vdsm/logUtils.py",=
line 45, in wrapper<o:p></o:p></p <p
class=3D"MsoNormal"> res =3D f(*args,
**kwargs)<o:p></=
o:p></p <p
class=3D"MsoNormal"> File
"/usr/share/vdsm/storage/hsm.py&quo=
t;, line 2683, in createStorageDomain<o:p></o:p></p <p
class=3D"MsoNormal">
domVersion)<o:p></o:p></p <p
class=3D"MsoNormal"> File
"/usr/share/vdsm/storage/localFsSD.=
py", line 84, in create<o:p></o:p></p <p
class=3D"MsoNormal">
cls._preCreateValidation(sdUUID, =
mntPoint, remotePath, version)<o:p></o:p></p <p
class=3D"MsoNormal"> File
"/usr/share/vdsm/storage/localFsSD.=
py", line 51, in _preCreateValidation<o:p></o:p></p <p
class=3D"MsoNormal">
fileSD.validateFileSystemFeatures=
(sdUUID, domPath)<o:p></o:p></p <p
class=3D"MsoNormal"> File
"/usr/share/vdsm/storage/fileSD.py&=
quot;, line 94, in validateFileSystemFeatures<o:p></o:p></p <p
class=3D"MsoNormal"> raise
se.StorageDomainTargetUnsup=
ported()<o:p></o:p></p <p
class=3D"MsoNormal">StorageDomainTargetUnsupported: Storage Domain targe=
t is unsupported: ()<o:p></o:p></p <p
class=3D"MsoNormal">Thread-1736::DEBUG::2014-11-17 15:50:32,110::task::8=
85::Storage.TaskManager.Task::(_run) Task=3D`54f143dd-2229-4bc1-b639-9017ea=
3ecdd0`::Task._run: 54f143dd-2229-4bc1-b639-9017ea3ecdd0 (4, u'c46aa2c4-c40=
5-45eb-b7fd-71f627d1c546', u'test',
u'/vmstore/isos', 1, u'3') {} failed - stopping
task<o:p></o:p></p <p
class=3D"MsoNormal">Thread-1736::DEBUG::2014-11-17 15:50:32,110::task::1=
217::Storage.TaskManager.Task::(stop) Task=3D`54f143dd-2229-4bc1-b639-9017e=
a3ecdd0`::stopping in state preparing (force False)<o:p></o:p></p <p
class=3D"MsoNormal">Thread-1736::DEBUG::2014-11-17 15:50:32,111::task::9=
93::Storage.TaskManager.Task::(_decref) Task=3D`54f143dd-2229-4bc1-b639-901=
7ea3ecdd0`::ref 1 aborting True<o:p></o:p></p <p
class=3D"MsoNormal">Thread-1736::INFO::2014-11-17 15:50:32,111::task::11=
71::Storage.TaskManager.Task::(prepare) Task=3D`54f143dd-2229-4bc1-b639-901=
7ea3ecdd0`::aborting: Task is aborted: 'Storage Domain target is unsupporte=
d' - code 399<o:p></o:p></p <p
class=3D"MsoNormal">Thread-1736::DEBUG::2014-11-17 15:50:32,111::task::1=
176::Storage.TaskManager.Task::(prepare) Task=3D`54f143dd-2229-4bc1-b639-90=
17ea3ecdd0`::Prepare: aborted: Storage Domain target is unsupported<o:p></o=
:p></p <p
class=3D"MsoNormal">Thread-1736::DEBUG::2014-11-17 15:50:32,111::task::9=
93::Storage.TaskManager.Task::(_decref) Task=3D`54f143dd-2229-4bc1-b639-901=
7ea3ecdd0`::ref 0 aborting True<o:p></o:p></p <p
class=3D"MsoNormal">Thread-1736::DEBUG::2014-11-17 15:50:32,112::task::9=
28::Storage.TaskManager.Task::(_doAbort) Task=3D`54f143dd-2229-4bc1-b639-90=
17ea3ecdd0`::Task._doAbort: force False<o:p></o:p></p <p
class=3D"MsoNormal">Thread-1736::DEBUG::2014-11-17 15:50:32,112::resourc=
eManager::977::Storage.ResourceManager.Owner::(cancelAll) Owner.cancelAll r=
equests {}<o:p></o:p></p <p
class=3D"MsoNormal">Thread-1736::DEBUG::2014-11-17 15:50:32,112::task::5=
95::Storage.TaskManager.Task::(_updateState) Task=3D`54f143dd-2229-4bc1-b63=
9-9017ea3ecdd0`::moving from state preparing -> state aborting<o:p></o:p=
></p <p
class=3D"MsoNormal">Thread-1736::DEBUG::2014-11-17 15:50:32,113::task::5=
50::Storage.TaskManager.Task::(__state_aborting) Task=3D`54f143dd-2229-4bc1=
-b639-9017ea3ecdd0`::_aborting: recover policy none<o:p></o:p></p <p
class=3D"MsoNormal">Thread-1736::DEBUG::2014-11-17 15:50:32,113::task::5=
95::Storage.TaskManager.Task::(_updateState) Task=3D`54f143dd-2229-4bc1-b63=
9-9017ea3ecdd0`::moving from state aborting -> state
failed<o:p></o:p></=
p <p
class=3D"MsoNormal">Thread-1736::DEBUG::2014-11-17 15:50:32,113::resourc=
eManager::940::Storage.ResourceManager.Owner::(releaseAll) Owner.releaseAll=
requests {} resources {}<o:p></o:p></p <p
class=3D"MsoNormal">Thread-1736::DEBUG::2014-11-17 15:50:32,113::resourc=
eManager::977::Storage.ResourceManager.Owner::(cancelAll) Owner.cancelAll r=
equests {}<o:p></o:p></p <p
class=3D"MsoNormal">Thread-1736::ERROR::2014-11-17 15:50:32,114::dispatc=
her::76::Storage.Dispatcher::(wrapper) {'status': {'message': 'Storage
Doma=
in target is unsupported: ()', 'code': 399}}<o:p></o:p></p <p
class=3D"MsoNormal">Thread-1736::DEBUG::2014-11-17 15:50:32,114::stompRe=
actor::163::yajsonrpc.StompServer::(send) Sending
response<o:p></o:p></p <p
class=3D"MsoNormal">JsonRpc (StompReactor)::DEBUG::2014-11-17 15:50:32,2=
12::stompReactor::98::Broker.StompAdapter::(handle_frame) Handling message =
<StompFrame command=3D'SEND'><o:p></o:p></p <p
class=3D"MsoNormal">Thread-1744::DEBUG::2014-11-17 15:50:32,215::__init_=
_::467::jsonrpc.JsonRpcServer::(_serveRequest) Calling 'StoragePool.disconn=
ectStorageServer' in bridge with {u'connectionParams': [{u'id':
u'deb5a580-=
6994-4db9-9899-cd05c39c2efa', u'connection':
u'/vmstore/isos', u'iqn': u'', u'user': u'',
u'tpgt': u'1', u'password': u=
'', u'port': u''}], u'storagepoolID':
u'00000000-0000-0000-0000-00000000000=
0', u'domainType': 4}<o:p></o:p></p <p
class=3D"MsoNormal">JsonRpcServer::DEBUG::2014-11-17 15:50:32,215::__ini=
t__::504::jsonrpc.JsonRpcServer::(serve_requests) Waiting for request<o:p><=
/o:p></p <p
class=3D"MsoNormal">Thread-1744::DEBUG::2014-11-17 15:50:32,220::task::5=
95::Storage.TaskManager.Task::(_updateState) Task=3D`11fde6d7-ad39-4b40-bb4=
0-00816fe2c131`::moving from state init -> state
preparing<o:p></o:p></p=
<p
class=3D"MsoNormal">Thread-1744::INFO::2014-11-17 15:50:32,221::logUtils=
::44::dispatcher::(wrapper) Run and protect: disconnectStorageServer(domTyp=
e=3D4, spUUID=3Du'00000000-0000-0000-0000-000000000000', conList=3D[{u'conn=
ection': u'/vmstore/isos', u'iqn': u'',
u'user': u'', u'tpgt': u'1', u'password':
'******', u'id': u'deb5a580-6994=
-4db9-9899-cd05c39c2efa', u'port': u''}],
options=3DNone)<o:p></o:p></p <p
class=3D"MsoNormal">Thread-1744::DEBUG::2014-11-17 15:50:32,221::misc::7=
41::Storage.SamplingMethod::(__call__) Trying to enter sampling method (sto=
rage.sdc.refreshStorage)<o:p></o:p></p <p
class=3D"MsoNormal">Thread-1744::DEBUG::2014-11-17 15:50:32,222::misc::7=
43::Storage.SamplingMethod::(__call__) Got in to sampling method<o:p></o:p>=
</p <p
class=3D"MsoNormal">Thread-1744::DEBUG::2014-11-17 15:50:32,222::misc::7=
41::Storage.SamplingMethod::(__call__) Trying to enter sampling method (sto=
rage.iscsi.rescan)<o:p></o:p></p <p
class=3D"MsoNormal">Thread-1744::DEBUG::2014-11-17 15:50:32,222::misc::7=
43::Storage.SamplingMethod::(__call__) Got in to sampling method<o:p></o:p>=
</p <p
class=3D"MsoNormal">Thread-1744::DEBUG::2014-11-17 15:50:32,222::iscsi::=
403::Storage.ISCSI::(rescan) Performing SCSI scan, this will take up to 30 =
seconds<o:p></o:p></p <p
class=3D"MsoNormal">Thread-1744::DEBUG::2014-11-17 15:50:32,223::iscsiad=
m::92::Storage.Misc.excCmd::(_runCmd) /usr/bin/sudo -n /sbin/iscsiadm -m se=
ssion -R (cwd None)<o:p></o:p></p <p
class=3D"MsoNormal">Thread-1744::DEBUG::2014-11-17 15:50:32,276::misc::7=
51::Storage.SamplingMethod::(__call__) Returning last
result<o:p></o:p></p <p
class=3D"MsoNormal">Thread-1744::DEBUG::2014-11-17 15:50:32,280::multipa=
th::110::Storage.Misc.excCmd::(rescan) /usr/bin/sudo -n /sbin/multipath (cw=
d None)<o:p></o:p></p <p
class=3D"MsoNormal">Thread-1744::DEBUG::2014-11-17 15:50:32,409::multipa=
th::110::Storage.Misc.excCmd::(rescan) SUCCESS: <err> =3D '';
<rc&=
gt; =3D 0<o:p></o:p></p <p
class=3D"MsoNormal">Thread-1744::DEBUG::2014-11-17 15:50:32,410::lvm::48=
9::Storage.OperationMutex::(_invalidateAllPvs) Operation 'lvm invalidate op=
eration' got the operation mutex<o:p></o:p></p <p
class=3D"MsoNormal">Thread-1744::DEBUG::2014-11-17 15:50:32,410::lvm::49=
1::Storage.OperationMutex::(_invalidateAllPvs) Operation 'lvm invalidate op=
eration' released the operation mutex<o:p></o:p></p <p
class=3D"MsoNormal">Thread-1744::DEBUG::2014-11-17 15:50:32,411::lvm::50=
0::Storage.OperationMutex::(_invalidateAllVgs) Operation 'lvm invalidate op=
eration' got the operation mutex<o:p></o:p></p <p
class=3D"MsoNormal">Thread-1744::DEBUG::2014-11-17 15:50:32,411::lvm::50=
2::Storage.OperationMutex::(_invalidateAllVgs) Operation 'lvm invalidate op=
eration' released the operation mutex<o:p></o:p></p <p
class=3D"MsoNormal">Thread-1744::DEBUG::2014-11-17 15:50:32,412::lvm::52=
0::Storage.OperationMutex::(_invalidateAllLvs) Operation 'lvm invalidate op=
eration' got the operation mutex<o:p></o:p></p <p
class=3D"MsoNormal">Thread-1744::DEBUG::2014-11-17 15:50:32,412::lvm::52=
2::Storage.OperationMutex::(_invalidateAllLvs) Operation 'lvm invalidate op=
eration' released the operation mutex<o:p></o:p></p <p
class=3D"MsoNormal">Thread-1744::DEBUG::2014-11-17 15:50:32,412::misc::7=
51::Storage.SamplingMethod::(__call__) Returning last
result<o:p></o:p></p <p
class=3D"MsoNormal">Thread-1744::INFO::2014-11-17 15:50:32,413::logUtils=
::47::dispatcher::(wrapper) Run and protect: disconnectStorageServer, Retur=
n response: {'statuslist': [{'status': 0, 'id':
u'deb5a580-6994-4db9-9899-c=
d05c39c2efa'}]}<o:p></o:p></p <p
class=3D"MsoNormal">Thread-1744::DEBUG::2014-11-17 15:50:32,413::task::1=
191::Storage.TaskManager.Task::(prepare) Task=3D`11fde6d7-ad39-4b40-bb40-00=
816fe2c131`::finished: {'statuslist': [{'status': 0, 'id':
u'deb5a580-6994-=
4db9-9899-cd05c39c2efa'}]}<o:p></o:p></p <p
class=3D"MsoNormal">Thread-1744::DEBUG::2014-11-17 15:50:32,414::task::5=
95::Storage.TaskManager.Task::(_updateState) Task=3D`11fde6d7-ad39-4b40-bb4=
0-00816fe2c131`::moving from state preparing -> state finished<o:p></o:p=
></p <p
class=3D"MsoNormal">Thread-1744::DEBUG::2014-11-17 15:50:32,414::resourc=
eManager::940::Storage.ResourceManager.Owner::(releaseAll) Owner.releaseAll=
requests {} resources {}<o:p></o:p></p <p
class=3D"MsoNormal">Thread-1744::DEBUG::2014-11-17 15:50:32,414::resourc=
eManager::977::Storage.ResourceManager.Owner::(cancelAll) Owner.cancelAll r=
equests {}<o:p></o:p></p <p
class=3D"MsoNormal">Thread-1744::DEBUG::2014-11-17 15:50:32,414::task::9=
93::Storage.TaskManager.Task::(_decref) Task=3D`11fde6d7-ad39-4b40-bb40-008=
16fe2c131`::ref 0 aborting False<o:p></o:p></p <p
class=3D"MsoNormal">Thread-1744::DEBUG::2014-11-17 15:50:32,415::__init_=
_::498::jsonrpc.JsonRpcServer::(_serveRequest) Return 'StoragePool.disconne=
ctStorageServer' in bridge with [{'status': 0, 'id':
u'deb5a580-6994-4db9-9=
899-cd05c39c2efa'}]<o:p></o:p></p <p
class=3D"MsoNormal">Thread-1744::DEBUG::2014-11-17 15:50:32,416::stompRe=
actor::163::yajsonrpc.StompServer::(send) Sending
response<o:p></o:p></p <p
class=3D"MsoNormal"><o:p> </o:p></p </div
</body </html
--_000_EE5DB373DE433D4A87A60EE0284C92F926421EFDRISXMBX03adsuhn_--