Remi Bergsma created CLOUDSTACK-9060:
----------------------------------------

             Summary: Create volume / template from S3 snapshot fails
                 Key: CLOUDSTACK-9060
                 URL: https://issues.apache.org/jira/browse/CLOUDSTACK-9060
             Project: CloudStack
          Issue Type: Bug
      Security Level: Public (Anyone can view this level - this is the default.)
          Components: Snapshot
    Affects Versions: 4.6.0
         Environment: KVM / XenServer and S3 compatible sec storage
            Reporter: Remi Bergsma
            Priority: Critical


Create a vm
Create a snapshot
This works fine. Now create a template from this snapshot:

Error: Failed to create templatejava.lang.NullPointerException

java.lang.NullPointerException
        at 
org.apache.cloudstack.storage.motion.AncientDataMotionStrategy.createTemplateFromSnapshot(AncientDataMotionStrategy.java:490)
        at 
org.apache.cloudstack.storage.motion.AncientDataMotionStrategy.copyAsync(AncientDataMotionStrategy.java:441)
        at 
org.apache.cloudstack.storage.motion.DataMotionServiceImpl.copyAsync(DataMotionServiceImpl.java:68)
        at 
org.apache.cloudstack.storage.motion.DataMotionServiceImpl.copyAsync(DataMotionServiceImpl.java:73)
        at 
org.apache.cloudstack.storage.image.TemplateServiceImpl.copyAsync(TemplateServiceImpl.java:657)
        at 
org.apache.cloudstack.storage.image.TemplateServiceImpl.createTemplateFromSnapshotAsync(TemplateServiceImpl.java:663)
        at 
com.cloud.template.TemplateManagerImpl.createPrivateTemplate(TemplateManagerImpl.java:1484)
        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at 
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
        at 
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
        at java.lang.reflect.Method.invoke(Method.java:497)
        at 
org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:317)
        at 
org.springframework.aop.framework.ReflectiveMethodInvocation.invokeJoinpoint(ReflectiveMethodInvocation.java:183)
        at 
org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:150)
        at 
org.apache.cloudstack.network.contrail.management.EventUtils$EventInterceptor.invoke(EventUtils.java:106)
        at 
org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:161)
        at 
com.cloud.event.ActionEventInterceptor.invoke(ActionEventInterceptor.java:51)
        at 
org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:161)
        at 
org.springframework.aop.interceptor.ExposeInvocationInterceptor.invoke(ExposeInvocationInterceptor.java:91)
        at 
org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172)
        at 
org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:204)
        at com.sun.proxy.$Proxy187.createPrivateTemplate(Unknown Source)
        at 
org.apache.cloudstack.api.command.admin.template.CreateTemplateCmdByAdmin.execute(CreateTemplateCmdByAdmin.java:43)
        at com.cloud.api.ApiDispatcher.dispatch(ApiDispatcher.java:150)
        at 
com.cloud.api.ApiAsyncJobDispatcher.runJob(ApiAsyncJobDispatcher.java:108)
        at 
org.apache.cloudstack.framework.jobs.impl.AsyncJobManagerImpl$5.runInContext(AsyncJobManagerImpl.java:537)
        at 
org.apache.cloudstack.managed.context.ManagedContextRunnable$1.run(ManagedContextRunnable.java:49)
        at 
org.apache.cloudstack.managed.context.impl.DefaultManagedContext$1.call(DefaultManagedContext.java:56)
        at 
org.apache.cloudstack.managed.context.impl.DefaultManagedContext.callWithContext(DefaultManagedContext.java:103)
        at 
org.apache.cloudstack.managed.context.impl.DefaultManagedContext.runWithContext(DefaultManagedContext.java:53)
        at 
org.apache.cloudstack.managed.context.ManagedContextRunnable.run(ManagedContextRunnable.java:46)
        at 
org.apache.cloudstack.framework.jobs.impl.AsyncJobManagerImpl$5.run(AsyncJobManagerImpl.java:494)
        at 
java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
        at java.util.concurrent.FutureTask.run(FutureTask.java:266)
        at 
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
        at 
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
        at java.lang.Thread.run(Thread.java:745)
