Re: [ovirt-users] Failed to run VM

On Sun, Mar 18, 2018 at 3:43 PM, maoz zadok <maozza(a)gmail.com&gt; 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.vdsbrok > er.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.vdsbrok > er.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.sch > eduling.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.sch > eduling.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.sch > eduling.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.sch > eduling.pending.PendingResourceManager] (EE-ManagedThreadFactory-engine-Thread-929544) > [b3b98e2c-6176-413d-9d75-315626918150] Clearing stale pending resource > org.ovirt.engine.core.bll.scheduling.pending.PendingCpuCores@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.sch > eduling.pending.PendingResourceManager] (EE-ManagedThreadFactory-engine-Thread-929544) > [b3b98e2c-6176-413d-9d75-315626918150] Clearing stale pending resource > org.ovirt.engine.core.bll.scheduling.pending.PendingMemory@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.sch > eduling.pending.PendingResourceManager] (EE-ManagedThreadFactory-engine-Thread-929544) > [b3b98e2c-6176-413d-9d75-315626918150] Clearing stale pending resource > org.ovirt.engine.core.bll.scheduling.pending.PendingOvercomm > itMemory@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.sch > eduling.pending.PendingResourceManager] (EE-ManagedThreadFactory-engine-Thread-929544) > [b3b98e2c-6176-413d-9d75-315626918150] Clearing stale pending resource > org.ovirt.engine.core.bll.scheduling.pending.PendingVM@1d15d667 (host: > e044db3f-c49b-467f-9f04-21b44ffe78c4, vm: fccee2da-92a3-4187-8aad-d6c1f9 > d5a3fb) > 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.re > freshCommitedMemory(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.PendingResource > Manager.notifyHostManagers(PendingResourceManager.java:227) [bll.jar:] > at org.ovirt.engine.core.bll.scheduling.SchedulingManager.sched > ule(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.executeActionInTransac > tionScope(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.e > xecuteInSuppressed(TransactionSupport.java:164) [utils.jar:] > at org.ovirt.engine.core.utils.transaction.TransactionSupport.e > xecuteInScope(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.PrevalidatingMultipleActionsRunner > .executeValidatedCommand(PrevalidatingMultipleActionsRunner.java:204) > [bll.jar:] > at org.ovirt.engine.core.bll.PrevalidatingMultipleActionsRunner > .runCommands(PrevalidatingMultipleActionsRunner.java:176) [bll.jar:] > at org.ovirt.engine.core.bll.SortedMultipleActionsRunnerBase. > runCommands(SortedMultipleActionsRunnerBase.java:20) [bll.jar:] > at org.ovirt.engine.core.bll.PrevalidatingMultipleActionsRunner > .lambda$invokeCommands$3(PrevalidatingMultipleActionsRunner.java:182) > [bll.jar:] > at org.ovirt.engine.core.utils.threadpool.ThreadPoolUtil$Intern > alWrapperRunnable.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.ElytronManagedThreadFacto > ry$ElytronManagedThread.run(ElytronManagedThreadFactory.java:78) > > 2018-03-18 09:39:57,969-04 ERROR [org.ovirt.engine.core.dal.dbb > roker.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@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(a)ovirt.org > http://lists.ovirt.org/mailman/listinfo/users > >