René's URL Explorer Experiment

{"@context":"https://schema.org","@type":"DiscussionForumPosting","headline":"First VM deployment from VMware template fails with VMDK locked; second deployment succeeds","articleBody":"### problem\n\n\nWhen deploying a VM from a VMware template, the first VM creation always fails,\nbut the second attempt succeeds. \n\n\n\n\n\n### versions\n\n- CloudStack version: 4.21.0\n- VMware version: 8.0.3 / 24853646\n- Primary Storage: VMFS 6\n\n### The steps to reproduce the bug\n\n1. Create a VMware template and register it in CloudStack.\n2. Deploy a VM using this template.\n3. First deployment fails.\n4. Deploy again using the same template.\n5. Second deployment succeeds.\n\nLogs:\n2025-11-06 08:51:08,334 INFO  [o.a.c.s.v.VolumeServiceImpl] (Work-Job-Executor-1:[ctx-cc6702ac, job-34/job-35, ctx-fc2cf834]) (logid:74e2243d) releasing lock for VMTemplateStoragePool 2\n2025-11-06 08:51:08,341 ERROR [o.a.c.e.o.VolumeOrchestrator] (Work-Job-Executor-1:[ctx-cc6702ac, job-34/job-35, ctx-fc2cf834]) (logid:74e2243d) Unable to create volume [{\"name\":\"ROOT-3\",\"uuid\":\"d91479c1-b9dd-4034-b394-26939dad4eff\"}] due to [CopyCommand failed due to [Exception: java.lang.RuntimeException\nMessage: Unable to access file /vmfs/volumes/683fea5e-bc390134-295d-d4f5ef3ec3e8/8d93164511d13929aac98eec33062d49/8d93164511d13929aac98eec33062d49-000001.vmdk since it is locked\n].].\n2025-11-06 08:51:08,344 WARN  [c.c.v.ClusteredVirtualMachineManagerImpl] (Work-Job-Executor-1:[ctx-cc6702ac, job-34/job-35, ctx-fc2cf834]) (logid:74e2243d) Unable to contact resource. com.cloud.exception.StorageUnavailableException: Resource [StoragePool:4] is unreachable: Unable to create volume [{\"name\":\"ROOT-3\",\"uuid\":\"d91479c1-b9dd-4034-b394-26939dad4eff\"}] due to [CopyCommand failed due to [Exception: java.lang.RuntimeException\nMessage: Unable to access file /vmfs/volumes/683fea5e-bc390134-295d-d4f5ef3ec3e8/8d93164511d13929aac98eec33062d49/8d93164511d13929aac98eec33062d49-000001.vmdk since it is locked\n].].\n        at org.apache.cloudstack.engine.orchestration.VolumeOrchestrator.recreateVolume(VolumeOrchestrator.java:1872)\n        at org.apache.cloudstack.engine.orchestration.VolumeOrchestrator.prepare(VolumeOrchestrator.java:2019)\n        at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)\n        at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:77)\n        at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)\n        at java.base/java.lang.reflect.Method.invoke(Method.java:569)\n        at org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:344)\n        at org.springframework.aop.framework.ReflectiveMethodInvocation.invokeJoinpoint(ReflectiveMethodInvocation.java:198)\n        at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:163)\n        at org.springframework.aop.interceptor.ExposeInvocationInterceptor.invoke(ExposeInvocationInterceptor.java:97)\n      at org.springframework.aop.interceptor.ExposeInvocationInterceptor.invoke(ExposeInvocationInterceptor.java:97)\n        at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:186)\n        at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:215)\n        at jdk.proxy3/jdk.proxy3.$Proxy257.prepare(Unknown Source)\n        at com.cloud.vm.VirtualMachineManagerImpl.orchestrateStart(VirtualMachineManagerImpl.java:1482)\n        at com.cloud.vm.VirtualMachineManagerImpl.orchestrateStart(VirtualMachineManagerImpl.java:5827)\n        at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)\n        at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:77)\n        at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)\n        at java.base/java.lang.reflect.Method.invoke(Method.java:569)\n        at com.cloud.vm.VmWorkJobHandlerProxy.handleVmWorkJob(VmWorkJobHandlerProxy.java:102)\n        at com.cloud.vm.VirtualMachineManagerImpl.handleVmWorkJob(VirtualMachineManagerImpl.java:5951)\n        at com.cloud.vm.VmWorkJobDispatcher.runJob(VmWorkJobDispatcher.java:99)\n        at org.apache.cloudstack.framework.jobs.impl.AsyncJobManagerImpl$5.runInContext(AsyncJobManagerImpl.java:689)\n        at org.apache.cloudstack.managed.context.ManagedContextRunnable$1.run(ManagedContextRunnable.java:49)\n        at org.apache.cloudstack.managed.context.impl.DefaultManagedContext$1.call(DefaultManagedContext.java:56)\n        at org.apache.cloudstack.managed.context.impl.DefaultManagedContext.callWithContext(DefaultManagedContext.java:103)\n        at org.apache.cloudstack.managed.context.impl.DefaultManagedContext.runWithContext(DefaultManagedContext.java:53)\n        at org.apache.cloudstack.managed.context.ManagedContextRunnable.run(ManagedContextRunnable.java:46)\n        at org.apache.cloudstack.framework.jobs.impl.AsyncJobManagerImpl$5.run(AsyncJobManagerImpl.java:637)\n        at java.base/java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:539)\n        at java.base/java.util.concurrent.FutureTask.run(FutureTask.java:264)\n        at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1136)\n        at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:635)\n        at java.base/java.lang.Thread.run(Thread.java:840)\n\n2025-11-06 08:51:08,345 DEBUG [c.c.d.D.ExcludeList] (Work-Job-Executor-1:[ctx-cc6702ac, job-34/job-35, ctx-fc2cf834]) (logid:74e2243d) Adding StoragePool [4] to the avoid set due to [Resource [StoragePool:4] is unreachable: Unable to create volume [{\"name\":\"ROOT-3\",\"uuid\":\"d91479c1-b9dd-4034-b394-26939dad4eff\"}] due to [CopyCommand failed due to [Exception: java.lang.RuntimeException\n2025-11-06 08:51:08,345 DEBUG [c.c.d.D.ExcludeList] (Work-Job-Executor-1:[ctx-cc6702ac, job-34/job-35, ctx-fc2cf834]) (logid:74e2243d) Adding StoragePool [4] to the avoid set due to [Resource [StoragePool:4] is unreachable: Unable to create volume [{\"name\":\"ROOT-3\",\"uuid\":\"d91479c1-b9dd-4034-b394-26939dad4eff\"}] due to [CopyCommand failed due to [Exception: java.lang.RuntimeException\nMessage: Unable to access file /vmfs/volumes/683fea5e-bc390134-295d-d4f5ef3ec3e8/8d93164511d13929aac98eec33062d49/8d93164511d13929aac98eec33062d49-000001.vmdk since it is locked\n\n\n### What to do about it?\n\n_No response_","author":{"url":"https://github.com/midhunpjos","@type":"Person","name":"midhunpjos"},"datePublished":"2025-11-06T06:36:51.000Z","interactionStatistic":{"@type":"InteractionCounter","interactionType":"https://schema.org/CommentAction","userInteractionCount":4},"url":"https://github.com/11997/cloudstack/issues/11997"}