2015-11-12 15:16:45,413 DEBUG [c.c.t.TemplateManagerImpl] 
(API-Job-Executor-2:ctx-e0bc8a04 job-1665 ctx-ce0e40f4) Failed to create 
templatejava.lang.NullPointerException
2015-11-12 15:16:45,449 ERROR [c.c.a.ApiAsyncJobDispatcher] 
(API-Job-Executor-2:ctx-e0bc8a04 job-1665) Unexpected exception while executing 
org.apache.cloudstack.api.command.admin.template.CreateTemplateCmdByAdmin
com.cloud.utils.exception.CloudRuntimeException: Failed to create 
templatejava.lang.NullPointerException
        at 
com.cloud.template.TemplateManagerImpl.createPrivateTemplate(TemplateManagerImpl.java:1498)
        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at 
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
        at 
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
        at java.lang.reflect.Method.invoke(Method.java:497)
        at 
org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:317)
        at 
org.springframework.aop.framework.ReflectiveMethodInvocation.invokeJoinpoint(ReflectiveMethodInvocation.java:183)
        at 
org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:150)
        at 
org.apache.cloudstack.network.contrail.management.EventUtils$EventInterceptor.invoke(EventUtils.java:106)
        at 
org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:161)
        at 
com.cloud.event.ActionEventInterceptor.invoke(ActionEventInterceptor.java:51)
        at 
org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:161)
        at 
org.springframework.aop.interceptor.ExposeInvocationInterceptor.invoke(ExposeInvocationInterceptor.java:91)
        at 
org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172)
        at 
org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:204)
        at com.sun.proxy.$Proxy187.createPrivateTemplate(Unknown Source)
        at 
org.apache.cloudstack.api.command.admin.template.CreateTemplateCmdByAdmin.execute(CreateTemplateCmdByAdmin.java:43)
        at com.cloud.api.ApiDispatcher.dispatch(ApiDispatcher.java:150)
        at 
com.cloud.api.ApiAsyncJobDispatcher.runJob(ApiAsyncJobDispatcher.java:108)
        at 
org.apache.cloudstack.framework.jobs.impl.AsyncJobManagerImpl$5.runInContext(AsyncJobManagerImpl.java:537)
        at 
org.apache.cloudstack.managed.context.ManagedContextRunnable$1.run(ManagedContextRunnable.java:49)
        at 
org.apache.cloudstack.managed.context.impl.DefaultManagedContext$1.call(DefaultManagedContext.java:56)
        at 
org.apache.cloudstack.managed.context.impl.DefaultManagedContext.callWithContext(DefaultManagedContext.java:103)
        at 
org.apache.cloudstack.managed.context.impl.DefaultManagedContext.runWithContext(DefaultManagedContext.java:53)
        at 
org.apache.cloudstack.managed.context.ManagedContextRunnable.run(ManagedContextRunnable.java:46)
        at 
org.apache.cloudstack.framework.jobs.impl.AsyncJobManagerImpl$5.run(AsyncJobManagerImpl.java:494)
        at 
java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
        at java.util.concurrent.FutureTask.run(FutureTask.java:266)
        at 
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
        at 
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
        at java.lang.Thread.run(Thread.java:745)
2015-11-12 15:16:45,450 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl] 
(API-Job-Executor-2:ctx-e0bc8a04 job-1665) Complete async job-1665, jobStatus: 
FAILED, resultCode: 530, result: 
org.apache.cloudstack.api.response.ExceptionResponse/null/{"uuidList":[],"errorcode":530,"errortext":"Failed
 to create templatejava.lang.NullPointerException"}
2015-11-12 15:16:45,469 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl] 
(API-Job-Executor-2:ctx-e0bc8a04 job-1665) Publish async job-1665 complete on 
message bus
2015-11-12 15:16:45,469 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl] 
(API-Job-Executor-2:ctx-e0bc8a04 job-1665) Wake up jobs related to job-1665
2015-11-12 15:16:45,469 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl] 
(API-Job-Executor-2:ctx-e0bc8a04 job-1665) Update db status for job-1665
2015-11-12 15:16:45,472 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl] 
(API-Job-Executor-2:ctx-e0bc8a04 job-1665) Wake up jobs joined with job-1665 
and disjoin all subjobs created from job- 1665
2015-11-12 15:16:45,478 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl] 
(API-Job-Executor-2:ctx-e0bc8a04 job-1665) Done executing 
org.apache.cloudstack.api.command.admin.template.CreateTemplateCmdByAdmin for 
job-1665
2015-11-12 15:16:45,478 INFO  [o.a.c.f.j.i.AsyncJobMonitor] 
(API-Job-Executor-2:ctx-e0bc8a04 job-1665) Remove job-1665 from job monitoring

