[
https://issues.apache.org/jira/browse/CLOUDSTACK-1353?page=com.atlassian.jira.plugin.system.issuetabpanels:all-tabpanel
]
angeline shen updated CLOUDSTACK-1353:
--------------------------------------
Attachment: management-server.log.gz
> KVM 6.3 snapshot Scheduling snapshot failed due to
> java.lang.NullPointerException
> ---------------------------------------------------------------------------------
>
> Key: CLOUDSTACK-1353
> URL: https://issues.apache.org/jira/browse/CLOUDSTACK-1353
> 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
> Environment: MS rhel6.3 ASF 4.1 latest build
> host rhel6.3 ASF 4.1 latest build
> Reporter: angeline shen
> Assignee: Fang Wang
> Priority: Critical
> Fix For: 4.1.0
>
> Attachments: management-server.log.gz
>
>
> 1. advance zone. create VMs
> 2. create snapshot schedule policies foro ROOT and DATA volumes
> Result:
> Scheduling snapshot failed and no snapshots were generated.
> 2013-02-20 20:15:20,045 DEBUG [agent.manager.AgentManagerImpl]
> (AgentManager-Handler-7:null) Ping from 1
> 2013-02-20 20:15:26,527 DEBUG [storage.snapshot.SnapshotSchedulerImpl]
> (SnapshotPollTask:null) Snapshot scheduler.poll is being called at 2013-02-21
> 04:15:26 GMT
> 2013-02-20 20:15:26,528 DEBUG [storage.snapshot.SnapshotSchedulerImpl]
> (SnapshotPollTask:null) Got 9 snapshots to be executed at 2013-02-21 04:15:26
> GMT
> 2013-02-20 20:15:26,530 DEBUG [storage.snapshot.SnapshotSchedulerImpl]
> (SnapshotPollTask:null) Scheduling 1 snapshot for volume 5 for schedule id: 1
> at 2013-02-21 02:15:00 GMT
> 2013-02-20 20:15:26,670 WARN [storage.snapshot.SnapshotSchedulerImpl]
> (SnapshotPollTask:null) Scheduling snapshot failed due to
> java.lang.NullPointerException
> 2013-02-20 20:15:26,674 WARN [utils.db.Merovingian2] (SnapshotPollTask:null)
> Was unable to find lock for the key snapshot_schedule1 and thread id
> 1759395050
> 2013-02-20 20:15:26,677 DEBUG [storage.snapshot.SnapshotSchedulerImpl]
> (SnapshotPollTask:null) Scheduling 1 snapshot for volume 4 for schedule id: 2
> at 2013-02-21 02:20:00 GMT
> 2013-02-20 20:15:26,685 WARN [storage.snapshot.SnapshotSchedulerImpl]
> (SnapshotPollTask:null) Scheduling snapshot failed due to
> java.lang.NullPointerException
> 2013-02-20 20:15:26,686 WARN [utils.db.Merovingian2] (SnapshotPollTask:null)
> Was unable to find lock for the key snapshot_schedule2 and thread id
> 1759395050
> 2013-02-20 20:15:26,689 DEBUG [storage.snapshot.SnapshotSchedulerImpl]
> (SnapshotPollTask:null) Scheduling 1 snapshot for volume 7 for schedule id: 3
> at 2013-02-21 02:45:00 GMT
> 2013-02-20 20:15:26,697 WARN [storage.snapshot.SnapshotSchedulerImpl]
> (SnapshotPollTask:null) Scheduling snapshot failed due to
> java.lang.NullPointerException
> 2013-02-20 20:15:26,697 WARN [utils.db.Merovingian2] (SnapshotPollTask:null)
> Was unable to find lock for the key snapshot_schedule3 and thread id
> 1759395050
> 2013-02-20 20:15:26,700 DEBUG [storage.snapshot.SnapshotSchedulerImpl]
> (SnapshotPollTask:null) Scheduling 1 snapshot for volume 14 for schedule id:
> 5 at 2013-02-21 02:50:00 GMT
> 2013-02-20 20:15:26,708 WARN [storage.snapshot.SnapshotSchedulerImpl]
> (SnapshotPollTask:null) Scheduling snapshot failed due to
> java.lang.NullPointerException
> 2013-02-20 20:15:26,708 WARN [utils.db.Merovingian2] (SnapshotPollTask:null)
> Was unable to find lock for the key snapshot_schedule5 and thread id
> 1759395050
> 2013-02-20 20:15:26,726 DEBUG [storage.snapshot.SnapshotSchedulerImpl]
> (SnapshotPollTask:null) Scheduling 1 snapshot for volume 6 for schedule id: 6
> at 2013-02-21 02:51:00 GMT
> 2013-02-20 20:15:26,786 WARN [storage.snapshot.SnapshotSchedulerImpl]
> (SnapshotPollTask:null) Scheduling snapshot failed due to
> java.lang.NullPointerException
> 2013-02-20 20:15:26,787 WARN [utils.db.Merovingian2] (SnapshotPollTask:null)
> Was unable to find lock for the key snapshot_schedule6 and thread id
> 1759395050
> 2013-02-20 20:15:26,821 DEBUG [storage.snapshot.SnapshotSchedulerImpl]
> (SnapshotPollTask:null) Scheduling 1 snapshot for volume 8 for schedule id: 7
> at 2013-02-21 02:52:00 GMT
> 2013-02-20 20:15:26,869 WARN [storage.snapshot.SnapshotSchedulerImpl]
> (SnapshotPollTask:null) Scheduling snapshot failed due to
> java.lang.NullPointerException
> 2013-02-20 20:15:26,870 WARN [utils.db.Merovingian2] (SnapshotPollTask:null)
> Was unable to find lock for the key snapshot_schedule7 and thread id
> 1759395050
> 2013-02-20 20:15:26,900 DEBUG [storage.snapshot.SnapshotSchedulerImpl]
> (SnapshotPollTask:null) Scheduling 1 snapshot for volume 11 for schedule id:
> 8 at 2013-02-21 02:53:00 GMT
> 2013-02-20 20:15:26,948 WARN [storage.snapshot.SnapshotSchedulerImpl]
> (SnapshotPollTask:null) Scheduling snapshot failed due to
> java.lang.NullPointerException
> 2013-02-20 20:15:26,949 WARN [utils.db.Merovingian2] (SnapshotPollTask:null)
> Was unable to find lock for the key snapshot_schedule8 and thread id
> 1759395050
> 2013-02-20 20:15:26,953 DEBUG [storage.snapshot.SnapshotSchedulerImpl]
> (SnapshotPollTask:null) Scheduling 1 snapshot for volume 15 for schedule id:
> 9 at 2013-02-21 02:54:00 GMT
> 2013-02-20 20:15:27,027 WARN [storage.snapshot.SnapshotSchedulerImpl]
> (SnapshotPollTask:null) Scheduling snapshot failed due to
> java.lang.NullPointerException
> 2013-02-20 20:15:27,027 WARN [utils.db.Merovingian2] (SnapshotPollTask:null)
> Was unable to find lock for the key snapshot_schedule9 and thread id
> 1759395050
> 2013-02-20 20:15:27,029 DEBUG [storage.snapshot.SnapshotSchedulerImpl]
> (SnapshotPollTask:null) Scheduling 1 snapshot for volume 10 for schedule id:
> 10 at 2013-02-21 03:30:00 GMT
> 2013-02-20 20:15:27,060 WARN [storage.snapshot.SnapshotSchedulerImpl]
> (SnapshotPollTask:null) Scheduling snapshot failed due to
> java.lang.NullPointerException
> 2013-02-20 20:15:27,060 WARN [utils.db.Merovingian2] (SnapshotPollTask:null)
> Was unable to find lock for the key snapshot_schedule10 and thread id
> 1759395050
> 2013-02-20 20:15:39,666 DEBUG [storage.secondary.SecondaryStorageManagerImpl]
> (secstorage-1:null) Zone 1 is ready to launch secondary storage VM
> mysql> select * from snapshot_schedule;
> +----+--------------------------------------+-----------+-----------+---------------------+--------------+-------------+
> | id | uuid | volume_id | policy_id |
> scheduled_timestamp | async_job_id | snapshot_id |
> +----+--------------------------------------+-----------+-----------+---------------------+--------------+-------------+
> | 1 | 1508fba4-c066-4118-917f-ba13b4a2a66c | 5 | 1 |
> 2013-02-21 02:15:00 | NULL | NULL |
> | 2 | c276a712-45a9-46a8-8820-7be3b4b0cf84 | 4 | 2 |
> 2013-02-21 02:20:00 | NULL | NULL |
> | 3 | ee877637-75c4-451e-ac46-4055ee977cb3 | 7 | 3 |
> 2013-02-21 02:45:00 | NULL | NULL |
> | 5 | 3be4be13-7a5e-4d6d-a1c2-1640888ab6dc | 14 | 5 |
> 2013-02-21 02:50:00 | NULL | NULL |
> | 6 | c28249c5-3f79-457d-aad4-4477ac7a3b5d | 6 | 6 |
> 2013-02-21 02:51:00 | NULL | NULL |
> | 7 | 2f1e2794-5cb2-459c-865d-765f11f1ca45 | 8 | 7 |
> 2013-02-21 02:52:00 | NULL | NULL |
> | 8 | b2c9eb30-cad4-4347-97fa-a6f579f55913 | 11 | 8 |
> 2013-02-21 02:53:00 | NULL | NULL |
> | 9 | 0a6c2da0-4f61-4395-86f0-7e3e2a214983 | 15 | 9 |
> 2013-02-21 02:54:00 | NULL | NULL |
> | 10 | 9c4f4a1d-17e4-45e8-b679-f35a553f955e | 10 | 10 |
> 2013-02-21 03:30:00 | NULL | NULL |
> +----+--------------------------------------+-----------+-----------+---------------------+--------------+-------------+
> 9 rows in set (0.00 sec)
> mysql> select * from snapshot_policy;
> +----+--------------------------------------+-----------+----------+---------------------+----------+-----------+--------+
> | id | uuid | volume_id | schedule | timezone
> | interval | max_snaps | active |
> +----+--------------------------------------+-----------+----------+---------------------+----------+-----------+--------+
> | 1 | ec9b0586-c492-481c-ae59-32d8e8b0e1bf | 5 | 15 |
> America/Los_Angeles | 0 | 1 | 1 |
> | 2 | 6b7fa0ca-d4b1-4403-a65a-63e374f7d0cf | 4 | 20 |
> America/Los_Angeles | 0 | 1 | 1 |
> | 3 | 4838d3fa-9111-49ed-961d-6d86ec72c8db | 7 | 45 |
> America/Los_Angeles | 0 | 2 | 1 |
> | 5 | bd8237c6-22ae-491f-91ed-15d60eddda97 | 14 | 50 |
> America/Los_Angeles | 0 | 1 | 1 |
> | 6 | 6593e5c5-4991-4026-91fd-ce9ffe27ee6d | 6 | 51 |
> America/Los_Angeles | 0 | 1 | 1 |
> | 7 | b6c014c1-fe43-4ca7-b6d4-89f5662de8f2 | 8 | 52 |
> America/Los_Angeles | 0 | 1 | 1 |
> | 8 | d534cffb-9d3e-4b77-80a5-e7d5af0966e9 | 11 | 53 |
> America/Los_Angeles | 0 | 1 | 1 |
> | 9 | f5b84149-ae3d-44e0-ad33-80180e83577b | 15 | 54 |
> America/Los_Angeles | 0 | 1 | 1 |
> | 10 | fc6822b0-6adb-4f39-aecc-3de8273f989e | 10 | 30 |
> America/Los_Angeles | 0 | 1 | 1 |
> +----+--------------------------------------+-----------+----------+---------------------+----------+-----------+--------+
> 9 rows in set (0.00 sec)
> mysql> select * from snapshots;
> +----+----------------+------------+-----------+-----------+------------------+----------+----------------------------------------------------------------------------------------------------------------------------+---------------------------------------------+--------------------------------------+---------------+------------------+------------+---------------------+---------+--------------------------------------------------------------+----------+------------+--------------+-----------------+---------+-------+
> | id | data_center_id | account_id | domain_id | volume_id | disk_offering_id
> | status | path
> | name
> | uuid |
> snapshot_type | type_description | size | created | removed
> | backup_snap_id | swift_id |
> sechost_id | prev_snap_id | hypervisor_type | version | s3_id |
> +----+----------------+------------+-----------+-----------+------------------+----------+----------------------------------------------------------------------------------------------------------------------------+---------------------------------------------+--------------------------------------+---------------+------------------+------------+---------------------+---------+--------------------------------------------------------------+----------+------------+--------------+-----------------+---------+-------+
> | 1 | 1 | 2 | 1 | 5 | 11
> | BackedUp |
> /mnt/f2cbe828-eb33-3f49-8175-20639eceddef/4e191ea6-0ee2-42a9-a2a9-14cae0abb5dc/z1vpc1G1admindataV2_ROOT-13_20130221010733
> | z1vpc1G1admindataV2_ROOT-13_20130221010733 |
> 7f4991d7-c781-4b58-a449-e7c5f71c4257 | 0 | MANUAL |
> 8589934592 | 2013-02-21 01:07:33 | NULL |
> /snapshots/1/2/5/z1vpc1G1admindataV2_ROOT-13_20130221010733 | NULL |
> 2 | 0 | KVM | 2.2 | NULL |
> | 2 | 1 | 2 | 1 | 6 | 3
> | Creating | NULL
> |
> z1vpc1G1admindataV2_DATA-13_20130221010741 |
> 32a5f811-23f1-424b-a577-cdd9465f743a | 0 | MANUAL |
> 5368709120 | 2013-02-21 01:07:41 | NULL | NULL
> | NULL | NULL | 0 | KVM
> | 2.2 | NULL |
> | 3 | 1 | 2 | 1 | 4 | 11
> | BackedUp |
> /mnt/f2cbe828-eb33-3f49-8175-20639eceddef/c4dcf515-b908-4a6f-8b9e-b5a249be0764/z1vpc1G1adminnodataV1_ROOT-4_20130221010755
> | z1vpc1G1adminnodataV1_ROOT-4_20130221010755 |
> f58f8917-b6ab-4e90-9470-60af17117cdb | 0 | MANUAL |
> 8589934592 | 2013-02-21 01:07:55 | NULL |
> /snapshots/1/2/4/z1vpc1G1adminnodataV1_ROOT-4_20130221010755 | NULL |
> 2 | 0 | KVM | 2.2 | NULL |
> | 4 | 1 | 2 | 1 | 7 | 11
> | BackedUp |
> /mnt/f2cbe828-eb33-3f49-8175-20639eceddef/6ab4d56c-4586-40fa-997f-bc536af055c9/z1isolateV40_ROOT-27_20130221021514
> | z1isolateV40_ROOT-27_20130221021514 |
> 53128535-aa59-476f-8818-ef970355e210 | 0 | MANUAL |
> 8589934592 | 2013-02-21 02:15:14 | NULL |
> /snapshots/1/2/7/z1isolateV40_ROOT-27_20130221021514 | NULL |
> 2 | 0 | KVM | 2.2 | NULL |
> | 5 | 1 | 2 | 1 | 8 | 3
> | Creating | NULL
> |
> z1isolateV40_DATA-27_20130221021551 |
> 87d7bd03-2b3d-497b-b3cb-4a8b31b6470f | 0 | MANUAL |
> 5368709120 | 2013-02-21 02:15:51 | NULL | NULL
> | NULL | NULL | 0 | KVM
> | 2.2 | NULL |
> | 6 | 1 | 2 | 1 | 12 | 11
> | BackedUp |
> /mnt/f2cbe828-eb33-3f49-8175-20639eceddef/9876e6de-438d-45db-9e95-f229fc202809/z1vpc1G1dataV7_ROOT-39_20130221032856
> | z1vpc1G1dataV7_ROOT-39_20130221032856 |
> 12ed6e1d-7536-4fa1-83e6-359d896cb5a0 | 0 | MANUAL |
> 8589934592 | 2013-02-21 03:28:56 | NULL |
> /snapshots/1/2/12/z1vpc1G1dataV7_ROOT-39_20130221032856 | NULL |
> 2 | 0 | KVM | 2.2 | NULL |
> +----+----------------+------------+-----------+-----------+------------------+----------+----------------------------------------------------------------------------------------------------------------------------+---------------------------------------------+--------------------------------------+---------------+------------------+------------+---------------------+---------+--------------------------------------------------------------+----------+------------+--------------+-----------------+---------+-------+
--
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
