Hi Michal,
Thanks for assisting.
I've just done as requested however nothing is logged in the engine.log at the time I click Migrate, below is the log and I hit the Migrate button about 4 times between 09:35 and 09:36 and nothing was logged about this...
2019-07-10 09:35:57,967+02 INFO [org.ovirt.engine.core.sso.utils.AuthenticationUtils] (default task-14) [] User trouble@internal successfully logged in with scopes: ovirt-app-admin ovirt-app-api ovirt-app-portal ovirt-ext=auth:sequence-priority=~ ovirt-ext=revoke:revoke-all ovirt-ext=token-info:authz-search ovirt-ext=token-info:public-authz-search ovirt-ext=token-info:validate ovirt-ext=token:password-access
2019-07-10 09:35:58,012+02 INFO [org.ovirt.engine.core.bll.aaa.CreateUserSessionCommand] (default task-14) [2997034] Running command: CreateUserSessionCommand internal: false.
2019-07-10 09:35:58,021+02 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (default task-14) [2997034] EVENT_ID: USER_VDC_LOGIN(30), User trouble@internal-authz connecting from '160.128.20.85' using session 'bv55G0wZznETUiQwjgjfUNje7wOsG4UDCuFunSslVeAFQkhdY2zzTY7du36ynTF5nW5U7JiPyr7gl9QDHfWuig==' logged in.
2019-07-10 09:36:58,304+02 INFO [org.ovirt.engine.core.bll.utils.ThreadPoolMonitoringService] (EE-ManagedThreadFactory-engineThreadMonitoring-Thread-1) [] Thread pool 'default' is using 0 threads out of 1, 5 threads waiting for tasks.
2019-07-10 09:36:58,305+02 INFO [org.ovirt.engine.core.bll.utils.ThreadPoolMonitoringService] (EE-ManagedThreadFactory-engineThreadMonitoring-Thread-1) [] Thread pool 'engine' is using 0 threads out of 500, 16 threads waiting for tasks and 0 tasks in queue.
2019-07-10 09:36:58,305+02 INFO [org.ovirt.engine.core.bll.utils.ThreadPoolMonitoringService] (EE-ManagedThreadFactory-engineThreadMonitoring-Thread-1) [] Thread pool 'engineScheduled' is using 0 threads out of 100, 100 threads waiting for tasks.
2019-07-10 09:36:58,305+02 INFO [org.ovirt.engine.core.bll.utils.ThreadPoolMonitoringService] (EE-ManagedThreadFactory-engineThreadMonitoring-Thread-1) [] Thread pool 'engineThreadMonitoring' is using 1 threads out of 1, 0 threads waiting for tasks.
2019-07-10 09:36:58,305+02 INFO [org.ovirt.engine.core.bll.utils.ThreadPoolMonitoringService] (EE-ManagedThreadFactory-engineThreadMonitoring-Thread-1) [] Thread pool 'hostUpdatesChecker' is using 0 threads out of 5, 2 threads waiting for tasks.
The same is observed in the vdsm.log too, below is the log during the attempted migration....
2019-07-10 09:39:57,034+0200 INFO (jsonrpc/7) [jsonrpc.JsonRpcServer] RPC call Host.getStats succeeded in 0.01 seconds (__init__:573)
2019-07-10 09:39:57,994+0200 INFO (jsonrpc/2) [api.host] START getStats() from=::ffff:10.0.1.1,57934 (api:46)
2019-07-10 09:39:57,994+0200 INFO (jsonrpc/2) [vdsm.api] START repoStats(domains=()) from=::ffff:10.0.1.1,57934, task_id=e2529cfc-4293-42b4-91fa-7f5558e279dd (api:46)
2019-07-10 09:39:57,994+0200 INFO (jsonrpc/2) [vdsm.api] FINISH repoStats return={u'8a607f8a-542a-473c-bb18-25c05fe2a3d4': {'code': 0, 'actual': True, 'version': 4, 'acquired': True, 'delay': '0.000194846', 'lastCheck': '2.4', 'valid': True}, u'37b1a5d7-4e29-4763-9337-63c51dbc5fc8': {'code': 0, 'actual': True, 'version': 0, 'acquired': True, 'delay': '0.000277154', 'lastCheck': '6.0', 'valid': True}, u'2558679a-2214-466b-8f05-06fdda9146e5': {'code': 0, 'actual': True, 'version': 4, 'acquired': True, 'delay': '0.000421988', 'lastCheck': '2.4', 'valid': True}, u'640a5875-3d82-43c0-860f-7bb3e4a7e6f0': {'code': 0, 'actual': True, 'version': 4, 'acquired': True, 'delay': '0.000228443', 'lastCheck': '2.4', 'valid': True}} from=::ffff:10.0.1.1,57934, task_id=e2529cfc-4293-42b4-91fa-7f5558e279dd (api:52)
2019-07-10 09:39:57,995+0200 INFO (jsonrpc/2) [vdsm.api] START multipath_health() from=::ffff:10.0.1.1,57934, task_id=fd7ad703-5096-4f09-99fa-54672cb4aad9 (api:46)
2019-07-10 09:39:57,995+0200 INFO (jsonrpc/2) [vdsm.api] FINISH multipath_health return={} from=::ffff:10.0.1.1,57934, task_id=fd7ad703-5096-4f09-99fa-54672cb4aad9 (api:52)
2019-07-10 09:39:58,002+0200 INFO (jsonrpc/2) [api.host] FINISH getStats return={'status': {'message': 'Done', 'code': 0}, 'info': {'cpuStatistics': {'42': {'cpuUser': '0.00', 'nodeIndex': 0, 'cpuSys': '0.13', 'cpuIdle': '99.87'}, '43': {'cpuUser': '0.00', 'nodeIndex': 1, 'cpuSys': '0.00', 'cpuIdle': '100.00'}, '24': {'cpuUser': '0.73', 'nodeIndex': 0, 'cpuSys': '0.07', 'cpuIdle': '99.20'}, '25': {'cpuUser': '0.07', 'nodeIndex': 1, 'cpuSys': '0.00', 'cpuIdle': '99.93'}, '26': {'cpuUser': '5.59', 'nodeIndex': 0, 'cpuSys': '1.20', 'cpuIdle': '93.21'}, '27': {'cpuUser': '0.87', 'nodeIndex': 1, 'cpuSys': '0.60', 'cpuIdle': '98.53'}, '20': {'cpuUser': '0.53', 'nodeIndex': 0, 'cpuSys': '0.13', 'cpuIdle': '99.34'}, '21': {'cpuUser': '0.00', 'nodeIndex': 1, 'cpuSys': '0.07', 'cpuIdle': '99.93'}, '22': {'cpuUser': '0.40', 'nodeIndex': 0, 'cpuSys': '0.20', 'cpuIdle': '99.40'}, '23': {'cpuUser': '0.07', 'nodeIndex': 1, 'cpuSys': '0.07', 'cpuIdle': '99.86'}, '46': {'cpuUser': '0.13', 'nodeIndex': 0, 'cpuSys': '0.00', 'cpuIdle': '99.87'}, '47': {'cpuUser': '0.00', 'nodeIndex': 1, 'cpuSys': '0.00', 'cpuIdle': '100.00'}, '44': {'cpuUser': '0.00', 'nodeIndex': 0, 'cpuSys': '0.00', 'cpuIdle': '100.00'}, '45': {'cpuUser': '0.00', 'nodeIndex': 1, 'cpuSys': '0.00', 'cpuIdle': '100.00'}, '28': {'cpuUser': '0.60', 'nodeIndex': 0, 'cpuSys': '0.07', 'cpuIdle': '99.33'}, '29': {'cpuUser': '1.07', 'nodeIndex': 1, 'cpuSys': '0.20', 'cpuIdle': '98.73'}, '40': {'cpuUser': '0.00', 'nodeIndex': 0, 'cpuSys': '0.00', 'cpuIdle': '100.00'}, '41': {'cpuUser': '0.00', 'nodeIndex': 1, 'cpuSys': '0.00', 'cpuIdle': '100.00'}, '1': {'cpuUser': '1.07', 'nodeIndex': 1, 'cpuSys': '1.13', 'cpuIdle': '97.80'}, '0': {'cpuUser': '0.60', 'nodeIndex': 0, 'cpuSys': '0.20', 'cpuIdle': '99.20'}, '3': {'cpuUser': '0.20', 'nodeIndex': 1, 'cpuSys': '0.07', 'cpuIdle': '99.73'}, '2': {'cpuUser': '3.00', 'nodeIndex': 0, 'cpuSys': '0.53', 'cpuIdle': '96.47'}, '5': {'cpuUser': '0.20', 'nodeIndex': 1, 'cpuSys': '0.13', 'cpuIdle': '99.67'}, '4': {'cpuUser': '0.47', 'nodeIndex': 0, 'cpuSys': '0.20', 'cpuIdle': '99.33'}, '7': {'cpuUser': '0.40', 'nodeIndex': 1, 'cpuSys': '0.20', 'cpuIdle': '99.40'}, '6': {'cpuUser': '0.67', 'nodeIndex': 0, 'cpuSys': '0.20', 'cpuIdle': '99.13'}, '9': {'cpuUser': '0.47', 'nodeIndex': 1, 'cpuSys': '0.40', 'cpuIdle': '99.13'}, '8': {'cpuUser': '0.13', 'nodeIndex': 0, 'cpuSys': '0.07', 'cpuIdle': '99.80'}, '39': {'cpuUser': '0.33', 'nodeIndex': 1, 'cpuSys': '0.13', 'cpuIdle': '99.54'}, '38': {'cpuUser': '0.07', 'nodeIndex': 0, 'cpuSys': '0.00', 'cpuIdle': '99.93'}, '11': {'cpuUser': '0.67', 'nodeIndex': 1, 'cpuSys': '0.27', 'cpuIdle': '99.06'}, '10': {'cpuUser': '0.13', 'nodeIndex': 0, 'cpuSys': '0.13', 'cpuIdle': '99.74'}, '13': {'cpuUser': '0.07', 'nodeIndex': 1, 'cpuSys': '0.07', 'cpuIdle': '99.86'}, '12': {'cpuUser': '0.07', 'nodeIndex': 0, 'cpuSys': '0.27', 'cpuIdle': '99.66'}, '15': {'cpuUser': '0.27', 'nodeIndex': 1, 'cpuSys': '0.13', 'cpuIdle': '99.60'}, '14': {'cpuUser': '0.27', 'nodeIndex': 0, 'cpuSys': '0.07', 'cpuIdle': '99.66'}, '17': {'cpuUser': '0.07', 'nodeIndex': 1, 'cpuSys': '0.27', 'cpuIdle': '99.66'}, '16': {'cpuUser': '0.53', 'nodeIndex': 0, 'cpuSys': '0.07', 'cpuIdle': '99.40'}, '19': {'cpuUser': '0.00', 'nodeIndex': 1, 'cpuSys': '0.00', 'cpuIdle': '100.00'}, '18': {'cpuUser': '1.00', 'nodeIndex': 0, 'cpuSys': '0.27', 'cpuIdle': '98.73'}, '31': {'cpuUser': '0.00', 'nodeIndex': 1, 'cpuSys': '0.00', 'cpuIdle': '100.00'}, '30': {'cpuUser': '0.00', 'nodeIndex': 0, 'cpuSys': '0.00', 'cpuIdle': '100.00'}, '37': {'cpuUser': '0.07', 'nodeIndex': 1, 'cpuSys': '0.07', 'cpuIdle': '99.86'}, '36': {'cpuUser': '0.00', 'nodeIndex': 0, 'cpuSys': '0.00', 'cpuIdle': '100.00'}, '35': {'cpuUser': '0.20', 'nodeIndex': 1, 'cpuSys': '0.33', 'cpuIdle': '99.47'}, '34': {'cpuUser': '0.00', 'nodeIndex': 0, 'cpuSys': '0.00', 'cpuIdle': '100.00'}, '33': {'cpuUser': '0.07', 'nodeIndex': 1, 'cpuSys': '0.13', 'cpuIdle': '99.80'}, '32': {'cpuUser': '0.00', 'nodeIndex': 0, 'cpuSys': '0.00', 'cpuIdle': '100.00'}}, 'numaNodeMemFree': {'1': {'memPercent': 5, 'memFree': '94165'}, '0': {'memPercent': 22, 'memFree': '77122'}}, 'memShared': 0, 'haScore': 3400, 'thpState': 'always', 'ksmMergeAcrossNodes': True, 'vmCount': 2, 'memUsed': '11', 'storageDomains': {u'8a607f8a-542a-473c-bb18-25c05fe2a3d4': {'code': 0, 'actual': True, 'version': 4, 'acquired': True, 'delay': '0.000194846', 'lastCheck': '2.4', 'valid': True}, u'37b1a5d7-4e29-4763-9337-63c51dbc5fc8': {'code': 0, 'actual': True, 'version': 0, 'acquired': True, 'delay': '0.000277154', 'lastCheck': '6.0', 'valid': True}, u'2558679a-2214-466b-8f05-06fdda9146e5': {'code': 0, 'actual': True, 'version': 4, 'acquired': True, 'delay': '0.000421988', 'lastCheck': '2.4', 'valid': True}, u'640a5875-3d82-43c0-860f-7bb3e4a7e6f0': {'code': 0, 'actual': True, 'version': 4, 'acquired': True, 'delay': '0.000228443', 'lastCheck': '2.4', 'valid': True}}, 'incomingVmMigrations': 0, 'network': {'em4': {'txErrors': '0', 'state': 'up', 'sampleTime': 1562744396.40508, 'name': 'em4', 'tx': '2160', 'txDropped': '0', 'rx': '261751836', 'rxErrors': '0', 'speed': '1000', 'rxDropped': '1'}, 'ovirtmgmt': {'txErrors': '0', 'state': 'up', 'sampleTime': 1562744396.40508, 'name': 'ovirtmgmt', 'tx': '193005142', 'txDropped': '0', 'rx': '4300879104', 'rxErrors': '0', 'speed': '1000', 'rxDropped': '478'}, 'restores': {'txErrors': '0', 'state': 'up', 'sampleTime': 1562744396.40508, 'name': 'restores', 'tx': '1362', 'txDropped': '0', 'rx': '226442665', 'rxErrors': '0', 'speed': '1000', 'rxDropped': '478'}, 'em2': {'txErrors': '0', 'state': 'down', 'sampleTime': 1562744396.40508, 'name': 'em2', 'tx': '0', 'txDropped': '0', 'rx': '0', 'rxErrors': '0', 'speed': '1000', 'rxDropped': '0'}, 'vnet0': {'txErrors': '0', 'state': 'up', 'sampleTime': 1562744396.40508, 'name': 'vnet0', 'tx': '2032610435', 'txDropped': '686', 'rx': '4287479548', 'rxErrors': '0', 'speed': '1000', 'rxDropped': '0'}, ';vdsmdummy;': {'txErrors': '0', 'state': 'down', 'sampleTime': 1562744396.40508, 'name': ';vdsmdummy;', 'tx': '0', 'txDropped': '0', 'rx': '0', 'rxErrors': '0', 'speed': '1000', 'rxDropped': '0'}, 'em1': {'txErrors': '0', 'state': 'up', 'sampleTime': 1562744396.40508, 'name': 'em1', 'tx': '4548433238', 'txDropped': '0', 'rx': '6476729588', 'rxErrors': '0', 'speed': '1000', 'rxDropped': '1'}, 'em3': {'txErrors': '0', 'state': 'down', 'sampleTime': 1562744396.40508, 'name': 'em3', 'tx': '0', 'txDropped': '0', 'rx': '0', 'rxErrors': '0', 'speed': '1000', 'rxDropped': '0'}, 'lo': {'txErrors': '0', 'state': 'up', 'sampleTime': 1562744396.40508, 'name': 'lo', 'tx': '397962377', 'txDropped': '0', 'rx': '397962377', 'rxErrors': '0', 'speed': '1000', 'rxDropped': '0'}, 'vnet1': {'txErrors': '0', 'state': 'up', 'sampleTime': 1562744396.40508, 'name': 'vnet1', 'tx': '526185708', 'txDropped': '0', 'rx': '118512222', 'rxErrors': '0', 'speed': '1000', 'rxDropped': '0'}}, 'txDropped': '686', 'anonHugePages': '18532', 'ksmPages': 100, 'elapsedTime': '85176.64', 'cpuLoad': '0.06', 'cpuSys': '0.17', 'diskStats': {'/var/log': {'free': '6850'}, '/var/run/vdsm/': {'free': '96410'}, '/tmp': {'free': '1825'}}, 'cpuUserVdsmd': '1.07', 'netConfigDirty': 'False', 'memCommitted': 24706, 'ksmState': False, 'vmMigrating': 0, 'ksmCpu': 0, 'memAvailable': 166010, 'bootTime': '1562659184', 'haStats': {'active': True, 'configured': True, 'score': 3400, 'localMaintenance': False, 'globalMaintenance': False}, 'momStatus': 'active', 'multipathHealth': {}, 'rxDropped': '958', 'outgoingVmMigrations': 0, 'swapTotal': 4095, 'swapFree': 4095, 'hugepages': defaultdict(<type 'dict'>, {1048576: {'resv_hugepages': 0, 'free_hugepages': 0, 'nr_overcommit_hugepages': 0, 'surplus_hugepages': 0, 'vm.free_hugepages': 0, 'nr_hugepages': 0, 'nr_hugepages_mempolicy': 0}, 2048: {'resv_hugepages': 0, 'free_hugepages': 0, 'nr_overcommit_hugepages': 0, 'surplus_hugepages': 0, 'vm.free_hugepages': 0, 'nr_hugepages': 0, 'nr_hugepages_mempolicy': 0}}), 'dateTime': '2019-07-10T07:39:57 GMT', 'cpuUser': '0.44', 'memFree': 172451, 'cpuIdle': '99.39', 'vmActive': 2, 'v2vJobs': {}, 'cpuSysVdsmd': '0.60'}} from=::ffff:10.0.1.1,57934 (api:52)
2019-07-10 09:39:58,004+0200 INFO (jsonrpc/2) [jsonrpc.JsonRpcServer] RPC call Host.getStats succeeded in 0.01 seconds (__init__:573)
Please let me know if you need further info.
Thank you.
Regards.