When creating a volume:
2015-11-12 16:47:31,480 DEBUG [c.c.a.ApiServlet] 
(http-bio-8080-exec-29:ctx-3ef81bf8 ctx-02cdde7c) ===END===  10.200.22.3 -- GET 
 
command=createVolume&response=json&snapshotid=83668f80-99fb-49b4-951c-de98cb2441b9&na
me=volume&zoneId=13ef0506-ca16-4843-81c3-009bffad42b8&_=1447343251240
2015-11-12 16:47:31,529 DEBUG [o.a.c.s.a.LocalStoragePoolAllocator] 
(API-Job-Executor-4:ctx-7aba86bb job-1671 ctx-d7a33806) 
LocalStoragePoolAllocator trying to find storage pool to fit the vm
2015-11-12 16:47:31,529 DEBUG [o.a.c.s.a.ClusterScopeStoragePoolAllocator] 
(API-Job-Executor-4:ctx-7aba86bb job-1671 ctx-d7a33806) 
ClusterScopeStoragePoolAllocator looking for storage pool
2015-11-12 16:47:31,529 DEBUG [o.a.c.s.a.ClusterScopeStoragePoolAllocator] 
(API-Job-Executor-4:ctx-7aba86bb job-1671 ctx-d7a33806) Looking for pools in 
dc: 1  pod:1  cluster:null. Disabled pools will be ignored.
2015-11-12 16:47:31,532 DEBUG [o.a.c.s.a.ClusterScopeStoragePoolAllocator] 
(API-Job-Executor-4:ctx-7aba86bb job-1671 ctx-d7a33806) Found pools matching 
tags: [Pool[1|NetworkFilesystem], Pool[2|NetworkFilesystem]]
2015-11-12 16:47:31,539 DEBUG [o.a.c.s.a.AbstractStoragePoolAllocator] 
(API-Job-Executor-4:ctx-7aba86bb job-1671 ctx-d7a33806) Checking if storage 
pool is suitable, name: null ,poolId: 1
2015-11-12 16:47:31,545 INFO  [c.c.s.StorageManagerImpl] 
(API-Job-Executor-4:ctx-7aba86bb job-1671 ctx-d7a33806) Storage pool null (1) 
does not supply IOPS capacity, assuming enough capacity
2015-11-12 16:47:31,549 DEBUG [c.c.s.StorageManagerImpl] 
(API-Job-Executor-4:ctx-7aba86bb job-1671 ctx-d7a33806) Checking pool 1 for 
storage, totalSize: 1099511627776, usedBytes: 7362772992, usedPct: 
0.0066964030265
808105, disable threshold: 0.85
2015-11-12 16:47:31,564 DEBUG [c.c.s.StorageManagerImpl] 
(API-Job-Executor-4:ctx-7aba86bb job-1671 ctx-d7a33806) Found storage pool 
MCCX_NL2_MCCXPOD01_2 of type NetworkFilesystem with overprovisioning factor 2
2015-11-12 16:47:31,564 DEBUG [c.c.s.StorageManagerImpl] 
(API-Job-Executor-4:ctx-7aba86bb job-1671 ctx-d7a33806) Total over provisioned 
capacity calculated is 2 * 1099511627776
2015-11-12 16:47:31,564 DEBUG [c.c.s.StorageManagerImpl] 
(API-Job-Executor-4:ctx-7aba86bb job-1671 ctx-d7a33806) Total capacity of the 
pool MCCX_NL2_MCCXPOD01_2 id: 1 is 2199023255552
2015-11-12 16:47:31,566 DEBUG [c.c.s.StorageManagerImpl] 
(API-Job-Executor-4:ctx-7aba86bb job-1671 ctx-d7a33806) Checking pool: 1 for 
volume allocation [Vol[223|vm=null|DATADISK]], maxSize : 2199023255552, 
totalAllo
catedSize : 15781081600, askingSize : 21476933632, allocated disable threshold: 
0.85
2015-11-12 16:47:31,566 DEBUG [o.a.c.s.a.ClusterScopeStoragePoolAllocator] 
(API-Job-Executor-4:ctx-7aba86bb job-1671 ctx-d7a33806) 
ClusterScopeStoragePoolAllocator returning 1 suitable storage pools
2015-11-12 16:47:31,567 DEBUG [o.a.c.e.o.VolumeOrchestrator] 
(API-Job-Executor-4:ctx-7aba86bb job-1671 ctx-d7a33806) Found a suitable pool 
for create volume: 1
2015-11-12 16:47:31,775 DEBUG [o.a.c.s.m.AncientDataMotionStrategy] 
(API-Job-Executor-4:ctx-7aba86bb job-1671 ctx-d7a33806) copyAsync inspecting 
src type SNAPSHOT copyAsync inspecting dest type VOLUME
2015-11-12 16:47:31,879 DEBUG [o.a.c.s.c.m.StorageCacheManagerImpl] 
(API-Job-Executor-4:ctx-7aba86bb job-1671 ctx-d7a33806) check snapshot cache 
entry(id: 11) on store(id: 1)
2015-11-12 16:47:31,898 DEBUG [o.a.c.s.c.m.StorageCacheManagerImpl] 
(API-Job-Executor-4:ctx-7aba86bb job-1671 ctx-d7a33806) create snapshot cache 
entry(id: 11) on store(id: 1)
2015-11-12 16:47:31,941 DEBUG [o.a.c.s.m.AncientDataMotionStrategy] 
(API-Job-Executor-4:ctx-7aba86bb job-1671 ctx-d7a33806) copyAsync inspecting 
src type SNAPSHOT copyAsync inspecting dest type SNAPSHOT
2015-11-12 16:47:32,035 ERROR [o.a.c.s.m.AncientDataMotionStrategy] 
(API-Job-Executor-4:ctx-7aba86bb job-1671 ctx-d7a33806) No remote endpoint to 
send command, check if host or ssvm is down?
2015-11-12 16:47:32,049 DEBUG [o.a.c.s.c.m.StorageCacheManagerImpl] 
(API-Job-Executor-4:ctx-7aba86bb job-1671 ctx-d7a33806) wake up all waiting 
threads(lock: 1489027980)
2015-11-12 16:47:32,054 ERROR [o.a.c.s.m.AncientDataMotionStrategy] 
(API-Job-Executor-4:ctx-7aba86bb job-1671 ctx-d7a33806) Failed to create volume 
from snappie on pool org.apache.cloudstack.storage.datastore.Primar
yDataStoreImpl@6f5c8ff7
java.lang.NullPointerException
        at 
