Bug 823257

Summary: NPE during template creation from a vm
Product: Red Hat Enterprise Virtualization Manager Reporter: Rami Vaknin <rvaknin>
Component: ovirt-engineAssignee: lpeer <lpeer>
Status: CLOSED DUPLICATE QA Contact:
Severity: high Docs Contact:
Priority: unspecified    
Version: 3.1.0CC: amureini, dyasny, iheim, lpeer, Rhev-m-bugs, yeylon, ykaul
Target Milestone: ---   
Target Release: 3.1.0   
Hardware: Unspecified   
OS: Unspecified   
Whiteboard: storage
Fixed In Version: Doc Type: Bug Fix
Doc Text:
Story Points: ---
Clone Of: Environment:
Last Closed: 2012-05-20 11:41:34 UTC Type: Bug
Regression: --- Mount Type: ---
Documentation: --- CRM:
Verified Versions: Category: ---
oVirt Team: Storage RHEL 7.3 requirements from Atomic Host:
Cloudforms Team: --- Target Upstream Version:
Embargoed:

Description Rami Vaknin 2012-05-20 11:34:42 UTC
Version:
rhevm-3.1.0_0001-9.el6ev.x86_64

Scenario:
1. I've installed winxp on vm with one 2GB virtio disk
2. I've created template from that vm

Result:
Template creation failed due to NullPointerException

