[ https://issues.apache.org/jira/browse/CLOUDSTACK-2905?page=com.atlassian.jira.plugin.system.issuetabpanels:comment-tabpanel&focusedCommentId=13685465#comment-13685465 ]
Rajesh Battala commented on CLOUDSTACK-2905: -------------------------------------------- Snapshot scheduler.poll is being called at 2013-06-17 11:14:12 GMT 2013-06-17 16:44:12,713 DEBUG [storage.snapshot.SnapshotSchedulerImpl] (SnapshotPollTask:null) Got 1 snapshots to be executed at 2013-06-17 11:14:12 GMT 2013-06-17 16:44:12,718 DEBUG [storage.snapshot.SnapshotSchedulerImpl] (SnapshotPollTask:null) Scheduling 1 snapshot for volume 13 for schedule id: 6 at 2013-06-17 11:07:00 GMT 2013-06-17 16:44:12,896 DEBUG [cloud.async.AsyncJobManagerImpl] (SnapshotPollTask:null) submit async job-59, details: AsyncJobVO {id:59, userId: 1, accountId: 2, sessionKey: null, instanceType: Snapshot, instanceId: 14, cmd: org.apache.cloudstack.api.command.user.snapshot.CreateSnapshotCmd, cmdOriginator: null, cmdInfo: {"id":"14","ctxUserId":"1","volumeid":"13","ctxAccountId":"2","ctxStartEventId":"1","policyid":"6"}, cmdVersion: 0, callbackType: 0, callbackAddress: null, status: 0, processStatus: 0, resultCode: 0, result: null, initMsid: 235715300172635, completeMsid: null, lastUpdated: null, lastPolled: null, created: null} 2013-06-17 16:44:12,902 DEBUG [cloud.async.AsyncJobManagerImpl] (Job-Executor-1:job-59) Executing org.apache.cloudstack.api.command.user.snapshot.CreateSnapshotCmd for job-59 2013-06-17 16:44:12,967 INFO [user.snapshot.CreateSnapshotCmd] (Job-Executor-1:job-59) VOLSS: createSnapshotCmd starts:1371467652967 2013-06-17 16:44:13,208 DEBUG [agent.transport.Request] (Job-Executor-1:job-59) Seq 1-2135752751: Sending { Cmd , MgmtId: 235715300172635, via: 1, Ver: v1, Flags: 100011, [{"ManageSnapshotCommand":{"_commandSwitch":"-c","_volumePath":"cf82064c-3803-4e1a-be7c-107db16a6136","_pool":{"id":1,"uuid":"d8490c13-0efb-3e81-b6a5-fe84779d70be","host":"mystorageserver","path":"/cpg_vol/rajesh/primary","port":2049,"type":"NetworkFilesystem"},"_snapshotName":"7d00b765-b3f0-48f9-88b7-c6097b5e20f6_20130617111412","_snapshotId":14,"_vmName":"i-2-8-VM","wait":0}}] } ... ... 2013-06-17 16:44:28,729 DEBUG [agent.transport.Request] (DirectAgent-19:null) Seq 1-2135752752: Processing: { Ans: , MgmtId: 235715300172635, via: 1, Ver: v1, Flags: 10, [{"BackupSnapshotAnswer":{"backupSnapshotName":"a0f5554d-38c9-4222-93a5-659aceb45f19","full":true,"result":true,"details":"Successfully backedUp the snapsho Uuid: 9071e524-ecf3-4c21-992b-207a31439028 to secondary storage.","wait":0}}] } 2013-06-17 16:44:28,730 DEBUG [agent.transport.Request] (Job-Executor-1:job-59) Seq 1-2135752752: Received: { Ans: , MgmtId: 235715300172635, via: 1, Ver: v1, Flags: 10, { BackupSnapshotAnswer } } 2013-06-17 16:44:28,790 INFO [user.snapshot.CreateSnapshotCmd] (Job-Executor-1:job-59) VOLSS: backupSnapshotCmd finishes:1371467668790 2013-06-17 16:44:28,790 DEBUG [cloud.async.AsyncJobManagerImpl] (Job-Executor-1:job-59) Complete async job-59, jobStatus: 1, resultCode: 0, result: org.apache.cloudstack.api.response.SnapshotResponse@1407344 2013-06-17 16:44:28,814 DEBUG [cloud.async.AsyncJobManagerImpl] (Job-Executor-1:job-59) Done executing org.apache.cloudstack.api.command.user.snapshot.CreateSnapshotCmd for job-59 > Recurring Snapshots are failing becuase of NullPointerException. > ---------------------------------------------------------------- > > Key: CLOUDSTACK-2905 > URL: https://issues.apache.org/jira/browse/CLOUDSTACK-2905 > Project: CloudStack > Issue Type: Bug > Security Level: Public(Anyone can view this level - this is the > default.) > Components: Management Server > Affects Versions: 4.1.0, 4.2.0 > Environment: cloudstack-management-4.1.0-0.el6.x86_64 > Reporter: satoru nakaya > Assignee: Rajesh Battala > Priority: Blocker > Fix For: 4.1.1, 4.2.0 > > > Create an Hourly Recurring snapshot policy on the ROOT disk. > Snapshot policy gets created successfully. > But when it is time to create a snapshot, snapshot scheduler thread > encounters a NullPointer Exception: > Management server logs: > # tail -f management-server.log | grep SnapshotSchedulerImpl > 2013-06-08 08:06:11,393 DEBUG [storage.snapshot.SnapshotSchedulerImpl] > (SnapshotPollTask:null) Snapshot scheduler.poll is being called at 2013-06-07 > 23:06:11 GMT > 2013-06-08 08:06:11,395 DEBUG [storage.snapshot.SnapshotSchedulerImpl] > (SnapshotPollTask:null) Got 0 snapshots to be executed at 2013-06-07 23:06:11 > GMT > 2013-06-08 08:08:39,459 DEBUG [storage.snapshot.SnapshotSchedulerImpl] > (catalina-exec-4:null) Current time is 2013-06-07 23:06:11 GMT. > NextScheduledTime of policyId 1 is 2013-06-07 23:10:00 GMT > 2013-06-08 08:11:11,393 DEBUG [storage.snapshot.SnapshotSchedulerImpl] > (SnapshotPollTask:null) Snapshot scheduler.poll is being called at 2013-06-07 > 23:11:11 GMT > 2013-06-08 08:11:11,397 DEBUG [storage.snapshot.SnapshotSchedulerImpl] > (SnapshotPollTask:null) Got 1 snapshots to be executed at 2013-06-07 23:11:11 > GMT > 2013-06-08 08:11:11,400 DEBUG [storage.snapshot.SnapshotSchedulerImpl] > (SnapshotPollTask:null) Scheduling 1 snapshot for volume 3 for schedule id: 1 > at 2013-06-07 23:10:00 GMT > 2013-06-08 08:11:11,453 WARN [storage.snapshot.SnapshotSchedulerImpl] > (SnapshotPollTask:null) Scheduling snapshot failed due to > java.lang.NullPointerException -- This message is automatically generated by JIRA. If you think it was sent incorrectly, please contact your JIRA administrators For more information on JIRA, see: http://www.atlassian.com/software/jira