Attachment 944300 Details for Bug 1149770 – log of failed BROKEN snapshot removal ...

log of failed BROKEN snapshot removal ...

snapshot.log (text/plain), 7.70 KB, created by gody1 on 2014-10-06 15:15:50 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: gody1

Created: 2014-10-06 15:15:50 UTC

Size: 7.70 KB

patch

obsolete

>2014-10-06 15:28:11,337 INFO  [org.ovirt.engine.core.bll.RemoveSnapshotCommand] (ajp-/127.0.0.1:8702-9) [16e90c2f] Lock Acquired to object EngineLock [exclusiveLocks= key: 5dfd843e-2268-4286-bfa0-175e5cdb8cb5 value: VM
>, sharedLocks= ]
>2014-10-06 15:28:11,400 INFO  [org.ovirt.engine.core.bll.RemoveSnapshotCommand] (ajp-/127.0.0.1:8702-9) [16e90c2f] Running command: RemoveSnapshotCommand internal: false. Entities affected :  ID: 5dfd843e-2268-4286-bfa0-175e5cdb8cb5 Type: VM
>2014-10-06 15:28:11,417 INFO  [org.ovirt.engine.core.bll.RemoveSnapshotCommand] (ajp-/127.0.0.1:8702-9) [16e90c2f] Lock freed to object EngineLock [exclusiveLocks= key: 5dfd843e-2268-4286-bfa0-175e5cdb8cb5 value: VM
>, sharedLocks= ]
>2014-10-06 15:28:11,444 INFO  [org.ovirt.engine.core.bll.RemoveSnapshotSingleDiskCommand] (ajp-/127.0.0.1:8702-9) [39ca1ee5] Running command: RemoveSnapshotSingleDiskCommand internal: true. Entities affected :  ID: 00000000-0000-0000-0000-000000000000 Type: Storage
>2014-10-06 15:28:11,453 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.MergeSnapshotsVDSCommand] (ajp-/127.0.0.1:8702-9) [39ca1ee5] START, MergeSnapshotsVDSCommand( storagePoolId = 5849b030-626e-47cb-ad90-3ce782d831b3, ignoreFailoverLimit = false, storageDomainId = da155090-3cd5-4812-8cf0-2d99931a5d34, imageGroupId = 44c453a8-ede6-4579-bc52-cb503e65c06a, imageId = 2a925a77-41c5-423d-82bd-a3fe996aceab, imageId2 = 6a79a6be-855a-4ae0-9e75-3a91d285f44e, vmId = 5dfd843e-2268-4286-bfa0-175e5cdb8cb5, postZero = false), log id: 79044f66
>2014-10-06 15:28:11,506 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.MergeSnapshotsVDSCommand] (ajp-/127.0.0.1:8702-9) [39ca1ee5] FINISH, MergeSnapshotsVDSCommand, log id: 79044f66
>2014-10-06 15:28:11,519 INFO  [org.ovirt.engine.core.bll.CommandAsyncTask] (ajp-/127.0.0.1:8702-9) [39ca1ee5] CommandAsyncTask::Adding CommandMultiAsyncTasks object for command d23ad0fe-c012-4fea-8d2e-4655cd2e7579
>2014-10-06 15:28:11,520 INFO  [org.ovirt.engine.core.bll.CommandMultiAsyncTasks] (ajp-/127.0.0.1:8702-9) [39ca1ee5] CommandMultiAsyncTasks::AttachTask: Attaching task 1b5a4eed-74e8-46b9-9c84-626562060052 to command d23ad0fe-c012-4fea-8d2e-4655cd2e7579.
>2014-10-06 15:28:11,534 INFO  [org.ovirt.engine.core.bll.AsyncTaskManager] (ajp-/127.0.0.1:8702-9) [39ca1ee5] Adding task 1b5a4eed-74e8-46b9-9c84-626562060052 (Parent Command RemoveSnapshot, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters), polling hasn't started yet..
>2014-10-06 15:28:11,559 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.DeleteImageGroupVDSCommand] (ajp-/127.0.0.1:8702-9) [39ca1ee5] START, DeleteImageGroupVDSCommand( storagePoolId = 5849b030-626e-47cb-ad90-3ce782d831b3, ignoreFailoverLimit = false, storageDomainId = 48f2b3b3-646d-4af5-91c0-ab50c8ff8e6d, imageGroupId = 206e6d62-7973-44d9-a245-59e3f88bde4a, postZeros = false, forceDelete = false), log id: 7fc196fe
>2014-10-06 15:28:12,913 ERROR [org.ovirt.engine.core.vdsbroker.irsbroker.DeleteImageGroupVDSCommand] (ajp-/127.0.0.1:8702-9) [39ca1ee5] Command DeleteImageGroupVDSCommand( storagePoolId = 5849b030-626e-47cb-ad90-3ce782d831b3, ignoreFailoverLimit = false, storageDomainId = 48f2b3b3-646d-4af5-91c0-ab50c8ff8e6d, imageGroupId = 206e6d62-7973-44d9-a245-59e3f88bde4a, postZeros = false, forceDelete = false) execution failed. Exception: IrsOperationFailedNoFailoverException: IRSGenericException: IRSErrorException: Image does not exist in domain: 'image=206e6d62-7973-44d9-a245-59e3f88bde4a, domain=48f2b3b3-646d-4af5-91c0-ab50c8ff8e6d'
>2014-10-06 15:28:12,913 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.DeleteImageGroupVDSCommand] (ajp-/127.0.0.1:8702-9) [39ca1ee5] FINISH, DeleteImageGroupVDSCommand, log id: 7fc196fe
>2014-10-06 15:28:12,913 ERROR [org.ovirt.engine.core.bll.RemoveSnapshotCommand] (ajp-/127.0.0.1:8702-9) [39ca1ee5] Command org.ovirt.engine.core.bll.RemoveSnapshotCommand throw Vdc Bll exception. With error message VdcBLLException: org.ovirt.engine.core.vdsbroker.irsbroker.IrsOperationFailedNoFailoverException: IRSGenericException: IRSErrorException: Image does not exist in domain: 'image=206e6d62-7973-44d9-a245-59e3f88bde4a, domain=48f2b3b3-646d-4af5-91c0-ab50c8ff8e6d' (Failed with error ImageDoesNotExistInDomainError and code 268)
>2014-10-06 15:28:12,915 INFO  [org.ovirt.engine.core.bll.RemoveSnapshotCommand] (ajp-/127.0.0.1:8702-9) [39ca1ee5] Command [id=d23ad0fe-c012-4fea-8d2e-4655cd2e7579]: Compensating CHANGED_STATUS_ONLY of org.ovirt.engine.core.common.businessentities.Snapshot; snapshot: EntityStatusSnapshot [id=ad1c831c-3ebd-43fd-8c9e-d90fd62a8577, status=BROKEN].
>2014-10-06 15:28:12,926 ERROR [org.ovirt.engine.core.bll.RemoveSnapshotSingleDiskCommand] (ajp-/127.0.0.1:8702-9) [39ca1ee5] Transaction rolled-back for command: org.ovirt.engine.core.bll.RemoveSnapshotSingleDiskCommand.
>2014-10-06 15:28:12,926 ERROR [org.ovirt.engine.core.bll.RemoveSnapshotCommand] (ajp-/127.0.0.1:8702-9) [39ca1ee5] Transaction rolled-back for command: org.ovirt.engine.core.bll.RemoveSnapshotCommand.
>2014-10-06 15:28:12,926 INFO  [org.ovirt.engine.core.bll.RemoveSnapshotCommand] (org.ovirt.thread.pool-4-thread-49) [39ca1ee5] Rollback for command: org.ovirt.engine.core.bll.RemoveSnapshotCommand.
>2014-10-06 15:28:12,926 INFO  [org.ovirt.engine.core.bll.AsyncTaskManager] (org.ovirt.thread.pool-4-thread-49) [39ca1ee5] Attempting to cancel task 1b5a4eed-74e8-46b9-9c84-626562060052.
>2014-10-06 15:28:12,926 INFO  [org.ovirt.engine.core.bll.SPMAsyncTask] (org.ovirt.thread.pool-4-thread-49) [39ca1ee5] SPMAsyncTask::StopTask: Attempting to stop task 1b5a4eed-74e8-46b9-9c84-626562060052 (Parent Command RemoveSnapshot, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters).
>2014-10-06 15:28:12,926 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.SPMStopTaskVDSCommand] (org.ovirt.thread.pool-4-thread-49) [39ca1ee5] START, SPMStopTaskVDSCommand( storagePoolId = 5849b030-626e-47cb-ad90-3ce782d831b3, ignoreFailoverLimit = false, taskId = 1b5a4eed-74e8-46b9-9c84-626562060052), log id: 6e2068f2
>2014-10-06 15:28:12,934 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp-/127.0.0.1:8702-9) [39ca1ee5] Correlation ID: 16e90c2f, Job ID: 73774fd9-8971-4995-9a1a-cdb5f1df800e, Call Stack: null, Custom Event ID: -1, Message: Failed to remove Snapshot alfresco42_clone for VM alfresco42.agenda.si (User: admin).
>2014-10-06 15:28:12,935 INFO  [org.ovirt.engine.core.bll.AsyncTaskManager] (ajp-/127.0.0.1:8702-9) [39ca1ee5] Removed task 2ea4d47b-d87b-4e1c-a0a0-d0c1e2e434cd from DataBase
>2014-10-06 15:28:13,344 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMStopTaskVDSCommand] (org.ovirt.thread.pool-4-thread-49) [39ca1ee5] START, HSMStopTaskVDSCommand(HostName = node4, HostId = 5f320eec-8d68-4842-904d-85f2de3c0925, taskId=1b5a4eed-74e8-46b9-9c84-626562060052), log id: 3fa2a13f
>2014-10-06 15:28:13,433 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMStopTaskVDSCommand] (org.ovirt.thread.pool-4-thread-49) [39ca1ee5] FINISH, HSMStopTaskVDSCommand, log id: 3fa2a13f
>2014-10-06 15:28:13,433 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.SPMStopTaskVDSCommand] (org.ovirt.thread.pool-4-thread-49) [39ca1ee5] FINISH, SPMStopTaskVDSCommand, log id: 6e2068f2
>2014-10-06 15:28:20,492 INFO  [org.ovirt.engine.core.bll.AsyncTaskManager] (DefaultQuartzScheduler_Worker-17) Polling and updating Async Tasks: 1 tasks, 1 tasks to poll now
>2014-10-06 15:28:20,619 INFO  [org.ovirt.engine.core.bll.SPMAsyncTask] (DefaultQuartzScheduler_Worker-17) SPMAsyncTask::PollTask: Polling task 1b5a4eed-74e8-46b9-9c84-626562060052 (Parent Command RemoveSnapshot, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters) returned status running.
>2014-10-06 15:28:20,619 INFO  [org.ovirt.engine.core.bll.AsyncTaskManager] (DefaultQuartzScheduler_Worker-17) Finished polling Tasks, will poll again in 10 seconds.

Actions: View

Attachments on bug 1149770: 944300