Bug 1346669 - Can't start a VM (NPE around scheduling.SchedulingManager.selectBestHost(SchedulingManager.java:434) )
Summary: Can't start a VM (NPE around scheduling.SchedulingManager.selectBestHost(Sche...
Keywords:
Status: CLOSED CURRENTRELEASE
Alias: None
Product: ovirt-engine
Classification: oVirt
Component: BLL.Virt
Version: 4.1.0
Hardware: Unspecified
OS: Unspecified
urgent
urgent
Target Milestone: ovirt-4.1.0-alpha
: 4.1.0
Assignee: Martin Sivák
QA Contact: Artyom
URL:
Whiteboard:
Depends On:
Blocks:
TreeView+ depends on / blocked
 
Reported: 2016-06-15 07:28 UTC by Petr Matyáš
Modified: 2017-02-01 14:38 UTC (History)
7 users (show)

Fixed In Version:
Doc Type: No Doc Update
Doc Text:
undefined
Clone Of:
Environment:
Last Closed: 2017-02-01 14:38:48 UTC
oVirt Team: SLA
Embargoed:
rule-engine: ovirt-4.1+
rule-engine: blocker+
rule-engine: planning_ack+
dfediuck: devel_ack+
gklein: testing_ack+


Attachments (Terms of Use)
complete engine log (4.96 MB, text/plain)
2016-06-15 12:58 UTC, Petr Matyáš
no flags Details


Links
System ID Private Priority Status Summary Last Updated
oVirt gerrit 59250 0 master MERGED scheduling: Handle empty selector and add selector validation 2016-06-23 08:32:33 UTC

Description Petr Matyáš 2016-06-15 07:28:55 UTC
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).

Comment 1 Yaniv Kaul 2016-06-15 09:22:44 UTC
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.

Comment 2 Yaniv Kaul 2016-06-15 09:23:25 UTC
Please provide complete logs, of course.

Comment 3 Petr Matyáš 2016-06-15 12:58:11 UTC
Created attachment 1168369 [details]
complete engine log

Comment 4 Michal Skrivanek 2016-06-15 13:06:39 UTC
Martin, is this relevant on 4.0?

Comment 5 Michal Skrivanek 2016-06-15 13:23:02 UTC
confirmed it is master-only

Comment 6 Red Hat Bugzilla Rules Engine 2016-06-16 11:50:26 UTC
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.

Comment 7 Sandro Bonazzola 2016-12-12 13:57:44 UTC
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.

Comment 8 Artyom 2016-12-22 09:44:54 UTC
Run once VM works without any troubles

Verified on ovirt-engine-4.0.6.3-0.1.el7ev.noarch


Note You need to log in before you can comment on or make changes to this bug.