org.apache.cloudstack.storage.motion.AncientDataMotionStrategy.copyVolumeFromSnapshot(AncientDataMotionStrategy.java:264)
        at 
org.apache.cloudstack.storage.motion.AncientDataMotionStrategy.copyAsync(AncientDataMotionStrategy.java:439)
        at 
org.apache.cloudstack.storage.motion.DataMotionServiceImpl.copyAsync(DataMotionServiceImpl.java:68)
        at 
org.apache.cloudstack.storage.motion.DataMotionServiceImpl.copyAsync(DataMotionServiceImpl.java:73)
        at 
org.apache.cloudstack.storage.volume.VolumeServiceImpl.createVolumeFromSnapshot(VolumeServiceImpl.java:783)
        at 
org.apache.cloudstack.engine.orchestration.VolumeOrchestrator.createVolumeFromSnapshot(VolumeOrchestrator.java:397)
        at 
com.cloud.storage.VolumeApiServiceImpl.createVolumeFromSnapshot(VolumeApiServiceImpl.java:798)
        at 
com.cloud.storage.VolumeApiServiceImpl.createVolume(VolumeApiServiceImpl.java:748)
        at 
com.cloud.storage.VolumeApiServiceImpl.createVolume(VolumeApiServiceImpl.java:172)
        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at 
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
        at 
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
        at java.lang.reflect.Method.invoke(Method.java:497)
        at 
org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:317)
        at 
org.springframework.aop.framework.ReflectiveMethodInvocation.invokeJoinpoint(ReflectiveMethodInvocation.java:183)
        at 
org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:150)
        at 
org.apache.cloudstack.network.contrail.management.EventUtils$EventInterceptor.invoke(EventUtils.java:106)
        at 
