[ovirt-users] 回复： create new domain failed
dhy336 at sina.com
dhy336 at sina.com
Wed Mar 14 08:31:31 UTC 2018
----- 原始邮件 -----
发件人：<dhy336 at sina.com>
收件人："users" <users at ovirt.org>
主题：[ovirt-users] create new domain failed
日期：2018年03月14日 16点01分
Hi I find a issue, I create new domain failed
error info is "Error while executing action New NFS Storage Domain: Storage domain already exists".
vdsm debug info :
2018-03-14 15:39:46,163+0800 INFO (jsonrpc/1) [DynamicBridge] ----------------duhy test--------------------- (Bridge:189)2018-03-14 15:39:46,163+0800 INFO (jsonrpc/1) [DynamicBridge] cmd = StoragePool_connectStorageServer (Bridge:190)2018-03-14 15:39:46,164+0800 INFO (jsonrpc/1) [vdsm.api] START connectStorageServer(domType=1, spUUID=u'00000000-0000-0000-0000-000000000000', conList=[{u'id': u'a16bcfa2-fd7c-49f9-84b2-3a905b4f4135', u'connection': u'192.168.122.134:/home/exports/vmstorage', u'iqn': u'', u'user': u'', u'tpgt': u'1', u'protocol_version': u'auto', u'password': '********', u'port': u''}], options=None) from=::ffff:192.168.122.134,45460, flow_id=61f0b27f, task_id=ecd65a2e-3687-49b6-a4d6-11b9fce74775 (api:46)2018-03-14 15:39:46,190+0800 INFO (jsonrpc/1) [vdsm.api] FINISH connectStorageServer return={'statuslist': [{'status': 0, 'id': u'a16bcfa2-fd7c-49f9-84b2-3a905b4f4135'}]} from=::ffff:192.168.122.134,45460, flow_id=61f0b27f, task_id=ecd65a2e-3687-49b6-a4d6-11b9fce74775 (api:52)2018-03-14 15:39:46,191+0800 INFO (jsonrpc/1) [jsonrpc.JsonRpcServer] RPC call StoragePool.connectStorageServer succeeded in 0.03 seconds (__init__:630)2018-03-14 15:39:46,204+0800 INFO (jsonrpc/3) [DynamicBridge] ----------------duhy test--------------------- (Bridge:189)2018-03-14 15:39:46,205+0800 INFO (jsonrpc/3) [DynamicBridge] cmd = StorageDomain_create (Bridge:190)2018-03-14 15:39:46,205+0800 INFO (jsonrpc/3) [vdsm.api] START createStorageDomain(storageType=1, sdUUID=u'80586557-820f-4e12-9763-11beed4259c8', domainName=u'vmstorage', typeSpecificArg=u'192.168.122.134:/home/exports/vmstorage', domClass=1, domVersion=u'4', options=None) from=::ffff:192.168.122.134,45460, flow_id=61f0b27f, task_id=4b38bebc-5e9f-49cf-aafd-0de9155abe06 (api:46)2018-03-14 15:39:46,206+0800 INFO (jsonrpc/3) [storage.StorageDomainCache] -------------------duhy test-------------- (sdc:106)2018-03-14 15:39:46,206+0800 INFO (jsonrpc/3) [storage.StorageDomainCache] /rhev/data-center (sdc:107)2018-03-14 15:39:46,206+0800 INFO (jsonrpc/3) [storage.StorageDomainCache] ------------------------------------------- (sdc:119)2018-03-14 15:39:46,206+0800 INFO (jsonrpc/3) [storage.StorageDomainCache] {} (sdc:120)2018-03-14 15:39:46,207+0800 INFO (jsonrpc/3) [storage.StorageDomainCache] sdUUID not in __inProgress (sdc:128)2018-03-14 15:39:46,207+0800 INFO (jsonrpc/3) [storage.StorageDomainCache] set([]) (sdc:129)2018-03-14 15:39:46,668+0800 INFO (itmap/0) [IOProcessClient] Starting client ioprocess-1 (__init__:330)2018-03-14 15:39:46,693+0800 INFO (ioprocess/31894) [IOProcess] Starting ioprocess (__init__:452)2018-03-14 15:39:46,704+0800 INFO (jsonrpc/3) [storage.StorageDomain] sdUUID=80586557-820f-4e12-9763-11beed4259c8 domainName=vmstorage remotePath=192.168.122.134:/home/exports/vmstorage domClass=1 (nfsSD:70)2018-03-14 15:39:46,745+0800 INFO (jsonrpc/3) [IOProcessClient] Starting client ioprocess-2 (__init__:330)2018-03-14 15:39:46,780+0800 INFO (ioprocess/31903) [IOProcess] Starting ioprocess (__init__:452)2018-03-14 15:39:47,108+0800 INFO (jsonrpc/3) [storage.xlease] Formatting index for lockspace u'80586557-820f-4e12-9763-11beed4259c8' (version=1) (xlease:641)2018-03-14 15:39:47,489+0800 INFO (jsonrpc/3) [storage.HSM] knownSDs: {80586557-820f-4e12-9763-11beed4259c8: vdsm.storage.nfsSD.findDomain} (hsm:2581)2018-03-14 15:39:47,490+0800 INFO (jsonrpc/3) [storage.StorageDomainCache] --------------manuallyAddDomain------------ (sdc:204)2018-03-14 15:39:47,490+0800 INFO (jsonrpc/3) [storage.StorageDomainCache] <vdsm.storage.nfsSD.NfsStorageDomain object at 0x3adcc90> (sdc:205)2018-03-14 15:39:47,490+0800 INFO (jsonrpc/3) [storage.StorageDomainCache] 80586557-820f-4e12-9763-11beed4259c8 (sdc:206)2018-03-14 15:39:47,490+0800 INFO (jsonrpc/3) [vdsm.api] FINISH createStorageDomain return=None from=::ffff:192.168.122.134,45460, flow_id=61f0b27f, task_id=4b38bebc-5e9f-49cf-aafd-0de9155abe06 (api:52)2018-03-14 15:39:47,491+0800 INFO (jsonrpc/3) [jsonrpc.JsonRpcServer] RPC call StorageDomain.create succeeded in 1.29 seconds (__init__:630)2018-03-14 15:39:47,508+0800 INFO (jsonrpc/4) [DynamicBridge] ----------------duhy test--------------------- (Bridge:189)2018-03-14 15:39:47,509+0800 INFO (jsonrpc/4) [DynamicBridge] cmd = StorageDomain_create (Bridge:190)2018-03-14 15:39:47,510+0800 INFO (jsonrpc/4) [vdsm.api] START createStorageDomain(storageType=1, sdUUID=u'80586557-820f-4e12-9763-11beed4259c8', domainName=u'vmstorage', typeSpecificArg=u'192.168.122.134:/home/exports/vmstorage', domClass=1, domVersion=u'4', options=None) from=::ffff:192.168.122.134,45460, flow_id=61f0b27f, task_id=e419bf88-6f4e-43ff-a6ae-0153b3a98c65 (api:46)2018-03-14 15:39:47,510+0800 INFO (jsonrpc/4) [storage.StorageDomainCache] -------------------duhy test-------------- (sdc:106)2018-03-14 15:39:47,511+0800 INFO (jsonrpc/4) [storage.StorageDomainCache] /rhev/data-center (sdc:107)2018-03-14 15:39:47,511+0800 INFO (jsonrpc/4) [storage.StorageDomainCache] ------------------------------------------- (sdc:119)2018-03-14 15:39:47,511+0800 INFO (jsonrpc/4) [storage.StorageDomainCache] {u'80586557-820f-4e12-9763-11beed4259c8': <vdsm.storage.nfsSD.NfsStorageDomain object at 0x3adcc90>} (sdc:120)2018-03-14 15:39:47,511+0800 INFO (jsonrpc/4) [storage.StorageDomainCache] domain is not None (sdc:123)2018-03-14 15:39:47,511+0800 INFO (jsonrpc/4) [storage.StorageDomainCache] <vdsm.storage.nfsSD.NfsStorageDomain object at 0x3adcc90> (sdc:124)2018-03-14 15:39:47,512+0800 INFO (jsonrpc/4) [vdsm.api] FINISH createStorageDomain error=Storage domain already exists: (u'80586557-820f-4e12-9763-11beed4259c8',) from=::ffff:192.168.122.134,45460, flow_id=61f0b27f, task_id=e419bf88-6f4e-43ff-a6ae-0153b3a98c65 (api:50)2018-03-14 15:39:47,512+0800 ERROR (jsonrpc/4) [storage.TaskManager.Task] (Task='e419bf88-6f4e-43ff-a6ae-0153b3a98c65') Unexpected error (task:875)Traceback (most recent call last): File "/usr/lib/python2.7/site-packages/vdsm/storage/task.py", line 882, in _run return fn(*args, **kargs) File "<string>", line 2, in createStorageDomain File "/usr/lib/python2.7/site-packages/vdsm/common/api.py", line 48, in method ret = func(*args, **kwargs) File "/usr/lib/python2.7/site-packages/vdsm/storage/hsm.py", line 2550, in createStorageDomain self.validateNonDomain(sdUUID) File "/usr/lib/python2.7/site-packages/vdsm/storage/hsm.py", line 322, in validateNonDomain raise se.StorageDomainAlreadyExists(sdUUID)StorageDomainAlreadyExists: Storage domain already exists: (u'80586557-820f-4e12-9763-11beed4259c8',)2018-03-14 15:39:47,515+0800 INFO (jsonrpc/4) [storage.TaskManager.Task] (Task='e419bf88-6f4e-43ff-a6ae-0153b3a98c65') aborting: Task is aborted: "Storage domain already exists: (u'80586557-820f-4e12-9763-11beed4259c8',)" - code 365 (task:1181)2018-03-14 15:39:47,516+0800 ERROR (jsonrpc/4) [storage.Dispatcher] FINISH createStorageDomain error=Storage domain already exists: (u'80586557-820f-4e12-9763-11beed4259c8',) (dispatcher:82)2018-03-14 15:39:47,516+0800 INFO (jsonrpc/4) [jsonrpc.JsonRpcServer] RPC call StorageDomain.create failed (error 365) in 0.01 seconds (__init__:630)2018-03-14 15:39:47,973+0800 INFO (jsonrpc/5) [DynamicBridge] ----------------duhy test--------------------- (Bridge:189)2018-03-14 15:39:47,973+0800 INFO (jsonrpc/5) [DynamicBridge] cmd = StoragePool_disconnectStorageServer (Bridge:190)2018-03-14 15:39:47,974+0800 INFO (jsonrpc/5) [vdsm.api] START disconnectStorageServer(domType=1, spUUID=u'00000000-0000-0000-0000-000000000000', conList=[{u'id': u'a16bcfa2-fd7c-49f9-84b2-3a905b4f4135', u'connection': u'192.168.122.134:/home/exports/vmstorage', u'iqn': u'', u'user': u'', u'tpgt': u'1', u'protocol_version': u'auto', u'password': '********', u'port': u''}], options=None) from=::ffff:192.168.122.134,45460, flow_id=fc7e65db-8a83-46c5-b38a-12c3837bc453, task_id=250f443a-40e9-4a91-83b4-799b515da2cd (api:46)2018-03-14 15:39:47,975+0800 INFO (jsonrpc/5) [storage.Mount] unmounting /rhev/data-center/mnt/192.168.122.134:_home_exports_vmstorage (mount:213)2018-03-14 15:39:48,061+0800 INFO (jsonrpc/6) [DynamicBridge] ----------------duhy test--------------------- (Bridge:189)2018-03-14 15:39:48,062+0800 INFO (jsonrpc/6) [DynamicBridge] cmd = Host_getAllVmStats (Bridge:190)2018-03-14 15:39:48,062+0800 INFO (jsonrpc/6) [api.host] START getAllVmStats() from=::1,33990 (api:46)2018-03-14 15:39:48,272+0800 INFO (jsonrpc/6) [root] /usr/libexec/vdsm/hooks/after_get_all_vm_stats/10_fakevmstats: rc=0 err= (hooks:109)2018-03-14 15:39:48,273+0800 INFO (jsonrpc/6) [api.host] FINISH getAllVmStats return={'status': {'message': 'Done', 'code': 0}, 'statsList': (suppressed)} from=::1,33990 (api:52)2018-03-14 15:39:48,274+0800 INFO (jsonrpc/6) [jsonrpc.JsonRpcServer] RPC call Host.getAllVmStats succeeded in 0.21 seconds (__init__:630)2018-03-14 15:39:48,412+0800 INFO (jsonrpc/5) [vdsm.api] FINISH disconnectStorageServer return={'statuslist': [{'status': 0, 'id': u'a16bcfa2-fd7c-49f9-84b2-3a905b4f4135'}]} from=::ffff:192.168.122.134,45460, flow_id=fc7e65db-8a83-46c5-b38a-12c3837bc453, task_id=250f443a-40e9-4a91-83b4-799b515da2cd (api:52)2018-03-14 15:39:48,413+0800 INFO (jsonrpc/5) [jsonrpc.JsonRpcServer] RPC call StoragePool.disconnectStorageServer succeeded in 0.44 seconds (__init__:630)2018-03-14 15:39:48,431+0800 INFO (jsonrpc/7) [DynamicBridge] ----------------duhy test--------------------- (Bridge:189)2018-03-14 15:39:48,432+0800 INFO (jsonrpc/7) [DynamicBridge] cmd = StoragePool_disconnectStorageServer (Bridge:190)2018-03-14 15:39:48,432+0800 INFO (jsonrpc/7) [vdsm.api] START disconnectStorageServer(domType=1, spUUID=u'00000000-0000-0000-0000-000000000000', conList=[{u'id': u'a16bcfa2-fd7c-49f9-84b2-3a905b4f4135', u'connection': u'192.168.122.134:/home/exports/vmstorage', u'iqn': u'', u'user': u'', u'tpgt': u'1', u'protocol_version': u'auto', u'password': '********', u'port': u''}], options=None) from=::ffff:192.168.122.134,45460, flow_id=fc7e65db-8a83-46c5-b38a-12c3837bc453, task_id=5014c75e-67b6-4401-a55b-480d5373f112 (api:46)2018-03-14 15:39:48,433+0800 INFO (jsonrpc/7) [storage.Mount] unmounting /rhev/data-center/mnt/192.168.122.134:_home_exports_vmstorage (mount:213)2018-03-14 15:39:48,491+0800 ERROR (jsonrpc/7) [storage.HSM] Could not disconnect from storageServer (hsm:2466)Traceback (most recent call last): File "/usr/lib/python2.7/site-packages/vdsm/storage/hsm.py", line 2462, in disconnectStorageServer conObj.disconnect() File "/usr/lib/python2.7/site-packages/vdsm/storage/storageServer.py", line 387, in disconnect return self._mountCon.disconnect() File "/usr/lib/python2.7/site-packages/vdsm/storage/storageServer.py", line 185, in disconnect self._mount.umount(True, True) File "/usr/lib/python2.7/site-packages/vdsm/storage/mount.py", line 216, in umount timeout=timeout) File "/usr/lib/python2.7/site-packages/vdsm/supervdsm.py", line 53, in __call__ return callMethod() File "/usr/lib/python2.7/site-packages/vdsm/supervdsm.py", line 51, in <lambda> **kwargs) File "<string>", line 2, in umount File "/usr/lib64/python2.7/multiprocessing/managers.py", line 773, in _callmethod raise convert_to_error(kind, result)MountError: (32, ';umount: /rhev/data-center/mnt/192.168.122.134:_home_exports_vmstorage: mountpoint not found\n')2018-03-14 15:39:48,768+0800 INFO (jsonrpc/7) [vdsm.api] FINISH disconnectStorageServer return={'statuslist': [{'status': 477, 'id': u'a16bcfa2-fd7c-49f9-84b2-3a905b4f4135'}]} from=::ffff:192.168.122.134,45460, flow_id=fc7e65db-8a83-46c5-b38a-12c3837bc453, task_id=5014c75e-67b6-4401-a55b-480d5373f112 (api:52)2018-03-14 15:39:48,769+0800 INFO (jsonrpc/7) [jsonrpc.JsonRpcServer] RPC call StoragePool.disconnectStorageServer succeeded in 0.34 seconds (__init__:630)2018-03-14 15:39:50,305+0800 INFO (jsonrpc/0) [DynamicBridge] ----------------duhy test--------------------- (Bridge:189)2018-03-14 15:39:50,306+0800 INFO (jsonrpc/0) [DynamicBridge] cmd = Host_getAllVmStats (Bridge:190)2018-03-14 15:39:50,307+0800 INFO (jsonrpc/0) [api.host] START getAllVmStats() from=::ffff:192.168.122.134,45460 (api:46)2018-03-14 15:39:50,465+0800 INFO (jsonrpc/0) [root] /usr/libexec/vdsm/hooks/after_get_all_vm_stats/10_fakevmstats: rc=0 err= (hooks:109)2018-03-14 15:39:50,467+0800 INFO (jsonrpc/0) [api.host] FINISH getAllVmStats return={'status': {'message': 'Done', 'code': 0}, 'statsList': (suppressed)} from=::ffff:192.168.122.134,45460 (api:52)2018-03-14 15:39:50,468+0800 INFO (jsonrpc/0) [jsonrpc.JsonRpcServer] RPC call Host.getAllVmStats succeeded in 0.16 seconds (__init__:630)
I find twice StorageDomain_create ,first is sucessed , cache has this uuid, so second is failed ,because cache has this uuid. finally "Error while executing action New NFS Storage Domain: Storage domain already exists". _______________________________________________
Users mailing list
Users at ovirt.org
http://lists.ovirt.org/mailman/listinfo/users
I debug engine, engine send twice StorageDomain_create command to vdsm, I want to know why to send twice StorageDomain_create command, Is this a bug or my enviroment is not work.
2018-03-14 04:23:01,604-04 INFO [org.ovirt.engine.core.bll.profiles.AddDiskProfileCommand] (default task-55) [4eb2816d] Running command: AddDiskProfileCommand internal: true. Entities affected : ID: 82483a27-d046-4082-9371-3c159c957191 Type: StorageAction group CREATE_STORAGE_DISK_PROFILE with role type ADMIN2018-03-14 04:23:01,633-04 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (default task-55) [4eb2816d] EVENT_ID: USER_ADDED_DISK_PROFILE(10,120), Disk Profile vmstorage was successfully added (User: admin at internal-authz).2018-03-14 04:23:01,642-04 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStorageServerVDSCommand] (default task-55) [4eb2816d] START, ConnectStorageServerVDSCommand(HostName = 192.168.122.54, StorageServerConnectionManagementVDSParameters:{hostId='72b8ea98-563b-4b8b-b48a-69571b17ff56', storagePoolId='00000000-0000-0000-0000-000000000000', storageType='NFS', connectionList='[StorageServerConnections:{id='ecce7868-d8fd-4871-8a22-c5e6d1102c86', connection='192.168.122.134:/home/exports/vmstorage', iqn='null', vfsType='null', mountOptions='null', nfsVersion='AUTO', nfsRetrans='null', nfsTimeo='null', iface='null', netIfaceName='null'}]'}), log id: 58ccf2018-03-14 04:23:01,690-04 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStorageServerVDSCommand] (default task-55) [4eb2816d] FINISH, ConnectStorageServerVDSCommand, return: {ecce7868-d8fd-4871-8a22-c5e6d1102c86=0}, log id: 58ccf2018-03-14 04:23:01,695-04 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStorageServerVDSCommand] (default task-55) [4eb2816d] START, ConnectStorageServerVDSCommand(HostName = 192.168.122.54, StorageServerConnectionManagementVDSParameters:{hostId='72b8ea98-563b-4b8b-b48a-69571b17ff56', storagePoolId='00000000-0000-0000-0000-000000000000', storageType='NFS', connectionList='[StorageServerConnections:{id='ecce7868-d8fd-4871-8a22-c5e6d1102c86', connection='192.168.122.134:/home/exports/vmstorage', iqn='null', vfsType='null', mountOptions='null', nfsVersion='AUTO', nfsRetrans='null', nfsTimeo='null', iface='null', netIfaceName='null'}]'}), log id: 7da62ba62018-03-14 04:23:01,737-04 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStorageServerVDSCommand] (default task-55) [4eb2816d] FINISH, ConnectStorageServerVDSCommand, return: {ecce7868-d8fd-4871-8a22-c5e6d1102c86=0}, log id: 7da62ba62018-03-14 04:23:01,744-04 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateStorageDomainVDSCommand] (default task-55) [4eb2816d] START, CreateStorageDomainVDSCommand(HostName = 192.168.122.54, CreateStorageDomainVDSCommandParameters:{hostId='72b8ea98-563b-4b8b-b48a-69571b17ff56', storageDomain='StorageDomainStatic:{name='vmstorage', id='82483a27-d046-4082-9371-3c159c957191'}', args='192.168.122.134:/home/exports/vmstorage'}), log id: 95323782018-03-14 04:23:01,744-04 INFO [org.ovirt.engine.core.vdsbroker.jsonrpc.JsonRpcVdsServer] (default task-55) [4eb2816d] ---------------------duhy test--------------------------2018-03-14 04:23:01,744-04 INFO [org.ovirt.engine.core.vdsbroker.jsonrpc.JsonRpcVdsServer] (default task-55) [4eb2816d] StorageDomain.createstoragedomainID=82483a27-d046-4082-9371-3c159c957191domainType=1domainClass=1storageFormatType42018-03-14 04:23:02,907-04 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateStorageDomainVDSCommand] (default task-55) [4eb2816d] FINISH, CreateStorageDomainVDSCommand, log id: 95323782018-03-14 04:23:02,917-04 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateStorageDomainVDSCommand] (default task-55) [4eb2816d] START, CreateStorageDomainVDSCommand(HostName = 192.168.122.54, CreateStorageDomainVDSCommandParameters:{hostId='72b8ea98-563b-4b8b-b48a-69571b17ff56', storageDomain='StorageDomainStatic:{name='vmstorage', id='82483a27-d046-4082-9371-3c159c957191'}', args='192.168.122.134:/home/exports/vmstorage'}), log id: 705b95752018-03-14 04:23:02,917-04 INFO [org.ovirt.engine.core.vdsbroker.jsonrpc.JsonRpcVdsServer] (default task-55) [4eb2816d] ---------------------duhy test--------------------------2018-03-14 04:23:02,917-04 INFO [org.ovirt.engine.core.vdsbroker.jsonrpc.JsonRpcVdsServer] (default task-55) [4eb2816d] StorageDomain.createstoragedomainID=82483a27-d046-4082-9371-3c159c957191domainType=1domainClass=1storageFormatType42018-03-14 04:23:02,944-04 ERROR [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (default task-55) [4eb2816d] EVENT_ID: VDS_BROKER_COMMAND_FAILURE(10,802), VDSM 192.168.122.54 command CreateStorageDomainVDS failed: Storage domain already exists: (u'82483a27-d046-4082-9371-3c159c957191',)2018-03-14 04:23:02,944-04 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateStorageDomainVDSCommand] (default task-55) [4eb2816d] Command 'org.ovirt.engine.core.vdsbroker.vdsbroker.CreateStorageDomainVDSCommand' return value 'StatusOnlyReturn [status=Status [code=365, message=Storage domain already exists: (u'82483a27-d046-4082-9371-3c159c957191',)]]'
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://lists.ovirt.org/pipermail/users/attachments/20180314/ea2e0835/attachment.html>
-------------- next part --------------
A non-text attachment was scrubbed...
Name: ??1.png
Type: image/png
Size: 235094 bytes
Desc: not available
URL: <http://lists.ovirt.org/pipermail/users/attachments/20180314/ea2e0835/attachment.png>
-------------- next part --------------
A non-text attachment was scrubbed...
Name: ??1.png
Type: image/png
Size: 62970 bytes
Desc: not available
URL: <http://lists.ovirt.org/pipermail/users/attachments/20180314/ea2e0835/attachment-0001.png>
More information about the Users
mailing list