[CLOUDSTACK-6438] [Automation] Creation of Template from Volume failed due to the requested plugin could not be found.

• Type: Bug
• Status: Closed
• Priority: Blocker
• Resolution: Fixed
• Affects Version/s: 4.4.0
• Fix Version/s: 4.4.0
• Component/s: Automation, Management Server
• Security Level: Public (Anyone can view this level - this is the default.)
• Environment: Basic Zone; XenServer
• Assignee: Anthony Xu
• Reporter: Chandan Purushothama
• Created: 18/Apr/14 00:25
• Updated: 11/Nov/14 10:10
• Resolved: 23/Apr/14 22:37

Description

================================
The requested plugin could not be found.
================================

2014-04-16 15:55:43,850 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl] (API-Job-Executor-41:job-97) Executing AsyncJobVO {id:97, userId: 2, accountId: 2, instanceType: Template, instanceId: 205, cmd: org.apache.cloudstack.api.command.admin.template.CreateTemplateCmdByAdmin, cmdInfo:

{"cmdEventType":"TEMPLATE.CREATE","ctxUserId":"2","ispublic":"False","isextractable":"False","httpmethod":"GET","volumeid":"58a0f1da-e5fe-4cf0-8981-8a8ce848662f","domainid":"fe2a30b2-c5a8-11e3-8f55-66bc12ed7ec3","isfeatured":"False","apiKey":"MNQSBTYnkNvc4PCMgv7itBuPnuHpnUT_TEJRzzCOZXPL5bl9ihaz0P3TpkK3IW36icuUoFQkySLQkvQ9K6Dh0Q","ostypeid":"feba4b70-c5a8-11e3-8f55-66bc12ed7ec3","id":"205","response":"json","name":"Cent OS Template-7WLKMH","passwordenabled":"False","displaytext":"Cent OS Template","account":"test-TestCreateTemplate-SCKX71","uuid":"2575698c-c82c-4fc3-bd51-0993e40e81cb","ctxAccountId":"2","ctxStartEventId":"291","signature":"8ILr8Hdfhc35eHAm1D0L7lkgRw4\u003d"}

, cmdVersion: 0, status: IN_PROGRESS, processStatus: 0, resultCode: 0, result: null, initMsid: 112957957439171, completeMsid: null, lastUpdated: null, lastPolled: null, created: null}
2014-04-16 15:55:43,853 DEBUG [c.c.a.ApiServlet] (catalina-exec-10:ctx-a6b8fe9b) ===START=== 10.223.240.161 – GET signature=vtGRmlHXmBNP%2F1rDkUstx%2BIMl4g%3D&apiKey=MNQSBTYnkNvc4PCMgv7itBuPnuHpnUT_TEJRzzCOZXPL5bl9ihaz0P3TpkK3IW36icuUoFQkySLQkvQ9K6Dh0Q&command=queryAsyncJobResult&response=json&jobid=51fab632-3d6f-4898-85e4-9597a8f39707
2014-04-16 15:55:43,877 WARN [c.c.a.d.ParamGenericValidationWorker] (API-Job-Executor-41:job-97 ctx-554395e3) Received unknown parameters for command createTemplate. Unknown parameters : isextractable account
2014-04-16 15:55:43,921 DEBUG [c.c.a.ApiServlet] (catalina-exec-10:ctx-a6b8fe9b ctx-62916341 ctx-11cd890f) ===END=== 10.223.240.161 – GET signature=vtGRmlHXmBNP%2F1rDkUstx%2BIMl4g%3D&apiKey=MNQSBTYnkNvc4PCMgv7itBuPnuHpnUT_TEJRzzCOZXPL5bl9ihaz0P3TpkK3IW36icuUoFQkySLQkvQ9K6Dh0Q&command=queryAsyncJobResult&response=json&jobid=51fab632-3d6f-4898-85e4-9597a8f39707
2014-04-16 15:55:43,947 DEBUG [o.a.c.s.i.TemplateDataFactoryImpl] (API-Job-Executor-41:job-97 ctx-554395e3) template 205 is already in store:1, type:Image
2014-04-16 15:55:43,972 DEBUG [o.a.c.s.m.AncientDataMotionStrategy] (API-Job-Executor-41:job-97 ctx-554395e3) copyAsync inspecting src type VOLUME copyAsync inspecting dest type TEMPLATE
2014-04-16 15:55:44,005 DEBUG [c.c.a.t.Request] (API-Job-Executor-41:job-97 ctx-554395e3) Seq 2-7234469851417280562: Sending { Cmd , MgmtId: 112957957439171, via: 2(marron.lab.vmops.com), Ver: v1, Flags: 100011, [{"org.apache.cloudstack.storage.command.CopyCommand":{"srcTO":{"org.apache.cloudstack.storage.to.VolumeObjectTO":{"uuid":"58a0f1da-e5fe-4cf0-8981-8a8ce848662f","volumeType":"ROOT","dataStore":{"org.apache.cloudstack.storage.to.PrimaryDataStoreTO":{"uuid":"ac0c4b04-6b72-38b1-a72f-c7fe7163d17a","id":1,"poolType":"NetworkFilesystem","host":"nfs1-ccp.citrix.com","path":"/home/common/automation/SC_QA_AUTO7/primary4","port":2049,"url":"NetworkFilesystem://nfs1-ccp.citrix.com/home/common/automation/SC_QA_AUTO7/primary4/?ROLE=Primary&STOREUUID=ac0c4b04-6b72-38b1-a72f-c7fe7163d17a"}},"name":"ROOT-17","size":21474836480,"path":"01269023-5389-407f-8dfc-a3ab22074207","volumeId":19,"vmName":"i-12-17-QA","accountId":12,"format":"VHD","id":19,"deviceId":0,"hypervisorType":"XenServer"}},"destTO":{"org.apache.cloudstack.storage.to.TemplateObjectTO":{"path":"template/tmpl/12/205","uuid":"2575698c-c82c-4fc3-bd51-0993e40e81cb","id":205,"format":"RAW","accountId":12,"hvm":true,"displayText":"Cent OS Template","imageDataStore":{"com.cloud.agent.api.to.NfsTO":{"_url":"nfs://nfs1-ccp.citrix.com:/home/common/automation/SC_QA_AUTO7/secondary4","_role":"Image"}},"name":"27b30290f-74f3-391d-ac26-5a9a8ba769f6","hypervisorType":"XenServer"}},"executeInSequence":false,"options":{},"wait":10800}}] }
2014-04-16 15:55:44,006 DEBUG [c.c.a.t.Request] (API-Job-Executor-41:job-97 ctx-554395e3) Seq 2-7234469851417280562: Executing: { Cmd , MgmtId: 112957957439171, via: 2(marron.lab.vmops.com), Ver: v1, Flags: 100011, [{"org.apache.cloudstack.storage.command.CopyCommand":{"srcTO":{"org.apache.cloudstack.storage.to.VolumeObjectTO":{"uuid":"58a0f1da-e5fe-4cf0-8981-8a8ce848662f","volumeType":"ROOT","dataStore":{"org.apache.cloudstack.storage.to.PrimaryDataStoreTO":{"uuid":"ac0c4b04-6b72-38b1-a72f-c7fe7163d17a","id":1,"poolType":"NetworkFilesystem","host":"nfs1-ccp.citrix.com","path":"/home/common/automation/SC_QA_AUTO7/primary4","port":2049,"url":"NetworkFilesystem://nfs1-ccp.citrix.com/home/common/automation/SC_QA_AUTO7/primary4/?ROLE=Primary&STOREUUID=ac0c4b04-6b72-38b1-a72f-c7fe7163d17a"}},"name":"ROOT-17","size":21474836480,"path":"01269023-5389-407f-8dfc-a3ab22074207","volumeId":19,"vmName":"i-12-17-QA","accountId":12,"format":"VHD","id":19,"deviceId":0,"hypervisorType":"XenServer"}},"destTO":{"org.apache.cloudstack.storage.to.TemplateObjectTO":{"path":"template/tmpl/12/205","uuid":"2575698c-c82c-4fc3-bd51-0993e40e81cb","id":205,"format":"RAW","accountId":12,"hvm":true,"displayText":"Cent OS Template","imageDataStore":{"com.cloud.agent.api.to.NfsTO":{"_url":"nfs://nfs1-ccp.citrix.com:/home/common/automation/SC_QA_AUTO7/secondary4","_role":"Image"}},"name":"27b30290f-74f3-391d-ac26-5a9a8ba769f6","hypervisorType":"XenServer"}},"executeInSequence":false,"options":{},"wait":10800}}] }
2014-04-16 15:55:44,012 DEBUG [c.c.a.m.DirectAgentAttache] (DirectAgent-88:ctx-22e198db) Seq 2-7234469851417280562: Executing request
2014-04-16 15:55:44,237 WARN [c.c.h.x.r.CitrixResourceBase] (DirectAgent-88:ctx-22e198db) callHostPlugin failed for cmd: create_secondary_storage_folder with args remoteMountPath: nfs1-ccp.citrix.com:/home/common/automation/SC_QA_AUTO7/secondary4, newFolder: template/tmpl/12/205, due to The requested plugin could not be found.
2014-04-16 15:55:44,271 WARN [c.c.h.x.r.CitrixResourceBase] (DirectAgent-88:ctx-22e198db) callHostPlugin failed for cmd: delete_secondary_storage_folder with args folder: template/tmpl/12/205, remoteMountPath: nfs1-ccp.citrix.com:/home/common/automation/SC_QA_AUTO7/secondary4, due to The requested plugin could not be found.
2014-04-16 15:55:44,272 WARN [c.c.a.m.DirectAgentAttache] (DirectAgent-88:ctx-22e198db) Seq 2-7234469851417280562: Exception Caught while executing command
com.cloud.utils.exception.CloudRuntimeException: callHostPlugin failed for cmd: delete_secondary_storage_folder with args folder: template/tmpl/12/205, remoteMountPath: nfs1-ccp.citrix.com:/home/common/automation/SC_QA_AUTO7/secondary4, due to The requested plugin could not be found.
at com.cloud.hypervisor.xen.resource.CitrixResourceBase.callHostPlugin(CitrixResourceBase.java:3836)
at com.cloud.hypervisor.xen.resource.CitrixResourceBase.deleteSecondaryStorageFolder(CitrixResourceBase.java:6947)
at com.cloud.hypervisor.xen.resource.Xenserver625StorageProcessor.createTemplateFromVolume(Xenserver625StorageProcessor.java:522)
at com.cloud.storage.resource.StorageSubsystemCommandHandlerBase.execute(StorageSubsystemCommandHandlerBase.java:90)
at com.cloud.storage.resource.StorageSubsystemCommandHandlerBase.handleStorageCommands(StorageSubsystemCommandHandlerBase.java:52)
at com.cloud.hypervisor.xen.resource.CitrixResourceBase.executeRequest(CitrixResourceBase.java:542)
at com.cloud.hypervisor.xen.resource.XenServer56Resource.executeRequest(XenServer56Resource.java:60)
at com.cloud.hypervisor.xen.resource.XenServer610Resource.executeRequest(XenServer610Resource.java:92)
at com.cloud.hypervisor.xen.resource.XenServer620SP1Resource.executeRequest(XenServer620SP1Resource.java:64)
at com.cloud.agent.manager.DirectAgentAttache$Task.runInContext(DirectAgentAttache.java:216)
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 java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:471)
at java.util.concurrent.FutureTask.run(FutureTask.java:262)
at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$201(ScheduledThreadPoolExecutor.java:178)
at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:292)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
at java.lang.Thread.run(Thread.java:744)
2014-04-16 15:55:44,273 DEBUG [c.c.a.m.DirectAgentAttache] (DirectAgent-88:ctx-22e198db) Seq 2-7234469851417280562: Response Received:
2014-04-16 15:55:44,273 DEBUG [c.c.a.t.Request] (DirectAgent-88:ctx-22e198db) Seq 2-7234469851417280562: Processing: { Ans: , MgmtId: 112957957439171, via: 2, Ver: v1, Flags: 10, [{"com.cloud.agent.api.Answer":{"result":false,"details":"com.cloud.utils.exception.CloudRuntimeException: callHostPlugin failed for cmd: delete_secondary_storage_folder with args folder: template/tmpl/12/205, remoteMountPath: nfs1-ccp.citrix.com:/home/common/automation/SC_QA_AUTO7/secondary4, due to The requested plugin could not be found.","wait":0}}] }
2014-04-16 15:55:44,273 DEBUG [c.c.a.t.Request] (API-Job-Executor-41:job-97 ctx-554395e3) Seq 2-7234469851417280562: Received: { Ans: , MgmtId: 112957957439171, via: 2, Ver: v1, Flags: 10,

{ Answer }

}
2014-04-16 15:55:44,282 DEBUG [c.c.t.TemplateManagerImpl] (API-Job-Executor-41:job-97 ctx-554395e3) Failed to create templatecom.cloud.utils.exception.CloudRuntimeException: callHostPlugin failed for cmd: delete_secondary_storage_folder with args folder: template/tmpl/12/205, remoteMountPath: nfs1-ccp.citrix.com:/home/common/automation/SC_QA_AUTO7/secondary4, due to The requested plugin could not be found.
2014-04-16 15:55:44,300 ERROR [c.c.a.ApiAsyncJobDispatcher] (API-Job-Executor-41:job-97) Unexpected exception while executing org.apache.cloudstack.api.command.admin.template.CreateTemplateCmdByAdmin
com.cloud.utils.exception.CloudRuntimeException: Failed to create templatecom.cloud.utils.exception.CloudRuntimeException: callHostPlugin failed for cmd: delete_secondary_storage_folder with args folder: template/tmpl/12/205, remoteMountPath: nfs1-ccp.citrix.com:/home/common/automation/SC_QA_AUTO7/secondary4, due to The requested plugin could not be found.
at com.cloud.template.TemplateManagerImpl.createPrivateTemplate(TemplateManagerImpl.java:1391)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:606)
at org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:317)