[ovirt-users] oVirt 4.1.5 Finally GlusterFS via lbgfapi
Ralf Schenk
rs at databay.de
Fri Aug 25 09:05:58 UTC 2017
Hello,
thank you for helping me with my problem.
I replayed migration (10:38:02 local time) and recorded vdsm.log of
source and destination as attached. I can't find anything in the gluster
logs that shows an error. One information: my FQDN
glusterfs.rxmgmt.databay.de points to all the gluster hosts:
glusterfs.rxmgmt.databay.de. 84600 IN A 172.16.252.121
glusterfs.rxmgmt.databay.de. 84600 IN A 172.16.252.125
glusterfs.rxmgmt.databay.de. 84600 IN A 172.16.252.127
glusterfs.rxmgmt.databay.de. 84600 IN A 172.16.252.122
glusterfs.rxmgmt.databay.de. 84600 IN A 172.16.252.124
glusterfs.rxmgmt.databay.de. 84600 IN A 172.16.252.123
glusterfs.rxmgmt.databay.de. 84600 IN A 172.16.252.126
glusterfs.rxmgmt.databay.de. 84600 IN A 172.16.252.128
I double checked all gluster hosts. They all are configured the same
regarding "option rpc-auth-allow-insecure on" No iptables rules on the host.
Bye
Am 24.08.2017 um 16:38 schrieb Yaniv Kaul:
> Can you also post to the users mailing list the destination VDSM log?
>
> On Thu, Aug 24, 2017 at 4:55 PM, Ralf Schenk <rs at databay.de
> <mailto:rs at databay.de>> wrote:
>
> Hello,
>
> nice to hear it worked for you.
>
> Attached you find the vdsm.log (from migration source) including
> the error and engine.log which looks ok.
>
> Hostnames/IP-Adresses are correct and use the ovirtmgmt Network.
>
> I checked (on both hosts):
>
> [root at microcloud22 glusterfs]# gluster volume get gv0
> storage.owner-uid
> Option Value
> ------ -----
> storage.owner-uid 36
> [root at microcloud22 glusterfs]# gluster volume get gv0
> storage.owner-gid
> Option Value
> ------ -----
> storage.owner-gid 36
> [root at microcloud22 glusterfs]# gluster volume get gv0
> server.allow-insecure
> Option Value
> ------ -----
> server.allow-insecure on
>
> and /etc/glusterd
>
> root at microcloud22 glusterfs]# cat /etc/glusterfs/glusterd.vol
> volume management
> type mgmt/glusterd
> option working-directory /var/lib/glusterd
> option transport-type socket
> option transport.socket.keepalive-time 10
> option transport.socket.keepalive-interval 2
> option transport.socket.read-fail-log off
> option ping-timeout 0
> option event-threads 1
> option rpc-auth-allow-insecure on
> # option transport.address-family inet6
> # option base-port 49152
> end-volume
>
> Bye
>
>
> Am 24.08.2017 um 15:25 schrieb Denis Chaplygin:
>> Hello!
>>
>> On Thu, Aug 24, 2017 at 3:07 PM, Ralf Schenk <rs at databay.de
>> <mailto:rs at databay.de>> wrote:
>>
>> Responsiveness of VM is much better (already seen when
>> Updateng OS Packages).
>>
>> But I'm not able to migrate the mashine live to another host
>> in the cluster. Manager only states "Migration failed"
>>
>>
>> Live migration worked for me.
>>
>> Cold you please provide some details? Engine/vdsm logs in +/- 10
>> minutes in the vicinity of migration failure.
>
> --
>
>
> *Ralf Schenk*
> fon +49 (0) 24 05 / 40 83 70 <tel:+49%202405%20408370>
> fax +49 (0) 24 05 / 40 83 759 <tel:+49%202405%204083759>
> mail *rs at databay.de* <mailto:rs at databay.de>
>
> *Databay AG*
> Jens-Otto-Krag-Straße 11
> D-52146 Würselen
> *www.databay.de* <http://www.databay.de>
>
> Sitz/Amtsgericht Aachen • HRB:8437 • USt-IdNr.: DE 210844202
> Vorstand: Ralf Schenk, Dipl.-Ing. Jens Conze, Aresch Yavari,
> Dipl.-Kfm. Philipp Hermanns
> Aufsichtsratsvorsitzender: Wilhelm Dohmen
>
> ------------------------------------------------------------------------
>
> _______________________________________________
> Users mailing list
> Users at ovirt.org <mailto:Users at ovirt.org>
> http://lists.ovirt.org/mailman/listinfo/users
> <http://lists.ovirt.org/mailman/listinfo/users>
>
>
--
*Ralf Schenk*
fon +49 (0) 24 05 / 40 83 70
fax +49 (0) 24 05 / 40 83 759
mail *rs at databay.de* <mailto:rs at databay.de>
*Databay AG*
Jens-Otto-Krag-Straße 11
D-52146 Würselen
*www.databay.de* <http://www.databay.de>
Sitz/Amtsgericht Aachen • HRB:8437 • USt-IdNr.: DE 210844202
Vorstand: Ralf Schenk, Dipl.-Ing. Jens Conze, Aresch Yavari, Dipl.-Kfm.
Philipp Hermanns
Aufsichtsratsvorsitzender: Wilhelm Dohmen
------------------------------------------------------------------------
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://lists.ovirt.org/pipermail/users/attachments/20170825/84971f03/attachment.html>
-------------- next part --------------
A non-text attachment was scrubbed...
Name: kjpdaaackclkdcfa.gif
Type: image/gif
Size: 1250 bytes
Desc: not available
URL: <http://lists.ovirt.org/pipermail/users/attachments/20170825/84971f03/attachment.gif>
-------------- next part --------------
A non-text attachment was scrubbed...
Name: mgcjbmjfamednkcf.gif
Type: image/gif
Size: 1250 bytes
Desc: not available
URL: <http://lists.ovirt.org/pipermail/users/attachments/20170825/84971f03/attachment-0001.gif>
-------------- next part --------------
2017-08-25 10:37:32,556+0200 INFO (jsonrpc/5) [jsonrpc.JsonRpcServer] RPC call Host.getAllVmStats succeeded in 0.00 seconds (__init__:539)
2017-08-25 10:37:32,558+0200 INFO (jsonrpc/6) [jsonrpc.JsonRpcServer] RPC call Host.getAllVmIoTunePolicies succeeded in 0.00 seconds (__init__:539)
2017-08-25 10:37:39,268+0200 INFO (jsonrpc/3) [vdsm.api] START repoStats(options=None) from=::ffff:172.16.252.200,56136, flow_id=50d5361d, task_id=2d55566e-c7f9-46d8-90a3-f77140bd7dde (api:46)
2017-08-25 10:37:39,269+0200 INFO (jsonrpc/3) [vdsm.api] FINISH repoStats return={u'7c8deaa8-be02-4aaf-b9b4-ddc8da99ad96': {'code': 0, 'actual': True, 'version': 4, 'acquired': True, 'delay': '0.000289122', 'lastCheck': '5.2', 'valid': True}, u'2b2a44fc-f2bd-47cd-b7af-00be59e30a35': {'code': 0, 'actual': True, 'version': 0, 'acquired': True, 'delay': '0.000713251', 'lastCheck': '6.3', 'valid': True}, u'5d99af76-33b5-47d8-99da-1f32413c7bb0': {'code': 0, 'actual': True, 'version': 4, 'acquired': True, 'delay': '0.000301521', 'lastCheck': '6.3', 'valid': True}, u'a7fbaaad-7043-4391-9523-3bedcdc4fb0d': {'code': 0, 'actual': True, 'version': 0, 'acquired': True, 'delay': '0.000624155', 'lastCheck': '6.2', 'valid': True}} from=::ffff:172.16.252.200,56136, flow_id=50d5361d, task_id=2d55566e-c7f9-46d8-90a3-f77140bd7dde (api:52)
2017-08-25 10:37:39,273+0200 INFO (jsonrpc/3) [jsonrpc.JsonRpcServer] RPC call Host.getStats succeeded in 0.01 seconds (__init__:539)
2017-08-25 10:37:46,294+0200 INFO (jsonrpc/0) [jsonrpc.JsonRpcServer] RPC call Host.getAllVmStats succeeded in 0.00 seconds (__init__:539)
2017-08-25 10:37:47,242+0200 INFO (periodic/1) [vdsm.api] START repoStats(options=None) from=internal, task_id=0186585f-9989-4baf-89cf-fe5d3f5e117e (api:46)
2017-08-25 10:37:47,242+0200 INFO (periodic/1) [vdsm.api] FINISH repoStats return={u'7c8deaa8-be02-4aaf-b9b4-ddc8da99ad96': {'code': 0, 'actual': True, 'version': 4, 'acquired': True, 'delay': '0.000286753', 'lastCheck': '3.2', 'valid': True}, u'2b2a44fc-f2bd-47cd-b7af-00be59e30a35': {'code': 0, 'actual': True, 'version': 0, 'acquired': True, 'delay': '0.000741823', 'lastCheck': '4.2', 'valid': True}, u'5d99af76-33b5-47d8-99da-1f32413c7bb0': {'code': 0, 'actual': True, 'version': 4, 'acquired': True, 'delay': '0.000281747', 'lastCheck': '4.3', 'valid': True}, u'a7fbaaad-7043-4391-9523-3bedcdc4fb0d': {'code': 0, 'actual': True, 'version': 0, 'acquired': True, 'delay': '0.000697041', 'lastCheck': '4.2', 'valid': True}} from=internal, task_id=0186585f-9989-4baf-89cf-fe5d3f5e117e (api:52)
2017-08-25 10:37:47,566+0200 INFO (jsonrpc/4) [jsonrpc.JsonRpcServer] RPC call Host.getAllVmStats succeeded in 0.00 seconds (__init__:539)
2017-08-25 10:37:47,574+0200 INFO (jsonrpc/2) [jsonrpc.JsonRpcServer] RPC call Host.getAllVmIoTunePolicies succeeded in 0.00 seconds (__init__:539)
2017-08-25 10:37:54,413+0200 INFO (jsonrpc/7) [vdsm.api] START repoStats(options=None) from=::ffff:172.16.252.200,56136, flow_id=7513f48c, task_id=4647c0eb-2237-4ddd-92a4-0ecfeabd5b82 (api:46)
2017-08-25 10:37:54,413+0200 INFO (jsonrpc/7) [vdsm.api] FINISH repoStats return={u'7c8deaa8-be02-4aaf-b9b4-ddc8da99ad96': {'code': 0, 'actual': True, 'version': 4, 'acquired': True, 'delay': '0.000305208', 'lastCheck': '0.4', 'valid': True}, u'2b2a44fc-f2bd-47cd-b7af-00be59e30a35': {'code': 0, 'actual': True, 'version': 0, 'acquired': True, 'delay': '0.000742639', 'lastCheck': '1.4', 'valid': True}, u'5d99af76-33b5-47d8-99da-1f32413c7bb0': {'code': 0, 'actual': True, 'version': 4, 'acquired': True, 'delay': '0.00028006', 'lastCheck': '1.5', 'valid': True}, u'a7fbaaad-7043-4391-9523-3bedcdc4fb0d': {'code': 0, 'actual': True, 'version': 0, 'acquired': True, 'delay': '0.000585459', 'lastCheck': '1.4', 'valid': True}} from=::ffff:172.16.252.200,56136, flow_id=7513f48c, task_id=4647c0eb-2237-4ddd-92a4-0ecfeabd5b82 (api:52)
2017-08-25 10:37:54,417+0200 INFO (jsonrpc/7) [jsonrpc.JsonRpcServer] RPC call Host.getStats succeeded in 0.00 seconds (__init__:539)
2017-08-25 10:37:59,938+0200 INFO (itmap/0) [IOProcessClient] Starting client ioprocess-1016 (__init__:330)
2017-08-25 10:37:59,945+0200 INFO (ioprocess/1976) [IOProcess] Starting ioprocess (__init__:452)
2017-08-25 10:37:59,946+0200 INFO (itmap/1) [IOProcessClient] Starting client ioprocess-1017 (__init__:330)
2017-08-25 10:37:59,955+0200 INFO (ioprocess/1988) [IOProcess] Starting ioprocess (__init__:452)
2017-08-25 10:37:59,961+0200 INFO (monitor/a7fbaaa) [storage.StorageDomain] Resource namespace 01_img_a7fbaaad-7043-4391-9523-3bedcdc4fb0d already registered (sd:727)
2017-08-25 10:37:59,961+0200 INFO (monitor/a7fbaaa) [storage.StorageDomain] Resource namespace 02_vol_a7fbaaad-7043-4391-9523-3bedcdc4fb0d already registered (sd:736)
2017-08-25 10:37:59,964+0200 INFO (monitor/2b2a44f) [storage.StorageDomain] Resource namespace 01_img_2b2a44fc-f2bd-47cd-b7af-00be59e30a35 already registered (sd:727)
2017-08-25 10:37:59,964+0200 INFO (monitor/2b2a44f) [storage.StorageDomain] Resource namespace 02_vol_2b2a44fc-f2bd-47cd-b7af-00be59e30a35 already registered (sd:736)
2017-08-25 10:38:02,259+0200 INFO (jsonrpc/1) [jsonrpc.JsonRpcServer] RPC call Host.getAllVmStats succeeded in 0.00 seconds (__init__:539)
2017-08-25 10:38:02,318+0200 INFO (periodic/0) [vdsm.api] START repoStats(options=None) from=internal, task_id=d0612740-f187-4e96-b519-53d588d0b829 (api:46)
2017-08-25 10:38:02,318+0200 INFO (periodic/0) [vdsm.api] FINISH repoStats return={u'7c8deaa8-be02-4aaf-b9b4-ddc8da99ad96': {'code': 0, 'actual': True, 'version': 4, 'acquired': True, 'delay': '0.000305208', 'lastCheck': '8.3', 'valid': True}, u'2b2a44fc-f2bd-47cd-b7af-00be59e30a35': {'code': 0, 'actual': True, 'version': 0, 'acquired': True, 'delay': '0.000742639', 'lastCheck': '2.4', 'valid': True}, u'5d99af76-33b5-47d8-99da-1f32413c7bb0': {'code': 0, 'actual': True, 'version': 4, 'acquired': True, 'delay': '0.00028006', 'lastCheck': '3.0', 'valid': True}, u'a7fbaaad-7043-4391-9523-3bedcdc4fb0d': {'code': 0, 'actual': True, 'version': 0, 'acquired': True, 'delay': '0.000585459', 'lastCheck': '2.4', 'valid': True}} from=internal, task_id=d0612740-f187-4e96-b519-53d588d0b829 (api:52)
2017-08-25 10:38:02,569+0200 INFO (jsonrpc/5) [jsonrpc.JsonRpcServer] RPC call Host.getAllVmStats succeeded in 0.00 seconds (__init__:539)
2017-08-25 10:38:02,576+0200 INFO (jsonrpc/6) [jsonrpc.JsonRpcServer] RPC call Host.getAllVmIoTunePolicies succeeded in 0.00 seconds (__init__:539)
2017-08-25 10:38:02,788+0200 INFO (jsonrpc/3) [vdsm.api] START migrate(params={u'incomingLimit': 2, u'src': u'microcloud22.rxmgmt.databay.de', u'dstqemu': u'172.16.252.124', u'autoConverge': u'true', u'tunneled': u'false', u'enableGuestEvents': True, u'dst': u'microcloud24.rxmgmt.databay.de:54321', u'convergenceSchedule': {u'init': [{u'params': [u'100'], u'name': u'setDowntime'}], u'stalling': [{u'action': {u'params': [u'150'], u'name': u'setDowntime'}, u'limit': 1}, {u'action': {u'params': [u'200'], u'name': u'setDowntime'}, u'limit': 2}, {u'action': {u'params': [u'300'], u'name': u'setDowntime'}, u'limit': 3}, {u'action': {u'params': [u'400'], u'name': u'setDowntime'}, u'limit': 4}, {u'action': {u'params': [u'500'], u'name': u'setDowntime'}, u'limit': 6}, {u'action': {u'params': [], u'name': u'abort'}, u'limit': -1}]}, u'vmId': u'ffb71f79-54cd-4f0e-b6b5-3670236cb497', u'abortOnError': u'true', u'outgoingLimit': 2, u'compressed': u'false', u'method': u'online', 'mode': 'remote'}) from=::ffff:172.16.252.200,56136, flow_id=05c5ebd2-d311-4f7a-b2f9-e9180224b04f (api:46)
2017-08-25 10:38:02,790+0200 INFO (jsonrpc/3) [vdsm.api] FINISH migrate return={'status': {'message': 'Migration in progress', 'code': 0}, 'progress': 0} from=::ffff:172.16.252.200,56136, flow_id=05c5ebd2-d311-4f7a-b2f9-e9180224b04f (api:52)
2017-08-25 10:38:02,791+0200 INFO (jsonrpc/3) [jsonrpc.JsonRpcServer] RPC call VM.migrate succeeded in 0.01 seconds (__init__:539)
2017-08-25 10:38:03,494+0200 INFO (migsrc/ffb71f79) [virt.vm] (vmId='ffb71f79-54cd-4f0e-b6b5-3670236cb497') Creation of destination VM took: 0 seconds (migration:455)
2017-08-25 10:38:03,495+0200 INFO (migsrc/ffb71f79) [virt.vm] (vmId='ffb71f79-54cd-4f0e-b6b5-3670236cb497') starting migration to qemu+tls://microcloud24.rxmgmt.databay.de/system with miguri tcp://172.16.252.124 (migration:480)
2017-08-25 10:38:03,666+0200 ERROR (migsrc/ffb71f79) [virt.vm] (vmId='ffb71f79-54cd-4f0e-b6b5-3670236cb497') failed to initialize gluster connection (src=0x7fd818056a00 priv=0x7fd818057a50): Success (migration:287)
2017-08-25 10:38:03,703+0200 ERROR (migsrc/ffb71f79) [virt.vm] (vmId='ffb71f79-54cd-4f0e-b6b5-3670236cb497') Failed to migrate (migration:429)
Traceback (most recent call last):
File "/usr/lib/python2.7/site-packages/vdsm/virt/migration.py", line 411, in run
self._startUnderlyingMigration(time.time())
File "/usr/lib/python2.7/site-packages/vdsm/virt/migration.py", line 487, in _startUnderlyingMigration
self._perform_with_conv_schedule(duri, muri)
File "/usr/lib/python2.7/site-packages/vdsm/virt/migration.py", line 563, in _perform_with_conv_schedule
self._perform_migration(duri, muri)
File "/usr/lib/python2.7/site-packages/vdsm/virt/migration.py", line 529, in _perform_migration
self._vm._dom.migrateToURI3(duri, params, flags)
File "/usr/lib/python2.7/site-packages/vdsm/virt/virdomain.py", line 69, in f
ret = attr(*args, **kwargs)
File "/usr/lib/python2.7/site-packages/vdsm/libvirtconnection.py", line 123, in wrapper
ret = f(*args, **kwargs)
File "/usr/lib/python2.7/site-packages/vdsm/utils.py", line 944, in wrapper
return func(inst, *args, **kwargs)
File "/usr/lib64/python2.7/site-packages/libvirt.py", line 1939, in migrateToURI3
if ret == -1: raise libvirtError ('virDomainMigrateToURI3() failed', dom=self)
libvirtError: failed to initialize gluster connection (src=0x7fd818056a00 priv=0x7fd818057a50): Success
2017-08-25 10:38:10,568+0200 INFO (jsonrpc/0) [vdsm.api] START repoStats(options=None) from=::ffff:172.16.252.200,56136, flow_id=4ea1f85a, task_id=f1d7bdd9-c845-4715-9406-721c3c0288c0 (api:46)
2017-08-25 10:38:10,569+0200 INFO (jsonrpc/0) [vdsm.api] FINISH repoStats return={u'7c8deaa8-be02-4aaf-b9b4-ddc8da99ad96': {'code': 0, 'actual': True, 'version': 4, 'acquired': True, 'delay': '0.000289024', 'lastCheck': '6.5', 'valid': True}, u'2b2a44fc-f2bd-47cd-b7af-00be59e30a35': {'code': 0, 'actual': True, 'version': 0, 'acquired': True, 'delay': '0.000649473', 'lastCheck': '0.6', 'valid': True}, u'5d99af76-33b5-47d8-99da-1f32413c7bb0': {'code': 0, 'actual': True, 'version': 4, 'acquired': True, 'delay': '0.000305441', 'lastCheck': '1.3', 'valid': True}, u'a7fbaaad-7043-4391-9523-3bedcdc4fb0d': {'code': 0, 'actual': True, 'version': 0, 'acquired': True, 'delay': '0.000741679', 'lastCheck': '0.6', 'valid': True}} from=::ffff:172.16.252.200,56136, flow_id=4ea1f85a, task_id=f1d7bdd9-c845-4715-9406-721c3c0288c0 (api:52)
2017-08-25 10:38:10,573+0200 INFO (jsonrpc/0) [jsonrpc.JsonRpcServer] RPC call Host.getStats succeeded in 0.01 seconds (__init__:539)
2017-08-25 10:38:11,876+0200 INFO (periodic/2) [vdsm.api] START getVolumeSize(sdUUID=u'5d99af76-33b5-47d8-99da-1f32413c7bb0', spUUID=u'00000001-0001-0001-0001-0000000000b9', imgUUID=u'9c007b27-0ab7-4474-9317-a294fd04c65f', volUUID=u'ba2dfe7f-bdcf-4680-878d-7d71272f3ffb', options=None) from=internal, task_id=1ac2dd1e-52f8-4508-ab5f-01119ad7500e (api:46)
2017-08-25 10:38:11,879+0200 INFO (periodic/2) [vdsm.api] FINISH getVolumeSize return={'truesize': '8133292032', 'apparentsize': '10737418240'} from=internal, task_id=1ac2dd1e-52f8-4508-ab5f-01119ad7500e (api:52)
2017-08-25 10:38:17,369+0200 INFO (periodic/3) [vdsm.api] START repoStats(options=None) from=internal, task_id=d99fac04-c9f3-4f1e-aa79-7593119b3e48 (api:46)
2017-08-25 10:38:17,370+0200 INFO (periodic/3) [vdsm.api] FINISH repoStats return={u'7c8deaa8-be02-4aaf-b9b4-ddc8da99ad96': {'code': 0, 'actual': True, 'version': 4, 'acquired': True, 'delay': '0.000290482', 'lastCheck': '3.3', 'valid': True}, u'2b2a44fc-f2bd-47cd-b7af-00be59e30a35': {'code': 0, 'actual': True, 'version': 0, 'acquired': True, 'delay': '0.000697581', 'lastCheck': '4.4', 'valid': True}, u'5d99af76-33b5-47d8-99da-1f32413c7bb0': {'code': 0, 'actual': True, 'version': 4, 'acquired': True, 'delay': '0.000283612', 'lastCheck': '4.4', 'valid': True}, u'a7fbaaad-7043-4391-9523-3bedcdc4fb0d': {'code': 0, 'actual': True, 'version': 0, 'acquired': True, 'delay': '0.000702052', 'lastCheck': '4.3', 'valid': True}} from=internal, task_id=d99fac04-c9f3-4f1e-aa79-7593119b3e48 (api:52)
2017-08-25 10:38:17,578+0200 INFO (jsonrpc/4) [jsonrpc.JsonRpcServer] RPC call Host.getAllVmStats succeeded in 0.00 seconds (__init__:539)
2017-08-25 10:38:17,587+0200 INFO (jsonrpc/2) [jsonrpc.JsonRpcServer] RPC call Host.getAllVmIoTunePolicies succeeded in 0.00 seconds (__init__:539)
2017-08-25 10:38:17,782+0200 INFO (jsonrpc/7) [jsonrpc.JsonRpcServer] RPC call Host.getAllVmStats succeeded in 0.00 seconds (__init__:539)
2017-08-25 10:38:18,131+0200 INFO (jsonrpc/1) [jsonrpc.JsonRpcServer] RPC call VM.getMigrationStatus succeeded in 0.00 seconds (__init__:539)
2017-08-25 10:38:25,764+0200 INFO (jsonrpc/5) [vdsm.api] START repoStats(options=None) from=::ffff:172.16.252.200,56136, flow_id=55a06c87, task_id=bea15a3a-6364-47b9-8449-4e9e2776c52b (api:46)
2017-08-25 10:38:25,765+0200 INFO (jsonrpc/5) [vdsm.api] FINISH repoStats return={u'7c8deaa8-be02-4aaf-b9b4-ddc8da99ad96': {'code': 0, 'actual': True, 'version': 4, 'acquired': True, 'delay': '0.000288111', 'lastCheck': '1.7', 'valid': True}, u'2b2a44fc-f2bd-47cd-b7af-00be59e30a35': {'code': 0, 'actual': True, 'version': 0, 'acquired': True, 'delay': '0.000629532', 'lastCheck': '2.8', 'valid': True}, u'5d99af76-33b5-47d8-99da-1f32413c7bb0': {'code': 0, 'actual': True, 'version': 4, 'acquired': True, 'delay': '0.00029337', 'lastCheck': '2.8', 'valid': True}, u'a7fbaaad-7043-4391-9523-3bedcdc4fb0d': {'code': 0, 'actual': True, 'version': 0, 'acquired': True, 'delay': '0.000624865', 'lastCheck': '2.8', 'valid': True}} from=::ffff:172.16.252.200,56136, flow_id=55a06c87, task_id=bea15a3a-6364-47b9-8449-4e9e2776c52b (api:52)
2017-08-25 10:38:25,769+0200 INFO (jsonrpc/5) [jsonrpc.JsonRpcServer] RPC call Host.getStats succeeded in 0.00 seconds (__init__:539)
2017-08-25 10:38:32,443+0200 INFO (periodic/1) [vdsm.api] START repoStats(options=None) from=internal, task_id=8fa3faca-7a93-4f49-9d17-9a0a0bed7034 (api:46)
2017-08-25 10:38:32,444+0200 INFO (periodic/1) [vdsm.api] FINISH repoStats return={u'7c8deaa8-be02-4aaf-b9b4-ddc8da99ad96': {'code': 0, 'actual': True, 'version': 4, 'acquired': True, 'delay': '0.000288111', 'lastCheck': '8.4', 'valid': True}, u'2b2a44fc-f2bd-47cd-b7af-00be59e30a35': {'code': 0, 'actual': True, 'version': 0, 'acquired': True, 'delay': '0.000629532', 'lastCheck': '2.5', 'valid': True}, u'5d99af76-33b5-47d8-99da-1f32413c7bb0': {'code': 0, 'actual': True, 'version': 4, 'acquired': True, 'delay': '0.00029337', 'lastCheck': '3.1', 'valid': True}, u'a7fbaaad-7043-4391-9523-3bedcdc4fb0d': {'code': 0, 'actual': True, 'version': 0, 'acquired': True, 'delay': '0.000624865', 'lastCheck': '2.5', 'valid': True}} from=internal, task_id=8fa3faca-7a93-4f49-9d17-9a0a0bed7034 (api:52)
2017-08-25 10:38:32,582+0200 INFO (jsonrpc/6) [jsonrpc.JsonRpcServer] RPC call Host.getAllVmStats succeeded in 0.00 seconds (__init__:539)
2017-08-25 10:38:32,589+0200 INFO (jsonrpc/3) [jsonrpc.JsonRpcServer] RPC call Host.getAllVmIoTunePolicies succeeded in 0.00 seconds (__init__:539)
2017-08-25 10:38:33,127+0200 INFO (jsonrpc/0) [jsonrpc.JsonRpcServer] RPC call Host.getAllVmStats succeeded in 0.00 seconds (__init__:539)
2017-08-25 10:38:41,014+0200 INFO (jsonrpc/4) [vdsm.api] START repoStats(options=None) from=::ffff:172.16.252.200,56136, flow_id=3c1b695e, task_id=372505e3-21ec-45d3-a874-3b7fcbd5b22a (api:46)
2017-08-25 10:38:41,015+0200 INFO (jsonrpc/4) [vdsm.api] FINISH repoStats return={u'7c8deaa8-be02-4aaf-b9b4-ddc8da99ad96': {'code': 0, 'actual': True, 'version': 4, 'acquired': True, 'delay': '0.000316814', 'lastCheck': '7.0', 'valid': True}, u'2b2a44fc-f2bd-47cd-b7af-00be59e30a35': {'code': 0, 'actual': True, 'version': 0, 'acquired': True, 'delay': '0.000756828', 'lastCheck': '1.0', 'valid': True}, u'5d99af76-33b5-47d8-99da-1f32413c7bb0': {'code': 0, 'actual': True, 'version': 4, 'acquired': True, 'delay': '0.000287003', 'lastCheck': '1.7', 'valid': True}, u'a7fbaaad-7043-4391-9523-3bedcdc4fb0d': {'code': 0, 'actual': True, 'version': 0, 'acquired': True, 'delay': '0.000710851', 'lastCheck': '1.0', 'valid': True}} from=::ffff:172.16.252.200,56136, flow_id=3c1b695e, task_id=372505e3-21ec-45d3-a874-3b7fcbd5b22a (api:52)
2017-08-25 10:38:41,019+0200 INFO (jsonrpc/4) [jsonrpc.JsonRpcServer] RPC call Host.getStats succeeded in 0.00 seconds (__init__:539)
2017-08-25 10:38:47,492+0200 INFO (periodic/1) [vdsm.api] START repoStats(options=None) from=internal, task_id=f1d18d8e-a843-4bf6-bd7d-8e77f714f880 (api:46)
2017-08-25 10:38:47,493+0200 INFO (periodic/1) [vdsm.api] FINISH repoStats return={u'7c8deaa8-be02-4aaf-b9b4-ddc8da99ad96': {'code': 0, 'actual': True, 'version': 4, 'acquired': True, 'delay': '0.000324973', 'lastCheck': '3.4', 'valid': True}, u'2b2a44fc-f2bd-47cd-b7af-00be59e30a35': {'code': 0, 'actual': True, 'version': 0, 'acquired': True, 'delay': '0.000749308', 'lastCheck': '4.5', 'valid': True}, u'5d99af76-33b5-47d8-99da-1f32413c7bb0': {'code': 0, 'actual': True, 'version': 4, 'acquired': True, 'delay': '0.000389212', 'lastCheck': '4.5', 'valid': True}, u'a7fbaaad-7043-4391-9523-3bedcdc4fb0d': {'code': 0, 'actual': True, 'version': 0, 'acquired': True, 'delay': '0.000690282', 'lastCheck': '4.5', 'valid': True}} from=internal, task_id=f1d18d8e-a843-4bf6-bd7d-8e77f714f880 (api:52)
2017-08-25 10:38:47,586+0200 INFO (jsonrpc/2) [jsonrpc.JsonRpcServer] RPC call Host.getAllVmStats succeeded in 0.00 seconds (__init__:539)
2017-08-25 10:38:47,595+0200 INFO (jsonrpc/7) [jsonrpc.JsonRpcServer] RPC call Host.getAllVmIoTunePolicies succeeded in 0.00 seconds (__init__:539)
2017-08-25 10:38:48,363+0200 INFO (jsonrpc/1) [jsonrpc.JsonRpcServer] RPC call Host.getAllVmStats succeeded in 0.00 seconds (__init__:539)
2017-08-25 10:38:56,159+0200 INFO (jsonrpc/5) [vdsm.api] START repoStats(options=None) from=::ffff:172.16.252.200,56136, flow_id=68d3a369, task_id=cc81bbaa-212e-496b-a67b-e9e40d97a550 (api:46)
2017-08-25 10:38:56,159+0200 INFO (jsonrpc/5) [vdsm.api] FINISH repoStats return={u'7c8deaa8-be02-4aaf-b9b4-ddc8da99ad96': {'code': 0, 'actual': True, 'version': 4, 'acquired': True, 'delay': '0.000397142', 'lastCheck': '2.1', 'valid': True}, u'2b2a44fc-f2bd-47cd-b7af-00be59e30a35': {'code': 0, 'actual': True, 'version': 0, 'acquired': True, 'delay': '0.000675355', 'lastCheck': '3.1', 'valid': True}, u'5d99af76-33b5-47d8-99da-1f32413c7bb0': {'code': 0, 'actual': True, 'version': 4, 'acquired': True, 'delay': '0.000287524', 'lastCheck': '3.2', 'valid': True}, u'a7fbaaad-7043-4391-9523-3bedcdc4fb0d': {'code': 0, 'actual': True, 'version': 0, 'acquired': True, 'delay': '0.000782259', 'lastCheck': '3.1', 'valid': True}} from=::ffff:172.16.252.200,56136, flow_id=68d3a369, task_id=cc81bbaa-212e-496b-a67b-e9e40d97a550 (api:52)
2017-08-25 10:38:56,164+0200 INFO (jsonrpc/5) [jsonrpc.JsonRpcServer] RPC call Host.getStats succeeded in 0.01 seconds (__init__:539)
2017-08-25 10:38:59,985+0200 INFO (monitor/a7fbaaa) [IOProcessClient] Closing client ioprocess-1015 (__init__:598)
2017-08-25 10:38:59,985+0200 INFO (monitor/a7fbaaa) [IOProcessClient] Closing client ioprocess-1016 (__init__:598)
2017-08-25 10:38:59,985+0200 INFO (monitor/a7fbaaa) [IOProcessClient] Closing client ioprocess-1014 (__init__:598)
2017-08-25 10:38:59,985+0200 INFO (monitor/a7fbaaa) [IOProcessClient] Closing client ioprocess-1017 (__init__:598)
2017-08-25 10:39:02,568+0200 INFO (periodic/0) [vdsm.api] START repoStats(options=None) from=internal, task_id=898068d1-f980-40d7-875f-6c5dd000d982 (api:46)
2017-08-25 10:39:02,568+0200 INFO (periodic/0) [vdsm.api] FINISH repoStats return={u'7c8deaa8-be02-4aaf-b9b4-ddc8da99ad96': {'code': 0, 'actual': True, 'version': 4, 'acquired': True, 'delay': '0.000397142', 'lastCheck': '8.5', 'valid': True}, u'2b2a44fc-f2bd-47cd-b7af-00be59e30a35': {'code': 0, 'actual': True, 'version': 0, 'acquired': True, 'delay': '0.000675355', 'lastCheck': '2.6', 'valid': True}, u'5d99af76-33b5-47d8-99da-1f32413c7bb0': {'code': 0, 'actual': True, 'version': 4, 'acquired': True, 'delay': '0.000287524', 'lastCheck': '3.2', 'valid': True}, u'a7fbaaad-7043-4391-9523-3bedcdc4fb0d': {'code': 0, 'actual': True, 'version': 0, 'acquired': True, 'delay': '0.000782259', 'lastCheck': '2.6', 'valid': True}} from=internal, task_id=898068d1-f980-40d7-875f-6c5dd000d982 (api:52)
2017-08-25 10:39:02,589+0200 INFO (jsonrpc/6) [jsonrpc.JsonRpcServer] RPC call Host.getAllVmStats succeeded in 0.00 seconds (__init__:539)
2017-08-25 10:39:02,597+0200 INFO (jsonrpc/3) [jsonrpc.JsonRpcServer] RPC call Host.getAllVmIoTunePolicies succeeded in 0.00 seconds (__init__:539)
2017-08-25 10:39:04,505+0200 INFO (jsonrpc/0) [jsonrpc.JsonRpcServer] RPC call Host.getAllVmStats succeeded in 0.00 seconds (__init__:539)
-------------- next part --------------
2017-08-25 10:37:48,309+0200 INFO (jsonrpc/3) [vdsm.api] FINISH repoStats return={u'7c8deaa8-be02-4aaf-b9b4-ddc8da99ad96': {'code': 0, 'actual': True, 'version': 4, 'acquired': True, 'delay': '0.000208357', 'lastCheck': '3.5', 'valid': True}, u'2b2a44fc-f2bd-47cd-b7af-00be59e30a35': {'code': 0, 'actual': True, 'version': 0, 'acquired': True, 'delay': '0.000663592', 'lastCheck': '6.8', 'valid': True}, u'5d99af76-33b5-47d8-99da-1f32413c7bb0': {'code': 0, 'actual': True, 'version': 4, 'acquired': True, 'delay': '0.000325978', 'lastCheck': '5.7', 'valid': True}, u'a7fbaaad-7043-4391-9523-3bedcdc4fb0d': {'code': 0, 'actual': True, 'version': 0, 'acquired': True, 'delay': '0.000761203', 'lastCheck': '6.8', 'valid': True}} from=::1,49730, task_id=b05966ee-de36-4ea5-b689-e6c6690cbab5 (api:52)
2017-08-25 10:37:48,309+0200 INFO (jsonrpc/3) [jsonrpc.JsonRpcServer] RPC call Host.getStorageRepoStats succeeded in 0.00 seconds (__init__:539)
2017-08-25 10:37:50,315+0200 INFO (Reactor thread) [ProtocolDetector.AcceptorImpl] Accepted connection from ::1:49732 (protocoldetector:72)
2017-08-25 10:37:50,320+0200 INFO (Reactor thread) [ProtocolDetector.Detector] Detected protocol stomp from ::1:49732 (protocoldetector:127)
2017-08-25 10:37:50,320+0200 INFO (Reactor thread) [Broker.StompAdapter] Processing CONNECT request (stompreactor:103)
2017-08-25 10:37:50,320+0200 INFO (JsonRpc (StompReactor)) [Broker.StompAdapter] Subscribe command received (stompreactor:130)
2017-08-25 10:37:50,466+0200 INFO (jsonrpc/0) [jsonrpc.JsonRpcServer] RPC call Host.getHardwareInfo succeeded in 0.01 seconds (__init__:539)
2017-08-25 10:37:50,469+0200 INFO (jsonrpc/1) [vdsm.api] START repoStats(options=None) from=::1,49732, task_id=1c3c9af0-22b3-495a-aaed-b06ad91ee46c (api:46)
2017-08-25 10:37:50,469+0200 INFO (jsonrpc/1) [vdsm.api] FINISH repoStats return={u'7c8deaa8-be02-4aaf-b9b4-ddc8da99ad96': {'code': 0, 'actual': True, 'version': 4, 'acquired': True, 'delay': '0.000208357', 'lastCheck': '5.7', 'valid': True}, u'2b2a44fc-f2bd-47cd-b7af-00be59e30a35': {'code': 0, 'actual': True, 'version': 0, 'acquired': True, 'delay': '0.000663592', 'lastCheck': '8.9', 'valid': True}, u'5d99af76-33b5-47d8-99da-1f32413c7bb0': {'code': 0, 'actual': True, 'version': 4, 'acquired': True, 'delay': '0.000325978', 'lastCheck': '7.9', 'valid': True}, u'a7fbaaad-7043-4391-9523-3bedcdc4fb0d': {'code': 0, 'actual': True, 'version': 0, 'acquired': True, 'delay': '0.000761203', 'lastCheck': '0.5', 'valid': True}} from=::1,49732, task_id=1c3c9af0-22b3-495a-aaed-b06ad91ee46c (api:52)
2017-08-25 10:37:50,470+0200 INFO (jsonrpc/1) [jsonrpc.JsonRpcServer] RPC call Host.getStorageRepoStats succeeded in 0.00 seconds (__init__:539)
2017-08-25 10:37:52,507+0200 INFO (Reactor thread) [ProtocolDetector.AcceptorImpl] Accepted connection from ::1:49734 (protocoldetector:72)
2017-08-25 10:37:52,512+0200 INFO (Reactor thread) [ProtocolDetector.Detector] Detected protocol stomp from ::1:49734 (protocoldetector:127)
2017-08-25 10:37:52,512+0200 INFO (Reactor thread) [Broker.StompAdapter] Processing CONNECT request (stompreactor:103)
2017-08-25 10:37:52,513+0200 INFO (JsonRpc (StompReactor)) [Broker.StompAdapter] Subscribe command received (stompreactor:130)
2017-08-25 10:37:52,669+0200 INFO (jsonrpc/5) [jsonrpc.JsonRpcServer] RPC call Host.getHardwareInfo succeeded in 0.00 seconds (__init__:539)
2017-08-25 10:37:56,078+0200 INFO (Reactor thread) [ProtocolDetector.AcceptorImpl] Accepted connection from ::1:49752 (protocoldetector:72)
2017-08-25 10:37:56,083+0200 INFO (Reactor thread) [ProtocolDetector.Detector] Detected protocol stomp from ::1:49752 (protocoldetector:127)
2017-08-25 10:37:56,083+0200 INFO (Reactor thread) [Broker.StompAdapter] Processing CONNECT request (stompreactor:103)
2017-08-25 10:37:56,084+0200 INFO (JsonRpc (StompReactor)) [Broker.StompAdapter] Subscribe command received (stompreactor:130)
2017-08-25 10:37:56,223+0200 INFO (Reactor thread) [ProtocolDetector.AcceptorImpl] Accepted connection from ::1:49754 (protocoldetector:72)
2017-08-25 10:37:56,224+0200 INFO (jsonrpc/4) [jsonrpc.JsonRpcServer] RPC call Host.getHardwareInfo succeeded in 0.00 seconds (__init__:539)
2017-08-25 10:37:56,227+0200 INFO (jsonrpc/2) [vdsm.api] START repoStats(options=None) from=::1,49752, task_id=1f25f05c-5918-4301-96a9-b4561c103b54 (api:46)
2017-08-25 10:37:56,227+0200 INFO (jsonrpc/2) [vdsm.api] FINISH repoStats return={u'7c8deaa8-be02-4aaf-b9b4-ddc8da99ad96': {'code': 0, 'actual': True, 'version': 4, 'acquired': True, 'delay': '0.000252271', 'lastCheck': '1.5', 'valid': True}, u'2b2a44fc-f2bd-47cd-b7af-00be59e30a35': {'code': 0, 'actual': True, 'version': 0, 'acquired': True, 'delay': '0.000661413', 'lastCheck': '4.7', 'valid': True}, u'5d99af76-33b5-47d8-99da-1f32413c7bb0': {'code': 0, 'actual': True, 'version': 4, 'acquired': True, 'delay': '0.000300337', 'lastCheck': '3.6', 'valid': True}, u'a7fbaaad-7043-4391-9523-3bedcdc4fb0d': {'code': 0, 'actual': True, 'version': 0, 'acquired': True, 'delay': '0.000906657', 'lastCheck': '4.7', 'valid': True}} from=::1,49752, task_id=1f25f05c-5918-4301-96a9-b4561c103b54 (api:52)
2017-08-25 10:37:56,230+0200 INFO (Reactor thread) [ProtocolDetector.Detector] Detected protocol stomp from ::1:49754 (protocoldetector:127)
2017-08-25 10:37:56,230+0200 INFO (Reactor thread) [Broker.StompAdapter] Processing CONNECT request (stompreactor:103)
2017-08-25 10:37:56,231+0200 INFO (JsonRpc (StompReactor)) [Broker.StompAdapter] Subscribe command received (stompreactor:130)
2017-08-25 10:37:56,384+0200 INFO (jsonrpc/7) [jsonrpc.JsonRpcServer] RPC call Host.getHardwareInfo succeeded in 0.00 seconds (__init__:539)
2017-08-25 10:37:56,386+0200 INFO (jsonrpc/2) [jsonrpc.JsonRpcServer] RPC call Host.getStats succeeded in 0.16 seconds (__init__:539)
2017-08-25 10:37:56,482+0200 INFO (jsonrpc/6) [jsonrpc.JsonRpcServer] RPC call Host.getCapabilities succeeded in 0.09 seconds (__init__:539)
2017-08-25 10:37:56,501+0200 INFO (jsonrpc/3) [jsonrpc.JsonRpcServer] RPC call Host.getAllVmStats succeeded in 0.00 seconds (__init__:539)
2017-08-25 10:37:57,827+0200 INFO (Reactor thread) [ProtocolDetector.AcceptorImpl] Accepted connection from ::1:49756 (protocoldetector:72)
2017-08-25 10:37:57,839+0200 INFO (Reactor thread) [ProtocolDetector.Detector] Detected protocol stomp from ::1:49756 (protocoldetector:127)
2017-08-25 10:37:57,839+0200 INFO (Reactor thread) [Broker.StompAdapter] Processing CONNECT request (stompreactor:103)
2017-08-25 10:37:57,842+0200 INFO (JsonRpc (StompReactor)) [Broker.StompAdapter] Subscribe command received (stompreactor:130)
2017-08-25 10:37:57,966+0200 INFO (jsonrpc/0) [jsonrpc.JsonRpcServer] RPC call Host.getHardwareInfo succeeded in 0.00 seconds (__init__:539)
2017-08-25 10:37:57,968+0200 INFO (jsonrpc/1) [jsonrpc.JsonRpcServer] RPC call VM.getStats failed (error 1) in 0.00 seconds (__init__:539)
2017-08-25 10:37:59,724+0200 INFO (jsonrpc/5) [jsonrpc.JsonRpcServer] RPC call Host.getAllVmStats succeeded in 0.00 seconds (__init__:539)
2017-08-25 10:38:00,252+0200 INFO (periodic/1) [vdsm.api] START repoStats(options=None) from=internal, task_id=9da83403-b6de-4822-90bc-a1a221e44d34 (api:46)
2017-08-25 10:38:00,253+0200 INFO (periodic/1) [vdsm.api] FINISH repoStats return={u'7c8deaa8-be02-4aaf-b9b4-ddc8da99ad96': {'code': 0, 'actual': True, 'version': 4, 'acquired': True, 'delay': '0.000252271', 'lastCheck': '5.5', 'valid': True}, u'2b2a44fc-f2bd-47cd-b7af-00be59e30a35': {'code': 0, 'actual': True, 'version': 0, 'acquired': True, 'delay': '0.000661413', 'lastCheck': '8.7', 'valid': True}, u'5d99af76-33b5-47d8-99da-1f32413c7bb0': {'code': 0, 'actual': True, 'version': 4, 'acquired': True, 'delay': '0.000300337', 'lastCheck': '7.6', 'valid': True}, u'a7fbaaad-7043-4391-9523-3bedcdc4fb0d': {'code': 0, 'actual': True, 'version': 0, 'acquired': True, 'delay': '0.000906657', 'lastCheck': '0.3', 'valid': True}} from=internal, task_id=9da83403-b6de-4822-90bc-a1a221e44d34 (api:52)
2017-08-25 10:38:02,793+0200 INFO (Reactor thread) [ProtocolDetector.AcceptorImpl] Accepted connection from ::ffff:172.16.252.122:45736 (protocoldetector:72)
2017-08-25 10:38:02,799+0200 INFO (Reactor thread) [ProtocolDetector.Detector] Detected protocol stomp from ::ffff:172.16.252.122:45736 (protocoldetector:127)
2017-08-25 10:38:02,799+0200 INFO (Reactor thread) [Broker.StompAdapter] Processing CONNECT request (stompreactor:103)
2017-08-25 10:38:02,800+0200 INFO (JsonRpc (StompReactor)) [Broker.StompAdapter] Subscribe command received (stompreactor:130)
2017-08-25 10:38:02,961+0200 INFO (jsonrpc/4) [jsonrpc.JsonRpcServer] RPC call Host.ping succeeded in 0.00 seconds (__init__:539)
2017-08-25 10:38:02,993+0200 INFO (jsonrpc/7) [vdsm.api] START __init__(cif=<clientIF.clientIF object at 0x3b66150>, params={u'guestFQDN': u'test-vm-xenial1', u'acpiEnable': u'true', u'emulatedMachine': u'pc-i440fx-rhel7.3.0', u'afterMigrationStatus': u'', u'enableGuestEvents': True, u'vmId': u'ffb71f79-54cd-4f0e-b6b5-3670236cb497', u'elapsedTimeOffset': 73081.72497606277, u'guestDiskMapping': {u'0QEMU_QEMU_HARDDISK_9c007b27-0ab7-4474-9': {u'name': u'/dev/sda'}, u'QEMU_DVD-ROM_QM00003': {u'name': u'/dev/sr0'}}, u'transparentHugePages': u'true', u'timeOffset': u'7200', u'cpuType': u'Haswell-noTSX', u'custom': {}, u'pauseCode': u'NOERR', u'migrationDest': u'libvirt', u'guestNumaNodes': [{u'nodeIndex': 0, u'cpus': u'0,1', u'memory': u'2048'}], u'display': u'qxl', u'smp': u'2', u'vmType': u'kvm', u'_srcDomXML': u'<domain type=\'kvm\' id=\'1\'>\n <name>test-vm-xenial1</name>\n <uuid>ffb71f79-54cd-4f0e-b6b5-3670236cb497</uuid>\n <metadata xmlns:ovirt="http://ovirt.org/vm/tune/1.0">\n <ovirt:qos/>\n </metadata>\n <maxMemory slots=\'16\' unit=\'KiB\'>8388608</maxMemory>\n <memory unit=\'KiB\'>2097152</memory>\n <currentMemory unit=\'KiB\'>2097152</currentMemory>\n <vcpu placement=\'static\' current=\'2\'>32</vcpu>\n <iothreads>2</iothreads>\n <cputune>\n <shares>1020</shares>\n </cputune>\n <resource>\n <partition>/machine</partition>\n </resource>\n <sysinfo type=\'smbios\'>\n <system>\n <entry name=\'manufacturer\'>oVirt</entry>\n <entry name=\'product\'>oVirt Node</entry>\n <entry name=\'version\'>7-3.1611.el7.centos</entry>\n <entry name=\'serial\'>00000000-0000-0000-0000-002590EAF566</entry>\n <entry name=\'uuid\'>ffb71f79-54cd-4f0e-b6b5-3670236cb497</entry>\n </system>\n </sysinfo>\n <os>\n <type arch=\'x86_64\' machine=\'pc-i440fx-rhel7.3.0\'>hvm</type>\n <bootmenu enable=\'yes\' timeout=\'10000\'/>\n <smbios mode=\'sysinfo\'/>\n </os>\n <features>\n <acpi/>\n </features>\n <cpu mode=\'custom\' match=\'exact\'>\n <model fallback=\'allow\'>Haswell-noTSX</model>\n <topology sockets=\'16\' cores=\'2\' threads=\'1\'/>\n <numa>\n <cell id=\'0\' cpus=\'0-1\' memory=\'2097152\' unit=\'KiB\'/>\n </numa>\n </cpu>\n <clock offset=\'variable\' adjustment=\'7200\' basis=\'utc\'>\n <timer name=\'rtc\' tickpolicy=\'catchup\'/>\n <timer name=\'pit\' tickpolicy=\'delay\'/>\n <timer name=\'hpet\' present=\'no\'/>\n </clock>\n <on_poweroff>destroy</on_poweroff>\n <on_reboot>restart</on_reboot>\n <on_crash>destroy</on_crash>\n <devices>\n <emulator>/usr/libexec/qemu-kvm</emulator>\n <disk type=\'file\' device=\'cdrom\'>\n <driver name=\'qemu\' type=\'raw\'/>\n <source startupPolicy=\'optional\'/>\n <backingStore/>\n <target dev=\'hdc\' bus=\'ide\'/>\n <readonly/>\n <alias name=\'ide0-1-0\'/>\n <address type=\'drive\' controller=\'0\' bus=\'1\' target=\'0\' unit=\'0\'/>\n </disk>\n <disk type=\'network\' device=\'disk\' snapshot=\'no\'>\n <driver name=\'qemu\' type=\'raw\' cache=\'none\' error_policy=\'stop\' io=\'threads\'/>\n <source protocol=\'gluster\' name=\'gv0/5d99af76-33b5-47d8-99da-1f32413c7bb0/images/9c007b27-0ab7-4474-9317-a294fd04c65f/ba2dfe7f-bdcf-4680-878d-7d71272f3ffb\'>\n <host name=\'glusterfs.rxmgmt.databay.de\' port=\'0\'/>\n </source>\n <backingStore/>\n <target dev=\'sda\' bus=\'scsi\'/>\n <serial>9c007b27-0ab7-4474-9317-a294fd04c65f</serial>\n <boot order=\'1\'/>\n <alias name=\'scsi0-0-0-0\'/>\n <address type=\'drive\' controller=\'0\' bus=\'0\' target=\'0\' unit=\'0\'/>\n </disk>\n <controller type=\'usb\' index=\'0\' model=\'piix3-uhci\'>\n <alias name=\'usb\'/>\n <address type=\'pci\' domain=\'0x0000\' bus=\'0x00\' slot=\'0x01\' function=\'0x2\'/>\n </controller>\n <controller type=\'scsi\' index=\'0\' model=\'virtio-scsi\'>\n <alias name=\'scsi0\'/>\n <address type=\'pci\' domain=\'0x0000\' bus=\'0x00\' slot=\'0x04\' function=\'0x0\'/>\n </controller>\n <controller type=\'scsi\' index=\'1\' model=\'virtio-scsi\'>\n <driver iothread=\'1\'/>\n <alias name=\'scsi1\'/>\n <address type=\'pci\' domain=\'0x0000\' bus=\'0x00\' slot=\'0x06\' function=\'0x0\'/>\n </controller>\n <controller type=\'virtio-serial\' index=\'0\' ports=\'16\'>\n <alias name=\'virtio-serial0\'/>\n <address type=\'pci\' domain=\'0x0000\' bus=\'0x00\' slot=\'0x05\' function=\'0x0\'/>\n </controller>\n <controller type=\'pci\' index=\'0\' model=\'pci-root\'>\n <alias name=\'pci.0\'/>\n </controller>\n <controller type=\'ide\' index=\'0\'>\n <alias name=\'ide\'/>\n <address type=\'pci\' domain=\'0x0000\' bus=\'0x00\' slot=\'0x01\' function=\'0x1\'/>\n </controller>\n <interface type=\'bridge\'>\n <mac address=\'00:1a:4a:16:01:5b\'/>\n <source bridge=\'ovirtmgmt\'/>\n <target dev=\'vnet0\'/>\n <model type=\'virtio\'/>\n <link state=\'up\'/>\n <boot order=\'3\'/>\n <alias name=\'net0\'/>\n <address type=\'pci\' domain=\'0x0000\' bus=\'0x00\' slot=\'0x09\' function=\'0x0\'/>\n </interface>\n <interface type=\'bridge\'>\n <mac address=\'52:54:00:e9:24:3f\'/>\n <source bridge=\'br0\'/>\n <target dev=\'vnet1\'/>\n <model type=\'virtio\'/>\n <link state=\'up\'/>\n <boot order=\'2\'/>\n <alias name=\'net1\'/>\n <address type=\'pci\' domain=\'0x0000\' bus=\'0x00\' slot=\'0x03\' function=\'0x0\'/>\n </interface>\n <channel type=\'unix\'>\n <source mode=\'bind\' path=\'/var/lib/libvirt/qemu/channels/ffb71f79-54cd-4f0e-b6b5-3670236cb497.com.redhat.rhevm.vdsm\'/>\n <target type=\'virtio\' name=\'com.redhat.rhevm.vdsm\' state=\'connected\'/>\n <alias name=\'channel0\'/>\n <address type=\'virtio-serial\' controller=\'0\' bus=\'0\' port=\'1\'/>\n </channel>\n <channel type=\'unix\'>\n <source mode=\'bind\' path=\'/var/lib/libvirt/qemu/channels/ffb71f79-54cd-4f0e-b6b5-3670236cb497.org.qemu.guest_agent.0\'/>\n <target type=\'virtio\' name=\'org.qemu.guest_agent.0\' state=\'disconnected\'/>\n <alias name=\'channel1\'/>\n <address type=\'virtio-serial\' controller=\'0\' bus=\'0\' port=\'2\'/>\n </channel>\n <channel type=\'spicevmc\'>\n <target type=\'virtio\' name=\'com.redhat.spice.0\' state=\'disconnected\'/>\n <alias name=\'channel2\'/>\n <address type=\'virtio-serial\' controller=\'0\' bus=\'0\' port=\'3\'/>\n </channel>\n <input type=\'mouse\' bus=\'ps2\'>\n <alias name=\'input0\'/>\n </input>\n <input type=\'keyboard\' bus=\'ps2\'>\n <alias name=\'input1\'/>\n </input>\n <graphics type=\'spice\' tlsPort=\'5900\' autoport=\'yes\' listen=\'172.16.252.122\' defaultMode=\'secure\' passwdValidTo=\'1970-01-01T00:00:01\'>\n <listen type=\'network\' address=\'172.16.252.122\' network=\'vdsm-ovirtmgmt\'/>\n <channel name=\'main\' mode=\'secure\'/>\n <channel name=\'display\' mode=\'secure\'/>\n <channel name=\'inputs\' mode=\'secure\'/>\n <channel name=\'cursor\' mode=\'secure\'/>\n <channel name=\'playback\' mode=\'secure\'/>\n <channel name=\'record\' mode=\'secure\'/>\n <channel name=\'smartcard\' mode=\'secure\'/>\n <channel name=\'usbredir\' mode=\'secure\'/>\n </graphics>\n <video>\n <model type=\'qxl\' ram=\'65536\' vram=\'8192\' vgamem=\'16384\' heads=\'1\' primary=\'yes\'/>\n <alias name=\'video0\'/>\n <address type=\'pci\' domain=\'0x0000\' bus=\'0x00\' slot=\'0x02\' function=\'0x0\'/>\n </video>\n <memballoon model=\'virtio\'>\n <alias name=\'balloon0\'/>\n <address type=\'pci\' domain=\'0x0000\' bus=\'0x00\' slot=\'0x07\' function=\'0x0\'/>\n </memballoon>\n <rng model=\'virtio\'>\n <backend model=\'random\'>/dev/urandom</backend>\n <alias name=\'rng0\'/>\n <address type=\'pci\' domain=\'0x0000\' bus=\'0x00\' slot=\'0x08\' function=\'0x0\'/>\n </rng>\n </devices>\n <seclabel type=\'none\' model=\'none\'/>\n <seclabel type=\'dynamic\' model=\'dac\' relabel=\'yes\'>\n <label>+107:+107</label>\n <imagelabel>+107:+107</imagelabel>\n </seclabel>\n</domain>\n', u'memSize': 2048, u'smpCoresPerSocket': u'2', u'vmName': u'test-vm-xenial1', u'nice': u'0', u'username': u'None', u'maxMemSize': 8192, u'bootMenuEnable': u'true', u'numOfIoThreads': 2, u'smpThreadsPerCore': u'1', u'smartcardEnable': u'false', u'clientIp': u'', u'guestAgentAPIVersion': 2, u'kvmEnable': u'true', u'displayNetwork': u'ovirtmgmt', u'devices': [{u'target': 2097152, u'alias': u'balloon0', u'specParams': {u'model': u'virtio'}, u'deviceId': u'34e2ded1-70fa-4373-b1ab-1fe8e0bb801a', u'address': {u'function': u'0x0', u'bus': u'0x00', u'domain': u'0x0000', u'type': u'pci', u'slot': u'0x07'}, u'device': u'memballoon', u'type': u'balloon'}, {u'alias': u'rng0', u'specParams': {u'source': u'urandom'}, u'deviceId': u'585205f6-b2f0-40c4-8897-c8ccf9459729', u'address': {u'slot': u'0x08', u'bus': u'0x00', u'domain': u'0x0000', u'type': u'pci', u'function': u'0x0'}, u'device': u'virtio', u'model': u'virtio', u'type': u'rng'}, {u'index': u'0', u'alias': u'usb', u'specParams': {}, u'deviceId': u'b10c9687-f4b7-429e-84be-3b9765845e91', u'address': {u'slot': u'0x01', u'bus': u'0x00', u'domain': u'0x0000', u'type': u'pci', u'function': u'0x2'}, u'device': u'usb', u'model': u'piix3-uhci', u'type': u'controller'}, {u'index': u'0', u'alias': u'scsi0', u'specParams': {u'ioThreadId': 0}, u'deviceId': u'22d33ccf-a15f-4656-9357-44e34d5e1263', u'address': {u'slot': u'0x04', u'bus': u'0x00', u'domain': u'0x0000', u'type': u'pci', u'function': u'0x0'}, u'device': u'scsi', u'model': u'virtio-scsi', u'type': u'controller'}, {u'index': u'1', u'alias': u'scsi1', u'specParams': {u'ioThreadId': 1}, u'deviceId': u'45834565-f9d2-46ab-8377-2afea5e7e3c2', u'address': {u'slot': u'0x06', u'bus': u'0x00', u'domain': u'0x0000', u'type': u'pci', u'function': u'0x0'}, u'device': u'scsi', u'model': u'virtio-scsi', u'type': u'controller'}, {u'alias': u'virtio-serial0', u'specParams': {}, u'deviceId': u'db193f79-7a42-4476-ba5a-394a4b940e7e', u'address': {u'slot': u'0x05', u'bus': u'0x00', u'domain': u'0x0000', u'type': u'pci', u'function': u'0x0'}, u'device': u'virtio-serial', u'type': u'controller'}, {u'alias': u'video0', u'specParams': {u'vram': u'8192', u'vgamem': u'16384', u'heads': u'1', u'ram': u'65536'}, u'deviceId': u'47d3a5a5-1123-42a7-a510-5c68c6341dc0', u'address': {u'slot': u'0x02', u'bus': u'0x00', u'domain': u'0x0000', u'type': u'pci', u'function': u'0x0'}, u'device': u'qxl', u'type': u'video'}, {u'device': u'spice', u'specParams': {u'fileTransferEnable': u'true', u'spiceSecureChannels': u'smain,sinputs,scursor,splayback,srecord,sdisplay,ssmartcard,susbredir', u'displayNetwork': u'ovirtmgmt', u'displayIp': u'172.16.252.122', u'copyPasteEnable': u'true'}, u'type': u'graphics', u'deviceId': u'2802e843-5262-4b1f-a587-eb905a6dbd68', u'tlsPort': u'5900'}, {u'nicModel': u'pv', u'macAddr': u'00:1a:4a:16:01:5b', u'linkActive': True, u'network': u'ovirtmgmt', u'alias': u'net0', u'bootOrder': u'3', u'specParams': {u'inbound': {}, u'outbound': {}}, u'deviceId': u'18a4fcdf-46d9-4be6-9b41-42f17bc574bd', u'address': {u'slot': u'0x09', u'bus': u'0x00', u'domain': u'0x0000', u'type': u'pci', u'function': u'0x0'}, u'device': u'bridge', u'type': u'interface', u'name': u'vnet0'}, {u'nicModel': u'pv', u'macAddr': u'52:54:00:e9:24:3f', u'linkActive': True, u'network': u'br0', u'alias': u'net1', u'bootOrder': u'2', u'specParams': {u'inbound': {}, u'outbound': {}}, u'deviceId': u'b7236c25-c03c-4538-a099-38075c1bf299', u'address': {u'slot': u'0x03', u'bus': u'0x00', u'domain': u'0x0000', u'type': u'pci', u'function': u'0x0'}, u'device': u'bridge', u'type': u'interface', u'name': u'vnet1'}, {u'index': u'2', u'iface': u'ide', u'specParams': {u'path': u'grml64-full_2014.11.iso'}, u'readonly': u'true', u'deviceId': u'740f9592-f456-4e05-8a60-981bf3cc73dd', u'address': {u'bus': u'1', u'controller': u'0', u'type': u'drive', u'target': u'0', u'unit': u'0'}, u'device': u'cdrom', u'shared': u'false', u'path': u'/rhev/data-center/mnt/nfs-mds.rxmgmt.databay.de:_NFS_ISO/a7fbaaad-7043-4391-9523-3bedcdc4fb0d/images/11111111-1111-1111-1111-111111111111/grml64-full_2014.11.iso', u'type': u'disk'}, {u'protocol': u'gluster', u'poolID': u'00000001-0001-0001-0001-0000000000b9', u'volumeInfo': {u'path': u'gv0/5d99af76-33b5-47d8-99da-1f32413c7bb0/images/9c007b27-0ab7-4474-9317-a294fd04c65f/ba2dfe7f-bdcf-4680-878d-7d71272f3ffb', u'protocol': u'gluster', u'hosts': [{u'port': u'0', u'transport': u'tcp', u'name': u'glusterfs.rxmgmt.databay.de'}, {u'port': u'0', u'transport': u'tcp', u'name': u'microcloud25.rxmgmt.databay.de'}, {u'port': u'0', u'transport': u'tcp', u'name': u'microcloud27.rxmgmt.databay.de'}, {u'port': u'0', u'transport': u'tcp', u'name': u'microcloud26.rxmgmt.databay.de'}, {u'port': u'0', u'transport': u'tcp', u'name': u'microcloud28.rxmgmt.databay.de'}, {u'port': u'0', u'transport': u'tcp', u'name': u'microcloud21.rxmgmt.databay.de'}, {u'port': u'0', u'transport': u'tcp', u'name': u'microcloud23.rxmgmt.databay.de'}, {u'port': u'0', u'transport': u'tcp', u'name': u'microcloud22.rxmgmt.databay.de'}, {u'port': u'0', u'transport': u'tcp', u'name': u'microcloud24.rxmgmt.databay.de'}]}, u'index': 0, u'iface': u'scsi', u'apparentsize': u'10737418240', u'alias': u'scsi0-0-0-0', u'imageID': u'9c007b27-0ab7-4474-9317-a294fd04c65f', u'readonly': u'False', u'shared': u'false', u'truesize': u'8133242880', u'type': u'disk', u'domainID': u'5d99af76-33b5-47d8-99da-1f32413c7bb0', u'reqsize': u'0', u'format': u'raw', u'deviceId': u'9c007b27-0ab7-4474-9317-a294fd04c65f', u'address': {u'bus': u'0', u'controller': u'0', u'type': u'drive', u'target': u'0', u'unit': u'0'}, u'device': u'disk', u'path': u'gv0/5d99af76-33b5-47d8-99da-1f32413c7bb0/images/9c007b27-0ab7-4474-9317-a294fd04c65f/ba2dfe7f-bdcf-4680-878d-7d71272f3ffb', u'propagateErrors': u'off', u'optional': u'false', u'name': u'sda', u'bootOrder': u'1', u'volumeID': u'ba2dfe7f-bdcf-4680-878d-7d71272f3ffb', u'diskType': u'network', u'specParams': {}, u'hosts': [{u'port': u'0', u'transport': u'tcp', u'name': u'glusterfs.rxmgmt.databay.de'}], u'discard': False, u'volumeChain': [{u'domainID': u'5d99af76-33b5-47d8-99da-1f32413c7bb0', u'leaseOffset': 0, u'volumeID': u'ba2dfe7f-bdcf-4680-878d-7d71272f3ffb', u'leasePath': u'/rhev/data-center/mnt/glusterSD/glusterfs.rxmgmt.databay.de:_gv0/5d99af76-33b5-47d8-99da-1f32413c7bb0/images/9c007b27-0ab7-4474-9317-a294fd04c65f/ba2dfe7f-bdcf-4680-878d-7d71272f3ffb.lease', u'imageID': u'9c007b27-0ab7-4474-9317-a294fd04c65f', u'path': u'gv0/5d99af76-33b5-47d8-99da-1f32413c7bb0/images/9c007b27-0ab7-4474-9317-a294fd04c65f/ba2dfe7f-bdcf-4680-878d-7d71272f3ffb'}]}, {u'iface': u'ide', u'name': u'hdc', u'alias': u'ide0-1-0', u'readonly': u'True', u'address': {u'bus': u'1', u'controller': u'0', u'type': u'drive', u'target': u'0', u'unit': u'0'}, u'device': u'cdrom', u'path': u'', u'type': u'disk'}, {u'device': u'ide', u'alias': u'ide', u'type': u'controller', u'address': {u'slot': u'0x01', u'bus': u'0x00', u'domain': u'0x0000', u'type': u'pci', u'function': u'0x1'}}, {u'device': u'unix', u'alias': u'channel0', u'type': u'channel', u'address': {u'bus': u'0', u'controller': u'0', u'type': u'virtio-serial', u'port': u'1'}}, {u'device': u'unix', u'alias': u'channel1', u'type': u'channel', u'address': {u'bus': u'0', u'controller': u'0', u'type': u'virtio-serial', u'port': u'2'}}, {u'device': u'spicevmc', u'alias': u'channel2', u'type': u'channel', u'address': {u'bus': u'0', u'controller': u'0', u'type': u'virtio-serial', u'port': u'3'}}], u'memGuaranteedSize': 1365, u'status': u'Up', u'maxVCpus': u'32', u'guestIPs': u'172.16.252.99 192.168.252.12', u'statusTime': u'4370238600', u'maxMemSlots': 16}, recover=False) from=::ffff:172.16.252.122,45736 (api:46)
2017-08-25 10:38:02,994+0200 INFO (jsonrpc/7) [vdsm.api] FINISH __init__ return=None from=::ffff:172.16.252.122,45736 (api:52)
2017-08-25 10:38:02,996+0200 INFO (vm/ffb71f79) [virt.vm] (vmId='ffb71f79-54cd-4f0e-b6b5-3670236cb497') VM wrapper has started (vm:1930)
2017-08-25 10:38:02,997+0200 INFO (vm/ffb71f79) [vdsm.api] START getVolumeSize(sdUUID=u'5d99af76-33b5-47d8-99da-1f32413c7bb0', spUUID=u'00000001-0001-0001-0001-0000000000b9', imgUUID=u'9c007b27-0ab7-4474-9317-a294fd04c65f', volUUID=u'ba2dfe7f-bdcf-4680-878d-7d71272f3ffb', options=None) from=internal, task_id=a688188b-833f-4295-b9c2-a1f8927a3ca1 (api:46)
2017-08-25 10:38:02,999+0200 INFO (vm/ffb71f79) [vdsm.api] FINISH getVolumeSize return={'truesize': '8133292032', 'apparentsize': '10737418240'} from=internal, task_id=a688188b-833f-4295-b9c2-a1f8927a3ca1 (api:52)
2017-08-25 10:38:03,000+0200 INFO (vm/ffb71f79) [vds] prepared volume path: /rhev/data-center/mnt/nfs-mds.rxmgmt.databay.de:_NFS_ISO/a7fbaaad-7043-4391-9523-3bedcdc4fb0d/images/11111111-1111-1111-1111-111111111111/grml64-full_2014.11.iso (clientIF:380)
2017-08-25 10:38:03,000+0200 INFO (vm/ffb71f79) [vdsm.api] START prepareImage(sdUUID=u'5d99af76-33b5-47d8-99da-1f32413c7bb0', spUUID=u'00000001-0001-0001-0001-0000000000b9', imgUUID=u'9c007b27-0ab7-4474-9317-a294fd04c65f', leafUUID=u'ba2dfe7f-bdcf-4680-878d-7d71272f3ffb', allowIllegal=False) from=internal, task_id=4e6c2050-a290-4ad5-8504-72651c4b219a (api:46)
2017-08-25 10:38:03,402+0200 INFO (vm/ffb71f79) [vdsm.api] FINISH prepareImage return={'info': {'path': u'gv0/5d99af76-33b5-47d8-99da-1f32413c7bb0/images/9c007b27-0ab7-4474-9317-a294fd04c65f/ba2dfe7f-bdcf-4680-878d-7d71272f3ffb', 'protocol': 'gluster', 'hosts': [{'port': '0', 'transport': 'tcp', 'name': 'glusterfs.rxmgmt.databay.de'}, {'port': '0', 'transport': 'tcp', 'name': 'microcloud25.rxmgmt.databay.de'}, {'port': '0', 'transport': 'tcp', 'name': 'microcloud27.rxmgmt.databay.de'}, {'port': '0', 'transport': 'tcp', 'name': 'microcloud26.rxmgmt.databay.de'}, {'port': '0', 'transport': 'tcp', 'name': 'microcloud28.rxmgmt.databay.de'}, {'port': '0', 'transport': 'tcp', 'name': 'microcloud21.rxmgmt.databay.de'}, {'port': '0', 'transport': 'tcp', 'name': 'microcloud23.rxmgmt.databay.de'}, {'port': '0', 'transport': 'tcp', 'name': 'microcloud22.rxmgmt.databay.de'}, {'port': '0', 'transport': 'tcp', 'name': 'microcloud24.rxmgmt.databay.de'}]}, 'path': u'/rhev/data-center/00000001-0001-0001-0001-0000000000b9/5d99af76-33b5-47d8-99da-1f32413c7bb0/images/9c007b27-0ab7-4474-9317-a294fd04c65f/ba2dfe7f-bdcf-4680-878d-7d71272f3ffb', 'imgVolumesInfo': [{'domainID': u'5d99af76-33b5-47d8-99da-1f32413c7bb0', 'leaseOffset': 0, 'path': u'/rhev/data-center/mnt/glusterSD/glusterfs.rxmgmt.databay.de:_gv0/5d99af76-33b5-47d8-99da-1f32413c7bb0/images/9c007b27-0ab7-4474-9317-a294fd04c65f/ba2dfe7f-bdcf-4680-878d-7d71272f3ffb', 'volumeID': u'ba2dfe7f-bdcf-4680-878d-7d71272f3ffb', 'leasePath': u'/rhev/data-center/mnt/glusterSD/glusterfs.rxmgmt.databay.de:_gv0/5d99af76-33b5-47d8-99da-1f32413c7bb0/images/9c007b27-0ab7-4474-9317-a294fd04c65f/ba2dfe7f-bdcf-4680-878d-7d71272f3ffb.lease', 'imageID': u'9c007b27-0ab7-4474-9317-a294fd04c65f'}]} from=internal, task_id=4e6c2050-a290-4ad5-8504-72651c4b219a (api:52)
2017-08-25 10:38:03,402+0200 INFO (vm/ffb71f79) [vds] prepared volume path: gv0/5d99af76-33b5-47d8-99da-1f32413c7bb0/images/9c007b27-0ab7-4474-9317-a294fd04c65f/ba2dfe7f-bdcf-4680-878d-7d71272f3ffb (clientIF:380)
2017-08-25 10:38:03,402+0200 INFO (vm/ffb71f79) [vds] prepared volume path: (clientIF:380)
2017-08-25 10:38:03,413+0200 ERROR (jsonrpc/7) [virt.vm] (vmId='ffb71f79-54cd-4f0e-b6b5-3670236cb497') Alias not found for device type disk during migration at destination host (vm:4587)
2017-08-25 10:38:03,478+0200 INFO (jsonrpc/7) [root] (hooks:108)
2017-08-25 10:38:03,492+0200 INFO (jsonrpc/7) [jsonrpc.JsonRpcServer] RPC call VM.migrationCreate succeeded in 0.51 seconds (__init__:539)
2017-08-25 10:38:03,669+0200 INFO (jsonrpc/2) [vdsm.api] START destroy(gracefulAttempts=1) from=::ffff:172.16.252.122,45736 (api:46)
2017-08-25 10:38:03,669+0200 INFO (jsonrpc/2) [virt.vm] (vmId='ffb71f79-54cd-4f0e-b6b5-3670236cb497') Release VM resources (vm:4254)
2017-08-25 10:38:03,670+0200 INFO (jsonrpc/2) [virt.vm] (vmId='ffb71f79-54cd-4f0e-b6b5-3670236cb497') Stopping connection (guestagent:430)
2017-08-25 10:38:03,671+0200 INFO (jsonrpc/2) [vdsm.api] START teardownImage(sdUUID=u'5d99af76-33b5-47d8-99da-1f32413c7bb0', spUUID=u'00000001-0001-0001-0001-0000000000b9', imgUUID=u'9c007b27-0ab7-4474-9317-a294fd04c65f', volUUID=None) from=::ffff:172.16.252.122,45736, task_id=4878dd0c-54e9-4bef-9ec7-446b110c9d8b (api:46)
2017-08-25 10:38:03,671+0200 INFO (jsonrpc/2) [vdsm.api] FINISH teardownImage return=None from=::ffff:172.16.252.122,45736, task_id=4878dd0c-54e9-4bef-9ec7-446b110c9d8b (api:52)
2017-08-25 10:38:03,672+0200 INFO (jsonrpc/2) [virt.vm] (vmId='ffb71f79-54cd-4f0e-b6b5-3670236cb497') Stopping connection (guestagent:430)
2017-08-25 10:38:03,672+0200 WARN (jsonrpc/2) [root] File: /var/lib/libvirt/qemu/channels/ffb71f79-54cd-4f0e-b6b5-3670236cb497.com.redhat.rhevm.vdsm already removed (utils:120)
2017-08-25 10:38:03,672+0200 WARN (jsonrpc/2) [root] File: /var/lib/libvirt/qemu/channels/ffb71f79-54cd-4f0e-b6b5-3670236cb497.org.qemu.guest_agent.0 already removed (utils:120)
2017-08-25 10:38:03,673+0200 WARN (jsonrpc/2) [virt.vm] (vmId='ffb71f79-54cd-4f0e-b6b5-3670236cb497') timestamp already removed from stats cache (vm:1743)
2017-08-25 10:38:03,673+0200 INFO (jsonrpc/2) [vdsm.api] START inappropriateDevices(thiefId=u'ffb71f79-54cd-4f0e-b6b5-3670236cb497') from=::ffff:172.16.252.122,45736, task_id=5abecbfc-6405-4040-a239-bf0d40db3a0e (api:46)
2017-08-25 10:38:03,674+0200 INFO (vm/ffb71f79) [virt.vm] (vmId='ffb71f79-54cd-4f0e-b6b5-3670236cb497') Changed state to Down: VM destroyed during the startup (code=10) (vm:1221)
2017-08-25 10:38:03,674+0200 INFO (vm/ffb71f79) [virt.vm] (vmId='ffb71f79-54cd-4f0e-b6b5-3670236cb497') Stopping connection (guestagent:430)
2017-08-25 10:38:03,675+0200 INFO (jsonrpc/2) [vdsm.api] FINISH inappropriateDevices return=None from=::ffff:172.16.252.122,45736, task_id=5abecbfc-6405-4040-a239-bf0d40db3a0e (api:52)
2017-08-25 10:38:03,697+0200 INFO (jsonrpc/2) [root] (hooks:108)
2017-08-25 10:38:03,698+0200 INFO (jsonrpc/2) [vdsm.api] FINISH destroy return={'status': {'message': 'Done', 'code': 0}} from=::ffff:172.16.252.122,45736 (api:52)
2017-08-25 10:38:03,698+0200 INFO (jsonrpc/2) [jsonrpc.JsonRpcServer] RPC call VM.destroy succeeded in 0.03 seconds (__init__:539)
2017-08-25 10:38:03,706+0200 INFO (jsonrpc/6) [jsonrpc.JsonRpcServer] RPC call VM.destroy failed (error 1) in 0.01 seconds (__init__:539)
2017-08-25 10:38:03,967+0200 INFO (jsonrpc/3) [vdsm.api] START repoStats(options=None) from=::ffff:172.16.252.200,42006, flow_id=71020485, task_id=8b5146ad-2e16-4580-af34-9481ebbc27ce (api:46)
2017-08-25 10:38:03,968+0200 INFO (jsonrpc/3) [vdsm.api] FINISH repoStats return={u'7c8deaa8-be02-4aaf-b9b4-ddc8da99ad96': {'code': 0, 'actual': True, 'version': 4, 'acquired': True, 'delay': '0.000274376', 'lastCheck': '2.4', 'valid': True}, u'2b2a44fc-f2bd-47cd-b7af-00be59e30a35': {'code': 0, 'actual': True, 'version': 0, 'acquired': True, 'delay': '0.000599443', 'lastCheck': '2.4', 'valid': True}, u'5d99af76-33b5-47d8-99da-1f32413c7bb0': {'code': 0, 'actual': True, 'version': 4, 'acquired': True, 'delay': '0.000346024', 'lastCheck': '1.3', 'valid': True}, u'a7fbaaad-7043-4391-9523-3bedcdc4fb0d': {'code': 0, 'actual': True, 'version': 0, 'acquired': True, 'delay': '0.00096871', 'lastCheck': '2.5', 'valid': True}} from=::ffff:172.16.252.200,42006, flow_id=71020485, task_id=8b5146ad-2e16-4580-af34-9481ebbc27ce (api:52)
2017-08-25 10:38:04,010+0200 INFO (jsonrpc/3) [jsonrpc.JsonRpcServer] RPC call Host.getStats succeeded in 0.04 seconds (__init__:539)
2017-08-25 10:38:04,766+0200 INFO (Reactor thread) [ProtocolDetector.AcceptorImpl] Accepted connection from ::1:49758 (protocoldetector:72)
2017-08-25 10:38:04,770+0200 INFO (Reactor thread) [ProtocolDetector.Detector] Detected protocol stomp from ::1:49758 (protocoldetector:127)
2017-08-25 10:38:04,771+0200 INFO (Reactor thread) [Broker.StompAdapter] Processing CONNECT request (stompreactor:103)
2017-08-25 10:38:04,775+0200 INFO (JsonRpc (StompReactor)) [Broker.StompAdapter] Subscribe command received (stompreactor:130)
2017-08-25 10:38:04,925+0200 INFO (jsonrpc/0) [jsonrpc.JsonRpcServer] RPC call Host.getHardwareInfo succeeded in 0.00 seconds (__init__:539)
2017-08-25 10:38:04,929+0200 INFO (jsonrpc/1) [vdsm.api] START getImagesList(sdUUID=u'7c8deaa8-be02-4aaf-b9b4-ddc8da99ad96', options=None) from=::1,49758, task_id=77e9432f-bd54-4a00-b3aa-3c4778591ef0 (api:46)
2017-08-25 10:38:04,935+0200 INFO (jsonrpc/1) [vdsm.api] FINISH getImagesList return={'imageslist': [u'849f4aa8-b70f-484c-9d08-de2845146ba6', u'9a4d1e94-21e2-4747-8855-824a07198eed', u'ebc17228-e424-4893-b0cb-b4dfec01335a', u'066b2632-44fe-455b-93db-aca85262b3ef', u'faed9c01-c9e7-4302-8053-3671163cc3b8', u'a469f231-61f7-4b7a-95af-b56ac91d0574']} from=::1,49758, task_id=77e9432f-bd54-4a00-b3aa-3c4778591ef0 (api:52)
2017-08-25 10:38:04,935+0200 INFO (jsonrpc/1) [jsonrpc.JsonRpcServer] RPC call StorageDomain.getImages succeeded in 0.00 seconds (__init__:539)
2017-08-25 10:38:04,944+0200 INFO (jsonrpc/5) [vdsm.api] START getVolumesList(sdUUID=u'7c8deaa8-be02-4aaf-b9b4-ddc8da99ad96', spUUID=u'00000000-0000-0000-0000-000000000000', imgUUID=u'849f4aa8-b70f-484c-9d08-de2845146ba6', options=None) from=::1,49758, task_id=fca96103-b9e5-4562-8085-edc2f290a524 (api:46)
2017-08-25 10:38:04,950+0200 INFO (jsonrpc/5) [vdsm.api] FINISH getVolumesList return={'uuidlist': [u'f5e62fac-e0f3-49c7-91fa-4dbe577825f4']} from=::1,49758, task_id=fca96103-b9e5-4562-8085-edc2f290a524 (api:52)
2017-08-25 10:38:04,950+0200 INFO (jsonrpc/5) [jsonrpc.JsonRpcServer] RPC call StorageDomain.getVolumes succeeded in 0.01 seconds (__init__:539)
2017-08-25 10:38:04,960+0200 INFO (jsonrpc/4) [vdsm.api] START getVolumeInfo(sdUUID=u'7c8deaa8-be02-4aaf-b9b4-ddc8da99ad96', spUUID=u'00000000-0000-0000-0000-000000000000', imgUUID=u'849f4aa8-b70f-484c-9d08-de2845146ba6', volUUID=u'f5e62fac-e0f3-49c7-91fa-4dbe577825f4', options=None) from=::1,49758, task_id=00ca32ac-a142-4084-a058-4885111038bb (api:46)
2017-08-25 10:38:04,964+0200 INFO (jsonrpc/4) [storage.VolumeManifest] Info request: sdUUID=7c8deaa8-be02-4aaf-b9b4-ddc8da99ad96 imgUUID=849f4aa8-b70f-484c-9d08-de2845146ba6 volUUID = f5e62fac-e0f3-49c7-91fa-4dbe577825f4 (volume:238)
2017-08-25 10:38:04,970+0200 INFO (jsonrpc/4) [storage.VolumeManifest] 7c8deaa8-be02-4aaf-b9b4-ddc8da99ad96/849f4aa8-b70f-484c-9d08-de2845146ba6/f5e62fac-e0f3-49c7-91fa-4dbe577825f4 info is {'status': 'OK', 'domain': '7c8deaa8-be02-4aaf-b9b4-ddc8da99ad96', 'voltype': 'LEAF', 'description': '{"Updated":true,"Size":20480,"Last Updated":"Thu Aug 24 15:19:07 CEST 2017","Storage Domains":[{"uuid":"7c8deaa8-be02-4aaf-b9b4-ddc8da99ad96"}],"Disk Description":"OVF_STORE"}', 'parent': '00000000-0000-0000-0000-000000000000', 'format': 'RAW', 'generation': 0, 'image': '849f4aa8-b70f-484c-9d08-de2845146ba6', 'ctime': '1464553328', 'disktype': '2', 'legality': 'LEGAL', 'mtime': '0', 'apparentsize': '20480', 'children': [], 'pool': '', 'capacity': '134217728', 'uuid': u'f5e62fac-e0f3-49c7-91fa-4dbe577825f4', 'truesize': '20480', 'type': 'PREALLOCATED', 'lease': {'owners': [], 'version': None}} (volume:272)
2017-08-25 10:38:04,971+0200 INFO (jsonrpc/4) [vdsm.api] FINISH getVolumeInfo return={'info': {'status': 'OK', 'domain': '7c8deaa8-be02-4aaf-b9b4-ddc8da99ad96', 'voltype': 'LEAF', 'description': '{"Updated":true,"Size":20480,"Last Updated":"Thu Aug 24 15:19:07 CEST 2017","Storage Domains":[{"uuid":"7c8deaa8-be02-4aaf-b9b4-ddc8da99ad96"}],"Disk Description":"OVF_STORE"}', 'parent': '00000000-0000-0000-0000-000000000000', 'format': 'RAW', 'generation': 0, 'image': '849f4aa8-b70f-484c-9d08-de2845146ba6', 'ctime': '1464553328', 'disktype': '2', 'legality': 'LEGAL', 'mtime': '0', 'apparentsize': '20480', 'children': [], 'pool': '', 'capacity': '134217728', 'uuid': u'f5e62fac-e0f3-49c7-91fa-4dbe577825f4', 'truesize': '20480', 'type': 'PREALLOCATED', 'lease': {'owners': [], 'version': None}}} from=::1,49758, task_id=00ca32ac-a142-4084-a058-4885111038bb (api:52)
2017-08-25 10:38:04,971+0200 INFO (jsonrpc/4) [jsonrpc.JsonRpcServer] RPC call Volume.getInfo succeeded in 0.01 seconds (__init__:539)
2017-08-25 10:38:04,978+0200 INFO (jsonrpc/7) [vdsm.api] START getVolumesList(sdUUID=u'7c8deaa8-be02-4aaf-b9b4-ddc8da99ad96', spUUID=u'00000000-0000-0000-0000-000000000000', imgUUID=u'9a4d1e94-21e2-4747-8855-824a07198eed', options=None) from=::1,49758, task_id=f543e849-90fb-48f9-bb55-757be3393b7d (api:46)
2017-08-25 10:38:04,985+0200 INFO (jsonrpc/7) [vdsm.api] FINISH getVolumesList return={'uuidlist': [u'53fed829-0fc3-4ec5-8487-0a5371f9b885']} from=::1,49758, task_id=f543e849-90fb-48f9-bb55-757be3393b7d (api:52)
2017-08-25 10:38:04,985+0200 INFO (jsonrpc/7) [jsonrpc.JsonRpcServer] RPC call StorageDomain.getVolumes succeeded in 0.00 seconds (__init__:539)
2017-08-25 10:38:04,993+0200 INFO (jsonrpc/2) [vdsm.api] START getVolumeInfo(sdUUID=u'7c8deaa8-be02-4aaf-b9b4-ddc8da99ad96', spUUID=u'00000000-0000-0000-0000-000000000000', imgUUID=u'9a4d1e94-21e2-4747-8855-824a07198eed', volUUID=u'53fed829-0fc3-4ec5-8487-0a5371f9b885', options=None) from=::1,49758, task_id=dac76bfc-6a14-4552-8012-3af86d92d92b (api:46)
2017-08-25 10:38:04,996+0200 INFO (jsonrpc/2) [storage.VolumeManifest] Info request: sdUUID=7c8deaa8-be02-4aaf-b9b4-ddc8da99ad96 imgUUID=9a4d1e94-21e2-4747-8855-824a07198eed volUUID = 53fed829-0fc3-4ec5-8487-0a5371f9b885 (volume:238)
2017-08-25 10:38:05,002+0200 INFO (jsonrpc/2) [storage.VolumeManifest] 7c8deaa8-be02-4aaf-b9b4-ddc8da99ad96/9a4d1e94-21e2-4747-8855-824a07198eed/53fed829-0fc3-4ec5-8487-0a5371f9b885 info is {'status': 'OK', 'domain': '7c8deaa8-be02-4aaf-b9b4-ddc8da99ad96', 'voltype': 'LEAF', 'description': 'hosted-engine.lockspace', 'parent': '00000000-0000-0000-0000-000000000000', 'format': 'RAW', 'generation': 0, 'image': '9a4d1e94-21e2-4747-8855-824a07198eed', 'ctime': '1464545270', 'disktype': '2', 'legality': 'LEGAL', 'mtime': '0', 'apparentsize': '1048576', 'children': [], 'pool': '', 'capacity': '1048576', 'uuid': u'53fed829-0fc3-4ec5-8487-0a5371f9b885', 'truesize': '1048576', 'type': 'PREALLOCATED', 'lease': {'owners': [], 'version': None}} (volume:272)
2017-08-25 10:38:05,002+0200 INFO (jsonrpc/2) [vdsm.api] FINISH getVolumeInfo return={'info': {'status': 'OK', 'domain': '7c8deaa8-be02-4aaf-b9b4-ddc8da99ad96', 'voltype': 'LEAF', 'description': 'hosted-engine.lockspace', 'parent': '00000000-0000-0000-0000-000000000000', 'format': 'RAW', 'generation': 0, 'image': '9a4d1e94-21e2-4747-8855-824a07198eed', 'ctime': '1464545270', 'disktype': '2', 'legality': 'LEGAL', 'mtime': '0', 'apparentsize': '1048576', 'children': [], 'pool': '', 'capacity': '1048576', 'uuid': u'53fed829-0fc3-4ec5-8487-0a5371f9b885', 'truesize': '1048576', 'type': 'PREALLOCATED', 'lease': {'owners': [], 'version': None}}} from=::1,49758, task_id=dac76bfc-6a14-4552-8012-3af86d92d92b (api:52)
2017-08-25 10:38:05,003+0200 INFO (jsonrpc/2) [jsonrpc.JsonRpcServer] RPC call Volume.getInfo succeeded in 0.01 seconds (__init__:539)
2017-08-25 10:38:05,008+0200 INFO (jsonrpc/6) [vdsm.api] START getVolumesList(sdUUID=u'7c8deaa8-be02-4aaf-b9b4-ddc8da99ad96', spUUID=u'00000000-0000-0000-0000-000000000000', imgUUID=u'ebc17228-e424-4893-b0cb-b4dfec01335a', options=None) from=::1,49758, task_id=99562526-ea10-467c-a69b-635d47b412ff (api:46)
2017-08-25 10:38:05,014+0200 INFO (jsonrpc/6) [vdsm.api] FINISH getVolumesList return={'uuidlist': [u'a64731f8-600f-4153-ac97-a808ac4aaa3e']} from=::1,49758, task_id=99562526-ea10-467c-a69b-635d47b412ff (api:52)
2017-08-25 10:38:05,015+0200 INFO (jsonrpc/6) [jsonrpc.JsonRpcServer] RPC call StorageDomain.getVolumes succeeded in 0.00 seconds (__init__:539)
2017-08-25 10:38:05,024+0200 INFO (jsonrpc/3) [vdsm.api] START getVolumeInfo(sdUUID=u'7c8deaa8-be02-4aaf-b9b4-ddc8da99ad96', spUUID=u'00000000-0000-0000-0000-000000000000', imgUUID=u'ebc17228-e424-4893-b0cb-b4dfec01335a', volUUID=u'a64731f8-600f-4153-ac97-a808ac4aaa3e', options=None) from=::1,49758, task_id=44f075ec-93af-4845-914c-fa92bf4fcfc8 (api:46)
2017-08-25 10:38:05,027+0200 INFO (jsonrpc/3) [storage.VolumeManifest] Info request: sdUUID=7c8deaa8-be02-4aaf-b9b4-ddc8da99ad96 imgUUID=ebc17228-e424-4893-b0cb-b4dfec01335a volUUID = a64731f8-600f-4153-ac97-a808ac4aaa3e (volume:238)
2017-08-25 10:38:05,034+0200 INFO (jsonrpc/3) [storage.VolumeManifest] 7c8deaa8-be02-4aaf-b9b4-ddc8da99ad96/ebc17228-e424-4893-b0cb-b4dfec01335a/a64731f8-600f-4153-ac97-a808ac4aaa3e info is {'status': 'OK', 'domain': '7c8deaa8-be02-4aaf-b9b4-ddc8da99ad96', 'voltype': 'LEAF', 'description': 'hosted-engine.metadata', 'parent': '00000000-0000-0000-0000-000000000000', 'format': 'RAW', 'generation': 0, 'image': 'ebc17228-e424-4893-b0cb-b4dfec01335a', 'ctime': '1464545271', 'disktype': '2', 'legality': 'LEGAL', 'mtime': '0', 'apparentsize': '1028096', 'children': [], 'pool': '', 'capacity': '1028096', 'uuid': u'a64731f8-600f-4153-ac97-a808ac4aaa3e', 'truesize': '1028096', 'type': 'PREALLOCATED', 'lease': {'owners': [], 'version': None}} (volume:272)
2017-08-25 10:38:05,034+0200 INFO (jsonrpc/3) [vdsm.api] FINISH getVolumeInfo return={'info': {'status': 'OK', 'domain': '7c8deaa8-be02-4aaf-b9b4-ddc8da99ad96', 'voltype': 'LEAF', 'description': 'hosted-engine.metadata', 'parent': '00000000-0000-0000-0000-000000000000', 'format': 'RAW', 'generation': 0, 'image': 'ebc17228-e424-4893-b0cb-b4dfec01335a', 'ctime': '1464545271', 'disktype': '2', 'legality': 'LEGAL', 'mtime': '0', 'apparentsize': '1028096', 'children': [], 'pool': '', 'capacity': '1028096', 'uuid': u'a64731f8-600f-4153-ac97-a808ac4aaa3e', 'truesize': '1028096', 'type': 'PREALLOCATED', 'lease': {'owners': [], 'version': None}}} from=::1,49758, task_id=44f075ec-93af-4845-914c-fa92bf4fcfc8 (api:52)
2017-08-25 10:38:05,035+0200 INFO (jsonrpc/3) [jsonrpc.JsonRpcServer] RPC call Volume.getInfo succeeded in 0.01 seconds (__init__:539)
2017-08-25 10:38:05,040+0200 INFO (jsonrpc/0) [vdsm.api] START getVolumesList(sdUUID=u'7c8deaa8-be02-4aaf-b9b4-ddc8da99ad96', spUUID=u'00000000-0000-0000-0000-000000000000', imgUUID=u'066b2632-44fe-455b-93db-aca85262b3ef', options=None) from=::1,49758, task_id=cd5e012b-23a3-40f8-956a-5547e34aaa54 (api:46)
2017-08-25 10:38:05,047+0200 INFO (jsonrpc/0) [vdsm.api] FINISH getVolumesList return={'uuidlist': [u'7e88ba33-345f-4317-977a-7ae98a2ccbbe']} from=::1,49758, task_id=cd5e012b-23a3-40f8-956a-5547e34aaa54 (api:52)
2017-08-25 10:38:05,048+0200 INFO (jsonrpc/0) [jsonrpc.JsonRpcServer] RPC call StorageDomain.getVolumes succeeded in 0.01 seconds (__init__:539)
2017-08-25 10:38:05,055+0200 INFO (jsonrpc/1) [vdsm.api] START getVolumeInfo(sdUUID=u'7c8deaa8-be02-4aaf-b9b4-ddc8da99ad96', spUUID=u'00000000-0000-0000-0000-000000000000', imgUUID=u'066b2632-44fe-455b-93db-aca85262b3ef', volUUID=u'7e88ba33-345f-4317-977a-7ae98a2ccbbe', options=None) from=::1,49758, task_id=35207ea3-f0bf-4a21-a762-09c2cd59b68a (api:46)
2017-08-25 10:38:05,058+0200 INFO (jsonrpc/1) [storage.VolumeManifest] Info request: sdUUID=7c8deaa8-be02-4aaf-b9b4-ddc8da99ad96 imgUUID=066b2632-44fe-455b-93db-aca85262b3ef volUUID = 7e88ba33-345f-4317-977a-7ae98a2ccbbe (volume:238)
2017-08-25 10:38:05,064+0200 INFO (jsonrpc/1) [storage.VolumeManifest] 7c8deaa8-be02-4aaf-b9b4-ddc8da99ad96/066b2632-44fe-455b-93db-aca85262b3ef/7e88ba33-345f-4317-977a-7ae98a2ccbbe info is {'status': 'OK', 'domain': '7c8deaa8-be02-4aaf-b9b4-ddc8da99ad96', 'voltype': 'LEAF', 'description': 'HostedEngineConfigurationImage', 'parent': '00000000-0000-0000-0000-000000000000', 'format': 'RAW', 'generation': 0, 'image': '066b2632-44fe-455b-93db-aca85262b3ef', 'ctime': '1464545267', 'disktype': '2', 'legality': 'LEGAL', 'mtime': '0', 'apparentsize': '20480', 'children': [], 'pool': '', 'capacity': '1073741824', 'uuid': u'7e88ba33-345f-4317-977a-7ae98a2ccbbe', 'truesize': '20480', 'type': 'PREALLOCATED', 'lease': {'owners': [], 'version': None}} (volume:272)
2017-08-25 10:38:05,064+0200 INFO (jsonrpc/1) [vdsm.api] FINISH getVolumeInfo return={'info': {'status': 'OK', 'domain': '7c8deaa8-be02-4aaf-b9b4-ddc8da99ad96', 'voltype': 'LEAF', 'description': 'HostedEngineConfigurationImage', 'parent': '00000000-0000-0000-0000-000000000000', 'format': 'RAW', 'generation': 0, 'image': '066b2632-44fe-455b-93db-aca85262b3ef', 'ctime': '1464545267', 'disktype': '2', 'legality': 'LEGAL', 'mtime': '0', 'apparentsize': '20480', 'children': [], 'pool': '', 'capacity': '1073741824', 'uuid': u'7e88ba33-345f-4317-977a-7ae98a2ccbbe', 'truesize': '20480', 'type': 'PREALLOCATED', 'lease': {'owners': [], 'version': None}}} from=::1,49758, task_id=35207ea3-f0bf-4a21-a762-09c2cd59b68a (api:52)
2017-08-25 10:38:05,065+0200 INFO (jsonrpc/1) [jsonrpc.JsonRpcServer] RPC call Volume.getInfo succeeded in 0.01 seconds (__init__:539)
2017-08-25 10:38:05,071+0200 INFO (jsonrpc/5) [vdsm.api] START getVolumesList(sdUUID=u'7c8deaa8-be02-4aaf-b9b4-ddc8da99ad96', spUUID=u'00000000-0000-0000-0000-000000000000', imgUUID=u'faed9c01-c9e7-4302-8053-3671163cc3b8', options=None) from=::1,49758, task_id=47452580-abc1-42b3-af71-39f50dc66af9 (api:46)
2017-08-25 10:38:05,077+0200 INFO (jsonrpc/5) [vdsm.api] FINISH getVolumesList return={'uuidlist': [u'6aaea89f-ea88-4d57-aa3e-6252ef102949']} from=::1,49758, task_id=47452580-abc1-42b3-af71-39f50dc66af9 (api:52)
2017-08-25 10:38:05,077+0200 INFO (jsonrpc/5) [jsonrpc.JsonRpcServer] RPC call StorageDomain.getVolumes succeeded in 0.01 seconds (__init__:539)
2017-08-25 10:38:05,086+0200 INFO (jsonrpc/4) [vdsm.api] START getVolumeInfo(sdUUID=u'7c8deaa8-be02-4aaf-b9b4-ddc8da99ad96', spUUID=u'00000000-0000-0000-0000-000000000000', imgUUID=u'faed9c01-c9e7-4302-8053-3671163cc3b8', volUUID=u'6aaea89f-ea88-4d57-aa3e-6252ef102949', options=None) from=::1,49758, task_id=d95e0a99-f29d-4453-ad4b-1cd23764e8d3 (api:46)
2017-08-25 10:38:05,089+0200 INFO (jsonrpc/4) [storage.VolumeManifest] Info request: sdUUID=7c8deaa8-be02-4aaf-b9b4-ddc8da99ad96 imgUUID=faed9c01-c9e7-4302-8053-3671163cc3b8 volUUID = 6aaea89f-ea88-4d57-aa3e-6252ef102949 (volume:238)
2017-08-25 10:38:05,096+0200 INFO (jsonrpc/4) [storage.VolumeManifest] 7c8deaa8-be02-4aaf-b9b4-ddc8da99ad96/faed9c01-c9e7-4302-8053-3671163cc3b8/6aaea89f-ea88-4d57-aa3e-6252ef102949 info is {'status': 'OK', 'domain': '7c8deaa8-be02-4aaf-b9b4-ddc8da99ad96', 'voltype': 'LEAF', 'description': 'Hosted Engine Image', 'parent': '00000000-0000-0000-0000-000000000000', 'format': 'RAW', 'generation': 0, 'image': 'faed9c01-c9e7-4302-8053-3671163cc3b8', 'ctime': '1464545278', 'disktype': '2', 'legality': 'LEGAL', 'mtime': '0', 'apparentsize': '10737418240', 'children': [], 'pool': '', 'capacity': '10737418240', 'uuid': u'6aaea89f-ea88-4d57-aa3e-6252ef102949', 'truesize': '8490876928', 'type': 'SPARSE', 'lease': {'owners': [1], 'version': 47L}} (volume:272)
2017-08-25 10:38:05,096+0200 INFO (jsonrpc/4) [vdsm.api] FINISH getVolumeInfo return={'info': {'status': 'OK', 'domain': '7c8deaa8-be02-4aaf-b9b4-ddc8da99ad96', 'voltype': 'LEAF', 'description': 'Hosted Engine Image', 'parent': '00000000-0000-0000-0000-000000000000', 'format': 'RAW', 'generation': 0, 'image': 'faed9c01-c9e7-4302-8053-3671163cc3b8', 'ctime': '1464545278', 'disktype': '2', 'legality': 'LEGAL', 'mtime': '0', 'apparentsize': '10737418240', 'children': [], 'pool': '', 'capacity': '10737418240', 'uuid': u'6aaea89f-ea88-4d57-aa3e-6252ef102949', 'truesize': '8490876928', 'type': 'SPARSE', 'lease': {'owners': [1], 'version': 47L}}} from=::1,49758, task_id=d95e0a99-f29d-4453-ad4b-1cd23764e8d3 (api:52)
2017-08-25 10:38:05,097+0200 INFO (jsonrpc/4) [jsonrpc.JsonRpcServer] RPC call Volume.getInfo succeeded in 0.02 seconds (__init__:539)
2017-08-25 10:38:05,101+0200 INFO (jsonrpc/7) [vdsm.api] START getVolumesList(sdUUID=u'7c8deaa8-be02-4aaf-b9b4-ddc8da99ad96', spUUID=u'00000000-0000-0000-0000-000000000000', imgUUID=u'a469f231-61f7-4b7a-95af-b56ac91d0574', options=None) from=::1,49758, task_id=e88b91a3-51d1-47b9-8d97-5f2a748d9265 (api:46)
2017-08-25 10:38:05,108+0200 INFO (jsonrpc/7) [vdsm.api] FINISH getVolumesList return={'uuidlist': [u'86edb440-cd6c-49f9-8df0-ee25f6ac4748']} from=::1,49758, task_id=e88b91a3-51d1-47b9-8d97-5f2a748d9265 (api:52)
2017-08-25 10:38:05,109+0200 INFO (jsonrpc/7) [jsonrpc.JsonRpcServer] RPC call StorageDomain.getVolumes succeeded in 0.01 seconds (__init__:539)
2017-08-25 10:38:05,117+0200 INFO (jsonrpc/2) [vdsm.api] START getVolumeInfo(sdUUID=u'7c8deaa8-be02-4aaf-b9b4-ddc8da99ad96', spUUID=u'00000000-0000-0000-0000-000000000000', imgUUID=u'a469f231-61f7-4b7a-95af-b56ac91d0574', volUUID=u'86edb440-cd6c-49f9-8df0-ee25f6ac4748', options=None) from=::1,49758, task_id=7e7c3678-7f8d-47ba-8e95-be017ac39589 (api:46)
2017-08-25 10:38:05,120+0200 INFO (jsonrpc/2) [storage.VolumeManifest] Info request: sdUUID=7c8deaa8-be02-4aaf-b9b4-ddc8da99ad96 imgUUID=a469f231-61f7-4b7a-95af-b56ac91d0574 volUUID = 86edb440-cd6c-49f9-8df0-ee25f6ac4748 (volume:238)
2017-08-25 10:38:05,127+0200 INFO (jsonrpc/2) [storage.VolumeManifest] 7c8deaa8-be02-4aaf-b9b4-ddc8da99ad96/a469f231-61f7-4b7a-95af-b56ac91d0574/86edb440-cd6c-49f9-8df0-ee25f6ac4748 info is {'status': 'OK', 'domain': '7c8deaa8-be02-4aaf-b9b4-ddc8da99ad96', 'voltype': 'LEAF', 'description': '{"Updated":true,"Size":20480,"Last Updated":"Thu Aug 24 15:19:07 CEST 2017","Storage Domains":[{"uuid":"7c8deaa8-be02-4aaf-b9b4-ddc8da99ad96"}],"Disk Description":"OVF_STORE"}', 'parent': '00000000-0000-0000-0000-000000000000', 'format': 'RAW', 'generation': 0, 'image': 'a469f231-61f7-4b7a-95af-b56ac91d0574', 'ctime': '1464553325', 'disktype': '2', 'legality': 'LEGAL', 'mtime': '0', 'apparentsize': '20480', 'children': [], 'pool': '', 'capacity': '134217728', 'uuid': u'86edb440-cd6c-49f9-8df0-ee25f6ac4748', 'truesize': '20480', 'type': 'PREALLOCATED', 'lease': {'owners': [], 'version': None}} (volume:272)
2017-08-25 10:38:05,127+0200 INFO (jsonrpc/2) [vdsm.api] FINISH getVolumeInfo return={'info': {'status': 'OK', 'domain': '7c8deaa8-be02-4aaf-b9b4-ddc8da99ad96', 'voltype': 'LEAF', 'description': '{"Updated":true,"Size":20480,"Last Updated":"Thu Aug 24 15:19:07 CEST 2017","Storage Domains":[{"uuid":"7c8deaa8-be02-4aaf-b9b4-ddc8da99ad96"}],"Disk Description":"OVF_STORE"}', 'parent': '00000000-0000-0000-0000-000000000000', 'format': 'RAW', 'generation': 0, 'image': 'a469f231-61f7-4b7a-95af-b56ac91d0574', 'ctime': '1464553325', 'disktype': '2', 'legality': 'LEGAL', 'mtime': '0', 'apparentsize': '20480', 'children': [], 'pool': '', 'capacity': '134217728', 'uuid': u'86edb440-cd6c-49f9-8df0-ee25f6ac4748', 'truesize': '20480', 'type': 'PREALLOCATED', 'lease': {'owners': [], 'version': None}}} from=::1,49758, task_id=7e7c3678-7f8d-47ba-8e95-be017ac39589 (api:52)
2017-08-25 10:38:05,128+0200 INFO (jsonrpc/2) [jsonrpc.JsonRpcServer] RPC call Volume.getInfo succeeded in 0.01 seconds (__init__:539)
2017-08-25 10:38:05,511+0200 INFO (Reactor thread) [ProtocolDetector.AcceptorImpl] Accepted connection from ::1:49760 (protocoldetector:72)
2017-08-25 10:38:05,515+0200 INFO (Reactor thread) [ProtocolDetector.Detector] Detected protocol stomp from ::1:49760 (protocoldetector:127)
2017-08-25 10:38:05,516+0200 INFO (Reactor thread) [Broker.StompAdapter] Processing CONNECT request (stompreactor:103)
2017-08-25 10:38:05,516+0200 INFO (JsonRpc (StompReactor)) [Broker.StompAdapter] Subscribe command received (stompreactor:130)
2017-08-25 10:38:05,652+0200 INFO (jsonrpc/6) [jsonrpc.JsonRpcServer] RPC call Host.getHardwareInfo succeeded in 0.00 seconds (__init__:539)
2017-08-25 10:38:05,746+0200 INFO (jsonrpc/3) [jsonrpc.JsonRpcServer] RPC call Host.getCapabilities succeeded in 0.09 seconds (__init__:539)
2017-08-25 10:38:06,422+0200 INFO (Reactor thread) [ProtocolDetector.AcceptorImpl] Accepted connection from ::1:49762 (protocoldetector:72)
2017-08-25 10:38:06,427+0200 INFO (Reactor thread) [ProtocolDetector.Detector] Detected protocol stomp from ::1:49762 (protocoldetector:127)
2017-08-25 10:38:06,428+0200 INFO (Reactor thread) [Broker.StompAdapter] Processing CONNECT request (stompreactor:103)
2017-08-25 10:38:06,429+0200 INFO (JsonRpc (StompReactor)) [Broker.StompAdapter] Subscribe command received (stompreactor:130)
2017-08-25 10:38:06,567+0200 INFO (jsonrpc/0) [jsonrpc.JsonRpcServer] RPC call Host.getHardwareInfo succeeded in 0.00 seconds (__init__:539)
2017-08-25 10:38:06,570+0200 INFO (jsonrpc/1) [vdsm.api] START repoStats(options=None) from=::1,49762, task_id=ef0f1452-7dc6-43b7-95e0-a109a5fc05bd (api:46)
2017-08-25 10:38:06,570+0200 INFO (jsonrpc/1) [vdsm.api] FINISH repoStats return={u'7c8deaa8-be02-4aaf-b9b4-ddc8da99ad96': {'code': 0, 'actual': True, 'version': 4, 'acquired': True, 'delay': '0.000274376', 'lastCheck': '1.8', 'valid': True}, u'2b2a44fc-f2bd-47cd-b7af-00be59e30a35': {'code': 0, 'actual': True, 'version': 0, 'acquired': True, 'delay': '0.000599443', 'lastCheck': '5.0', 'valid': True}, u'5d99af76-33b5-47d8-99da-1f32413c7bb0': {'code': 0, 'actual': True, 'version': 4, 'acquired': True, 'delay': '0.000346024', 'lastCheck': '3.9', 'valid': True}, u'a7fbaaad-7043-4391-9523-3bedcdc4fb0d': {'code': 0, 'actual': True, 'version': 0, 'acquired': True, 'delay': '0.00096871', 'lastCheck': '5.1', 'valid': True}} from=::1,49762, task_id=ef0f1452-7dc6-43b7-95e0-a109a5fc05bd (api:52)
2017-08-25 10:38:06,612+0200 INFO (jsonrpc/1) [jsonrpc.JsonRpcServer] RPC call Host.getStats succeeded in 0.04 seconds (__init__:539)
2017-08-25 10:38:07,188+0200 INFO (Reactor thread) [ProtocolDetector.AcceptorImpl] Accepted connection from ::1:49764 (protocoldetector:72)
2017-08-25 10:38:07,193+0200 INFO (Reactor thread) [ProtocolDetector.Detector] Detected protocol stomp from ::1:49764 (protocoldetector:127)
2017-08-25 10:38:07,194+0200 INFO (Reactor thread) [Broker.StompAdapter] Processing CONNECT request (stompreactor:103)
2017-08-25 10:38:07,195+0200 INFO (JsonRpc (StompReactor)) [Broker.StompAdapter] Subscribe command received (stompreactor:130)
2017-08-25 10:38:07,357+0200 INFO (jsonrpc/5) [jsonrpc.JsonRpcServer] RPC call Host.getHardwareInfo succeeded in 0.00 seconds (__init__:539)
2017-08-25 10:38:07,983+0200 INFO (Reactor thread) [ProtocolDetector.AcceptorImpl] Accepted connection from ::1:49766 (protocoldetector:72)
2017-08-25 10:38:07,988+0200 INFO (Reactor thread) [ProtocolDetector.Detector] Detected protocol stomp from ::1:49766 (protocoldetector:127)
2017-08-25 10:38:07,988+0200 INFO (Reactor thread) [Broker.StompAdapter] Processing CONNECT request (stompreactor:103)
2017-08-25 10:38:07,988+0200 INFO (JsonRpc (StompReactor)) [Broker.StompAdapter] Subscribe command received (stompreactor:130)
2017-08-25 10:38:08,124+0200 INFO (jsonrpc/4) [jsonrpc.JsonRpcServer] RPC call Host.getHardwareInfo succeeded in 0.00 seconds (__init__:539)
2017-08-25 10:38:08,127+0200 INFO (jsonrpc/7) [jsonrpc.JsonRpcServer] RPC call VM.getStats failed (error 1) in 0.00 seconds (__init__:539)
2017-08-25 10:38:09,366+0200 INFO (Reactor thread) [ProtocolDetector.AcceptorImpl] Accepted connection from ::1:49768 (protocoldetector:72)
2017-08-25 10:38:09,372+0200 INFO (Reactor thread) [ProtocolDetector.Detector] Detected protocol stomp from ::1:49768 (protocoldetector:127)
2017-08-25 10:38:09,373+0200 INFO (Reactor thread) [Broker.StompAdapter] Processing CONNECT request (stompreactor:103)
2017-08-25 10:38:09,378+0200 INFO (JsonRpc (StompReactor)) [Broker.StompAdapter] Subscribe command received (stompreactor:130)
2017-08-25 10:38:09,540+0200 INFO (jsonrpc/2) [jsonrpc.JsonRpcServer] RPC call Host.getHardwareInfo succeeded in 0.00 seconds (__init__:539)
2017-08-25 10:38:09,543+0200 INFO (jsonrpc/6) [vdsm.api] START repoStats(options=None) from=::1,49768, task_id=d11fa854-7651-4e7c-8e0d-1a2c832b869d (api:46)
2017-08-25 10:38:09,543+0200 INFO (jsonrpc/6) [vdsm.api] FINISH repoStats return={u'7c8deaa8-be02-4aaf-b9b4-ddc8da99ad96': {'code': 0, 'actual': True, 'version': 4, 'acquired': True, 'delay': '0.000274376', 'lastCheck': '4.8', 'valid': True}, u'2b2a44fc-f2bd-47cd-b7af-00be59e30a35': {'code': 0, 'actual': True, 'version': 0, 'acquired': True, 'delay': '0.000599443', 'lastCheck': '8.0', 'valid': True}, u'5d99af76-33b5-47d8-99da-1f32413c7bb0': {'code': 0, 'actual': True, 'version': 4, 'acquired': True, 'delay': '0.000346024', 'lastCheck': '6.9', 'valid': True}, u'a7fbaaad-7043-4391-9523-3bedcdc4fb0d': {'code': 0, 'actual': True, 'version': 0, 'acquired': True, 'delay': '0.00096871', 'lastCheck': '8.0', 'valid': True}} from=::1,49768, task_id=d11fa854-7651-4e7c-8e0d-1a2c832b869d (api:52)
2017-08-25 10:38:09,543+0200 INFO (jsonrpc/6) [jsonrpc.JsonRpcServer] RPC call Host.getStorageRepoStats succeeded in 0.00 seconds (__init__:539)
2017-08-25 10:38:11,520+0200 INFO (jsonrpc/3) [jsonrpc.JsonRpcServer] RPC call Host.getAllVmStats succeeded in 0.00 seconds (__init__:539)
2017-08-25 10:38:11,549+0200 INFO (Reactor thread) [ProtocolDetector.AcceptorImpl] Accepted connection from ::1:49770 (protocoldetector:72)
2017-08-25 10:38:11,554+0200 INFO (Reactor thread) [ProtocolDetector.Detector] Detected protocol stomp from ::1:49770 (protocoldetector:127)
2017-08-25 10:38:11,554+0200 INFO (Reactor thread) [Broker.StompAdapter] Processing CONNECT request (stompreactor:103)
2017-08-25 10:38:11,555+0200 INFO (JsonRpc (StompReactor)) [Broker.StompAdapter] Subscribe command received (stompreactor:130)
2017-08-25 10:38:11,718+0200 INFO (jsonrpc/0) [jsonrpc.JsonRpcServer] RPC call Host.getHardwareInfo succeeded in 0.00 seconds (__init__:539)
2017-08-25 10:38:11,721+0200 INFO (jsonrpc/1) [vdsm.api] START repoStats(options=None) from=::1,49770, task_id=a4baf01b-9669-4b15-8f5a-f3054e26d43f (api:46)
2017-08-25 10:38:11,721+0200 INFO (jsonrpc/1) [vdsm.api] FINISH repoStats return={u'7c8deaa8-be02-4aaf-b9b4-ddc8da99ad96': {'code': 0, 'actual': True, 'version': 4, 'acquired': True, 'delay': '0.00025539', 'lastCheck': '0.2', 'valid': True}, u'2b2a44fc-f2bd-47cd-b7af-00be59e30a35': {'code': 0, 'actual': True, 'version': 0, 'acquired': True, 'delay': '0.000634126', 'lastCheck': '0.2', 'valid': True}, u'5d99af76-33b5-47d8-99da-1f32413c7bb0': {'code': 0, 'actual': True, 'version': 4, 'acquired': True, 'delay': '0.000311195', 'lastCheck': '0.3', 'valid': True}, u'a7fbaaad-7043-4391-9523-3bedcdc4fb0d': {'code': 0, 'actual': True, 'version': 0, 'acquired': True, 'delay': '0.00114361', 'lastCheck': '0.2', 'valid': True}} from=::1,49770, task_id=a4baf01b-9669-4b15-8f5a-f3054e26d43f (api:52)
2017-08-25 10:38:11,722+0200 INFO (jsonrpc/1) [jsonrpc.JsonRpcServer] RPC call Host.getStorageRepoStats succeeded in 0.00 seconds (__init__:539)
2017-08-25 10:38:13,755+0200 INFO (Reactor thread) [ProtocolDetector.AcceptorImpl] Accepted connection from ::1:49772 (protocoldetector:72)
2017-08-25 10:38:13,761+0200 INFO (Reactor thread) [ProtocolDetector.Detector] Detected protocol stomp from ::1:49772 (protocoldetector:127)
2017-08-25 10:38:13,761+0200 INFO (Reactor thread) [Broker.StompAdapter] Processing CONNECT request (stompreactor:103)
2017-08-25 10:38:13,765+0200 INFO (JsonRpc (StompReactor)) [Broker.StompAdapter] Subscribe command received (stompreactor:130)
2017-08-25 10:38:13,933+0200 INFO (jsonrpc/5) [jsonrpc.JsonRpcServer] RPC call Host.getHardwareInfo succeeded in 0.00 seconds (__init__:539)
2017-08-25 10:38:14,961+0200 INFO (jsonrpc/4) [jsonrpc.JsonRpcServer] RPC call Host.getAllVmStats succeeded in 0.00 seconds (__init__:539)
2017-08-25 10:38:15,361+0200 INFO (periodic/1) [vdsm.api] START repoStats(options=None) from=internal, task_id=48721ed6-a5b0-4c35-9f94-c83f2c45d883 (api:46)
2017-08-25 10:38:15,361+0200 INFO (periodic/1) [vdsm.api] FINISH repoStats return={u'7c8deaa8-be02-4aaf-b9b4-ddc8da99ad96': {'code': 0, 'actual': True, 'version': 4, 'acquired': True, 'delay': '0.00025539', 'lastCheck': '0.6', 'valid': True}, u'2b2a44fc-f2bd-47cd-b7af-00be59e30a35': {'code': 0, 'actual': True, 'version': 0, 'acquired': True, 'delay': '0.000634126', 'lastCheck': '3.8', 'valid': True}, u'5d99af76-33b5-47d8-99da-1f32413c7bb0': {'code': 0, 'actual': True, 'version': 4, 'acquired': True, 'delay': '0.000311195', 'lastCheck': '2.7', 'valid': True}, u'a7fbaaad-7043-4391-9523-3bedcdc4fb0d': {'code': 0, 'actual': True, 'version': 0, 'acquired': True, 'delay': '0.00114361', 'lastCheck': '3.8', 'valid': True}} from=internal, task_id=48721ed6-a5b0-4c35-9f94-c83f2c45d883 (api:52)
2017-08-25 10:38:15,644+0200 INFO (Reactor thread) [ProtocolDetector.AcceptorImpl] Accepted connection from ::1:49774 (protocoldetector:72)
2017-08-25 10:38:15,649+0200 INFO (Reactor thread) [ProtocolDetector.Detector] Detected protocol stomp from ::1:49774 (protocoldetector:127)
2017-08-25 10:38:15,649+0200 INFO (Reactor thread) [Broker.StompAdapter] Processing CONNECT request (stompreactor:103)
2017-08-25 10:38:15,650+0200 INFO (JsonRpc (StompReactor)) [Broker.StompAdapter] Subscribe command received (stompreactor:130)
2017-08-25 10:38:15,796+0200 INFO (jsonrpc/7) [jsonrpc.JsonRpcServer] RPC call Host.getHardwareInfo succeeded in 0.01 seconds (__init__:539)
2017-08-25 10:38:15,797+0200 INFO (Reactor thread) [ProtocolDetector.AcceptorImpl] Accepted connection from ::1:49776 (protocoldetector:72)
2017-08-25 10:38:15,799+0200 INFO (jsonrpc/2) [vdsm.api] START repoStats(options=None) from=::1,49774, task_id=e82b3b10-e272-441e-9b23-8f08a63bf7ca (api:46)
2017-08-25 10:38:15,799+0200 INFO (jsonrpc/2) [vdsm.api] FINISH repoStats return={u'7c8deaa8-be02-4aaf-b9b4-ddc8da99ad96': {'code': 0, 'actual': True, 'version': 4, 'acquired': True, 'delay': '0.00025539', 'lastCheck': '1.0', 'valid': True}, u'2b2a44fc-f2bd-47cd-b7af-00be59e30a35': {'code': 0, 'actual': True, 'version': 0, 'acquired': True, 'delay': '0.000634126', 'lastCheck': '4.3', 'valid': True}, u'5d99af76-33b5-47d8-99da-1f32413c7bb0': {'code': 0, 'actual': True, 'version': 4, 'acquired': True, 'delay': '0.000311195', 'lastCheck': '3.2', 'valid': True}, u'a7fbaaad-7043-4391-9523-3bedcdc4fb0d': {'code': 0, 'actual': True, 'version': 0, 'acquired': True, 'delay': '0.00114361', 'lastCheck': '4.3', 'valid': True}} from=::1,49774, task_id=e82b3b10-e272-441e-9b23-8f08a63bf7ca (api:52)
2017-08-25 10:38:15,830+0200 INFO (Reactor thread) [ProtocolDetector.Detector] Detected protocol stomp from ::1:49776 (protocoldetector:127)
2017-08-25 10:38:15,831+0200 INFO (Reactor thread) [Broker.StompAdapter] Processing CONNECT request (stompreactor:103)
2017-08-25 10:38:15,832+0200 INFO (JsonRpc (StompReactor)) [Broker.StompAdapter] Subscribe command received (stompreactor:130)
2017-08-25 10:38:16,006+0200 INFO (jsonrpc/6) [jsonrpc.JsonRpcServer] RPC call Host.getHardwareInfo succeeded in 0.01 seconds (__init__:539)
2017-08-25 10:38:16,008+0200 INFO (jsonrpc/2) [jsonrpc.JsonRpcServer] RPC call Host.getStats succeeded in 0.21 seconds (__init__:539)
2017-08-25 10:38:16,097+0200 INFO (jsonrpc/3) [jsonrpc.JsonRpcServer] RPC call Host.getCapabilities succeeded in 0.09 seconds (__init__:539)
2017-08-25 10:38:18,138+0200 INFO (Reactor thread) [ProtocolDetector.AcceptorImpl] Accepted connection from ::1:49778 (protocoldetector:72)
2017-08-25 10:38:18,143+0200 INFO (Reactor thread) [ProtocolDetector.Detector] Detected protocol stomp from ::1:49778 (protocoldetector:127)
2017-08-25 10:38:18,143+0200 INFO (Reactor thread) [Broker.StompAdapter] Processing CONNECT request (stompreactor:103)
2017-08-25 10:38:18,144+0200 INFO (JsonRpc (StompReactor)) [Broker.StompAdapter] Subscribe command received (stompreactor:130)
2017-08-25 10:38:18,260+0200 INFO (jsonrpc/0) [jsonrpc.JsonRpcServer] RPC call Host.getHardwareInfo succeeded in 0.00 seconds (__init__:539)
2017-08-25 10:38:18,262+0200 INFO (jsonrpc/1) [jsonrpc.JsonRpcServer] RPC call VM.getStats failed (error 1) in 0.00 seconds (__init__:539)
2017-08-25 10:38:20,259+0200 INFO (jsonrpc/5) [vdsm.api] START repoStats(options=None) from=::ffff:172.16.252.200,42006, flow_id=538f0c0a, task_id=257e7571-493c-428f-9d73-1c8a93d505d7 (api:46)
2017-08-25 10:38:20,260+0200 INFO (jsonrpc/5) [vdsm.api] FINISH repoStats return={u'7c8deaa8-be02-4aaf-b9b4-ddc8da99ad96': {'code': 0, 'actual': True, 'version': 4, 'acquired': True, 'delay': '0.00025539', 'lastCheck': '5.5', 'valid': True}, u'2b2a44fc-f2bd-47cd-b7af-00be59e30a35': {'code': 0, 'actual': True, 'version': 0, 'acquired': True, 'delay': '0.000634126', 'lastCheck': '8.7', 'valid': True}, u'5d99af76-33b5-47d8-99da-1f32413c7bb0': {'code': 0, 'actual': True, 'version': 4, 'acquired': True, 'delay': '0.000311195', 'lastCheck': '7.6', 'valid': True}, u'a7fbaaad-7043-4391-9523-3bedcdc4fb0d': {'code': 0, 'actual': True, 'version': 0, 'acquired': True, 'delay': '0.00114361', 'lastCheck': '0.3', 'valid': True}} from=::ffff:172.16.252.200,42006, flow_id=538f0c0a, task_id=257e7571-493c-428f-9d73-1c8a93d505d7 (api:52)
2017-08-25 10:38:20,300+0200 INFO (jsonrpc/5) [jsonrpc.JsonRpcServer] RPC call Host.getStats succeeded in 0.04 seconds (__init__:539)
2017-08-25 10:38:25,997+0200 INFO (Reactor thread) [ProtocolDetector.AcceptorImpl] Accepted connection from ::1:49780 (protocoldetector:72)
2017-08-25 10:38:26,002+0200 INFO (Reactor thread) [ProtocolDetector.Detector] Detected protocol stomp from ::1:49780 (protocoldetector:127)
2017-08-25 10:38:26,002+0200 INFO (Reactor thread) [Broker.StompAdapter] Processing CONNECT request (stompreactor:103)
2017-08-25 10:38:26,003+0200 INFO (JsonRpc (StompReactor)) [Broker.StompAdapter] Subscribe command received (stompreactor:130)
2017-08-25 10:38:26,026+0200 INFO (Reactor thread) [ProtocolDetector.AcceptorImpl] Accepted connection from ::1:49782 (protocoldetector:72)
2017-08-25 10:38:26,034+0200 INFO (Reactor thread) [ProtocolDetector.Detector] Detected protocol stomp from ::1:49782 (protocoldetector:127)
2017-08-25 10:38:26,035+0200 INFO (Reactor thread) [Broker.StompAdapter] Processing CONNECT request (stompreactor:103)
2017-08-25 10:38:26,036+0200 INFO (JsonRpc (StompReactor)) [Broker.StompAdapter] Subscribe command received (stompreactor:130)
2017-08-25 10:38:26,185+0200 INFO (Reactor thread) [ProtocolDetector.AcceptorImpl] Accepted connection from ::1:49784 (protocoldetector:72)
2017-08-25 10:38:26,187+0200 INFO (jsonrpc/4) [jsonrpc.JsonRpcServer] RPC call Host.getHardwareInfo succeeded in 0.01 seconds (__init__:539)
2017-08-25 10:38:26,191+0200 INFO (Reactor thread) [ProtocolDetector.Detector] Detected protocol stomp from ::1:49784 (protocoldetector:127)
2017-08-25 10:38:26,191+0200 INFO (Reactor thread) [Broker.StompAdapter] Processing CONNECT request (stompreactor:103)
2017-08-25 10:38:26,192+0200 INFO (jsonrpc/7) [vdsm.api] START repoStats(options=None) from=::1,49782, task_id=75340568-a627-41c2-9693-42b7b4eb917e (api:46)
2017-08-25 10:38:26,193+0200 INFO (jsonrpc/7) [vdsm.api] FINISH repoStats return={u'7c8deaa8-be02-4aaf-b9b4-ddc8da99ad96': {'code': 0, 'actual': True, 'version': 4, 'acquired': True, 'delay': '0.000266598', 'lastCheck': '1.4', 'valid': True}, u'2b2a44fc-f2bd-47cd-b7af-00be59e30a35': {'code': 0, 'actual': True, 'version': 0, 'acquired': True, 'delay': '0.000744327', 'lastCheck': '4.6', 'valid': True}, u'5d99af76-33b5-47d8-99da-1f32413c7bb0': {'code': 0, 'actual': True, 'version': 4, 'acquired': True, 'delay': '0.000285439', 'lastCheck': '3.6', 'valid': True}, u'a7fbaaad-7043-4391-9523-3bedcdc4fb0d': {'code': 0, 'actual': True, 'version': 0, 'acquired': True, 'delay': '0.000704031', 'lastCheck': '4.7', 'valid': True}} from=::1,49782, task_id=75340568-a627-41c2-9693-42b7b4eb917e (api:52)
2017-08-25 10:38:26,196+0200 INFO (JsonRpc (StompReactor)) [Broker.StompAdapter] Subscribe command received (stompreactor:130)
2017-08-25 10:38:26,199+0200 INFO (jsonrpc/6) [jsonrpc.JsonRpcServer] RPC call Host.getHardwareInfo succeeded in 0.00 seconds (__init__:539)
2017-08-25 10:38:26,203+0200 INFO (jsonrpc/2) [vdsm.api] START getImagesList(sdUUID=u'7c8deaa8-be02-4aaf-b9b4-ddc8da99ad96', options=None) from=::1,49780, task_id=e4181e17-1805-44bb-a35e-75864b8b8010 (api:46)
2017-08-25 10:38:26,209+0200 INFO (jsonrpc/2) [vdsm.api] FINISH getImagesList return={'imageslist': [u'849f4aa8-b70f-484c-9d08-de2845146ba6', u'9a4d1e94-21e2-4747-8855-824a07198eed', u'ebc17228-e424-4893-b0cb-b4dfec01335a', u'066b2632-44fe-455b-93db-aca85262b3ef', u'faed9c01-c9e7-4302-8053-3671163cc3b8', u'a469f231-61f7-4b7a-95af-b56ac91d0574']} from=::1,49780, task_id=e4181e17-1805-44bb-a35e-75864b8b8010 (api:52)
2017-08-25 10:38:26,209+0200 INFO (jsonrpc/2) [jsonrpc.JsonRpcServer] RPC call StorageDomain.getImages succeeded in 0.01 seconds (__init__:539)
2017-08-25 10:38:26,218+0200 INFO (jsonrpc/3) [vdsm.api] START getVolumesList(sdUUID=u'7c8deaa8-be02-4aaf-b9b4-ddc8da99ad96', spUUID=u'00000000-0000-0000-0000-000000000000', imgUUID=u'849f4aa8-b70f-484c-9d08-de2845146ba6', options=None) from=::1,49780, task_id=194c9e23-60c4-4198-a19d-5da76133cd4f (api:46)
2017-08-25 10:38:26,225+0200 INFO (jsonrpc/3) [vdsm.api] FINISH getVolumesList return={'uuidlist': [u'f5e62fac-e0f3-49c7-91fa-4dbe577825f4']} from=::1,49780, task_id=194c9e23-60c4-4198-a19d-5da76133cd4f (api:52)
2017-08-25 10:38:26,226+0200 INFO (jsonrpc/3) [jsonrpc.JsonRpcServer] RPC call StorageDomain.getVolumes succeeded in 0.00 seconds (__init__:539)
2017-08-25 10:38:26,234+0200 INFO (jsonrpc/0) [vdsm.api] START getVolumeInfo(sdUUID=u'7c8deaa8-be02-4aaf-b9b4-ddc8da99ad96', spUUID=u'00000000-0000-0000-0000-000000000000', imgUUID=u'849f4aa8-b70f-484c-9d08-de2845146ba6', volUUID=u'f5e62fac-e0f3-49c7-91fa-4dbe577825f4', options=None) from=::1,49780, task_id=196c93ff-0d0a-4a57-9658-ea189f7ca061 (api:46)
2017-08-25 10:38:26,237+0200 INFO (jsonrpc/0) [storage.VolumeManifest] Info request: sdUUID=7c8deaa8-be02-4aaf-b9b4-ddc8da99ad96 imgUUID=849f4aa8-b70f-484c-9d08-de2845146ba6 volUUID = f5e62fac-e0f3-49c7-91fa-4dbe577825f4 (volume:238)
2017-08-25 10:38:26,244+0200 INFO (jsonrpc/0) [storage.VolumeManifest] 7c8deaa8-be02-4aaf-b9b4-ddc8da99ad96/849f4aa8-b70f-484c-9d08-de2845146ba6/f5e62fac-e0f3-49c7-91fa-4dbe577825f4 info is {'status': 'OK', 'domain': '7c8deaa8-be02-4aaf-b9b4-ddc8da99ad96', 'voltype': 'LEAF', 'description': '{"Updated":true,"Size":20480,"Last Updated":"Thu Aug 24 15:19:07 CEST 2017","Storage Domains":[{"uuid":"7c8deaa8-be02-4aaf-b9b4-ddc8da99ad96"}],"Disk Description":"OVF_STORE"}', 'parent': '00000000-0000-0000-0000-000000000000', 'format': 'RAW', 'generation': 0, 'image': '849f4aa8-b70f-484c-9d08-de2845146ba6', 'ctime': '1464553328', 'disktype': '2', 'legality': 'LEGAL', 'mtime': '0', 'apparentsize': '20480', 'children': [], 'pool': '', 'capacity': '134217728', 'uuid': u'f5e62fac-e0f3-49c7-91fa-4dbe577825f4', 'truesize': '20480', 'type': 'PREALLOCATED', 'lease': {'owners': [], 'version': None}} (volume:272)
2017-08-25 10:38:26,244+0200 INFO (jsonrpc/0) [vdsm.api] FINISH getVolumeInfo return={'info': {'status': 'OK', 'domain': '7c8deaa8-be02-4aaf-b9b4-ddc8da99ad96', 'voltype': 'LEAF', 'description': '{"Updated":true,"Size":20480,"Last Updated":"Thu Aug 24 15:19:07 CEST 2017","Storage Domains":[{"uuid":"7c8deaa8-be02-4aaf-b9b4-ddc8da99ad96"}],"Disk Description":"OVF_STORE"}', 'parent': '00000000-0000-0000-0000-000000000000', 'format': 'RAW', 'generation': 0, 'image': '849f4aa8-b70f-484c-9d08-de2845146ba6', 'ctime': '1464553328', 'disktype': '2', 'legality': 'LEGAL', 'mtime': '0', 'apparentsize': '20480', 'children': [], 'pool': '', 'capacity': '134217728', 'uuid': u'f5e62fac-e0f3-49c7-91fa-4dbe577825f4', 'truesize': '20480', 'type': 'PREALLOCATED', 'lease': {'owners': [], 'version': None}}} from=::1,49780, task_id=196c93ff-0d0a-4a57-9658-ea189f7ca061 (api:52)
2017-08-25 10:38:26,245+0200 INFO (jsonrpc/0) [jsonrpc.JsonRpcServer] RPC call Volume.getInfo succeeded in 0.01 seconds (__init__:539)
2017-08-25 10:38:26,252+0200 INFO (jsonrpc/1) [vdsm.api] START getVolumesList(sdUUID=u'7c8deaa8-be02-4aaf-b9b4-ddc8da99ad96', spUUID=u'00000000-0000-0000-0000-000000000000', imgUUID=u'9a4d1e94-21e2-4747-8855-824a07198eed', options=None) from=::1,49780, task_id=87b2bf3d-f093-4659-99db-f0d4859b93f2 (api:46)
2017-08-25 10:38:26,257+0200 INFO (jsonrpc/1) [vdsm.api] FINISH getVolumesList return={'uuidlist': [u'53fed829-0fc3-4ec5-8487-0a5371f9b885']} from=::1,49780, task_id=87b2bf3d-f093-4659-99db-f0d4859b93f2 (api:52)
2017-08-25 10:38:26,258+0200 INFO (jsonrpc/1) [jsonrpc.JsonRpcServer] RPC call StorageDomain.getVolumes succeeded in 0.01 seconds (__init__:539)
2017-08-25 10:38:26,267+0200 INFO (jsonrpc/5) [vdsm.api] START getVolumeInfo(sdUUID=u'7c8deaa8-be02-4aaf-b9b4-ddc8da99ad96', spUUID=u'00000000-0000-0000-0000-000000000000', imgUUID=u'9a4d1e94-21e2-4747-8855-824a07198eed', volUUID=u'53fed829-0fc3-4ec5-8487-0a5371f9b885', options=None) from=::1,49780, task_id=470b05f6-0bb1-4fd2-92aa-4f0fd72792d9 (api:46)
2017-08-25 10:38:26,270+0200 INFO (jsonrpc/5) [storage.VolumeManifest] Info request: sdUUID=7c8deaa8-be02-4aaf-b9b4-ddc8da99ad96 imgUUID=9a4d1e94-21e2-4747-8855-824a07198eed volUUID = 53fed829-0fc3-4ec5-8487-0a5371f9b885 (volume:238)
2017-08-25 10:38:26,298+0200 INFO (jsonrpc/5) [storage.VolumeManifest] 7c8deaa8-be02-4aaf-b9b4-ddc8da99ad96/9a4d1e94-21e2-4747-8855-824a07198eed/53fed829-0fc3-4ec5-8487-0a5371f9b885 info is {'status': 'OK', 'domain': '7c8deaa8-be02-4aaf-b9b4-ddc8da99ad96', 'voltype': 'LEAF', 'description': 'hosted-engine.lockspace', 'parent': '00000000-0000-0000-0000-000000000000', 'format': 'RAW', 'generation': 0, 'image': '9a4d1e94-21e2-4747-8855-824a07198eed', 'ctime': '1464545270', 'disktype': '2', 'legality': 'LEGAL', 'mtime': '0', 'apparentsize': '1048576', 'children': [], 'pool': '', 'capacity': '1048576', 'uuid': u'53fed829-0fc3-4ec5-8487-0a5371f9b885', 'truesize': '1048576', 'type': 'PREALLOCATED', 'lease': {'owners': [], 'version': None}} (volume:272)
2017-08-25 10:38:26,298+0200 INFO (jsonrpc/5) [vdsm.api] FINISH getVolumeInfo return={'info': {'status': 'OK', 'domain': '7c8deaa8-be02-4aaf-b9b4-ddc8da99ad96', 'voltype': 'LEAF', 'description': 'hosted-engine.lockspace', 'parent': '00000000-0000-0000-0000-000000000000', 'format': 'RAW', 'generation': 0, 'image': '9a4d1e94-21e2-4747-8855-824a07198eed', 'ctime': '1464545270', 'disktype': '2', 'legality': 'LEGAL', 'mtime': '0', 'apparentsize': '1048576', 'children': [], 'pool': '', 'capacity': '1048576', 'uuid': u'53fed829-0fc3-4ec5-8487-0a5371f9b885', 'truesize': '1048576', 'type': 'PREALLOCATED', 'lease': {'owners': [], 'version': None}}} from=::1,49780, task_id=470b05f6-0bb1-4fd2-92aa-4f0fd72792d9 (api:52)
2017-08-25 10:38:26,298+0200 INFO (jsonrpc/5) [jsonrpc.JsonRpcServer] RPC call Volume.getInfo succeeded in 0.03 seconds (__init__:539)
2017-08-25 10:38:26,331+0200 INFO (jsonrpc/4) [vdsm.api] START getVolumesList(sdUUID=u'7c8deaa8-be02-4aaf-b9b4-ddc8da99ad96', spUUID=u'00000000-0000-0000-0000-000000000000', imgUUID=u'ebc17228-e424-4893-b0cb-b4dfec01335a', options=None) from=::1,49780, task_id=7d81b0db-b0e4-433f-a2f7-2f94aa79eeb8 (api:46)
2017-08-25 10:38:26,337+0200 INFO (jsonrpc/4) [vdsm.api] FINISH getVolumesList return={'uuidlist': [u'a64731f8-600f-4153-ac97-a808ac4aaa3e']} from=::1,49780, task_id=7d81b0db-b0e4-433f-a2f7-2f94aa79eeb8 (api:52)
2017-08-25 10:38:26,338+0200 INFO (jsonrpc/4) [jsonrpc.JsonRpcServer] RPC call StorageDomain.getVolumes succeeded in 0.01 seconds (__init__:539)
2017-08-25 10:38:26,346+0200 INFO (jsonrpc/6) [jsonrpc.JsonRpcServer] RPC call Host.getHardwareInfo succeeded in 0.00 seconds (__init__:539)
2017-08-25 10:38:26,347+0200 INFO (jsonrpc/2) [vdsm.api] START getVolumeInfo(sdUUID=u'7c8deaa8-be02-4aaf-b9b4-ddc8da99ad96', spUUID=u'00000000-0000-0000-0000-000000000000', imgUUID=u'ebc17228-e424-4893-b0cb-b4dfec01335a', volUUID=u'a64731f8-600f-4153-ac97-a808ac4aaa3e', options=None) from=::1,49780, task_id=cdffaaab-1111-4a63-913b-dabcbc7ababe (api:46)
2017-08-25 10:38:26,360+0200 INFO (jsonrpc/7) [jsonrpc.JsonRpcServer] RPC call Host.getStats succeeded in 0.17 seconds (__init__:539)
2017-08-25 10:38:26,380+0200 INFO (jsonrpc/2) [storage.VolumeManifest] Info request: sdUUID=7c8deaa8-be02-4aaf-b9b4-ddc8da99ad96 imgUUID=ebc17228-e424-4893-b0cb-b4dfec01335a volUUID = a64731f8-600f-4153-ac97-a808ac4aaa3e (volume:238)
2017-08-25 10:38:26,389+0200 INFO (jsonrpc/2) [storage.VolumeManifest] 7c8deaa8-be02-4aaf-b9b4-ddc8da99ad96/ebc17228-e424-4893-b0cb-b4dfec01335a/a64731f8-600f-4153-ac97-a808ac4aaa3e info is {'status': 'OK', 'domain': '7c8deaa8-be02-4aaf-b9b4-ddc8da99ad96', 'voltype': 'LEAF', 'description': 'hosted-engine.metadata', 'parent': '00000000-0000-0000-0000-000000000000', 'format': 'RAW', 'generation': 0, 'image': 'ebc17228-e424-4893-b0cb-b4dfec01335a', 'ctime': '1464545271', 'disktype': '2', 'legality': 'LEGAL', 'mtime': '0', 'apparentsize': '1028096', 'children': [], 'pool': '', 'capacity': '1028096', 'uuid': u'a64731f8-600f-4153-ac97-a808ac4aaa3e', 'truesize': '1028096', 'type': 'PREALLOCATED', 'lease': {'owners': [], 'version': None}} (volume:272)
2017-08-25 10:38:26,389+0200 INFO (jsonrpc/2) [vdsm.api] FINISH getVolumeInfo return={'info': {'status': 'OK', 'domain': '7c8deaa8-be02-4aaf-b9b4-ddc8da99ad96', 'voltype': 'LEAF', 'description': 'hosted-engine.metadata', 'parent': '00000000-0000-0000-0000-000000000000', 'format': 'RAW', 'generation': 0, 'image': 'ebc17228-e424-4893-b0cb-b4dfec01335a', 'ctime': '1464545271', 'disktype': '2', 'legality': 'LEGAL', 'mtime': '0', 'apparentsize': '1028096', 'children': [], 'pool': '', 'capacity': '1028096', 'uuid': u'a64731f8-600f-4153-ac97-a808ac4aaa3e', 'truesize': '1028096', 'type': 'PREALLOCATED', 'lease': {'owners': [], 'version': None}}} from=::1,49780, task_id=cdffaaab-1111-4a63-913b-dabcbc7ababe (api:52)
2017-08-25 10:38:26,390+0200 INFO (jsonrpc/2) [jsonrpc.JsonRpcServer] RPC call Volume.getInfo succeeded in 0.04 seconds (__init__:539)
2017-08-25 10:38:26,410+0200 INFO (jsonrpc/0) [vdsm.api] START getVolumesList(sdUUID=u'7c8deaa8-be02-4aaf-b9b4-ddc8da99ad96', spUUID=u'00000000-0000-0000-0000-000000000000', imgUUID=u'066b2632-44fe-455b-93db-aca85262b3ef', options=None) from=::1,49780, task_id=8f4f26e8-d7d8-4207-9661-13e173960427 (api:46)
2017-08-25 10:38:26,421+0200 INFO (jsonrpc/0) [vdsm.api] FINISH getVolumesList return={'uuidlist': [u'7e88ba33-345f-4317-977a-7ae98a2ccbbe']} from=::1,49780, task_id=8f4f26e8-d7d8-4207-9661-13e173960427 (api:52)
2017-08-25 10:38:26,421+0200 INFO (jsonrpc/0) [jsonrpc.JsonRpcServer] RPC call StorageDomain.getVolumes succeeded in 0.01 seconds (__init__:539)
2017-08-25 10:38:26,425+0200 INFO (jsonrpc/1) [vdsm.api] START getVolumeInfo(sdUUID=u'7c8deaa8-be02-4aaf-b9b4-ddc8da99ad96', spUUID=u'00000000-0000-0000-0000-000000000000', imgUUID=u'066b2632-44fe-455b-93db-aca85262b3ef', volUUID=u'7e88ba33-345f-4317-977a-7ae98a2ccbbe', options=None) from=::1,49780, task_id=7716a659-51c2-4843-8345-570a8a3a4454 (api:46)
2017-08-25 10:38:26,428+0200 INFO (jsonrpc/1) [storage.VolumeManifest] Info request: sdUUID=7c8deaa8-be02-4aaf-b9b4-ddc8da99ad96 imgUUID=066b2632-44fe-455b-93db-aca85262b3ef volUUID = 7e88ba33-345f-4317-977a-7ae98a2ccbbe (volume:238)
2017-08-25 10:38:26,435+0200 INFO (jsonrpc/1) [storage.VolumeManifest] 7c8deaa8-be02-4aaf-b9b4-ddc8da99ad96/066b2632-44fe-455b-93db-aca85262b3ef/7e88ba33-345f-4317-977a-7ae98a2ccbbe info is {'status': 'OK', 'domain': '7c8deaa8-be02-4aaf-b9b4-ddc8da99ad96', 'voltype': 'LEAF', 'description': 'HostedEngineConfigurationImage', 'parent': '00000000-0000-0000-0000-000000000000', 'format': 'RAW', 'generation': 0, 'image': '066b2632-44fe-455b-93db-aca85262b3ef', 'ctime': '1464545267', 'disktype': '2', 'legality': 'LEGAL', 'mtime': '0', 'apparentsize': '20480', 'children': [], 'pool': '', 'capacity': '1073741824', 'uuid': u'7e88ba33-345f-4317-977a-7ae98a2ccbbe', 'truesize': '20480', 'type': 'PREALLOCATED', 'lease': {'owners': [], 'version': None}} (volume:272)
2017-08-25 10:38:26,435+0200 INFO (jsonrpc/1) [vdsm.api] FINISH getVolumeInfo return={'info': {'status': 'OK', 'domain': '7c8deaa8-be02-4aaf-b9b4-ddc8da99ad96', 'voltype': 'LEAF', 'description': 'HostedEngineConfigurationImage', 'parent': '00000000-0000-0000-0000-000000000000', 'format': 'RAW', 'generation': 0, 'image': '066b2632-44fe-455b-93db-aca85262b3ef', 'ctime': '1464545267', 'disktype': '2', 'legality': 'LEGAL', 'mtime': '0', 'apparentsize': '20480', 'children': [], 'pool': '', 'capacity': '1073741824', 'uuid': u'7e88ba33-345f-4317-977a-7ae98a2ccbbe', 'truesize': '20480', 'type': 'PREALLOCATED', 'lease': {'owners': [], 'version': None}}} from=::1,49780, task_id=7716a659-51c2-4843-8345-570a8a3a4454 (api:52)
2017-08-25 10:38:26,436+0200 INFO (jsonrpc/1) [jsonrpc.JsonRpcServer] RPC call Volume.getInfo succeeded in 0.01 seconds (__init__:539)
2017-08-25 10:38:26,455+0200 INFO (jsonrpc/5) [vdsm.api] START getVolumesList(sdUUID=u'7c8deaa8-be02-4aaf-b9b4-ddc8da99ad96', spUUID=u'00000000-0000-0000-0000-000000000000', imgUUID=u'faed9c01-c9e7-4302-8053-3671163cc3b8', options=None) from=::1,49780, task_id=d17b9874-85ab-4841-82b3-07ec2859492f (api:46)
2017-08-25 10:38:26,459+0200 INFO (jsonrpc/3) [jsonrpc.JsonRpcServer] RPC call Host.getCapabilities succeeded in 0.11 seconds (__init__:539)
2017-08-25 10:38:26,462+0200 INFO (jsonrpc/5) [vdsm.api] FINISH getVolumesList return={'uuidlist': [u'6aaea89f-ea88-4d57-aa3e-6252ef102949']} from=::1,49780, task_id=d17b9874-85ab-4841-82b3-07ec2859492f (api:52)
2017-08-25 10:38:26,462+0200 INFO (jsonrpc/5) [jsonrpc.JsonRpcServer] RPC call StorageDomain.getVolumes succeeded in 0.01 seconds (__init__:539)
2017-08-25 10:38:26,473+0200 INFO (jsonrpc/4) [vdsm.api] START getVolumeInfo(sdUUID=u'7c8deaa8-be02-4aaf-b9b4-ddc8da99ad96', spUUID=u'00000000-0000-0000-0000-000000000000', imgUUID=u'faed9c01-c9e7-4302-8053-3671163cc3b8', volUUID=u'6aaea89f-ea88-4d57-aa3e-6252ef102949', options=None) from=::1,49780, task_id=1d6d370b-0b1b-45c9-859f-593b82e566ad (api:46)
2017-08-25 10:38:26,475+0200 INFO (jsonrpc/4) [storage.VolumeManifest] Info request: sdUUID=7c8deaa8-be02-4aaf-b9b4-ddc8da99ad96 imgUUID=faed9c01-c9e7-4302-8053-3671163cc3b8 volUUID = 6aaea89f-ea88-4d57-aa3e-6252ef102949 (volume:238)
2017-08-25 10:38:26,483+0200 INFO (jsonrpc/4) [storage.VolumeManifest] 7c8deaa8-be02-4aaf-b9b4-ddc8da99ad96/faed9c01-c9e7-4302-8053-3671163cc3b8/6aaea89f-ea88-4d57-aa3e-6252ef102949 info is {'status': 'OK', 'domain': '7c8deaa8-be02-4aaf-b9b4-ddc8da99ad96', 'voltype': 'LEAF', 'description': 'Hosted Engine Image', 'parent': '00000000-0000-0000-0000-000000000000', 'format': 'RAW', 'generation': 0, 'image': 'faed9c01-c9e7-4302-8053-3671163cc3b8', 'ctime': '1464545278', 'disktype': '2', 'legality': 'LEGAL', 'mtime': '0', 'apparentsize': '10737418240', 'children': [], 'pool': '', 'capacity': '10737418240', 'uuid': u'6aaea89f-ea88-4d57-aa3e-6252ef102949', 'truesize': '8490876928', 'type': 'SPARSE', 'lease': {'owners': [1], 'version': 47L}} (volume:272)
2017-08-25 10:38:26,483+0200 INFO (jsonrpc/4) [vdsm.api] FINISH getVolumeInfo return={'info': {'status': 'OK', 'domain': '7c8deaa8-be02-4aaf-b9b4-ddc8da99ad96', 'voltype': 'LEAF', 'description': 'Hosted Engine Image', 'parent': '00000000-0000-0000-0000-000000000000', 'format': 'RAW', 'generation': 0, 'image': 'faed9c01-c9e7-4302-8053-3671163cc3b8', 'ctime': '1464545278', 'disktype': '2', 'legality': 'LEGAL', 'mtime': '0', 'apparentsize': '10737418240', 'children': [], 'pool': '', 'capacity': '10737418240', 'uuid': u'6aaea89f-ea88-4d57-aa3e-6252ef102949', 'truesize': '8490876928', 'type': 'SPARSE', 'lease': {'owners': [1], 'version': 47L}}} from=::1,49780, task_id=1d6d370b-0b1b-45c9-859f-593b82e566ad (api:52)
2017-08-25 10:38:26,483+0200 INFO (jsonrpc/4) [jsonrpc.JsonRpcServer] RPC call Volume.getInfo succeeded in 0.01 seconds (__init__:539)
2017-08-25 10:38:26,488+0200 INFO (jsonrpc/6) [vdsm.api] START getVolumesList(sdUUID=u'7c8deaa8-be02-4aaf-b9b4-ddc8da99ad96', spUUID=u'00000000-0000-0000-0000-000000000000', imgUUID=u'a469f231-61f7-4b7a-95af-b56ac91d0574', options=None) from=::1,49780, task_id=c49fe081-e93f-4f3c-8798-7dbd5f62f1db (api:46)
2017-08-25 10:38:26,494+0200 INFO (jsonrpc/6) [vdsm.api] FINISH getVolumesList return={'uuidlist': [u'86edb440-cd6c-49f9-8df0-ee25f6ac4748']} from=::1,49780, task_id=c49fe081-e93f-4f3c-8798-7dbd5f62f1db (api:52)
2017-08-25 10:38:26,495+0200 INFO (jsonrpc/6) [jsonrpc.JsonRpcServer] RPC call StorageDomain.getVolumes succeeded in 0.00 seconds (__init__:539)
2017-08-25 10:38:26,503+0200 INFO (jsonrpc/7) [vdsm.api] START getVolumeInfo(sdUUID=u'7c8deaa8-be02-4aaf-b9b4-ddc8da99ad96', spUUID=u'00000000-0000-0000-0000-000000000000', imgUUID=u'a469f231-61f7-4b7a-95af-b56ac91d0574', volUUID=u'86edb440-cd6c-49f9-8df0-ee25f6ac4748', options=None) from=::1,49780, task_id=ba4106a3-df9e-424f-b042-f321dabc0745 (api:46)
2017-08-25 10:38:26,506+0200 INFO (jsonrpc/7) [storage.VolumeManifest] Info request: sdUUID=7c8deaa8-be02-4aaf-b9b4-ddc8da99ad96 imgUUID=a469f231-61f7-4b7a-95af-b56ac91d0574 volUUID = 86edb440-cd6c-49f9-8df0-ee25f6ac4748 (volume:238)
2017-08-25 10:38:26,512+0200 INFO (jsonrpc/7) [storage.VolumeManifest] 7c8deaa8-be02-4aaf-b9b4-ddc8da99ad96/a469f231-61f7-4b7a-95af-b56ac91d0574/86edb440-cd6c-49f9-8df0-ee25f6ac4748 info is {'status': 'OK', 'domain': '7c8deaa8-be02-4aaf-b9b4-ddc8da99ad96', 'voltype': 'LEAF', 'description': '{"Updated":true,"Size":20480,"Last Updated":"Thu Aug 24 15:19:07 CEST 2017","Storage Domains":[{"uuid":"7c8deaa8-be02-4aaf-b9b4-ddc8da99ad96"}],"Disk Description":"OVF_STORE"}', 'parent': '00000000-0000-0000-0000-000000000000', 'format': 'RAW', 'generation': 0, 'image': 'a469f231-61f7-4b7a-95af-b56ac91d0574', 'ctime': '1464553325', 'disktype': '2', 'legality': 'LEGAL', 'mtime': '0', 'apparentsize': '20480', 'children': [], 'pool': '', 'capacity': '134217728', 'uuid': u'86edb440-cd6c-49f9-8df0-ee25f6ac4748', 'truesize': '20480', 'type': 'PREALLOCATED', 'lease': {'owners': [], 'version': None}} (volume:272)
2017-08-25 10:38:26,513+0200 INFO (jsonrpc/7) [vdsm.api] FINISH getVolumeInfo return={'info': {'status': 'OK', 'domain': '7c8deaa8-be02-4aaf-b9b4-ddc8da99ad96', 'voltype': 'LEAF', 'description': '{"Updated":true,"Size":20480,"Last Updated":"Thu Aug 24 15:19:07 CEST 2017","Storage Domains":[{"uuid":"7c8deaa8-be02-4aaf-b9b4-ddc8da99ad96"}],"Disk Description":"OVF_STORE"}', 'parent': '00000000-0000-0000-0000-000000000000', 'format': 'RAW', 'generation': 0, 'image': 'a469f231-61f7-4b7a-95af-b56ac91d0574', 'ctime': '1464553325', 'disktype': '2', 'legality': 'LEGAL', 'mtime': '0', 'apparentsize': '20480', 'children': [], 'pool': '', 'capacity': '134217728', 'uuid': u'86edb440-cd6c-49f9-8df0-ee25f6ac4748', 'truesize': '20480', 'type': 'PREALLOCATED', 'lease': {'owners': [], 'version': None}}} from=::1,49780, task_id=ba4106a3-df9e-424f-b042-f321dabc0745 (api:52)
2017-08-25 10:38:26,513+0200 INFO (jsonrpc/7) [jsonrpc.JsonRpcServer] RPC call Volume.getInfo succeeded in 0.01 seconds (__init__:539)
2017-08-25 10:38:26,532+0200 INFO (jsonrpc/2) [jsonrpc.JsonRpcServer] RPC call Host.getAllVmStats succeeded in 0.00 seconds (__init__:539)
2017-08-25 10:38:28,282+0200 INFO (Reactor thread) [ProtocolDetector.AcceptorImpl] Accepted connection from ::1:49786 (protocoldetector:72)
2017-08-25 10:38:28,287+0200 INFO (Reactor thread) [ProtocolDetector.Detector] Detected protocol stomp from ::1:49786 (protocoldetector:127)
2017-08-25 10:38:28,287+0200 INFO (Reactor thread) [Broker.StompAdapter] Processing CONNECT request (stompreactor:103)
2017-08-25 10:38:28,288+0200 INFO (JsonRpc (StompReactor)) [Broker.StompAdapter] Subscribe command received (stompreactor:130)
2017-08-25 10:38:28,418+0200 INFO (jsonrpc/0) [jsonrpc.JsonRpcServer] RPC call Host.getHardwareInfo succeeded in 0.00 seconds (__init__:539)
2017-08-25 10:38:28,420+0200 INFO (jsonrpc/1) [jsonrpc.JsonRpcServer] RPC call VM.getStats failed (error 1) in 0.00 seconds (__init__:539)
2017-08-25 10:38:28,574+0200 INFO (Reactor thread) [ProtocolDetector.AcceptorImpl] Accepted connection from ::1:49788 (protocoldetector:72)
2017-08-25 10:38:28,579+0200 INFO (Reactor thread) [ProtocolDetector.Detector] Detected protocol stomp from ::1:49788 (protocoldetector:127)
2017-08-25 10:38:28,579+0200 INFO (Reactor thread) [Broker.StompAdapter] Processing CONNECT request (stompreactor:103)
2017-08-25 10:38:28,580+0200 INFO (JsonRpc (StompReactor)) [Broker.StompAdapter] Subscribe command received (stompreactor:130)
2017-08-25 10:38:28,704+0200 INFO (jsonrpc/3) [jsonrpc.JsonRpcServer] RPC call Host.getHardwareInfo succeeded in 0.00 seconds (__init__:539)
2017-08-25 10:38:30,277+0200 INFO (jsonrpc/5) [jsonrpc.JsonRpcServer] RPC call Host.getAllVmStats succeeded in 0.00 seconds (__init__:539)
2017-08-25 10:38:30,478+0200 INFO (periodic/1) [vdsm.api] START repoStats(options=None) from=internal, task_id=d91d0cca-202d-4181-9d24-045da7387da0 (api:46)
2017-08-25 10:38:30,478+0200 INFO (periodic/1) [vdsm.api] FINISH repoStats return={u'7c8deaa8-be02-4aaf-b9b4-ddc8da99ad96': {'code': 0, 'actual': True, 'version': 4, 'acquired': True, 'delay': '0.000266598', 'lastCheck': '5.7', 'valid': True}, u'2b2a44fc-f2bd-47cd-b7af-00be59e30a35': {'code': 0, 'actual': True, 'version': 0, 'acquired': True, 'delay': '0.000744327', 'lastCheck': '8.9', 'valid': True}, u'5d99af76-33b5-47d8-99da-1f32413c7bb0': {'code': 0, 'actual': True, 'version': 4, 'acquired': True, 'delay': '0.000285439', 'lastCheck': '7.8', 'valid': True}, u'a7fbaaad-7043-4391-9523-3bedcdc4fb0d': {'code': 0, 'actual': True, 'version': 0, 'acquired': True, 'delay': '0.000704031', 'lastCheck': '0.5', 'valid': True}} from=internal, task_id=d91d0cca-202d-4181-9d24-045da7387da0 (api:52)
2017-08-25 10:38:30,711+0200 INFO (Reactor thread) [ProtocolDetector.AcceptorImpl] Accepted connection from ::1:49790 (protocoldetector:72)
2017-08-25 10:38:30,715+0200 INFO (Reactor thread) [ProtocolDetector.Detector] Detected protocol stomp from ::1:49790 (protocoldetector:127)
2017-08-25 10:38:30,716+0200 INFO (Reactor thread) [Broker.StompAdapter] Processing CONNECT request (stompreactor:103)
2017-08-25 10:38:30,717+0200 INFO (JsonRpc (StompReactor)) [Broker.StompAdapter] Subscribe command received (stompreactor:130)
2017-08-25 10:38:30,872+0200 INFO (jsonrpc/4) [jsonrpc.JsonRpcServer] RPC call Host.getHardwareInfo succeeded in 0.00 seconds (__init__:539)
2017-08-25 10:38:30,874+0200 INFO (jsonrpc/6) [vdsm.api] START repoStats(options=None) from=::1,49790, task_id=4200f4a5-ee07-40c5-bbf6-f35457b0a949 (api:46)
2017-08-25 10:38:30,875+0200 INFO (jsonrpc/6) [vdsm.api] FINISH repoStats return={u'7c8deaa8-be02-4aaf-b9b4-ddc8da99ad96': {'code': 0, 'actual': True, 'version': 4, 'acquired': True, 'delay': '0.000266598', 'lastCheck': '6.1', 'valid': True}, u'2b2a44fc-f2bd-47cd-b7af-00be59e30a35': {'code': 0, 'actual': True, 'version': 0, 'acquired': True, 'delay': '0.000744327', 'lastCheck': '0.2', 'valid': True}, u'5d99af76-33b5-47d8-99da-1f32413c7bb0': {'code': 0, 'actual': True, 'version': 4, 'acquired': True, 'delay': '0.000285439', 'lastCheck': '8.2', 'valid': True}, u'a7fbaaad-7043-4391-9523-3bedcdc4fb0d': {'code': 0, 'actual': True, 'version': 0, 'acquired': True, 'delay': '0.000704031', 'lastCheck': '0.9', 'valid': True}} from=::1,49790, task_id=4200f4a5-ee07-40c5-bbf6-f35457b0a949 (api:52)
2017-08-25 10:38:30,875+0200 INFO (jsonrpc/6) [jsonrpc.JsonRpcServer] RPC call Host.getStorageRepoStats succeeded in 0.00 seconds (__init__:539)
2017-08-25 10:38:32,881+0200 INFO (Reactor thread) [ProtocolDetector.AcceptorImpl] Accepted connection from ::1:49792 (protocoldetector:72)
2017-08-25 10:38:32,886+0200 INFO (Reactor thread) [ProtocolDetector.Detector] Detected protocol stomp from ::1:49792 (protocoldetector:127)
2017-08-25 10:38:32,887+0200 INFO (Reactor thread) [Broker.StompAdapter] Processing CONNECT request (stompreactor:103)
2017-08-25 10:38:32,887+0200 INFO (JsonRpc (StompReactor)) [Broker.StompAdapter] Subscribe command received (stompreactor:130)
2017-08-25 10:38:33,035+0200 INFO (jsonrpc/7) [jsonrpc.JsonRpcServer] RPC call Host.getHardwareInfo succeeded in 0.00 seconds (__init__:539)
2017-08-25 10:38:33,038+0200 INFO (jsonrpc/2) [vdsm.api] START repoStats(options=None) from=::1,49792, task_id=08dfd086-01b0-4a93-852a-7e5d92f2b6be (api:46)
2017-08-25 10:38:33,038+0200 INFO (jsonrpc/2) [vdsm.api] FINISH repoStats return={u'7c8deaa8-be02-4aaf-b9b4-ddc8da99ad96': {'code': 0, 'actual': True, 'version': 4, 'acquired': True, 'delay': '0.000208777', 'lastCheck': '1.5', 'valid': True}, u'2b2a44fc-f2bd-47cd-b7af-00be59e30a35': {'code': 0, 'actual': True, 'version': 0, 'acquired': True, 'delay': '0.00076274', 'lastCheck': '1.5', 'valid': True}, u'5d99af76-33b5-47d8-99da-1f32413c7bb0': {'code': 0, 'actual': True, 'version': 4, 'acquired': True, 'delay': '0.000336876', 'lastCheck': '0.4', 'valid': True}, u'a7fbaaad-7043-4391-9523-3bedcdc4fb0d': {'code': 0, 'actual': True, 'version': 0, 'acquired': True, 'delay': '0.000794113', 'lastCheck': '1.5', 'valid': True}} from=::1,49792, task_id=08dfd086-01b0-4a93-852a-7e5d92f2b6be (api:52)
2017-08-25 10:38:33,039+0200 INFO (jsonrpc/2) [jsonrpc.JsonRpcServer] RPC call Host.getStorageRepoStats succeeded in 0.00 seconds (__init__:539)
2017-08-25 10:38:35,075+0200 INFO (Reactor thread) [ProtocolDetector.AcceptorImpl] Accepted connection from ::1:49794 (protocoldetector:72)
2017-08-25 10:38:35,080+0200 INFO (Reactor thread) [ProtocolDetector.Detector] Detected protocol stomp from ::1:49794 (protocoldetector:127)
2017-08-25 10:38:35,080+0200 INFO (Reactor thread) [Broker.StompAdapter] Processing CONNECT request (stompreactor:103)
2017-08-25 10:38:35,081+0200 INFO (JsonRpc (StompReactor)) [Broker.StompAdapter] Subscribe command received (stompreactor:130)
2017-08-25 10:38:35,240+0200 INFO (jsonrpc/0) [jsonrpc.JsonRpcServer] RPC call Host.getHardwareInfo succeeded in 0.00 seconds (__init__:539)
2017-08-25 10:38:36,393+0200 INFO (jsonrpc/1) [vdsm.api] START repoStats(options=None) from=::ffff:172.16.252.200,42006, flow_id=1e3f29c0, task_id=e64bef3d-2eb7-4cc6-983f-107038563b19 (api:46)
2017-08-25 10:38:36,393+0200 INFO (jsonrpc/1) [vdsm.api] FINISH repoStats return={u'7c8deaa8-be02-4aaf-b9b4-ddc8da99ad96': {'code': 0, 'actual': True, 'version': 4, 'acquired': True, 'delay': '0.000208777', 'lastCheck': '1.6', 'valid': True}, u'2b2a44fc-f2bd-47cd-b7af-00be59e30a35': {'code': 0, 'actual': True, 'version': 0, 'acquired': True, 'delay': '0.00076274', 'lastCheck': '4.8', 'valid': True}, u'5d99af76-33b5-47d8-99da-1f32413c7bb0': {'code': 0, 'actual': True, 'version': 4, 'acquired': True, 'delay': '0.000336876', 'lastCheck': '3.7', 'valid': True}, u'a7fbaaad-7043-4391-9523-3bedcdc4fb0d': {'code': 0, 'actual': True, 'version': 0, 'acquired': True, 'delay': '0.000794113', 'lastCheck': '4.9', 'valid': True}} from=::ffff:172.16.252.200,42006, flow_id=1e3f29c0, task_id=e64bef3d-2eb7-4cc6-983f-107038563b19 (api:52)
2017-08-25 10:38:36,395+0200 INFO (Reactor thread) [ProtocolDetector.AcceptorImpl] Accepted connection from ::1:49796 (protocoldetector:72)
2017-08-25 10:38:36,401+0200 INFO (Reactor thread) [ProtocolDetector.Detector] Detected protocol stomp from ::1:49796 (protocoldetector:127)
2017-08-25 10:38:36,401+0200 INFO (Reactor thread) [Broker.StompAdapter] Processing CONNECT request (stompreactor:103)
2017-08-25 10:38:36,402+0200 INFO (JsonRpc (StompReactor)) [Broker.StompAdapter] Subscribe command received (stompreactor:130)
2017-08-25 10:38:36,559+0200 INFO (Reactor thread) [ProtocolDetector.AcceptorImpl] Accepted connection from ::1:49798 (protocoldetector:72)
2017-08-25 10:38:36,560+0200 INFO (jsonrpc/3) [jsonrpc.JsonRpcServer] RPC call Host.getHardwareInfo succeeded in 0.00 seconds (__init__:539)
2017-08-25 10:38:36,564+0200 INFO (jsonrpc/5) [vdsm.api] START repoStats(options=None) from=::1,49796, task_id=a2cdc571-0615-4f30-a979-0dc882f50e20 (api:46)
2017-08-25 10:38:36,564+0200 INFO (jsonrpc/5) [vdsm.api] FINISH repoStats return={u'7c8deaa8-be02-4aaf-b9b4-ddc8da99ad96': {'code': 0, 'actual': True, 'version': 4, 'acquired': True, 'delay': '0.000208777', 'lastCheck': '1.8', 'valid': True}, u'2b2a44fc-f2bd-47cd-b7af-00be59e30a35': {'code': 0, 'actual': True, 'version': 0, 'acquired': True, 'delay': '0.00076274', 'lastCheck': '5.0', 'valid': True}, u'5d99af76-33b5-47d8-99da-1f32413c7bb0': {'code': 0, 'actual': True, 'version': 4, 'acquired': True, 'delay': '0.000336876', 'lastCheck': '3.9', 'valid': True}, u'a7fbaaad-7043-4391-9523-3bedcdc4fb0d': {'code': 0, 'actual': True, 'version': 0, 'acquired': True, 'delay': '0.000794113', 'lastCheck': '5.0', 'valid': True}} from=::1,49796, task_id=a2cdc571-0615-4f30-a979-0dc882f50e20 (api:52)
2017-08-25 10:38:36,566+0200 INFO (jsonrpc/1) [jsonrpc.JsonRpcServer] RPC call Host.getStats succeeded in 0.17 seconds (__init__:539)
2017-08-25 10:38:36,567+0200 INFO (Reactor thread) [ProtocolDetector.Detector] Detected protocol stomp from ::1:49798 (protocoldetector:127)
2017-08-25 10:38:36,567+0200 INFO (Reactor thread) [Broker.StompAdapter] Processing CONNECT request (stompreactor:103)
2017-08-25 10:38:36,568+0200 INFO (JsonRpc (StompReactor)) [Broker.StompAdapter] Subscribe command received (stompreactor:130)
2017-08-25 10:38:36,719+0200 INFO (jsonrpc/4) [jsonrpc.JsonRpcServer] RPC call Host.getHardwareInfo succeeded in 0.00 seconds (__init__:539)
2017-08-25 10:38:36,722+0200 INFO (jsonrpc/5) [jsonrpc.JsonRpcServer] RPC call Host.getStats succeeded in 0.16 seconds (__init__:539)
2017-08-25 10:38:36,812+0200 INFO (jsonrpc/6) [jsonrpc.JsonRpcServer] RPC call Host.getCapabilities succeeded in 0.09 seconds (__init__:539)
2017-08-25 10:38:38,437+0200 INFO (Reactor thread) [ProtocolDetector.AcceptorImpl] Accepted connection from ::1:49800 (protocoldetector:72)
2017-08-25 10:38:38,442+0200 INFO (Reactor thread) [ProtocolDetector.Detector] Detected protocol stomp from ::1:49800 (protocoldetector:127)
2017-08-25 10:38:38,442+0200 INFO (Reactor thread) [Broker.StompAdapter] Processing CONNECT request (stompreactor:103)
2017-08-25 10:38:38,446+0200 INFO (JsonRpc (StompReactor)) [Broker.StompAdapter] Subscribe command received (stompreactor:130)
2017-08-25 10:38:38,581+0200 INFO (jsonrpc/7) [jsonrpc.JsonRpcServer] RPC call Host.getHardwareInfo succeeded in 0.00 seconds (__init__:539)
2017-08-25 10:38:38,584+0200 INFO (jsonrpc/2) [jsonrpc.JsonRpcServer] RPC call VM.getStats failed (error 1) in 0.00 seconds (__init__:539)
2017-08-25 10:38:39,391+0200 INFO (Reactor thread) [ProtocolDetector.AcceptorImpl] Accepted connection from ::1:49802 (protocoldetector:72)
2017-08-25 10:38:39,403+0200 INFO (Reactor thread) [ProtocolDetector.Detector] Detected protocol stomp from ::1:49802 (protocoldetector:127)
2017-08-25 10:38:39,404+0200 INFO (Reactor thread) [Broker.StompAdapter] Processing CONNECT request (stompreactor:103)
2017-08-25 10:38:39,407+0200 INFO (JsonRpc (StompReactor)) [Broker.StompAdapter] Subscribe command received (stompreactor:130)
2017-08-25 10:38:39,535+0200 INFO (jsonrpc/0) [jsonrpc.JsonRpcServer] RPC call Host.getHardwareInfo succeeded in 0.00 seconds (__init__:539)
2017-08-25 10:38:39,537+0200 INFO (jsonrpc/3) [jsonrpc.JsonRpcServer] RPC call VM.getStats failed (error 1) in 0.00 seconds (__init__:539)
2017-08-25 10:38:40,626+0200 INFO (monitor/2b2a44f) [IOProcessClient] Closing client ioprocess-1001 (__init__:598)
2017-08-25 10:38:40,627+0200 INFO (monitor/2b2a44f) [IOProcessClient] Closing client ioprocess-1003 (__init__:598)
2017-08-25 10:38:40,627+0200 INFO (monitor/2b2a44f) [IOProcessClient] Closing client ioprocess-1002 (__init__:598)
2017-08-25 10:38:40,627+0200 INFO (monitor/2b2a44f) [IOProcessClient] Closing client ioprocess-1004 (__init__:598)
2017-08-25 10:38:41,550+0200 INFO (jsonrpc/1) [jsonrpc.JsonRpcServer] RPC call Host.getAllVmStats succeeded in 0.00 seconds (__init__:539)
2017-08-25 10:38:45,413+0200 INFO (jsonrpc/4) [jsonrpc.JsonRpcServer] RPC call Host.getAllVmStats succeeded in 0.00 seconds (__init__:539)
2017-08-25 10:38:45,595+0200 INFO (periodic/1) [vdsm.api] START repoStats(options=None) from=internal, task_id=bed6ba9d-2474-4ea9-8a8f-813a781ba595 (api:46)
2017-08-25 10:38:45,595+0200 INFO (periodic/1) [vdsm.api] FINISH repoStats return={u'7c8deaa8-be02-4aaf-b9b4-ddc8da99ad96': {'code': 0, 'actual': True, 'version': 4, 'acquired': True, 'delay': '0.000242868', 'lastCheck': '0.8', 'valid': True}, u'2b2a44fc-f2bd-47cd-b7af-00be59e30a35': {'code': 0, 'actual': True, 'version': 0, 'acquired': True, 'delay': '0.000708472', 'lastCheck': '4.0', 'valid': True}, u'5d99af76-33b5-47d8-99da-1f32413c7bb0': {'code': 0, 'actual': True, 'version': 4, 'acquired': True, 'delay': '0.000366237', 'lastCheck': '2.9', 'valid': True}, u'a7fbaaad-7043-4391-9523-3bedcdc4fb0d': {'code': 0, 'actual': True, 'version': 0, 'acquired': True, 'delay': '0.000796127', 'lastCheck': '4.1', 'valid': True}} from=internal, task_id=bed6ba9d-2474-4ea9-8a8f-813a781ba595 (api:52)
2017-08-25 10:38:45,762+0200 INFO (Reactor thread) [ProtocolDetector.AcceptorImpl] Accepted connection from ::1:49804 (protocoldetector:72)
2017-08-25 10:38:45,766+0200 INFO (Reactor thread) [ProtocolDetector.Detector] Detected protocol stomp from ::1:49804 (protocoldetector:127)
2017-08-25 10:38:45,767+0200 INFO (Reactor thread) [Broker.StompAdapter] Processing CONNECT request (stompreactor:103)
2017-08-25 10:38:45,767+0200 INFO (JsonRpc (StompReactor)) [Broker.StompAdapter] Subscribe command received (stompreactor:130)
2017-08-25 10:38:45,893+0200 INFO (Reactor thread) [ProtocolDetector.AcceptorImpl] Accepted connection from ::1:49806 (protocoldetector:72)
2017-08-25 10:38:45,893+0200 INFO (jsonrpc/5) [jsonrpc.JsonRpcServer] RPC call Host.getHardwareInfo succeeded in 0.00 seconds (__init__:539)
2017-08-25 10:38:45,896+0200 INFO (jsonrpc/6) [vdsm.api] START repoStats(options=None) from=::1,49804, task_id=3dc38774-e79f-41af-8b45-fa90e90288b7 (api:46)
2017-08-25 10:38:45,896+0200 INFO (jsonrpc/6) [vdsm.api] FINISH repoStats return={u'7c8deaa8-be02-4aaf-b9b4-ddc8da99ad96': {'code': 0, 'actual': True, 'version': 4, 'acquired': True, 'delay': '0.000242868', 'lastCheck': '1.1', 'valid': True}, u'2b2a44fc-f2bd-47cd-b7af-00be59e30a35': {'code': 0, 'actual': True, 'version': 0, 'acquired': True, 'delay': '0.000708472', 'lastCheck': '4.3', 'valid': True}, u'5d99af76-33b5-47d8-99da-1f32413c7bb0': {'code': 0, 'actual': True, 'version': 4, 'acquired': True, 'delay': '0.000366237', 'lastCheck': '3.2', 'valid': True}, u'a7fbaaad-7043-4391-9523-3bedcdc4fb0d': {'code': 0, 'actual': True, 'version': 0, 'acquired': True, 'delay': '0.000796127', 'lastCheck': '4.4', 'valid': True}} from=::1,49804, task_id=3dc38774-e79f-41af-8b45-fa90e90288b7 (api:52)
2017-08-25 10:38:45,902+0200 INFO (Reactor thread) [ProtocolDetector.Detector] Detected protocol stomp from ::1:49806 (protocoldetector:127)
2017-08-25 10:38:45,902+0200 INFO (Reactor thread) [Broker.StompAdapter] Processing CONNECT request (stompreactor:103)
2017-08-25 10:38:45,903+0200 INFO (JsonRpc (StompReactor)) [Broker.StompAdapter] Subscribe command received (stompreactor:130)
2017-08-25 10:38:46,058+0200 INFO (jsonrpc/7) [jsonrpc.JsonRpcServer] RPC call Host.getHardwareInfo succeeded in 0.00 seconds (__init__:539)
2017-08-25 10:38:46,061+0200 INFO (jsonrpc/6) [jsonrpc.JsonRpcServer] RPC call Host.getStats succeeded in 0.17 seconds (__init__:539)
2017-08-25 10:38:46,158+0200 INFO (jsonrpc/2) [jsonrpc.JsonRpcServer] RPC call Host.getCapabilities succeeded in 0.10 seconds (__init__:539)
2017-08-25 10:38:47,383+0200 INFO (Reactor thread) [ProtocolDetector.AcceptorImpl] Accepted connection from ::1:49808 (protocoldetector:72)
2017-08-25 10:38:47,387+0200 INFO (Reactor thread) [ProtocolDetector.Detector] Detected protocol stomp from ::1:49808 (protocoldetector:127)
2017-08-25 10:38:47,388+0200 INFO (Reactor thread) [Broker.StompAdapter] Processing CONNECT request (stompreactor:103)
2017-08-25 10:38:47,391+0200 INFO (JsonRpc (StompReactor)) [Broker.StompAdapter] Subscribe command received (stompreactor:130)
2017-08-25 10:38:47,545+0200 INFO (jsonrpc/0) [jsonrpc.JsonRpcServer] RPC call Host.getHardwareInfo succeeded in 0.00 seconds (__init__:539)
2017-08-25 10:38:47,548+0200 INFO (jsonrpc/3) [vdsm.api] START getImagesList(sdUUID=u'7c8deaa8-be02-4aaf-b9b4-ddc8da99ad96', options=None) from=::1,49808, task_id=87a8d6a7-627a-4878-aef4-1dddae86c850 (api:46)
2017-08-25 10:38:47,555+0200 INFO (jsonrpc/3) [vdsm.api] FINISH getImagesList return={'imageslist': [u'849f4aa8-b70f-484c-9d08-de2845146ba6', u'9a4d1e94-21e2-4747-8855-824a07198eed', u'ebc17228-e424-4893-b0cb-b4dfec01335a', u'066b2632-44fe-455b-93db-aca85262b3ef', u'faed9c01-c9e7-4302-8053-3671163cc3b8', u'a469f231-61f7-4b7a-95af-b56ac91d0574']} from=::1,49808, task_id=87a8d6a7-627a-4878-aef4-1dddae86c850 (api:52)
2017-08-25 10:38:47,556+0200 INFO (jsonrpc/3) [jsonrpc.JsonRpcServer] RPC call StorageDomain.getImages succeeded in 0.00 seconds (__init__:539)
2017-08-25 10:38:47,564+0200 INFO (jsonrpc/1) [vdsm.api] START getVolumesList(sdUUID=u'7c8deaa8-be02-4aaf-b9b4-ddc8da99ad96', spUUID=u'00000000-0000-0000-0000-000000000000', imgUUID=u'849f4aa8-b70f-484c-9d08-de2845146ba6', options=None) from=::1,49808, task_id=513734f9-6ad0-416a-bfb0-0d0f8dab5ee6 (api:46)
2017-08-25 10:38:47,569+0200 INFO (jsonrpc/1) [vdsm.api] FINISH getVolumesList return={'uuidlist': [u'f5e62fac-e0f3-49c7-91fa-4dbe577825f4']} from=::1,49808, task_id=513734f9-6ad0-416a-bfb0-0d0f8dab5ee6 (api:52)
2017-08-25 10:38:47,570+0200 INFO (jsonrpc/1) [jsonrpc.JsonRpcServer] RPC call StorageDomain.getVolumes succeeded in 0.01 seconds (__init__:539)
2017-08-25 10:38:47,571+0200 INFO (jsonrpc/4) [vdsm.api] START getVolumeInfo(sdUUID=u'7c8deaa8-be02-4aaf-b9b4-ddc8da99ad96', spUUID=u'00000000-0000-0000-0000-000000000000', imgUUID=u'849f4aa8-b70f-484c-9d08-de2845146ba6', volUUID=u'f5e62fac-e0f3-49c7-91fa-4dbe577825f4', options=None) from=::1,49808, task_id=1a1651b5-328d-463b-8d1e-62e4826276bf (api:46)
2017-08-25 10:38:47,575+0200 INFO (jsonrpc/4) [storage.VolumeManifest] Info request: sdUUID=7c8deaa8-be02-4aaf-b9b4-ddc8da99ad96 imgUUID=849f4aa8-b70f-484c-9d08-de2845146ba6 volUUID = f5e62fac-e0f3-49c7-91fa-4dbe577825f4 (volume:238)
2017-08-25 10:38:47,581+0200 INFO (jsonrpc/4) [storage.VolumeManifest] 7c8deaa8-be02-4aaf-b9b4-ddc8da99ad96/849f4aa8-b70f-484c-9d08-de2845146ba6/f5e62fac-e0f3-49c7-91fa-4dbe577825f4 info is {'status': 'OK', 'domain': '7c8deaa8-be02-4aaf-b9b4-ddc8da99ad96', 'voltype': 'LEAF', 'description': '{"Updated":true,"Size":20480,"Last Updated":"Thu Aug 24 15:19:07 CEST 2017","Storage Domains":[{"uuid":"7c8deaa8-be02-4aaf-b9b4-ddc8da99ad96"}],"Disk Description":"OVF_STORE"}', 'parent': '00000000-0000-0000-0000-000000000000', 'format': 'RAW', 'generation': 0, 'image': '849f4aa8-b70f-484c-9d08-de2845146ba6', 'ctime': '1464553328', 'disktype': '2', 'legality': 'LEGAL', 'mtime': '0', 'apparentsize': '20480', 'children': [], 'pool': '', 'capacity': '134217728', 'uuid': u'f5e62fac-e0f3-49c7-91fa-4dbe577825f4', 'truesize': '20480', 'type': 'PREALLOCATED', 'lease': {'owners': [], 'version': None}} (volume:272)
2017-08-25 10:38:47,581+0200 INFO (jsonrpc/4) [vdsm.api] FINISH getVolumeInfo return={'info': {'status': 'OK', 'domain': '7c8deaa8-be02-4aaf-b9b4-ddc8da99ad96', 'voltype': 'LEAF', 'description': '{"Updated":true,"Size":20480,"Last Updated":"Thu Aug 24 15:19:07 CEST 2017","Storage Domains":[{"uuid":"7c8deaa8-be02-4aaf-b9b4-ddc8da99ad96"}],"Disk Description":"OVF_STORE"}', 'parent': '00000000-0000-0000-0000-000000000000', 'format': 'RAW', 'generation': 0, 'image': '849f4aa8-b70f-484c-9d08-de2845146ba6', 'ctime': '1464553328', 'disktype': '2', 'legality': 'LEGAL', 'mtime': '0', 'apparentsize': '20480', 'children': [], 'pool': '', 'capacity': '134217728', 'uuid': u'f5e62fac-e0f3-49c7-91fa-4dbe577825f4', 'truesize': '20480', 'type': 'PREALLOCATED', 'lease': {'owners': [], 'version': None}}} from=::1,49808, task_id=1a1651b5-328d-463b-8d1e-62e4826276bf (api:52)
2017-08-25 10:38:47,582+0200 INFO (jsonrpc/4) [jsonrpc.JsonRpcServer] RPC call Volume.getInfo succeeded in 0.01 seconds (__init__:539)
2017-08-25 10:38:47,589+0200 INFO (jsonrpc/5) [vdsm.api] START getVolumesList(sdUUID=u'7c8deaa8-be02-4aaf-b9b4-ddc8da99ad96', spUUID=u'00000000-0000-0000-0000-000000000000', imgUUID=u'9a4d1e94-21e2-4747-8855-824a07198eed', options=None) from=::1,49808, task_id=775d9062-04e4-4e7e-8f6b-079d60895645 (api:46)
2017-08-25 10:38:47,595+0200 INFO (jsonrpc/5) [vdsm.api] FINISH getVolumesList return={'uuidlist': [u'53fed829-0fc3-4ec5-8487-0a5371f9b885']} from=::1,49808, task_id=775d9062-04e4-4e7e-8f6b-079d60895645 (api:52)
2017-08-25 10:38:47,596+0200 INFO (jsonrpc/5) [jsonrpc.JsonRpcServer] RPC call StorageDomain.getVolumes succeeded in 0.00 seconds (__init__:539)
2017-08-25 10:38:47,597+0200 INFO (Reactor thread) [ProtocolDetector.AcceptorImpl] Accepted connection from ::1:49810 (protocoldetector:72)
2017-08-25 10:38:47,602+0200 INFO (Reactor thread) [ProtocolDetector.Detector] Detected protocol stomp from ::1:49810 (protocoldetector:127)
2017-08-25 10:38:47,603+0200 INFO (Reactor thread) [Broker.StompAdapter] Processing CONNECT request (stompreactor:103)
2017-08-25 10:38:47,604+0200 INFO (JsonRpc (StompReactor)) [Broker.StompAdapter] Subscribe command received (stompreactor:130)
2017-08-25 10:38:47,605+0200 INFO (jsonrpc/7) [vdsm.api] START getVolumeInfo(sdUUID=u'7c8deaa8-be02-4aaf-b9b4-ddc8da99ad96', spUUID=u'00000000-0000-0000-0000-000000000000', imgUUID=u'9a4d1e94-21e2-4747-8855-824a07198eed', volUUID=u'53fed829-0fc3-4ec5-8487-0a5371f9b885', options=None) from=::1,49808, task_id=5b2355bb-6bd9-455c-a5b0-6addd8a01fd0 (api:46)
2017-08-25 10:38:47,608+0200 INFO (jsonrpc/7) [storage.VolumeManifest] Info request: sdUUID=7c8deaa8-be02-4aaf-b9b4-ddc8da99ad96 imgUUID=9a4d1e94-21e2-4747-8855-824a07198eed volUUID = 53fed829-0fc3-4ec5-8487-0a5371f9b885 (volume:238)
2017-08-25 10:38:47,614+0200 INFO (jsonrpc/7) [storage.VolumeManifest] 7c8deaa8-be02-4aaf-b9b4-ddc8da99ad96/9a4d1e94-21e2-4747-8855-824a07198eed/53fed829-0fc3-4ec5-8487-0a5371f9b885 info is {'status': 'OK', 'domain': '7c8deaa8-be02-4aaf-b9b4-ddc8da99ad96', 'voltype': 'LEAF', 'description': 'hosted-engine.lockspace', 'parent': '00000000-0000-0000-0000-000000000000', 'format': 'RAW', 'generation': 0, 'image': '9a4d1e94-21e2-4747-8855-824a07198eed', 'ctime': '1464545270', 'disktype': '2', 'legality': 'LEGAL', 'mtime': '0', 'apparentsize': '1048576', 'children': [], 'pool': '', 'capacity': '1048576', 'uuid': u'53fed829-0fc3-4ec5-8487-0a5371f9b885', 'truesize': '1048576', 'type': 'PREALLOCATED', 'lease': {'owners': [], 'version': None}} (volume:272)
2017-08-25 10:38:47,614+0200 INFO (jsonrpc/7) [vdsm.api] FINISH getVolumeInfo return={'info': {'status': 'OK', 'domain': '7c8deaa8-be02-4aaf-b9b4-ddc8da99ad96', 'voltype': 'LEAF', 'description': 'hosted-engine.lockspace', 'parent': '00000000-0000-0000-0000-000000000000', 'format': 'RAW', 'generation': 0, 'image': '9a4d1e94-21e2-4747-8855-824a07198eed', 'ctime': '1464545270', 'disktype': '2', 'legality': 'LEGAL', 'mtime': '0', 'apparentsize': '1048576', 'children': [], 'pool': '', 'capacity': '1048576', 'uuid': u'53fed829-0fc3-4ec5-8487-0a5371f9b885', 'truesize': '1048576', 'type': 'PREALLOCATED', 'lease': {'owners': [], 'version': None}}} from=::1,49808, task_id=5b2355bb-6bd9-455c-a5b0-6addd8a01fd0 (api:52)
2017-08-25 10:38:47,615+0200 INFO (jsonrpc/7) [jsonrpc.JsonRpcServer] RPC call Volume.getInfo succeeded in 0.01 seconds (__init__:539)
2017-08-25 10:38:47,620+0200 INFO (jsonrpc/6) [vdsm.api] START getVolumesList(sdUUID=u'7c8deaa8-be02-4aaf-b9b4-ddc8da99ad96', spUUID=u'00000000-0000-0000-0000-000000000000', imgUUID=u'ebc17228-e424-4893-b0cb-b4dfec01335a', options=None) from=::1,49808, task_id=c7b03a46-0317-4971-80d5-824b26717328 (api:46)
2017-08-25 10:38:47,627+0200 INFO (jsonrpc/6) [vdsm.api] FINISH getVolumesList return={'uuidlist': [u'a64731f8-600f-4153-ac97-a808ac4aaa3e']} from=::1,49808, task_id=c7b03a46-0317-4971-80d5-824b26717328 (api:52)
2017-08-25 10:38:47,627+0200 INFO (jsonrpc/6) [jsonrpc.JsonRpcServer] RPC call StorageDomain.getVolumes succeeded in 0.01 seconds (__init__:539)
2017-08-25 10:38:47,636+0200 INFO (jsonrpc/2) [vdsm.api] START getVolumeInfo(sdUUID=u'7c8deaa8-be02-4aaf-b9b4-ddc8da99ad96', spUUID=u'00000000-0000-0000-0000-000000000000', imgUUID=u'ebc17228-e424-4893-b0cb-b4dfec01335a', volUUID=u'a64731f8-600f-4153-ac97-a808ac4aaa3e', options=None) from=::1,49808, task_id=94725ecc-c724-424a-8b1b-42385a6697b1 (api:46)
2017-08-25 10:38:47,639+0200 INFO (jsonrpc/2) [storage.VolumeManifest] Info request: sdUUID=7c8deaa8-be02-4aaf-b9b4-ddc8da99ad96 imgUUID=ebc17228-e424-4893-b0cb-b4dfec01335a volUUID = a64731f8-600f-4153-ac97-a808ac4aaa3e (volume:238)
2017-08-25 10:38:47,646+0200 INFO (jsonrpc/2) [storage.VolumeManifest] 7c8deaa8-be02-4aaf-b9b4-ddc8da99ad96/ebc17228-e424-4893-b0cb-b4dfec01335a/a64731f8-600f-4153-ac97-a808ac4aaa3e info is {'status': 'OK', 'domain': '7c8deaa8-be02-4aaf-b9b4-ddc8da99ad96', 'voltype': 'LEAF', 'description': 'hosted-engine.metadata', 'parent': '00000000-0000-0000-0000-000000000000', 'format': 'RAW', 'generation': 0, 'image': 'ebc17228-e424-4893-b0cb-b4dfec01335a', 'ctime': '1464545271', 'disktype': '2', 'legality': 'LEGAL', 'mtime': '0', 'apparentsize': '1028096', 'children': [], 'pool': '', 'capacity': '1028096', 'uuid': u'a64731f8-600f-4153-ac97-a808ac4aaa3e', 'truesize': '1028096', 'type': 'PREALLOCATED', 'lease': {'owners': [], 'version': None}} (volume:272)
2017-08-25 10:38:47,646+0200 INFO (jsonrpc/2) [vdsm.api] FINISH getVolumeInfo return={'info': {'status': 'OK', 'domain': '7c8deaa8-be02-4aaf-b9b4-ddc8da99ad96', 'voltype': 'LEAF', 'description': 'hosted-engine.metadata', 'parent': '00000000-0000-0000-0000-000000000000', 'format': 'RAW', 'generation': 0, 'image': 'ebc17228-e424-4893-b0cb-b4dfec01335a', 'ctime': '1464545271', 'disktype': '2', 'legality': 'LEGAL', 'mtime': '0', 'apparentsize': '1028096', 'children': [], 'pool': '', 'capacity': '1028096', 'uuid': u'a64731f8-600f-4153-ac97-a808ac4aaa3e', 'truesize': '1028096', 'type': 'PREALLOCATED', 'lease': {'owners': [], 'version': None}}} from=::1,49808, task_id=94725ecc-c724-424a-8b1b-42385a6697b1 (api:52)
2017-08-25 10:38:47,646+0200 INFO (jsonrpc/2) [jsonrpc.JsonRpcServer] RPC call Volume.getInfo succeeded in 0.02 seconds (__init__:539)
2017-08-25 10:38:47,651+0200 INFO (jsonrpc/0) [vdsm.api] START getVolumesList(sdUUID=u'7c8deaa8-be02-4aaf-b9b4-ddc8da99ad96', spUUID=u'00000000-0000-0000-0000-000000000000', imgUUID=u'066b2632-44fe-455b-93db-aca85262b3ef', options=None) from=::1,49808, task_id=87db117b-0695-4614-a6c9-bab6cc592cc2 (api:46)
2017-08-25 10:38:47,657+0200 INFO (jsonrpc/0) [vdsm.api] FINISH getVolumesList return={'uuidlist': [u'7e88ba33-345f-4317-977a-7ae98a2ccbbe']} from=::1,49808, task_id=87db117b-0695-4614-a6c9-bab6cc592cc2 (api:52)
2017-08-25 10:38:47,658+0200 INFO (jsonrpc/0) [jsonrpc.JsonRpcServer] RPC call StorageDomain.getVolumes succeeded in 0.01 seconds (__init__:539)
2017-08-25 10:38:47,667+0200 INFO (jsonrpc/3) [vdsm.api] START getVolumeInfo(sdUUID=u'7c8deaa8-be02-4aaf-b9b4-ddc8da99ad96', spUUID=u'00000000-0000-0000-0000-000000000000', imgUUID=u'066b2632-44fe-455b-93db-aca85262b3ef', volUUID=u'7e88ba33-345f-4317-977a-7ae98a2ccbbe', options=None) from=::1,49808, task_id=c10316e5-1e61-4fb3-93f8-c679342da031 (api:46)
2017-08-25 10:38:47,670+0200 INFO (jsonrpc/3) [storage.VolumeManifest] Info request: sdUUID=7c8deaa8-be02-4aaf-b9b4-ddc8da99ad96 imgUUID=066b2632-44fe-455b-93db-aca85262b3ef volUUID = 7e88ba33-345f-4317-977a-7ae98a2ccbbe (volume:238)
2017-08-25 10:38:47,677+0200 INFO (jsonrpc/3) [storage.VolumeManifest] 7c8deaa8-be02-4aaf-b9b4-ddc8da99ad96/066b2632-44fe-455b-93db-aca85262b3ef/7e88ba33-345f-4317-977a-7ae98a2ccbbe info is {'status': 'OK', 'domain': '7c8deaa8-be02-4aaf-b9b4-ddc8da99ad96', 'voltype': 'LEAF', 'description': 'HostedEngineConfigurationImage', 'parent': '00000000-0000-0000-0000-000000000000', 'format': 'RAW', 'generation': 0, 'image': '066b2632-44fe-455b-93db-aca85262b3ef', 'ctime': '1464545267', 'disktype': '2', 'legality': 'LEGAL', 'mtime': '0', 'apparentsize': '20480', 'children': [], 'pool': '', 'capacity': '1073741824', 'uuid': u'7e88ba33-345f-4317-977a-7ae98a2ccbbe', 'truesize': '20480', 'type': 'PREALLOCATED', 'lease': {'owners': [], 'version': None}} (volume:272)
2017-08-25 10:38:47,677+0200 INFO (jsonrpc/3) [vdsm.api] FINISH getVolumeInfo return={'info': {'status': 'OK', 'domain': '7c8deaa8-be02-4aaf-b9b4-ddc8da99ad96', 'voltype': 'LEAF', 'description': 'HostedEngineConfigurationImage', 'parent': '00000000-0000-0000-0000-000000000000', 'format': 'RAW', 'generation': 0, 'image': '066b2632-44fe-455b-93db-aca85262b3ef', 'ctime': '1464545267', 'disktype': '2', 'legality': 'LEGAL', 'mtime': '0', 'apparentsize': '20480', 'children': [], 'pool': '', 'capacity': '1073741824', 'uuid': u'7e88ba33-345f-4317-977a-7ae98a2ccbbe', 'truesize': '20480', 'type': 'PREALLOCATED', 'lease': {'owners': [], 'version': None}}} from=::1,49808, task_id=c10316e5-1e61-4fb3-93f8-c679342da031 (api:52)
2017-08-25 10:38:47,678+0200 INFO (jsonrpc/3) [jsonrpc.JsonRpcServer] RPC call Volume.getInfo succeeded in 0.01 seconds (__init__:539)
2017-08-25 10:38:47,682+0200 INFO (jsonrpc/1) [vdsm.api] START getVolumesList(sdUUID=u'7c8deaa8-be02-4aaf-b9b4-ddc8da99ad96', spUUID=u'00000000-0000-0000-0000-000000000000', imgUUID=u'faed9c01-c9e7-4302-8053-3671163cc3b8', options=None) from=::1,49808, task_id=d4258135-1be5-47c5-a706-9209919aa655 (api:46)
2017-08-25 10:38:47,688+0200 INFO (jsonrpc/1) [vdsm.api] FINISH getVolumesList return={'uuidlist': [u'6aaea89f-ea88-4d57-aa3e-6252ef102949']} from=::1,49808, task_id=d4258135-1be5-47c5-a706-9209919aa655 (api:52)
2017-08-25 10:38:47,689+0200 INFO (jsonrpc/1) [jsonrpc.JsonRpcServer] RPC call StorageDomain.getVolumes succeeded in 0.01 seconds (__init__:539)
2017-08-25 10:38:47,698+0200 INFO (jsonrpc/4) [vdsm.api] START getVolumeInfo(sdUUID=u'7c8deaa8-be02-4aaf-b9b4-ddc8da99ad96', spUUID=u'00000000-0000-0000-0000-000000000000', imgUUID=u'faed9c01-c9e7-4302-8053-3671163cc3b8', volUUID=u'6aaea89f-ea88-4d57-aa3e-6252ef102949', options=None) from=::1,49808, task_id=a386952c-fecf-4861-b135-89518b51163e (api:46)
2017-08-25 10:38:47,701+0200 INFO (jsonrpc/4) [storage.VolumeManifest] Info request: sdUUID=7c8deaa8-be02-4aaf-b9b4-ddc8da99ad96 imgUUID=faed9c01-c9e7-4302-8053-3671163cc3b8 volUUID = 6aaea89f-ea88-4d57-aa3e-6252ef102949 (volume:238)
2017-08-25 10:38:47,708+0200 INFO (jsonrpc/4) [storage.VolumeManifest] 7c8deaa8-be02-4aaf-b9b4-ddc8da99ad96/faed9c01-c9e7-4302-8053-3671163cc3b8/6aaea89f-ea88-4d57-aa3e-6252ef102949 info is {'status': 'OK', 'domain': '7c8deaa8-be02-4aaf-b9b4-ddc8da99ad96', 'voltype': 'LEAF', 'description': 'Hosted Engine Image', 'parent': '00000000-0000-0000-0000-000000000000', 'format': 'RAW', 'generation': 0, 'image': 'faed9c01-c9e7-4302-8053-3671163cc3b8', 'ctime': '1464545278', 'disktype': '2', 'legality': 'LEGAL', 'mtime': '0', 'apparentsize': '10737418240', 'children': [], 'pool': '', 'capacity': '10737418240', 'uuid': u'6aaea89f-ea88-4d57-aa3e-6252ef102949', 'truesize': '8490876928', 'type': 'SPARSE', 'lease': {'owners': [1], 'version': 47L}} (volume:272)
2017-08-25 10:38:47,708+0200 INFO (jsonrpc/4) [vdsm.api] FINISH getVolumeInfo return={'info': {'status': 'OK', 'domain': '7c8deaa8-be02-4aaf-b9b4-ddc8da99ad96', 'voltype': 'LEAF', 'description': 'Hosted Engine Image', 'parent': '00000000-0000-0000-0000-000000000000', 'format': 'RAW', 'generation': 0, 'image': 'faed9c01-c9e7-4302-8053-3671163cc3b8', 'ctime': '1464545278', 'disktype': '2', 'legality': 'LEGAL', 'mtime': '0', 'apparentsize': '10737418240', 'children': [], 'pool': '', 'capacity': '10737418240', 'uuid': u'6aaea89f-ea88-4d57-aa3e-6252ef102949', 'truesize': '8490876928', 'type': 'SPARSE', 'lease': {'owners': [1], 'version': 47L}}} from=::1,49808, task_id=a386952c-fecf-4861-b135-89518b51163e (api:52)
2017-08-25 10:38:47,709+0200 INFO (jsonrpc/4) [jsonrpc.JsonRpcServer] RPC call Volume.getInfo succeeded in 0.01 seconds (__init__:539)
2017-08-25 10:38:47,713+0200 INFO (jsonrpc/5) [vdsm.api] START getVolumesList(sdUUID=u'7c8deaa8-be02-4aaf-b9b4-ddc8da99ad96', spUUID=u'00000000-0000-0000-0000-000000000000', imgUUID=u'a469f231-61f7-4b7a-95af-b56ac91d0574', options=None) from=::1,49808, task_id=cdd7a12f-223a-4796-82a5-7701a69634f0 (api:46)
2017-08-25 10:38:47,720+0200 INFO (jsonrpc/5) [vdsm.api] FINISH getVolumesList return={'uuidlist': [u'86edb440-cd6c-49f9-8df0-ee25f6ac4748']} from=::1,49808, task_id=cdd7a12f-223a-4796-82a5-7701a69634f0 (api:52)
2017-08-25 10:38:47,720+0200 INFO (jsonrpc/5) [jsonrpc.JsonRpcServer] RPC call StorageDomain.getVolumes succeeded in 0.01 seconds (__init__:539)
2017-08-25 10:38:47,729+0200 INFO (jsonrpc/7) [vdsm.api] START getVolumeInfo(sdUUID=u'7c8deaa8-be02-4aaf-b9b4-ddc8da99ad96', spUUID=u'00000000-0000-0000-0000-000000000000', imgUUID=u'a469f231-61f7-4b7a-95af-b56ac91d0574', volUUID=u'86edb440-cd6c-49f9-8df0-ee25f6ac4748', options=None) from=::1,49808, task_id=ab1ad6e8-e15c-4147-ae9c-645f00b5263f (api:46)
2017-08-25 10:38:47,732+0200 INFO (jsonrpc/7) [storage.VolumeManifest] Info request: sdUUID=7c8deaa8-be02-4aaf-b9b4-ddc8da99ad96 imgUUID=a469f231-61f7-4b7a-95af-b56ac91d0574 volUUID = 86edb440-cd6c-49f9-8df0-ee25f6ac4748 (volume:238)
2017-08-25 10:38:47,738+0200 INFO (jsonrpc/7) [storage.VolumeManifest] 7c8deaa8-be02-4aaf-b9b4-ddc8da99ad96/a469f231-61f7-4b7a-95af-b56ac91d0574/86edb440-cd6c-49f9-8df0-ee25f6ac4748 info is {'status': 'OK', 'domain': '7c8deaa8-be02-4aaf-b9b4-ddc8da99ad96', 'voltype': 'LEAF', 'description': '{"Updated":true,"Size":20480,"Last Updated":"Thu Aug 24 15:19:07 CEST 2017","Storage Domains":[{"uuid":"7c8deaa8-be02-4aaf-b9b4-ddc8da99ad96"}],"Disk Description":"OVF_STORE"}', 'parent': '00000000-0000-0000-0000-000000000000', 'format': 'RAW', 'generation': 0, 'image': 'a469f231-61f7-4b7a-95af-b56ac91d0574', 'ctime': '1464553325', 'disktype': '2', 'legality': 'LEGAL', 'mtime': '0', 'apparentsize': '20480', 'children': [], 'pool': '', 'capacity': '134217728', 'uuid': u'86edb440-cd6c-49f9-8df0-ee25f6ac4748', 'truesize': '20480', 'type': 'PREALLOCATED', 'lease': {'owners': [], 'version': None}} (volume:272)
2017-08-25 10:38:47,739+0200 INFO (jsonrpc/7) [vdsm.api] FINISH getVolumeInfo return={'info': {'status': 'OK', 'domain': '7c8deaa8-be02-4aaf-b9b4-ddc8da99ad96', 'voltype': 'LEAF', 'description': '{"Updated":true,"Size":20480,"Last Updated":"Thu Aug 24 15:19:07 CEST 2017","Storage Domains":[{"uuid":"7c8deaa8-be02-4aaf-b9b4-ddc8da99ad96"}],"Disk Description":"OVF_STORE"}', 'parent': '00000000-0000-0000-0000-000000000000', 'format': 'RAW', 'generation': 0, 'image': 'a469f231-61f7-4b7a-95af-b56ac91d0574', 'ctime': '1464553325', 'disktype': '2', 'legality': 'LEGAL', 'mtime': '0', 'apparentsize': '20480', 'children': [], 'pool': '', 'capacity': '134217728', 'uuid': u'86edb440-cd6c-49f9-8df0-ee25f6ac4748', 'truesize': '20480', 'type': 'PREALLOCATED', 'lease': {'owners': [], 'version': None}}} from=::1,49808, task_id=ab1ad6e8-e15c-4147-ae9c-645f00b5263f (api:52)
2017-08-25 10:38:47,739+0200 INFO (jsonrpc/7) [jsonrpc.JsonRpcServer] RPC call Volume.getInfo succeeded in 0.01 seconds (__init__:539)
2017-08-25 10:38:47,745+0200 INFO (jsonrpc/6) [jsonrpc.JsonRpcServer] RPC call Host.getHardwareInfo succeeded in 0.00 seconds (__init__:539)
2017-08-25 10:38:47,748+0200 INFO (jsonrpc/2) [jsonrpc.JsonRpcServer] RPC call VM.getStats failed (error 1) in 0.00 seconds (__init__:539)
2017-08-25 10:38:49,801+0200 INFO (Reactor thread) [ProtocolDetector.AcceptorImpl] Accepted connection from ::1:49812 (protocoldetector:72)
2017-08-25 10:38:49,806+0200 INFO (Reactor thread) [ProtocolDetector.Detector] Detected protocol stomp from ::1:49812 (protocoldetector:127)
2017-08-25 10:38:49,806+0200 INFO (Reactor thread) [Broker.StompAdapter] Processing CONNECT request (stompreactor:103)
2017-08-25 10:38:49,808+0200 INFO (JsonRpc (StompReactor)) [Broker.StompAdapter] Subscribe command received (stompreactor:130)
2017-08-25 10:38:49,967+0200 INFO (jsonrpc/0) [jsonrpc.JsonRpcServer] RPC call Host.getHardwareInfo succeeded in 0.00 seconds (__init__:539)
2017-08-25 10:38:51,904+0200 INFO (jsonrpc/3) [vdsm.api] START repoStats(options=None) from=::ffff:172.16.252.200,42006, flow_id=1b46fc99, task_id=3338c8d7-0c14-4f26-a08d-0c3ef3eb7edf (api:46)
2017-08-25 10:38:51,905+0200 INFO (jsonrpc/3) [vdsm.api] FINISH repoStats return={u'7c8deaa8-be02-4aaf-b9b4-ddc8da99ad96': {'code': 0, 'actual': True, 'version': 4, 'acquired': True, 'delay': '0.00021935', 'lastCheck': '0.4', 'valid': True}, u'2b2a44fc-f2bd-47cd-b7af-00be59e30a35': {'code': 0, 'actual': True, 'version': 0, 'acquired': True, 'delay': '0.000668457', 'lastCheck': '0.4', 'valid': True}, u'5d99af76-33b5-47d8-99da-1f32413c7bb0': {'code': 0, 'actual': True, 'version': 4, 'acquired': True, 'delay': '0.000346297', 'lastCheck': '0.5', 'valid': True}, u'a7fbaaad-7043-4391-9523-3bedcdc4fb0d': {'code': 0, 'actual': True, 'version': 0, 'acquired': True, 'delay': '0.000891773', 'lastCheck': '0.4', 'valid': True}} from=::ffff:172.16.252.200,42006, flow_id=1b46fc99, task_id=3338c8d7-0c14-4f26-a08d-0c3ef3eb7edf (api:52)
2017-08-25 10:38:51,947+0200 INFO (jsonrpc/3) [jsonrpc.JsonRpcServer] RPC call Host.getStats succeeded in 0.05 seconds (__init__:539)
2017-08-25 10:38:51,975+0200 INFO (Reactor thread) [ProtocolDetector.AcceptorImpl] Accepted connection from ::1:49814 (protocoldetector:72)
2017-08-25 10:38:51,980+0200 INFO (Reactor thread) [ProtocolDetector.Detector] Detected protocol stomp from ::1:49814 (protocoldetector:127)
2017-08-25 10:38:51,980+0200 INFO (Reactor thread) [Broker.StompAdapter] Processing CONNECT request (stompreactor:103)
2017-08-25 10:38:51,981+0200 INFO (JsonRpc (StompReactor)) [Broker.StompAdapter] Subscribe command received (stompreactor:130)
2017-08-25 10:38:52,142+0200 INFO (jsonrpc/1) [jsonrpc.JsonRpcServer] RPC call Host.getHardwareInfo succeeded in 0.00 seconds (__init__:539)
2017-08-25 10:38:52,144+0200 INFO (jsonrpc/4) [vdsm.api] START repoStats(options=None) from=::1,49814, task_id=db0951a5-5475-493f-a58f-e5a3e83d08de (api:46)
2017-08-25 10:38:52,145+0200 INFO (jsonrpc/4) [vdsm.api] FINISH repoStats return={u'7c8deaa8-be02-4aaf-b9b4-ddc8da99ad96': {'code': 0, 'actual': True, 'version': 4, 'acquired': True, 'delay': '0.00021935', 'lastCheck': '0.6', 'valid': True}, u'2b2a44fc-f2bd-47cd-b7af-00be59e30a35': {'code': 0, 'actual': True, 'version': 0, 'acquired': True, 'delay': '0.000668457', 'lastCheck': '0.6', 'valid': True}, u'5d99af76-33b5-47d8-99da-1f32413c7bb0': {'code': 0, 'actual': True, 'version': 4, 'acquired': True, 'delay': '0.000346297', 'lastCheck': '0.7', 'valid': True}, u'a7fbaaad-7043-4391-9523-3bedcdc4fb0d': {'code': 0, 'actual': True, 'version': 0, 'acquired': True, 'delay': '0.000891773', 'lastCheck': '0.6', 'valid': True}} from=::1,49814, task_id=db0951a5-5475-493f-a58f-e5a3e83d08de (api:52)
2017-08-25 10:38:52,145+0200 INFO (jsonrpc/4) [jsonrpc.JsonRpcServer] RPC call Host.getStorageRepoStats succeeded in 0.00 seconds (__init__:539)
2017-08-25 10:38:54,151+0200 INFO (Reactor thread) [ProtocolDetector.AcceptorImpl] Accepted connection from ::1:49816 (protocoldetector:72)
2017-08-25 10:38:54,155+0200 INFO (Reactor thread) [ProtocolDetector.Detector] Detected protocol stomp from ::1:49816 (protocoldetector:127)
2017-08-25 10:38:54,156+0200 INFO (Reactor thread) [Broker.StompAdapter] Processing CONNECT request (stompreactor:103)
2017-08-25 10:38:54,159+0200 INFO (JsonRpc (StompReactor)) [Broker.StompAdapter] Subscribe command received (stompreactor:130)
2017-08-25 10:38:54,322+0200 INFO (jsonrpc/5) [jsonrpc.JsonRpcServer] RPC call Host.getHardwareInfo succeeded in 0.00 seconds (__init__:539)
2017-08-25 10:38:54,325+0200 INFO (jsonrpc/7) [vdsm.api] START repoStats(options=None) from=::1,49816, task_id=a881acf7-ca0a-4a7e-94e3-17489293409b (api:46)
2017-08-25 10:38:54,325+0200 INFO (jsonrpc/7) [vdsm.api] FINISH repoStats return={u'7c8deaa8-be02-4aaf-b9b4-ddc8da99ad96': {'code': 0, 'actual': True, 'version': 4, 'acquired': True, 'delay': '0.00021935', 'lastCheck': '2.8', 'valid': True}, u'2b2a44fc-f2bd-47cd-b7af-00be59e30a35': {'code': 0, 'actual': True, 'version': 0, 'acquired': True, 'delay': '0.000668457', 'lastCheck': '2.8', 'valid': True}, u'5d99af76-33b5-47d8-99da-1f32413c7bb0': {'code': 0, 'actual': True, 'version': 4, 'acquired': True, 'delay': '0.000346297', 'lastCheck': '1.7', 'valid': True}, u'a7fbaaad-7043-4391-9523-3bedcdc4fb0d': {'code': 0, 'actual': True, 'version': 0, 'acquired': True, 'delay': '0.000891773', 'lastCheck': '2.8', 'valid': True}} from=::1,49816, task_id=a881acf7-ca0a-4a7e-94e3-17489293409b (api:52)
2017-08-25 10:38:54,326+0200 INFO (jsonrpc/7) [jsonrpc.JsonRpcServer] RPC call Host.getStorageRepoStats succeeded in 0.00 seconds (__init__:539)
2017-08-25 10:38:56,111+0200 INFO (Reactor thread) [ProtocolDetector.AcceptorImpl] Accepted connection from ::1:49834 (protocoldetector:72)
2017-08-25 10:38:56,123+0200 INFO (Reactor thread) [ProtocolDetector.Detector] Detected protocol stomp from ::1:49834 (protocoldetector:127)
2017-08-25 10:38:56,123+0200 INFO (Reactor thread) [Broker.StompAdapter] Processing CONNECT request (stompreactor:103)
2017-08-25 10:38:56,128+0200 INFO (JsonRpc (StompReactor)) [Broker.StompAdapter] Subscribe command received (stompreactor:130)
2017-08-25 10:38:56,247+0200 INFO (Reactor thread) [ProtocolDetector.AcceptorImpl] Accepted connection from ::1:49836 (protocoldetector:72)
2017-08-25 10:38:56,266+0200 INFO (Reactor thread) [ProtocolDetector.Detector] Detected protocol stomp from ::1:49836 (protocoldetector:127)
2017-08-25 10:38:56,266+0200 INFO (Reactor thread) [Broker.StompAdapter] Processing CONNECT request (stompreactor:103)
2017-08-25 10:38:56,266+0200 INFO (jsonrpc/6) [jsonrpc.JsonRpcServer] RPC call Host.getHardwareInfo succeeded in 0.01 seconds (__init__:539)
2017-08-25 10:38:56,267+0200 INFO (JsonRpc (StompReactor)) [Broker.StompAdapter] Subscribe command received (stompreactor:130)
2017-08-25 10:38:56,323+0200 INFO (jsonrpc/2) [vdsm.api] START repoStats(options=None) from=::1,49834, task_id=84ddab5d-9317-4a92-9ab4-4576b54a50b2 (api:46)
2017-08-25 10:38:56,323+0200 INFO (jsonrpc/2) [vdsm.api] FINISH repoStats return={u'7c8deaa8-be02-4aaf-b9b4-ddc8da99ad96': {'code': 0, 'actual': True, 'version': 4, 'acquired': True, 'delay': '0.00021935', 'lastCheck': '1.5', 'valid': True}, u'2b2a44fc-f2bd-47cd-b7af-00be59e30a35': {'code': 0, 'actual': True, 'version': 0, 'acquired': True, 'delay': '0.000668457', 'lastCheck': '4.8', 'valid': True}, u'5d99af76-33b5-47d8-99da-1f32413c7bb0': {'code': 0, 'actual': True, 'version': 4, 'acquired': True, 'delay': '0.000346297', 'lastCheck': '3.7', 'valid': True}, u'a7fbaaad-7043-4391-9523-3bedcdc4fb0d': {'code': 0, 'actual': True, 'version': 0, 'acquired': True, 'delay': '0.000891773', 'lastCheck': '4.8', 'valid': True}} from=::1,49834, task_id=84ddab5d-9317-4a92-9ab4-4576b54a50b2 (api:52)
2017-08-25 10:38:56,360+0200 INFO (Reactor thread) [ProtocolDetector.AcceptorImpl] Accepted connection from ::1:49838 (protocoldetector:72)
2017-08-25 10:38:56,366+0200 INFO (Reactor thread) [ProtocolDetector.Detector] Detected protocol stomp from ::1:49838 (protocoldetector:127)
2017-08-25 10:38:56,366+0200 INFO (Reactor thread) [Broker.StompAdapter] Processing CONNECT request (stompreactor:103)
2017-08-25 10:38:56,367+0200 INFO (JsonRpc (StompReactor)) [Broker.StompAdapter] Subscribe command received (stompreactor:130)
2017-08-25 10:38:56,418+0200 INFO (jsonrpc/0) [jsonrpc.JsonRpcServer] RPC call Host.getHardwareInfo succeeded in 0.00 seconds (__init__:539)
2017-08-25 10:38:56,457+0200 INFO (jsonrpc/2) [jsonrpc.JsonRpcServer] RPC call Host.getStats succeeded in 0.14 seconds (__init__:539)
2017-08-25 10:38:56,532+0200 INFO (jsonrpc/3) [jsonrpc.JsonRpcServer] RPC call Host.getCapabilities succeeded in 0.10 seconds (__init__:539)
2017-08-25 10:38:56,553+0200 INFO (jsonrpc/1) [jsonrpc.JsonRpcServer] RPC call Host.getHardwareInfo succeeded in 0.00 seconds (__init__:539)
2017-08-25 10:38:56,568+0200 INFO (jsonrpc/4) [jsonrpc.JsonRpcServer] RPC call Host.getAllVmStats succeeded in 0.00 seconds (__init__:539)
2017-08-25 10:38:57,755+0200 INFO (Reactor thread) [ProtocolDetector.AcceptorImpl] Accepted connection from ::1:49840 (protocoldetector:72)
2017-08-25 10:38:57,763+0200 INFO (Reactor thread) [ProtocolDetector.Detector] Detected protocol stomp from ::1:49840 (protocoldetector:127)
2017-08-25 10:38:57,764+0200 INFO (Reactor thread) [Broker.StompAdapter] Processing CONNECT request (stompreactor:103)
2017-08-25 10:38:57,767+0200 INFO (JsonRpc (StompReactor)) [Broker.StompAdapter] Subscribe command received (stompreactor:130)
2017-08-25 10:38:57,894+0200 INFO (jsonrpc/5) [jsonrpc.JsonRpcServer] RPC call Host.getHardwareInfo succeeded in 0.00 seconds (__init__:539)
2017-08-25 10:38:57,897+0200 INFO (jsonrpc/7) [jsonrpc.JsonRpcServer] RPC call VM.getStats failed (error 1) in 0.00 seconds (__init__:539)
2017-08-25 10:39:00,681+0200 INFO (periodic/3) [vdsm.api] START repoStats(options=None) from=internal, task_id=a50cd9ba-319c-40fb-ad66-fde8b23399eb (api:46)
2017-08-25 10:39:00,681+0200 INFO (periodic/3) [vdsm.api] FINISH repoStats return={u'7c8deaa8-be02-4aaf-b9b4-ddc8da99ad96': {'code': 0, 'actual': True, 'version': 4, 'acquired': True, 'delay': '0.00021935', 'lastCheck': '5.9', 'valid': True}, u'2b2a44fc-f2bd-47cd-b7af-00be59e30a35': {'code': 0, 'actual': True, 'version': 0, 'acquired': True, 'delay': '0.000668457', 'lastCheck': '0.0', 'valid': True}, u'5d99af76-33b5-47d8-99da-1f32413c7bb0': {'code': 0, 'actual': True, 'version': 4, 'acquired': True, 'delay': '0.000346297', 'lastCheck': '8.0', 'valid': True}, u'a7fbaaad-7043-4391-9523-3bedcdc4fb0d': {'code': 0, 'actual': True, 'version': 0, 'acquired': True, 'delay': '0.000891773', 'lastCheck': '0.7', 'valid': True}} from=internal, task_id=a50cd9ba-319c-40fb-ad66-fde8b23399eb (api:52)
2017-08-25 10:39:01,417+0200 INFO (jsonrpc/6) [jsonrpc.JsonRpcServer] RPC call Host.getAllVmStats succeeded in 0.00 seconds (__init__:539)
More information about the Users
mailing list