You are viewing a plain text version of this content. The canonical link for it is here.
Posted to issues@cloudstack.apache.org by "Gaurav Aradhye (JIRA)" <ji...@apache.org> on 2013/12/11 11:37:08 UTC
[jira] [Commented] (CLOUDSTACK-5334) [Automation] Failed to create
template from snapshot while executing copy command, observed NPE In SSVM
log
[ https://issues.apache.org/jira/browse/CLOUDSTACK-5334?page=com.atlassian.jira.plugin.system.issuetabpanels:comment-tabpanel&focusedCommentId=13845269#comment-13845269 ]
Gaurav Aradhye commented on CLOUDSTACK-5334:
--------------------------------------------
Hi Rayees,
I tried reproducing this manually (as per the steps you have mentioned) and also through test case (test_blocker_bugs.Test_Templates.test_02_check_size_snapshotTemplate), but couldn't reproduce.
(Manual steps --> Create VM, create snapshot of root disk, create template from this snapshot, create vm again from this template | All operations were successful)
I also checked the MS logs related to template creation from snapshot, but the command present in your log (copySnapshotToTemplateFromNfsToNfs) is missing in my Log. Also, there was no NPE.
Please let me know if I am missing something.
Below is the related log:
2013-12-11 01:34:21,675 DEBUG [o.a.c.s.m.AncientDataMotionStrategy] (Job-Executor-101:ctx-faaf5090 ctx-102153d9) copyAsync inspecting src type SNAPSHOT copyAsyn
c inspecting dest type TEMPLATE
2013-12-11 01:34:21,711 DEBUG [c.c.a.t.Request] (Job-Executor-101:ctx-faaf5090 ctx-102153d9) Seq 8-2095776316: Sending { Cmd , MgmtId: 29066118877352, via: 8(s
-70-QA), Ver: v1, Flags: 100011, [{"org.apache.cloudstack.storage.command.CopyCommand":{"srcTO":{"org.apache.cloudstack.storage.to.SnapshotObjectTO":{"path":"sn
apshots/2/966/5044a0ed-5991-47e7-8a4d-27882b07d6cf","volume":{"uuid":"396bd9da-7b9f-4a64-86bb-4aa070c44261","volumeType":"ROOT","dataStore":{"org.apache.cloudst
ack.storage.to.PrimaryDataStoreTO":{"uuid":"41b632b5-40b3-3024-a38b-ea259c72579f","id":2,"poolType":"NetworkFilesystem","host":"10.223.110.232","path":"/export/
home/rayees/SC_QA_AUTO4/primary2","port":2049,"url":"NetworkFilesystem://10.223.110.232//export/home/rayees/SC_QA_AUTO4/primary2/?ROLE=Primary&STOREUUID=41b632b
5-40b3-3024-a38b-ea259c72579f"}},"name":"ROOT-907","size":8589934592,"path":"396bd9da-7b9f-4a64-86bb-4aa070c44261","volumeId":966,"vmName":"i-2-907-QA","account
Id":2,"format":"QCOW2","id":966,"deviceId":0,"hypervisorType":"KVM"},"dataStore":{"com.cloud.agent.api.to.NfsTO":{"_url":"nfs://10.223.110.232:/export/home/raye
es/SC_QA_AUTO4/secondary","_role":"Image"}},"vmName":"i-2-907-QA","name":"test-vm_ROOT-907_20131211093157","hypervisorType":"KVM","id":27,"quiescevm":false}},"d
estTO":{"org.apache.cloudstack.storage.to.TemplateObjectTO":{"path":"template/tmpl/2/242","uuid":"b779f973-d304-4a8f-9a18-d2480cc58021","id":242,"format":"RAW",
"accountId":2,"hvm":true,"displayText":"test-template","imageDataStore":{"com.cloud.agent.api.to.NfsTO":{"_url":"nfs://10.223.110.232:/export/home/rayees/SC_QA_
AUTO4/secondary","_role":"Image"}},"name":"22ef57484-c9b6-32e8-b32d-0a41567b917f","hypervisorType":"KVM"}},"executeInSequence":false,"wait":10800}}] }
> [Automation] Failed to create template from snapshot while executing copy command, observed NPE In SSVM log
> -----------------------------------------------------------------------------------------------------------
>
> Key: CLOUDSTACK-5334
> URL: https://issues.apache.org/jira/browse/CLOUDSTACK-5334
> Project: CloudStack
> Issue Type: Bug
> Security Level: Public(Anyone can view this level - this is the default.)
> Components: Template
> Affects Versions: 4.3.0
> Environment: KVM
> Branch 4.3
> Reporter: Rayees Namathponnan
> Assignee: Gaurav Aradhye
> Priority: Blocker
> Fix For: 4.3.0
>
> Attachments: CLOUDSTACK-5334.rar
>
>
> Steps to reproduce
> Step 1 : snapshot root volume
> Step 2 : Create template from snapshot
> Result
> Failed to create template from snapshot, observed below "copy command failure and NPE" in MS log and SSVM log
> MS log
> 2013-12-02 08:28:49,406 DEBUG [o.a.c.s.m.AncientDataMotionStrategy] (Job-Executor-140:ctx-62f776d6 ctx-120aa5f1) copyAsync inspecting src type SNAPSHOT copy
> Async inspecting dest type TEMPLATE
> 2013-12-02 08:28:49,418 DEBUG [c.c.a.t.Request] (Job-Executor-140:ctx-62f776d6 ctx-120aa5f1) Seq 8-1124074796: Sending { Cmd , MgmtId: 29066118877352, via:
> 8(s-5-VM), Ver: v1, Flags: 100011, [{"org.apache.cloudstack.storage.command.CopyCommand":{"srcTO":{"org.apache.cloudstack.storage.to.SnapshotObjectTO":{"pa
> th":"snapshots/282/589/d2b20627-b60e-489b-9eee-9f4705331a72","dataStore":{"com.cloud.agent.api.to.NfsTO":{"_url":"nfs://10.223.110.232:/export/home/rayees/S
> C_QA_AUTO4/secondary","_role":"Image"}},"name":"VM-f9e129c2-8d62-4b70-8105-abd304bf7605_ROOT-526_20131202080509","hypervisorType":"KVM","id":20,"quiescevm":
> false}},"destTO":{"org.apache.cloudstack.storage.to.TemplateObjectTO":{"path":"template/tmpl/282/236","uuid":"1e48c6bf-388e-4184-93f5-28a21b12329c","id":236
> ,"format":"RAW","accountId":282,"hvm":true,"displayText":"raytemp","imageDataStore":{"com.cloud.agent.api.to.NfsTO":{"_url":"nfs://10.223.110.232:/export/ho
> me/rayees/SC_QA_AUTO4/secondary","_role":"Image"}},"name":"2c497573e-38cc-3f4d-a73a-49c7b63bbb6c","hypervisorType":"KVM"}},"executeInSequence":false,"wait":
> 10800}}] }
> 2013-12-02 08:28:49,655 DEBUG [c.c.a.t.Request] (AgentManager-Handler-14:null) Seq 8-1124074796: Processing: { Ans: , MgmtId: 29066118877352, via: 8, Ver:
> v1, Flags: 10, [{"com.cloud.agent.api.Answer":{"result":false,"details":"java.lang.NullPointerException\n\tat org.apache.cloudstack.storage.resource.NfsSeco
> ndaryStorageResource.copySnapshotToTemplateFromNfsToNfs(NfsSecondaryStorageResource.java:449)\n\tat org.apache.cloudstack.storage.resource.NfsSecondaryStora
> geResource.createTemplateFromSnapshot(NfsSecondaryStorageResource.java:546)\n\tat org.apache.cloudstack.storage.resource.NfsSecondaryStorageResource.execute
> (NfsSecondaryStorageResource.java:625)\n\tat org.apache.cloudstack.storage.resource.NfsSecondaryStorageResource.executeRequest(NfsSecondaryStorageResource.j
> ava:236)\n\tat com.cloud.storage.resource.PremiumSecondaryStorageResource.defaultAction(PremiumSecondaryStorageResource.java:63)\n\tat com.cloud.storage.res
> ource.PremiumSecondaryStorageResource.executeRequest(PremiumSecondaryStorageResource.java:59)\n\tat com.cloud.agent.Agent.processRequest(Agent.java:498)\n\t
> at com.cloud.agent.Agent$AgentRequestHandler.doTask(Agent.java:806)\n\tat com.cloud.utils.nio.Task.run(Task.java:83)\n\tat java.util.concurrent.ThreadPoolEx
> ecutor.runWorker(ThreadPoolExecutor.java:1146)\n\tat java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)\n\tat java.lang.Thread.
> run(Thread.java:679)\n","wait":0}}] }
> 2013-12-02 08:28:49,656 DEBUG [c.c.a.t.Request] (Job-Executor-140:ctx-62f776d6 ctx-120aa5f1) Seq 8-1124074796: Received: { Ans: , MgmtId: 29066118877352, v
> ia: 8, Ver: v1, Flags: 10, { Answer } }
> 2013-12-02 08:28:49,663 DEBUG [c.c.t.TemplateManagerImpl] (Job-Executor-140:ctx-62f776d6 ctx-120aa5f1) Failed to create templatejava.lang.NullPointerExcepti
> on
> at org.apache.cloudstack.storage.resource.NfsSecondaryStorageResource.copySnapshotToTemplateFromNfsToNfs(NfsSecondaryStorageResource.java:449)
> at org.apache.cloudstack.storage.resource.NfsSecondaryStorageResource.createTemplateFromSnapshot(NfsSecondaryStorageResource.java:546)
> at org.apache.cloudstack.storage.resource.NfsSecondaryStorageResource.execute(NfsSecondaryStorageResource.java:625)
> at org.apache.cloudstack.storage.resource.NfsSecondaryStorageResource.executeRequest(NfsSecondaryStorageResource.java:236)
> at com.cloud.storage.resource.PremiumSecondaryStorageResource.defaultAction(PremiumSecondaryStorageResource.java:63)
> at com.cloud.storage.resource.PremiumSecondaryStorageResource.executeRequest(PremiumSecondaryStorageResource.java:59)
> at com.cloud.agent.Agent.processRequest(Agent.java:498)
> at com.cloud.agent.Agent$AgentRequestHandler.doTask(Agent.java:806)
> at com.cloud.utils.nio.Task.run(Task.java:83)
> at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1146)
> at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
> at java.lang.Thread.run(Thread.java:679)
> SSVM log
> 2013-12-02 16:28:11,308 DEBUG [cloud.agent.Agent] (UgentTask-5:null) Sending ping: Seq 8-263: { Cmd , MgmtId: -1, via: 8, Ver: v1, Flags: 11, [{"com.cloud.agen
> t.api.PingStorageCommand":{"changes":{},"hostType":"Storage","hostId":8,"wait":0}}] }
> 2013-12-02 16:28:11,350 DEBUG [cloud.agent.Agent] (Agent-Handler-3:null) Received response: Seq 8-263: { Ans: , MgmtId: 29066118877352, via: 8, Ver: v1, Flags:
> 100010, [{"com.cloud.agent.api.PingAnswer":{"_command":{"hostType":"Storage","hostId":8,"wait":0},"result":true,"wait":0}}] }
> 2013-12-02 16:28:49,422 DEBUG [cloud.agent.Agent] (agentRequest-Handler-2:null) Request:Seq 8-1124074796: { Cmd , MgmtId: 29066118877352, via: 8, Ver: v1, Flag
> s: 100011, [{"org.apache.cloudstack.storage.command.CopyCommand":{"srcTO":{"org.apache.cloudstack.storage.to.SnapshotObjectTO":{"path":"snapshots/282/589/d2b206
> 27-b60e-489b-9eee-9f4705331a72","dataStore":{"com.cloud.agent.api.to.NfsTO":{"_url":"nfs://10.223.110.232:/export/home/rayees/SC_QA_AUTO4/secondary","_role":"Im
> age"}},"name":"VM-f9e129c2-8d62-4b70-8105-abd304bf7605_ROOT-526_20131202080509","hypervisorType":"KVM","id":20,"quiescevm":false}},"destTO":{"org.apache.cloudst
> ack.storage.to.TemplateObjectTO":{"path":"template/tmpl/282/236","uuid":"1e48c6bf-388e-4184-93f5-28a21b12329c","id":236,"format":"RAW","accountId":282,"hvm":tru
> e,"displayText":"raytemp","imageDataStore":{"com.cloud.agent.api.to.NfsTO":{"_url"
> :"nfs://10.223.110.232:/export/home/rayees/SC_QA_AUTO4/secondary","_role":"Image"}},"name":"2c497573e-38cc-3f4d-a73a-49c7b63bbb6c","hypervisorType":"KVM"}},"executeInSequence":false,"wait":10800}}] }
> 2013-12-02 16:28:49,422 DEBUG [cloud.agent.Agent] (agentRequest-Handler-2:null) Processing command: org.apache.cloudstack.storage.command.CopyCommand
> 2013-12-02 16:28:49,423 INFO [storage.resource.NfsSecondaryStorageResource] (agentRequest-Handler-2:null) Determined host 10.223.110.232 corresponds to IP 10.223.110.232
> 2013-12-02 16:28:49,509 INFO [storage.resource.NfsSecondaryStorageResource] (agentRequest-Handler-2:null) Determined host 10.223.110.232 corresponds to IP 10.223.110.232
> 2013-12-02 16:28:49,581 WARN [cloud.agent.Agent] (agentRequest-Handler-2:null) Caught:
> java.lang.NullPointerException
> at org.apache.cloudstack.storage.resource.NfsSecondaryStorageResource.copySnapshotToTemplateFromNfsToNfs(NfsSecondaryStorageResource.java:449)
> at org.apache.cloudstack.storage.resource.NfsSecondaryStorageResource.createTemplateFromSnapshot(NfsSecondaryStorageResource.java:546)
> at org.apache.cloudstack.storage.resource.NfsSecondaryStorageResource.execute(NfsSecondaryStorageResource.java:625)
> at org.apache.cloudstack.storage.resource.NfsSecondaryStorageResource.executeRequest(NfsSecondaryStorageResource.java:236)
> at com.cloud.storage.resource.PremiumSecondaryStorageResource.defaultAction(PremiumSecondaryStorageResource.java:63)
> at com.cloud.storage.resource.PremiumSecondaryStorageResource.executeRequest(PremiumSecondaryStorageResource.java:59)
> at com.cloud.agent.Agent.processRequest(Agent.java:498)
> at com.cloud.agent.Agent$AgentRequestHandler.doTask(Agent.java:806)
> at com.cloud.utils.nio.Task.run(Task.java:83)
> at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1146)
> at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
> at java.lang.Thread.run(Thread.java:679)
> 2013-12-02 16:28:49,615 DEBUG [cloud.agent.Agent] (agentRequest-Handler-2:null) Seq 8-1124074796: { Ans: , MgmtId: 29066118877352, via: 8, Ver: v1, Flags: 10, [{"com.cloud.agent.api.Answer":{"result":false,"details":"java.lang.NullPointerException\n\tat org.apache.cloudstack.storage.resource.NfsSecondaryStorageResource.copySnapshotToTemplateFromNfsToNfs(NfsSecondaryStorageResource.java:449)\n\tat org.apache.cloudstack.storage.resource.NfsSecondaryStorageResource.createTemplateFromSnapshot(NfsSecondaryStorageResource.java:546)\n\tat org.apache.cloudstack.storage.resource.NfsSecondaryStorageResource.execute(NfsSecondaryStorageResource.java:625)\n\tat org.apache.cloudstack.storage.resource.NfsSecondaryStorageResource.executeRequest(NfsSecondaryStorageResource.java:236)\n\tat com.cloud.storage.resource.PremiumSecondaryStorageResource.defaultAction(PremiumSecondaryStorageResource.java:63)\n\tat com.cloud.storage.resource.PremiumSecondaryStorageResource.executeRequest(PremiumSecondaryStorageResource.java:59)\n\tat com.cloud.agent.Agent.processRequest(Agent.java:498)\n\tat com.cloud.agent.Agent$AgentRequestHa:
--
This message was sent by Atlassian JIRA
(v6.1.4#6159)