2012-05-20 17:23:45,519 INFO  [org.ovirt.engine.core.bll.CreateImageTemplateCommand] (ajp--0.0.0.0-8009-9) [632e61cd] Running command: CreateImageTemplateCommand internal: true. Entities affected :  ID: f43c05c7-4bd9-4bda-a6ae-86d930f77c0f Type: Storage
2012-05-20 17:23:45,535 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.GetImageInfoVDSCommand] (ajp--0.0.0.0-8009-9) [632e61cd] START, GetImageInfoVDSCommand(storagePoolId = a18a5388-55fb-4a46-a316-22730256dc16, ignoreFailoverLimit = false, compatabilityVersion = null, storageDomainId = f43c05c7-4bd9-4bda-a6ae-86d930f77c0f, imageGroupId = 4c38fddf-2edc-4927-966a-d03d296334b5, imageId = 6c108bfe-87dc-4bba-8040-2c01a7de4c81), log id: 231f6deb
2012-05-20 17:23:46,103 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.GetImageInfoVDSCommand] (ajp--0.0.0.0-8009-9) [632e61cd] FINISH, GetImageInfoVDSCommand, return: org.ovirt.engine.core.common.businessentities.DiskImage@4fe92ed3, log id: 231f6deb
2012-05-20 17:23:46,149 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.CopyImageVDSCommand] (ajp--0.0.0.0-8009-9) [632e61cd] START, CopyImageVDSCommand(storagePoolId = a18a5388-55fb-4a46-a316-22730256dc16, ignoreFailoverLimit = false, compatabilityVersion = 3.1, storageDomainId = f43c05c7-4bd9-4bda-a6ae-86d930f77c0f, imageGroupId = 4c38fddf-2edc-4927-966a-d03d296334b5, imageId = 6c108bfe-87dc-4bba-8040-2c01a7de4c81, dstImageGroupId = e9015f04-23fb-4e0b-b7ae-0f4587e85616, vmId = 0cc3eb18-c6e4-4d6e-86f5-45b2f846530d, dstImageId = bacf428b-f8cf-4320-8aaf-9418472280fc, imageDescription = Active VM, dstStorageDomainId = f43c05c7-4bd9-4bda-a6ae-86d930f77c0f, copyVolumeType = SharedVol, volumeFormat = COW, preallocate = Sparse, postZero = false, force = false), log id: 3c26d7e7
2012-05-20 17:23:46,160 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.CopyImageVDSCommand] (ajp--0.0.0.0-8009-9) [632e61cd] -- CopyImageVDSCommand::ExecuteIrsBrokerCommand: calling 'copyImage' with two new parameters: description and UUID
2012-05-20 17:23:46,163 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.CopyImageVDSCommand] (ajp--0.0.0.0-8009-9) [632e61cd] -- copyImage parameters:
2012-05-20 17:23:47,206 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.CopyImageVDSCommand] (ajp--0.0.0.0-8009-9) [632e61cd] FINISH, CopyImageVDSCommand, return: 00000000-0000-0000-0000-000000000000, log id: 3c26d7e7
2012-05-20 17:23:47,220 INFO  [org.ovirt.engine.core.bll.SPMAsyncTask] (ajp--0.0.0.0-8009-9) [632e61cd] BaseAsyncTask::AddOrUpdateTaskInDB: Adding task f2765175-f54a-4636-b9f1-2b17a0fc77e9 to DataBase
2012-05-20 17:23:47,243 INFO  [org.ovirt.engine.core.bll.EntityAsyncTask] (ajp--0.0.0.0-8009-9) [632e61cd] EntityAsyncTask::Adding EntityMultiAsyncTasks object for entity e0e11a27-1243-48af-9bbd-3fd9240d161b
2012-05-20 17:23:47,245 INFO  [org.ovirt.engine.core.bll.EntityMultiAsyncTasks] (ajp--0.0.0.0-8009-9) [632e61cd] EntityMultiAsyncTasks::AttachTask: Attaching task f2765175-f54a-4636-b9f1-2b17a0fc77e9 to entity e0e11a27-1243-48af-9bbd-3fd9240d161b.
2012-05-20 17:23:47,249 INFO  [org.ovirt.engine.core.bll.AsyncTaskManager] (ajp--0.0.0.0-8009-9) [632e61cd] AsyncTaskManager::AddTaskToManager: Adding task f2765175-f54a-4636-b9f1-2b17a0fc77e9 (Parent Command AddVmTemplate, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters), polling hasn't started yet..
2012-05-20 17:23:47,316 ERROR [org.ovirt.engine.core.bll.CreateImageTemplateCommand] (ajp--0.0.0.0-8009-9) [632e61cd] Transaction rolled-back for command: org.ovirt.engine.core.bll.CreateImageTemplateCommand.
2012-05-20 17:23:47,318 INFO  [org.ovirt.engine.core.utils.transaction.TransactionSupport] (ajp--0.0.0.0-8009-9) [632e61cd] transaction rolled back
2012-05-20 17:23:47,320 ERROR [org.ovirt.engine.core.bll.AddVmTemplateCommand] (ajp--0.0.0.0-8009-9) [632e61cd] Command org.ovirt.engine.core.bll.AddVmTemplateCommand throw exception: java.lang.NullPointerException
        at org.ovirt.engine.core.bll.utils.VmDeviceUtils.copyVmDevices(VmDeviceUtils.java:119) [engine-bll.jar:]
        at org.ovirt.engine.core.bll.AddVmTemplateCommand$2.runInTransaction(AddVmTemplateCommand.java:133) [engine-bll.jar:]
        at org.ovirt.engine.core.bll.AddVmTemplateCommand$2.runInTransaction(AddVmTemplateCommand.java:126) [engine-bll.jar:]
        at org.ovirt.engine.core.utils.transaction.TransactionSupport.executeInNewTransaction(TransactionSupport.java:204) [engine-utils.jar:]
        at org.ovirt.engine.core.bll.AddVmTemplateCommand.executeCommand(AddVmTemplateCommand.java:126) [engine-bll.jar:]
        at org.ovirt.engine.core.bll.CommandBase.ExecuteWithoutTransaction(CommandBase.java:745) [engine-bll.jar:]
        at org.ovirt.engine.core.bll.CommandBase.executeActionInTransactionScope(CommandBase.java:837) [engine-bll.jar:]
        at org.ovirt.engine.core.bll.CommandBase.runInTransaction(CommandBase.java:1146) [engine-bll.jar:]
        at org.ovirt.engine.core.utils.transaction.TransactionSupport.executeInSuppressed(TransactionSupport.java:168) [engine-utils.jar:]
        at org.ovirt.engine.core.utils.transaction.TransactionSupport.executeInScope(TransactionSupport.java:107) [engine-utils.jar:]
        at org.ovirt.engine.core.bll.CommandBase.Execute(CommandBase.java:852) [engine-bll.jar:]
        at org.ovirt.engine.core.bll.CommandBase.ExecuteAction(CommandBase.java:259) [engine-bll.jar:]
        at org.ovirt.engine.core.bll.Backend.runActionImpl(Backend.java:327) [engine-bll.jar:]
        at org.ovirt.engine.core.bll.Backend.RunAction(Backend.java:287) [engine-bll.jar:]
        at sun.reflect.GeneratedMethodAccessor172.invoke(Unknown Source) [:1.6.0_24]
        at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) [rt.jar:1.6.0_24]
        at java.lang.reflect.Method.invoke(Method.java:616) [rt.jar:1.6.0_24]
        at org.jboss.as.ee.component.ManagedReferenceMethodInterceptorFactory$ManagedReferenceMethodInterceptor.processInvocation(ManagedReferenceMethodInterceptorFactory.java:72) [jboss-as-ee.jar:7.1.1.Final-redhat-1]
        at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation.jar:1.1.1.Final-redhat-1]
        at org.jboss.invocation.InterceptorContext$Invocation.proceed(InterceptorContext.java:374) [jboss-invocation.jar:1.1.1.Final-redhat-1]
        at org.ovirt.engine.core.utils.ThreadLocalSessionCleanerInterceptor.injectWebContextToThreadLocal(ThreadLocalSessionCleanerInterceptor.java:11) [engine-utils.jar:]
        at sun.reflect.GeneratedMethodAccessor25.invoke(Unknown Source) [:1.6.0_24]
        at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) [rt.jar:1.6.0_24]
        at java.lang.reflect.Method.invoke(Method.java:616) [rt.jar:1.6.0_24]
        at org.jboss.as.ee.component.ManagedReferenceLifecycleMethodInterceptorFactory$ManagedReferenceLifecycleMethodInterceptor.processInvocation(ManagedReferenceLifecycleMethodInterceptorFactory.java:123) [jboss-as-ee.jar:7.1.1.Final-
redhat-1]
        at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation.jar:1.1.1.Final-redhat-1]
        at org.jboss.invocation.WeavedInterceptor.processInvocation(WeavedInterceptor.java:53) [jboss-invocation.jar:1.1.1.Final-redhat-1]
        at org.jboss.as.ee.component.interceptors.UserInterceptorFactory$1.processInvocation(UserInterceptorFactory.java:36) [jboss-as-ee.jar:7.1.1.Final-redhat-1]
        at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation.jar:1.1.1.Final-redhat-1]
        at org.jboss.invocation.InitialInterceptor.processInvocation(InitialInterceptor.java:21) [jboss-invocation.jar:1.1.1.Final-redhat-1]
        at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation.jar:1.1.1.Final-redhat-1]
        at org.jboss.invocation.ChainedInterceptor.processInvocation(ChainedInterceptor.java:61) [jboss-invocation.jar:1.1.1.Final-redhat-1]
        at org.jboss.as.ee.component.interceptors.ComponentDispatcherInterceptor.processInvocation(ComponentDispatcherInterceptor.java:53) [jboss-as-ee.jar:7.1.1.Final-redhat-1]
        at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation.jar:1.1.1.Final-redhat-1]
        at org.jboss.as.ejb3.component.singleton.SingletonComponentInstanceAssociationInterceptor.processInvocation(SingletonComponentInstanceAssociationInterceptor.java:53) [jboss-as-ejb3.jar:7.1.1.Final-redhat-1]
        at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation.jar:1.1.1.Final-redhat-1]
        at org.jboss.as.ejb3.tx.CMTTxInterceptor.invokeInNoTx(CMTTxInterceptor.java:211) [jboss-as-ejb3.jar:7.1.1.Final-redhat-1]
        at org.jboss.as.ejb3.tx.CMTTxInterceptor.supports(CMTTxInterceptor.java:363) [jboss-as-ejb3.jar:7.1.1.Final-redhat-1]
        at org.jboss.as.ejb3.tx.CMTTxInterceptor.processInvocation(CMTTxInterceptor.java:194) [jboss-as-ejb3.jar:7.1.1.Final-redhat-1]
        at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation.jar:1.1.1.Final-redhat-1]
        at org.jboss.as.ejb3.component.interceptors.CurrentInvocationContextInterceptor.processInvocation(CurrentInvocationContextInterceptor.java:41) [jboss-as-ejb3.jar:7.1.1.Final-redhat-1]
        at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation.jar:1.1.1.Final-redhat-1]
        at org.jboss.as.ejb3.component.interceptors.LoggingInterceptor.processInvocation(LoggingInterceptor.java:59) [jboss-as-ejb3.jar:7.1.1.Final-redhat-1]
        at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation.jar:1.1.1.Final-redhat-1]
        at org.jboss.as.ee.component.NamespaceContextInterceptor.processInvocation(NamespaceContextInterceptor.java:50) [jboss-as-ee.jar:7.1.1.Final-redhat-1]
        at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation.jar:1.1.1.Final-redhat-1]
        at org.jboss.as.ejb3.component.interceptors.AdditionalSetupInterceptor.processInvocation(AdditionalSetupInterceptor.java:32) [jboss-as-ejb3.jar:7.1.1.Final-redhat-1]
        at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation.jar:1.1.1.Final-redhat-1]
        at org.jboss.as.ee.component.TCCLInterceptor.processInvocation(TCCLInterceptor.java:45) [jboss-as-ee.jar:7.1.1.Final-redhat-1]
        at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation.jar:1.1.1.Final-redhat-1]
        at org.jboss.invocation.ChainedInterceptor.processInvocation(ChainedInterceptor.java:61) [jboss-invocation.jar:1.1.1.Final-redhat-1]
        at org.jboss.as.ee.component.ViewService$View.invoke(ViewService.java:165) [jboss-as-ee.jar:7.1.1.Final-redhat-1]
        at org.jboss.as.ee.component.ViewDescription$1.processInvocation(ViewDescription.java:173) [jboss-as-ee.jar:7.1.1.Final-redhat-1]
        at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation.jar:1.1.1.Final-redhat-1]
        at org.jboss.invocation.ChainedInterceptor.processInvocation(ChainedInterceptor.java:61) [jboss-invocation.jar:1.1.1.Final-redhat-1]
        at org.jboss.as.ee.component.ProxyInvocationHandler.invoke(ProxyInvocationHandler.java:72) [jboss-as-ee.jar:7.1.1.Final-redhat-1]
        at org.ovirt.engine.core.common.interfaces.BackendLocal$$$view9.RunAction(Unknown Source) [engine-common.jar:]
        at org.ovirt.engine.ui.frontend.server.gwt.GenericApiGWTServiceImpl.RunAction(GenericApiGWTServiceImpl.java:120)
        at sun.reflect.GeneratedMethodAccessor171.invoke(Unknown Source) [:1.6.0_24]
        at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) [rt.jar:1.6.0_24]
        at java.lang.reflect.Method.invoke(Method.java:616) [rt.jar:1.6.0_24]
        at com.google.gwt.rpc.server.RPC.invokeAndStreamResponse(RPC.java:196)
        at com.google.gwt.rpc.server.RpcServlet.processCall(RpcServlet.java:161)
        at com.google.gwt.rpc.server.RpcServlet.processPost(RpcServlet.java:222)
        at com.google.gwt.user.server.rpc.AbstractRemoteServiceServlet.doPost(AbstractRemoteServiceServlet.java:62)
        at javax.servlet.http.HttpServlet.service(HttpServlet.java:754) [jboss-servlet-api_3.0_spec.jar:1.0.0.Final-redhat-1]
        at javax.servlet.http.HttpServlet.service(HttpServlet.java:847) [jboss-servlet-api_3.0_spec.jar:1.0.0.Final-redhat-1]
        at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:329)
        at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:248)
        at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:275)
        at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:161)
        at org.jboss.as.web.security.SecurityContextAssociationValve.invoke(SecurityContextAssociationValve.java:153)
        at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:155)
        at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:102)
        at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:109)
        at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:368)
        at org.apache.coyote.ajp.AjpProcessor.process(AjpProcessor.java:505)
        at org.apache.coyote.ajp.AjpProtocol$AjpConnectionHandler.process(AjpProtocol.java:445)
        at org.apache.tomcat.util.net.JIoEndpoint$Worker.run(JIoEndpoint.java:930)
        at java.lang.Thread.run(Thread.java:679) [rt.jar:1.6.0_24]