org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:161)
        at 
com.cloud.event.ActionEventInterceptor.invoke(ActionEventInterceptor.java:51)
        at 
org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:161)
        at 
org.springframework.aop.interceptor.ExposeInvocationInterceptor.invoke(ExposeInvocationInterceptor.java:91)
        at 
org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172)
        at 
org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:204)
        at com.sun.proxy.$Proxy197.createVolume(Unknown Source)
        at 
org.apache.cloudstack.api.command.admin.volume.CreateVolumeCmdByAdmin.execute(CreateVolumeCmdByAdmin.java:42)
        at com.cloud.api.ApiDispatcher.dispatch(ApiDispatcher.java:150)
        at 
com.cloud.api.ApiAsyncJobDispatcher.runJob(ApiAsyncJobDispatcher.java:108)
        at 
org.apache.cloudstack.framework.jobs.impl.AsyncJobManagerImpl$5.runInContext(AsyncJobManagerImpl.java:537)
        at 
org.apache.cloudstack.managed.context.ManagedContextRunnable$1.run(ManagedContextRunnable.java:49)
        at 
org.apache.cloudstack.managed.context.impl.DefaultManagedContext$1.call(DefaultManagedContext.java:56)
        at 
org.apache.cloudstack.managed.context.impl.DefaultManagedContext.callWithContext(DefaultManagedContext.java:103)
        at 
org.apache.cloudstack.managed.context.impl.DefaultManagedContext.runWithContext(DefaultManagedContext.java:53)
        at 
org.apache.cloudstack.managed.context.ManagedContextRunnable.run(ManagedContextRunnable.java:46)
        at 
org.apache.cloudstack.framework.jobs.impl.AsyncJobManagerImpl$5.run(AsyncJobManagerImpl.java:494)
        at 
java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
        at java.util.concurrent.FutureTask.run(FutureTask.java:266)
        at 
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
        at 
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
        at java.lang.Thread.run(Thread.java:745)
2015-11-12 16:47:32,055 DEBUG [o.a.c.s.m.AncientDataMotionStrategy] 
(API-Job-Executor-4:ctx-7aba86bb job-1671 ctx-d7a33806) copy failed
com.cloud.utils.exception.CloudRuntimeException: Failed to create volume from 
snappie on pool 
org.apache.cloudstack.storage.datastore.PrimaryDataStoreImpl@6f5c8ff7
        at 
org.apache.cloudstack.storage.motion.AncientDataMotionStrategy.copyVolumeFromSnapshot(AncientDataMotionStrategy.java:283)
        at 
org.apache.cloudstack.storage.motion.AncientDataMotionStrategy.copyAsync(AncientDataMotionStrategy.java:439)
        at 
org.apache.cloudstack.storage.motion.DataMotionServiceImpl.copyAsync(DataMotionServiceImpl.java:68)
        at 
org.apache.cloudstack.storage.motion.DataMotionServiceImpl.copyAsync(DataMotionServiceImpl.java:73)
        at 
org.apache.cloudstack.storage.volume.VolumeServiceImpl.createVolumeFromSnapshot(VolumeServiceImpl.java:783)
        at 
org.apache.cloudstack.engine.orchestration.VolumeOrchestrator.createVolumeFromSnapshot(VolumeOrchestrator.java:397)
        at 
com.cloud.storage.VolumeApiServiceImpl.createVolumeFromSnapshot(VolumeApiServiceImpl.java:798)
        at 
com.cloud.storage.VolumeApiServiceImpl.createVolume(VolumeApiServiceImpl.java:748)
        at 
com.cloud.storage.VolumeApiServiceImpl.createVolume(VolumeApiServiceImpl.java:172)
        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at 
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
        at 
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
        at java.lang.reflect.Method.invoke(Method.java:497)
        at 
org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:317)
        at 
org.springframework.aop.framework.ReflectiveMethodInvocation.invokeJoinpoint(ReflectiveMethodInvocation.java:183)
        at 
org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:150)
        at 
org.apache.cloudstack.network.contrail.management.EventUtils$EventInterceptor.invoke(EventUtils.java:106)
        at 
org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:161)
        at 
com.cloud.event.ActionEventInterceptor.invoke(ActionEventInterceptor.java:51)
        at 
org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:161)
        at 
