[ovirt-users] Failed to run VM

Sun Mar 18 14:11:53 UTC 2018

On Sun, Mar 18, 2018 at 3:43 PM, maoz zadok <maozza at gmail.com> wrote:

> Hello All,
> I'm receiving this message every time that I try to start VM,
> any idea?
>

Seems you enounter [1] that is solved in 4.2.2.
I assume you're using 4.2.1, right? By just looking at the code, it seems
that restarting the engine could be a possible workaround for this.

[1] https://bugzilla.redhat.com/show_bug.cgi?id=1532884

>
>
>
> 2018-03-18 09:39:51,479-04 ERROR [org.ovirt.engine.core.
> vdsbroker.monitoring.VmsMonitoring] (EE-ManagedThreadFactory-engineScheduled-Thread-11)
> [] Failed during monitoring vm: 2445a47e-b102-11e6-ad11-1866da511add ,
> error is: {}: java.lang.NullPointerException
>
> 2018-03-18 09:39:51,480-04 ERROR [org.ovirt.engine.core.
> vdsbroker.monitoring.VmsMonitoring] (EE-ManagedThreadFactory-engineScheduled-Thread-11)
> [] Exception:: java.lang.NullPointerException
>
> 2018-03-18 09:39:57,802-04 INFO  [org.ovirt.engine.core.bll.RunVmCommand]
> (default task-79) [b3b98e2c-6176-413d-9d75-315626918150] Lock Acquired to
> object 'EngineLock:{exclusiveLocks='[fccee2da-92a3-4187-8aad-d6c1f9d5a3fb=VM]',
> sharedLocks=''}'
> 2018-03-18 09:39:57,822-04 INFO  [org.ovirt.engine.core.vdsbroker.
> IsVmDuringInitiatingVDSCommand] (default task-79)
> [b3b98e2c-6176-413d-9d75-315626918150] START,
> IsVmDuringInitiatingVDSCommand( IsVmDuringInitiatingVDSCommand
> Parameters:{vmId='fccee2da-92a3-4187-8aad-d6c1f9d5a3fb'}), log id:
> 2217c887
> 2018-03-18 09:39:57,822-04 INFO  [org.ovirt.engine.core.vdsbroker.
> IsVmDuringInitiatingVDSCommand] (default task-79)
> [b3b98e2c-6176-413d-9d75-315626918150] FINISH,
> IsVmDuringInitiatingVDSCommand, return: false, log id: 2217c887
> 2018-03-18 09:39:57,927-04 INFO  [org.ovirt.engine.core.bll.RunVmCommand]
> (EE-ManagedThreadFactory-engine-Thread-929544) [b3b98e2c-6176-413d-9d75-315626918150]
> Running command: RunVmCommand internal: false. Entities affected :  ID:
> fccee2da-92a3-4187-8aad-d6c1f9d5a3fb Type: VMAction group RUN_VM with
> role type USER
> 2018-03-18 09:39:57,944-04 INFO  [org.ovirt.engine.core.bll.
> scheduling.policyunits.PreferredHostsWeightPolicyUnit]
> (EE-ManagedThreadFactory-engine-Thread-929544) [b3b98e2c-6176-413d-9d75-315626918150]
> Penalizing host 'kvm5' because it is not preferred.
> 2018-03-18 09:39:57,944-04 INFO  [org.ovirt.engine.core.bll.
> scheduling.policyunits.PreferredHostsWeightPolicyUnit]
> (EE-ManagedThreadFactory-engine-Thread-929544) [b3b98e2c-6176-413d-9d75-315626918150]
> Penalizing host 'kvm6' because it is not preferred.
> 2018-03-18 09:39:57,944-04 INFO  [org.ovirt.engine.core.bll.
> scheduling.policyunits.PreferredHostsWeightPolicyUnit]
> (EE-ManagedThreadFactory-engine-Thread-929544) [b3b98e2c-6176-413d-9d75-315626918150]
> Penalizing host 'kvm7' because it is not preferred.
> 2018-03-18 09:39:57,945-04 WARN  [org.ovirt.engine.core.bll.
> scheduling.pending.PendingResourceManager] (EE-ManagedThreadFactory-engine-Thread-929544)
> [b3b98e2c-6176-413d-9d75-315626918150] Clearing stale pending resource
> org.ovirt.engine.core.bll.scheduling.pending.PendingCpuCores at 1d15d667
> (host: e044db3f-c49b-467f-9f04-21b44ffe78c4, vm: fccee2da-92a3-4187-8aad-
> d6c1f9d5a3fb)
> 2018-03-18 09:39:57,945-04 WARN  [org.ovirt.engine.core.bll.
> scheduling.pending.PendingResourceManager] (EE-ManagedThreadFactory-engine-Thread-929544)
> [b3b98e2c-6176-413d-9d75-315626918150] Clearing stale pending resource
> org.ovirt.engine.core.bll.scheduling.pending.PendingMemory at 1d15d667
> (host: e044db3f-c49b-467f-9f04-21b44ffe78c4, vm: fccee2da-92a3-4187-8aad-
> d6c1f9d5a3fb)
> 2018-03-18 09:39:57,946-04 WARN  [org.ovirt.engine.core.bll.
> scheduling.pending.PendingResourceManager] (EE-ManagedThreadFactory-engine-Thread-929544)
> [b3b98e2c-6176-413d-9d75-315626918150] Clearing stale pending resource
> org.ovirt.engine.core.bll.scheduling.pending.PendingOvercommitMemory at 1d15d667
> (host: e044db3f-c49b-467f-9f04-21b44ffe78c4, vm: fccee2da-92a3-4187-8aad-
> d6c1f9d5a3fb)
> 2018-03-18 09:39:57,946-04 WARN  [org.ovirt.engine.core.bll.
> scheduling.pending.PendingResourceManager] (EE-ManagedThreadFactory-engine-Thread-929544)
> [b3b98e2c-6176-413d-9d75-315626918150] Clearing stale pending resource
> org.ovirt.engine.core.bll.scheduling.pending.PendingVM at 1d15d667 (host:
> e044db3f-c49b-467f-9f04-21b44ffe78c4, vm: fccee2da-92a3-4187-8aad-
> d6c1f9d5a3fb)
> 2018-03-18 09:39:57,946-04 ERROR [org.ovirt.engine.core.bll.RunVmCommand]
> (EE-ManagedThreadFactory-engine-Thread-929544) [b3b98e2c-6176-413d-9d75-315626918150]
> Command 'org.ovirt.engine.core.bll.RunVmCommand' failed: null
> 2018-03-18 09:39:57,946-04 ERROR [org.ovirt.engine.core.bll.RunVmCommand]
> (EE-ManagedThreadFactory-engine-Thread-929544) [b3b98e2c-6176-413d-9d75-315626918150]
> Exception: java.lang.NullPointerException
>     at org.ovirt.engine.core.vdsbroker.monitoring.HostMonitoring.
> refreshCommitedMemory(HostMonitoring.java:687) [vdsbroker.jar:]
>     at org.ovirt.engine.core.vdsbroker.VdsManager.
> updatePendingData(VdsManager.java:476) [vdsbroker.jar:]
>     at org.ovirt.engine.core.bll.scheduling.pending.
> PendingResourceManager.notifyHostManagers(PendingResourceManager.java:227)
> [bll.jar:]
>     at org.ovirt.engine.core.bll.scheduling.SchedulingManager.
> schedule(SchedulingManager.java:361) [bll.jar:]
>     at org.ovirt.engine.core.bll.RunVmCommand.getVdsToRunOn(RunVmCommand.java:878)
> [bll.jar:]
>     at org.ovirt.engine.core.bll.RunVmCommand.runVm(RunVmCommand.java:266)
> [bll.jar:]
>     at org.ovirt.engine.core.bll.RunVmCommand.perform(RunVmCommand.java:440)
> [bll.jar:]
>     at org.ovirt.engine.core.bll.RunVmCommand.executeVmCommand(RunVmCommand.java:365)
> [bll.jar:]
>     at org.ovirt.engine.core.bll.VmCommand.executeCommand(VmCommand.java:147)
> [bll.jar:]
>     at org.ovirt.engine.core.bll.CommandBase.executeWithoutTransaction(CommandBase.java:1205)
> [bll.jar:]
>     at org.ovirt.engine.core.bll.CommandBase.
> executeActionInTransactionScope(CommandBase.java:1345) [bll.jar:]
>     at org.ovirt.engine.core.bll.CommandBase.runInTransaction(CommandBase.java:1987)
> [bll.jar:]
>     at org.ovirt.engine.core.utils.transaction.TransactionSupport.
> executeInSuppressed(TransactionSupport.java:164) [utils.jar:]
>     at org.ovirt.engine.core.utils.transaction.TransactionSupport.
> executeInScope(TransactionSupport.java:103) [utils.jar:]
>     at org.ovirt.engine.core.bll.CommandBase.execute(CommandBase.java:1405)
> [bll.jar:]
>     at org.ovirt.engine.core.bll.CommandBase.executeAction(CommandBase.java:412)
> [bll.jar:]
>     at org.ovirt.engine.core.bll.PrevalidatingMultipleActionsRu
> nner.executeValidatedCommand(PrevalidatingMultipleActionsRunner.java:204)
> [bll.jar:]
>     at org.ovirt.engine.core.bll.PrevalidatingMultipleActionsRu
> nner.runCommands(PrevalidatingMultipleActionsRunner.java:176) [bll.jar:]
>     at org.ovirt.engine.core.bll.SortedMultipleActionsRunnerBas
> e.runCommands(SortedMultipleActionsRunnerBase.java:20) [bll.jar:]
>     at org.ovirt.engine.core.bll.PrevalidatingMultipleActionsRu
> nner.lambda$invokeCommands$3(PrevalidatingMultipleActionsRunner.java:182)
> [bll.jar:]
>     at org.ovirt.engine.core.utils.threadpool.ThreadPoolUtil$
> InternalWrapperRunnable.run(ThreadPoolUtil.java:96) [utils.jar:]
>     at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
> [rt.jar:1.8.0_161]
>     at java.util.concurrent.FutureTask.run(FutureTask.java:266)
> [rt.jar:1.8.0_161]
>     at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
> [rt.jar:1.8.0_161]
>     at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
> [rt.jar:1.8.0_161]
>     at java.lang.Thread.run(Thread.java:748) [rt.jar:1.8.0_161]
>     at org.glassfish.enterprise.concurrent.ManagedThreadFactoryImpl$
> ManagedThread.run(ManagedThreadFactoryImpl.java:250)
> [javax.enterprise.concurrent-1.0.jar:]
>     at org.jboss.as.ee.concurrent.service.ElytronManagedThreadFactory$
> ElytronManagedThread.run(ElytronManagedThreadFactory.java:78)
>
> 2018-03-18 09:39:57,969-04 ERROR [org.ovirt.engine.core.dal.
> dbbroker.auditloghandling.AuditLogDirector] (EE-ManagedThreadFactory-engine-Thread-929544)
> [b3b98e2c-6176-413d-9d75-315626918150] EVENT_ID: USER_FAILED_RUN_VM(54),
> Failed to run VM centos7-template (User: admin at internal-authz).
> 2018-03-18 09:39:57,976-04 INFO  [org.ovirt.engine.core.bll.RunVmCommand]
> (EE-ManagedThreadFactory-engine-Thread-929544) [b3b98e2c-6176-413d-9d75-315626918150]
> Lock freed to object 'EngineLock:{exclusiveLocks='[
> fccee2da-92a3-4187-8aad-d6c1f9d5a3fb=VM]', sharedLocks=''}'
>
>
> _______________________________________________
> Users mailing list
> Users at ovirt.org
> http://lists.ovirt.org/mailman/listinfo/users
>
>
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://lists.ovirt.org/pipermail/users/attachments/20180318/0cc35b7d/attachment.html>