Hi,
With a fresh engine (new path and new db) and a fresh f24 4.1 host, I'm
unable to add a VM either via the UI or REST API. Both hang, and I see no
helpful logs. Engine log just says it's running the command, and then
nothing. I don't see anything in vdsm.log that suggests the command came
through, so I think it's a problem in engine. Logs attached.
Anyone else seeing this?
Greg
--
GREG SHEREMETA
SENIOR SOFTWARE ENGINEER - TEAM LEAD - RHV UX
Red Hat
<https://www.redhat.com/>
[email protected] IRC: gshereme
<https://red.ht/sig>
2017-09-11 10:01:15,566-04 INFO [org.ovirt.engine.core.bll.AddVmCommand] (default task-45) [cc52fb64-146f-4332-b633-e0d7498810a1] Lock Acquired to object 'EngineLock:{exclusiveLocks='[vm1=VM_NAME]', sharedLocks='[00000000-0000-0000-0000-000000000000=TEMPLATE]'}'
2017-09-11 10:01:15,667-04 INFO [org.ovirt.engine.core.bll.network.macpool.MacPoolUsingRanges] (default task-45) [] Initializing MacPoolUsingRanges:{id='58ca604b-017d-0374-0220-00000000014e'}
2017-09-11 10:01:15,668-04 INFO [org.ovirt.engine.core.bll.network.macpool.MacPoolUsingRanges] (default task-45) [] Finished initializing MacPoolUsingRanges:{id='58ca604b-017d-0374-0220-00000000014e'}. Available MACs in pool: 1024
2017-09-11 10:01:15,668-04 INFO [org.ovirt.engine.core.bll.network.macpool.MacPoolPerCluster] (default task-45) [] Successfully initialized
2017-09-11 10:01:15,733-04 INFO [org.ovirt.engine.core.bll.AddVmCommand] (default task-45) [] Running command: AddVmCommand internal: false. Entities affected : ID: 972ae666-fa02-4261-8598-84952633d857 Type: ClusterAction group CREATE_VM with role type USER, ID: 00000000-0000-0000-0000-000000000000 Type: VmTemplateAction group CREATE_VM with role type USER
2017-09-11 10:00:00,689-0400 INFO (jsonrpc/5) [jsonrpc.JsonRpcServer] RPC call Host.getAllVmStats succeeded in 0.00 seconds (__init__:539)
2017-09-11 10:00:00,817-0400 INFO (jsonrpc/7) [vdsm.api] START getTaskStatus(taskID=u'f4693807-8ce9-4936-973e-e1149e523341', spUUID=None, options=None) from=::ffff:192.168.1.21,40048, task_id=af5aaa82-d6cd-4c32-bb66-12fcd66fc768 (api:46)
2017-09-11 10:00:00,817-0400 INFO (jsonrpc/7) [vdsm.api] FINISH getTaskStatus return={'taskStatus': {'code': 0, 'message': 'running job 1 of 1', 'taskState': 'running', 'taskResult': '', 'taskID': 'f4693807-8ce9-4936-973e-e1149e523341'}} from=::ffff:192.168.1.21,40048, task_id=af5aaa82-d6cd-4c32-bb66-12fcd66fc768 (api:52)
2017-09-11 10:00:00,817-0400 INFO (jsonrpc/7) [jsonrpc.JsonRpcServer] RPC call Task.getStatus succeeded in 0.01 seconds (__init__:539)
2017-09-11 10:00:02,331-0400 INFO (tasks/0) [storage.SANLock] Host id for domain 4cf0bbe1-19aa-4617-8e96-3d624fc871a1 successfully acquired (id=1, async=False) (clusterlock:311)
2017-09-11 10:00:02,332-0400 INFO (tasks/0) [storage.SANLock] Acquiring Lease(name='SDM', path=u'/rhev/data-center/mnt/192.168.1.10:_mnt_nas2_ovirt_ovirt-export-data-1/4cf0bbe1-19aa-4617-8e96-3d624fc871a1/dom_md/leases', offset=1048576) for host id 1 (clusterlock:381)
2017-09-11 10:00:02,463-0400 INFO (tasks/0) [storage.SANLock] Successfully acquired Lease(name='SDM', path=u'/rhev/data-center/mnt/192.168.1.10:_mnt_nas2_ovirt_ovirt-export-data-1/4cf0bbe1-19aa-4617-8e96-3d624fc871a1/dom_md/leases', offset=1048576) for host id 1 (clusterlock:419)
2017-09-11 10:00:02,465-0400 INFO (tasks/0) [IOProcessClient] Closing client ioprocess-1 (__init__:598)
2017-09-11 10:00:02,466-0400 INFO (tasks/0) [IOProcessClient] Closing client ioprocess-0 (__init__:598)
2017-09-11 10:00:02,467-0400 INFO (monitor/4cf0bbe) [storage.Monitor] Host id for domain 4cf0bbe1-19aa-4617-8e96-3d624fc871a1 successfully acquired (id: 1) (monitor:449)
2017-09-11 10:00:02,538-0400 INFO (tasks/0) [storage.ThreadPool.WorkerThread] FINISH task f4693807-8ce9-4936-973e-e1149e523341 (threadPool:210)
2017-09-11 10:00:02,825-0400 INFO (jsonrpc/6) [vdsm.api] START getTaskStatus(taskID=u'f4693807-8ce9-4936-973e-e1149e523341', spUUID=None, options=None) from=::ffff:192.168.1.21,40048, task_id=acb21a48-f29f-4183-88b9-6a860c547710 (api:46)
2017-09-11 10:00:02,826-0400 INFO (jsonrpc/6) [vdsm.api] FINISH getTaskStatus return={'taskStatus': {'code': 0, 'message': '1 jobs completed successfully', 'taskState': 'finished', 'taskResult': 'success', 'taskID': 'f4693807-8ce9-4936-973e-e1149e523341'}} from=::ffff:192.168.1.21,40048, task_id=acb21a48-f29f-4183-88b9-6a860c547710 (api:52)
2017-09-11 10:00:02,826-0400 INFO (jsonrpc/6) [jsonrpc.JsonRpcServer] RPC call Task.getStatus succeeded in 0.00 seconds (__init__:539)
2017-09-11 10:00:03,834-0400 INFO (jsonrpc/1) [vdsm.api] START getSpmStatus(spUUID=u'3fb4ebd6-e75c-4f33-8360-60aa3ee06b1c', options=None) from=::ffff:192.168.1.21,40048, task_id=8fcd2204-19c0-46e1-98b3-7e2157fdaf4a (api:46)
2017-09-11 10:00:03,838-0400 INFO (jsonrpc/1) [vdsm.api] FINISH getSpmStatus return={'spm_st': {'spmId': 1, 'spmStatus': 'SPM', 'spmLver': 4L}} from=::ffff:192.168.1.21,40048, task_id=8fcd2204-19c0-46e1-98b3-7e2157fdaf4a (api:52)
2017-09-11 10:00:03,838-0400 INFO (jsonrpc/1) [jsonrpc.JsonRpcServer] RPC call StoragePool.getSpmStatus succeeded in 0.01 seconds (__init__:539)
2017-09-11 10:00:04,847-0400 INFO (jsonrpc/2) [vdsm.api] START clearTask(taskID=u'f4693807-8ce9-4936-973e-e1149e523341', spUUID=None, options=None) from=::ffff:192.168.1.21,40048, task_id=d020b7b7-8bc1-4712-bd09-46c1f74d2e25 (api:46)
2017-09-11 10:00:04,847-0400 INFO (jsonrpc/2) [vdsm.api] FINISH clearTask return=None from=::ffff:192.168.1.21,40048, task_id=d020b7b7-8bc1-4712-bd09-46c1f74d2e25 (api:52)
2017-09-11 10:00:04,847-0400 INFO (jsonrpc/2) [jsonrpc.JsonRpcServer] RPC call Task.clear succeeded in 0.01 seconds (__init__:539)
2017-09-11 10:00:05,877-0400 INFO (Reactor thread) [ProtocolDetector.AcceptorImpl] Accepted connection from ::ffff:192.168.1.21:40050 (protocoldetector:72)
2017-09-11 10:00:05,945-0400 INFO (Reactor thread) [ProtocolDetector.Detector] Detected protocol stomp from ::ffff:192.168.1.21:40050 (protocoldetector:127)
2017-09-11 10:00:05,946-0400 INFO (Reactor thread) [Broker.StompAdapter] Processing CONNECT request (stompreactor:103)
2017-09-11 10:00:05,947-0400 INFO (JsonRpc (StompReactor)) [Broker.StompAdapter] Subscribe command received (stompreactor:130)
2017-09-11 10:00:06,952-0400 INFO (jsonrpc/0) [vdsm.api] START getStoragePoolInfo(spUUID=u'3fb4ebd6-e75c-4f33-8360-60aa3ee06b1c', options=None) from=::ffff:192.168.1.21,40050, task_id=b3c8dcb7-d6ab-4677-b569-d6d3e656cc9c (api:46)
2017-09-11 10:00:06,961-0400 INFO (jsonrpc/0) [vdsm.api] FINISH getStoragePoolInfo return={'info': {'name': 'No Description', 'isoprefix': '', 'pool_status': 'connected', 'lver': 4L, 'domains': u'4cf0bbe1-19aa-4617-8e96-3d624fc871a1:Active', 'master_uuid': u'4cf0bbe1-19aa-4617-8e96-3d624fc871a1', 'version': '4', 'spm_id': 1, 'type': 'NFS', 'master_ver': 1}, 'dominfo': {u'4cf0bbe1-19aa-4617-8e96-3d624fc871a1': {'status': u'Active', 'diskfree': '677538430976', 'isoprefix': '', 'alerts': [], 'disktotal': '984374837248', 'version': 4}}} from=::ffff:192.168.1.21,40050, task_id=b3c8dcb7-d6ab-4677-b569-d6d3e656cc9c (api:52)
2017-09-11 10:00:06,962-0400 INFO (jsonrpc/0) [jsonrpc.JsonRpcServer] RPC call StoragePool.getInfo succeeded in 0.01 seconds (__init__:539)
2017-09-11 10:00:07,022-0400 INFO (periodic/0) [vdsm.api] START repoStats(options=None) from=internal, task_id=6c6e3712-7ef9-45ca-90eb-fe4a025265f1 (api:46)
2017-09-11 10:00:07,023-0400 INFO (periodic/0) [vdsm.api] FINISH repoStats return={u'4cf0bbe1-19aa-4617-8e96-3d624fc871a1': {'code': 0, 'actual': True, 'version': 4, 'acquired': True, 'delay': '0.000604832', 'lastCheck': '4.6', 'valid': True}} from=internal, task_id=6c6e3712-7ef9-45ca-90eb-fe4a025265f1 (api:52)
2017-09-11 10:00:07,998-0400 INFO (jsonrpc/4) [vdsm.api] START getAllTasksInfo(spUUID=None, options=None) from=::ffff:192.168.1.21,40048, task_id=1663ffbb-db83-4b55-8673-1142b8c6d204 (api:46)
2017-09-11 10:00:07,999-0400 INFO (jsonrpc/4) [vdsm.api] FINISH getAllTasksInfo return={'allTasksInfo': {}} from=::ffff:192.168.1.21,40048, task_id=1663ffbb-db83-4b55-8673-1142b8c6d204 (api:52)
2017-09-11 10:00:07,999-0400 INFO (jsonrpc/4) [jsonrpc.JsonRpcServer] RPC call Host.getAllTasksInfo succeeded in 0.00 seconds (__init__:539)
2017-09-11 10:00:11,992-0400 INFO (jsonrpc/5) [jsonrpc.JsonRpcServer] RPC call Host.getAllVmStats succeeded in 0.00 seconds (__init__:539)
2017-09-11 10:00:13,859-0400 INFO (jsonrpc/7) [vdsm.api] START repoStats(options=None) from=::ffff:192.168.1.21,40048, task_id=dd275461-559d-489f-8b3f-3aa6aa1fde31 (api:46)
2017-09-11 10:00:13,859-0400 INFO (jsonrpc/7) [vdsm.api] FINISH repoStats return={u'4cf0bbe1-19aa-4617-8e96-3d624fc871a1': {'code': 0, 'actual': True, 'version': 4, 'acquired': True, 'delay': '0.000364911', 'lastCheck': '1.4', 'valid': True}} from=::ffff:192.168.1.21,40048, task_id=dd275461-559d-489f-8b3f-3aa6aa1fde31 (api:52)
2017-09-11 10:00:13,863-0400 INFO (jsonrpc/7) [jsonrpc.JsonRpcServer] RPC call Host.getStats succeeded in 0.00 seconds (__init__:539)
2017-09-11 10:00:15,822-0400 INFO (jsonrpc/3) [jsonrpc.JsonRpcServer] RPC call Host.getAllVmStats succeeded in 0.00 seconds (__init__:539)
2017-09-11 10:00:17,998-0400 INFO (jsonrpc/6) [vdsm.api] START getSpmStatus(spUUID=u'3fb4ebd6-e75c-4f33-8360-60aa3ee06b1c', options=None) from=::ffff:192.168.1.21,40048, task_id=bd04f499-95dc-4545-b6a1-7863b0ad5b7b (api:46)
2017-09-11 10:00:18,002-0400 INFO (jsonrpc/6) [vdsm.api] FINISH getSpmStatus return={'spm_st': {'spmId': 1, 'spmStatus': 'SPM', 'spmLver': 4L}} from=::ffff:192.168.1.21,40048, task_id=bd04f499-95dc-4545-b6a1-7863b0ad5b7b (api:52)
2017-09-11 10:00:18,003-0400 INFO (jsonrpc/6) [jsonrpc.JsonRpcServer] RPC call StoragePool.getSpmStatus succeeded in 0.00 seconds (__init__:539)
2017-09-11 10:00:19,027-0400 INFO (jsonrpc/1) [vdsm.api] START getStoragePoolInfo(spUUID=u'3fb4ebd6-e75c-4f33-8360-60aa3ee06b1c', options=None) from=::ffff:192.168.1.21,40050, task_id=1aaaed26-c85d-445e-898e-1750d5ca666c (api:46)
2017-09-11 10:00:19,037-0400 INFO (jsonrpc/1) [vdsm.api] FINISH getStoragePoolInfo return={'info': {'name': 'No Description', 'isoprefix': '', 'pool_status': 'connected', 'lver': 4L, 'domains': u'4cf0bbe1-19aa-4617-8e96-3d624fc871a1:Active', 'master_uuid': u'4cf0bbe1-19aa-4617-8e96-3d624fc871a1', 'version': '4', 'spm_id': 1, 'type': 'NFS', 'master_ver': 1}, 'dominfo': {u'4cf0bbe1-19aa-4617-8e96-3d624fc871a1': {'status': u'Active', 'diskfree': '677536333824', 'isoprefix': '', 'alerts': [], 'disktotal': '984374837248', 'version': 4}}} from=::ffff:192.168.1.21,40050, task_id=1aaaed26-c85d-445e-898e-1750d5ca666c (api:52)
2017-09-11 10:00:19,038-0400 INFO (jsonrpc/1) [jsonrpc.JsonRpcServer] RPC call StoragePool.getInfo succeeded in 0.02 seconds (__init__:539)
2017-09-11 10:00:22,037-0400 INFO (periodic/2) [vdsm.api] START repoStats(options=None) from=internal, task_id=c2873f68-b138-46d6-a6a5-f831661b86f1 (api:46)
2017-09-11 10:00:22,038-0400 INFO (periodic/2) [vdsm.api] FINISH repoStats return={u'4cf0bbe1-19aa-4617-8e96-3d624fc871a1': {'code': 0, 'actual': True, 'version': 4, 'acquired': True, 'delay': '0.00105408', 'lastCheck': '1.1', 'valid': True}} from=internal, task_id=c2873f68-b138-46d6-a6a5-f831661b86f1 (api:52)
2017-09-11 10:00:27,007-0400 INFO (jsonrpc/2) [jsonrpc.JsonRpcServer] RPC call Host.getAllVmStats succeeded in 0.01 seconds (__init__:539)
2017-09-11 10:00:29,927-0400 INFO (jsonrpc/0) [vdsm.api] START repoStats(options=None) from=::ffff:192.168.1.21,40048, task_id=caaad909-119d-4bac-a4b8-214dffdde667 (api:46)
2017-09-11 10:00:29,927-0400 INFO (jsonrpc/0) [vdsm.api] FINISH repoStats return={u'4cf0bbe1-19aa-4617-8e96-3d624fc871a1': {'code': 0, 'actual': True, 'version': 4, 'acquired': True, 'delay': '0.00105408', 'lastCheck': '7.4', 'valid': True}} from=::ffff:192.168.1.21,40048, task_id=caaad909-119d-4bac-a4b8-214dffdde667 (api:52)
2017-09-11 10:00:29,931-0400 INFO (jsonrpc/0) [jsonrpc.JsonRpcServer] RPC call Host.getStats succeeded in 0.01 seconds (__init__:539)
2017-09-11 10:00:30,059-0400 INFO (jsonrpc/4) [vdsm.api] START getSpmStatus(spUUID=u'3fb4ebd6-e75c-4f33-8360-60aa3ee06b1c', options=None) from=::ffff:192.168.1.21,40048, task_id=7b42116d-63dc-46f8-a91c-63a9586623d4 (api:46)
2017-09-11 10:00:30,062-0400 INFO (jsonrpc/4) [vdsm.api] FINISH getSpmStatus return={'spm_st': {'spmId': 1, 'spmStatus': 'SPM', 'spmLver': 4L}} from=::ffff:192.168.1.21,40048, task_id=7b42116d-63dc-46f8-a91c-63a9586623d4 (api:52)
2017-09-11 10:00:30,062-0400 INFO (jsonrpc/4) [jsonrpc.JsonRpcServer] RPC call StoragePool.getSpmStatus succeeded in 0.00 seconds (__init__:539)
2017-09-11 10:00:31,098-0400 INFO (jsonrpc/5) [vdsm.api] START getStoragePoolInfo(spUUID=u'3fb4ebd6-e75c-4f33-8360-60aa3ee06b1c', options=None) from=::ffff:192.168.1.21,40050, task_id=655bc7b9-09a5-4d16-8db6-d2dff17a42e2 (api:46)
2017-09-11 10:00:31,106-0400 INFO (jsonrpc/5) [vdsm.api] FINISH getStoragePoolInfo return={'info': {'name': 'No Description', 'isoprefix': '', 'pool_status': 'connected', 'lver': 4L, 'domains': u'4cf0bbe1-19aa-4617-8e96-3d624fc871a1:Active', 'master_uuid': u'4cf0bbe1-19aa-4617-8e96-3d624fc871a1', 'version': '4', 'spm_id': 1, 'type': 'NFS', 'master_ver': 1}, 'dominfo': {u'4cf0bbe1-19aa-4617-8e96-3d624fc871a1': {'status': u'Active', 'diskfree': '677536333824', 'isoprefix': '', 'alerts': [], 'disktotal': '984374837248', 'version': 4}}} from=::ffff:192.168.1.21,40050, task_id=655bc7b9-09a5-4d16-8db6-d2dff17a42e2 (api:52)
2017-09-11 10:00:31,107-0400 INFO (jsonrpc/5) [jsonrpc.JsonRpcServer] RPC call StoragePool.getInfo succeeded in 0.00 seconds (__init__:539)
2017-09-11 10:00:31,832-0400 INFO (jsonrpc/7) [jsonrpc.JsonRpcServer] RPC call Host.getAllVmStats succeeded in 0.00 seconds (__init__:539)
2017-09-11 10:00:37,046-0400 INFO (periodic/1) [vdsm.api] START repoStats(options=None) from=internal, task_id=ee113678-9a5c-4468-9c74-4ad324dd5654 (api:46)
2017-09-11 10:00:37,047-0400 INFO (periodic/1) [vdsm.api] FINISH repoStats return={u'4cf0bbe1-19aa-4617-8e96-3d624fc871a1': {'code': 0, 'actual': True, 'version': 4, 'acquired': True, 'delay': '0.000496535', 'lastCheck': '4.6', 'valid': True}} from=internal, task_id=ee113678-9a5c-4468-9c74-4ad324dd5654 (api:52)
2017-09-11 10:00:41,847-0400 INFO (jsonrpc/3) [vdsm.api] START getSpmStatus(spUUID=u'3fb4ebd6-e75c-4f33-8360-60aa3ee06b1c', options=None) from=::ffff:192.168.1.21,40048, task_id=e9a0e65c-9b46-4488-8fae-076d47bce4b8 (api:46)
2017-09-11 10:00:41,850-0400 INFO (jsonrpc/3) [vdsm.api] FINISH getSpmStatus return={'spm_st': {'spmId': 1, 'spmStatus': 'SPM', 'spmLver': 4L}} from=::ffff:192.168.1.21,40048, task_id=e9a0e65c-9b46-4488-8fae-076d47bce4b8 (api:52)
2017-09-11 10:00:41,850-0400 INFO (jsonrpc/3) [jsonrpc.JsonRpcServer] RPC call StoragePool.getSpmStatus succeeded in 0.01 seconds (__init__:539)
2017-09-11 10:00:42,028-0400 INFO (jsonrpc/6) [jsonrpc.JsonRpcServer] RPC call Host.getAllVmStats succeeded in 0.00 seconds (__init__:539)
2017-09-11 10:00:42,864-0400 INFO (jsonrpc/1) [vdsm.api] START getStoragePoolInfo(spUUID=u'3fb4ebd6-e75c-4f33-8360-60aa3ee06b1c', options=None) from=::ffff:192.168.1.21,40050, task_id=b7ad6f33-3230-4734-ac0f-4b648d4c0de3 (api:46)
2017-09-11 10:00:42,869-0400 INFO (jsonrpc/1) [vdsm.api] FINISH getStoragePoolInfo return={'info': {'name': 'No Description', 'isoprefix': '', 'pool_status': 'connected', 'lver': 4L, 'domains': u'4cf0bbe1-19aa-4617-8e96-3d624fc871a1:Active', 'master_uuid': u'4cf0bbe1-19aa-4617-8e96-3d624fc871a1', 'version': '4', 'spm_id': 1, 'type': 'NFS', 'master_ver': 1}, 'dominfo': {u'4cf0bbe1-19aa-4617-8e96-3d624fc871a1': {'status': u'Active', 'diskfree': '677536333824', 'isoprefix': '', 'alerts': [], 'disktotal': '984374837248', 'version': 4}}} from=::ffff:192.168.1.21,40050, task_id=b7ad6f33-3230-4734-ac0f-4b648d4c0de3 (api:52)
2017-09-11 10:00:42,870-0400 INFO (jsonrpc/1) [jsonrpc.JsonRpcServer] RPC call StoragePool.getInfo succeeded in 0.01 seconds (__init__:539)
2017-09-11 10:00:45,121-0400 INFO (jsonrpc/2) [vdsm.api] START repoStats(options=None) from=::ffff:192.168.1.21,40048, task_id=fe170005-aaf2-45ce-866c-f7b0933b8930 (api:46)
2017-09-11 10:00:45,122-0400 INFO (jsonrpc/2) [vdsm.api] FINISH repoStats return={u'4cf0bbe1-19aa-4617-8e96-3d624fc871a1': {'code': 0, 'actual': True, 'version': 4, 'acquired': True, 'delay': '0.00057454', 'lastCheck': '2.6', 'valid': True}} from=::ffff:192.168.1.21,40048, task_id=fe170005-aaf2-45ce-866c-f7b0933b8930 (api:52)
2017-09-11 10:00:45,133-0400 INFO (jsonrpc/2) [jsonrpc.JsonRpcServer] RPC call Host.getStats succeeded in 0.01 seconds (__init__:539)
2017-09-11 10:00:47,841-0400 INFO (jsonrpc/0) [jsonrpc.JsonRpcServer] RPC call Host.getAllVmStats succeeded in 0.00 seconds (__init__:539)
2017-09-11 10:00:52,054-0400 INFO (periodic/2) [vdsm.api] START repoStats(options=None) from=internal, task_id=5ee42872-7285-4428-9324-ebd38dcf90b3 (api:46)
2017-09-11 10:00:52,054-0400 INFO (periodic/2) [vdsm.api] FINISH repoStats return={u'4cf0bbe1-19aa-4617-8e96-3d624fc871a1': {'code': 0, 'actual': True, 'version': 4, 'acquired': True, 'delay': '0.000397918', 'lastCheck': '1.1', 'valid': True}} from=internal, task_id=5ee42872-7285-4428-9324-ebd38dcf90b3 (api:52)
2017-09-11 10:00:53,890-0400 INFO (jsonrpc/4) [vdsm.api] START getSpmStatus(spUUID=u'3fb4ebd6-e75c-4f33-8360-60aa3ee06b1c', options=None) from=::ffff:192.168.1.21,40048, task_id=85fc26f3-4d98-4ef3-934d-9aeb0645eee3 (api:46)
2017-09-11 10:00:53,894-0400 INFO (jsonrpc/4) [vdsm.api] FINISH getSpmStatus return={'spm_st': {'spmId': 1, 'spmStatus': 'SPM', 'spmLver': 4L}} from=::ffff:192.168.1.21,40048, task_id=85fc26f3-4d98-4ef3-934d-9aeb0645eee3 (api:52)
2017-09-11 10:00:53,894-0400 INFO (jsonrpc/4) [jsonrpc.JsonRpcServer] RPC call StoragePool.getSpmStatus succeeded in 0.00 seconds (__init__:539)
2017-09-11 10:00:54,908-0400 INFO (jsonrpc/5) [vdsm.api] START getStoragePoolInfo(spUUID=u'3fb4ebd6-e75c-4f33-8360-60aa3ee06b1c', options=None) from=::ffff:192.168.1.21,40050, task_id=39e0e18e-31f4-4055-b983-5b355156e4df (api:46)
2017-09-11 10:00:54,922-0400 INFO (jsonrpc/5) [vdsm.api] FINISH getStoragePoolInfo return={'info': {'name': 'No Description', 'isoprefix': '', 'pool_status': 'connected', 'lver': 4L, 'domains': u'4cf0bbe1-19aa-4617-8e96-3d624fc871a1:Active', 'master_uuid': u'4cf0bbe1-19aa-4617-8e96-3d624fc871a1', 'version': '4', 'spm_id': 1, 'type': 'NFS', 'master_ver': 1}, 'dominfo': {u'4cf0bbe1-19aa-4617-8e96-3d624fc871a1': {'status': u'Active', 'diskfree': '677536333824', 'isoprefix': '', 'alerts': [], 'disktotal': '984374837248', 'version': 4}}} from=::ffff:192.168.1.21,40050, task_id=39e0e18e-31f4-4055-b983-5b355156e4df (api:52)
2017-09-11 10:00:54,924-0400 INFO (jsonrpc/5) [jsonrpc.JsonRpcServer] RPC call StoragePool.getInfo succeeded in 0.02 seconds (__init__:539)
2017-09-11 10:00:57,048-0400 INFO (jsonrpc/7) [jsonrpc.JsonRpcServer] RPC call Host.getAllVmStats succeeded in 0.00 seconds (__init__:539)
2017-09-11 10:01:01,200-0400 INFO (jsonrpc/3) [vdsm.api] START repoStats(options=None) from=::ffff:192.168.1.21,40048, task_id=9e7cb06a-51a8-40e1-ade8-a4008b8b2f51 (api:46)
2017-09-11 10:01:01,200-0400 INFO (jsonrpc/3) [vdsm.api] FINISH repoStats return={u'4cf0bbe1-19aa-4617-8e96-3d624fc871a1': {'code': 0, 'actual': True, 'version': 4, 'acquired': True, 'delay': '0.000615487', 'lastCheck': '0.3', 'valid': True}} from=::ffff:192.168.1.21,40048, task_id=9e7cb06a-51a8-40e1-ade8-a4008b8b2f51 (api:52)
2017-09-11 10:01:01,204-0400 INFO (jsonrpc/3) [jsonrpc.JsonRpcServer] RPC call Host.getStats succeeded in 0.00 seconds (__init__:539)
2017-09-11 10:01:03,851-0400 INFO (jsonrpc/6) [jsonrpc.JsonRpcServer] RPC call Host.getAllVmStats succeeded in 0.00 seconds (__init__:539)
2017-09-11 10:01:05,947-0400 INFO (jsonrpc/1) [vdsm.api] START getSpmStatus(spUUID=u'3fb4ebd6-e75c-4f33-8360-60aa3ee06b1c', options=None) from=::ffff:192.168.1.21,40048, task_id=0e65a64c-0601-4f32-a346-d1406ffeeb7f (api:46)
2017-09-11 10:01:05,954-0400 INFO (jsonrpc/1) [vdsm.api] FINISH getSpmStatus return={'spm_st': {'spmId': 1, 'spmStatus': 'SPM', 'spmLver': 4L}} from=::ffff:192.168.1.21,40048, task_id=0e65a64c-0601-4f32-a346-d1406ffeeb7f (api:52)
2017-09-11 10:01:05,955-0400 INFO (jsonrpc/1) [jsonrpc.JsonRpcServer] RPC call StoragePool.getSpmStatus succeeded in 0.01 seconds (__init__:539)
2017-09-11 10:01:06,971-0400 INFO (jsonrpc/2) [vdsm.api] START getStoragePoolInfo(spUUID=u'3fb4ebd6-e75c-4f33-8360-60aa3ee06b1c', options=None) from=::ffff:192.168.1.21,40050, task_id=ba78bcc3-feee-4f40-bb62-451d85ec06b7 (api:46)
2017-09-11 10:01:06,983-0400 INFO (jsonrpc/2) [vdsm.api] FINISH getStoragePoolInfo return={'info': {'name': 'No Description', 'isoprefix': '', 'pool_status': 'connected', 'lver': 4L, 'domains': u'4cf0bbe1-19aa-4617-8e96-3d624fc871a1:Active', 'master_uuid': u'4cf0bbe1-19aa-4617-8e96-3d624fc871a1', 'version': '4', 'spm_id': 1, 'type': 'NFS', 'master_ver': 1}, 'dominfo': {u'4cf0bbe1-19aa-4617-8e96-3d624fc871a1': {'status': u'Active', 'diskfree': '677536333824', 'isoprefix': '', 'alerts': [], 'disktotal': '984374837248', 'version': 4}}} from=::ffff:192.168.1.21,40050, task_id=ba78bcc3-feee-4f40-bb62-451d85ec06b7 (api:52)
2017-09-11 10:01:06,984-0400 INFO (jsonrpc/2) [jsonrpc.JsonRpcServer] RPC call StoragePool.getInfo succeeded in 0.01 seconds (__init__:539)
2017-09-11 10:01:07,056-0400 INFO (periodic/3) [vdsm.api] START repoStats(options=None) from=internal, task_id=8e45540d-0e84-4de5-b998-378fd0d10053 (api:46)
2017-09-11 10:01:07,056-0400 INFO (periodic/3) [vdsm.api] FINISH repoStats return={u'4cf0bbe1-19aa-4617-8e96-3d624fc871a1': {'code': 0, 'actual': True, 'version': 4, 'acquired': True, 'delay': '0.000615487', 'lastCheck': '4.6', 'valid': True}} from=internal, task_id=8e45540d-0e84-4de5-b998-378fd0d10053 (api:52)
2017-09-11 10:01:12,067-0400 INFO (jsonrpc/0) [jsonrpc.JsonRpcServer] RPC call Host.getAllVmStats succeeded in 0.00 seconds (__init__:539)
2017-09-11 10:01:18,009-0400 INFO (jsonrpc/4) [vdsm.api] START getSpmStatus(spUUID=u'3fb4ebd6-e75c-4f33-8360-60aa3ee06b1c', options=None) from=::ffff:192.168.1.21,40048, task_id=d5617e2b-6333-4923-8d89-dd4c73f4d6ce (api:46)
2017-09-11 10:01:18,016-0400 INFO (jsonrpc/4) [vdsm.api] FINISH getSpmStatus return={'spm_st': {'spmId': 1, 'spmStatus': 'SPM', 'spmLver': 4L}} from=::ffff:192.168.1.21,40048, task_id=d5617e2b-6333-4923-8d89-dd4c73f4d6ce (api:52)
2017-09-11 10:01:18,017-0400 INFO (jsonrpc/4) [jsonrpc.JsonRpcServer] RPC call StoragePool.getSpmStatus succeeded in 0.00 seconds (__init__:539)
2017-09-11 10:01:19,031-0400 INFO (jsonrpc/5) [vdsm.api] START getStoragePoolInfo(spUUID=u'3fb4ebd6-e75c-4f33-8360-60aa3ee06b1c', options=None) from=::ffff:192.168.1.21,40050, task_id=c41390c6-3835-4539-872d-c7afbb44603e (api:46)
2017-09-11 10:01:19,040-0400 INFO (jsonrpc/5) [vdsm.api] FINISH getStoragePoolInfo return={'info': {'name': 'No Description', 'isoprefix': '', 'pool_status': 'connected', 'lver': 4L, 'domains': u'4cf0bbe1-19aa-4617-8e96-3d624fc871a1:Active', 'master_uuid': u'4cf0bbe1-19aa-4617-8e96-3d624fc871a1', 'version': '4', 'spm_id': 1, 'type': 'NFS', 'master_ver': 1}, 'dominfo': {u'4cf0bbe1-19aa-4617-8e96-3d624fc871a1': {'status': u'Active', 'diskfree': '677536333824', 'isoprefix': '', 'alerts': [], 'disktotal': '984374837248', 'version': 4}}} from=::ffff:192.168.1.21,40050, task_id=c41390c6-3835-4539-872d-c7afbb44603e (api:52)
2017-09-11 10:01:19,041-0400 INFO (jsonrpc/5) [jsonrpc.JsonRpcServer] RPC call StoragePool.getInfo succeeded in 0.01 seconds (__init__:539)
2017-09-11 10:01:19,863-0400 INFO (jsonrpc/7) [jsonrpc.JsonRpcServer] RPC call Host.getAllVmStats succeeded in 0.00 seconds (__init__:539)
2017-09-11 10:01:22,065-0400 INFO (periodic/1) [vdsm.api] START repoStats(options=None) from=internal, task_id=92af9266-9b2c-4101-a6a1-288bd086829f (api:46)
2017-09-11 10:01:22,066-0400 INFO (periodic/1) [vdsm.api] FINISH repoStats return={u'4cf0bbe1-19aa-4617-8e96-3d624fc871a1': {'code': 0, 'actual': True, 'version': 4, 'acquired': True, 'delay': '0.000774601', 'lastCheck': '1.1', 'valid': True}} from=internal, task_id=92af9266-9b2c-4101-a6a1-288bd086829f (api:52)
2017-09-11 10:01:27,086-0400 INFO (jsonrpc/3) [jsonrpc.JsonRpcServer] RPC call Host.getAllVmStats succeeded in 0.00 seconds (__init__:539)
2017-09-11 10:01:29,877-0400 INFO (jsonrpc/6) [vdsm.api] START getSpmStatus(spUUID=u'3fb4ebd6-e75c-4f33-8360-60aa3ee06b1c', options=None) from=::ffff:192.168.1.21,40048, task_id=a00239e1-720d-463f-9b31-c5fc63d8dd01 (api:46)
2017-09-11 10:01:29,880-0400 INFO (jsonrpc/6) [vdsm.api] FINISH getSpmStatus return={'spm_st': {'spmId': 1, 'spmStatus': 'SPM', 'spmLver': 4L}} from=::ffff:192.168.1.21,40048, task_id=a00239e1-720d-463f-9b31-c5fc63d8dd01 (api:52)
2017-09-11 10:01:29,880-0400 INFO (jsonrpc/6) [jsonrpc.JsonRpcServer] RPC call StoragePool.getSpmStatus succeeded in 0.01 seconds (__init__:539)
2017-09-11 10:01:30,930-0400 INFO (jsonrpc/1) [vdsm.api] START getStoragePoolInfo(spUUID=u'3fb4ebd6-e75c-4f33-8360-60aa3ee06b1c', options=None) from=::ffff:192.168.1.21,40050, task_id=7c9ae25d-b86f-434b-a962-f786ade50bb7 (api:46)
2017-09-11 10:01:30,937-0400 INFO (jsonrpc/1) [vdsm.api] FINISH getStoragePoolInfo return={'info': {'name': 'No Description', 'isoprefix': '', 'pool_status': 'connected', 'lver': 4L, 'domains': u'4cf0bbe1-19aa-4617-8e96-3d624fc871a1:Active', 'master_uuid': u'4cf0bbe1-19aa-4617-8e96-3d624fc871a1', 'version': '4', 'spm_id': 1, 'type': 'NFS', 'master_ver': 1}, 'dominfo': {u'4cf0bbe1-19aa-4617-8e96-3d624fc871a1': {'status': u'Active', 'diskfree': '677536333824', 'isoprefix': '', 'alerts': [], 'disktotal': '984374837248', 'version': 4}}} from=::ffff:192.168.1.21,40050, task_id=7c9ae25d-b86f-434b-a962-f786ade50bb7 (api:52)
2017-09-11 10:01:30,938-0400 INFO (jsonrpc/1) [jsonrpc.JsonRpcServer] RPC call StoragePool.getInfo succeeded in 0.01 seconds (__init__:539)
2017-09-11 10:01:35,872-0400 INFO (jsonrpc/2) [jsonrpc.JsonRpcServer] RPC call Host.getAllVmStats succeeded in 0.00 seconds (__init__:539)
2017-09-11 10:01:37,077-0400 INFO (periodic/2) [vdsm.api] START repoStats(options=None) from=internal, task_id=5c0e0bb9-084f-4aff-a958-b98028743d8b (api:46)
2017-09-11 10:01:37,077-0400 INFO (periodic/2) [vdsm.api] FINISH repoStats return={u'4cf0bbe1-19aa-4617-8e96-3d624fc871a1': {'code': 0, 'actual': True, 'version': 4, 'acquired': True, 'delay': '0.0011525', 'lastCheck': '4.6', 'valid': True}} from=internal, task_id=5c0e0bb9-084f-4aff-a958-b98028743d8b (api:52)
2017-09-11 10:01:41,958-0400 INFO (jsonrpc/0) [vdsm.api] START getSpmStatus(spUUID=u'3fb4ebd6-e75c-4f33-8360-60aa3ee06b1c', options=None) from=::ffff:192.168.1.21,40048, task_id=b85f9916-0470-475c-8176-b2ca5301d259 (api:46)
2017-09-11 10:01:41,961-0400 INFO (jsonrpc/0) [vdsm.api] FINISH getSpmStatus return={'spm_st': {'spmId': 1, 'spmStatus': 'SPM', 'spmLver': 4L}} from=::ffff:192.168.1.21,40048, task_id=b85f9916-0470-475c-8176-b2ca5301d259 (api:52)
2017-09-11 10:01:41,962-0400 INFO (jsonrpc/0) [jsonrpc.JsonRpcServer] RPC call StoragePool.getSpmStatus succeeded in 0.01 seconds (__init__:539)
2017-09-11 10:01:42,106-0400 INFO (jsonrpc/4) [jsonrpc.JsonRpcServer] RPC call Host.getAllVmStats succeeded in 0.00 seconds (__init__:539)
2017-09-11 10:01:42,972-0400 INFO (jsonrpc/5) [vdsm.api] START getStoragePoolInfo(spUUID=u'3fb4ebd6-e75c-4f33-8360-60aa3ee06b1c', options=None) from=::ffff:192.168.1.21,40050, task_id=a731ab04-2f9f-4405-ad8e-fd068c0c19de (api:46)
2017-09-11 10:01:42,982-0400 INFO (jsonrpc/5) [vdsm.api] FINISH getStoragePoolInfo return={'info': {'name': 'No Description', 'isoprefix': '', 'pool_status': 'connected', 'lver': 4L, 'domains': u'4cf0bbe1-19aa-4617-8e96-3d624fc871a1:Active', 'master_uuid': u'4cf0bbe1-19aa-4617-8e96-3d624fc871a1', 'version': '4', 'spm_id': 1, 'type': 'NFS', 'master_ver': 1}, 'dominfo': {u'4cf0bbe1-19aa-4617-8e96-3d624fc871a1': {'status': u'Active', 'diskfree': '677536333824', 'isoprefix': '', 'alerts': [], 'disktotal': '984374837248', 'version': 4}}} from=::ffff:192.168.1.21,40050, task_id=a731ab04-2f9f-4405-ad8e-fd068c0c19de (api:52)
2017-09-11 10:01:42,983-0400 INFO (jsonrpc/5) [jsonrpc.JsonRpcServer] RPC call StoragePool.getInfo succeeded in 0.01 seconds (__init__:539)
2017-09-11 10:01:51,881-0400 INFO (jsonrpc/7) [jsonrpc.JsonRpcServer] RPC call Host.getAllVmStats succeeded in 0.00 seconds (__init__:539)
2017-09-11 10:01:52,088-0400 INFO (periodic/1) [vdsm.api] START repoStats(options=None) from=internal, task_id=a8809fb7-b29e-4b9c-9d26-e2c22fcb27b5 (api:46)
2017-09-11 10:01:52,088-0400 INFO (periodic/1) [vdsm.api] FINISH repoStats return={u'4cf0bbe1-19aa-4617-8e96-3d624fc871a1': {'code': 0, 'actual': True, 'version': 4, 'acquired': True, 'delay': '0.000963364', 'lastCheck': '1.2', 'valid': True}} from=internal, task_id=a8809fb7-b29e-4b9c-9d26-e2c22fcb27b5 (api:52)
2017-09-11 10:01:54,001-0400 INFO (jsonrpc/3) [vdsm.api] START getSpmStatus(spUUID=u'3fb4ebd6-e75c-4f33-8360-60aa3ee06b1c', options=None) from=::ffff:192.168.1.21,40048, task_id=4fa1156e-ce67-44ff-9d3f-c952d2743efe (api:46)
2017-09-11 10:01:54,005-0400 INFO (jsonrpc/3) [vdsm.api] FINISH getSpmStatus return={'spm_st': {'spmId': 1, 'spmStatus': 'SPM', 'spmLver': 4L}} from=::ffff:192.168.1.21,40048, task_id=4fa1156e-ce67-44ff-9d3f-c952d2743efe (api:52)
2017-09-11 10:01:54,006-0400 INFO (jsonrpc/3) [jsonrpc.JsonRpcServer] RPC call StoragePool.getSpmStatus succeeded in 0.00 seconds (__init__:539)
2017-09-11 10:01:55,069-0400 INFO (jsonrpc/6) [vdsm.api] START getStoragePoolInfo(spUUID=u'3fb4ebd6-e75c-4f33-8360-60aa3ee06b1c', options=None) from=::ffff:192.168.1.21,40050, task_id=a740f8fc-fab6-4000-823d-7fd56ccf0342 (api:46)
2017-09-11 10:01:55,078-0400 INFO (jsonrpc/6) [vdsm.api] FINISH getStoragePoolInfo return={'info': {'name': 'No Description', 'isoprefix': '', 'pool_status': 'connected', 'lver': 4L, 'domains': u'4cf0bbe1-19aa-4617-8e96-3d624fc871a1:Active', 'master_uuid': u'4cf0bbe1-19aa-4617-8e96-3d624fc871a1', 'version': '4', 'spm_id': 1, 'type': 'NFS', 'master_ver': 1}, 'dominfo': {u'4cf0bbe1-19aa-4617-8e96-3d624fc871a1': {'status': u'Active', 'diskfree': '677536333824', 'isoprefix': '', 'alerts': [], 'disktotal': '984374837248', 'version': 4}}} from=::ffff:192.168.1.21,40050, task_id=a740f8fc-fab6-4000-823d-7fd56ccf0342 (api:52)
2017-09-11 10:01:55,079-0400 INFO (jsonrpc/6) [jsonrpc.JsonRpcServer] RPC call StoragePool.getInfo succeeded in 0.01 seconds (__init__:539)
2017-09-11 10:01:57,117-0400 INFO (jsonrpc/1) [jsonrpc.JsonRpcServer] RPC call Host.getAllVmStats succeeded in 0.00 seconds (__init__:539)
2017-09-11 10:02:06,100-0400 INFO (jsonrpc/2) [vdsm.api] START getSpmStatus(spUUID=u'3fb4ebd6-e75c-4f33-8360-60aa3ee06b1c', options=None) from=::ffff:192.168.1.21,40048, task_id=bb867bc6-a565-483d-95d5-b2bacf0bd74b (api:46)
2017-09-11 10:02:06,103-0400 INFO (jsonrpc/2) [vdsm.api] FINISH getSpmStatus return={'spm_st': {'spmId': 1, 'spmStatus': 'SPM', 'spmLver': 4L}} from=::ffff:192.168.1.21,40048, task_id=bb867bc6-a565-483d-95d5-b2bacf0bd74b (api:52)
2017-09-11 10:02:06,103-0400 INFO (jsonrpc/2) [jsonrpc.JsonRpcServer] RPC call StoragePool.getSpmStatus succeeded in 0.00 seconds (__init__:539)
2017-09-11 10:02:07,095-0400 INFO (periodic/3) [vdsm.api] START repoStats(options=None) from=internal, task_id=b51d624f-400d-4539-8a11-180ef415fcfc (api:46)
2017-09-11 10:02:07,096-0400 INFO (periodic/3) [vdsm.api] FINISH repoStats return={u'4cf0bbe1-19aa-4617-8e96-3d624fc871a1': {'code': 0, 'actual': True, 'version': 4, 'acquired': True, 'delay': '0.00066058', 'lastCheck': '4.6', 'valid': True}} from=internal, task_id=b51d624f-400d-4539-8a11-180ef415fcfc (api:52)
2017-09-11 10:02:07,114-0400 INFO (jsonrpc/0) [vdsm.api] START getStoragePoolInfo(spUUID=u'3fb4ebd6-e75c-4f33-8360-60aa3ee06b1c', options=None) from=::ffff:192.168.1.21,40050, task_id=f4d369d7-9b17-488b-9610-abfbeccb661a (api:46)
2017-09-11 10:02:07,117-0400 INFO (jsonrpc/0) [vdsm.api] FINISH getStoragePoolInfo return={'info': {'name': 'No Description', 'isoprefix': '', 'pool_status': 'connected', 'lver': 4L, 'domains': u'4cf0bbe1-19aa-4617-8e96-3d624fc871a1:Active', 'master_uuid': u'4cf0bbe1-19aa-4617-8e96-3d624fc871a1', 'version': '4', 'spm_id': 1, 'type': 'NFS', 'master_ver': 1}, 'dominfo': {u'4cf0bbe1-19aa-4617-8e96-3d624fc871a1': {'status': u'Active', 'diskfree': '677535285248', 'isoprefix': '', 'alerts': [], 'disktotal': '984374837248', 'version': 4}}} from=::ffff:192.168.1.21,40050, task_id=f4d369d7-9b17-488b-9610-abfbeccb661a (api:52)
2017-09-11 10:02:07,118-0400 INFO (jsonrpc/0) [jsonrpc.JsonRpcServer] RPC call StoragePool.getInfo succeeded in 0.01 seconds (__init__:539)
2017-09-11 10:02:07,890-0400 INFO (jsonrpc/4) [jsonrpc.JsonRpcServer] RPC call Host.getAllVmStats succeeded in 0.00 seconds (__init__:539)
2017-09-11 10:02:12,136-0400 INFO (jsonrpc/5) [jsonrpc.JsonRpcServer] RPC call Host.getAllVmStats succeeded in 0.00 seconds (__init__:539)
2017-09-11 10:02:17,906-0400 INFO (jsonrpc/7) [vdsm.api] START getSpmStatus(spUUID=u'3fb4ebd6-e75c-4f33-8360-60aa3ee06b1c', options=None) from=::ffff:192.168.1.21,40048, task_id=69178aa2-32f0-4dc9-81ed-cef99994c9cc (api:46)
2017-09-11 10:02:17,915-0400 INFO (jsonrpc/7) [vdsm.api] FINISH getSpmStatus return={'spm_st': {'spmId': 1, 'spmStatus': 'SPM', 'spmLver': 4L}} from=::ffff:192.168.1.21,40048, task_id=69178aa2-32f0-4dc9-81ed-cef99994c9cc (api:52)
2017-09-11 10:02:17,916-0400 INFO (jsonrpc/7) [jsonrpc.JsonRpcServer] RPC call StoragePool.getSpmStatus succeeded in 0.01 seconds (__init__:539)
2017-09-11 10:02:18,927-0400 INFO (jsonrpc/3) [vdsm.api] START getStoragePoolInfo(spUUID=u'3fb4ebd6-e75c-4f33-8360-60aa3ee06b1c', options=None) from=::ffff:192.168.1.21,40050, task_id=b5f55d5d-0f32-4528-9106-a4546fb9e3f0 (api:46)
2017-09-11 10:02:18,932-0400 INFO (jsonrpc/3) [vdsm.api] FINISH getStoragePoolInfo return={'info': {'name': 'No Description', 'isoprefix': '', 'pool_status': 'connected', 'lver': 4L, 'domains': u'4cf0bbe1-19aa-4617-8e96-3d624fc871a1:Active', 'master_uuid': u'4cf0bbe1-19aa-4617-8e96-3d624fc871a1', 'version': '4', 'spm_id': 1, 'type': 'NFS', 'master_ver': 1}, 'dominfo': {u'4cf0bbe1-19aa-4617-8e96-3d624fc871a1': {'status': u'Active', 'diskfree': '677535285248', 'isoprefix': '', 'alerts': [], 'disktotal': '984374837248', 'version': 4}}} from=::ffff:192.168.1.21,40050, task_id=b5f55d5d-0f32-4528-9106-a4546fb9e3f0 (api:52)
2017-09-11 10:02:18,933-0400 INFO (jsonrpc/3) [jsonrpc.JsonRpcServer] RPC call StoragePool.getInfo succeeded in 0.01 seconds (__init__:539)
2017-09-11 10:02:22,115-0400 INFO (periodic/2) [vdsm.api] START repoStats(options=None) from=internal, task_id=711f3042-fb85-40d1-9050-b2fe0030ffb3 (api:46)
2017-09-11 10:02:22,116-0400 INFO (periodic/2) [vdsm.api] FINISH repoStats return={u'4cf0bbe1-19aa-4617-8e96-3d624fc871a1': {'code': 0, 'actual': True, 'version': 4, 'acquired': True, 'delay': '0.000502211', 'lastCheck': '1.2', 'valid': True}} from=internal, task_id=711f3042-fb85-40d1-9050-b2fe0030ffb3 (api:52)
2017-09-11 10:02:22,541-0400 INFO (itmap/0) [IOProcessClient] Starting client ioprocess-3 (__init__:330)
2017-09-11 10:02:22,547-0400 INFO (ioprocess/2228) [IOProcess] Starting ioprocess (__init__:452)
2017-09-11 10:02:22,564-0400 INFO (monitor/4cf0bbe) [storage.StorageDomain] Resource namespace 01_img_4cf0bbe1-19aa-4617-8e96-3d624fc871a1 already registered (sd:727)
2017-09-11 10:02:22,564-0400 INFO (monitor/4cf0bbe) [storage.StorageDomain] Resource namespace 02_vol_4cf0bbe1-19aa-4617-8e96-3d624fc871a1 already registered (sd:736)
2017-09-11 10:02:23,898-0400 INFO (jsonrpc/6) [jsonrpc.JsonRpcServer] RPC call Host.getAllVmStats succeeded in 0.00 seconds (__init__:539)
2017-09-11 10:02:27,155-0400 INFO (jsonrpc/1) [jsonrpc.JsonRpcServer] RPC call Host.getAllVmStats succeeded in 0.00 seconds (__init__:539)
2017-09-11 10:02:29,956-0400 INFO (jsonrpc/2) [vdsm.api] START getSpmStatus(spUUID=u'3fb4ebd6-e75c-4f33-8360-60aa3ee06b1c', options=None) from=::ffff:192.168.1.21,40048, task_id=07afe05a-c69d-4a49-b1bf-971d54202fa1 (api:46)
2017-09-11 10:02:29,964-0400 INFO (jsonrpc/2) [vdsm.api] FINISH getSpmStatus return={'spm_st': {'spmId': 1, 'spmStatus': 'SPM', 'spmLver': 4L}} from=::ffff:192.168.1.21,40048, task_id=07afe05a-c69d-4a49-b1bf-971d54202fa1 (api:52)
2017-09-11 10:02:29,967-0400 INFO (jsonrpc/2) [jsonrpc.JsonRpcServer] RPC call StoragePool.getSpmStatus succeeded in 0.01 seconds (__init__:539)
2017-09-11 10:02:30,976-0400 INFO (jsonrpc/0) [vdsm.api] START getStoragePoolInfo(spUUID=u'3fb4ebd6-e75c-4f33-8360-60aa3ee06b1c', options=None) from=::ffff:192.168.1.21,40050, task_id=2d4c50c2-d994-4082-a827-5a2aec9202fd (api:46)
2017-09-11 10:02:30,981-0400 INFO (jsonrpc/0) [vdsm.api] FINISH getStoragePoolInfo return={'info': {'name': 'No Description', 'isoprefix': '', 'pool_status': 'connected', 'lver': 4L, 'domains': u'4cf0bbe1-19aa-4617-8e96-3d624fc871a1:Active', 'master_uuid': u'4cf0bbe1-19aa-4617-8e96-3d624fc871a1', 'version': '4', 'spm_id': 1, 'type': 'NFS', 'master_ver': 1}, 'dominfo': {u'4cf0bbe1-19aa-4617-8e96-3d624fc871a1': {'status': u'Active', 'diskfree': '677535285248', 'isoprefix': '', 'alerts': [], 'disktotal': '984374837248', 'version': 4}}} from=::ffff:192.168.1.21,40050, task_id=2d4c50c2-d994-4082-a827-5a2aec9202fd (api:52)
2017-09-11 10:02:30,982-0400 INFO (jsonrpc/0) [jsonrpc.JsonRpcServer] RPC call StoragePool.getInfo succeeded in 0.01 seconds (__init__:539)
2017-09-11 10:02:37,126-0400 INFO (periodic/1) [vdsm.api] START repoStats(options=None) from=internal, task_id=f81884a1-d347-4897-803f-4024ac823c02 (api:46)
2017-09-11 10:02:37,126-0400 INFO (periodic/1) [vdsm.api] FINISH repoStats return={u'4cf0bbe1-19aa-4617-8e96-3d624fc871a1': {'code': 0, 'actual': True, 'version': 4, 'acquired': True, 'delay': '0.000453909', 'lastCheck': '4.6', 'valid': True}} from=internal, task_id=f81884a1-d347-4897-803f-4024ac823c02 (api:52)
2017-09-11 10:02:39,907-0400 INFO (jsonrpc/4) [jsonrpc.JsonRpcServer] RPC call Host.getAllVmStats succeeded in 0.00 seconds (__init__:539)
2017-09-11 10:02:41,999-0400 INFO (jsonrpc/5) [vdsm.api] START getSpmStatus(spUUID=u'3fb4ebd6-e75c-4f33-8360-60aa3ee06b1c', options=None) from=::ffff:192.168.1.21,40048, task_id=5bbfd083-fbf0-4064-814d-7d4744298d5c (api:46)
2017-09-11 10:02:42,002-0400 INFO (jsonrpc/5) [vdsm.api] FINISH getSpmStatus return={'spm_st': {'spmId': 1, 'spmStatus': 'SPM', 'spmLver': 4L}} from=::ffff:192.168.1.21,40048, task_id=5bbfd083-fbf0-4064-814d-7d4744298d5c (api:52)
2017-09-11 10:02:42,002-0400 INFO (jsonrpc/5) [jsonrpc.JsonRpcServer] RPC call StoragePool.getSpmStatus succeeded in 0.00 seconds (__init__:539)
2017-09-11 10:02:42,166-0400 INFO (jsonrpc/7) [jsonrpc.JsonRpcServer] RPC call Host.getAllVmStats succeeded in 0.00 seconds (__init__:539)
2017-09-11 10:02:43,013-0400 INFO (jsonrpc/3) [vdsm.api] START getStoragePoolInfo(spUUID=u'3fb4ebd6-e75c-4f33-8360-60aa3ee06b1c', options=None) from=::ffff:192.168.1.21,40050, task_id=fa3207eb-edd5-4707-997b-eac830586939 (api:46)
2017-09-11 10:02:43,017-0400 INFO (jsonrpc/3) [vdsm.api] FINISH getStoragePoolInfo return={'info': {'name': 'No Description', 'isoprefix': '', 'pool_status': 'connected', 'lver': 4L, 'domains': u'4cf0bbe1-19aa-4617-8e96-3d624fc871a1:Active', 'master_uuid': u'4cf0bbe1-19aa-4617-8e96-3d624fc871a1', 'version': '4', 'spm_id': 1, 'type': 'NFS', 'master_ver': 1}, 'dominfo': {u'4cf0bbe1-19aa-4617-8e96-3d624fc871a1': {'status': u'Active', 'diskfree': '677535285248', 'isoprefix': '', 'alerts': [], 'disktotal': '984374837248', 'version': 4}}} from=::ffff:192.168.1.21,40050, task_id=fa3207eb-edd5-4707-997b-eac830586939 (api:52)
2017-09-11 10:02:43,017-0400 INFO (jsonrpc/3) [jsonrpc.JsonRpcServer] RPC call StoragePool.getInfo succeeded in 0.01 seconds (__init__:539)
2017-09-11 10:02:52,132-0400 INFO (periodic/2) [vdsm.api] START repoStats(options=None) from=internal, task_id=81b7a1f7-9999-4a80-9abe-9d06e966a9fb (api:46)
2017-09-11 10:02:52,132-0400 INFO (periodic/2) [vdsm.api] FINISH repoStats return={u'4cf0bbe1-19aa-4617-8e96-3d624fc871a1': {'code': 0, 'actual': True, 'version': 4, 'acquired': True, 'delay': '0.000316677', 'lastCheck': '1.2', 'valid': True}} from=internal, task_id=81b7a1f7-9999-4a80-9abe-9d06e966a9fb (api:52)
2017-09-11 10:02:54,034-0400 INFO (jsonrpc/6) [vdsm.api] START getSpmStatus(spUUID=u'3fb4ebd6-e75c-4f33-8360-60aa3ee06b1c', options=None) from=::ffff:192.168.1.21,40048, task_id=2f2bbd67-c747-45d9-bc8c-2d9790696b1f (api:46)
2017-09-11 10:02:54,037-0400 INFO (jsonrpc/6) [vdsm.api] FINISH getSpmStatus return={'spm_st': {'spmId': 1, 'spmStatus': 'SPM', 'spmLver': 4L}} from=::ffff:192.168.1.21,40048, task_id=2f2bbd67-c747-45d9-bc8c-2d9790696b1f (api:52)
2017-09-11 10:02:54,038-0400 INFO (jsonrpc/6) [jsonrpc.JsonRpcServer] RPC call StoragePool.getSpmStatus succeeded in 0.01 seconds (__init__:539)
2017-09-11 10:02:55,048-0400 INFO (jsonrpc/1) [vdsm.api] START getStoragePoolInfo(spUUID=u'3fb4ebd6-e75c-4f33-8360-60aa3ee06b1c', options=None) from=::ffff:192.168.1.21,40050, task_id=293156c9-6f73-4677-b5d5-e2d76b2d2b84 (api:46)
2017-09-11 10:02:55,052-0400 INFO (jsonrpc/1) [vdsm.api] FINISH getStoragePoolInfo return={'info': {'name': 'No Description', 'isoprefix': '', 'pool_status': 'connected', 'lver': 4L, 'domains': u'4cf0bbe1-19aa-4617-8e96-3d624fc871a1:Active', 'master_uuid': u'4cf0bbe1-19aa-4617-8e96-3d624fc871a1', 'version': '4', 'spm_id': 1, 'type': 'NFS', 'master_ver': 1}, 'dominfo': {u'4cf0bbe1-19aa-4617-8e96-3d624fc871a1': {'status': u'Active', 'diskfree': '677535285248', 'isoprefix': '', 'alerts': [], 'disktotal': '984374837248', 'version': 4}}} from=::ffff:192.168.1.21,40050, task_id=293156c9-6f73-4677-b5d5-e2d76b2d2b84 (api:52)
2017-09-11 10:02:55,053-0400 INFO (jsonrpc/1) [jsonrpc.JsonRpcServer] RPC call StoragePool.getInfo succeeded in 0.00 seconds (__init__:539)
2017-09-11 10:02:55,915-0400 INFO (jsonrpc/2) [jsonrpc.JsonRpcServer] RPC call Host.getAllVmStats succeeded in 0.00 seconds (__init__:539)
2017-09-11 10:02:57,180-0400 INFO (jsonrpc/0) [jsonrpc.JsonRpcServer] RPC call Host.getAllVmStats succeeded in 0.00 seconds (__init__:539)
2017-09-11 10:03:05,934-0400 INFO (jsonrpc/4) [vdsm.api] START getSpmStatus(spUUID=u'3fb4ebd6-e75c-4f33-8360-60aa3ee06b1c', options=None) from=::ffff:192.168.1.21,40048, task_id=bce8c75c-4620-48cd-ba42-9a68b09dd259 (api:46)
2017-09-11 10:03:05,938-0400 INFO (jsonrpc/4) [vdsm.api] FINISH getSpmStatus return={'spm_st': {'spmId': 1, 'spmStatus': 'SPM', 'spmLver': 4L}} from=::ffff:192.168.1.21,40048, task_id=bce8c75c-4620-48cd-ba42-9a68b09dd259 (api:52)
2017-09-11 10:03:05,938-0400 INFO (jsonrpc/4) [jsonrpc.JsonRpcServer] RPC call StoragePool.getSpmStatus succeeded in 0.01 seconds (__init__:539)
2017-09-11 10:03:06,955-0400 INFO (jsonrpc/5) [vdsm.api] START getStoragePoolInfo(spUUID=u'3fb4ebd6-e75c-4f33-8360-60aa3ee06b1c', options=None) from=::ffff:192.168.1.21,40050, task_id=21c08955-44cd-46d4-acb4-677aa2a22f98 (api:46)
2017-09-11 10:03:06,959-0400 INFO (jsonrpc/5) [vdsm.api] FINISH getStoragePoolInfo return={'info': {'name': 'No Description', 'isoprefix': '', 'pool_status': 'connected', 'lver': 4L, 'domains': u'4cf0bbe1-19aa-4617-8e96-3d624fc871a1:Active', 'master_uuid': u'4cf0bbe1-19aa-4617-8e96-3d624fc871a1', 'version': '4', 'spm_id': 1, 'type': 'NFS', 'master_ver': 1}, 'dominfo': {u'4cf0bbe1-19aa-4617-8e96-3d624fc871a1': {'status': u'Active', 'diskfree': '677535285248', 'isoprefix': '', 'alerts': [], 'disktotal': '984374837248', 'version': 4}}} from=::ffff:192.168.1.21,40050, task_id=21c08955-44cd-46d4-acb4-677aa2a22f98 (api:52)
2017-09-11 10:03:06,959-0400 INFO (jsonrpc/5) [jsonrpc.JsonRpcServer] RPC call StoragePool.getInfo succeeded in 0.01 seconds (__init__:539)
2017-09-11 10:03:07,139-0400 INFO (periodic/3) [vdsm.api] START repoStats(options=None) from=internal, task_id=81014fe6-3077-4b70-8102-9775061ab79b (api:46)
2017-09-11 10:03:07,140-0400 INFO (periodic/3) [vdsm.api] FINISH repoStats return={u'4cf0bbe1-19aa-4617-8e96-3d624fc871a1': {'code': 0, 'actual': True, 'version': 4, 'acquired': True, 'delay': '0.000834398', 'lastCheck': '4.6', 'valid': True}} from=internal, task_id=81014fe6-3077-4b70-8102-9775061ab79b (api:52)
2017-09-11 10:03:11,923-0400 INFO (jsonrpc/7) [jsonrpc.JsonRpcServer] RPC call Host.getAllVmStats succeeded in 0.00 seconds (__init__:539)
2017-09-11 10:03:12,199-0400 INFO (jsonrpc/3) [jsonrpc.JsonRpcServer] RPC call Host.getAllVmStats succeeded in 0.00 seconds (__init__:539)
2017-09-11 10:03:17,980-0400 INFO (jsonrpc/6) [vdsm.api] START getSpmStatus(spUUID=u'3fb4ebd6-e75c-4f33-8360-60aa3ee06b1c', options=None) from=::ffff:192.168.1.21,40048, task_id=04dc9008-878b-4319-a457-c1956d13418f (api:46)
2017-09-11 10:03:17,984-0400 INFO (jsonrpc/6) [vdsm.api] FINISH getSpmStatus return={'spm_st': {'spmId': 1, 'spmStatus': 'SPM', 'spmLver': 4L}} from=::ffff:192.168.1.21,40048, task_id=04dc9008-878b-4319-a457-c1956d13418f (api:52)
2017-09-11 10:03:17,984-0400 INFO (jsonrpc/6) [jsonrpc.JsonRpcServer] RPC call StoragePool.getSpmStatus succeeded in 0.00 seconds (__init__:539)
2017-09-11 10:03:18,999-0400 INFO (jsonrpc/1) [vdsm.api] START getStoragePoolInfo(spUUID=u'3fb4ebd6-e75c-4f33-8360-60aa3ee06b1c', options=None) from=::ffff:192.168.1.21,40050, task_id=7e7074c4-0ecc-4469-8997-d3177fbb4144 (api:46)
2017-09-11 10:03:19,003-0400 INFO (jsonrpc/1) [vdsm.api] FINISH getStoragePoolInfo return={'info': {'name': 'No Description', 'isoprefix': '', 'pool_status': 'connected', 'lver': 4L, 'domains': u'4cf0bbe1-19aa-4617-8e96-3d624fc871a1:Active', 'master_uuid': u'4cf0bbe1-19aa-4617-8e96-3d624fc871a1', 'version': '4', 'spm_id': 1, 'type': 'NFS', 'master_ver': 1}, 'dominfo': {u'4cf0bbe1-19aa-4617-8e96-3d624fc871a1': {'status': u'Active', 'diskfree': '677535285248', 'isoprefix': '', 'alerts': [], 'disktotal': '984374837248', 'version': 4}}} from=::ffff:192.168.1.21,40050, task_id=7e7074c4-0ecc-4469-8997-d3177fbb4144 (api:52)
2017-09-11 10:03:19,004-0400 INFO (jsonrpc/1) [jsonrpc.JsonRpcServer] RPC call StoragePool.getInfo succeeded in 0.00 seconds (__init__:539)
2017-09-11 10:03:22,144-0400 INFO (periodic/1) [vdsm.api] START repoStats(options=None) from=internal, task_id=e2739d7a-6b80-4dd2-b640-bfce4f4ce27a (api:46)
2017-09-11 10:03:22,144-0400 INFO (periodic/1) [vdsm.api] FINISH repoStats return={u'4cf0bbe1-19aa-4617-8e96-3d624fc871a1': {'code': 0, 'actual': True, 'version': 4, 'acquired': True, 'delay': '0.000452478', 'lastCheck': '1.3', 'valid': True}} from=internal, task_id=e2739d7a-6b80-4dd2-b640-bfce4f4ce27a (api:52)
2017-09-11 10:03:22,584-0400 INFO (monitor/4cf0bbe) [IOProcessClient] Closing client ioprocess-3 (__init__:598)
2017-09-11 10:03:27,218-0400 INFO (jsonrpc/2) [jsonrpc.JsonRpcServer] RPC call Host.getAllVmStats succeeded in 0.00 seconds (__init__:539)
2017-09-11 10:03:27,931-0400 INFO (jsonrpc/0) [jsonrpc.JsonRpcServer] RPC call Host.getAllVmStats succeeded in 0.00 seconds (__init__:539)
2017-09-11 10:03:30,031-0400 INFO (jsonrpc/4) [vdsm.api] START getSpmStatus(spUUID=u'3fb4ebd6-e75c-4f33-8360-60aa3ee06b1c', options=None) from=::ffff:192.168.1.21,40048, task_id=2cb12cde-7835-48c3-8ffc-aa28bc89b461 (api:46)
2017-09-11 10:03:30,034-0400 INFO (jsonrpc/4) [vdsm.api] FINISH getSpmStatus return={'spm_st': {'spmId': 1, 'spmStatus': 'SPM', 'spmLver': 4L}} from=::ffff:192.168.1.21,40048, task_id=2cb12cde-7835-48c3-8ffc-aa28bc89b461 (api:52)
2017-09-11 10:03:30,035-0400 INFO (jsonrpc/4) [jsonrpc.JsonRpcServer] RPC call StoragePool.getSpmStatus succeeded in 0.00 seconds (__init__:539)
2017-09-11 10:03:31,049-0400 INFO (jsonrpc/5) [vdsm.api] START getStoragePoolInfo(spUUID=u'3fb4ebd6-e75c-4f33-8360-60aa3ee06b1c', options=None) from=::ffff:192.168.1.21,40050, task_id=472d94a5-9738-4859-885e-9a46ca4049d8 (api:46)
2017-09-11 10:03:31,053-0400 INFO (jsonrpc/5) [vdsm.api] FINISH getStoragePoolInfo return={'info': {'name': 'No Description', 'isoprefix': '', 'pool_status': 'connected', 'lver': 4L, 'domains': u'4cf0bbe1-19aa-4617-8e96-3d624fc871a1:Active', 'master_uuid': u'4cf0bbe1-19aa-4617-8e96-3d624fc871a1', 'version': '4', 'spm_id': 1, 'type': 'NFS', 'master_ver': 1}, 'dominfo': {u'4cf0bbe1-19aa-4617-8e96-3d624fc871a1': {'status': u'Active', 'diskfree': '677535285248', 'isoprefix': '', 'alerts': [], 'disktotal': '984374837248', 'version': 4}}} from=::ffff:192.168.1.21,40050, task_id=472d94a5-9738-4859-885e-9a46ca4049d8 (api:52)
2017-09-11 10:03:31,054-0400 INFO (jsonrpc/5) [jsonrpc.JsonRpcServer] RPC call StoragePool.getInfo succeeded in 0.00 seconds (__init__:539)
2017-09-11 10:03:37,159-0400 INFO (periodic/2) [vdsm.api] START repoStats(options=None) from=internal, task_id=d13d080d-6f67-48ea-8750-ab8ea88ffe0c (api:46)
2017-09-11 10:03:37,160-0400 INFO (periodic/2) [vdsm.api] FINISH repoStats return={u'4cf0bbe1-19aa-4617-8e96-3d624fc871a1': {'code': 0, 'actual': True, 'version': 4, 'acquired': True, 'delay': '0.000855249', 'lastCheck': '4.6', 'valid': True}} from=internal, task_id=d13d080d-6f67-48ea-8750-ab8ea88ffe0c (api:52)
2017-09-11 10:03:42,073-0400 INFO (jsonrpc/7) [vdsm.api] START getSpmStatus(spUUID=u'3fb4ebd6-e75c-4f33-8360-60aa3ee06b1c', options=None) from=::ffff:192.168.1.21,40048, task_id=2012e563-f041-4a62-a423-1c1f5a451ffc (api:46)
2017-09-11 10:03:42,076-0400 INFO (jsonrpc/7) [vdsm.api] FINISH getSpmStatus return={'spm_st': {'spmId': 1, 'spmStatus': 'SPM', 'spmLver': 4L}} from=::ffff:192.168.1.21,40048, task_id=2012e563-f041-4a62-a423-1c1f5a451ffc (api:52)
2017-09-11 10:03:42,076-0400 INFO (jsonrpc/7) [jsonrpc.JsonRpcServer] RPC call StoragePool.getSpmStatus succeeded in 0.00 seconds (__init__:539)
2017-09-11 10:03:42,224-0400 INFO (jsonrpc/3) [jsonrpc.JsonRpcServer] RPC call Host.getAllVmStats succeeded in 0.00 seconds (__init__:539)
2017-09-11 10:03:43,091-0400 INFO (jsonrpc/6) [vdsm.api] START getStoragePoolInfo(spUUID=u'3fb4ebd6-e75c-4f33-8360-60aa3ee06b1c', options=None) from=::ffff:192.168.1.21,40050, task_id=30f1ab65-31c6-4ff7-99cc-147cc81622c8 (api:46)
2017-09-11 10:03:43,095-0400 INFO (jsonrpc/6) [vdsm.api] FINISH getStoragePoolInfo return={'info': {'name': 'No Description', 'isoprefix': '', 'pool_status': 'connected', 'lver': 4L, 'domains': u'4cf0bbe1-19aa-4617-8e96-3d624fc871a1:Active', 'master_uuid': u'4cf0bbe1-19aa-4617-8e96-3d624fc871a1', 'version': '4', 'spm_id': 1, 'type': 'NFS', 'master_ver': 1}, 'dominfo': {u'4cf0bbe1-19aa-4617-8e96-3d624fc871a1': {'status': u'Active', 'diskfree': '677535285248', 'isoprefix': '', 'alerts': [], 'disktotal': '984374837248', 'version': 4}}} from=::ffff:192.168.1.21,40050, task_id=30f1ab65-31c6-4ff7-99cc-147cc81622c8 (api:52)
2017-09-11 10:03:43,095-0400 INFO (jsonrpc/6) [jsonrpc.JsonRpcServer] RPC call StoragePool.getInfo succeeded in 0.00 seconds (__init__:539)
2017-09-11 10:03:43,939-0400 INFO (jsonrpc/1) [jsonrpc.JsonRpcServer] RPC call Host.getAllVmStats succeeded in 0.00 seconds (__init__:539)
2017-09-11 10:03:52,179-0400 INFO (periodic/1) [vdsm.api] START repoStats(options=None) from=internal, task_id=72612326-9de8-43e6-a7d3-9efea64266cf (api:46)
2017-09-11 10:03:52,180-0400 INFO (periodic/1) [vdsm.api] FINISH repoStats return={u'4cf0bbe1-19aa-4617-8e96-3d624fc871a1': {'code': 0, 'actual': True, 'version': 4, 'acquired': True, 'delay': '0.00119346', 'lastCheck': '1.2', 'valid': True}} from=internal, task_id=72612326-9de8-43e6-a7d3-9efea64266cf (api:52)
2017-09-11 10:03:53,955-0400 INFO (jsonrpc/2) [vdsm.api] START getSpmStatus(spUUID=u'3fb4ebd6-e75c-4f33-8360-60aa3ee06b1c', options=None) from=::ffff:192.168.1.21,40048, task_id=f2c4ed0c-67ed-41f4-9d5b-457c2f878c0e (api:46)
2017-09-11 10:03:53,958-0400 INFO (jsonrpc/2) [vdsm.api] FINISH getSpmStatus return={'spm_st': {'spmId': 1, 'spmStatus': 'SPM', 'spmLver': 4L}} from=::ffff:192.168.1.21,40048, task_id=f2c4ed0c-67ed-41f4-9d5b-457c2f878c0e (api:52)
2017-09-11 10:03:53,958-0400 INFO (jsonrpc/2) [jsonrpc.JsonRpcServer] RPC call StoragePool.getSpmStatus succeeded in 0.01 seconds (__init__:539)
2017-09-11 10:03:55,025-0400 INFO (jsonrpc/0) [vdsm.api] START getStoragePoolInfo(spUUID=u'3fb4ebd6-e75c-4f33-8360-60aa3ee06b1c', options=None) from=::ffff:192.168.1.21,40050, task_id=10f45698-c35f-4ef7-9ba3-75cd8190ef12 (api:46)
2017-09-11 10:03:55,036-0400 INFO (jsonrpc/0) [vdsm.api] FINISH getStoragePoolInfo return={'info': {'name': 'No Description', 'isoprefix': '', 'pool_status': 'connected', 'lver': 4L, 'domains': u'4cf0bbe1-19aa-4617-8e96-3d624fc871a1:Active', 'master_uuid': u'4cf0bbe1-19aa-4617-8e96-3d624fc871a1', 'version': '4', 'spm_id': 1, 'type': 'NFS', 'master_ver': 1}, 'dominfo': {u'4cf0bbe1-19aa-4617-8e96-3d624fc871a1': {'status': u'Active', 'diskfree': '677535285248', 'isoprefix': '', 'alerts': [], 'disktotal': '984374837248', 'version': 4}}} from=::ffff:192.168.1.21,40050, task_id=10f45698-c35f-4ef7-9ba3-75cd8190ef12 (api:52)
2017-09-11 10:03:55,037-0400 INFO (jsonrpc/0) [jsonrpc.JsonRpcServer] RPC call StoragePool.getInfo succeeded in 0.01 seconds (__init__:539)
2017-09-11 10:03:57,243-0400 INFO (jsonrpc/4) [jsonrpc.JsonRpcServer] RPC call Host.getAllVmStats succeeded in 0.00 seconds (__init__:539)
2017-09-11 10:03:59,951-0400 INFO (jsonrpc/5) [jsonrpc.JsonRpcServer] RPC call Host.getAllVmStats succeeded in 0.00 seconds (__init__:539)
2017-09-11 10:04:06,060-0400 INFO (jsonrpc/7) [vdsm.api] START getSpmStatus(spUUID=u'3fb4ebd6-e75c-4f33-8360-60aa3ee06b1c', options=None) from=::ffff:192.168.1.21,40048, task_id=92390e7a-dea5-4e4e-bf1e-25b1aea0c723 (api:46)
2017-09-11 10:04:06,063-0400 INFO (jsonrpc/7) [vdsm.api] FINISH getSpmStatus return={'spm_st': {'spmId': 1, 'spmStatus': 'SPM', 'spmLver': 4L}} from=::ffff:192.168.1.21,40048, task_id=92390e7a-dea5-4e4e-bf1e-25b1aea0c723 (api:52)
2017-09-11 10:04:06,064-0400 INFO (jsonrpc/7) [jsonrpc.JsonRpcServer] RPC call StoragePool.getSpmStatus succeeded in 0.00 seconds (__init__:539)
_______________________________________________
Devel mailing list
[email protected]
http://lists.ovirt.org/mailman/listinfo/devel