org.springframework.aop.interceptor.ExposeInvocationInterceptor.invoke(ExposeInvocationInterceptor.java:91)
        at 
org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172)
        at 
org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:204)
        at com.sun.proxy.$Proxy197.createVolume(Unknown Source)
        at 
org.apache.cloudstack.api.command.admin.volume.CreateVolumeCmdByAdmin.execute(CreateVolumeCmdByAdmin.java:42)
        at com.cloud.api.ApiDispatcher.dispatch(ApiDispatcher.java:150)
        at 
com.cloud.api.ApiAsyncJobDispatcher.runJob(ApiAsyncJobDispatcher.java:108)
        at 
org.apache.cloudstack.framework.jobs.impl.AsyncJobManagerImpl$5.runInContext(AsyncJobManagerImpl.java:537)
        at 
org.apache.cloudstack.managed.context.ManagedContextRunnable$1.run(ManagedContextRunnable.java:49)
        at 
org.apache.cloudstack.managed.context.impl.DefaultManagedContext$1.call(DefaultManagedContext.java:56)
        at 
org.apache.cloudstack.managed.context.impl.DefaultManagedContext.callWithContext(DefaultManagedContext.java:103)
        at 
org.apache.cloudstack.managed.context.impl.DefaultManagedContext.runWithContext(DefaultManagedContext.java:53)
        at 
org.apache.cloudstack.managed.context.ManagedContextRunnable.run(ManagedContextRunnable.java:46)
        at 
org.apache.cloudstack.framework.jobs.impl.AsyncJobManagerImpl$5.run(AsyncJobManagerImpl.java:494)
        at 
java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
        at java.util.concurrent.FutureTask.run(FutureTask.java:266)
        at 
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
        at 
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
        at java.lang.Thread.run(Thread.java:745)
2015-11-12 16:47:32,081 WARN  [o.a.c.s.d.ObjectInDataStoreManagerImpl] 
(API-Job-Executor-4:ctx-7aba86bb job-1671 ctx-d7a33806) Unsupported data object 
(VOLUME, 
org.apache.cloudstack.storage.datastore.PrimaryDataStoreImpl@6f5c8ff7), no need 
to delete from object in store ref table
2015-11-12 16:47:32,090 WARN  [o.a.c.s.d.ObjectInDataStoreManagerImpl] 
(API-Job-Executor-4:ctx-7aba86bb job-1671 ctx-d7a33806) Snapshot 11 is not 
found on image store 2, so no need to delete
2015-11-12 16:47:32,090 DEBUG [o.a.c.e.o.VolumeOrchestrator] 
(API-Job-Executor-4:ctx-7aba86bb job-1671 ctx-d7a33806) Failed to create volume 
from snapshot:com.cloud.utils.exception.CloudRuntimeException: Failed to create 
volume from snappie on pool 
org.apache.cloudstack.storage.datastore.PrimaryDataStoreImpl@6f5c8ff7
2015-11-12 16:47:32,127 DEBUG [c.c.u.AccountManagerImpl] 
(API-Job-Executor-4:ctx-7aba86bb job-1671 ctx-d7a33806) Access granted to 
Acct[cb74b7a3-78cf-11e5-b870-5254002d5738-admin] to Domain:1/ by 
AffinityGroupAccessChecker
2015-11-12 16:47:32,158 ERROR [c.c.a.ApiAsyncJobDispatcher] 
(API-Job-Executor-4:ctx-7aba86bb job-1671) Unexpected exception while executing 
org.apache.cloudstack.api.command.admin.volume.CreateVolumeCmdByAdmin
com.cloud.utils.exception.CloudRuntimeException: Failed to create volume: 223
        at 
com.cloud.storage.VolumeApiServiceImpl.createVolume(VolumeApiServiceImpl.java:776)
        at 
com.cloud.storage.VolumeApiServiceImpl.createVolume(VolumeApiServiceImpl.java:172)
        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at 
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
        at 
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
        at java.lang.reflect.Method.invoke(Method.java:497)
        at 
org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:317)
        at 
org.springframework.aop.framework.ReflectiveMethodInvocation.invokeJoinpoint(ReflectiveMethodInvocation.java:183)
        at 
org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:150)
        at 
org.apache.cloudstack.network.contrail.management.EventUtils$EventInterceptor.invoke(EventUtils.java:106)
        at 
