Attachment 1446729 Details for Bug 1584775 – engine.log

engine.log

engine.log (text/plain), 10.41 KB, created by Kapetanakis Giannis on 2018-06-01 15:29:18 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Kapetanakis Giannis

Created: 2018-06-01 15:29:18 UTC

Size: 10.41 KB

patch

obsolete

>2018-05-31 13:08:41,221+03 INFO  [org.ovirt.engine.core.bll.MigrateVmCommand] (default task-245) [6941e62a] Lock Acquired to object 'EngineLock:{exclusiveLocks='[26fd3df7-8b49-4f05-9c8c-3b2db092264f=VM]', sharedLocks=''}'
>2018-05-31 13:08:41,311+03 INFO  [org.ovirt.engine.core.bll.MigrateVmCommand] (default task-245) [6941e62a] Running command: MigrateVmCommand internal: true. Entities affected :  ID: 26fd3df7-8b49-4f05-9c8c-3b2db092264f Type: VMAction group MIGRATE_VM with role type USER
>2018-05-31 13:08:41,466+03 INFO  [org.ovirt.engine.core.vdsbroker.MigrateVDSCommand] (default task-245) [6941e62a] START, MigrateVDSCommand( MigrateVDSCommandParameters:{hostId='1d1bd9b5-5934-4c79-a0d8-bc6ea76cdb29', vmId='26fd3df7-8b49-4f05-9c8c-3b2db092264f', srcHost='x.x.x.x', dstVdsId='0822eecc-3b58-459c-9fc0-a1f642deff52', dstHost='x.x.x.x:54321', migrationMethod='ONLINE', tunnelMigration='false', migrationDowntime='0', autoConverge='true', migrateCompressed='false', consoleAddress='null', maxBandwidth='5000', enableGuestEvents='true', maxIncomingMigrations='2', maxOutgoingMigrations='2', convergenceSchedule='[init=[{name=setDowntime, params=[100]}], stalling=[{limit=1, action={name=setDowntime, params=[150]}}, {limit=2, action={name=setDowntime, params=[200]}}, {limit=3, action={name=setDowntime, params=[300]}}, {limit=4, action={name=setDowntime, params=[400]}}, {limit=6, action={name=setDowntime, params=[500]}}, {limit=-1, action={name=abort, params=[]}}]]', dstQemu='10.1.3.53'}), log id: 1076370
>2018-05-31 13:08:41,469+03 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.MigrateBrokerVDSCommand] (default task-245) [6941e62a] START, MigrateBrokerVDSCommand(HostName = node1, MigrateVDSCommandParameters:{hostId='1d1bd9b5-5934-4c79-a0d8-bc6ea76cdb29', vmId='26fd3df7-8b49-4f05-9c8c-3b2db092264f', srcHost='x.x.x.x', dstVdsId='0822eecc-3b58-459c-9fc0-a1f642deff52', dstHost='x.x.x.x:54321', migrationMethod='ONLINE', tunnelMigration='false', migrationDowntime='0', autoConverge='true', migrateCompressed='false', consoleAddress='null', maxBandwidth='5000', enableGuestEvents='true', maxIncomingMigrations='2', maxOutgoingMigrations='2', convergenceSchedule='[init=[{name=setDowntime, params=[100]}], stalling=[{limit=1, action={name=setDowntime, params=[150]}}, {limit=2, action={name=setDowntime, params=[200]}}, {limit=3, action={name=setDowntime, params=[300]}}, {limit=4, action={name=setDowntime, params=[400]}}, {limit=6, action={name=setDowntime, params=[500]}}, {limit=-1, action={name=abort, params=[]}}]]', dstQemu='10.1.3.53'}), log id: 568e5a9c
>2018-05-31 13:08:49,269+03 INFO  [org.ovirt.engine.core.vdsbroker.monitoring.VmAnalyzer] (ForkJoinPool-1-worker-15) [] VM '26fd3df7-8b49-4f05-9c8c-3b2db092264f' was reported as Down on VDS '1d1bd9b5-5934-4c79-a0d8-bc6ea76cdb29'(node1)
>2018-05-31 13:08:49,270+03 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.DestroyVDSCommand] (ForkJoinPool-1-worker-15) [] START, DestroyVDSCommand(HostName = node1, DestroyVmVDSCommandParameters:{hostId='1d1bd9b5-5934-4c79-a0d8-bc6ea76cdb29', vmId='26fd3df7-8b49-4f05-9c8c-3b2db092264f', secondsToWait='0', gracefully='false', reason='', ignoreNoVm='true'}), log id: 339769d5
>2018-05-31 13:08:49,524+03 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.DumpXmlsVDSCommand] (ForkJoinPool-1-worker-13) [] START, DumpXmlsVDSCommand(HostName = node2, Params:{hostId='0822eecc-3b58-459c-9fc0-a1f642deff52', vmIds='[26fd3df7-8b49-4f05-9c8c-3b2db092264f]'}), log id: 5cd7464b
>2018-05-31 13:08:49,560+03 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.DumpXmlsVDSCommand] (ForkJoinPool-1-worker-13) [] FINISH, DumpXmlsVDSCommand, return: [{vmId=26fd3df7-8b49-4f05-9c8c-3b2db092264f, devices=[Ljava.util.Map;@15712052, guestDiskMapping={2e58b697-63b6-4e80-96cf-f3060758f755={name=/dev/vda}}}], log id: 5cd7464b
>2018-05-31 13:08:50,163+03 INFO  [org.ovirt.engine.core.vdsbroker.monitoring.VmAnalyzer] (ForkJoinPool-1-worker-15) [] VM '26fd3df7-8b49-4f05-9c8c-3b2db092264f'(vm) moved from 'MigratingFrom' --> 'Down'
>2018-05-31 13:08:50,165+03 INFO  [org.ovirt.engine.core.vdsbroker.monitoring.VmAnalyzer] (ForkJoinPool-1-worker-15) [] Handing over VM '26fd3df7-8b49-4f05-9c8c-3b2db092264f'(vm) to Host '0822eecc-3b58-459c-9fc0-a1f642deff52'. Setting VM to status 'MigratingTo'
>2018-05-31 13:09:01,304+03 INFO  [org.ovirt.engine.core.vdsbroker.monitoring.VmAnalyzer] (EE-ManagedThreadFactory-engineScheduled-Thread-72) [] VM '26fd3df7-8b49-4f05-9c8c-3b2db092264f'(vm) moved from 'MigratingTo' --> 'Up'
>2018-05-31 13:09:01,385+03 INFO  [org.ovirt.engine.core.bll.MigrateVmCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-72) [] Lock freed to object 'EngineLock:{exclusiveLocks='[26fd3df7-8b49-4f05-9c8c-3b2db092264f=VM]', sharedLocks=''}'
>2018-05-31 13:19:04,525+03 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.DumpXmlsVDSCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-3) [] START, DumpXmlsVDSCommand(HostName = node2, Params:{hostId='0822eecc-3b58-459c-9fc0-a1f642deff52', vmIds='[26fd3df7-8b49-4f05-9c8c-3b2db092264f]'}), log id: 27cb749b
>2018-05-31 13:19:04,551+03 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.DumpXmlsVDSCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-3) [] FINISH, DumpXmlsVDSCommand, return: [{vmId=26fd3df7-8b49-4f05-9c8c-3b2db092264f, devices=[Ljava.util.Map;@4ebf856a, guestDiskMapping={2e58b697-63b6-4e80-96cf-f3060758f755={name=/dev/vda}}}], log id: 27cb749b
>2018-05-31 13:19:14,198+03 INFO  [org.ovirt.engine.core.bll.StopVmCommand] (EE-ManagedThreadFactory-engine-Thread-311) [1c40289d-d110-42e6-8282-e8568b1a5f83] Running command: StopVmCommand internal: false. Entities affected :  ID: 26fd3df7-8b49-4f05-9c8c-3b2db092264f Type: VMAction group STOP_VM with role type USER
>2018-05-31 13:19:14,202+03 INFO  [org.ovirt.engine.core.vdsbroker.DestroyVmVDSCommand] (EE-ManagedThreadFactory-engine-Thread-311) [1c40289d-d110-42e6-8282-e8568b1a5f83] START, DestroyVmVDSCommand( DestroyVmVDSCommandParameters:{hostId='0822eecc-3b58-459c-9fc0-a1f642deff52', vmId='26fd3df7-8b49-4f05-9c8c-3b2db092264f', secondsToWait='0', gracefully='false', reason='', ignoreNoVm='false'}), log id: 26620ed3
>2018-05-31 13:19:14,203+03 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.DestroyVDSCommand] (EE-ManagedThreadFactory-engine-Thread-311) [1c40289d-d110-42e6-8282-e8568b1a5f83] START, DestroyVDSCommand(HostName = node2, DestroyVmVDSCommandParameters:{hostId='0822eecc-3b58-459c-9fc0-a1f642deff52', vmId='26fd3df7-8b49-4f05-9c8c-3b2db092264f', secondsToWait='0', gracefully='false', reason='', ignoreNoVm='false'}), log id: 1c00a228
>2018-05-31 13:19:15,951+03 INFO  [org.ovirt.engine.core.vdsbroker.monitoring.VmAnalyzer] (ForkJoinPool-1-worker-12) [] VM '26fd3df7-8b49-4f05-9c8c-3b2db092264f' was reported as Down on VDS '0822eecc-3b58-459c-9fc0-a1f642deff52'(node2)
>2018-05-31 13:19:15,953+03 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.DestroyVDSCommand] (ForkJoinPool-1-worker-12) [] START, DestroyVDSCommand(HostName = node2, DestroyVmVDSCommandParameters:{hostId='0822eecc-3b58-459c-9fc0-a1f642deff52', vmId='26fd3df7-8b49-4f05-9c8c-3b2db092264f', secondsToWait='0', gracefully='false', reason='', ignoreNoVm='true'}), log id: 1c2e9a12
>2018-05-31 13:19:15,955+03 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.DestroyVDSCommand] (ForkJoinPool-1-worker-12) [] Failed to destroy VM '26fd3df7-8b49-4f05-9c8c-3b2db092264f' because VM does not exist, ignoring
>2018-05-31 13:19:15,956+03 INFO  [org.ovirt.engine.core.vdsbroker.monitoring.VmAnalyzer] (ForkJoinPool-1-worker-12) [] VM '26fd3df7-8b49-4f05-9c8c-3b2db092264f'(vm) moved from 'Up' --> 'Down'
>2018-05-31 13:19:18,868+03 INFO  [org.ovirt.engine.core.bll.RunVmCommand] (default task-164) [242c3ebf-78d8-4fc9-9d4e-d3d6437ba3f6] Lock Acquired to object 'EngineLock:{exclusiveLocks='[26fd3df7-8b49-4f05-9c8c-3b2db092264f=VM]', sharedLocks=''}'
>2018-05-31 13:19:18,927+03 INFO  [org.ovirt.engine.core.vdsbroker.IsVmDuringInitiatingVDSCommand] (default task-164) [242c3ebf-78d8-4fc9-9d4e-d3d6437ba3f6] START, IsVmDuringInitiatingVDSCommand( IsVmDuringInitiatingVDSCommandParameters:{vmId='26fd3df7-8b49-4f05-9c8c-3b2db092264f'}), log id: 16cbfc03
>2018-05-31 13:19:19,008+03 INFO  [org.ovirt.engine.core.bll.RunVmCommand] (EE-ManagedThreadFactory-engine-Thread-315) [242c3ebf-78d8-4fc9-9d4e-d3d6437ba3f6] Running command: RunVmCommand internal: false. Entities affected :  ID: 26fd3df7-8b49-4f05-9c8c-3b2db092264f Type: VMAction group RUN_VM with role type USER
>2018-05-31 13:19:19,056+03 INFO  [org.ovirt.engine.core.vdsbroker.UpdateVmDynamicDataVDSCommand] (EE-ManagedThreadFactory-engine-Thread-315) [242c3ebf-78d8-4fc9-9d4e-d3d6437ba3f6] START, UpdateVmDynamicDataVDSCommand( UpdateVmDynamicDataVDSCommandParameters:{hostId='null', vmId='26fd3df7-8b49-4f05-9c8c-3b2db092264f', vmDynamic='org.ovirt.engine.core.common.businessentities.VmDynamic@5d05c59'}), log id: 55837eb6
>2018-05-31 13:19:19,065+03 INFO  [org.ovirt.engine.core.vdsbroker.CreateVDSCommand] (EE-ManagedThreadFactory-engine-Thread-315) [242c3ebf-78d8-4fc9-9d4e-d3d6437ba3f6] START, CreateVDSCommand( CreateVDSCommandParameters:{hostId='1d1bd9b5-5934-4c79-a0d8-bc6ea76cdb29', vmId='26fd3df7-8b49-4f05-9c8c-3b2db092264f', vm='VM [vm]'}), log id: 5c70e927
>2018-05-31 13:19:19,069+03 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateBrokerVDSCommand] (EE-ManagedThreadFactory-engine-Thread-315) [242c3ebf-78d8-4fc9-9d4e-d3d6437ba3f6] START, CreateBrokerVDSCommand(HostName = node1, CreateVDSCommandParameters:{hostId='1d1bd9b5-5934-4c79-a0d8-bc6ea76cdb29', vmId='26fd3df7-8b49-4f05-9c8c-3b2db092264f', vm='VM [vm]'}), log id: 286cb236
>2018-05-31 13:19:19,168+03 INFO  [org.ovirt.engine.core.bll.RunVmCommand] (EE-ManagedThreadFactory-engine-Thread-315) [242c3ebf-78d8-4fc9-9d4e-d3d6437ba3f6] Lock freed to object 'EngineLock:{exclusiveLocks='[26fd3df7-8b49-4f05-9c8c-3b2db092264f=VM]', sharedLocks=''}'
>2018-05-31 13:19:22,286+03 INFO  [org.ovirt.engine.core.vdsbroker.monitoring.VmAnalyzer] (ForkJoinPool-1-worker-14) [] VM '26fd3df7-8b49-4f05-9c8c-3b2db092264f'(vm) moved from 'WaitForLaunch' --> 'PoweringUp'
>2018-05-31 13:19:22,308+03 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.DumpXmlsVDSCommand] (ForkJoinPool-1-worker-14) [] START, DumpXmlsVDSCommand(HostName = node1, Params:{hostId='1d1bd9b5-5934-4c79-a0d8-bc6ea76cdb29', vmIds='[26fd3df7-8b49-4f05-9c8c-3b2db092264f]'}), log id: 62c8c937
>2018-05-31 13:19:22,332+03 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.DumpXmlsVDSCommand] (ForkJoinPool-1-worker-14) [] FINISH, DumpXmlsVDSCommand, return: [{vmId=26fd3df7-8b49-4f05-9c8c-3b2db092264f, devices=[Ljava.util.Map;@20b3651e, guestDiskMapping={}}], log id: 62c8c937

Actions: View

Attachments on bug 1584775: 1446729 | 1446730 | 1446731