Return-Path: X-Original-To: apmail-cloudstack-issues-archive@www.apache.org Delivered-To: apmail-cloudstack-issues-archive@www.apache.org Received: from mail.apache.org (hermes.apache.org [140.211.11.3]) by minotaur.apache.org (Postfix) with SMTP id 9571C10B30 for ; Wed, 11 Dec 2013 22:49:07 +0000 (UTC) Received: (qmail 8340 invoked by uid 500); 11 Dec 2013 22:49:07 -0000 Delivered-To: apmail-cloudstack-issues-archive@cloudstack.apache.org Received: (qmail 8299 invoked by uid 500); 11 Dec 2013 22:49:07 -0000 Mailing-List: contact issues-help@cloudstack.apache.org; run by ezmlm Precedence: bulk List-Help: List-Unsubscribe: List-Post: List-Id: Reply-To: dev@cloudstack.apache.org Delivered-To: mailing list issues@cloudstack.apache.org Received: (qmail 8292 invoked by uid 500); 11 Dec 2013 22:49:07 -0000 Delivered-To: apmail-incubator-cloudstack-issues@incubator.apache.org Received: (qmail 8288 invoked by uid 99); 11 Dec 2013 22:49:07 -0000 Received: from arcas.apache.org (HELO arcas.apache.org) (140.211.11.28) by apache.org (qpsmtpd/0.29) with ESMTP; Wed, 11 Dec 2013 22:49:07 +0000 Date: Wed, 11 Dec 2013 22:49:07 +0000 (UTC) From: "Rayees Namathponnan (JIRA)" To: cloudstack-issues@incubator.apache.org Message-ID: In-Reply-To: References: Subject: [jira] [Commented] (CLOUDSTACK-5334) [Automation] Failed to create template from snapshot while executing copy command, observed NPE In SSVM log MIME-Version: 1.0 Content-Type: text/plain; charset=utf-8 Content-Transfer-Encoding: quoted-printable X-JIRA-FingerPrint: 30527f35849b9dde25b450d4833f0394 [ https://issues.apache.org/jira/browse/CLOUDSTACK-5334?page=3Dcom.atla= ssian.jira.plugin.system.issuetabpanels:comment-tabpanel&focusedCommentId= =3D13845801#comment-13845801 ]=20 Rayees Namathponnan commented on CLOUDSTACK-5334: ------------------------------------------------- I tested again with latest KVM setup , facing same issue=20 > [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-533= 4 > Project: CloudStack > Issue Type: Bug > Security Level: Public(Anyone can view this level - this is the defa= ult.)=20 > 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=20 > Step 1 : snapshot root volume > Step 2 : Create template from snapshot=20 > Result=20 > Failed to create template from snapshot, observed below "copy command fai= lure 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 SNAPS= HOT copy > Async inspecting dest type TEMPLATE > 2013-12-02 08:28:49,418 DEBUG [c.c.a.t.Request] (Job-Executor-140:ctx-62f= 776d6 ctx-120aa5f1) Seq 8-1124074796: Sending { Cmd , MgmtId: 290661188773= 52, via: > 8(s-5-VM), Ver: v1, Flags: 100011, [{"org.apache.cloudstack.storage.comm= and.CopyCommand":{"srcTO":{"org.apache.cloudstack.storage.to.SnapshotObject= TO":{"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-810= 5-abd304bf7605_ROOT-526_20131202080509","hypervisorType":"KVM","id":20,"qui= escevm": > false}},"destTO":{"org.apache.cloudstack.storage.to.TemplateObjectTO":{"p= ath":"template/tmpl/282/236","uuid":"1e48c6bf-388e-4184-93f5-28a21b12329c",= "id":236 > ,"format":"RAW","accountId":282,"hvm":true,"displayText":"raytemp","image= DataStore":{"com.cloud.agent.api.to.NfsTO":{"_url":"nfs://10.223.110.232:/e= xport/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(NfsSecondaryStora= geResource.java:449)\n\tat org.apache.cloudstack.storage.resource.NfsSecond= aryStora > geResource.createTemplateFromSnapshot(NfsSecondaryStorageResource.java:54= 6)\n\tat org.apache.cloudstack.storage.resource.NfsSecondaryStorageResource= .execute > (NfsSecondaryStorageResource.java:625)\n\tat org.apache.cloudstack.storag= e.resource.NfsSecondaryStorageResource.executeRequest(NfsSecondaryStorageRe= source.j > ava:236)\n\tat com.cloud.storage.resource.PremiumSecondaryStorageResource= .defaultAction(PremiumSecondaryStorageResource.java:63)\n\tat com.cloud.sto= rage.res > ource.PremiumSecondaryStorageResource.executeRequest(PremiumSecondaryStor= ageResource.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.Thre= adPoolEx > 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-62f= 776d6 ctx-120aa5f1) Seq 8-1124074796: Received: { Ans: , MgmtId: 290661188= 77352, v > ia: 8, Ver: v1, Flags: 10, { Answer } } > 2013-12-02 08:28:49,663 DEBUG [c.c.t.TemplateManagerImpl] (Job-Executor-1= 40:ctx-62f776d6 ctx-120aa5f1) Failed to create templatejava.lang.NullPointe= rExcepti > on > at org.apache.cloudstack.storage.resource.NfsSecondaryStorageReso= urce.copySnapshotToTemplateFromNfsToNfs(NfsSecondaryStorageResource.java:44= 9) > at org.apache.cloudstack.storage.resource.NfsSecondaryStorageReso= urce.createTemplateFromSnapshot(NfsSecondaryStorageResource.java:546) > at org.apache.cloudstack.storage.resource.NfsSecondaryStorageReso= urce.execute(NfsSecondaryStorageResource.java:625) > at org.apache.cloudstack.storage.resource.NfsSecondaryStorageReso= urce.executeRequest(NfsSecondaryStorageResource.java:236) > at com.cloud.storage.resource.PremiumSecondaryStorageResource.def= aultAction(PremiumSecondaryStorageResource.java:63) > at com.cloud.storage.resource.PremiumSecondaryStorageResource.exe= cuteRequest(PremiumSecondaryStorageResource.java:59) > at com.cloud.agent.Agent.processRequest(Agent.java:498) > at com.cloud.agent.Agent$AgentRequestHandler.doTask(Agent.java:80= 6) > at com.cloud.utils.nio.Task.run(Task.java:83) > at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolEx= ecutor.java:1146) > at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolE= xecutor.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) Send= ing ping: Seq 8-263: { Cmd , MgmtId: -1, via: 8, Ver: v1, Flags: 11, [{"co= m.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":"Stor= age","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, V= er: v1, Flag > s: 100011, [{"org.apache.cloudstack.storage.command.CopyCommand":{"srcTO"= :{"org.apache.cloudstack.storage.to.SnapshotObjectTO":{"path":"snapshots/28= 2/589/d2b206 > 27-b60e-489b-9eee-9f4705331a72","dataStore":{"com.cloud.agent.api.to.NfsT= O":{"_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_201312020= 80509","hypervisorType":"KVM","id":20,"quiescevm":false}},"destTO":{"org.ap= ache.cloudst > ack.storage.to.TemplateObjectTO":{"path":"template/tmpl/282/236","uuid":"= 1e48c6bf-388e-4184-93f5-28a21b12329c","id":236,"format":"RAW","accountId":2= 82,"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.CopyComman= d > 2013-12-02 16:28:49,423 INFO [storage.resource.NfsSecondaryStorageResour= ce] (agentRequest-Handler-2:null) Determined host 10.223.110.232 correspond= s to IP 10.223.110.232 > 2013-12-02 16:28:49,509 INFO [storage.resource.NfsSecondaryStorageResour= ce] (agentRequest-Handler-2:null) Determined host 10.223.110.232 correspond= s 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.NfsSecondaryStorageReso= urce.copySnapshotToTemplateFromNfsToNfs(NfsSecondaryStorageResource.java:44= 9) > at org.apache.cloudstack.storage.resource.NfsSecondaryStorageReso= urce.createTemplateFromSnapshot(NfsSecondaryStorageResource.java:546) > at org.apache.cloudstack.storage.resource.NfsSecondaryStorageReso= urce.execute(NfsSecondaryStorageResource.java:625) > at org.apache.cloudstack.storage.resource.NfsSecondaryStorageReso= urce.executeRequest(NfsSecondaryStorageResource.java:236) > at com.cloud.storage.resource.PremiumSecondaryStorageResource.def= aultAction(PremiumSecondaryStorageResource.java:63) > at com.cloud.storage.resource.PremiumSecondaryStorageResource.exe= cuteRequest(PremiumSecondaryStorageResource.java:59) > at com.cloud.agent.Agent.processRequest(Agent.java:498) > at com.cloud.agent.Agent$AgentRequestHandler.doTask(Agent.java:80= 6) > at com.cloud.utils.nio.Task.run(Task.java:83) > at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolEx= ecutor.java:1146) > at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolE= xecutor.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.NfsS= econdaryStorageResource.copySnapshotToTemplateFromNfsToNfs(NfsSecondaryStor= ageResource.java:449)\n\tat org.apache.cloudstack.storage.resource.NfsSecon= daryStorageResource.createTemplateFromSnapshot(NfsSecondaryStorageResource.= java:546)\n\tat org.apache.cloudstack.storage.resource.NfsSecondaryStorageR= esource.execute(NfsSecondaryStorageResource.java:625)\n\tat org.apache.clou= dstack.storage.resource.NfsSecondaryStorageResource.executeRequest(NfsSecon= daryStorageResource.java:236)\n\tat com.cloud.storage.resource.PremiumSecon= daryStorageResource.defaultAction(PremiumSecondaryStorageResource.java:63)\= n\tat com.cloud.storage.resource.PremiumSecondaryStorageResource.executeReq= uest(PremiumSecondaryStorageResource.java:59)\n\tat com.cloud.agent.Agent.p= rocessRequest(Agent.java:498)\n\tat com.cloud.agent.Agent$AgentRequestHa: -- This message was sent by Atlassian JIRA (v6.1.4#6159)