[
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
