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 9FE211087D for ; Tue, 23 Jul 2013 22:30:50 +0000 (UTC) Received: (qmail 61072 invoked by uid 500); 23 Jul 2013 22:30:50 -0000 Delivered-To: apmail-cloudstack-issues-archive@cloudstack.apache.org Received: (qmail 61051 invoked by uid 500); 23 Jul 2013 22:30:50 -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 61019 invoked by uid 500); 23 Jul 2013 22:30:50 -0000 Delivered-To: apmail-incubator-cloudstack-issues@incubator.apache.org Received: (qmail 61005 invoked by uid 99); 23 Jul 2013 22:30:50 -0000 Received: from arcas.apache.org (HELO arcas.apache.org) (140.211.11.28) by apache.org (qpsmtpd/0.29) with ESMTP; Tue, 23 Jul 2013 22:30:50 +0000 Date: Tue, 23 Jul 2013 22:30:49 +0000 (UTC) From: "Chandan Purushothama (JIRA)" To: cloudstack-issues@incubator.apache.org Message-ID: In-Reply-To: References: Subject: [jira] [Created] (CLOUDSTACK-3756) Object_Store_Refactor - [KVM] Snapshot Retention Policy Fails. MIME-Version: 1.0 Content-Type: text/plain; charset=utf-8 Content-Transfer-Encoding: quoted-printable X-JIRA-FingerPrint: 30527f35849b9dde25b450d4833f0394 Chandan Purushothama created CLOUDSTACK-3756: ------------------------------------------------ Summary: Object_Store_Refactor - [KVM] Snapshot Retention Poli= cy Fails. Key: CLOUDSTACK-3756 URL: https://issues.apache.org/jira/browse/CLOUDSTACK-3756 Project: CloudStack Issue Type: Bug Security Level: Public (Anyone can view this level - this is the defa= ult.) Components: Snapshot Affects Versions: 4.2.0 Reporter: Chandan Purushothama Priority: Critical Fix For: 4.2.0 =3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D= =3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D During the creation of a Hourly Snapshot - NPE is thrown: =3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D= =3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D 2013-07-23 14:51:47,359 DEBUG [agent.transport.Request] (Job-Executor-26:jo= b-55 =3D [ 70344d23-f8f7-49c2-8046-e3511ba2ee56 ]) Seq 1-710935896: Sending= { Cmd , MgmtId: 7471666038533, via: 1, Ver: v1, Flags: 100011, [{"org.apa= che.cloudstack.storage.command.CopyCommand":{"srcTO":{"org.apache.cloudstac= k.storage.to.SnapshotObjectTO":{"path":"/mnt/ef3dea99-0a1b-35b9-87ec-d8cabe= 7ed9c1/906ec8f4-e625-4943-aedd-aff3444150b9/d3b4dcd2-ae74-45cc-bf43-a61d133= 09e72","volume":{"uuid":"031802d8-c10a-4038-9acf-b86eba54b647","volumeType"= :"ROOT","dataStore":{"org.apache.cloudstack.storage.to.PrimaryDataStoreTO":= {"uuid":"ef3dea99-0a1b-35b9-87ec-d8cabe7ed9c1","id":1,"poolType":"NetworkFi= lesystem","host":"10.223.110.232","path":"/export/home/chandan/campocr-195-= 103/primarykvm","port":2049}},"name":"ROOT-6","size":147456,"path":"906ec8f= 4-e625-4943-aedd-aff3444150b9","volumeId":6,"vmName":"i-3-6-JULYLAST","acco= untId":3,"format":"QCOW2","id":6},"parentSnapshotPath":"/mnt/ef3dea99-0a1b-= 35b9-87ec-d8cabe7ed9c1/906ec8f4-e625-4943-aedd-aff3444150b9/d9190a5c-84a8-4= 928-be9f-6755e1b0d1e6","dataStore":{"org.apache.cloudstack.storage.to.Prima= ryDataStoreTO":{"uuid":"ef3dea99-0a1b-35b9-87ec-d8cabe7ed9c1","id":1,"poolT= ype":"NetworkFilesystem","host":"10.223.110.232","path":"/export/home/chand= an/campocr-195-103/primarykvm","port":2049}},"vmName":"i-3-6-JULYLAST","nam= e":"Atoms-VM-1_ROOT-6_20130723215146","hypervisorType":"KVM","id":11}},"des= tTO":{"org.apache.cloudstack.storage.to.SnapshotObjectTO":{"path":"snapshot= s/3/6","volume":{"uuid":"031802d8-c10a-4038-9acf-b86eba54b647","volumeType"= :"ROOT","dataStore":{"org.apache.cloudstack.storage.to.PrimaryDataStoreTO":= {"uuid":"ef3dea99-0a1b-35b9-87ec-d8cabe7ed9c1","id":1,"poolType":"NetworkFi= lesystem","host":"10.223.110.232","path":"/export/home/chandan/campocr-195-= 103/primarykvm","port":2049}},"name":"ROOT-6","size":147456,"path":"906ec8f= 4-e625-4943-aedd-aff3444150b9","volumeId":6,"vmName":"i-3-6-JULYLAST","acco= untId":3,"format":"QCOW2","id":6},"parentSnapshotPath":"snapshots/3/6/d9190= a5c-84a8-4928-be9f-6755e1b0d1e6","dataStore":{"com.cloud.agent.api.to.NfsTO= ":{"_url":"nfs://10.223.110.232/export/home/chandan/campocr-195-103/seconda= ry/","_role":"Image"}},"vmName":"i-3-6-JULYLAST","name":"Atoms-VM-1_ROOT-6_= 20130723215146","hypervisorType":"KVM","id":11}},"executeInSequence":false,= "wait":21600}}] } 2013-07-23 14:52:50,158 DEBUG [agent.transport.Request] (Job-Executor-26:jo= b-55 =3D [ 70344d23-f8f7-49c2-8046-e3511ba2ee56 ]) Seq 1-710935896: Receive= d: { Ans: , MgmtId: 7471666038533, via: 1, Ver: v1, Flags: 10, { CopyCmdAn= swer } } 2013-07-23 14:52:50,232 DEBUG [storage.snapshot.SnapshotManagerImpl] (Job-E= xecutor-26:job-55 =3D [ 70344d23-f8f7-49c2-8046-e3511ba2ee56 ]) Max snaps: = 2 exceeded for snapshot policy with Id: 3. Deleting oldest snapshot: 5 2013-07-23 14:52:50,277 DEBUG [cloud.user.AccountManagerImpl] (Job-Executor= -26:job-55 =3D [ 70344d23-f8f7-49c2-8046-e3511ba2ee56 ]) Access to com.clou= d.storage.SnapshotVO$$EnhancerByCGLIB$$59847169@f1779fa granted to Acct[3-a= toms] by DomainChecker_EnhancerByCloudStack_ccb7a71 2013-07-23 14:52:50,315 DEBUG [storage.snapshot.SnapshotManagerImpl] (Job-E= xecutor-26:job-55 =3D [ 70344d23-f8f7-49c2-8046-e3511ba2ee56 ]) Max snaps: = 2 exceeded for snapshot policy with Id: 3. Deleting oldest snapshot: 7 2013-07-23 14:52:50,321 DEBUG [cloud.user.AccountManagerImpl] (Job-Executor= -26:job-55 =3D [ 70344d23-f8f7-49c2-8046-e3511ba2ee56 ]) Access to com.clou= d.storage.SnapshotVO$$EnhancerByCGLIB$$59847169@1db4492d granted to Acct[3-= atoms] by DomainChecker_EnhancerByCloudStack_ccb7a71 2013-07-23 14:52:50,346 DEBUG [storage.snapshot.SnapshotManagerImpl] (Job-E= xecutor-26:job-55 =3D [ 70344d23-f8f7-49c2-8046-e3511ba2ee56 ]) Max snaps: = 2 exceeded for snapshot policy with Id: 3. Deleting oldest snapshot: 8 2013-07-23 14:52:50,353 DEBUG [cloud.user.AccountManagerImpl] (Job-Executor= -26:job-55 =3D [ 70344d23-f8f7-49c2-8046-e3511ba2ee56 ]) Access to com.clou= d.storage.SnapshotVO$$EnhancerByCGLIB$$59847169@7bca0fdb granted to Acct[3-= atoms] by DomainChecker_EnhancerByCloudStack_ccb7a71 2013-07-23 14:52:50,361 DEBUG [storage.snapshot.XenserverSnapshotStrategy] = (Job-Executor-26:job-55 =3D [ 70344d23-f8f7-49c2-8046-e3511ba2ee56 ]) delet= e snapshot chain for snapshot: 8 2013-07-23 14:52:50,363 DEBUG [storage.snapshot.XenserverSnapshotStrategy] = (Job-Executor-26:job-55 =3D [ 70344d23-f8f7-49c2-8046-e3511ba2ee56 ]) Snaps= hot: 8 doesn't have children, so it's ok to delete it and its parents 2013-07-23 14:52:50,383 DEBUG [agent.manager.AgentAttache] (Job-Executor-26= :job-55 =3D [ 70344d23-f8f7-49c2-8046-e3511ba2ee56 ]) Request seq: 10395323= 22 2013-07-23 14:52:50,383 DEBUG [agent.manager.AgentAttache] (Job-Executor-26= :job-55 =3D [ 70344d23-f8f7-49c2-8046-e3511ba2ee56 ]) waiting to send 10395= 32322 2013-07-23 14:52:50,383 DEBUG [agent.manager.AgentAttache] (Job-Executor-26= :job-55 =3D [ 70344d23-f8f7-49c2-8046-e3511ba2ee56 ]) entering synchronize = block for sending 1039532322 2013-07-23 14:52:50,383 DEBUG [agent.transport.Request] (Job-Executor-26:jo= b-55 =3D [ 70344d23-f8f7-49c2-8046-e3511ba2ee56 ]) Seq 3-1039532322: Sendin= g { Cmd , MgmtId: 7471666038533, via: 3, Ver: v1, Flags: 100011, [{"org.ap= ache.cloudstack.storage.command.DeleteCommand":{"data":{"org.apache.cloudst= ack.storage.to.SnapshotObjectTO":{"path":"snapshots/3/6/99b5f9f3-9c6d-4db7-= af19-05106fe6e1cc","volume":{"uuid":"031802d8-c10a-4038-9acf-b86eba54b647",= "volumeType":"ROOT","dataStore":{"org.apache.cloudstack.storage.to.PrimaryD= ataStoreTO":{"uuid":"ef3dea99-0a1b-35b9-87ec-d8cabe7ed9c1","id":1,"poolType= ":"NetworkFilesystem","host":"10.223.110.232","path":"/export/home/chandan/= campocr-195-103/primarykvm","port":2049}},"name":"ROOT-6","size":147456,"pa= th":"906ec8f4-e625-4943-aedd-aff3444150b9","volumeId":6,"vmName":"i-3-6-JUL= YLAST","accountId":3,"format":"QCOW2","id":6},"dataStore":{"com.cloud.agent= .api.to.NfsTO":{"_url":"nfs://10.223.110.232/export/home/chandan/campocr-19= 5-103/secondary/","_role":"Image"}},"vmName":"i-3-6-JULYLAST","name":"Atoms= -VM-1_ROOT-6_20130723195146","hypervisorType":"KVM","id":8}},"wait":0}}] } 2013-07-23 14:52:56,181 DEBUG [agent.transport.Request] (Job-Executor-26:jo= b-55 =3D [ 70344d23-f8f7-49c2-8046-e3511ba2ee56 ]) Seq 3-1039532322: Receiv= ed: { Ans: , MgmtId: 7471666038533, via: 3, Ver: v1, Flags: 10, { Answer }= } 2013-07-23 14:52:56,195 DEBUG [storage.snapshot.XenserverSnapshotStrategy] = (Job-Executor-26:job-55 =3D [ 70344d23-f8f7-49c2-8046-e3511ba2ee56 ]) Faile= d to delete snapshot: java.lang.NullPointerException at org.apache.cloudstack.storage.snapshot.XenserverSnapshotStrategy= .deleteSnapshot(XenserverSnapshotStrategy.java:192) at com.cloud.storage.snapshot.SnapshotManagerImpl.deleteSnapshot(Sn= apshotManagerImpl.java:496) at com.cloud.utils.component.ComponentInstantiationPostProcessor$In= terceptorDispatcher.intercept(ComponentInstantiationPostProcessor.java:125) at com.cloud.storage.snapshot.SnapshotManagerImpl.postCreateRecurri= ngSnapshotForPolicy(SnapshotManagerImpl.java:467) at com.cloud.storage.snapshot.SnapshotManagerImpl.postCreateSnapsho= t(SnapshotManagerImpl.java:448) at com.cloud.storage.snapshot.SnapshotManagerImpl.takeSnapshot(Snap= shotManagerImpl.java:999) at org.apache.cloudstack.storage.volume.VolumeServiceImpl.takeSnaps= hot(VolumeServiceImpl.java:1185) at com.cloud.storage.VolumeManagerImpl.takeSnapshot(VolumeManagerIm= pl.java:2654) at org.apache.cloudstack.api.command.user.snapshot.CreateSnapshotCm= d.execute(CreateSnapshotCmd.java:170) at com.cloud.api.ApiDispatcher.dispatch(ApiDispatcher.java:158) at com.cloud.async.AsyncJobManagerImpl$1.run(AsyncJobManagerImpl.ja= va:531) at java.util.concurrent.Executors$RunnableAdapter.call(Executors.ja= va:471) at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:33= 4) at java.util.concurrent.FutureTask.run(FutureTask.java:166) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExec= utor.java:1110) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExe= cutor.java:603) at java.lang.Thread.run(Thread.java:679) 2013-07-23 14:52:56,195 DEBUG [storage.snapshot.XenserverSnapshotStrategy] = (Job-Executor-26:job-55 =3D [ 70344d23-f8f7-49c2-8046-e3511ba2ee56 ]) Faile= d to change snapshot state: java.lang.NullPointerException 2013-07-23 14:52:56,292 DEBUG [cloud.async.AsyncJobManagerImpl] (Job-Execut= or-26:job-55 =3D [ 70344d23-f8f7-49c2-8046-e3511ba2ee56 ]) Complete async j= ob-55 =3D [ 70344d23-f8f7-49c2-8046-e3511ba2ee56 ], jobStatus: 1, resultCod= e: 0, result: org.apache.cloudstack.api.response.SnapshotResponse@1b581772 2013-07-23 14:52:56,297 DEBUG [cloud.async.AsyncJobManagerImpl] (Job-Execut= or-26:job-55 =3D [ 70344d23-f8f7-49c2-8046-e3511ba2ee56 ]) Done executing o= rg.apache.cloudstack.api.command.user.snapshot.CreateSnapshotCmd for job-55= =3D [ 70344d23-f8f7-49c2-8046-e3511ba2ee56 ] -- This message is automatically generated by JIRA. If you think it was sent incorrectly, please contact your JIRA administrato= rs For more information on JIRA, see: http://www.atlassian.com/software/jira