Description of problem: I'am unable to start a VM, when I use Run Once, it only prints Internal Engine Error Version-Release number of selected component (if applicable): ovirt-engine-4.1.0-0.0.master.20160614231313.git26c6df3.el7.centos.noarch How reproducible: always Steps to Reproduce: 1. start a VM 2. check events 3. Actual results: VM start failed Expected results: VM should start Additional info: 2016-06-15 07:11:54,200 INFO [org.ovirt.engine.core.bll.RunVmCommand] (default task-43) [3d619013] Lock Acquired to object 'EngineLock:{exclusiveLocks=' [57518b94-ca0f-412b-99de-865a24315d45=<VM, ACTION_TYPE_FAILED_OBJECT_LOCKED>]', sharedLocks='null'}' 2016-06-15 07:11:54,270 INFO [org.ovirt.engine.core.vdsbroker.IsVmDuringInitiatingVDSCommand] (default task-43) [3d619013] START, IsVmDuringInitiatingVD SCommand( IsVmDuringInitiatingVDSCommandParameters:{runAsync='true', vmId='57518b94-ca0f-412b-99de-865a24315d45'}), log id: 3ed0fd12 2016-06-15 07:11:54,270 INFO [org.ovirt.engine.core.vdsbroker.IsVmDuringInitiatingVDSCommand] (default task-43) [3d619013] FINISH, IsVmDuringInitiatingV DSCommand, return: false, log id: 3ed0fd12 2016-06-15 07:11:54,384 INFO [org.ovirt.engine.core.bll.RunVmCommand] (org.ovirt.thread.pool-8-thread-30) [3d619013] Running command: RunVmCommand inter nal: false. Entities affected : ID: 57518b94-ca0f-412b-99de-865a24315d45 Type: VMAction group RUN_VM with role type USER 2016-06-15 07:11:54,437 ERROR [org.ovirt.engine.core.bll.RunVmCommand] (org.ovirt.thread.pool-8-thread-30) [3d619013] Command 'org.ovirt.engine.core.bll. RunVmCommand' failed: null 2016-06-15 07:11:54,437 ERROR [org.ovirt.engine.core.bll.RunVmCommand] (org.ovirt.thread.pool-8-thread-30) [3d619013] Exception: java.lang.NullPointerExc eption at java.util.concurrent.ConcurrentHashMap.get(ConcurrentHashMap.java:936) [rt.jar:1.8.0_91] at org.ovirt.engine.core.bll.scheduling.SchedulingManager.selectBestHost(SchedulingManager.java:434) [bll.jar:] at org.ovirt.engine.core.bll.scheduling.SchedulingManager.schedule(SchedulingManager.java:320) [bll.jar:] at org.ovirt.engine.core.bll.RunVmCommand.getVdsToRunOn(RunVmCommand.java:824) [bll.jar:] at org.ovirt.engine.core.bll.RunVmCommand.runVm(RunVmCommand.java:230) [bll.jar:] at org.ovirt.engine.core.bll.RunVmCommand.perform(RunVmCommand.java:419) [bll.jar:] at org.ovirt.engine.core.bll.RunVmCommand.executeVmCommand(RunVmCommand.java:338) [bll.jar:] at org.ovirt.engine.core.bll.VmCommand.executeCommand(VmCommand.java:111) [bll.jar:] at org.ovirt.engine.core.bll.CommandBase.executeWithoutTransaction(CommandBase.java:1304) [bll.jar:] at org.ovirt.engine.core.bll.CommandBase.executeActionInTransactionScope(CommandBase.java:1446) [bll.jar:] at org.ovirt.engine.core.bll.CommandBase.runInTransaction(CommandBase.java:2066) [bll.jar:] at org.ovirt.engine.core.utils.transaction.TransactionSupport.executeInSuppressed(TransactionSupport.java:166) [utils.jar:] at org.ovirt.engine.core.utils.transaction.TransactionSupport.executeInScope(TransactionSupport.java:105) [utils.jar:] at org.ovirt.engine.core.bll.CommandBase.execute(CommandBase.java:1485) [bll.jar:] at org.ovirt.engine.core.bll.CommandBase.executeAction(CommandBase.java:401) [bll.jar:] at org.ovirt.engine.core.bll.PrevalidatingMultipleActionsRunner.executeValidatedCommand(PrevalidatingMultipleActionsRunner.java:198) [bll.jar:] at org.ovirt.engine.core.bll.PrevalidatingMultipleActionsRunner.runCommands(PrevalidatingMultipleActionsRunner.java:170) [bll.jar:] at org.ovirt.engine.core.bll.SortedMultipleActionsRunnerBase.runCommands(SortedMultipleActionsRunnerBase.java:20) [bll.jar:] at org.ovirt.engine.core.bll.PrevalidatingMultipleActionsRunner.lambda$invokeCommands$1(PrevalidatingMultipleActionsRunner.java:176) [bll.jar:] at org.ovirt.engine.core.utils.threadpool.ThreadPoolUtil$InternalWrapperRunnable.run(ThreadPoolUtil.java:92) [utils.jar:] at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511) [rt.jar:1.8.0_91] at java.util.concurrent.FutureTask.run(FutureTask.java:266) [rt.jar:1.8.0_91] at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142) [rt.jar:1.8.0_91] at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) [rt.jar:1.8.0_91] at java.lang.Thread.run(Thread.java:745) [rt.jar:1.8.0_91] 2016-06-15 07:11:54,474 ERROR [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (org.ovirt.thread.pool-8-thread-30) [3d619013] Corre lation ID: 3d619013, Job ID: 1171195c-09b6-49cb-8c0f-e6eb3268e73f, Call Stack: null, Custom Event ID: -1, Message: Failed to run VM sedfihfe (User: admin @internal).
Saw it on master as well, running on ovirt-system-tests. Not sure why it was not marked as a regression and suggested as a blocker.
Please provide complete logs, of course.
Created attachment 1168369 [details] complete engine log
Martin, is this relevant on 4.0?
confirmed it is master-only
Target release should be placed once a package build is known to fix a issue. Since this bug is not modified, the target version has been reset. Please use target milestone to plan a fix for a oVirt release.
The fix for this issue should be included in oVirt 4.1.0 beta 1 released on December 1st. If not included please move back to modified.
Run once VM works without any troubles Verified on ovirt-engine-4.0.6.3-0.1.el7ev.noarch