<div dir="ltr"><div>Hi Ala,</div><div>When running Live Merge.<br>I create the live snapshot and when I try delete snapshot and has this error.<br>I saw the bug <a href="https://bugzilla.redhat.com/1368203">https://bugzilla.redhat.com/1368203</a> , but I not move the disk to another domain.</div><div>Thanks.</div><div><br></div><div>Marcelo Leandro</div></div><div class="gmail_extra"><br><div class="gmail_quote">2016-09-28 9:19 GMT-03:00 Ala Hino <span dir="ltr"><<a href="mailto:ahino@redhat.com" target="_blank">ahino@redhat.com</a>></span>:<br><blockquote class="gmail_quote" style="margin:0 0 0 .8ex;border-left:1px #ccc solid;padding-left:1ex"><div dir="ltr">Hi Marcelo,<div><br></div><div>This error indicates that the image you are trying to delete doesn't exist.</div><div>When do you get this error? When running Live Merge or Live Storage Migration (LSM)?</div><div><br></div><div>Please note that we fixed in LSM area where the VM went down while we tried to delete the auto-generated snapshot. See <a href="https://bugzilla.redhat.com/1368203" target="_blank">https://bugzilla.redhat.<wbr>com/1368203</a> .</div><span class="HOEnZb"><font color="#888888"><div><br></div><div>-Ala</div></font></span></div><div class="HOEnZb"><div class="h5"><div class="gmail_extra"><br><div class="gmail_quote">On Wed, Sep 28, 2016 at 3:07 PM, Marcelo Leandro <span dir="ltr"><<a href="mailto:marceloltmm@gmail.com" target="_blank">marceloltmm@gmail.com</a>></span> wrote:<br><blockquote class="gmail_quote" style="margin:0 0 0 .8ex;border-left:1px #ccc solid;padding-left:1ex"><div dir="ltr"><div>Hello, I have the same problem but i use the ovirt version 4.0.4.4-1.el7.centos .<div><br></div></div><div>My logs.</div><div><br></div><div><br></div><div>Engine.log<br></div><div><br></div><div>2016-09-28 08:18:00,947 INFO [org.ovirt.engine.core.vdsbrok<wbr>er.monitoring.VmJobsMonitoring<wbr>] (DefaultQuartzScheduler1) [7013b545] VM Job [4dd2b885-2452-4520-b20a-928ed<wbr>ea50836]: In progress (no change)<br>2016-09-28 08:18:08,010 INFO [org.ovirt.engine.core.bll.sna<wbr>pshots.RemoveSnapshotCommand] (default task-54) [5a27e364] Lock Acquired to object 'EngineLock:{exclusiveLocks='[<wbr>eb73a967-1908-46e9-9de2-9706bf<wbr>29643a=<VM, ACTION_TYPE_FAILED_OBJECT_LOCK<wbr>ED>]', sharedLocks='null'}'<br>2016-09-28 08:18:09,169 INFO [org.ovirt.engine.core.bll.sna<wbr>pshots.RemoveSnapshotCommand] (default task-54) [5a27e364] Running command: RemoveSnapshotCommand internal: false. Entities affected : ID: eb73a967-1908-46e9-9de2-9706bf<wbr>29643a Type: VMAction group MANIPULATE_VM_SNAPSHOTS with role type USER<br>2016-09-28 08:18:09,185 INFO [org.ovirt.engine.core.bll.sna<wbr>pshots.RemoveSnapshotCommand] (default task-54) [5a27e364] Lock freed to object 'EngineLock:{exclusiveLocks='[<wbr>eb73a967-1908-46e9-9de2-9706bf<wbr>29643a=<VM, ACTION_TYPE_FAILED_OBJECT_LOCK<wbr>ED>]', sharedLocks='null'}'<br>2016-09-28 08:18:09,265 INFO [org.ovirt.engine.core.bll.sna<wbr>pshots.RemoveSnapshotSingleDis<wbr>kLiveCommand] (pool-7-thread-3) [354939e9] Running command: RemoveSnapshotSingleDiskLiveCo<wbr>mmand internal: true. Entities affected : ID: 00000000-0000-0000-0000-000000<wbr>000000 Type: Storage<br>2016-09-28 08:18:09,302 INFO [org.ovirt.engine.core.dal.dbb<wbr>roker.auditloghandling.AuditLo<wbr>gDirector] (default task-54) [] Correlation ID: 5a27e364, Job ID: 661f8f55-30c6-4735-bb0a-fdcd3a<wbr>c4004a, Call Stack: null, Custom Event ID: -1, Message: Snapshot 'Backup the VM' deletion for VM 'SRV-ActPrint' was initiated by admin@internal.<br>2016-09-28 08:18:10,197 INFO [org.ovirt.engine.core.bll.sna<wbr>pshots.RemoveSnapshotSingleDis<wbr>kLiveCommand] (DefaultQuartzScheduler4) [354939e9] Executing Live Merge command step 'EXTEND'<br>2016-09-28 08:18:10,254 INFO [org.ovirt.engine.core.bll.Mer<wbr>geExtendCommand] (pool-7-thread-7) [57c94fc3] Running command: MergeExtendCommand internal: true. Entities affected : ID: 6e5cce71-3438-4045-9d54-607123<wbr>e0557e Type: Storage<br>2016-09-28 08:18:10,255 INFO [org.ovirt.engine.core.bll.Mer<wbr>geExtendCommand] (pool-7-thread-7) [57c94fc3] Refreshing volume c08d86ed-46f1-44bc-9476-0cc2c6<wbr>aed367 on host f22d87b9-4449-4a71-8529-58095d<wbr>d81b6f<br>2016-09-28 08:18:10,275 INFO [org.ovirt.engine.core.bll.Ref<wbr>reshVolumeCommand] (pool-7-thread-7) [47625ba4] Running command: RefreshVolumeCommand internal: true.<br>2016-09-28 08:18:10,275 INFO [org.ovirt.engine.core.vdsbrok<wbr>er.vdsbroker.RefreshVolumeVDSC<wbr>ommand] (pool-7-thread-7) [47625ba4] START, RefreshVolumeVDSCommand(HostNa<wbr>me = Host04, RefreshVolumeVDSCommandParamet<wbr>ers:{runAsync='true', hostId='f22d87b9-4449-4a71-852<wbr>9-58095dd81b6f', storagePoolId='77e24b20-9d21-4<wbr>952-a089-3c5c592b4e6d', storageDomainId='6e5cce71-3438<wbr>-4045-9d54-607123e0557e', imageGroupId='9fc0b2f6-d786-4a<wbr>21-8f5c-b22b23df4aaa', imageId='c08d86ed-46f1-44bc-94<wbr>76-0cc2c6aed367'}), log id: 77b9ded4<br>2016-09-28 08:18:11,245 INFO [org.ovirt.engine.core.bll.Con<wbr>currentChildCommandsExecutionC<wbr>allback] (DefaultQuartzScheduler8) [354939e9] Command 'RemoveSnapshot' (id: '18613dc9-d8c8-45c4-9fbe-a298e<wbr>701ead5') waiting on child command id: 'fd866748-3211-4d48-9908-12eb6<wbr>078a69e' type:'RemoveSnapshotSingleDisk<wbr>Live' to complete<br>2016-09-28 08:18:11,810 INFO [org.ovirt.engine.core.vdsbrok<wbr>er.vdsbroker.RefreshVolumeVDSC<wbr>ommand] (pool-7-thread-7) [47625ba4] FINISH, RefreshVolumeVDSCommand, log id: 77b9ded4<br>2016-09-28 08:18:11,810 INFO [org.ovirt.engine.core.bll.Ref<wbr>reshVolumeCommand] (pool-7-thread-7) [47625ba4] Successfully refreshed volume 'c08d86ed-46f1-44bc-9476-0cc2c<wbr>6aed367' on host 'f22d87b9-4449-4a71-8529-58095<wbr>dd81b6f'<br>2016-09-28 08:18:12,267 INFO [org.ovirt.engine.core.bll.sna<wbr>pshots.RemoveSnapshotSingleDis<wbr>kLiveCommand] (DefaultQuartzScheduler10) [354939e9] Waiting on Live Merge command step 'EXTEND' to finalize<br>2016-09-28 08:18:14,294 INFO [org.ovirt.engine.core.bll.sna<wbr>pshots.RemoveSnapshotSingleDis<wbr>kLiveCommand] (DefaultQuartzScheduler9) [354939e9] Executing Live Merge command step 'MERGE'<br>2016-09-28 08:18:14,347 INFO [org.ovirt.engine.core.bll.Mer<wbr>geCommand] (pool-7-thread-2) [15ef379f] Running command: MergeCommand internal: true. Entities affected : ID: 6e5cce71-3438-4045-9d54-607123<wbr>e0557e Type: Storage<br>2016-09-28 08:18:14,348 INFO [org.ovirt.engine.core.vdsbrok<wbr>er.vdsbroker.MergeVDSCommand] (pool-7-thread-2) [15ef379f] START, MergeVDSCommand(HostName = Host04, MergeVDSCommandParameters:{run<wbr>Async='true', hostId='f22d87b9-4449-4a71-852<wbr>9-58095dd81b6f', vmId='eb73a967-1908-46e9-9de2-<wbr>9706bf29643a', storagePoolId='77e24b20-9d21-4<wbr>952-a089-3c5c592b4e6d', storageDomainId='6e5cce71-3438<wbr>-4045-9d54-607123e0557e', imageGroupId='9fc0b2f6-d786-4a<wbr>21-8f5c-b22b23df4aaa', imageId='95fefce5-7599-460f-b3<wbr>8c-377323659b52', baseImageId='c08d86ed-46f1-44b<wbr>c-9476-0cc2c6aed367', topImageId='95fefce5-7599-460f<wbr>-b38c-377323659b52', bandwidth='0'}), log id: 544f03ae<br>2016-09-28 08:18:15,335 INFO [org.ovirt.engine.core.bll.Con<wbr>currentChildCommandsExecutionC<wbr>allback] (DefaultQuartzScheduler8) [354939e9] Command 'RemoveSnapshot' (id: '18613dc9-d8c8-45c4-9fbe-a298e<wbr>701ead5') waiting on child command id: 'fd866748-3211-4d48-9908-12eb6<wbr>078a69e' type:'RemoveSnapshotSingleDisk<wbr>Live' to complete<br>2016-09-28 08:18:15,625 ERROR [org.ovirt.engine.core.vdsbrok<wbr>er.vdsbroker.MergeVDSCommand] (pool-7-thread-2) [15ef379f] Failed in 'MergeVDS' method<br>2016-09-28 08:18:15,628 ERROR [org.ovirt.engine.core.dal.dbb<wbr>roker.auditloghandling.AuditLo<wbr>gDirector] (pool-7-thread-2) [15ef379f] Correlation ID: null, Call Stack: null, Custom Event ID: -1, Message: VDSM Host04 command failed: Merge failed<br>2016-09-28 08:18:15,628 INFO [org.ovirt.engine.core.vdsbrok<wbr>er.vdsbroker.MergeVDSCommand] (pool-7-thread-2) [15ef379f] Command 'org.ovirt.engine.core.vdsbrok<wbr>er.vdsbroker.MergeVDSCommand' return value 'StatusOnlyReturnForXmlRpc [status=StatusForXmlRpc [code=52, message=Merge failed]]'<br>2016-09-28 08:18:15,629 INFO [org.ovirt.engine.core.vdsbrok<wbr>er.vdsbroker.MergeVDSCommand] (pool-7-thread-2) [15ef379f] HostName = Host04<br>2016-09-28 08:18:15,629 ERROR [org.ovirt.engine.core.vdsbrok<wbr>er.vdsbroker.MergeVDSCommand] (pool-7-thread-2) [15ef379f] Command 'MergeVDSCommand(HostName = Host04, MergeVDSCommandParameters:{run<wbr>Async='true', hostId='f22d87b9-4449-4a71-852<wbr>9-58095dd81b6f', vmId='eb73a967-1908-46e9-9de2-<wbr>9706bf29643a', storagePoolId='77e24b20-9d21-4<wbr>952-a089-3c5c592b4e6d', storageDomainId='6e5cce71-3438<wbr>-4045-9d54-607123e0557e', imageGroupId='9fc0b2f6-d786-4a<wbr>21-8f5c-b22b23df4aaa', imageId='95fefce5-7599-460f-b3<wbr>8c-377323659b52', baseImageId='c08d86ed-46f1-44b<wbr>c-9476-0cc2c6aed367', topImageId='95fefce5-7599-460f<wbr>-b38c-377323659b52', bandwidth='0'})' execution failed: VDSGenericException: VDSErrorException: Failed to MergeVDS, error = Merge failed, code = 52<br>2016-09-28 08:18:15,629 INFO [org.ovirt.engine.core.vdsbrok<wbr>er.vdsbroker.MergeVDSCommand] (pool-7-thread-2) [15ef379f] FINISH, MergeVDSCommand, log id: 544f03ae<br>2016-09-28 08:18:15,629 ERROR [org.ovirt.engine.core.bll.Mer<wbr>geCommand] (pool-7-thread-2) [15ef379f] Engine exception thrown while sending merge command: org.ovirt.engine.core.common.e<wbr>rrors.EngineException: EngineException: org.ovirt.engine.core.vdsbroke<wbr>r.vdsbroker.VDSErrorException: VDSGenericException: VDSErrorException: Failed to MergeVDS, error = Merge failed, code = 52 (Failed with error mergeErr and code 52)<br> at org.ovirt.engine.core.bll.VdsH<wbr>andler.handleVdsResult(VdsHand<wbr>ler.java:114) [bll.jar:]<br> at org.ovirt.engine.core.bll.VDSB<wbr>rokerFrontendImpl.runVdsComman<wbr>d(VDSBrokerFrontendImpl.java:<wbr>33) [bll.jar:]<br> at org.ovirt.engine.core.bll.Comm<wbr>andBase.runVdsCommand(CommandB<wbr>ase.java:2171) [bll.jar:]<br> at org.ovirt.engine.core.bll.Merg<wbr>eCommand.executeCommand(MergeC<wbr>ommand.java:45) [bll.jar:]<br> at org.ovirt.engine.core.bll.Comm<wbr>andBase.executeWithoutTransact<wbr>ion(CommandBase.java:1305) [bll.jar:]<br> at org.ovirt.engine.core.bll.Comm<wbr>andBase.executeActionInTransac<wbr>tionScope(CommandBase.java:<wbr>1447) [bll.jar:]<br> at org.ovirt.engine.core.bll.Comm<wbr>andBase.runInTransaction(Comma<wbr>ndBase.java:2075) [bll.jar:]<br> at <a href="http://org.ovirt.engine.core.utils.tr">org.ovirt.engine.core.utils.tr</a><wbr>ansaction.TransactionSupport.e<wbr>xecuteInNewTransaction(Transac<wbr>tionSupport.java:204) [utils.jar:]<br> at <a href="http://org.ovirt.engine.core.utils.tr">org.ovirt.engine.core.utils.tr</a><wbr>ansaction.TransactionSupport.e<wbr>xecuteInRequired(TransactionSu<wbr>pport.java:139) [utils.jar:]<br> at <a href="http://org.ovirt.engine.core.utils.tr">org.ovirt.engine.core.utils.tr</a><wbr>ansaction.TransactionSupport.e<wbr>xecuteInScope(TransactionSuppo<wbr>rt.java:107) [utils.jar:]<br> at org.ovirt.engine.core.bll.Comm<wbr>andBase.execute(CommandBase.<wbr>java:1490) [bll.jar:]<br> at org.ovirt.engine.core.bll.Comm<wbr>andBase.executeAction(CommandB<wbr>ase.java:398) [bll.jar:]<br> at org.ovirt.engine.core.bll.Back<wbr>end.runAction(Backend.java:<wbr>493) [bll.jar:]<br> at org.ovirt.engine.core.bll.Back<wbr>end.runAction(Backend.java:<wbr>729) [bll.jar:]<br> at sun.reflect.GeneratedMethodAcc<wbr>essor675.invoke(Unknown Source) [:1.8.0_101]<br> at sun.reflect.DelegatingMethodAc<wbr>cessorImpl.invoke(DelegatingMe<wbr>thodAccessorImpl.java:43) [rt.jar:1.8.0_101]<br> at java.lang.reflect.Method.invok<wbr>e(Method.java:498) [rt.jar:1.8.0_101]<br> at org.jboss.as.ee.component.Mana<wbr>gedReferenceMethodInterceptor.<wbr>processInvocation(ManagedRefer<wbr>enceMethodInterceptor.java:52)<br> at org.jboss.invocation.Intercept<wbr>orContext.proceed(InterceptorC<wbr>ontext.java:340)<br> at org.jboss.invocation.Intercept<wbr>orContext$Invocation.proceed(<wbr>InterceptorContext.java:437)<br> at org.jboss.as.weld.ejb.Jsr299Bi<wbr>ndingsInterceptor.delegateInte<wbr>rception(Jsr299BindingsInterce<wbr>ptor.java:70) [wildfly-weld-10.0.0.Final.jar<wbr>:10.0.0.Final]<br> at org.jboss.as.weld.ejb.Jsr299Bi<wbr>ndingsInterceptor.doMethodInte<wbr>rception(Jsr299BindingsInterce<wbr>ptor.java:80) [wildfly-weld-10.0.0.Final.jar<wbr>:10.0.0.Final]<br> at org.jboss.as.weld.ejb.Jsr299Bi<wbr>ndingsInterceptor.processInvoc<wbr>ation(Jsr299BindingsIntercepto<wbr>r.java:93) [wildfly-weld-10.0.0.Final.jar<wbr>:10.0.0.Final]<br> at org.jboss.as.ee.component.inte<wbr>rceptors.UserInterceptorFactor<wbr>y$1.processInvocation(UserInte<wbr>rceptorFactory.java:63)<br> at org.jboss.invocation.Intercept<wbr>orContext.proceed(InterceptorC<wbr>ontext.java:340)<br> at org.jboss.invocation.Intercept<wbr>orContext$Invocation.proceed(<wbr>InterceptorContext.java:437)<br> at org.ovirt.engine.core.bll.inte<wbr>rceptors.CorrelationIdTrackerI<wbr>nterceptor.aroundInvoke(Correl<wbr>ationIdTrackerInterceptor.<wbr>java:13) [bll.jar:]<br> at sun.reflect.GeneratedMethodAcc<wbr>essor140.invoke(Unknown Source) [:1.8.0_101]<br> at sun.reflect.DelegatingMethodAc<wbr>cessorImpl.invoke(DelegatingMe<wbr>thodAccessorImpl.java:43) [rt.jar:1.8.0_101]<br> at java.lang.reflect.Method.invok<wbr>e(Method.java:498) [rt.jar:1.8.0_101]<br> at org.jboss.as.ee.component.Mana<wbr>gedReferenceLifecycleMethodInt<wbr>erceptor.processInvocation(Man<wbr>agedReferenceLifecycleMethodIn<wbr>terceptor.java:89)<br> at org.jboss.invocation.Intercept<wbr>orContext.proceed(InterceptorC<wbr>ontext.java:340)<br> at <a href="http://org.jboss.as.ejb3.component.in">org.jboss.as.ejb3.component.in</a><wbr>vocationmetrics.ExecutionTimeI<wbr>nterceptor.processInvocation(E<wbr>xecutionTimeInterceptor.java:4<wbr>3) [wildfly-ejb3-10.0.0.Final.jar<wbr>:10.0.0.Final]<br> at org.jboss.invocation.Intercept<wbr>orContext.proceed(InterceptorC<wbr>ontext.java:340)<br> at org.jboss.invocation.Intercept<wbr>orContext$Invocation.proceed(<wbr>InterceptorContext.java:437)<br> at org.jboss.weld.ejb.AbstractEJB<wbr>RequestScopeActivationIntercep<wbr>tor.aroundInvoke(AbstractEJBRe<wbr>questScopeActivationIntercepto<wbr>r.java:73) [weld-core-impl-2.3.2.Final.ja<wbr>r:2.3.2.Final]<br> at org.jboss.as.weld.ejb.EjbReque<wbr>stScopeActivationInterceptor.<wbr>processInvocation(EjbRequestSc<wbr>opeActivationInterceptor.java:<wbr>83) [wildfly-weld-10.0.0.Final.jar<wbr>:10.0.0.Final]<br> at org.jboss.invocation.Intercept<wbr>orContext.proceed(InterceptorC<wbr>ontext.java:340)<br> at org.jboss.as.ee.concurrent.Con<wbr>currentContextInterceptor.proc<wbr>essInvocation(ConcurrentContex<wbr>tInterceptor.java:45) [wildfly-ee-10.0.0.Final.jar:1<wbr>0.0.0.Final]<br> at org.jboss.invocation.Intercept<wbr>orContext.proceed(InterceptorC<wbr>ontext.java:340)<br> at org.jboss.invocation.InitialIn<wbr>terceptor.processInvocation(In<wbr>itialInterceptor.java:21)<br> at org.jboss.invocation.Intercept<wbr>orContext.proceed(InterceptorC<wbr>ontext.java:340)<br> at org.jboss.invocation.ChainedIn<wbr>terceptor.processInvocation(Ch<wbr>ainedInterceptor.java:61)<br> at org.jboss.as.ee.component.inte<wbr>rceptors.ComponentDispatcherIn<wbr>terceptor.processInvocation(Co<wbr>mponentDispatcherInterceptor.<wbr>java:52)<br> at org.jboss.invocation.Intercept<wbr>orContext.proceed(InterceptorC<wbr>ontext.java:340)<br> at <a href="http://org.jboss.as.ejb3.component.si">org.jboss.as.ejb3.component.si</a><wbr>ngleton.SingletonComponentInst<wbr>anceAssociationInterceptor.pro<wbr>cessInvocation(SingletonCompon<wbr>entInstanceAssociationIntercep<wbr>tor.java:53) [wildfly-ejb3-10.0.0.Final.jar<wbr>:10.0.0.Final]<br> at org.jboss.invocation.Intercept<wbr>orContext.proceed(InterceptorC<wbr>ontext.java:340)<br> at org.jboss.as.ejb3.tx.CMTTxInte<wbr>rceptor.invokeInNoTx(CMTTxInte<wbr>rceptor.java:263) [wildfly-ejb3-10.0.0.Final.jar<wbr>:10.0.0.Final]<br> at org.jboss.as.ejb3.tx.CMTTxInte<wbr>rceptor.supports(CMTTxIntercep<wbr>tor.java:374) [wildfly-ejb3-10.0.0.Final.jar<wbr>:10.0.0.Final]<br> at org.jboss.as.ejb3.tx.CMTTxInte<wbr>rceptor.processInvocation(CMTT<wbr>xInterceptor.java:243) [wildfly-ejb3-10.0.0.Final.jar<wbr>:10.0.0.Final]<br> at org.jboss.invocation.Intercept<wbr>orContext.proceed(InterceptorC<wbr>ontext.java:340)<br> at <a href="http://org.jboss.as.ejb3.component.in">org.jboss.as.ejb3.component.in</a><wbr>terceptors.CurrentInvocationCo<wbr>ntextInterceptor.<wbr>processInvocation(CurrentInvoc<wbr>ationContextInterceptor.java:<wbr>41) [wildfly-ejb3-10.0.0.Final.jar<wbr>:10.0.0.Final]<br> at org.jboss.invocation.Intercept<wbr>orContext.proceed(InterceptorC<wbr>ontext.java:340)<br> at <a href="http://org.jboss.as.ejb3.component.in">org.jboss.as.ejb3.component.in</a><wbr>vocationmetrics.WaitTimeInterc<wbr>eptor.processInvocation(WaitTi<wbr>meInterceptor.java:43) [wildfly-ejb3-10.0.0.Final.jar<wbr>:10.0.0.Final]<br> at org.jboss.invocation.Intercept<wbr>orContext.proceed(InterceptorC<wbr>ontext.java:340)<br> at org.jboss.as.ejb3.security.Sec<wbr>urityContextInterceptor.proces<wbr>sInvocation(SecurityContextInt<wbr>erceptor.java:100) [wildfly-ejb3-10.0.0.Final.jar<wbr>:10.0.0.Final]<br> at org.jboss.invocation.Intercept<wbr>orContext.proceed(InterceptorC<wbr>ontext.java:340)<br> at <a href="http://org.jboss.as.ejb3.component.in">org.jboss.as.ejb3.component.in</a><wbr>terceptors.ShutDownInterceptor<wbr>Factory$1.processInvocation(Sh<wbr>utDownInterceptorFactory.java:<wbr>64) [wildfly-ejb3-10.0.0.Final.jar<wbr>:10.0.0.Final]<br> at org.jboss.invocation.Intercept<wbr>orContext.proceed(InterceptorC<wbr>ontext.java:340)<br> at <a href="http://org.jboss.as.ejb3.component.in">org.jboss.as.ejb3.component.in</a><wbr>terceptors.LoggingInterceptor.<wbr>processInvocation(LoggingInter<wbr>ceptor.java:66) [wildfly-ejb3-10.0.0.Final.jar<wbr>:10.0.0.Final]<br> at org.jboss.invocation.Intercept<wbr>orContext.proceed(InterceptorC<wbr>ontext.java:340)<br> at <a href="http://org.jboss.as.ee.component.Name">org.jboss.as.ee.component.Name</a><wbr>spaceContextInterceptor.proces<wbr>sInvocation(NamespaceContextIn<wbr>terceptor.java:50)<br> at org.jboss.invocation.Intercept<wbr>orContext.proceed(InterceptorC<wbr>ontext.java:340)<br> at org.jboss.invocation.ContextCl<wbr>assLoaderInterceptor.processIn<wbr>vocation(ContextClassLoaderInt<wbr>erceptor.java:64)<br> at org.jboss.invocation.Intercept<wbr>orContext.proceed(InterceptorC<wbr>ontext.java:340)<br> at org.jboss.invocation.Intercept<wbr>orContext.run(InterceptorConte<wbr>xt.java:356)<br> at org.wildfly.security.manager.W<wbr>ildFlySecurityManager.doChecke<wbr>d(WildFlySecurityManager.java:<wbr>636)<br> at org.jboss.invocation.AccessChe<wbr>ckingInterceptor.processInvoca<wbr>tion(AccessCheckingInterceptor<wbr>.java:61)<br> at org.jboss.invocation.Intercept<wbr>orContext.proceed(InterceptorC<wbr>ontext.java:340)<br> at org.jboss.invocation.Intercept<wbr>orContext.run(InterceptorConte<wbr>xt.java:356)<br> at org.jboss.invocation.Privilege<wbr>dWithCombinerInterceptor.<wbr>processInvocation(PrivilegedWi<wbr>thCombinerInterceptor.java:80)<br> at org.jboss.invocation.Intercept<wbr>orContext.proceed(InterceptorC<wbr>ontext.java:340)<br> at org.jboss.invocation.ChainedIn<wbr>terceptor.processInvocation(Ch<wbr>ainedInterceptor.java:61)<br> at org.jboss.as.ee.component.View<wbr>Service$View.invoke(ViewServic<wbr>e.java:195)<br> at org.jboss.as.ee.component.View<wbr>Description$1.processInvocatio<wbr>n(ViewDescription.java:185)<br> at org.jboss.invocation.Intercept<wbr>orContext.proceed(InterceptorC<wbr>ontext.java:340)<br> at org.jboss.invocation.ChainedIn<wbr>terceptor.processInvocation(Ch<wbr>ainedInterceptor.java:61)<br> at org.jboss.as.ee.component.Prox<wbr>yInvocationHandler.invoke(Prox<wbr>yInvocationHandler.java:73)<br> at org.ovirt.engine.core.bll.inte<wbr>rfaces.BackendCommandObjectsHa<wbr>ndler$$$view3.runAction(<wbr>Unknown Source) [bll.jar:]<br> at org.ovirt.engine.core.bll.task<wbr>s.CommandExecutor.executeComma<wbr>nd(CommandExecutor.java:57) [bll.jar:]<br> at org.ovirt.engine.core.bll.task<wbr>s.CommandExecutor.lambda$execu<wbr>teAsyncCommand$0(CommandExecut<wbr>or.java:46) [bll.jar:]<br> at java.util.concurrent.FutureTas<wbr>k.run(FutureTask.java:266) [rt.jar:1.8.0_101]<br> at java.util.concurrent.ThreadPoo<wbr>lExecutor.runWorker(ThreadPool<wbr>Executor.java:1142) [rt.jar:1.8.0_101]<br> at java.util.concurrent.ThreadPoo<wbr>lExecutor$Worker.run(ThreadPoo<wbr>lExecutor.java:617) [rt.jar:1.8.0_101]<br> at java.lang.Thread.run(Thread.ja<wbr>va:745) [rt.jar:1.8.0_101]</div><div>2016-09-28 08:18:16,038 INFO [org.ovirt.engine.core.vdsbrok<wbr>er.monitoring.VmJobsMonitoring<wbr>] (DefaultQuartzScheduler10) [354939e9] VM Job [4dd2b885-2452-4520-b20a-928ed<wbr>ea50836]: In progress (no change)<br>2016-09-28 08:18:16,341 INFO [org.ovirt.engine.core.bll.sna<wbr>pshots.RemoveSnapshotSingleDis<wbr>kLiveCommand] (DefaultQuartzScheduler6) [354939e9] Waiting on Live Merge command step 'MERGE' to complete<br>2016-09-28 08:18:16,351 INFO [org.ovirt.engine.core.bll.Mer<wbr>geCommandCallback] (DefaultQuartzScheduler6) [15ef379f] Merge command (jobId = null) has completed for images 'c08d86ed-46f1-44bc-9476-0cc2c<wbr>6aed367'..'95fefce5-7599-460f-<wbr>b38c-377323659b52'<br>2016-09-28 08:18:18,372 INFO [org.ovirt.engine.core.bll.sna<wbr>pshots.RemoveSnapshotSingleDis<wbr>kLiveCommand] (DefaultQuartzScheduler6) [354939e9] Executing Live Merge command step 'MERGE_STATUS'<br>2016-09-28 08:18:18,418 INFO [org.ovirt.engine.core.bll.Mer<wbr>geStatusCommand] (pool-7-thread-1) [42d41e81] Running command: MergeStatusCommand internal: true. Entities affected : ID: 6e5cce71-3438-4045-9d54-607123<wbr>e0557e Type: Storage<br>2016-09-28 08:18:18,506 INFO [org.ovirt.engine.core.vdsbrok<wbr>er.vdsbroker.FullListVDSComman<wbr>d] (pool-7-thread-1) [42d41e81] START, FullListVDSCommand(HostName = Host04, FullListVDSCommandParameters:{<wbr>runAsync='true', hostId='f22d87b9-4449-4a71-852<wbr>9-58095dd81b6f', vds='Host[Host04,f22d87b9-4449<wbr>-4a71-8529-58095dd81b6f]', vmIds='[eb73a967-1908-46e9-9de<wbr>2-9706bf29643a]'}), log id: 62d7ca75<br>2016-09-28 08:18:19,528 INFO [org.ovirt.engine.core.vdsbrok<wbr>er.vdsbroker.FullListVDSComman<wbr>d] (pool-7-thread-1) [42d41e81] FINISH, FullListVDSCommand, return: [{acpiEnable=true, emulatedMachine=pc-i440fx-rhel<wbr>7.2.0, afterMigrationStatus=, vmId=eb73a967-1908-46e9-9de2-9<wbr>706bf29643a, memGuaranteedSize=1740, transparentHugePages=true, displaySecurePort=5907, timeOffset=-7088, cpuType=Nehalem, smp=2, guestNumaNodes=[Ljava.lang.Obj<wbr>ect;@1f96e8d8, hypervEnable=true, custom={device_66bcfc2f-fca9-4<wbr>890-8bf5-74095d059da9device_de<wbr>626997-b95c-45be-81f2-b2de374b<wbr>5f8adevice_0faaec88-ceff-467b-<wbr>85fd-6f131cfae9fadevice_<wbr>ad363387-214a-4435-ab86-<wbr>486feeea6ad3=VmDevice:{id='<wbr>VmDeviceId:{deviceId='<wbr>ad363387-214a-4435-ab86-<wbr>486feeea6ad3', vmId='eb73a967-1908-46e9-9de2-<wbr>9706bf29643a'}', device='spicevmc', type='CHANNEL', bootOrder='0', specParams='[]', address='{bus=0, controller=0, type=virtio-serial, port=3}', managed='false', plugged='true', readOnly='false', deviceAlias='channel2', customProperties='[]', snapshotId='null', logicalName='null', usingScsiReservation='false', hostDevice='null'}, device_66bcfc2f-fca9-4890-8bf5<wbr>-74095d059da9=VmDevice:{id='<wbr>VmDeviceId:{deviceId='66bcfc2f<wbr>-fca9-4890-8bf5-74095d059da9', vmId='eb73a967-1908-46e9-9de2-<wbr>9706bf29643a'}', device='ide', type='CONTROLLER', bootOrder='0', specParams='[]', address='{slot=0x01, bus=0x00, domain=0x0000, type=pci, function=0x1}', managed='false', plugged='true', readOnly='false', deviceAlias='ide', customProperties='[]', snapshotId='null', logicalName='null', usingScsiReservation='false', hostDevice='null'}, device_66bcfc2f-fca9-4890-8bf5<wbr>-74095d059da9device_de626997-<wbr>b95c-45be-81f2-b2de374b5f8a=<wbr>VmDevice:{id='VmDeviceId:{<wbr>deviceId='de626997-b95c-45be-<wbr>81f2-b2de374b5f8a', vmId='eb73a967-1908-46e9-9de2-<wbr>9706bf29643a'}', device='unix', type='CHANNEL', bootOrder='0', specParams='[]', address='{bus=0, controller=0, type=virtio-serial, port=1}', managed='false', plugged='true', readOnly='false', deviceAlias='channel0', customProperties='[]', snapshotId='null', logicalName='null', usingScsiReservation='false', hostDevice='null'}, device_66bcfc2f-fca9-4890-8bf5<wbr>-74095d059da9device_de626997-<wbr>b95c-45be-81f2-b2de374b5f8adev<wbr>ice_0faaec88-ceff-467b-85fd-<wbr>6f131cfae9fa=VmDevice:{id='<wbr>VmDeviceId:{deviceId='<wbr>0faaec88-ceff-467b-85fd-<wbr>6f131cfae9fa', vmId='eb73a967-1908-46e9-9de2-<wbr>9706bf29643a'}', device='unix', type='CHANNEL', bootOrder='0', specParams='[]', address='{bus=0, controller=0, type=virtio-serial, port=2}', managed='false', plugged='true', readOnly='false', deviceAlias='channel1', customProperties='[]', snapshotId='null', logicalName='null', usingScsiReservation='false', hostDevice='null'}}, vmType=kvm, smpThreadsPerCore=1, memSize=1740, smpCoresPerSocket=2, vmName=SRV-ActPrint, nice=0, status=Up, maxMemSize=4194304, bootMenuEnable=false, guestDiskMapping={}, pid=14894, displayIp=192.168.144.104, displayPort=-1, smartcardEnable=false, maxMemSlots=16, nicModel=rtl8139,pv, kvmEnable=true, displayNetwork=ovirtmgmt, devices=[Ljava.lang.Object;@1c<wbr>3c34e6, maxVCpus=32, clientIp=, statusTime=7115868520, display=qxl}], log id: 62d7ca75<br>2016-09-28 08:18:19,538 ERROR [org.ovirt.engine.core.bll.Mer<wbr>geStatusCommand] (pool-7-thread-1) [42d41e81] Failed to live merge, still in volume chain: [c08d86ed-46f1-44bc-9476-0cc2c<wbr>6aed367, 95fefce5-7599-460f-b38c-377323<wbr>659b52]<br>2016-09-28 08:18:20,422 ERROR [org.ovirt.engine.core.bll.sna<wbr>pshots.RemoveSnapshotSingleDis<wbr>kLiveCommand] (DefaultQuartzScheduler9) [354939e9] Failed child command status for step 'MERGE_STATUS'<br>2016-09-28 08:18:21,449 ERROR [org.ovirt.engine.core.bll.sna<wbr>pshots.RemoveSnapshotSingleDis<wbr>kLiveCommand] (DefaultQuartzScheduler8) [354939e9] Merging of snapshot '275c0570-32b6-4d50-9529-b2428<wbr>f6a8a0b' images 'c08d86ed-46f1-44bc-9476-0cc2c<wbr>6aed367'..'95fefce5-7599-460f-<wbr>b38c-377323659b52' failed. Images have been marked illegal and can no longer be previewed or reverted to. Please retry Live Merge on the snapshot to complete the operation.<br>2016-09-28 08:18:21,459 INFO [org.ovirt.engine.core.bll.Con<wbr>currentChildCommandsExecutionC<wbr>allback] (DefaultQuartzScheduler8) [354939e9] Command 'RemoveSnapshot' id: '18613dc9-d8c8-45c4-9fbe-a298e<wbr>701ead5' child commands '[fd866748-3211-4d48-9908-12eb<wbr>6078a69e]' executions were completed, status 'FAILED'<br>2016-09-28 08:18:22,564 ERROR [org.ovirt.engine.core.bll.sna<wbr>pshots.RemoveSnapshotCommand] (DefaultQuartzScheduler10) [354939e9] Ending command 'org.ovirt.engine.core.bll.sna<wbr>pshots.RemoveSnapshotCommand' with failure.<br>2016-09-28 08:18:22,634 ERROR [org.ovirt.engine.core.dal.dbb<wbr>roker.auditloghandling.AuditLo<wbr>gDirector] (DefaultQuartzScheduler10) [354939e9] Correlation ID: 5a27e364, Job ID: 661f8f55-30c6-4735-bb0a-fdcd3a<wbr>c4004a, Call Stack: null, Custom Event ID: -1, Message: Failed to delete snapshot 'Backup the VM' for VM 'SRV-ActPrint'.</div><div><br></div><div><br></div><div> Vdsm.log</div><div><div>periodic/5740::DEBUG::2016-09-<wbr>28 08:01:31,574::vm::4519::virt.v<wbr>m::(queryBlockJobs) vmId=`eb73a967-1908-46e9-9de2-<wbr>9706bf29643a`::Still waiting for block job 4dd2b885-2452-4520-b20a-928ede<wbr>a50836 to be synchronized</div><b></b><i></i><u></u><sub></sub><sup></sup><strike></strike>....<br></div><div>jsonrpc.Executor/5::ERROR::201<wbr>6-09-28 08:33:04,783::vm::4427::virt.v<wbr>m::(trackBlockJob) vmId=`eb73a967-1908-46e9-9de2-<wbr>9706bf29643a`::Cannot add block job 50a359bd-097d-4c07-a7ae-5f4a9d<wbr>b23b81. A block job with id 4dd2b885-2452-4520-b20a-928ede<wbr>a50836 already exists for image 9fc0b2f6-d786-4a21-8f5c-b22b23<wbr>df4aaa<br>jsonrpc.Executor/5::ERROR::201<wbr>6-09-28 08:33:04,784::vm::4600::virt.v<wbr>m::(merge) vmId=`eb73a967-1908-46e9-9de2-<wbr>9706bf29643a`::A block job is already active on this disk</div><div><br></div><div><br></div><div>Thanks.</div><span><font color="#888888"><div><br></div><div>Marcelo Leandro<br></div></font></span></div><div class="gmail_extra"><br><div class="gmail_quote"><div><div>2016-06-24 12:49 GMT-03:00 Nicolás <span dir="ltr"><<a href="mailto:nicolas@devels.es" target="_blank">nicolas@devels.es</a>></span>:<br></div></div><blockquote class="gmail_quote" style="margin:0 0 0 .8ex;border-left:1px #ccc solid;padding-left:1ex"><div><div>Done, you can find it in [1]. Feel free to add/modify anything you consider inaccurate.<br>
<br>
Thank you.<br>
<br>
[1]: <a href="https://bugzilla.redhat.com/show_bug.cgi?id=1349950" target="_blank" rel="noreferrer">https://bugzilla.redhat.com/sh<wbr>ow_bug.cgi?id=1349950</a><br>
<br>
El 24/06/16 a las 12:39, Nir Soffer escribió:</div></div><div><div><div><div><br>
<blockquote class="gmail_quote" style="margin:0 0 0 .8ex;border-left:1px #ccc solid;padding-left:1ex">
On Fri, Jun 24, 2016 at 11:39 AM, Vinzenz Feenstra <<a href="mailto:vfeenstr@redhat.com" target="_blank">vfeenstr@redhat.com</a>> wrote:<br>
<blockquote class="gmail_quote" style="margin:0 0 0 .8ex;border-left:1px #ccc solid;padding-left:1ex"><blockquote class="gmail_quote" style="margin:0 0 0 .8ex;border-left:1px #ccc solid;padding-left:1ex">
On Jun 24, 2016, at 9:10 AM, <a href="mailto:nicolas@devels.es" target="_blank">nicolas@devels.es</a> wrote:<br>
<br>
Hi,<br>
<br>
We're trying to delete an auto-generated live snapshot that has been created after migrating an online VM's storage to a different domain. oVirt version is 3.6.6 and VDSM version is 4.17.28. Most relevant log lines are:<br>
<br>
2016-06-24 07:50:36,252 ERROR [org.ovirt.engine.core.vdsbrok<wbr>er.vdsbroker.MergeVDSCommand] (pool-7-thread-10) [799a22e3] Failed in 'MergeVDS' method<br>
2016-06-24 07:50:36,256 ERROR [org.ovirt.engine.core.dal.dbb<wbr>roker.auditloghandling.AuditLo<wbr>gDirector] (pool-7-thread-10) [799a22e3] Correlation ID: null, Call Stack: null, Custom Event ID: -1, Message: VDSM <a href="http://host2.domain.com" target="_blank" rel="noreferrer">host2.domain.com</a> command failed: Merge failed<br>
2016-06-24 07:50:36,256 ERROR [org.ovirt.engine.core.vdsbrok<wbr>er.vdsbroker.MergeVDSCommand] (pool-7-thread-10) [799a22e3] Command 'MergeVDSCommand(HostName = <a href="http://host2.domain.com" target="_blank" rel="noreferrer">host2.domain.com</a>, MergeVDSCommandParameters:{run<wbr>Async='true', hostId='c31dca1a-e5bc-43f6-940<wbr>f-6397e3ddbee4', vmId='7083832a-a1a2-42b7-961f-<wbr>2e9c0dcd7e18', storagePoolId='fa155d43-4e68-4<wbr>86f-9f9d-ae3e3916cc4f', storageDomainId='9339780c-3667<wbr>-4fef-aa13-9bec08957c5f', imageGroupId='65a0b0d4-5c96-4d<wbr>d9-a31b-4d08e40a46a5', imageId='9eec9e8f-38db-4abf-b1<wbr>c4-92fa9383f8b1', baseImageId='568b2f77-0ddf-434<wbr>9-a45c-36fcb0edecb6', topImageId='9eec9e8f-38db-4abf<wbr>-b1c4-92fa9383f8b1', bandwidth='0'})' execution failed: VDSGenericException: VDSErrorException: Failed to MergeVDS, error = Merge failed, code = 52<br>
2016-06-24 07:50:36,256 ERROR [org.ovirt.engine.core.bll.Mer<wbr>geCommand] (pool-7-thread-10) [799a22e3] Engine exception thrown while sending merge command: org.ovirt.engine.core.common.e<wbr>rrors.EngineException: EngineException: org.ovirt.engine.core.vdsbroke<wbr>r.vdsbroker.VDSErrorException: VDSGenericException: VDSErrorException: Failed to MergeVDS, error = Merge failed, code = 52 (Failed with error mergeErr and code 52)<br>
<br>
I'm attaching relevant logs (both for ovirt-engine and SPM's vdsm).<br>
<br>
What could be the reason for this error?<br>
</blockquote>
@Nir,<br>
<br>
that looks like some issue in VDSM please have a look<br>
<br>
jsonrpc.Executor/3::ERROR::201<wbr>6-06-24 07:50:36,216::vm::4955::virt.v<wbr>m::(merge) vmId=`7083832a-a1a2-42b7-961f-<wbr>2e9c0dcd7e18`::Live merge failed (job: 3ea68e36-6d99-4af9-a54e-4c5b06<wbr>df6a0f)<br>
Traceback (most recent call last):<br>
File "/usr/share/vdsm/virt/vm.py", line 4951, in merge<br>
flags)<br>
File "/usr/share/vdsm/virt/virdomai<wbr>n.py", line 68, in f<br>
ret = attr(*args, **kwargs)<br>
File "/usr/lib/python2.7/site-packa<wbr>ges/vdsm/libvirtconnection.py"<wbr>, line 124, in wrapper<br>
ret = f(*args, **kwargs)<br>
File "/usr/lib/python2.7/site-packa<wbr>ges/vdsm/utils.py", line 1313, in wrapper<br>
return func(inst, *args, **kwargs)<br>
File "/usr/lib64/python2.7/site-pac<wbr>kages/libvirt.py", line 668, in blockCommit<br>
if ret == -1: raise libvirtError ('virDomainBlockCommit() failed', dom=self)<br>
libvirtError: block copy still active: disk 'vda' already in active block job<br>
</blockquote>
This means there was a previous attempt to merge, and the block job<br>
did not complete yet.<br>
<br>
Engine should detect that there is an active block job and wait until<br>
it completes.<br>
<br>
This is probably engine bug, please file a bug and attach complete vdsm and<br>
engine logs.<br>
<br>
Ala, can you look at this?<br>
<br>
Nir<br>
</blockquote>
<br></div></div><span>
______________________________<wbr>_________________<br>
Users mailing list<br>
<a href="mailto:Users@ovirt.org" target="_blank">Users@ovirt.org</a><br>
<a href="http://lists.ovirt.org/mailman/listinfo/users" target="_blank" rel="noreferrer">http://lists.ovirt.org/mailman<wbr>/listinfo/users</a><br>
</span></div></div></blockquote></div><br></div>
</blockquote></div><br></div>
</div></div></blockquote></div><br></div>