[ovirt-users] Re: Migration VM

Hi Nikolov, Thanks for the support, this is the log from start vm to fail start vm (/var/log/vdsm/vdsm.log): 2020-11-18 18:06:00,054+0100 INFO (jsonrpc/1) [api.host] START getAllVmStats() from=::1,57668 (api:48) 2020-11-18 18:06:00,056+0100 INFO (jsonrpc/1) [api.host] FINISH getAllVmStats return={'status': {'code': 0, 'message': 'Done'}, 'statsList': (suppressed)} from=::1,57668 (api:54) 2020-11-18 18:06:00,057+0100 INFO (jsonrpc/1) [jsonrpc.JsonRpcServer] RPC call Host.getAllVmStats succeeded in 0.00 seconds (__init__:312) 2020-11-18 18:06:00,061+0100 INFO (jsonrpc/5) [api.host] START getAllVmIoTunePolicies() from=::1,57668 (api:48) 2020-11-18 18:06:00,062+0100 INFO (jsonrpc/5) [api.host] FINISH getAllVmIoTunePolicies return={'status': {'code': 0, 'message': 'Done'}, 'io_tune_policies_dict': {'cfe08595-6ff9-4b4c-a48d-51530e42230c': {'policy': [], 'current_values': [{'name': 'vda', 'path': '/var/run/vdsm/storage/33992e1c-5383-4e3d-b9d0-945491ee0ccd/a387fa74-fa0d-4027-9f3f-560d2c723309/5ffd3ebe-097a-48eb-adb0-d74d8b0e547d', 'ioTune': {'total_bytes_sec': 0, 'read_bytes_sec': 0, 'write_bytes_sec': 0, 'total_iops_sec': 0, 'write_iops_sec': 0, 'read_iops_sec': 0}}]}, '0a940b7e-5f9c-4e4b-b11c-0d3c1d811a83': {'policy': [], 'current_values': [{'name': 'sda', 'path': '/rhev/data-center/mnt/blockSD/dfeb1c22-4c0a-4bb4-9ef1-934962f18707/images/519b7c5a-dcf7-44b0-a36a-3cd64116d37c/5919b6b3-8e27-4733-9434-44e388d66bd3', 'ioTune': {'total_bytes_sec': 0, 'read_bytes_sec': 0, 'write_bytes_sec': 0, 'total_iops_sec': 0, 'write_iops_sec': 0, 'read_iops_sec': 0}}]}}} from=::1,57668 (api:54) 2020-11-18 18:06:00,062+0100 INFO (jsonrpc/5) [jsonrpc.JsonRpcServer] RPC call Host.getAllVmIoTunePolicies succeeded in 0.00 seconds (__init__:312) 2020-11-18 18:06:00,396+0100 INFO (monitor/3b532b1) [storage.StorageDomainCache] Removing domain 3b532b12-5151-4626-883a-26f2a82d4f9e from storage domain cache (sdc:211) 2020-11-18 18:06:00,397+0100 INFO (tmap-3283/0) [IOProcessClient] (si-nas.babel.it:_iso-domain) Starting client (__init__:308) 2020-11-18 18:06:00,425+0100 INFO (tmap-3283/1) [IOProcessClient] (10.1.0.4:_share_CACHEDEV1__DATA_Export__VM) Starting client (__init__:308) 2020-11-18 18:06:00,440+0100 INFO (ioprocess/1135781) [IOProcess] (si-nas.babel.it:_iso-domain) Starting ioprocess (__init__:434) 2020-11-18 18:06:00,460+0100 INFO (ioprocess/1135784) [IOProcess] (10.1.0.4:_share_CACHEDEV1__DATA_Export__VM) Starting ioprocess (__init__:434) 2020-11-18 18:06:00,468+0100 INFO (monitor/3b532b1) [storage.StorageDomain] Removing remnants of deleted images [] (fileSD:735) 2020-11-18 18:06:01,150+0100 INFO (jsonrpc/6) [jsonrpc.JsonRpcServer] RPC call Host.ping2 succeeded in 0.00 seconds (__init__:312) 2020-11-18 18:06:01,153+0100 INFO (jsonrpc/7) [api.host] START getStats() from=::1,57660 (api:48) 2020-11-18 18:06:01,213+0100 INFO (jsonrpc/7) [vdsm.api] START repoStats(domains=()) from=::1,57660, task_id=3f27e851-483a-4394-81fd-caf1d88dd2d0 (api:48) 2020-11-18 18:06:01,214+0100 INFO (jsonrpc/7) [vdsm.api] FINISH repoStats return={'33992e1c-5383-4e3d-b9d0-945491ee0ccd': {'code': 0, 'lastCheck': '2.9', 'delay': '0.000540462', 'valid': True, 'version': 5, 'acquired': True, 'actual': True}, 'dfeb1c22-4c0a-4bb4-9ef1-934962f18707': {'code': 0, 'lastCheck': '0.3', 'delay': '0.000648293', 'valid': True, 'version': 5, 'acquired': True, 'actual': True}, '2974d4ff-27c5-4a25-b956-0ff4ff426cd3': {'code': 0, 'lastCheck': '1.6', 'delay': '0.000389333', 'valid': True, 'version': 0, 'acquired': True, 'actual': True}, '3b532b12-5151-4626-883a-26f2a82d4f9e': {'code': 0, 'lastCheck': '0.7', 'delay': '0.000363655', 'valid': True, 'version': 0, 'acquired': True, 'actual': True}} from=::1,57660, task_id=3f27e851-483a-4394-81fd-caf1d88dd2d0 (api:54) 2020-11-18 18:06:01,215+0100 INFO (jsonrpc/7) [vdsm.api] START multipath_health() from=::1,57660, task_id=d2cc8791-d96d-43ce-bfc6-64a26aeb1917 (api:48) 2020-11-18 18:06:01,215+0100 INFO (jsonrpc/7) [vdsm.api] FINISH multipath_health return={} from=::1,57660, task_id=d2cc8791-d96d-43ce-bfc6-64a26aeb1917 (api:54) 2020-11-18 18:06:01,227+0100 INFO (jsonrpc/7) [api.host] FINISH getStats return={'status': {'code': 0, 'message': 'Done'}, 'info': (suppressed)} from=::1,57660 (api:54) 2020-11-18 18:06:01,230+0100 INFO (jsonrpc/7) [jsonrpc.JsonRpcServer] RPC call Host.getStats succeeded in 0.08 seconds (__init__:312) 2020-11-18 18:06:01,773+0100 INFO (jsonrpc/3) [jsonrpc.JsonRpcServer] RPC call Host.ping2 succeeded in 0.00 seconds (__init__:312) 2020-11-18 18:06:01,831+0100 INFO (jsonrpc/2) [jsonrpc.JsonRpcServer] RPC call Host.ping2 succeeded in 0.00 seconds (__init__:312) 2020-11-18 18:06:01,833+0100 INFO (jsonrpc/0) [vdsm.api] START repoStats(domains=['33992e1c-5383-4e3d-b9d0-945491ee0ccd']) from=::1,57660, task_id=5c3061e1-289c-44a1-ad3c-098abd9f26c5 (api:48) 2020-11-18 18:06:01,834+0100 INFO (jsonrpc/0) [vdsm.api] FINISH repoStats return={'33992e1c-5383-4e3d-b9d0-945491ee0ccd': {'code': 0, 'lastCheck': '3.6', 'delay': '0.000540462', 'valid': True, 'version': 5, 'acquired': True, 'actual': True}} from=::1,57660, task_id=5c3061e1-289c-44a1-ad3c-098abd9f26c5 (api:54) 2020-11-18 18:06:01,834+0100 INFO (jsonrpc/0) [jsonrpc.JsonRpcServer] RPC call Host.getStorageRepoStats succeeded in 0.00 seconds (__init__:312) 2020-11-18 18:06:05,840+0100 INFO (jsonrpc/4) [vdsm.api] START getSpmStatus(spUUID='2678c340-2299-11eb-bb74-00163e0a2411', options=None) from=::ffff:10.0.0.97,51960, task_id=06fe0b65-3909-464c-bbea-97304b9af2f9 (api:48) 2020-11-18 18:06:05,852+0100 INFO (jsonrpc/4) [vdsm.api] FINISH getSpmStatus return={'spm_st': {'spmStatus': 'SPM', 'spmLver': 3, 'spmId': 1}} from=::ffff:10.0.0.97,51960, task_id=06fe0b65-3909-464c-bbea-97304b9af2f9 (api:54) 2020-11-18 18:06:05,852+0100 INFO (jsonrpc/4) [jsonrpc.JsonRpcServer] RPC call StoragePool.getSpmStatus succeeded in 0.01 seconds (__init__:312) 2020-11-18 18:06:05,862+0100 INFO (jsonrpc/1) [vdsm.api] START getStoragePoolInfo(spUUID='2678c340-2299-11eb-bb74-00163e0a2411', options=None) from=::ffff:10.0.0.97,51966, task_id=52e09faa-1587-40d6-a1b0-59c05314a233 (api:48) 2020-11-18 18:06:05,877+0100 INFO (jsonrpc/1) [vdsm.api] FINISH getStoragePoolInfo return={'info': {'domains': '2974d4ff-27c5-4a25-b956-0ff4ff426cd3:Active,33992e1c-5383-4e3d-b9d0-945491ee0ccd:Active,dfeb1c22-4c0a-4bb4-9ef1-934962f18707:Active,3b532b12-5151-4626-883a-26f2a82d4f9e:Active', 'isoprefix': '/rhev/data-center/mnt/si-nas.babel.it:_iso-domain/2974d4ff-27c5-4a25-b956-0ff4ff426cd3/images/11111111-1111-1111-1111-111111111111', 'lver': 3, 'master_uuid': '33992e1c-5383-4e3d-b9d0-945491ee0ccd', 'master_ver': 1, 'name': 'No Description', 'pool_status': 'connected', 'spm_id': 1, 'type': 'ISCSI', 'version': '5'}, 'dominfo': {'2974d4ff-27c5-4a25-b956-0ff4ff426cd3': {'status': 'Active', 'alerts': [], 'isoprefix': '/rhev/data-center/mnt/si-nas.babel.it:_iso-domain/2974d4ff-27c5-4a25-b956-0ff4ff426cd3/images/11111111-1111-1111-1111-111111111111', 'version': 0, 'disktotal': '211378241536', 'diskfree': '176700424192'}, '33992e1c-5383-4e3d-b9d0-945491ee0ccd': {'status': 'Active', 'alerts': [], 'isoprefix': '', 'version': 5, 'disktotal': '106971529216', 'diskfree': '12750684160'}, 'dfeb1c22-4c0a-4bb4-9ef1-934962f18707': {'status': 'Active', 'alerts': [], 'isoprefix': '', 'version': 5, 'disktotal': '751216623616', 'diskfree': '738734374912'}, '3b532b12-5151-4626-883a-26f2a82d4f9e': {'status': 'Active', 'alerts': [], 'isoprefix': '', 'version': 0, 'disktotal': '5827912007680', 'diskfree': '1412350607360'}}} from=::ffff:10.0.0.97,51966, task_id=52e09faa-1587-40d6-a1b0-59c05314a233 (api:54) 2020-11-18 18:06:05,878+0100 INFO (jsonrpc/1) [jsonrpc.JsonRpcServer] RPC call StoragePool.getInfo succeeded in 0.02 seconds (__init__:312) 2020-11-18 18:06:06,135+0100 INFO (jsonrpc/5) [jsonrpc.JsonRpcServer] RPC call Host.ping2 succeeded in 0.00 seconds (__init__:312) 2020-11-18 18:06:06,137+0100 INFO (jsonrpc/6) [api.host] START getStats() from=::1,57660 (api:48) 2020-11-18 18:06:06,198+0100 INFO (jsonrpc/6) [vdsm.api] START repoStats(domains=()) from=::1,57660, task_id=649d005c-24ac-4a19-b035-2428ab2bab2e (api:48) 2020-11-18 18:06:06,198+0100 INFO (jsonrpc/6) [vdsm.api] FINISH repoStats return={'33992e1c-5383-4e3d-b9d0-945491ee0ccd': {'code': 0, 'lastCheck': '2.3', 'delay': '0.000540462', 'valid': True, 'version': 5, 'acquired': True, 'actual': True}, 'dfeb1c22-4c0a-4bb4-9ef1-934962f18707': {'code': 0, 'lastCheck': '5.3', 'delay': '0.000648293', 'valid': True, 'version': 5, 'acquired': True, 'actual': True}, '2974d4ff-27c5-4a25-b956-0ff4ff426cd3': {'code': 0, 'lastCheck': '3.7', 'delay': '0.000373626', 'valid': True, 'version': 0, 'acquired': True, 'actual': True}, '3b532b12-5151-4626-883a-26f2a82d4f9e': {'code': 0, 'lastCheck': '0.7', 'delay': '0.000372763', 'valid': True, 'version': 0, 'acquired': True, 'actual': True}} from=::1,57660, task_id=649d005c-24ac-4a19-b035-2428ab2bab2e (api:54) 2020-11-18 18:06:06,199+0100 INFO (jsonrpc/6) [vdsm.api] START multipath_health() from=::1,57660, task_id=82d66fde-fbe3-4bf2-8530-a0dde65adbd0 (api:48) 2020-11-18 18:06:06,200+0100 INFO (jsonrpc/6) [vdsm.api] FINISH multipath_health return={} from=::1,57660, task_id=82d66fde-fbe3-4bf2-8530-a0dde65adbd0 (api:54) 2020-11-18 18:06:06,211+0100 INFO (jsonrpc/6) [api.host] FINISH getStats return={'status': {'code': 0, 'message': 'Done'}, 'info': (suppressed)} from=::1,57660 (api:54) 2020-11-18 18:06:06,214+0100 INFO (jsonrpc/6) [jsonrpc.JsonRpcServer] RPC call Host.getStats succeeded in 0.08 seconds (__init__:312) 2020-11-18 18:06:06,724+0100 INFO (jsonrpc/7) [jsonrpc.JsonRpcServer] RPC call Host.ping2 succeeded in 0.00 seconds (__init__:312) 2020-11-18 18:06:06,727+0100 INFO (jsonrpc/3) [vdsm.api] START repoStats(domains=['33992e1c-5383-4e3d-b9d0-945491ee0ccd']) from=::1,57660, task_id=bc3d2a70-d7f4-410a-81a8-2338801800e7 (api:48) 2020-11-18 18:06:06,727+0100 INFO (jsonrpc/3) [vdsm.api] FINISH repoStats return={'33992e1c-5383-4e3d-b9d0-945491ee0ccd': {'code': 0, 'lastCheck': '2.9', 'delay': '0.000540462', 'valid': True, 'version': 5, 'acquired': True, 'actual': True}} from=::1,57660, task_id=bc3d2a70-d7f4-410a-81a8-2338801800e7 (api:54) 2020-11-18 18:06:06,728+0100 INFO (jsonrpc/3) [jsonrpc.JsonRpcServer] RPC call Host.getStorageRepoStats succeeded in 0.00 seconds (__init__:312) 2020-11-18 18:06:07,081+0100 INFO (periodic/2) [vdsm.api] START repoStats(domains=()) from=internal, task_id=5327aa97-2cd4-46f6-bf41-0de6cdb025bb (api:48) 2020-11-18 18:06:07,082+0100 INFO (periodic/2) [vdsm.api] FINISH repoStats return={'33992e1c-5383-4e3d-b9d0-945491ee0ccd': {'code': 0, 'lastCheck': '3.2', 'delay': '0.000540462', 'valid': True, 'version': 5, 'acquired': True, 'actual': True}, 'dfeb1c22-4c0a-4bb4-9ef1-934962f18707': {'code': 0, 'lastCheck': '0.7', 'delay': '0.000531715', 'valid': True, 'version': 5, 'acquired': True, 'actual': True}, '2974d4ff-27c5-4a25-b956-0ff4ff426cd3': {'code': 0, 'lastCheck': '4.6', 'delay': '0.000373626', 'valid': True, 'version': 0, 'acquired': True, 'actual': True}, '3b532b12-5151-4626-883a-26f2a82d4f9e': {'code': 0, 'lastCheck': '1.6', 'delay': '0.000372763', 'valid': True, 'version': 0, 'acquired': True, 'actual': True}} from=internal, task_id=5327aa97-2cd4-46f6-bf41-0de6cdb025bb (api:54) 2020-11-18 18:06:07,941+0100 INFO (jsonrpc/2) [jsonrpc.JsonRpcServer] RPC call Host.ping2 succeeded in 0.00 seconds (__init__:312) 2020-11-18 18:06:07,943+0100 INFO (jsonrpc/0) [api.virt] START getStats() from=::1,57660, vmId=cfe08595-6ff9-4b4c-a48d-51530e42230c (api:48) 2020-11-18 18:06:07,945+0100 INFO (jsonrpc/0) [api.virt] FINISH getStats return={'status': {'code': 0, 'message': 'Done'}, 'statsList': [{'statusTime': '2930012241', 'status': 'Up', 'vmId': 'cfe08595-6ff9-4b4c-a48d-51530e42230c', 'vmName': 'HostedEngine', 'vmType': 'kvm', 'kvmEnable': 'true', 'acpiEnable': 'true', 'elapsedTime': '198823', 'monitorResponse': '0', 'clientIp': '', 'timeOffset': '0', 'pauseCode': 'NOERR', 'cpuUser': '4.28', 'cpuSys': '0.60', 'cpuUsage': '2272410000000', 'network': {'vnet0': {'macAddr': '00:16:3e:0a:24:11', 'name': 'vnet0', 'speed': '1000', 'state': 'unknown', 'rxErrors': '0', 'rxDropped': '0', 'txErrors': '0', 'txDropped': '0', 'rx': '326227752', 'tx': '79641804', 'sampleTime': 782528.594316281}}, 'disks': {'sdc': {'truesize': '0', 'apparentsize': '0', 'readLatency': '0', 'writeLatency': '0', 'flushLatency': '0', 'writtenBytes': '0', 'writeOps': '0', 'readOps': '24', 'readBytes': '500', 'readRate': '0.0', 'writeRate': '0.0'}, 'vda': {'truesize': '858993 45920', 'apparentsize': '85899345920', 'readLatency': '0', 'writeLatency': '9266591.06060606', 'flushLatency': '24282438.636363637', 'writtenBytes': '6459164160', 'writeOps': '480151', 'readOps': '32874', 'readBytes': '814649344', 'readRate': '0.0', 'writeRate': '25839.869225829178', 'imageID': 'a387fa74-fa0d-4027-9f3f-560d2c723309'}}, 'balloonInfo': {'balloon_max': '16777216', 'balloon_min': '1048576', 'balloon_cur': '16777216', 'balloon_target': '16777216'}, 'vcpuCount': '4', 'memoryStats': {'mem_total': '16241900', 'mem_unused': '9958900', 'mem_free': '10857704', 'swap_in': 0, 'swap_out': 0, 'majflt': 0, 'minflt': 38, 'pageflt': 38}, 'displayInfo': [{'type': 'vnc', 'port': '5900', 'tlsPort': '', 'ipAddress': '10.0.0.95'}, {'type': 'spice', 'port': '5901', 'tlsPort': '5902', 'ipAddress': '10.0.0.95'}], 'hash': '503429482678177929', 'vmJobs': {}, 'vcpuQuota': '-1', 'vcpuPeriod': 100000, 'username': 'root', 'session': 'Unknown', 'memUsage': '35', 'guestCPUCount': -1, 'appsList': ('k ernel-4.18.0-193.28.1.el8_2.x86_64', 'qemu-guest-agent-4.2.0'), 'guestIPs': '', 'guestFQDN': 'si-ovirt-manager.par-tec.it', 'netIfaces': [{'hw': '00:00:00:00:00:00', 'inet': ['127.0.0.1'], 'inet6': ['::1'], 'name': 'lo'}, {'hw': '00:16:3e:0a:24:11', 'inet': ['10.0.0.97'], 'inet6': ['fe80::216:3eff:fe0a:2411'], 'name': 'eth0'}], 'disksUsage': [{'path': '/', 'total': '7505707008', 'used': '3535548416', 'fs': 'xfs'}, {'path': '/home', 'total': '1063256064', 'used': '41316352', 'fs': 'xfs'}, {'path': '/boot', 'total': '1063256064', 'used': '261574656', 'fs': 'xfs'}, {'path': '/tmp', 'total': '2136997888', 'used': '48939008', 'fs': 'xfs'}, {'path': '/var', 'total': '21464350720', 'used': '968744960', 'fs': 'xfs'}, {'path': '/var/log', 'total': '10726932480', 'used': '159023104', 'fs': 'xfs'}, {'path': '/var/log/audit', 'total': '1063256064', 'used': '42029056', 'fs': 'xfs'}], 'guestName': 'si-ovirt-manager.par-tec.it', 'guestOs': '4.18.0-193.28.1.el8_2.x86_64', 'guestOsInfo': {'type': 'l inux', 'arch': 'x86_64', 'kernel': '4.18.0-193.28.1.el8_2.x86_64', 'distribution': 'CentOS Linux', 'version': '8', 'codename': ''}, 'guestTimezone': {'offset': 60, 'zone': 'CET'}}]} from=::1,57660, vmId=cfe08595-6ff9-4b4c-a48d-51530e42230c (api:54) 2020-11-18 18:06:07,945+0100 INFO (jsonrpc/0) [jsonrpc.JsonRpcServer] RPC call VM.getStats succeeded in 0.00 seconds (__init__:312) 2020-11-18 18:06:07,995+0100 INFO (jsonrpc/4) [api.host] START getAllVmStats() from=::ffff:10.0.0.97,51960 (api:48) 2020-11-18 18:06:07,996+0100 INFO (jsonrpc/4) [api.host] FINISH getAllVmStats return={'status': {'code': 0, 'message': 'Done'}, 'statsList': (suppressed)} from=::ffff:10.0.0.97,51960 (api:54) 2020-11-18 18:06:07,997+0100 INFO (jsonrpc/4) [jsonrpc.JsonRpcServer] RPC call Host.getAllVmStats succeeded in 0.00 seconds (__init__:312) 2020-11-18 18:06:09,323+0100 INFO (jsonrpc/1) [jsonrpc.JsonRpcServer] RPC call Host.ping2 succeeded in 0.00 seconds (__init__:312) 2020-11-18 18:06:09,325+0100 INFO (jsonrpc/5) [api.virt] START getStats() from=::1,57660, vmId=cfe08595-6ff9-4b4c-a48d-51530e42230c (api:48) 2020-11-18 18:06:09,327+0100 INFO (jsonrpc/5) [api.virt] FINISH getStats return={'status': {'code': 0, 'message': 'Done'}, 'statsList': [{'statusTime': '2930013623', 'status': 'Up', 'vmId': 'cfe08595-6ff9-4b4c-a48d-51530e42230c', 'vmName': 'HostedEngine', 'vmType': 'kvm', 'kvmEnable': 'true', 'acpiEnable': 'true', 'elapsedTime': '198825', 'monitorResponse': '0', 'clientIp': '', 'timeOffset': '0', 'pauseCode': 'NOERR', 'cpuUser': '4.28', 'cpuSys': '0.60', 'cpuUsage': '2272410000000', 'network': {'vnet0': {'macAddr': '00:16:3e:0a:24:11', 'name': 'vnet0', 'speed': '1000', 'state': 'unknown', 'rxErrors': '0', 'rxDropped': '0', 'txErrors': '0', 'txDropped': '0', 'rx': '326227752', 'tx': '79641804', 'sampleTime': 782529.9764803}}, 'disks': {'sdc': {'truesize': '0', 'apparentsize': '0', 'readLatency': '0', 'writeLatency': '0', 'flushLatency': '0', 'writtenBytes': '0', 'writeOps': '0', 'readOps': '24', 'readBytes': '500', 'readRate': '0.0', 'writeRate': '0.0'}, 'vda': {'truesize': '85899345 920', 'apparentsize': '85899345920', 'readLatency': '0', 'writeLatency': '9266591.06060606', 'flushLatency': '24282438.636363637', 'writtenBytes': '6459164160', 'writeOps': '480151', 'readOps': '32874', 'readBytes': '814649344', 'readRate': '0.0', 'writeRate': '25839.869225829178', 'imageID': 'a387fa74-fa0d-4027-9f3f-560d2c723309'}}, 'balloonInfo': {'balloon_max': '16777216', 'balloon_min': '1048576', 'balloon_cur': '16777216', 'balloon_target': '16777216'}, 'vcpuCount': '4', 'memoryStats': {'mem_total': '16241900', 'mem_unused': '9958900', 'mem_free': '10857704', 'swap_in': 0, 'swap_out': 0, 'majflt': 0, 'minflt': 38, 'pageflt': 38}, 'displayInfo': [{'type': 'vnc', 'port': '5900', 'tlsPort': '', 'ipAddress': '10.0.0.95'}, {'type': 'spice', 'port': '5901', 'tlsPort': '5902', 'ipAddress': '10.0.0.95'}], 'hash': '503429482678177929', 'vmJobs': {}, 'vcpuQuota': '-1', 'vcpuPeriod': 100000, 'username': 'root', 'session': 'Unknown', 'memUsage': '35', 'guestCPUCount': -1, 'appsList': ('ker nel-4.18.0-193.28.1.el8_2.x86_64', 'qemu-guest-agent-4.2.0'), 'guestIPs': '', 'guestFQDN': 'si-ovirt-manager.par-tec.it', 'netIfaces': [{'hw': '00:00:00:00:00:00', 'inet': ['127.0.0.1'], 'inet6': ['::1'], 'name': 'lo'}, {'hw': '00:16:3e:0a:24:11', 'inet': ['10.0.0.97'], 'inet6': ['fe80::216:3eff:fe0a:2411'], 'name': 'eth0'}], 'disksUsage': [{'path': '/', 'total': '7505707008', 'used': '3535548416', 'fs': 'xfs'}, {'path': '/home', 'total': '1063256064', 'used': '41316352', 'fs': 'xfs'}, {'path': '/boot', 'total': '1063256064', 'used': '261574656', 'fs': 'xfs'}, {'path': '/tmp', 'total': '2136997888', 'used': '48939008', 'fs': 'xfs'}, {'path': '/var', 'total': '21464350720', 'used': '968744960', 'fs': 'xfs'}, {'path': '/var/log', 'total': '10726932480', 'used': '159023104', 'fs': 'xfs'}, {'path': '/var/log/audit', 'total': '1063256064', 'used': '42029056', 'fs': 'xfs'}], 'guestName': 'si-ovirt-manager.par-tec.it', 'guestOs': '4.18.0-193.28.1.el8_2.x86_64', 'guestOsInfo': {'type': 'lin ux', 'arch': 'x86_64', 'kernel': '4.18.0-193.28.1.el8_2.x86_64', 'distribution': 'CentOS Linux', 'version': '8', 'codename': ''}, 'guestTimezone': {'offset': 60, 'zone': 'CET'}}]} from=::1,57660, vmId=cfe08595-6ff9-4b4c-a48d-51530e42230c (api:54) 2020-11-18 18:06:09,327+0100 INFO (jsonrpc/5) [jsonrpc.JsonRpcServer] RPC call VM.getStats succeeded in 0.00 seconds (__init__:312) 2020-11-18 18:06:10,202+0100 INFO (periodic/2) [vdsm.api] START getVolumeSize(sdUUID='33992e1c-5383-4e3d-b9d0-945491ee0ccd', spUUID='00000000-0000-0000-0000-000000000000', imgUUID='a387fa74-fa0d-4027-9f3f-560d2c723309', volUUID='5ffd3ebe-097a-48eb-adb0-d74d8b0e547d', options=None) from=internal, task_id=fc08910d-dd6b-42ff-baba-dd7b4526d57a (api:48) 2020-11-18 18:06:10,202+0100 INFO (periodic/1) [vdsm.api] START getVolumeSize(sdUUID='dfeb1c22-4c0a-4bb4-9ef1-934962f18707', spUUID='2678c340-2299-11eb-bb74-00163e0a2411', imgUUID='519b7c5a-dcf7-44b0-a36a-3cd64116d37c', volUUID='5919b6b3-8e27-4733-9434-44e388d66bd3', options=None) from=internal, task_id=4476efc0-86de-4c86-9985-693c64d123fe (api:48) 2020-11-18 18:06:10,203+0100 INFO (periodic/1) [vdsm.api] FINISH getVolumeSize return={'apparentsize': '3221225472', 'truesize': '3221225472'} from=internal, task_id=4476efc0-86de-4c86-9985-693c64d123fe (api:54) 2020-11-18 18:06:10,204+0100 INFO (periodic/2) [vdsm.api] FINISH getVolumeSize return={'apparentsize': '85899345920', 'truesize': '85899345920'} from=internal, task_id=fc08910d-dd6b-42ff-baba-dd7b4526d57a (api:54) 2020-11-18 18:06:10,903+0100 INFO (jsonrpc/6) [jsonrpc.JsonRpcServer] RPC call Host.ping2 succeeded in 0.00 seconds (__init__:312) 2020-11-18 18:06:10,963+0100 INFO (jsonrpc/7) [jsonrpc.JsonRpcServer] RPC call Host.ping2 succeeded in 0.00 seconds (__init__:312) 2020-11-18 18:06:10,965+0100 INFO (jsonrpc/3) [vdsm.api] START repoStats(domains=['33992e1c-5383-4e3d-b9d0-945491ee0ccd']) from=::1,57660, task_id=3d16b966-b8ab-492a-8269-27bd600c4d48 (api:48) 2020-11-18 18:06:10,966+0100 INFO (jsonrpc/3) [vdsm.api] FINISH repoStats return={'33992e1c-5383-4e3d-b9d0-945491ee0ccd': {'code': 0, 'lastCheck': '2.7', 'delay': '0.00150785', 'valid': True, 'version': 5, 'acquired': True, 'actual': True}} from=::1,57660, task_id=3d16b966-b8ab-492a-8269-27bd600c4d48 (api:54) 2020-11-18 18:06:10,966+0100 INFO (jsonrpc/3) [jsonrpc.JsonRpcServer] RPC call Host.getStorageRepoStats succeeded in 0.00 seconds (__init__:312) 2020-11-18 18:06:11,244+0100 INFO (jsonrpc/2) [jsonrpc.JsonRpcServer] RPC call Host.ping2 succeeded in 0.00 seconds (__init__:312) 2020-11-18 18:06:11,246+0100 INFO (jsonrpc/0) [api.host] START getStats() from=::1,57660 (api:48) 2020-11-18 18:06:11,307+0100 INFO (jsonrpc/0) [vdsm.api] START repoStats(domains=()) from=::1,57660, task_id=dfe6a2c2-88e2-4c69-ae32-b51fed7690cc (api:48) 2020-11-18 18:06:11,308+0100 INFO (jsonrpc/0) [vdsm.api] FINISH repoStats return={'33992e1c-5383-4e3d-b9d0-945491ee0ccd': {'code': 0, 'lastCheck': '3.0', 'delay': '0.00150785', 'valid': True, 'version': 5, 'acquired': True, 'actual': True}, 'dfeb1c22-4c0a-4bb4-9ef1-934962f18707': {'code': 0, 'lastCheck': '0.4', 'delay': '0.000531715', 'valid': True, 'version': 5, 'acquired': True, 'actual': True}, '2974d4ff-27c5-4a25-b956-0ff4ff426cd3': {'code': 0, 'lastCheck': '1.7', 'delay': '0.000373626', 'valid': True, 'version': 0, 'acquired': True, 'actual': True}, '3b532b12-5151-4626-883a-26f2a82d4f9e': {'code': 0, 'lastCheck': '0.8', 'delay': '0.000372763', 'valid': True, 'version': 0, 'acquired': True, 'actual': True}} from=::1,57660, task_id=dfe6a2c2-88e2-4c69-ae32-b51fed7690cc (api:54) 2020-11-18 18:06:11,308+0100 INFO (jsonrpc/0) [vdsm.api] START multipath_health() from=::1,57660, task_id=4bb5664b-2eb8-4dfb-935f-40f979a5e132 (api:48) 2020-11-18 18:06:11,309+0100 INFO (jsonrpc/0) [vdsm.api] FINISH multipath_health return={} from=::1,57660, task_id=4bb5664b-2eb8-4dfb-935f-40f979a5e132 (api:54) 2020-11-18 18:06:11,321+0100 INFO (jsonrpc/0) [api.host] FINISH getStats return={'status': {'code': 0, 'message': 'Done'}, 'info': (suppressed)} from=::1,57660 (api:54) 2020-11-18 18:06:11,324+0100 INFO (jsonrpc/0) [jsonrpc.JsonRpcServer] RPC call Host.getStats succeeded in 0.08 seconds (__init__:312) 2020-11-18 18:06:11,648+0100 INFO (jsonrpc/4) [api.host] START getStats() from=::ffff:10.0.0.97,51960 (api:48) 2020-11-18 18:06:11,709+0100 INFO (jsonrpc/4) [vdsm.api] START repoStats(domains=()) from=::ffff:10.0.0.97,51960, task_id=75a39cbe-db77-47ab-8fdd-7296489f8a41 (api:48) 2020-11-18 18:06:11,710+0100 INFO (jsonrpc/4) [vdsm.api] FINISH repoStats return={'33992e1c-5383-4e3d-b9d0-945491ee0ccd': {'code': 0, 'lastCheck': '3.4', 'delay': '0.00150785', 'valid': True, 'version': 5, 'acquired': True, 'actual': True}, 'dfeb1c22-4c0a-4bb4-9ef1-934962f18707': {'code': 0, 'lastCheck': '0.8', 'delay': '0.000531715', 'valid': True, 'version': 5, 'acquired': True, 'actual': True}, '2974d4ff-27c5-4a25-b956-0ff4ff426cd3': {'code': 0, 'lastCheck': '2.1', 'delay': '0.000373626', 'valid': True, 'version': 0, 'acquired': True, 'actual': True}, '3b532b12-5151-4626-883a-26f2a82d4f9e': {'code': 0, 'lastCheck': '1.2', 'delay': '0.000372763', 'valid': True, 'version': 0, 'acquired': True, 'actual': True}} from=::ffff:10.0.0.97,51960, task_id=75a39cbe-db77-47ab-8fdd-7296489f8a41 (api:54) 2020-11-18 18:06:11,710+0100 INFO (jsonrpc/4) [vdsm.api] START multipath_health() from=::ffff:10.0.0.97,51960, task_id=aeb08940-7f29-49ed-8e00-646ca35ca736 (api:48) 2020-11-18 18:06:11,711+0100 INFO (jsonrpc/4) [vdsm.api] FINISH multipath_health return={} from=::ffff:10.0.0.97,51960, task_id=aeb08940-7f29-49ed-8e00-646ca35ca736 (api:54) 2020-11-18 18:06:11,722+0100 INFO (jsonrpc/4) [api.host] FINISH getStats return={'status': {'code': 0, 'message': 'Done'}, 'info': (suppressed)} from=::ffff:10.0.0.97,51960 (api:54) 2020-11-18 18:06:11,725+0100 INFO (jsonrpc/4) [jsonrpc.JsonRpcServer] RPC call Host.getStats succeeded in 0.08 seconds (__init__:312) 2020-11-18 18:06:14,911+0100 INFO (jsonrpc/1) [jsonrpc.JsonRpcServer] RPC call Host.ping2 succeeded in 0.00 seconds (__init__:312) 2020-11-18 18:06:15,076+0100 INFO (jsonrpc/5) [api.host] START getAllVmStats() from=::1,57668 (api:48) 2020-11-18 18:06:15,079+0100 INFO (jsonrpc/5) [api.host] FINISH getAllVmStats return={'status': {'code': 0, 'message': 'Done'}, 'statsList': (suppressed)} from=::1,57668 (api:54) 2020-11-18 18:06:15,079+0100 INFO (jsonrpc/5) [jsonrpc.JsonRpcServer] RPC call Host.getAllVmStats succeeded in 0.00 seconds (__init__:312) 2020-11-18 18:06:15,083+0100 INFO (jsonrpc/6) [api.host] START getAllVmIoTunePolicies() from=::1,57668 (api:48) 2020-11-18 18:06:15,084+0100 INFO (jsonrpc/6) [api.host] FINISH getAllVmIoTunePolicies return={'status': {'code': 0, 'message': 'Done'}, 'io_tune_policies_dict': {'cfe08595-6ff9-4b4c-a48d-51530e42230c': {'policy': [], 'current_values': [{'name': 'vda', 'path': '/var/run/vdsm/storage/33992e1c-5383-4e3d-b9d0-945491ee0ccd/a387fa74-fa0d-4027-9f3f-560d2c723309/5ffd3ebe-097a-48eb-adb0-d74d8b0e547d', 'ioTune': {'total_bytes_sec': 0, 'read_bytes_sec': 0, 'write_bytes_sec': 0, 'total_iops_sec': 0, 'write_iops_sec': 0, 'read_iops_sec': 0}}]}, '0a940b7e-5f9c-4e4b-b11c-0d3c1d811a83': {'policy': [], 'current_values': [{'name': 'sda', 'path': '/rhev/data-center/mnt/blockSD/dfeb1c22-4c0a-4bb4-9ef1-934962f18707/images/519b7c5a-dcf7-44b0-a36a-3cd64116d37c/5919b6b3-8e27-4733-9434-44e388d66bd3', 'ioTune': {'total_bytes_sec': 0, 'read_bytes_sec': 0, 'write_bytes_sec': 0, 'total_iops_sec': 0, 'write_iops_sec': 0, 'read_iops_sec': 0}}]}}} from=::1,57668 (api:54) 2020-11-18 18:06:15,084+0100 INFO (jsonrpc/6) [jsonrpc.JsonRpcServer] RPC call Host.getAllVmIoTunePolicies succeeded in 0.00 seconds (__init__:312) 2020-11-18 18:06:15,980+0100 INFO (jsonrpc/7) [vdsm.api] START getSpmStatus(spUUID='2678c340-2299-11eb-bb74-00163e0a2411', options=None) from=::ffff:10.0.0.97,51960, task_id=a196bd19-3d97-4283-b44a-32fa5c0d7bce (api:48) 2020-11-18 18:06:15,993+0100 INFO (jsonrpc/7) [vdsm.api] FINISH getSpmStatus return={'spm_st': {'spmStatus': 'SPM', 'spmLver': 3, 'spmId': 1}} from=::ffff:10.0.0.97,51960, task_id=a196bd19-3d97-4283-b44a-32fa5c0d7bce (api:54) 2020-11-18 18:06:15,994+0100 INFO (jsonrpc/7) [jsonrpc.JsonRpcServer] RPC call StoragePool.getSpmStatus succeeded in 0.01 seconds (__init__:312) 2020-11-18 18:06:16,001+0100 INFO (jsonrpc/3) [vdsm.api] START getStoragePoolInfo(spUUID='2678c340-2299-11eb-bb74-00163e0a2411', options=None) from=::ffff:10.0.0.97,51966, task_id=9397f7ba-07df-4fb7-a74a-ba2e10a05f33 (api:48) 2020-11-18 18:06:16,016+0100 INFO (jsonrpc/3) [vdsm.api] FINISH getStoragePoolInfo return={'info': {'domains': '2974d4ff-27c5-4a25-b956-0ff4ff426cd3:Active,33992e1c-5383-4e3d-b9d0-945491ee0ccd:Active,dfeb1c22-4c0a-4bb4-9ef1-934962f18707:Active,3b532b12-5151-4626-883a-26f2a82d4f9e:Active', 'isoprefix': '/rhev/data-center/mnt/si-nas.babel.it:_iso-domain/2974d4ff-27c5-4a25-b956-0ff4ff426cd3/images/11111111-1111-1111-1111-111111111111', 'lver': 3, 'master_uuid': '33992e1c-5383-4e3d-b9d0-945491ee0ccd', 'master_ver': 1, 'name': 'No Description', 'pool_status': 'connected', 'spm_id': 1, 'type': 'ISCSI', 'version': '5'}, 'dominfo': {'2974d4ff-27c5-4a25-b956-0ff4ff426cd3': {'status': 'Active', 'alerts': [], 'isoprefix': '/rhev/data-center/mnt/si-nas.babel.it:_iso-domain/2974d4ff-27c5-4a25-b956-0ff4ff426cd3/images/11111111-1111-1111-1111-111111111111', 'version': 0, 'disktotal': '211378241536', 'diskfree': '176700424192'}, '33992e1c-5383-4e3d-b9d0-945491ee0ccd': {'status': 'Active', 'alerts': [], 'isoprefix': '', 'version': 5, 'disktotal': '106971529216', 'diskfree': '12750684160'}, 'dfeb1c22-4c0a-4bb4-9ef1-934962f18707': {'status': 'Active', 'alerts': [], 'isoprefix': '', 'version': 5, 'disktotal': '751216623616', 'diskfree': '738734374912'}, '3b532b12-5151-4626-883a-26f2a82d4f9e': {'status': 'Active', 'alerts': [], 'isoprefix': '', 'version': 0, 'disktotal': '5827912007680', 'diskfree': '1412350607360'}}} from=::ffff:10.0.0.97,51966, task_id=9397f7ba-07df-4fb7-a74a-ba2e10a05f33 (api:54) 2020-11-18 18:06:16,017+0100 INFO (jsonrpc/3) [jsonrpc.JsonRpcServer] RPC call StoragePool.getInfo succeeded in 0.02 seconds (__init__:312) 2020-11-18 18:06:16,228+0100 INFO (jsonrpc/2) [jsonrpc.JsonRpcServer] RPC call Host.ping2 succeeded in 0.00 seconds (__init__:312) 2020-11-18 18:06:16,230+0100 INFO (jsonrpc/0) [api.host] START getStats() from=::1,57660 (api:48) 2020-11-18 18:06:16,290+0100 INFO (jsonrpc/0) [vdsm.api] START repoStats(domains=()) from=::1,57660, task_id=6be35f67-17a6-4d13-987c-a30fcec05c50 (api:48) 2020-11-18 18:06:16,291+0100 INFO (jsonrpc/0) [vdsm.api] FINISH repoStats return={'33992e1c-5383-4e3d-b9d0-945491ee0ccd': {'code': 0, 'lastCheck': '2.4', 'delay': '0.00150785', 'valid': True, 'version': 5, 'acquired': True, 'actual': True}, 'dfeb1c22-4c0a-4bb4-9ef1-934962f18707': {'code': 0, 'lastCheck': '5.4', 'delay': '0.000531715', 'valid': True, 'version': 5, 'acquired': True, 'actual': True}, '2974d4ff-27c5-4a25-b956-0ff4ff426cd3': {'code': 0, 'lastCheck': '3.8', 'delay': '0.000390806', 'valid': True, 'version': 0, 'acquired': True, 'actual': True}, '3b532b12-5151-4626-883a-26f2a82d4f9e': {'code': 0, 'lastCheck': '0.8', 'delay': '0.000373419', 'valid': True, 'version': 0, 'acquired': True, 'actual': True}} from=::1,57660, task_id=6be35f67-17a6-4d13-987c-a30fcec05c50 (api:54) 2020-11-18 18:06:16,291+0100 INFO (jsonrpc/0) [vdsm.api] START multipath_health() from=::1,57660, task_id=f89686a1-c037-4b27-9f39-aaf443bad5b1 (api:48) 2020-11-18 18:06:16,292+0100 INFO (jsonrpc/0) [vdsm.api] FINISH multipath_health return={} from=::1,57660, task_id=f89686a1-c037-4b27-9f39-aaf443bad5b1 (api:54) 2020-11-18 18:06:16,304+0100 INFO (jsonrpc/0) [api.host] FINISH getStats return={'status': {'code': 0, 'message': 'Done'}, 'info': (suppressed)} from=::1,57660 (api:54) 2020-11-18 18:06:16,306+0100 INFO (jsonrpc/0) [jsonrpc.JsonRpcServer] RPC call Host.getStats succeeded in 0.08 seconds (__init__:312) 2020-11-18 18:06:16,740+0100 INFO (jsonrpc/4) [jsonrpc.JsonRpcServer] RPC call Host.ping2 succeeded in 0.00 seconds (__init__:312) 2020-11-18 18:06:16,743+0100 INFO (jsonrpc/1) [vdsm.api] START repoStats(domains=['33992e1c-5383-4e3d-b9d0-945491ee0ccd']) from=::1,57660, task_id=2e5b5029-7d88-4f40-acde-fe49284f2984 (api:48) 2020-11-18 18:06:16,743+0100 INFO (jsonrpc/1) [vdsm.api] FINISH repoStats return={'33992e1c-5383-4e3d-b9d0-945491ee0ccd': {'code': 0, 'lastCheck': '2.9', 'delay': '0.00150785', 'valid': True, 'version': 5, 'acquired': True, 'actual': True}} from=::1,57660, task_id=2e5b5029-7d88-4f40-acde-fe49284f2984 (api:54) 2020-11-18 18:06:16,744+0100 INFO (jsonrpc/1) [jsonrpc.JsonRpcServer] RPC call Host.getStorageRepoStats succeeded in 0.00 seconds (__init__:312) 2020-11-18 18:06:19,505+0100 INFO (jsonrpc/5) [jsonrpc.JsonRpcServer] RPC call Host.ping2 succeeded in 0.00 seconds (__init__:312) 2020-11-18 18:06:19,507+0100 INFO (jsonrpc/6) [api.virt] START getStats() from=::1,57660, vmId=cfe08595-6ff9-4b4c-a48d-51530e42230c (api:48) 2020-11-18 18:06:19,509+0100 INFO (jsonrpc/6) [api.virt] FINISH getStats return={'status': {'code': 0, 'message': 'Done'}, 'statsList': [{'statusTime': '2930023805', 'status': 'Up', 'vmId': 'cfe08595-6ff9-4b4c-a48d-51530e42230c', 'vmName': 'HostedEngine', 'vmType': 'kvm', 'kvmEnable': 'true', 'acpiEnable': 'true', 'elapsedTime': '198835', 'monitorResponse': '0', 'clientIp': '', 'timeOffset': '0', 'pauseCode': 'NOERR', 'cpuUser': '3.58', 'cpuSys': '0.60', 'cpuUsage': '2272500000000', 'network': {'vnet0': {'macAddr': '00:16:3e:0a:24:11', 'name': 'vnet0', 'speed': '1000', 'state': 'unknown', 'rxErrors': '0', 'rxDropped': '0', 'txErrors': '0', 'txDropped': '0', 'rx': '326253116', 'tx': '79649186', 'sampleTime': 782540.158670337}}, 'disks': {'sdc': {'truesize': '0', 'apparentsize': '0', 'readLatency': '0', 'writeLatency': '0', 'flushLatency': '0', 'writtenBytes': '0', 'writeOps': '0', 'readOps': '24', 'readBytes': '500', 'readRate': '0.0', 'writeRate': '0.0'}, 'vda': {'truesize': '858993 45920', 'apparentsize': '85899345920', 'readLatency': '0', 'writeLatency': '2327450.714285714', 'flushLatency': '31771128.222222224', 'writtenBytes': '6459450880', 'writeOps': '480172', 'readOps': '32874', 'readBytes': '814649344', 'readRate': '0.0', 'writeRate': '19114.56211641065', 'imageID': 'a387fa74-fa0d-4027-9f3f-560d2c723309'}}, 'balloonInfo': {'balloon_max': '16777216', 'balloon_min': '1048576', 'balloon_cur': '16777216', 'balloon_target': '16777216'}, 'vcpuCount': '4', 'memoryStats': {'mem_total': '16241900', 'mem_unused': '9958668', 'mem_free': '10857472', 'swap_in': 0, 'swap_out': 0, 'majflt': 0, 'minflt': 1, 'pageflt': 1}, 'displayInfo': [{'type': 'vnc', 'port': '5900', 'tlsPort': '', 'ipAddress': '10.0.0.95'}, {'type': 'spice', 'port': '5901', 'tlsPort': '5902', 'ipAddress': '10.0.0.95'}], 'hash': '503429482678177929', 'vmJobs': {}, 'vcpuQuota': '-1', 'vcpuPeriod': 100000, 'username': 'root', 'session': 'Unknown', 'memUsage': '35', 'guestCPUCount': -1, 'appsList': ('ker nel-4.18.0-193.28.1.el8_2.x86_64', 'qemu-guest-agent-4.2.0'), 'guestIPs': '', 'guestFQDN': 'si-ovirt-manager.par-tec.it', 'netIfaces': [{'hw': '00:00:00:00:00:00', 'inet': ['127.0.0.1'], 'inet6': ['::1'], 'name': 'lo'}, {'hw': '00:16:3e:0a:24:11', 'inet': ['10.0.0.97'], 'inet6': ['fe80::216:3eff:fe0a:2411'], 'name': 'eth0'}], 'disksUsage': [{'path': '/', 'total': '7505707008', 'used': '3535548416', 'fs': 'xfs'}, {'path': '/home', 'total': '1063256064', 'used': '41316352', 'fs': 'xfs'}, {'path': '/boot', 'total': '1063256064', 'used': '261574656', 'fs': 'xfs'}, {'path': '/tmp', 'total': '2136997888', 'used': '48939008', 'fs': 'xfs'}, {'path': '/var', 'total': '21464350720', 'used': '968744960', 'fs': 'xfs'}, {'path': '/var/log', 'total': '10726932480', 'used': '159023104', 'fs': 'xfs'}, {'path': '/var/log/audit', 'total': '1063256064', 'used': '42029056', 'fs': 'xfs'}], 'guestName': 'si-ovirt-manager.par-tec.it', 'guestOs': '4.18.0-193.28.1.el8_2.x86_64', 'guestOsInfo': {'type': 'lin ux', 'arch': 'x86_64', 'kernel': '4.18.0-193.28.1.el8_2.x86_64', 'distribution': 'CentOS Linux', 'version': '8', 'codename': ''}, 'guestTimezone': {'offset': 60, 'zone': 'CET'}}]} from=::1,57660, vmId=cfe08595-6ff9-4b4c-a48d-51530e42230c (api:54) 2020-11-18 18:06:19,510+0100 INFO (jsonrpc/6) [jsonrpc.JsonRpcServer] RPC call VM.getStats succeeded in 0.00 seconds (__init__:312) 2020-11-18 18:06:21,037+0100 INFO (jsonrpc/7) [jsonrpc.JsonRpcServer] RPC call Host.ping2 succeeded in 0.00 seconds (__init__:312) 2020-11-18 18:06:21,095+0100 INFO (jsonrpc/3) [jsonrpc.JsonRpcServer] RPC call Host.ping2 succeeded in 0.00 seconds (__init__:312) 2020-11-18 18:06:21,097+0100 INFO (jsonrpc/2) [vdsm.api] START repoStats(domains=['33992e1c-5383-4e3d-b9d0-945491ee0ccd']) from=::1,57660, task_id=e2e2445c-5e65-46ff-b0e7-031ecd42629d (api:48) 2020-11-18 18:06:21,097+0100 INFO (jsonrpc/2) [vdsm.api] FINISH repoStats return={'33992e1c-5383-4e3d-b9d0-945491ee0ccd': {'code': 0, 'lastCheck': '2.8', 'delay': '0.000561104', 'valid': True, 'version': 5, 'acquired': True, 'actual': True}} from=::1,57660, task_id=e2e2445c-5e65-46ff-b0e7-031ecd42629d (api:54) 2020-11-18 18:06:21,098+0100 INFO (jsonrpc/2) [jsonrpc.JsonRpcServer] RPC call Host.getStorageRepoStats succeeded in 0.00 seconds (__init__:312) 2020-11-18 18:06:21,338+0100 INFO (jsonrpc/0) [jsonrpc.JsonRpcServer] RPC call Host.ping2 succeeded in 0.00 seconds (__init__:312) 2020-11-18 18:06:21,340+0100 INFO (jsonrpc/4) [api.host] START getStats() from=::1,57660 (api:48) 2020-11-18 18:06:21,401+0100 INFO (jsonrpc/4) [vdsm.api] START repoStats(domains=()) from=::1,57660, task_id=ad8523ce-6f6f-4f6c-8476-4ac04ccbcf52 (api:48) 2020-11-18 18:06:21,401+0100 INFO (jsonrpc/4) [vdsm.api] FINISH repoStats return={'33992e1c-5383-4e3d-b9d0-945491ee0ccd': {'code': 0, 'lastCheck': '3.1', 'delay': '0.000561104', 'valid': True, 'version': 5, 'acquired': True, 'actual': True}, 'dfeb1c22-4c0a-4bb4-9ef1-934962f18707': {'code': 0, 'lastCheck': '0.5', 'delay': '0.000575686', 'valid': True, 'version': 5, 'acquired': True, 'actual': True}, '2974d4ff-27c5-4a25-b956-0ff4ff426cd3': {'code': 0, 'lastCheck': '1.8', 'delay': '0.000390806', 'valid': True, 'version': 0, 'acquired': True, 'actual': True}, '3b532b12-5151-4626-883a-26f2a82d4f9e': {'code': 0, 'lastCheck': '0.9', 'delay': '0.000373419', 'valid': True, 'version': 0, 'acquired': True, 'actual': True}} from=::1,57660, task_id=ad8523ce-6f6f-4f6c-8476-4ac04ccbcf52 (api:54) 2020-11-18 18:06:21,402+0100 INFO (jsonrpc/4) [vdsm.api] START multipath_health() from=::1,57660, task_id=e269abde-159d-4ac7-a7ca-bc0444020025 (api:48) 2020-11-18 18:06:21,403+0100 INFO (jsonrpc/4) [vdsm.api] FINISH multipath_health return={} from=::1,57660, task_id=e269abde-159d-4ac7-a7ca-bc0444020025 (api:54) 2020-11-18 18:06:21,414+0100 INFO (jsonrpc/4) [api.host] FINISH getStats return={'status': {'code': 0, 'message': 'Done'}, 'info': (suppressed)} from=::1,57660 (api:54) 2020-11-18 18:06:21,417+0100 INFO (jsonrpc/4) [jsonrpc.JsonRpcServer] RPC call Host.getStats succeeded in 0.08 seconds (__init__:312) 2020-11-18 18:06:22,159+0100 INFO (periodic/3) [vdsm.api] START repoStats(domains=()) from=internal, task_id=dd5eea75-98d2-459b-9f5a-0db38a9ffb43 (api:48) 2020-11-18 18:06:22,160+0100 INFO (periodic/3) [vdsm.api] FINISH repoStats return={'33992e1c-5383-4e3d-b9d0-945491ee0ccd': {'code': 0, 'lastCheck': '3.8', 'delay': '0.000561104', 'valid': True, 'version': 5, 'acquired': True, 'actual': True}, 'dfeb1c22-4c0a-4bb4-9ef1-934962f18707': {'code': 0, 'lastCheck': '1.3', 'delay': '0.000575686', 'valid': True, 'version': 5, 'acquired': True, 'actual': True}, '2974d4ff-27c5-4a25-b956-0ff4ff426cd3': {'code': 0, 'lastCheck': '2.6', 'delay': '0.000390806', 'valid': True, 'version': 0, 'acquired': True, 'actual': True}, '3b532b12-5151-4626-883a-26f2a82d4f9e': {'code': 0, 'lastCheck': '1.7', 'delay': '0.000373419', 'valid': True, 'version': 0, 'acquired': True, 'actual': True}} from=internal, task_id=dd5eea75-98d2-459b-9f5a-0db38a9ffb43 (api:54) 2020-11-18 18:06:23,055+0100 INFO (jsonrpc/1) [api.host] START getAllVmStats() from=::ffff:10.0.0.97,51960 (api:48) 2020-11-18 18:06:23,057+0100 INFO (jsonrpc/1) [api.host] FINISH getAllVmStats return={'status': {'code': 0, 'message': 'Done'}, 'statsList': (suppressed)} from=::ffff:10.0.0.97,51960 (api:54) 2020-11-18 18:06:23,058+0100 INFO (jsonrpc/1) [jsonrpc.JsonRpcServer] RPC call Host.getAllVmStats succeeded in 0.00 seconds (__init__:312) 2020-11-18 18:06:26,064+0100 INFO (jsonrpc/5) [vdsm.api] START getSpmStatus(spUUID='2678c340-2299-11eb-bb74-00163e0a2411', options=None) from=::ffff:10.0.0.97,51960, task_id=5e9c0d33-e46b-47ba-9138-cb5e99f2cd8e (api:48) 2020-11-18 18:06:26,076+0100 INFO (jsonrpc/5) [vdsm.api] FINISH getSpmStatus return={'spm_st': {'spmStatus': 'SPM', 'spmLver': 3, 'spmId': 1}} from=::ffff:10.0.0.97,51960, task_id=5e9c0d33-e46b-47ba-9138-cb5e99f2cd8e (api:54) 2020-11-18 18:06:26,077+0100 INFO (jsonrpc/5) [jsonrpc.JsonRpcServer] RPC call StoragePool.getSpmStatus succeeded in 0.01 seconds (__init__:312) 2020-11-18 18:06:26,084+0100 INFO (jsonrpc/6) [vdsm.api] START getStoragePoolInfo(spUUID='2678c340-2299-11eb-bb74-00163e0a2411', options=None) from=::ffff:10.0.0.97,51966, task_id=c704bb66-fe90-4a5d-b8b0-e68983c198db (api:48) 2020-11-18 18:06:26,098+0100 INFO (jsonrpc/6) [vdsm.api] FINISH getStoragePoolInfo return={'info': {'domains': '2974d4ff-27c5-4a25-b956-0ff4ff426cd3:Active,33992e1c-5383-4e3d-b9d0-945491ee0ccd:Active,dfeb1c22-4c0a-4bb4-9ef1-934962f18707:Active,3b532b12-5151-4626-883a-26f2a82d4f9e:Active', 'isoprefix': '/rhev/data-center/mnt/si-nas.babel.it:_iso-domain/2974d4ff-27c5-4a25-b956-0ff4ff426cd3/images/11111111-1111-1111-1111-111111111111', 'lver': 3, 'master_uuid': '33992e1c-5383-4e3d-b9d0-945491ee0ccd', 'master_ver': 1, 'name': 'No Description', 'pool_status': 'connected', 'spm_id': 1, 'type': 'ISCSI', 'version': '5'}, 'dominfo': {'2974d4ff-27c5-4a25-b956-0ff4ff426cd3': {'status': 'Active', 'alerts': [], 'isoprefix': '/rhev/data-center/mnt/si-nas.babel.it:_iso-domain/2974d4ff-27c5-4a25-b956-0ff4ff426cd3/images/11111111-1111-1111-1111-111111111111', 'version': 0, 'disktotal': '211378241536', 'diskfree': '176700424192'}, '33992e1c-5383-4e3d-b9d0-945491ee0ccd': {'status': 'Active', 'alerts': [], 'isoprefix': '', 'version': 5, 'disktotal': '106971529216', 'diskfree': '12750684160'}, 'dfeb1c22-4c0a-4bb4-9ef1-934962f18707': {'status': 'Active', 'alerts': [], 'isoprefix': '', 'version': 5, 'disktotal': '751216623616', 'diskfree': '738734374912'}, '3b532b12-5151-4626-883a-26f2a82d4f9e': {'status': 'Active', 'alerts': [], 'isoprefix': '', 'version': 0, 'disktotal': '5827912007680', 'diskfree': '1412350607360'}}} from=::ffff:10.0.0.97,51966, task_id=c704bb66-fe90-4a5d-b8b0-e68983c198db (api:54) 2020-11-18 18:06:26,099+0100 INFO (jsonrpc/6) [jsonrpc.JsonRpcServer] RPC call StoragePool.getInfo succeeded in 0.01 seconds (__init__:312) 2020-11-18 18:06:26,320+0100 INFO (jsonrpc/7) [jsonrpc.JsonRpcServer] RPC call Host.ping2 succeeded in 0.00 seconds (__init__:312) 2020-11-18 18:06:26,322+0100 INFO (jsonrpc/3) [api.host] START getStats() from=::1,57660 (api:48) 2020-11-18 18:06:26,383+0100 INFO (jsonrpc/3) [vdsm.api] START repoStats(domains=()) from=::1,57660, task_id=78f8c14b-8bc2-4e39-b614-3a113e5b76d1 (api:48) 2020-11-18 18:06:26,383+0100 INFO (jsonrpc/3) [vdsm.api] FINISH repoStats return={'33992e1c-5383-4e3d-b9d0-945491ee0ccd': {'code': 0, 'lastCheck': '2.5', 'delay': '0.000561104', 'valid': True, 'version': 5, 'acquired': True, 'actual': True}, 'dfeb1c22-4c0a-4bb4-9ef1-934962f18707': {'code': 0, 'lastCheck': '5.5', 'delay': '0.000575686', 'valid': True, 'version': 5, 'acquired': True, 'actual': True}, '2974d4ff-27c5-4a25-b956-0ff4ff426cd3': {'code': 0, 'lastCheck': '3.9', 'delay': '0.000337233', 'valid': True, 'version': 0, 'acquired': True, 'actual': True}, '3b532b12-5151-4626-883a-26f2a82d4f9e': {'code': 0, 'lastCheck': '0.9', 'delay': '0.000373066', 'valid': True, 'version': 0, 'acquired': True, 'actual': True}} from=::1,57660, task_id=78f8c14b-8bc2-4e39-b614-3a113e5b76d1 (api:54) 2020-11-18 18:06:26,401+0100 INFO (jsonrpc/3) [vdsm.api] START multipath_health() from=::1,57660, task_id=bc7f3675-9996-47dd-a5fa-df2a885d8c38 (api:48) 2020-11-18 18:06:26,403+0100 INFO (jsonrpc/3) [vdsm.api] FINISH multipath_health return={} from=::1,57660, task_id=bc7f3675-9996-47dd-a5fa-df2a885d8c38 (api:54) 2020-11-18 18:06:26,421+0100 INFO (jsonrpc/3) [api.host] FINISH getStats return={'status': {'code': 0, 'message': 'Done'}, 'info': (suppressed)} from=::1,57660 (api:54) 2020-11-18 18:06:26,425+0100 INFO (jsonrpc/3) [jsonrpc.JsonRpcServer] RPC call Host.getStats succeeded in 0.10 seconds (__init__:312) 2020-11-18 18:06:26,672+0100 INFO (jsonrpc/2) [api.host] START getStats() from=::ffff:10.0.0.97,51960 (api:48) 2020-11-18 18:06:26,732+0100 INFO (jsonrpc/2) [vdsm.api] START repoStats(domains=()) from=::ffff:10.0.0.97,51960, task_id=f74ccc6e-da72-4b94-b9fb-3207c3f2ccfe (api:48) 2020-11-18 18:06:26,733+0100 INFO (jsonrpc/2) [vdsm.api] FINISH repoStats return={'33992e1c-5383-4e3d-b9d0-945491ee0ccd': {'code': 0, 'lastCheck': '2.9', 'delay': '0.000561104', 'valid': True, 'version': 5, 'acquired': True, 'actual': True}, 'dfeb1c22-4c0a-4bb4-9ef1-934962f18707': {'code': 0, 'lastCheck': '0.3', 'delay': '0.000575857', 'valid': True, 'version': 5, 'acquired': True, 'actual': True}, '2974d4ff-27c5-4a25-b956-0ff4ff426cd3': {'code': 0, 'lastCheck': '4.3', 'delay': '0.000337233', 'valid': True, 'version': 0, 'acquired': True, 'actual': True}, '3b532b12-5151-4626-883a-26f2a82d4f9e': {'code': 0, 'lastCheck': '1.2', 'delay': '0.000373066', 'valid': True, 'version': 0, 'acquired': True, 'actual': True}} from=::ffff:10.0.0.97,51960, task_id=f74ccc6e-da72-4b94-b9fb-3207c3f2ccfe (api:54) 2020-11-18 18:06:26,733+0100 INFO (jsonrpc/2) [vdsm.api] START multipath_health() from=::ffff:10.0.0.97,51960, task_id=ea891f01-a519-4450-8420-9cc9f0485b02 (api:48) 2020-11-18 18:06:26,734+0100 INFO (jsonrpc/2) [vdsm.api] FINISH multipath_health return={} from=::ffff:10.0.0.97,51960, task_id=ea891f01-a519-4450-8420-9cc9f0485b02 (api:54) 2020-11-18 18:06:26,744+0100 INFO (jsonrpc/2) [api.host] FINISH getStats return={'status': {'code': 0, 'message': 'Done'}, 'info': (suppressed)} from=::ffff:10.0.0.97,51960 (api:54) 2020-11-18 18:06:26,747+0100 INFO (jsonrpc/2) [jsonrpc.JsonRpcServer] RPC call Host.getStats succeeded in 0.08 seconds (__init__:312) 2020-11-18 18:06:26,756+0100 INFO (jsonrpc/0) [jsonrpc.JsonRpcServer] RPC call Host.ping2 succeeded in 0.00 seconds (__init__:312) 2020-11-18 18:06:26,757+0100 INFO (jsonrpc/4) [vdsm.api] START repoStats(domains=['33992e1c-5383-4e3d-b9d0-945491ee0ccd']) from=::1,57660, task_id=6ac73ee5-2519-4084-90e8-b2280093e2c5 (api:48) 2020-11-18 18:06:26,758+0100 INFO (jsonrpc/4) [vdsm.api] FINISH repoStats return={'33992e1c-5383-4e3d-b9d0-945491ee0ccd': {'code': 0, 'lastCheck': '2.9', 'delay': '0.000561104', 'valid': True, 'version': 5, 'acquired': True, 'actual': True}} from=::1,57660, task_id=6ac73ee5-2519-4084-90e8-b2280093e2c5 (api:54) 2020-11-18 18:06:26,758+0100 INFO (jsonrpc/4) [jsonrpc.JsonRpcServer] RPC call Host.getStorageRepoStats succeeded in 0.00 seconds (__init__:312) 2020-11-18 18:06:29,687+0100 INFO (jsonrpc/1) [jsonrpc.JsonRpcServer] RPC call Host.ping2 succeeded in 0.00 seconds (__init__:312) 2020-11-18 18:06:29,689+0100 INFO (jsonrpc/5) [api.virt] START getStats() from=::1,57660, vmId=cfe08595-6ff9-4b4c-a48d-51530e42230c (api:48) 2020-11-18 18:06:29,691+0100 INFO (jsonrpc/5) [api.virt] FINISH getStats return={'status': {'code': 0, 'message': 'Done'}, 'statsList': [{'statusTime': '2930033987', 'status': 'Up', 'vmId': 'cfe08595-6ff9-4b4c-a48d-51530e42230c', 'vmName': 'HostedEngine', 'vmType': 'kvm', 'kvmEnable': 'true', 'acpiEnable': 'true', 'elapsedTime': '198845', 'monitorResponse': '0', 'clientIp': '', 'timeOffset': '0', 'pauseCode': 'NOERR', 'cpuUser': '5.39', 'cpuSys': '0.80', 'cpuUsage': '2272620000000', 'network': {'vnet0': {'macAddr': '00:16:3e:0a:24:11', 'name': 'vnet0', 'speed': '1000', 'state': 'unknown', 'rxErrors': '0', 'rxDropped': '0', 'txErrors': '0', 'txDropped': '0', 'rx': '326281599', 'tx': '79663923', 'sampleTime': 782550.340684496}}, 'disks': {'sdc': {'truesize': '0', 'apparentsize': '0', 'readLatency': '0', 'writeLatency': '0', 'flushLatency': '0', 'writtenBytes': '0', 'writeOps': '0', 'readOps': '24', 'readBytes': '500', 'readRate': '0.0', 'writeRate': '0.0'}, 'vda': {'truesize': '858993 45920', 'apparentsize': '85899345920', 'readLatency': '0', 'writeLatency': '3739009.296296296', 'flushLatency': '18780516.72', 'writtenBytes': '6459756032', 'writeOps': '480199', 'readOps': '32874', 'readBytes': '814649344', 'readRate': '0.0', 'writeRate': '20343.832566662484', 'imageID': 'a387fa74-fa0d-4027-9f3f-560d2c723309'}}, 'balloonInfo': {'balloon_max': '16777216', 'balloon_min': '1048576', 'balloon_cur': '16777216', 'balloon_target': '16777216'}, 'vcpuCount': '4', 'memoryStats': {'mem_total': '16241900', 'mem_unused': '9958840', 'mem_free': '10857648', 'swap_in': 0, 'swap_out': 0, 'majflt': 0, 'minflt': 334, 'pageflt': 334}, 'displayInfo': [{'type': 'vnc', 'port': '5900', 'tlsPort': '', 'ipAddress': '10.0.0.95'}, {'type': 'spice', 'port': '5901', 'tlsPort': '5902', 'ipAddress': '10.0.0.95'}], 'hash': '503429482678177929', 'vmJobs': {}, 'vcpuQuota': '-1', 'vcpuPeriod': 100000, 'username': 'root', 'session': 'Unknown', 'memUsage': '35', 'guestCPUCount': -1, 'appsList': ('kerne l-4.18.0-193.28.1.el8_2.x86_64', 'qemu-guest-agent-4.2.0'), 'guestIPs': '', 'guestFQDN': 'si-ovirt-manager.par-tec.it', 'netIfaces': [{'hw': '00:00:00:00:00:00', 'inet': ['127.0.0.1'], 'inet6': ['::1'], 'name': 'lo'}, {'hw': '00:16:3e:0a:24:11', 'inet': ['10.0.0.97'], 'inet6': ['fe80::216:3eff:fe0a:2411'], 'name': 'eth0'}], 'disksUsage': [{'path': '/', 'total': '7505707008', 'used': '3535548416', 'fs': 'xfs'}, {'path': '/home', 'total': '1063256064', 'used': '41316352', 'fs': 'xfs'}, {'path': '/boot', 'total': '1063256064', 'used': '261574656', 'fs': 'xfs'}, {'path': '/tmp', 'total': '2136997888', 'used': '48939008', 'fs': 'xfs'}, {'path': '/var', 'total': '21464350720', 'used': '968724480', 'fs': 'xfs'}, {'path': '/var/log', 'total': '10726932480', 'used': '159023104', 'fs': 'xfs'}, {'path': '/var/log/audit', 'total': '1063256064', 'used': '42029056', 'fs': 'xfs'}], 'guestName': 'si-ovirt-manager.par-tec.it', 'guestOs': '4.18.0-193.28.1.el8_2.x86_64', 'guestOsInfo': {'type': 'linux ', 'arch': 'x86_64', 'kernel': '4.18.0-193.28.1.el8_2.x86_64', 'distribution': 'CentOS Linux', 'version': '8', 'codename': ''}, 'guestTimezone': {'offset': 60, 'zone': 'CET'}}]} from=::1,57660, vmId=cfe08595-6ff9-4b4c-a48d-51530e42230c (api:54) 2020-11-18 18:06:29,692+0100 INFO (jsonrpc/5) [jsonrpc.JsonRpcServer] RPC call VM.getStats succeeded in 0.00 seconds (__init__:312) 2020-11-18 18:06:30,098+0100 INFO (jsonrpc/6) [api.host] START getAllVmStats() from=::1,57668 (api:48) 2020-11-18 18:06:30,100+0100 INFO (jsonrpc/6) [api.host] FINISH getAllVmStats return={'status': {'code': 0, 'message': 'Done'}, 'statsList': (suppressed)} from=::1,57668 (api:54) 2020-11-18 18:06:30,101+0100 INFO (jsonrpc/6) [jsonrpc.JsonRpcServer] RPC call Host.getAllVmStats succeeded in 0.00 seconds (__init__:312) 2020-11-18 18:06:30,105+0100 INFO (jsonrpc/7) [api.host] START getAllVmIoTunePolicies() from=::1,57668 (api:48) 2020-11-18 18:06:30,105+0100 INFO (jsonrpc/7) [api.host] FINISH getAllVmIoTunePolicies return={'status': {'code': 0, 'message': 'Done'}, 'io_tune_policies_dict': {'cfe08595-6ff9-4b4c-a48d-51530e42230c': {'policy': [], 'current_values': [{'name': 'vda', 'path': '/var/run/vdsm/storage/33992e1c-5383-4e3d-b9d0-945491ee0ccd/a387fa74-fa0d-4027-9f3f-560d2c723309/5ffd3ebe-097a-48eb-adb0-d74d8b0e547d', 'ioTune': {'total_bytes_sec': 0, 'read_bytes_sec': 0, 'write_bytes_sec': 0, 'total_iops_sec': 0, 'write_iops_sec': 0, 'read_iops_sec': 0}}]}, '0a940b7e-5f9c-4e4b-b11c-0d3c1d811a83': {'policy': [], 'current_values': [{'name': 'sda', 'path': '/rhev/data-center/mnt/blockSD/dfeb1c22-4c0a-4bb4-9ef1-934962f18707/images/519b7c5a-dcf7-44b0-a36a-3cd64116d37c/5919b6b3-8e27-4733-9434-44e388d66bd3', 'ioTune': {'total_bytes_sec': 0, 'read_bytes_sec': 0, 'write_bytes_sec': 0, 'total_iops_sec': 0, 'write_iops_sec': 0, 'read_iops_sec': 0}}]}}} from=::1,57668 (api:54) 2020-11-18 18:06:30,106+0100 INFO (jsonrpc/7) [jsonrpc.JsonRpcServer] RPC call Host.getAllVmIoTunePolicies succeeded in 0.00 seconds (__init__:312) 2020-11-18 18:06:31,119+0100 INFO (jsonrpc/3) [api.virt] START create(vmParams={'vmId': 'ef2c32bf-6ee7-4892-ba63-9ae54ac349e6', 'xml': '<?xml version="1.0" encoding="UTF-8"?><domain type="kvm" xmlns:ovirt-tune="http://ovirt.org/vm/tune/1.0" xmlns:ovirt-vm="http://ovirt.org/vm/1.0" xmlns:qemu="http://libvirt.org/schemas/domain/qemu/1.0"><name>si-test-migrazione</name><uuid>ef2c32bf-6ee7-4892-ba63-9ae54ac349e6</uuid><memory>2097152</memory><currentMemory>2097152</currentMemory><maxMemory slots="16">8388608</maxMemory><vcpu current="1">16</vcpu><sysinfo type="smbios"><system><entry name="manufacturer">oVirt</entry><entry name="product">OS-NAME:</entry><entry name="version">OS-VERSION:</entry><entry name="family">oVirt</entry><entry name="serial">HOST-SERIAL:</entry><entry name="uuid">ef2c32bf-6ee7-4892-ba63-9ae54ac349e6</entry></system></sysinfo><clock offset="variable" adjustment="0"><timer name="rtc" tickpolicy="catchup"></timer><timer name="pit" tickpolicy="delay"></timer><timer nam e="hpet" present="no"></timer></clock><features><acpi></acpi></features><cpu match="exact"><model>Westmere</model><topology cores="1" threads="1" sockets="16"></topology><numa><cell id="0" cpus="0-15" memory="2097152"></cell></numa></cpu><cputune></cputune><qemu:capabilities><qemu:add capability="blockdev"></qemu:add><qemu:add capability="incremental-backup"></qemu:add></qemu:capabilities><devices><input type="mouse" bus="ps2"></input><channel type="unix"><target type="virtio" name="ovirt-guest-agent.0"></target><source mode="bind" path="/var/lib/libvirt/qemu/channels/ef2c32bf-6ee7-4892-ba63-9ae54ac349e6.ovirt-guest-agent.0"></source></channel><channel type="unix"><target type="virtio" name="org.qemu.guest_agent.0"></target><source mode="bind" path="/var/lib/libvirt/qemu/channels/ef2c32bf-6ee7-4892-ba63-9ae54ac349e6.org.qemu.guest_agent.0"></source></channel><graphics type="spice" port="-1" autoport="yes" passwd="*****" passwdValidTo="1970-01-01T00:00:01" tlsPort="-1"><channel name= "main" mode="secure"></channel><channel name="inputs" mode="secure"></channel><channel name="cursor" mode="secure"></channel><channel name="playback" mode="secure"></channel><channel name="record" mode="secure"></channel><channel name="display" mode="secure"></channel><channel name="smartcard" mode="secure"></channel><channel name="usbredir" mode="secure"></channel><listen type="network" network="vdsm-ovirtmgmt"></listen></graphics><video><model type="qxl" vram="8192" heads="1" ram="65536" vgamem="16384"></model><alias name="ua-4c733dd3-3dfc-4540-be8c-a346f1ea533c"></alias><address bus="0x00" domain="0x0000" function="0x0" slot="0x02" type="pci"></address></video><memballoon model="virtio"><stats period="5"></stats><alias name="ua-61678f48-9af9-4723-aed9-4d876c84f54a"></alias><address bus="0x00" domain="0x0000" function="0x0" slot="0x06" type="pci"></address></memballoon><controller type="scsi" model="virtio-scsi" index="0"><alias name="ua-72c29d7b-4090-40ae-8f19-5b7abc03d7e2"></ali as><address bus="0x00" domain="0x0000" function="0x0" slot="0x04" type="pci"></address></controller><controller type="usb" model="qemu-xhci" index="0" ports="8"><alias name="ua-7cea086c-6a70-4803-8036-ebabf11b7d86"></alias></controller><rng model="virtio"><backend model="random">/dev/urandom</backend><alias name="ua-8beaed6a-fafc-4e90-b363-519b68fdb1c8"></alias></rng><controller type="virtio-serial" index="0" ports="16"><alias name="ua-b21852aa-1be4-4beb-bd27-2ac3a1424f32"></alias><address bus="0x00" domain="0x0000" function="0x0" slot="0x05" type="pci"></address></controller><channel type="spicevmc"><target type="virtio" name="com.redhat.spice.0"></target></channel><controller type="pci" model="pcie-root"></controller><controller type="pci" model="pcie-root-port"></controller><controller type="pci" model="pcie-root-port"></controller><controller type="pci" model="pcie-root-port"></controller><controller type="pci" model="pcie-root-port"></controller><controller type="pci" model="pc ie-root-port"></controller><controller type="pci" model="pcie-root-port"></controller><controller type="pci" model="pcie-root-port"></controller><controller type="pci" model="pcie-root-port"></controller><controller type="pci" model="pcie-root-port"></controller><controller type="pci" model="pcie-root-port"></controller><controller type="pci" model="pcie-root-port"></controller><controller type="pci" model="pcie-root-port"></controller><controller type="pci" model="pcie-root-port"></controller><controller type="pci" model="pcie-root-port"></controller><controller type="pci" model="pcie-root-port"></controller><controller type="pci" model="pcie-root-port"></controller><interface type="bridge"><model type="virtio"></model><link state="up"></link><source bridge="user"></source><alias name="ua-24311bf2-f1d0-45da-bca0-c5d811f46fc2"></alias><address bus="0x00" domain="0x0000" function="0x0" slot="0x03" type="pci"></address><mac address="00:1a:4a:16:01:51"></mac><mtu size="1500"></mtu><fil terref filter="vdsm-no-mac-spoofing"></filterref><bandwidth></bandwidth></interface><disk type="file" device="cdrom" snapshot="no"><driver name="qemu" type="raw" error_policy="report"></driver><source file="" startupPolicy="optional"><seclabel model="dac" type="none" relabel="no"></seclabel></source><target dev="sdc" bus="sata"></target><readonly></readonly><alias name="ua-e475a8a7-45f7-4686-b7e8-9a70ffcc7b5f"></alias><address bus="1" controller="0" unit="0" type="drive" target="0"></address><boot order="2"></boot></disk><disk snapshot="no" type="block" device="disk"><target dev="sda" bus="scsi"></target><source dev="/rhev/data-center/mnt/blockSD/dfeb1c22-4c0a-4bb4-9ef1-934962f18707/images/eec9da44-7b03-4a35-a7e1-4b9387608402/283a6b72-6983-42c2-a060-982aac095dd5"><seclabel model="dac" type="none" relabel="no"></seclabel></source><driver name="qemu" io="native" type="qcow2" error_policy="stop" cache="none"></driver><alias name="ua-eec9da44-7b03-4a35-a7e1-4b9387608402"></alias><addres s bus="0" controller="0" unit="0" type="drive" target="0"></address><boot order="1"></boot><serial>eec9da44-7b03-4a35-a7e1-4b9387608402</serial></disk></devices><pm><suspend-to-disk enabled="no"></suspend-to-disk><suspend-to-mem enabled="no"></suspend-to-mem></pm><os><type arch="x86_64" machine="pc-q35-rhel8.1.0">hvm</type><smbios mode="sysinfo"></smbios><bootmenu enable="yes" timeout="30000"></bootmenu></os><metadata><ovirt-tune:qos></ovirt-tune:qos><ovirt-vm:vm><ovirt-vm:minGuaranteedMemoryMb type="int">2048</ovirt-vm:minGuaranteedMemoryMb><ovirt-vm:clusterVersion>4.4</ovirt-vm:clusterVersion><ovirt-vm:custom></ovirt-vm:custom><ovirt-vm:device mac_address="00:1a:4a:16:01:51"><ovirt-vm:custom></ovirt-vm:custom></ovirt-vm:device><ovirt-vm:device devtype="disk" name="sda"><ovirt-vm:poolID>2678c340-2299-11eb-bb74-00163e0a2411</ovirt-vm:poolID><ovirt-vm:volumeID>283a6b72-6983-42c2-a060-982aac095dd5</ovirt-vm:volumeID><ovirt-vm:imageID>eec9da44-7b03-4a35-a7e1-4b9387608402</ovirt-vm:imag eID><ovirt-vm:domainID>dfeb1c22-4c0a-4bb4-9ef1-934962f18707</ovirt-vm:domainID></ovirt-vm:device><ovirt-vm:launchPaused>false</ovirt-vm:launchPaused><ovirt-vm:resumeBehavior>auto_resume</ovirt-vm:resumeBehavior></ovirt-vm:vm></metadata></domain>'}) from=::ffff:10.0.0.97,51960, flow_id=bc3ce69b-8a52-4d97-9f3a-8bf289a8df75, vmId=ef2c32bf-6ee7-4892-ba63-9ae54ac349e6 (api:48) 2020-11-18 18:06:31,126+0100 INFO (jsonrpc/3) [virt.vm] (vmId='ef2c32bf-6ee7-4892-ba63-9ae54ac349e6') using a computed convergence schedule for a legacy migration: {'init': [{'params': ['101'], 'name': 'setDowntime'}], 'stalling': [{'action': {'params': ['104'], 'name': 'setDowntime'}, 'limit': 1}, {'action': {'params': ['120'], 'name': 'setDowntime'}, 'limit': 2}, {'action': {'params': ['189'], 'name': 'setDowntime'}, 'limit': 3}, {'action': {'params': ['500'], 'name': 'setDowntime'}, 'limit': 4}, {'action': {'params': ['500'], 'name': 'setDowntime'}, 'limit': 42}, {'action': {'params': [], 'name': 'abort'}, 'limit': -1}]} (migration:159) 2020-11-18 18:06:31,127+0100 INFO (vm/ef2c32bf) [virt.vm] (vmId='ef2c32bf-6ee7-4892-ba63-9ae54ac349e6') VM wrapper has started (vm:2504) 2020-11-18 18:06:31,128+0100 INFO (jsonrpc/3) [api.virt] FINISH create return={'vmList': {'vmId': 'ef2c32bf-6ee7-4892-ba63-9ae54ac349e6', 'status': 'WaitForLaunch', 'statusTime': '2930035425', 'xml': '<?xml version="1.0" encoding="UTF-8"?><domain type="kvm" xmlns:ovirt-tune="http://ovirt.org/vm/tune/1.0" xmlns:ovirt-vm="http://ovirt.org/vm/1.0" xmlns:qemu="http://libvirt.org/schemas/domain/qemu/1.0"><name>si-test-migrazione</name><uuid>ef2c32bf-6ee7-4892-ba63-9ae54ac349e6</uuid><memory>2097152</memory><currentMemory>2097152</currentMemory><maxMemory slots="16">8388608</maxMemory><vcpu current="1">16</vcpu><sysinfo type="smbios"><system><entry name="manufacturer">oVirt</entry><entry name="product">OS-NAME:</entry><entry name="version">OS-VERSION:</entry><entry name="family">oVirt</entry><entry name="serial">HOST-SERIAL:</entry><entry name="uuid">ef2c32bf-6ee7-4892-ba63-9ae54ac349e6</entry></system></sysinfo><clock offset="variable" adjustment="0"><timer name="rtc" tickpolicy="catchup "></timer><timer name="pit" tickpolicy="delay"></timer><timer name="hpet" present="no"></timer></clock><features><acpi></acpi></features><cpu match="exact"><model>Westmere</model><topology cores="1" threads="1" sockets="16"></topology><numa><cell id="0" cpus="0-15" memory="2097152"></cell></numa></cpu><cputune></cputune><qemu:capabilities><qemu:add capability="blockdev"></qemu:add><qemu:add capability="incremental-backup"></qemu:add></qemu:capabilities><devices><input type="mouse" bus="ps2"></input><channel type="unix"><target type="virtio" name="ovirt-guest-agent.0"></target><source mode="bind" path="/var/lib/libvirt/qemu/channels/ef2c32bf-6ee7-4892-ba63-9ae54ac349e6.ovirt-guest-agent.0"></source></channel><channel type="unix"><target type="virtio" name="org.qemu.guest_agent.0"></target><source mode="bind" path="/var/lib/libvirt/qemu/channels/ef2c32bf-6ee7-4892-ba63-9ae54ac349e6.org.qemu.guest_agent.0"></source></channel><graphics type="spice" port="-1" autoport="yes" passwd="***** " passwdValidTo="1970-01-01T00:00:01" tlsPort="-1"><channel name="main" mode="secure"></channel><channel name="inputs" mode="secure"></channel><channel name="cursor" mode="secure"></channel><channel name="playback" mode="secure"></channel><channel name="record" mode="secure"></channel><channel name="display" mode="secure"></channel><channel name="smartcard" mode="secure"></channel><channel name="usbredir" mode="secure"></channel><listen type="network" network="vdsm-ovirtmgmt"></listen></graphics><video><model type="qxl" vram="8192" heads="1" ram="65536" vgamem="16384"></model><alias name="ua-4c733dd3-3dfc-4540-be8c-a346f1ea533c"></alias><address bus="0x00" domain="0x0000" function="0x0" slot="0x02" type="pci"></address></video><memballoon model="virtio"><stats period="5"></stats><alias name="ua-61678f48-9af9-4723-aed9-4d876c84f54a"></alias><address bus="0x00" domain="0x0000" function="0x0" slot="0x06" type="pci"></address></memballoon><controller type="scsi" model="virtio-scsi" inde x="0"><alias name="ua-72c29d7b-4090-40ae-8f19-5b7abc03d7e2"></alias><address bus="0x00" domain="0x0000" function="0x0" slot="0x04" type="pci"></address></controller><controller type="usb" model="qemu-xhci" index="0" ports="8"><alias name="ua-7cea086c-6a70-4803-8036-ebabf11b7d86"></alias></controller><rng model="virtio"><backend model="random">/dev/urandom</backend><alias name="ua-8beaed6a-fafc-4e90-b363-519b68fdb1c8"></alias></rng><controller type="virtio-serial" index="0" ports="16"><alias name="ua-b21852aa-1be4-4beb-bd27-2ac3a1424f32"></alias><address bus="0x00" domain="0x0000" function="0x0" slot="0x05" type="pci"></address></controller><channel type="spicevmc"><target type="virtio" name="com.redhat.spice.0"></target></channel><controller type="pci" model="pcie-root"></controller><controller type="pci" model="pcie-root-port"></controller><controller type="pci" model="pcie-root-port"></controller><controller type="pci" model="pcie-root-port"></controller><controller type="pci" mod el="pcie-root-port"></controller><controller type="pci" model="pcie-root-port"></controller><controller type="pci" model="pcie-root-port"></controller><controller type="pci" model="pcie-root-port"></controller><controller type="pci" model="pcie-root-port"></controller><controller type="pci" model="pcie-root-port"></controller><controller type="pci" model="pcie-root-port"></controller><controller type="pci" model="pcie-root-port"></controller><controller type="pci" model="pcie-root-port"></controller><controller type="pci" model="pcie-root-port"></controller><controller type="pci" model="pcie-root-port"></controller><controller type="pci" model="pcie-root-port"></controller><controller type="pci" model="pcie-root-port"></controller><interface type="bridge"><model type="virtio"></model><link state="up"></link><source bridge="user"></source><alias name="ua-24311bf2-f1d0-45da-bca0-c5d811f46fc2"></alias><address bus="0x00" domain="0x0000" function="0x0" slot="0x03" type="pci"></address>< mac address="00:1a:4a:16:01:51"></mac><mtu size="1500"></mtu><filterref filter="vdsm-no-mac-spoofing"></filterref><bandwidth></bandwidth></interface><disk type="file" device="cdrom" snapshot="no"><driver name="qemu" type="raw" error_policy="report"></driver><source file="" startupPolicy="optional"><seclabel model="dac" type="none" relabel="no"></seclabel></source><target dev="sdc" bus="sata"></target><readonly></readonly><alias name="ua-e475a8a7-45f7-4686-b7e8-9a70ffcc7b5f"></alias><address bus="1" controller="0" unit="0" type="drive" target="0"></address><boot order="2"></boot></disk><disk snapshot="no" type="block" device="disk"><target dev="sda" bus="scsi"></target><source dev="/rhev/data-center/mnt/blockSD/dfeb1c22-4c0a-4bb4-9ef1-934962f18707/images/eec9da44-7b03-4a35-a7e1-4b9387608402/283a6b72-6983-42c2-a060-982aac095dd5"><seclabel model="dac" type="none" relabel="no"></seclabel></source><driver name="qemu" io="native" type="qcow2" error_policy="stop" cache="none"></driver><ali as name="ua-eec9da44-7b03-4a35-a7e1-4b9387608402"></alias><address bus="0" controller="0" unit="0" type="drive" target="0"></address><boot order="1"></boot><serial>eec9da44-7b03-4a35-a7e1-4b9387608402</serial></disk></devices><pm><suspend-to-disk enabled="no"></suspend-to-disk><suspend-to-mem enabled="no"></suspend-to-mem></pm><os><type arch="x86_64" machine="pc-q35-rhel8.1.0">hvm</type><smbios mode="sysinfo"></smbios><bootmenu enable="yes" timeout="30000"></bootmenu></os><metadata><ovirt-tune:qos></ovirt-tune:qos><ovirt-vm:vm><ovirt-vm:minGuaranteedMemoryMb type="int">2048</ovirt-vm:minGuaranteedMemoryMb><ovirt-vm:clusterVersion>4.4</ovirt-vm:clusterVersion><ovirt-vm:custom></ovirt-vm:custom><ovirt-vm:device mac_address="00:1a:4a:16:01:51"><ovirt-vm:custom></ovirt-vm:custom></ovirt-vm:device><ovirt-vm:device devtype="disk" name="sda"><ovirt-vm:poolID>2678c340-2299-11eb-bb74-00163e0a2411</ovirt-vm:poolID><ovirt-vm:volumeID>283a6b72-6983-42c2-a060-982aac095dd5</ovirt-vm:volumeID><ovi rt-vm:imageID>eec9da44-7b03-4a35-a7e1-4b9387608402</ovirt-vm:imageID><ovirt-vm:domainID>dfeb1c22-4c0a-4bb4-9ef1-934962f18707</ovirt-vm:domainID></ovirt-vm:device><ovirt-vm:launchPaused>false</ovirt-vm:launchPaused><ovirt-vm:resumeBehavior>auto_resume</ovirt-vm:resumeBehavior></ovirt-vm:vm></metadata></domain>'}, 'status': {'code': 0, 'message': 'Done'}} from=::ffff:10.0.0.97,51960, flow_id=bc3ce69b-8a52-4d97-9f3a-8bf289a8df75, vmId=ef2c32bf-6ee7-4892-ba63-9ae54ac349e6 (api:54) 2020-11-18 18:06:31,128+0100 INFO (jsonrpc/3) [jsonrpc.JsonRpcServer] RPC call VM.create succeeded in 0.01 seconds (__init__:312) 2020-11-18 18:06:31,174+0100 INFO (jsonrpc/2) [jsonrpc.JsonRpcServer] RPC call Host.ping2 succeeded in 0.00 seconds (__init__:312) 2020-11-18 18:06:31,189+0100 INFO (vm/ef2c32bf) [vdsm.api] START getVolumeSize(sdUUID='dfeb1c22-4c0a-4bb4-9ef1-934962f18707', spUUID='2678c340-2299-11eb-bb74-00163e0a2411', imgUUID='eec9da44-7b03-4a35-a7e1-4b9387608402', volUUID='283a6b72-6983-42c2-a060-982aac095dd5', options=None) from=internal, task_id=63e2135a-a953-4e7e-9421-7b013e48caf1 (api:48) 2020-11-18 18:06:31,240+0100 INFO (jsonrpc/0) [jsonrpc.JsonRpcServer] RPC call Host.ping2 succeeded in 0.00 seconds (__init__:312) 2020-11-18 18:06:31,242+0100 INFO (jsonrpc/4) [vdsm.api] START repoStats(domains=['33992e1c-5383-4e3d-b9d0-945491ee0ccd']) from=::1,57660, task_id=2039058a-a371-4ead-a2a6-4f83e565e818 (api:48) 2020-11-18 18:06:31,243+0100 INFO (jsonrpc/4) [vdsm.api] FINISH repoStats return={'33992e1c-5383-4e3d-b9d0-945491ee0ccd': {'code': 0, 'lastCheck': '3.0', 'delay': '0.00058048', 'valid': True, 'version': 5, 'acquired': True, 'actual': True}} from=::1,57660, task_id=2039058a-a371-4ead-a2a6-4f83e565e818 (api:54) 2020-11-18 18:06:31,243+0100 INFO (jsonrpc/4) [jsonrpc.JsonRpcServer] RPC call Host.getStorageRepoStats succeeded in 0.00 seconds (__init__:312) 2020-11-18 18:06:31,317+0100 INFO (vm/ef2c32bf) [vdsm.api] FINISH getVolumeSize return={'apparentsize': '4160749568', 'truesize': '4160749568'} from=internal, task_id=63e2135a-a953-4e7e-9421-7b013e48caf1 (api:54) 2020-11-18 18:06:31,317+0100 INFO (vm/ef2c32bf) [vds] prepared volume path: (clientIF:512) 2020-11-18 18:06:31,318+0100 INFO (vm/ef2c32bf) [vdsm.api] START prepareImage(sdUUID='dfeb1c22-4c0a-4bb4-9ef1-934962f18707', spUUID='2678c340-2299-11eb-bb74-00163e0a2411', imgUUID='eec9da44-7b03-4a35-a7e1-4b9387608402', leafUUID='283a6b72-6983-42c2-a060-982aac095dd5', allowIllegal=False) from=internal, task_id=cc6457be-4e5c-4298-addb-1f2a432cf22e (api:48) 2020-11-18 18:06:31,431+0100 INFO (jsonrpc/1) [jsonrpc.JsonRpcServer] RPC call Host.ping2 succeeded in 0.00 seconds (__init__:312) 2020-11-18 18:06:31,434+0100 INFO (jsonrpc/5) [api.host] START getStats() from=::1,57660 (api:48) 2020-11-18 18:06:31,495+0100 INFO (jsonrpc/5) [vdsm.api] START repoStats(domains=()) from=::1,57660, task_id=717c328d-a115-4787-be48-b51b287ccfbe (api:48) 2020-11-18 18:06:31,495+0100 INFO (jsonrpc/5) [vdsm.api] FINISH repoStats return={'33992e1c-5383-4e3d-b9d0-945491ee0ccd': {'code': 0, 'lastCheck': '3.2', 'delay': '0.00058048', 'valid': True, 'version': 5, 'acquired': True, 'actual': True}, 'dfeb1c22-4c0a-4bb4-9ef1-934962f18707': {'code': 0, 'lastCheck': '0.6', 'delay': '0.000575857', 'valid': True, 'version': 5, 'acquired': True, 'actual': True}, '2974d4ff-27c5-4a25-b956-0ff4ff426cd3': {'code': 0, 'lastCheck': '1.9', 'delay': '0.000337233', 'valid': True, 'version': 0, 'acquired': True, 'actual': True}, '3b532b12-5151-4626-883a-26f2a82d4f9e': {'code': 0, 'lastCheck': '1.0', 'delay': '0.000373066', 'valid': True, 'version': 0, 'acquired': True, 'actual': True}} from=::1,57660, task_id=717c328d-a115-4787-be48-b51b287ccfbe (api:54) 2020-11-18 18:06:31,496+0100 INFO (jsonrpc/5) [vdsm.api] START multipath_health() from=::1,57660, task_id=f26f0a21-db01-40f5-923b-4267128000c3 (api:48) 2020-11-18 18:06:31,496+0100 INFO (jsonrpc/5) [vdsm.api] FINISH multipath_health return={} from=::1,57660, task_id=f26f0a21-db01-40f5-923b-4267128000c3 (api:54) 2020-11-18 18:06:31,528+0100 INFO (jsonrpc/5) [api.host] FINISH getStats return={'status': {'code': 0, 'message': 'Done'}, 'info': (suppressed)} from=::1,57660 (api:54) 2020-11-18 18:06:31,532+0100 INFO (jsonrpc/5) [jsonrpc.JsonRpcServer] RPC call Host.getStats succeeded in 0.10 seconds (__init__:312) 2020-11-18 18:06:31,561+0100 INFO (vm/ef2c32bf) [storage.LVM] Activating lvs: vg=dfeb1c22-4c0a-4bb4-9ef1-934962f18707 lvs=['283a6b72-6983-42c2-a060-982aac095dd5'] (lvm:1724) 2020-11-18 18:06:31,813+0100 INFO (vm/ef2c32bf) [storage.StorageDomain] Creating image run directory '/var/run/vdsm/storage/dfeb1c22-4c0a-4bb4-9ef1-934962f18707/eec9da44-7b03-4a35-a7e1-4b9387608402' (blockSD:1347) 2020-11-18 18:06:31,814+0100 INFO (vm/ef2c32bf) [storage.fileUtils] Creating directory: /var/run/vdsm/storage/dfeb1c22-4c0a-4bb4-9ef1-934962f18707/eec9da44-7b03-4a35-a7e1-4b9387608402 mode: None (fileUtils:198) 2020-11-18 18:06:31,815+0100 INFO (vm/ef2c32bf) [storage.StorageDomain] Creating symlink from /dev/dfeb1c22-4c0a-4bb4-9ef1-934962f18707/283a6b72-6983-42c2-a060-982aac095dd5 to /var/run/vdsm/storage/dfeb1c22-4c0a-4bb4-9ef1-934962f18707/eec9da44-7b03-4a35-a7e1-4b9387608402/283a6b72-6983-42c2-a060-982aac095dd5 (blockSD:1352) 2020-11-18 18:06:32,040+0100 INFO (vm/ef2c32bf) [storage.StorageDomain] Creating symlink from /var/run/vdsm/storage/dfeb1c22-4c0a-4bb4-9ef1-934962f18707/eec9da44-7b03-4a35-a7e1-4b9387608402 to /rhev/data-center/mnt/blockSD/dfeb1c22-4c0a-4bb4-9ef1-934962f18707/images/eec9da44-7b03-4a35-a7e1-4b9387608402 (blockSD:1317) 2020-11-18 18:06:32,041+0100 INFO (vm/ef2c32bf) [vdsm.api] FINISH prepareImage return={'path': '/rhev/data-center/mnt/blockSD/dfeb1c22-4c0a-4bb4-9ef1-934962f18707/images/eec9da44-7b03-4a35-a7e1-4b9387608402/283a6b72-6983-42c2-a060-982aac095dd5', 'info': {'type': 'block', 'path': '/rhev/data-center/mnt/blockSD/dfeb1c22-4c0a-4bb4-9ef1-934962f18707/images/eec9da44-7b03-4a35-a7e1-4b9387608402/283a6b72-6983-42c2-a060-982aac095dd5'}, 'imgVolumesInfo': [{'domainID': 'dfeb1c22-4c0a-4bb4-9ef1-934962f18707', 'imageID': 'eec9da44-7b03-4a35-a7e1-4b9387608402', 'volumeID': '283a6b72-6983-42c2-a060-982aac095dd5', 'path': '/rhev/data-center/mnt/blockSD/dfeb1c22-4c0a-4bb4-9ef1-934962f18707/images/eec9da44-7b03-4a35-a7e1-4b9387608402/283a6b72-6983-42c2-a060-982aac095dd5', 'leasePath': '/dev/dfeb1c22-4c0a-4bb4-9ef1-934962f18707/leases', 'leaseOffset': 109051904}]} from=internal, task_id=cc6457be-4e5c-4298-addb-1f2a432cf22e (api:54) 2020-11-18 18:06:32,042+0100 INFO (vm/ef2c32bf) [vds] prepared volume path: /rhev/data-center/mnt/blockSD/dfeb1c22-4c0a-4bb4-9ef1-934962f18707/images/eec9da44-7b03-4a35-a7e1-4b9387608402/283a6b72-6983-42c2-a060-982aac095dd5 (clientIF:512) 2020-11-18 18:06:32,043+0100 INFO (vm/ef2c32bf) [virt.vm] (vmId='ef2c32bf-6ee7-4892-ba63-9ae54ac349e6') Enabling drive monitoring (drivemonitor:56) 2020-11-18 18:06:32,068+0100 INFO (vm/ef2c32bf) [virt.vm] (vmId='ef2c32bf-6ee7-4892-ba63-9ae54ac349e6') drive 'sdc' path: 'file=' -> '*file=' (storagexml:337) 2020-11-18 18:06:32,068+0100 INFO (vm/ef2c32bf) [virt.vm] (vmId='ef2c32bf-6ee7-4892-ba63-9ae54ac349e6') drive 'sda' path: 'dev=/rhev/data-center/mnt/blockSD/dfeb1c22-4c0a-4bb4-9ef1-934962f18707/images/eec9da44-7b03-4a35-a7e1-4b9387608402/283a6b72-6983-42c2-a060-982aac095dd5' -> '*dev=/rhev/data-center/mnt/blockSD/dfeb1c22-4c0a-4bb4-9ef1-934962f18707/images/eec9da44-7b03-4a35-a7e1-4b9387608402/283a6b72-6983-42c2-a060-982aac095dd5' (storagexml:337) 2020-11-18 18:06:32,231+0100 INFO (vm/ef2c32bf) [root] /usr/libexec/vdsm/hooks/before_device_create/10_ovirt_provider_ovn_hook: rc=0 err=b'' (hooks:122) 2020-11-18 18:06:32,392+0100 INFO (vm/ef2c32bf) [root] /usr/libexec/vdsm/hooks/before_device_create/20_ovirt_provider_ovn_vhostuser_hook: rc=0 err=b'' (hooks:122) 2020-11-18 18:06:33,086+0100 INFO (vm/ef2c32bf) [root] /usr/libexec/vdsm/hooks/before_device_create/50_openstacknet: rc=0 err=b'' (hooks:122) 2020-11-18 18:06:33,282+0100 INFO (vm/ef2c32bf) [root] /usr/libexec/vdsm/hooks/before_device_create/50_vmfex: rc=0 err=b'' (hooks:122) 2020-11-18 18:06:33,950+0100 INFO (vm/ef2c32bf) [root] /usr/libexec/vdsm/hooks/before_device_create/openstacknet_utils.py: rc=0 err=b'' (hooks:122) 2020-11-18 18:06:34,143+0100 INFO (vm/ef2c32bf) [root] /usr/libexec/vdsm/hooks/before_vm_start/50_hostedengine: rc=0 err=b'' (hooks:122) 2020-11-18 18:06:34,311+0100 INFO (vm/ef2c32bf) [root] /usr/libexec/vdsm/hooks/before_vm_start/50_vhostmd: rc=0 err=b'' (hooks:122) 2020-11-18 18:06:34,315+0100 INFO (vm/ef2c32bf) [virt.vm] (vmId='ef2c32bf-6ee7-4892-ba63-9ae54ac349e6') <?xml version="1.0" encoding="utf-8"?><domain type="kvm" xmlns:ns0="http://libvirt.org/schemas/domain/qemu/1.0" xmlns:ns1="http://ovirt.org/vm/tune/1.0" xmlns:ovirt-vm="http://ovirt.org/vm/1.0"> 2020-11-18 18:06:34,919+0100 INFO (jsonrpc/6) [jsonrpc.JsonRpcServer] RPC call Host.ping2 succeeded in 0.00 seconds (__init__:312) 2020-11-18 18:06:36,189+0100 INFO (jsonrpc/7) [vdsm.api] START getSpmStatus(spUUID='2678c340-2299-11eb-bb74-00163e0a2411', options=None) from=::ffff:10.0.0.97,51960, task_id=6a6be04f-c942-4fae-9c38-4b06bef3423d (api:48) 2020-11-18 18:06:36,201+0100 INFO (jsonrpc/7) [vdsm.api] FINISH getSpmStatus return={'spm_st': {'spmStatus': 'SPM', 'spmLver': 3, 'spmId': 1}} from=::ffff:10.0.0.97,51960, task_id=6a6be04f-c942-4fae-9c38-4b06bef3423d (api:54) 2020-11-18 18:06:36,202+0100 INFO (jsonrpc/7) [jsonrpc.JsonRpcServer] RPC call StoragePool.getSpmStatus succeeded in 0.01 seconds (__init__:312) 2020-11-18 18:06:36,211+0100 INFO (jsonrpc/3) [vdsm.api] START getStoragePoolInfo(spUUID='2678c340-2299-11eb-bb74-00163e0a2411', options=None) from=::ffff:10.0.0.97,51966, task_id=d0f965d5-e8c9-47f6-a8e6-ba138c969041 (api:48) 2020-11-18 18:06:36,224+0100 INFO (jsonrpc/3) [vdsm.api] FINISH getStoragePoolInfo return={'info': {'domains': '2974d4ff-27c5-4a25-b956-0ff4ff426cd3:Active,33992e1c-5383-4e3d-b9d0-945491ee0ccd:Active,dfeb1c22-4c0a-4bb4-9ef1-934962f18707:Active,3b532b12-5151-4626-883a-26f2a82d4f9e:Active', 'isoprefix': '/rhev/data-center/mnt/si-nas.babel.it:_iso-domain/2974d4ff-27c5-4a25-b956-0ff4ff426cd3/images/11111111-1111-1111-1111-111111111111', 'lver': 3, 'master_uuid': '33992e1c-5383-4e3d-b9d0-945491ee0ccd', 'master_ver': 1, 'name': 'No Description', 'pool_status': 'connected', 'spm_id': 1, 'type': 'ISCSI', 'version': '5'}, 'dominfo': {'2974d4ff-27c5-4a25-b956-0ff4ff426cd3': {'status': 'Active', 'alerts': [], 'isoprefix': '/rhev/data-center/mnt/si-nas.babel.it:_iso-domain/2974d4ff-27c5-4a25-b956-0ff4ff426cd3/images/11111111-1111-1111-1111-111111111111', 'version': 0, 'disktotal': '211378241536', 'diskfree': '176700424192'}, '33992e1c-5383-4e3d-b9d0-945491ee0ccd': {'status': 'Active', 'alerts': [], 'isoprefix': '', 'version': 5, 'disktotal': '106971529216', 'diskfree': '12750684160'}, 'dfeb1c22-4c0a-4bb4-9ef1-934962f18707': {'status': 'Active', 'alerts': [], 'isoprefix': '', 'version': 5, 'disktotal': '751216623616', 'diskfree': '738734374912'}, '3b532b12-5151-4626-883a-26f2a82d4f9e': {'status': 'Active', 'alerts': [], 'isoprefix': '', 'version': 0, 'disktotal': '5827912007680', 'diskfree': '1412350607360'}}} from=::ffff:10.0.0.97,51966, task_id=d0f965d5-e8c9-47f6-a8e6-ba138c969041 (api:54) 2020-11-18 18:06:36,224+0100 INFO (jsonrpc/3) [jsonrpc.JsonRpcServer] RPC call StoragePool.getInfo succeeded in 0.01 seconds (__init__:312) 2020-11-18 18:06:36,440+0100 INFO (jsonrpc/2) [jsonrpc.JsonRpcServer] RPC call Host.ping2 succeeded in 0.00 seconds (__init__:312) 2020-11-18 18:06:36,442+0100 INFO (jsonrpc/0) [api.host] START getStats() from=::1,57660 (api:48) 2020-11-18 18:06:36,492+0100 ERROR (vm/ef2c32bf) [virt.vm] (vmId='ef2c32bf-6ee7-4892-ba63-9ae54ac349e6') The vm start process failed (vm:872) 2020-11-18 18:06:36,492+0100 INFO (vm/ef2c32bf) [virt.vm] (vmId='ef2c32bf-6ee7-4892-ba63-9ae54ac349e6') Changed state to Down: unsupported configuration: bus must be 0 for ide controller (code=1) (vm:1636) 2020-11-18 18:06:36,505+0100 INFO (jsonrpc/0) [vdsm.api] START repoStats(domains=()) from=::1,57660, task_id=4629e520-0e8b-4fcb-99ce-dd64fb532064 (api:48) 2020-11-18 18:06:36,506+0100 INFO (jsonrpc/0) [vdsm.api] FINISH repoStats return={'33992e1c-5383-4e3d-b9d0-945491ee0ccd': {'code': 0, 'lastCheck': '2.6', 'delay': '0.00058048', 'valid': True, 'version': 5, 'acquired': True, 'actual': True}, 'dfeb1c22-4c0a-4bb4-9ef1-934962f18707': {'code': 0, 'lastCheck': '0.1', 'delay': '0.00049727', 'valid': True, 'version': 5, 'acquired': True, 'actual': True}, '2974d4ff-27c5-4a25-b956-0ff4ff426cd3': {'code': 0, 'lastCheck': '4.0', 'delay': '0.000322774', 'valid': True, 'version': 0, 'acquired': True, 'actual': True}, '3b532b12-5151-4626-883a-26f2a82d4f9e': {'code': 0, 'lastCheck': '1.0', 'delay': '0.000333269', 'valid': True, 'version': 0, 'acquired': True, 'actual': True}} from=::1,57660, task_id=4629e520-0e8b-4fcb-99ce-dd64fb532064 (api:54) 2020-11-18 18:06:36,506+0100 INFO (jsonrpc/0) [vdsm.api] START multipath_health() from=::1,57660, task_id=124eab7d-d956-4f43-a3fa-23da5909ef42 (api:48) 2020-11-18 18:06:36,507+0100 INFO (jsonrpc/0) [vdsm.api] FINISH multipath_health return={} from=::1,57660, task_id=124eab7d-d956-4f43-a3fa-23da5909ef42 (api:54) 2020-11-18 18:06:36,519+0100 INFO (jsonrpc/0) [api.host] FINISH getStats return={'status': {'code': 0, 'message': 'Done'}, 'info': (suppressed)} from=::1,57660 (api:54) 2020-11-18 18:06:36,522+0100 INFO (jsonrpc/0) [jsonrpc.JsonRpcServer] RPC call Host.getStats succeeded in 0.08 seconds (__init__:312) 2020-11-18 18:06:36,523+0100 INFO (vm/ef2c32bf) [virt.vm] (vmId='ef2c32bf-6ee7-4892-ba63-9ae54ac349e6') Stopping connection (guestagent:444) 2020-11-18 18:06:36,531+0100 INFO (jsonrpc/4) [api.virt] START destroy(gracefulAttempts=1) from=::ffff:10.0.0.97,51960, vmId=ef2c32bf-6ee7-4892-ba63-9ae54ac349e6 (api:48) 2020-11-18 18:06:36,531+0100 INFO (jsonrpc/4) [virt.vm] (vmId='ef2c32bf-6ee7-4892-ba63-9ae54ac349e6') Release VM resources (vm:4628) 2020-11-18 18:06:36,531+0100 WARN (jsonrpc/4) [virt.vm] (vmId='ef2c32bf-6ee7-4892-ba63-9ae54ac349e6') trying to set state to Powering down when already Down (vm:618) 2020-11-18 18:06:36,531+0100 INFO (jsonrpc/4) [virt.vm] (vmId='ef2c32bf-6ee7-4892-ba63-9ae54ac349e6') Stopping connection (guestagent:444) 2020-11-18 18:06:36,532+0100 INFO (jsonrpc/4) [vdsm.api] START teardownImage(sdUUID='dfeb1c22-4c0a-4bb4-9ef1-934962f18707', spUUID='2678c340-2299-11eb-bb74-00163e0a2411', imgUUID='eec9da44-7b03-4a35-a7e1-4b9387608402', volUUID=None) from=::ffff:10.0.0.97,51960, task_id=8a128dd3-347c-45af-84d0-e2585eb8fc96 (api:48) 2020-11-18 18:06:36,532+0100 INFO (jsonrpc/4) [storage.StorageDomain] Removing image run directory '/var/run/vdsm/storage/dfeb1c22-4c0a-4bb4-9ef1-934962f18707/eec9da44-7b03-4a35-a7e1-4b9387608402' (blockSD:1371) 2020-11-18 18:06:36,532+0100 INFO (jsonrpc/4) [storage.fileUtils] Removing directory: /var/run/vdsm/storage/dfeb1c22-4c0a-4bb4-9ef1-934962f18707/eec9da44-7b03-4a35-a7e1-4b9387608402 (fileUtils:179) 2020-11-18 18:06:36,646+0100 INFO (jsonrpc/4) [storage.LVM] Deactivating lvs: vg=dfeb1c22-4c0a-4bb4-9ef1-934962f18707 lvs=['283a6b72-6983-42c2-a060-982aac095dd5'] (lvm:1732) 2020-11-18 18:06:36,771+0100 INFO (jsonrpc/1) [jsonrpc.JsonRpcServer] RPC call Host.ping2 succeeded in 0.00 seconds (__init__:312) 2020-11-18 18:06:36,774+0100 INFO (jsonrpc/4) [vdsm.api] FINISH teardownImage return=None from=::ffff:10.0.0.97,51960, task_id=8a128dd3-347c-45af-84d0-e2585eb8fc96 (api:54) 2020-11-18 18:06:36,774+0100 INFO (jsonrpc/4) [virt.vm] (vmId='ef2c32bf-6ee7-4892-ba63-9ae54ac349e6') Stopping connection (guestagent:444) 2020-11-18 18:06:36,775+0100 WARN (jsonrpc/4) [virt.vm] (vmId='ef2c32bf-6ee7-4892-ba63-9ae54ac349e6') timestamp already removed from stats cache (vm:2211) 2020-11-18 18:06:36,775+0100 INFO (jsonrpc/4) [vdsm.api] START inappropriateDevices(thiefId='ef2c32bf-6ee7-4892-ba63-9ae54ac349e6') from=::ffff:10.0.0.97,51960, task_id=9e7e2cd2-ea9c-46e9-9bb7-c9fbd9cdd063 (api:48) 2020-11-18 18:06:36,776+0100 INFO (jsonrpc/5) [vdsm.api] START repoStats(domains=['33992e1c-5383-4e3d-b9d0-945491ee0ccd']) from=::1,57660, task_id=ba65b049-6986-4697-bc07-f7b6d3cd4fb8 (api:48) 2020-11-18 18:06:36,776+0100 INFO (jsonrpc/5) [vdsm.api] FINISH repoStats return={'33992e1c-5383-4e3d-b9d0-945491ee0ccd': {'code': 0, 'lastCheck': '2.9', 'delay': '0.00058048', 'valid': True, 'version': 5, 'acquired': True, 'actual': True}} from=::1,57660, task_id=ba65b049-6986-4697-bc07-f7b6d3cd4fb8 (api:54) 2020-11-18 18:06:36,776+0100 INFO (jsonrpc/5) [jsonrpc.JsonRpcServer] RPC call Host.getStorageRepoStats succeeded in 0.00 seconds (__init__:312) 2020-11-18 18:06:36,779+0100 INFO (jsonrpc/4) [vdsm.api] FINISH inappropriateDevices return=None from=::ffff:10.0.0.97,51960, task_id=9e7e2cd2-ea9c-46e9-9bb7-c9fbd9cdd063 (api:54) 2020-11-18 18:06:37,027+0100 INFO (jsonrpc/4) [root] /usr/libexec/vdsm/hooks/after_vm_destroy/50_vhostmd: rc=0 err=b'' (hooks:122) 2020-11-18 18:06:37,196+0100 INFO (jsonrpc/4) [root] /usr/libexec/vdsm/hooks/after_vm_destroy/delete_vhostuserclient_hook: rc=0 err=b'' (hooks:122) 2020-11-18 18:06:37,197+0100 INFO (jsonrpc/4) [api.virt] FINISH destroy return={'status': {'code': 0, 'message': 'Machine destroyed'}} from=::ffff:10.0.0.97,51960, vmId=ef2c32bf-6ee7-4892-ba63-9ae54ac349e6 (api:54) 2020-11-18 18:06:37,197+0100 INFO (jsonrpc/4) [jsonrpc.JsonRpcServer] RPC call VM.destroy succeeded in 0.67 seconds (__init__:312) 2020-11-18 18:06:37,244+0100 INFO (periodic/3) [vdsm.api] START repoStats(domains=()) from=internal, task_id=145ee78a-6191-4b5a-baa7-114d76c91982 (api:48) 2020-11-18 18:06:37,245+0100 INFO (periodic/3) [vdsm.api] FINISH repoStats return={'33992e1c-5383-4e3d-b9d0-945491ee0ccd': {'code': 0, 'lastCheck': '3.4', 'delay': '0.00058048', 'valid': True, 'version': 5, 'acquired': True, 'actual': True}, 'dfeb1c22-4c0a-4bb4-9ef1-934962f18707': {'code': 0, 'lastCheck': '0.8', 'delay': '0.00049727', 'valid': True, 'version': 5, 'acquired': True, 'actual': True}, '2974d4ff-27c5-4a25-b956-0ff4ff426cd3': {'code': 0, 'lastCheck': '4.8', 'delay': '0.000322774', 'valid': True, 'version': 0, 'acquired': True, 'actual': True}, '3b532b12-5151-4626-883a-26f2a82d4f9e': {'code': 0, 'lastCheck': '1.8', 'delay': '0.000333269', 'valid': True, 'version': 0, 'acquired': True, 'actual': True}} from=internal, task_id=145ee78a-6191-4b5a-baa7-114d76c91982 (api:54) 2020-11-18 18:06:38,103+0100 INFO (jsonrpc/6) [api.host] START getAllVmStats() from=::ffff:10.0.0.97,51960 (api:48) 2020-11-18 18:06:38,106+0100 INFO (jsonrpc/6) [api.host] FINISH getAllVmStats return={'status': {'code': 0, 'message': 'Done'}, 'statsList': (suppressed)} from=::ffff:10.0.0.97,51960 (api:54) 2020-11-18 18:06:38,106+0100 INFO (jsonrpc/6) [jsonrpc.JsonRpcServer] RPC call Host.getAllVmStats succeeded in 0.00 seconds (__init__:312) 2020-11-18 18:06:38,531+0100 INFO (health) [health] LVM cache hit ratio: 98.67% (hits: 617983 misses: 8355) (health:131) 2020-11-18 18:06:38,869+0100 INFO (jsonrpc/7) [jsonrpc.JsonRpcServer] RPC call Host.ping2 succeeded in 0.00 seconds (__init__:312) 2020-11-18 18:06:38,872+0100 INFO (jsonrpc/3) [api.virt] START getStats() from=::1,57660, vmId=cfe08595-6ff9-4b4c-a48d-51530e42230c (api:48) 2020-11-18 18:06:38,873+0100 INFO (jsonrpc/3) [api.virt] FINISH getStats return={'status': {'code': 0, 'message': 'Done'}, 'statsList': [{'statusTime': '2930043169', 'status': 'Up', 'vmId': 'cfe08595-6ff9-4b4c-a48d-51530e42230c', 'vmName': 'HostedEngine', 'vmType': 'kvm', 'kvmEnable': 'true', 'acpiEnable': 'true', 'elapsedTime': '198854', 'monitorResponse': '0', 'clientIp': '', 'timeOffset': '0', 'pauseCode': 'NOERR', 'cpuUser': '5.39', 'cpuSys': '0.80', 'cpuUsage': '2272620000000', 'network': {'vnet0': {'macAddr': '00:16:3e:0a:24:11', 'name': 'vnet0', 'speed': '1000', 'state': 'unknown', 'rxErrors': '0', 'rxDropped': '0', 'txErrors': '0', 'txDropped': '0', 'rx': '326281599', 'tx': '79663923', 'sampleTime': 782559.522728283}}, 'disks': {'sdc': {'truesize': '0', 'apparentsize': '0', 'readLatency': '0', 'writeLatency': '0', 'flushLatency': '0', 'writtenBytes': '0', 'writeOps': '0', 'readOps': '24', 'readBytes': '500', 'readRate': '0.0', 'writeRate': '0.0'}, 'vda': {'truesize': '858993 45920', 'apparentsize': '85899345920', 'readLatency': '0', 'writeLatency': '3739009.296296296', 'flushLatency': '18780516.72', 'writtenBytes': '6459756032', 'writeOps': '480199', 'readOps': '32874', 'readBytes': '814649344', 'readRate': '0.0', 'writeRate': '20343.832566662484', 'imageID': 'a387fa74-fa0d-4027-9f3f-560d2c723309'}}, 'balloonInfo': {'balloon_max': '16777216', 'balloon_min': '1048576', 'balloon_cur': '16777216', 'balloon_target': '16777216'}, 'vcpuCount': '4', 'memoryStats': {'mem_total': '16241900', 'mem_unused': '9958840', 'mem_free': '10857648', 'swap_in': 0, 'swap_out': 0, 'majflt': 0, 'minflt': 334, 'pageflt': 334}, 'displayInfo': [{'type': 'vnc', 'port': '5900', 'tlsPort': '', 'ipAddress': '10.0.0.95'}, {'type': 'spice', 'port': '5901', 'tlsPort': '5902', 'ipAddress': '10.0.0.95'}], 'hash': '503429482678177929', 'vmJobs': {}, 'vcpuQuota': '-1', 'vcpuPeriod': 100000, 'username': 'root', 'session': 'Unknown', 'memUsage': '35', 'guestCPUCount': -1, 'appsList': ('kerne l-4.18.0-193.28.1.el8_2.x86_64', 'qemu-guest-agent-4.2.0'), 'guestIPs': '', 'guestFQDN': 'si-ovirt-manager.par-tec.it', 'netIfaces': [{'hw': '00:00:00:00:00:00', 'inet': ['127.0.0.1'], 'inet6': ['::1'], 'name': 'lo'}, {'hw': '00:16:3e:0a:24:11', 'inet': ['10.0.0.97'], 'inet6': ['fe80::216:3eff:fe0a:2411'], 'name': 'eth0'}], 'disksUsage': [{'path': '/', 'total': '7505707008', 'used': '3535548416', 'fs': 'xfs'}, {'path': '/home', 'total': '1063256064', 'used': '41316352', 'fs': 'xfs'}, {'path': '/boot', 'total': '1063256064', 'used': '261574656', 'fs': 'xfs'}, {'path': '/tmp', 'total': '2136997888', 'used': '48939008', 'fs': 'xfs'}, {'path': '/var', 'total': '21464350720', 'used': '968814592', 'fs': 'xfs'}, {'path': '/var/log', 'total': '10726932480', 'used': '159064064', 'fs': 'xfs'}, {'path': '/var/log/audit', 'total': '1063256064', 'used': '42029056', 'fs': 'xfs'}], 'guestName': 'si-ovirt-manager.par-tec.it', 'guestOs': '4.18.0-193.28.1.el8_2.x86_64', 'guestOsInfo': {'type': 'linux ', 'arch': 'x86_64', 'kernel': '4.18.0-193.28.1.el8_2.x86_64', 'distribution': 'CentOS Linux', 'version': '8', 'codename': ''}, 'guestTimezone': {'offset': 60, 'zone': 'CET'}}]} from=::1,57660, vmId=cfe08595-6ff9-4b4c-a48d-51530e42230c (api:54) 2020-11-18 18:06:38,873+0100 INFO (jsonrpc/3) [jsonrpc.JsonRpcServer] RPC call VM.getStats succeeded in 0.00 seconds (__init__:312) 2020-11-18 18:06:41,317+0100 INFO (jsonrpc/2) [jsonrpc.JsonRpcServer] RPC call Host.ping2 succeeded in 0.00 seconds (__init__:312) 2020-11-18 18:06:41,378+0100 INFO (jsonrpc/0) [jsonrpc.JsonRpcServer] RPC call Host.ping2 succeeded in 0.00 seconds (__init__:312) 2020-11-18 18:06:41,380+0100 INFO (jsonrpc/1) [vdsm.api] START repoStats(domains=['33992e1c-5383-4e3d-b9d0-945491ee0ccd']) from=::1,57660, task_id=825e9b63-a8a5-482d-a3fb-72c38872ca22 (api:48) 2020-11-18 18:06:41,381+0100 INFO (jsonrpc/1) [vdsm.api] FINISH repoStats return={'33992e1c-5383-4e3d-b9d0-945491ee0ccd': {'code': 0, 'lastCheck': '3.1', 'delay': '0.000385081', 'valid': True, 'version': 5, 'acquired': True, 'actual': True}} from=::1,57660, task_id=825e9b63-a8a5-482d-a3fb-72c38872ca22 (api:54) 2020-11-18 18:06:41,381+0100 INFO (jsonrpc/1) [jsonrpc.JsonRpcServer] RPC call Host.getStorageRepoStats succeeded in 0.00 seconds (__init__:312) 2020-11-18 18:06:41,548+0100 INFO (jsonrpc/5) [jsonrpc.JsonRpcServer] RPC call Host.ping2 succeeded in 0.00 seconds (__init__:312) 2020-11-18 18:06:41,550+0100 INFO (jsonrpc/4) [api.host] START getStats() from=::1,57660 (api:48) 2020-11-18 18:06:41,611+0100 INFO (jsonrpc/4) [vdsm.api] START repoStats(domains=()) from=::1,57660, task_id=968aeea3-d6b2-4a48-9782-488d11d20ab3 (api:48) 2020-11-18 18:06:41,612+0100 INFO (jsonrpc/4) [vdsm.api] FINISH repoStats return={'33992e1c-5383-4e3d-b9d0-945491ee0ccd': {'code': 0, 'lastCheck': '3.3', 'delay': '0.000385081', 'valid': True, 'version': 5, 'acquired': True, 'actual': True}, 'dfeb1c22-4c0a-4bb4-9ef1-934962f18707': {'code': 0, 'lastCheck': '0.7', 'delay': '0.00049727', 'valid': True, 'version': 5, 'acquired': True, 'actual': True}, '2974d4ff-27c5-4a25-b956-0ff4ff426cd3': {'code': 0, 'lastCheck': '2.0', 'delay': '0.000322774', 'valid': True, 'version': 0, 'acquired': True, 'actual': True}, '3b532b12-5151-4626-883a-26f2a82d4f9e': {'code': 0, 'lastCheck': '1.1', 'delay': '0.000333269', 'valid': True, 'version': 0, 'acquired': True, 'actual': True}} from=::1,57660, task_id=968aeea3-d6b2-4a48-9782-488d11d20ab3 (api:54) 2020-11-18 18:06:41,612+0100 INFO (jsonrpc/4) [vdsm.api] START multipath_health() from=::1,57660, task_id=07322ba2-1df9-4ad2-83dd-42a200982450 (api:48) 2020-11-18 18:06:41,613+0100 INFO (jsonrpc/4) [vdsm.api] FINISH multipath_health return={} from=::1,57660, task_id=07322ba2-1df9-4ad2-83dd-42a200982450 (api:54) 2020-11-18 18:06:41,625+0100 INFO (jsonrpc/4) [api.host] FINISH getStats return={'status': {'code': 0, 'message': 'Done'}, 'info': (suppressed)} from=::1,57660 (api:54) 2020-11-18 18:06:41,628+0100 INFO (jsonrpc/4) [jsonrpc.JsonRpcServer] RPC call Host.getStats succeeded in 0.08 seconds (__init__:312) 2020-11-18 18:06:41,697+0100 INFO (jsonrpc/6) [api.host] START getStats() from=::ffff:10.0.0.97,51960 (api:48) 2020-11-18 18:06:41,756+0100 INFO (jsonrpc/6) [vdsm.api] START repoStats(domains=()) from=::ffff:10.0.0.97,51960, task_id=55e192e3-9f29-4be5-8a50-a8e9bd17da1a (api:48) 2020-11-18 18:06:41,757+0100 INFO (jsonrpc/6) [vdsm.api] FINISH repoStats return={'33992e1c-5383-4e3d-b9d0-945491ee0ccd': {'code': 0, 'lastCheck': '3.5', 'delay': '0.000385081', 'valid': True, 'version': 5, 'acquired': True, 'actual': True}, 'dfeb1c22-4c0a-4bb4-9ef1-934962f18707': {'code': 0, 'lastCheck': '0.9', 'delay': '0.00049727', 'valid': True, 'version': 5, 'acquired': True, 'actual': True}, '2974d4ff-27c5-4a25-b956-0ff4ff426cd3': {'code': 0, 'lastCheck': '2.2', 'delay': '0.000322774', 'valid': True, 'version': 0, 'acquired': True, 'actual': True}, '3b532b12-5151-4626-883a-26f2a82d4f9e': {'code': 0, 'lastCheck': '1.3', 'delay': '0.000333269', 'valid': True, 'version': 0, 'acquired': True, 'actual': True}} from=::ffff:10.0.0.97,51960, task_id=55e192e3-9f29-4be5-8a50-a8e9bd17da1a (api:54) 2020-11-18 18:06:41,757+0100 INFO (jsonrpc/6) [vdsm.api] START multipath_health() from=::ffff:10.0.0.97,51960, task_id=91753036-5515-4e85-9d79-cca404190608 (api:48) 2020-11-18 18:06:41,758+0100 INFO (jsonrpc/6) [vdsm.api] FINISH multipath_health return={} from=::ffff:10.0.0.97,51960, task_id=91753036-5515-4e85-9d79-cca404190608 (api:54) 2020-11-18 18:06:41,767+0100 INFO (jsonrpc/6) [api.host] FINISH getStats return={'status': {'code': 0, 'message': 'Done'}, 'info': (suppressed)} from=::ffff:10.0.0.97,51960 (api:54) 2020-11-18 18:06:41,770+0100 INFO (jsonrpc/6) [jsonrpc.JsonRpcServer] RPC call Host.getStats succeeded in 0.07 seconds (__init__:312) 2020-11-18 18:06:45,122+0100 INFO (jsonrpc/7) [api.host] START getAllVmStats() from=::1,57668 (api:48) 2020-11-18 18:06:45,124+0100 INFO (jsonrpc/7) [api.host] FINISH getAllVmStats return={'status': {'code': 0, 'message': 'Done'}, 'statsList': (suppressed)} from=::1,57668 (api:54) 2020-11-18 18:06:45,125+0100 INFO (jsonrpc/7) [jsonrpc.JsonRpcServer] RPC call Host.getAllVmStats succeeded in 0.00 seconds (__init__:312) 2020-11-18 18:06:45,129+0100 INFO (jsonrpc/3) [api.host] START getAllVmIoTunePolicies() from=::1,57668 (api:48) 2020-11-18 18:06:45,130+0100 INFO (jsonrpc/3) [api.host] FINISH getAllVmIoTunePolicies return={'status': {'code': 0, 'message': 'Done'}, 'io_tune_policies_dict': {'cfe08595-6ff9-4b4c-a48d-51530e42230c': {'policy': [], 'current_values': [{'name': 'vda', 'path': '/var/run/vdsm/storage/33992e1c-5383-4e3d-b9d0-945491ee0ccd/a387fa74-fa0d-4027-9f3f-560d2c723309/5ffd3ebe-097a-48eb-adb0-d74d8b0e547d', 'ioTune': {'total_bytes_sec': 0, 'read_bytes_sec': 0, 'write_bytes_sec': 0, 'total_iops_sec': 0, 'write_iops_sec': 0, 'read_iops_sec': 0}}]}, '0a940b7e-5f9c-4e4b-b11c-0d3c1d811a83': {'policy': [], 'current_values': [{'name': 'sda', 'path': '/rhev/data-center/mnt/blockSD/dfeb1c22-4c0a-4bb4-9ef1-934962f18707/images/519b7c5a-dcf7-44b0-a36a-3cd64116d37c/5919b6b3-8e27-4733-9434-44e388d66bd3', 'ioTune': {'total_bytes_sec': 0, 'read_bytes_sec': 0, 'write_bytes_sec': 0, 'total_iops_sec': 0, 'write_iops_sec': 0, 'read_iops_sec': 0}}]}}} from=::1,57668 (api:54) 2020-11-18 18:06:45,130+0100 INFO (jsonrpc/3) [jsonrpc.JsonRpcServer] RPC call Host.getAllVmIoTunePolicies succeeded in 0.00 seconds (__init__:312) 2020-11-18 18:06:46,296+0100 INFO (jsonrpc/2) [vdsm.api] START getSpmStatus(spUUID='2678c340-2299-11eb-bb74-00163e0a2411', options=None) from=::ffff:10.0.0.97,51960, task_id=31fbfff6-cddf-4b01-a709-55e9fd5e4620 (api:48) 2020-11-18 18:06:46,308+0100 INFO (jsonrpc/2) [vdsm.api] FINISH getSpmStatus return={'spm_st': {'spmStatus': 'SPM', 'spmLver': 3, 'spmId': 1}} from=::ffff:10.0.0.97,51960, task_id=31fbfff6-cddf-4b01-a709-55e9fd5e4620 (api:54) 2020-11-18 18:06:46,309+0100 INFO (jsonrpc/2) [jsonrpc.JsonRpcServer] RPC call StoragePool.getSpmStatus succeeded in 0.01 seconds (__init__:312) 2020-11-18 18:06:46,319+0100 INFO (jsonrpc/0) [vdsm.api] START getStoragePoolInfo(spUUID='2678c340-2299-11eb-bb74-00163e0a2411', options=None) from=::ffff:10.0.0.97,51966, task_id=6f8ae45b-2359-4890-bb4f-77f1b67fe33b (api:48) 2020-11-18 18:06:46,334+0100 INFO (jsonrpc/0) [vdsm.api] FINISH getStoragePoolInfo return={'info': {'domains': '2974d4ff-27c5-4a25-b956-0ff4ff426cd3:Active,33992e1c-5383-4e3d-b9d0-945491ee0ccd:Active,dfeb1c22-4c0a-4bb4-9ef1-934962f18707:Active,3b532b12-5151-4626-883a-26f2a82d4f9e:Active', 'isoprefix': '/rhev/data-center/mnt/si-nas.babel.it:_iso-domain/2974d4ff-27c5-4a25-b956-0ff4ff426cd3/images/11111111-1111-1111-1111-111111111111', 'lver': 3, 'master_uuid': '33992e1c-5383-4e3d-b9d0-945491ee0ccd', 'master_ver': 1, 'name': 'No Description', 'pool_status': 'connected', 'spm_id': 1, 'type': 'ISCSI', 'version': '5'}, 'dominfo': {'2974d4ff-27c5-4a25-b956-0ff4ff426cd3': {'status': 'Active', 'alerts': [], 'isoprefix': '/rhev/data-center/mnt/si-nas.babel.it:_iso-domain/2974d4ff-27c5-4a25-b956-0ff4ff426cd3/images/11111111-1111-1111-1111-111111111111', 'version': 0, 'disktotal': '211378241536', 'diskfree': '176700424192'}, '33992e1c-5383-4e3d-b9d0-945491ee0ccd': {'status': 'Active', 'alerts': [], 'isoprefix': '', 'version': 5, 'disktotal': '106971529216', 'diskfree': '12750684160'}, 'dfeb1c22-4c0a-4bb4-9ef1-934962f18707': {'status': 'Active', 'alerts': [], 'isoprefix': '', 'version': 5, 'disktotal': '751216623616', 'diskfree': '738734374912'}, '3b532b12-5151-4626-883a-26f2a82d4f9e': {'status': 'Active', 'alerts': [], 'isoprefix': '', 'version': 0, 'disktotal': '5827912007680', 'diskfree': '1412350607360'}}} from=::ffff:10.0.0.97,51966, task_id=6f8ae45b-2359-4890-bb4f-77f1b67fe33b (api:54) 2020-11-18 18:06:46,335+0100 INFO (jsonrpc/0) [jsonrpc.JsonRpcServer] RPC call StoragePool.getInfo succeeded in 0.02 seconds (__init__:312) 2020-11-18 18:06:46,536+0100 INFO (jsonrpc/1) [jsonrpc.JsonRpcServer] RPC call Host.ping2 succeeded in 0.00 seconds (__init__:312) 2020-11-18 18:06:46,539+0100 INFO (jsonrpc/5) [api.host] START getStats() from=::1,57660 (api:48) 2020-11-18 18:06:46,600+0100 INFO (jsonrpc/5) [vdsm.api] START repoStats(domains=()) from=::1,57660, task_id=379d3b5c-bb55-4537-b587-f9960ee406f1 (api:48) 2020-11-18 18:06:46,601+0100 INFO (jsonrpc/5) [vdsm.api] FINISH repoStats return={'33992e1c-5383-4e3d-b9d0-945491ee0ccd': {'code': 0, 'lastCheck': '2.7', 'delay': '0.000385081', 'valid': True, 'version': 5, 'acquired': True, 'actual': True}, 'dfeb1c22-4c0a-4bb4-9ef1-934962f18707': {'code': 0, 'lastCheck': '0.2', 'delay': '0.000454734', 'valid': True, 'version': 5, 'acquired': True, 'actual': True}, '2974d4ff-27c5-4a25-b956-0ff4ff426cd3': {'code': 0, 'lastCheck': '4.1', 'delay': '0.000355208', 'valid': True, 'version': 0, 'acquired': True, 'actual': True}, '3b532b12-5151-4626-883a-26f2a82d4f9e': {'code': 0, 'lastCheck': '1.1', 'delay': '0.000390319', 'valid': True, 'version': 0, 'acquired': True, 'actual': True}} from=::1,57660, task_id=379d3b5c-bb55-4537-b587-f9960ee406f1 (api:54) 2020-11-18 18:06:46,602+0100 INFO (jsonrpc/5) [vdsm.api] START multipath_health() from=::1,57660, task_id=eb7c8167-629a-4425-aabe-61537e4f9a7a (api:48) 2020-11-18 18:06:46,602+0100 INFO (jsonrpc/5) [vdsm.api] FINISH multipath_health return={} from=::1,57660, task_id=eb7c8167-629a-4425-aabe-61537e4f9a7a (api:54) 2020-11-18 18:06:46,615+0100 INFO (jsonrpc/5) [api.host] FINISH getStats return={'status': {'code': 0, 'message': 'Done'}, 'info': (suppressed)} from=::1,57660 (api:54) 2020-11-18 18:06:46,617+0100 INFO (jsonrpc/5) [jsonrpc.JsonRpcServer] RPC call Host.getStats succeeded in 0.08 seconds (__init__:312) 2020-11-18 18:06:46,809+0100 INFO (jsonrpc/4) [jsonrpc.JsonRpcServer] RPC call Host.ping2 succeeded in 0.00 seconds (__init__:312) 2020-11-18 18:06:46,810+0100 INFO (jsonrpc/6) [vdsm.api] START repoStats(domains=['33992e1c-5383-4e3d-b9d0-945491ee0ccd']) from=::1,57660, task_id=48116abd-a726-4fdd-86ac-d30bda1f4667 (api:48) 2020-11-18 18:06:46,810+0100 INFO (jsonrpc/6) [vdsm.api] FINISH repoStats return={'33992e1c-5383-4e3d-b9d0-945491ee0ccd': {'code': 0, 'lastCheck': '2.9', 'delay': '0.000385081', 'valid': True, 'version': 5, 'acquired': True, 'actual': True}} from=::1,57660, task_id=48116abd-a726-4fdd-86ac-d30bda1f4667 (api:54) 2020-11-18 18:06:46,811+0100 INFO (jsonrpc/6) [jsonrpc.JsonRpcServer] RPC call Host.getStorageRepoStats succeeded in 0.00 seconds (__init__:312) 2020-11-18 18:06:49,053+0100 INFO (jsonrpc/7) [jsonrpc.JsonRpcServer] RPC call Host.ping2 succeeded in 0.00 seconds (__init__:312) 2020-11-18 18:06:49,055+0100 INFO (jsonrpc/3) [api.virt] START getStats() from=::1,57660, vmId=cfe08595-6ff9-4b4c-a48d-51530e42230c (api:48) 2020-11-18 18:06:49,057+0100 INFO (jsonrpc/3) [api.virt] FINISH getStats return={'status': {'code': 0, 'message': 'Done'}, 'statsList': [{'statusTime': '2930053353', 'status': 'Up', 'vmId': 'cfe08595-6ff9-4b4c-a48d-51530e42230c', 'vmName': 'HostedEngine', 'vmType': 'kvm', 'kvmEnable': 'true', 'acpiEnable': 'true', 'elapsedTime': '198865', 'monitorResponse': '0', 'clientIp': '', 'timeOffset': '0', 'pauseCode': 'NOERR', 'cpuUser': '11.02', 'cpuSys': '1.20', 'cpuUsage': '2272800000000', 'network': {'vnet0': {'macAddr': '00:16:3e:0a:24:11', 'name': 'vnet0', 'speed': '1000', 'state': 'unknown', 'rxErrors': '0', 'rxDropped': '0', 'txErrors': '0', 'txDropped': '0', 'rx': '326353882', 'tx': '79755184', 'sampleTime': 782569.706517403}}, 'disks': {'sdc': {'truesize': '0', 'apparentsize': '0', 'readLatency': '0', 'writeLatency': '0', 'flushLatency': '0', 'writtenBytes': '0', 'writeOps': '0', 'readOps': '24', 'readBytes': '500', 'readRate': '0.0', 'writeRate': '0.0'}, 'vda': {'truesize': '85899 345920', 'apparentsize': '85899345920', 'readLatency': '0', 'writeLatency': '1107020.358974359', 'flushLatency': '14701725.916666666', 'writtenBytes': '6460218880', 'writeOps': '480238', 'readOps': '32874', 'readBytes': '814649344', 'readRate': '0.0', 'writeRate': '30851.647378558595', 'imageID': 'a387fa74-fa0d-4027-9f3f-560d2c723309'}}, 'balloonInfo': {'balloon_max': '16777216', 'balloon_min': '1048576', 'balloon_cur': '16777216', 'balloon_target': '16777216'}, 'vcpuCount': '4', 'memoryStats': {'mem_total': '16241900', 'mem_unused': '9958140', 'mem_free': '10857004', 'swap_in': 0, 'swap_out': 0, 'majflt': 0, 'minflt': 49, 'pageflt': 49}, 'displayInfo': [{'type': 'vnc', 'port': '5900', 'tlsPort': '', 'ipAddress': '10.0.0.95'}, {'type': 'spice', 'port': '5901', 'tlsPort': '5902', 'ipAddress': '10.0.0.95'}], 'hash': '503429482678177929', 'vmJobs': {}, 'vcpuQuota': '-1', 'vcpuPeriod': 100000, 'username': 'root', 'session': 'Unknown', 'memUsage': '35', 'guestCPUCount': -1, 'appsList': ( 'kernel-4.18.0-193.28.1.el8_2.x86_64', 'qemu-guest-agent-4.2.0'), 'guestIPs': '', 'guestFQDN': 'si-ovirt-manager.par-tec.it', 'netIfaces': [{'hw': '00:00:00:00:00:00', 'inet': ['127.0.0.1'], 'inet6': ['::1'], 'name': 'lo'}, {'hw': '00:16:3e:0a:24:11', 'inet': ['10.0.0.97'], 'inet6': ['fe80::216:3eff:fe0a:2411'], 'name': 'eth0'}], 'disksUsage': [{'path': '/', 'total': '7505707008', 'used': '3535548416', 'fs': 'xfs'}, {'path': '/home', 'total': '1063256064', 'used': '41316352', 'fs': 'xfs'}, {'path': '/boot', 'total': '1063256064', 'used': '261574656', 'fs': 'xfs'}, {'path': '/tmp', 'total': '2136997888', 'used': '48939008', 'fs': 'xfs'}, {'path': '/var', 'total': '21464350720', 'used': '968814592', 'fs': 'xfs'}, {'path': '/var/log', 'total': '10726932480', 'used': '159064064', 'fs': 'xfs'}, {'path': '/var/log/audit', 'total': '1063256064', 'used': '42029056', 'fs': 'xfs'}], 'guestName': 'si-ovirt-manager.par-tec.it', 'guestOs': '4.18.0-193.28.1.el8_2.x86_64', 'guestOsInfo': {'type': 'linux', 'arch': 'x86_64', 'kernel': '4.18.0-193.28.1.el8_2.x86_64', 'distribution': 'CentOS Linux', 'version': '8', 'codename': ''}, 'guestTimezone': {'offset': 60, 'zone': 'CET'}}]} from=::1,57660, vmId=cfe08595-6ff9-4b4c-a48d-51530e42230c (api:54) 2020-11-18 18:06:49,057+0100 INFO (jsonrpc/3) [jsonrpc.JsonRpcServer] RPC call VM.getStats succeeded in 0.00 seconds (__init__:312) 2020-11-18 18:06:51,454+0100 INFO (jsonrpc/2) [jsonrpc.JsonRpcServer] RPC call Host.ping2 succeeded in 0.00 seconds (__init__:312) 2020-11-18 18:06:51,513+0100 INFO (jsonrpc/0) [jsonrpc.JsonRpcServer] RPC call Host.ping2 succeeded in 0.00 seconds (__init__:312) 2020-11-18 18:06:51,516+0100 INFO (jsonrpc/1) [vdsm.api] START repoStats(domains=['33992e1c-5383-4e3d-b9d0-945491ee0ccd']) from=::1,57660, task_id=6f4cd7b9-3a48-4009-8b66-e83c8af9c463 (api:48) 2020-11-18 18:06:51,516+0100 INFO (jsonrpc/1) [vdsm.api] FINISH repoStats return={'33992e1c-5383-4e3d-b9d0-945491ee0ccd': {'code': 0, 'lastCheck': '3.2', 'delay': '0.000625533', 'valid': True, 'version': 5, 'acquired': True, 'actual': True}} from=::1,57660, task_id=6f4cd7b9-3a48-4009-8b66-e83c8af9c463 (api:54) 2020-11-18 18:06:51,516+0100 INFO (jsonrpc/1) [jsonrpc.JsonRpcServer] RPC call Host.getStorageRepoStats succeeded in 0.00 seconds (__init__:312) 2020-11-18 18:06:51,642+0100 INFO (jsonrpc/5) [jsonrpc.JsonRpcServer] RPC call Host.ping2 succeeded in 0.00 seconds (__init__:312) 2020-11-18 18:06:51,644+0100 INFO (jsonrpc/4) [api.host] START getStats() from=::1,57660 (api:48) 2020-11-18 18:06:51,705+0100 INFO (jsonrpc/4) [vdsm.api] START repoStats(domains=()) from=::1,57660, task_id=91e2ca8d-bc8f-488f-9b06-b47ea47b47b9 (api:48) 2020-11-18 18:06:51,705+0100 INFO (jsonrpc/4) [vdsm.api] FINISH repoStats return={'33992e1c-5383-4e3d-b9d0-945491ee0ccd': {'code': 0, 'lastCheck': '3.4', 'delay': '0.000625533', 'valid': True, 'version': 5, 'acquired': True, 'actual': True}, 'dfeb1c22-4c0a-4bb4-9ef1-934962f18707': {'code': 0, 'lastCheck': '0.8', 'delay': '0.000454734', 'valid': True, 'version': 5, 'acquired': True, 'actual': True}, '2974d4ff-27c5-4a25-b956-0ff4ff426cd3': {'code': 0, 'lastCheck': '2.1', 'delay': '0.000355208', 'valid': True, 'version': 0, 'acquired': True, 'actual': True}, '3b532b12-5151-4626-883a-26f2a82d4f9e': {'code': 0, 'lastCheck': '1.2', 'delay': '0.000390319', 'valid': True, 'version': 0, 'acquired': True, 'actual': True}} from=::1,57660, task_id=91e2ca8d-bc8f-488f-9b06-b47ea47b47b9 (api:54) 2020-11-18 18:06:51,706+0100 INFO (jsonrpc/4) [vdsm.api] START multipath_health() from=::1,57660, task_id=61fe2b23-e02b-4162-a73c-ac30220d8237 (api:48) 2020-11-18 18:06:51,707+0100 INFO (jsonrpc/4) [vdsm.api] FINISH multipath_health return={} from=::1,57660, task_id=61fe2b23-e02b-4162-a73c-ac30220d8237 (api:54) 2020-11-18 18:06:51,718+0100 INFO (jsonrpc/4) [api.host] FINISH getStats return={'status': {'code': 0, 'message': 'Done'}, 'info': (suppressed)} from=::1,57660 (api:54) 2020-11-18 18:06:51,720+0100 INFO (jsonrpc/4) [jsonrpc.JsonRpcServer] RPC call Host.getStats succeeded in 0.08 seconds (__init__:312) 2020-11-18 18:06:52,324+0100 INFO (periodic/1) [vdsm.api] START repoStats(domains=()) from=internal, task_id=ccf2efe3-ae45-410c-bc89-aa30193521a3 (api:48) 2020-11-18 18:06:52,325+0100 INFO (periodic/1) [vdsm.api] FINISH repoStats return={'33992e1c-5383-4e3d-b9d0-945491ee0ccd': {'code': 0, 'lastCheck': '4.0', 'delay': '0.000625533', 'valid': True, 'version': 5, 'acquired': True, 'actual': True}, 'dfeb1c22-4c0a-4bb4-9ef1-934962f18707': {'code': 0, 'lastCheck': '1.4', 'delay': '0.000454734', 'valid': True, 'version': 5, 'acquired': True, 'actual': True}, '2974d4ff-27c5-4a25-b956-0ff4ff426cd3': {'code': 0, 'lastCheck': '2.7', 'delay': '0.000355208', 'valid': True, 'version': 0, 'acquired': True, 'actual': True}, '3b532b12-5151-4626-883a-26f2a82d4f9e': {'code': 0, 'lastCheck': '1.8', 'delay': '0.000390319', 'valid': True, 'version': 0, 'acquired': True, 'actual': True}} from=internal, task_id=ccf2efe3-ae45-410c-bc89-aa30193521a3 (api:54) 2020-11-18 18:06:53,215+0100 INFO (jsonrpc/6) [api.host] START getAllVmStats() from=::ffff:10.0.0.97,51960 (api:48) 2020-11-18 18:06:53,217+0100 INFO (jsonrpc/6) [api.host] FINISH getAllVmStats return={'status': {'code': 0, 'message': 'Done'}, 'statsList': (suppressed)} from=::ffff:10.0.0.97,51960 (api:54) 2020-11-18 18:06:53,218+0100 INFO (jsonrpc/6) [jsonrpc.JsonRpcServer] RPC call Host.getAllVmStats succeeded in 0.00 seconds (__init__:312) 2020-11-18 18:06:55,822+0100 INFO (jsonrpc/7) [jsonrpc.JsonRpcServer] RPC call Host.ping2 succeeded in 0.00 seconds (__init__:312) 2020-11-18 18:06:55,825+0100 INFO (jsonrpc/3) [vdsm.api] START repoStats(domains=['33992e1c-5383-4e3d-b9d0-945491ee0ccd']) from=::1,57660, task_id=da5aea7e-3851-426a-b369-dc0a6c89cb72 (api:48) 2020-11-18 18:06:55,825+0100 INFO (jsonrpc/3) [vdsm.api] FINISH repoStats return={'33992e1c-5383-4e3d-b9d0-945491ee0ccd': {'code': 0, 'lastCheck': '1.9', 'delay': '0.000625533', 'valid': True, 'version': 5, 'acquired': True, 'actual': True}} from=::1,57660, task_id=da5aea7e-3851-426a-b369-dc0a6c89cb72 (api:54) 2020-11-18 18:06:55,825+0100 INFO (jsonrpc/3) [jsonrpc.JsonRpcServer] RPC call Host.getStorageRepoStats succeeded in 0.00 seconds (__init__:312) 2020-11-18 18:06:56,489+0100 INFO (jsonrpc/2) [vdsm.api] START getSpmStatus(spUUID='2678c340-2299-11eb-bb74-00163e0a2411', options=None) from=::ffff:10.0.0.97,51960, task_id=bf94dd45-2934-4d55-ad0a-0036138ebaa8 (api:48) 2020-11-18 18:06:56,502+0100 INFO (jsonrpc/2) [vdsm.api] FINISH getSpmStatus return={'spm_st': {'spmStatus': 'SPM', 'spmLver': 3, 'spmId': 1}} from=::ffff:10.0.0.97,51960, task_id=bf94dd45-2934-4d55-ad0a-0036138ebaa8 (api:54) 2020-11-18 18:06:56,502+0100 INFO (jsonrpc/2) [jsonrpc.JsonRpcServer] RPC call StoragePool.getSpmStatus succeeded in 0.01 seconds (__init__:312) 2020-11-18 18:06:56,510+0100 INFO (jsonrpc/0) [vdsm.api] START getStoragePoolInfo(spUUID='2678c340-2299-11eb-bb74-00163e0a2411', options=None) from=::ffff:10.0.0.97,51966, task_id=847ff048-3306-4c60-bc69-d628d67ee7c1 (api:48) 2020-11-18 18:06:56,525+0100 INFO (jsonrpc/0) [vdsm.api] FINISH getStoragePoolInfo return={'info': {'domains': '2974d4ff-27c5-4a25-b956-0ff4ff426cd3:Active,33992e1c-5383-4e3d-b9d0-945491ee0ccd:Active,dfeb1c22-4c0a-4bb4-9ef1-934962f18707:Active,3b532b12-5151-4626-883a-26f2a82d4f9e:Active', 'isoprefix': '/rhev/data-center/mnt/si-nas.babel.it:_iso-domain/2974d4ff-27c5-4a25-b956-0ff4ff426cd3/images/11111111-1111-1111-1111-111111111111', 'lver': 3, 'master_uuid': '33992e1c-5383-4e3d-b9d0-945491ee0ccd', 'master_ver': 1, 'name': 'No Description', 'pool_status': 'connected', 'spm_id': 1, 'type': 'ISCSI', 'version': '5'}, 'dominfo': {'2974d4ff-27c5-4a25-b956-0ff4ff426cd3': {'status': 'Active', 'alerts': [], 'isoprefix': '/rhev/data-center/mnt/si-nas.babel.it:_iso-domain/2974d4ff-27c5-4a25-b956-0ff4ff426cd3/images/11111111-1111-1111-1111-111111111111', 'version': 0, 'disktotal': '211378241536', 'diskfree': '176700424192'}, '33992e1c-5383-4e3d-b9d0-945491ee0ccd': {'status': 'Active', 'alerts': [], 'isoprefix': '', 'version': 5, 'disktotal': '106971529216', 'diskfree': '12750684160'}, 'dfeb1c22-4c0a-4bb4-9ef1-934962f18707': {'status': 'Active', 'alerts': [], 'isoprefix': '', 'version': 5, 'disktotal': '751216623616', 'diskfree': '738734374912'}, '3b532b12-5151-4626-883a-26f2a82d4f9e': {'status': 'Active', 'alerts': [], 'isoprefix': '', 'version': 0, 'disktotal': '5827912007680', 'diskfree': '1412350607360'}}} from=::ffff:10.0.0.97,51966, task_id=847ff048-3306-4c60-bc69-d628d67ee7c1 (api:54) 2020-11-18 18:06:56,525+0100 INFO (jsonrpc/0) [jsonrpc.JsonRpcServer] RPC call StoragePool.getInfo succeeded in 0.02 seconds (__init__:312) 2020-11-18 18:06:56,570+0100 INFO (jsonrpc/1) [jsonrpc.JsonRpcServer] RPC call Host.ping2 succeeded in 0.00 seconds (__init__:312) 2020-11-18 18:06:56,630+0100 INFO (jsonrpc/5) [jsonrpc.JsonRpcServer] RPC call Host.ping2 succeeded in 0.00 seconds (__init__:312) 2020-11-18 18:06:56,632+0100 INFO (jsonrpc/4) [api.host] START getStats() from=::1,57660 (api:48) 2020-11-18 18:06:56,693+0100 INFO (jsonrpc/4) [vdsm.api] START repoStats(domains=()) from=::1,57660, task_id=4cd76fa5-deb8-4441-a710-7fa15c47953c (api:48) 2020-11-18 18:06:56,694+0100 INFO (jsonrpc/4) [vdsm.api] FINISH repoStats return={'33992e1c-5383-4e3d-b9d0-945491ee0ccd': {'code': 0, 'lastCheck': '2.8', 'delay': '0.000625533', 'valid': True, 'version': 5, 'acquired': True, 'actual': True}, 'dfeb1c22-4c0a-4bb4-9ef1-934962f18707': {'code': 0, 'lastCheck': '0.3', 'delay': '0.000475724', 'valid': True, 'version': 5, 'acquired': True, 'actual': True}, '2974d4ff-27c5-4a25-b956-0ff4ff426cd3': {'code': 0, 'lastCheck': '4.2', 'delay': '0.00036804', 'valid': True, 'version': 0, 'acquired': True, 'actual': True}, '3b532b12-5151-4626-883a-26f2a82d4f9e': {'code': 0, 'lastCheck': '1.2', 'delay': '0.00034326', 'valid': True, 'version': 0, 'acquired': True, 'actual': True}} from=::1,57660, task_id=4cd76fa5-deb8-4441-a710-7fa15c47953c (api:54) 2020-11-18 18:06:56,694+0100 INFO (jsonrpc/4) [vdsm.api] START multipath_health() from=::1,57660, task_id=ab54ef78-6290-413b-b03a-940c39ae3c5f (api:48) 2020-11-18 18:06:56,695+0100 INFO (jsonrpc/4) [vdsm.api] FINISH multipath_health return={} from=::1,57660, task_id=ab54ef78-6290-413b-b03a-940c39ae3c5f (api:54) 2020-11-18 18:06:56,708+0100 INFO (jsonrpc/4) [api.host] FINISH getStats return={'status': {'code': 0, 'message': 'Done'}, 'info': (suppressed)} from=::1,57660 (api:54) 2020-11-18 18:06:56,711+0100 INFO (jsonrpc/4) [jsonrpc.JsonRpcServer] RPC call Host.getStats succeeded in 0.08 seconds (__init__:312) 2020-11-18 18:06:56,720+0100 INFO (jsonrpc/6) [api.host] START getStats() from=::ffff:10.0.0.97,51960 (api:48) 2020-11-18 18:06:56,779+0100 INFO (jsonrpc/6) [vdsm.api] START repoStats(domains=()) from=::ffff:10.0.0.97,51960, task_id=925245f0-3ca8-4ee9-a88b-74422bbdba64 (api:48) 2020-11-18 18:06:56,780+0100 INFO (jsonrpc/6) [vdsm.api] FINISH repoStats return={'33992e1c-5383-4e3d-b9d0-945491ee0ccd': {'code': 0, 'lastCheck': '2.9', 'delay': '0.000625533', 'valid': True, 'version': 5, 'acquired': True, 'actual': True}, 'dfeb1c22-4c0a-4bb4-9ef1-934962f18707': {'code': 0, 'lastCheck': '0.4', 'delay': '0.000475724', 'valid': True, 'version': 5, 'acquired': True, 'actual': True}, '2974d4ff-27c5-4a25-b956-0ff4ff426cd3': {'code': 0, 'lastCheck': '4.3', 'delay': '0.00036804', 'valid': True, 'version': 0, 'acquired': True, 'actual': True}, '3b532b12-5151-4626-883a-26f2a82d4f9e': {'code': 0, 'lastCheck': '1.3', 'delay': '0.00034326', 'valid': True, 'version': 0, 'acquired': True, 'actual': True}} from=::ffff:10.0.0.97,51960, task_id=925245f0-3ca8-4ee9-a88b-74422bbdba64 (api:54) 2020-11-18 18:06:56,781+0100 INFO (jsonrpc/6) [vdsm.api] START multipath_health() from=::ffff:10.0.0.97,51960, task_id=d3064d6b-fb51-48cb-9c32-72fd4c4d2c9f (api:48) 2020-11-18 18:06:56,781+0100 INFO (jsonrpc/6) [vdsm.api] FINISH multipath_health return={} from=::ffff:10.0.0.97,51960, task_id=d3064d6b-fb51-48cb-9c32-72fd4c4d2c9f (api:54) 2020-11-18 18:06:56,791+0100 INFO (jsonrpc/6) [api.host] FINISH getStats return={'status': {'code': 0, 'message': 'Done'}, 'info': (suppressed)} from=::ffff:10.0.0.97,51960 (api:54) 2020-11-18 18:06:56,793+0100 INFO (jsonrpc/6) [jsonrpc.JsonRpcServer] RPC call Host.getStats succeeded in 0.07 seconds (__init__:312) 2020-11-18 18:06:59,239+0100 INFO (jsonrpc/7) [jsonrpc.JsonRpcServer] RPC call Host.ping2 succeeded in 0.00 seconds (__init__:312) 2020-11-18 18:06:59,241+0100 INFO (jsonrpc/3) [api.virt] START getStats() from=::1,57660, vmId=cfe08595-6ff9-4b4c-a48d-51530e42230c (api:48) 2020-11-18 18:06:59,243+0100 INFO (jsonrpc/3) [api.virt] FINISH getStats return={'status': {'code': 0, 'message': 'Done'}, 'statsList': [{'statusTime': '2930063539', 'status': 'Up', 'vmId': 'cfe08595-6ff9-4b4c-a48d-51530e42230c', 'vmName': 'HostedEngine', 'vmType': 'kvm', 'kvmEnable': 'true', 'acpiEnable': 'true', 'elapsedTime': '198875', 'monitorResponse': '0', 'clientIp': '', 'timeOffset': '0', 'pauseCode': 'NOERR', 'cpuUser': '3.82', 'cpuSys': '0.93', 'cpuUsage': '2272940000000', 'network': {'vnet0': {'macAddr': '00:16:3e:0a:24:11', 'name': 'vnet0', 'speed': '1000', 'state': 'unknown', 'rxErrors': '0', 'rxDropped': '0', 'txErrors': '0', 'txDropped': '0', 'rx': '326377193', 'tx': '79759388', 'sampleTime': 782579.892564877}}, 'disks': {'sdc': {'truesize': '0', 'apparentsize': '0', 'readLatency': '0', 'writeLatency': '0', 'flushLatency': '0', 'writtenBytes': '0', 'writeOps': '0', 'readOps': '24', 'readBytes': '500', 'readRate': '0.0', 'writeRate': '0.0'}, 'vda': {'truesize': '858993 45920', 'apparentsize': '85899345920', 'readLatency': '0', 'writeLatency': '2077097.9047619049', 'flushLatency': '35968369.22727273', 'writtenBytes': '6460508160', 'writeOps': '480259', 'readOps': '32874', 'readBytes': '814649344', 'readRate': '0.0', 'writeRate': '19287.748635161122', 'imageID': 'a387fa74-fa0d-4027-9f3f-560d2c723309'}}, 'balloonInfo': {'balloon_max': '16777216', 'balloon_min': '1048576', 'balloon_cur': '16777216', 'balloon_target': '16777216'}, 'vcpuCount': '4', 'memoryStats': {'mem_total': '16241900', 'mem_unused': '9958236', 'mem_free': '10857104', 'swap_in': 0, 'swap_out': 0, 'majflt': 0, 'minflt': 39, 'pageflt': 39}, 'displayInfo': [{'type': 'vnc', 'port': '5900', 'tlsPort': '', 'ipAddress': '10.0.0.95'}, {'type': 'spice', 'port': '5901', 'tlsPort': '5902', 'ipAddress': '10.0.0.95'}], 'hash': '503429482678177929', 'vmJobs': {}, 'vcpuQuota': '-1', 'vcpuPeriod': 100000, 'username': 'root', 'session': 'Unknown', 'memUsage': '35', 'guestCPUCount': -1, 'appsList': (' kernel-4.18.0-193.28.1.el8_2.x86_64', 'qemu-guest-agent-4.2.0'), 'guestIPs': '', 'guestFQDN': 'si-ovirt-manager.par-tec.it', 'netIfaces': [{'hw': '00:00:00:00:00:00', 'inet': ['127.0.0.1'], 'inet6': ['::1'], 'name': 'lo'}, {'hw': '00:16:3e:0a:24:11', 'inet': ['10.0.0.97'], 'inet6': ['fe80::216:3eff:fe0a:2411'], 'name': 'eth0'}], 'disksUsage': [{'path': '/', 'total': '7505707008', 'used': '3535548416', 'fs': 'xfs'}, {'path': '/home', 'total': '1063256064', 'used': '41316352', 'fs': 'xfs'}, {'path': '/boot', 'total': '1063256064', 'used': '261574656', 'fs': 'xfs'}, {'path': '/tmp', 'total': '2136997888', 'used': '48939008', 'fs': 'xfs'}, {'path': '/var', 'total': '21464350720', 'used': '968835072', 'fs': 'xfs'}, {'path': '/var/log', 'total': '10726932480', 'used': '159064064', 'fs': 'xfs'}, {'path': '/var/log/audit', 'total': '1063256064', 'used': '42029056', 'fs': 'xfs'}], 'guestName': 'si-ovirt-manager.par-tec.it', 'guestOs': '4.18.0-193.28.1.el8_2.x86_64', 'guestOsInfo': {'type': ' linux', 'arch': 'x86_64', 'kernel': '4.18.0-193.28.1.el8_2.x86_64', 'distribution': 'CentOS Linux', 'version': '8', 'codename': ''}, 'guestTimezone': {'offset': 60, 'zone': 'CET'}}]} from=::1,57660, vmId=cfe08595-6ff9-4b4c-a48d-51530e42230c (api:54) 2020-11-18 18:06:59,244+0100 INFO (jsonrpc/3) [jsonrpc.JsonRpcServer] RPC call VM.getStats succeeded in 0.00 seconds (__init__:312)