2012-05-20 17:23:47,388 INFO  [org.ovirt.engine.core.bll.AddVmTemplateCommand] (ajp--0.0.0.0-8009-9) [632e61cd] Command [id=723212c5-672e-4b1e-897b-e59c24cc7097]: Compensating NEW_ENTITY_ID of org.ovirt.engine.core.common.businessentities.VmTemplate; snapshot: e0e11a27-1243-48af-9bbd-3fd9240d161b.
2012-05-20 17:23:47,400 INFO  [org.ovirt.engine.core.bll.AddVmTemplateCommand] (ajp--0.0.0.0-8009-9) [632e61cd] Command [id=723212c5-672e-4b1e-897b-e59c24cc7097]: Compensating CHANGED_STATUS_ONLY of org.ovirt.engine.core.common.businessentities.VmDynamic; snapshot: EntityStatusSnapshot [id=0cc3eb18-c6e4-4d6e-86f5-45b2f846530d, status=Down].
2012-05-20 17:23:47,420 INFO  [org.ovirt.engine.core.bll.AsyncTaskManager] (Rollback-pool-10-thread-45) [632e61cd] AsyncTaskManager::CancelTask: Attempting to cancel task f2765175-f54a-4636-b9f1-2b17a0fc77e9.
2012-05-20 17:23:47,423 INFO  [org.ovirt.engine.core.bll.SPMAsyncTask] (Rollback-pool-10-thread-45) [632e61cd] SPMAsyncTask::StopTask: Attempting to stop task f2765175-f54a-4636-b9f1-2b17a0fc77e9 (Parent Command AddVmTemplate, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters).
2012-05-20 17:23:47,428 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.SPMStopTaskVDSCommand] (Rollback-pool-10-thread-45) [632e61cd] START, SPMStopTaskVDSCommand(storagePoolId = a18a5388-55fb-4a46-a316-22730256dc16, ignoreFailoverLimit = false, compatabilityVersion = null, taskId = f2765175-f54a-4636-b9f1-2b17a0fc77e9), log id: 496ffe3e
2012-05-20 17:23:47,437 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMStopTaskVDSCommand] (Rollback-pool-10-thread-45) [632e61cd] START, HSMStopTaskVDSCommand(vdsId = 20cc0430-9ebc-11e1-9084-9f5e12e5069e, taskId=f2765175-f54a-4636-b9f1-2b17a0fc77e9), log id: 15d9316a
2012-05-20 17:23:47,472 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMStopTaskVDSCommand] (Rollback-pool-10-thread-45) [632e61cd] FINISH, HSMStopTaskVDSCommand, log id: 15d9316a
2012-05-20 17:23:47,474 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.SPMStopTaskVDSCommand] (Rollback-pool-10-thread-45) [632e61cd] FINISH, SPMStopTaskVDSCommand, log id: 496ffe3e
2012-05-20 17:23:47,479 INFO  [org.ovirt.engine.core.bll.EntityMultiAsyncTasks] (Rollback-pool-10-thread-45) [632e61cd] EntityMultiAsyncTasks::StartPollingTask: Current Action Type for entity e0e11a27-1243-48af-9bbd-3fd9240d161b is AddVmTemplate (determined by task f2765175-f54a-4636-b9f1-2b17a0fc77e9)
2012-05-20 17:24:02,239 ERROR [org.ovirt.engine.core.bll.CreateImageTemplateCommand] (pool-10-thread-40) [632e61cd] Ending command with failure: org.ovirt.engine.core.bll.CreateImageTemplateCommand

Comment 1 Yaniv Kaul 2012-05-20 11:41:34 UTC
Dup of 813199 ?

*** This bug has been marked as a duplicate of bug 813199 ***