org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:161)
        at 
com.cloud.event.ActionEventInterceptor.invoke(ActionEventInterceptor.java:51)
        at 
org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:161)
        at 
org.springframework.aop.interceptor.ExposeInvocationInterceptor.invoke(ExposeInvocationInterceptor.java:91)
        at 
org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172)
        at 
org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:204)
        at com.sun.proxy.$Proxy197.createVolume(Unknown Source)
        at 
org.apache.cloudstack.api.command.admin.volume.CreateVolumeCmdByAdmin.execute(CreateVolumeCmdByAdmin.java:42)
        at com.cloud.api.ApiDispatcher.dispatch(ApiDispatcher.java:150)
        at 
com.cloud.api.ApiAsyncJobDispatcher.runJob(ApiAsyncJobDispatcher.java:108)
        at 
org.apache.cloudstack.framework.jobs.impl.AsyncJobManagerImpl$5.runInContext(AsyncJobManagerImpl.java:537)
        at 
org.apache.cloudstack.managed.context.ManagedContextRunnable$1.run(ManagedContextRunnable.java:49)
        at 
org.apache.cloudstack.managed.context.impl.DefaultManagedContext$1.call(DefaultManagedContext.java:56)
        at 
org.apache.cloudstack.managed.context.impl.DefaultManagedContext.callWithContext(DefaultManagedContext.java:103)
        at 
org.apache.cloudstack.managed.context.impl.DefaultManagedContext.runWithContext(DefaultManagedContext.java:53)
        at 
org.apache.cloudstack.managed.context.ManagedContextRunnable.run(ManagedContextRunnable.java:46)
        at 
org.apache.cloudstack.framework.jobs.impl.AsyncJobManagerImpl$5.run(AsyncJobManagerImpl.java:494)
        at 
java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
        at java.util.concurrent.FutureTask.run(FutureTask.java:266)
        at 
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
        at 
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
        at java.lang.Thread.run(Thread.java:745)
Caused by: com.cloud.utils.exception.CloudRuntimeException: Failed to create 
volume from snapshot:com.cloud.utils.exception.CloudRuntimeException: Failed to 
create volume from snappie on pool 
org.apache.cloudstack.storage.datastore.PrimaryDataStoreImpl@6f5c8ff7
        at 
org.apache.cloudstack.engine.orchestration.VolumeOrchestrator.createVolumeFromSnapshot(VolumeOrchestrator.java:402)
        at 
com.cloud.storage.VolumeApiServiceImpl.createVolumeFromSnapshot(VolumeApiServiceImpl.java:798)
        at 
com.cloud.storage.VolumeApiServiceImpl.createVolume(VolumeApiServiceImpl.java:748)
        ... 31 more
2015-11-12 16:47:32,160 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl] 
(API-Job-Executor-4:ctx-7aba86bb job-1671) Complete async job-1671, jobStatus: 
FAILED, resultCode: 530, result: 
org.apache.cloudstack.api.response.ExceptionResponse/null/{"uuidList":[],"errorcode":530,"errortext":"Failed
 to create volume: 223"}
2015-11-12 16:47:32,174 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl] 
(API-Job-Executor-4:ctx-7aba86bb job-1671) Publish async job-1671 complete on 
message bus
2015-11-12 16:47:32,174 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl] 
(API-Job-Executor-4:ctx-7aba86bb job-1671) Wake up jobs related to job-1671
2015-11-12 16:47:32,174 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl] 
(API-Job-Executor-4:ctx-7aba86bb job-1671) Update db status for job-1671
2015-11-12 16:47:32,177 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl] 
(API-Job-Executor-4:ctx-7aba86bb job-1671) Wake up jobs joined with job-1671 
and disjoin all subjobs created from job- 1671
2015-11-12 16:47:32,182 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl] 
(API-Job-Executor-4:ctx-7aba86bb job-1671) Done executing 
org.apache.cloudstack.api.command.admin.volume.CreateVolumeCmdByAdmin for 
job-1671
2015-11-12 16:47:32,183 INFO  [o.a.c.f.j.i.AsyncJobMonitor] 
(API-Job-Executor-4:ctx-7aba86bb job-1671) Remove job-1671 from job monitoring




--
This message was sent by Atlassian JIRA
(v6.3.4#6332)

Reply via email to