5:14 p.m.
On Tue, Mar 12, 2019 at 3:53 PM Strahil Nikolov <hunter86_bg(a)yahoo.com>
wrote:
Dear Simone,
first I want to thank you for your help.
I'm attaching the engine log from that time ,as I the output provided will
not be enough to detect any issues.
Adding also Nir on this, the whole sequence is tracked here:
2019-03-11 18:15:07,202+02 INFO
[org.ovirt.engine.core.bll.storage.disk.image.TransferDiskImageCommand]
(default task-9) [434241fa-037c-44d2-8f83-0a69baa027e4] Lock Acquired to
object 'EngineLock:{exclusiveLocks='',
sharedLocks='[441abdc8-6cb1-49a4-903f-a1ec0ed88429=DISK]'}'
2019-03-11 18:15:07,550+02 INFO
[org.ovirt.engine.core.bll.storage.disk.image.TransferDiskImageCommand]
(default task-9) [434241fa-037c-44d2-8f83-0a69baa027e4] Running command:
TransferDiskImageCommand internal: false. Entities affected : ID:
441abdc8-6cb1-49a4-903f-a1ec0ed88429 Type: DiskAction group
EDIT_DISK_PROPERTIES with role type USER
2019-03-11 18:15:07,550+02 INFO
[org.ovirt.engine.core.bll.storage.disk.image.TransferDiskImageCommand]
(default task-9) [434241fa-037c-44d2-8f83-0a69baa027e4] Creating
ImageTransfer entity for command '485b99d4-75d9-45bb-acf5-2ac050fbf115'
2019-03-11 18:15:08,295+02 INFO
[org.ovirt.engine.core.bll.storage.disk.image.TransferDiskImageCommand]
(default task-9) [434241fa-037c-44d2-8f83-0a69baa027e4] Successfully added
Download disk 'OVF_STORE' (disk id:
'441abdc8-6cb1-49a4-903f-a1ec0ed88429',
image id: 'c3309fc0-8707-4de1-903d-8d4bbb024f81') for image transfer
command '485b99d4-75d9-45bb-acf5-2ac050fbf115'
2019-03-11 18:15:08,826+02 INFO
[org.ovirt.engine.core.vdsbroker.vdsbroker.PrepareImageVDSCommand] (default
task-9) [434241fa-037c-44d2-8f83-0a69baa027e4] START,
PrepareImageVDSCommand(HostName = ovirt2.localdomain,
PrepareImageVDSCommandParameters:{hostId='a06f6597-323b-4f60-83a9-699cf5f768eb'}),
log id: 6e769a9c
2019-03-11 18:15:09,048+02 INFO
[org.ovirt.engine.core.vdsbroker.vdsbroker.PrepareImageVDSCommand] (default
task-9) [434241fa-037c-44d2-8f83-0a69baa027e4] FINISH,
PrepareImageVDSCommand, return: PrepareImageReturn:{status='Status [code=0,
message=Done]'}, log id: 6e769a9c
2019-03-11 18:15:09,049+02 INFO
[org.ovirt.engine.core.vdsbroker.vdsbroker.AddImageTicketVDSCommand]
(default task-9) [434241fa-037c-44d2-8f83-0a69baa027e4] START,
AddImageTicketVDSCommand(HostName = ovirt2.localdomain,
AddImageTicketVDSCommandParameters:{hostId='a06f6597-323b-4f60-83a9-699cf5f768eb',
ticketId='455b196c-1b59-44cd-9168-549e6c6732ab', timeout='300',
operations='[read]', size='134217728',
url='file:///rhev/data-center/mnt/glusterSD/ovirt1.localdomain:_engine/808423f9-8a5c-40cd-bc9f-2568c85b8c74/images/441abdc8-6cb1-49a4-903f-a1ec0ed88429/c3309fc0-8707-4de1-903d-8d4bbb024f81',
filename='OVF_STORE.raw', sparse='false',
transferId='485b99d4-75d9-45bb-acf5-2ac050fbf115'}), log id: 71e97bc0
2019-03-11 18:15:09,057+02 INFO
[org.ovirt.engine.core.vdsbroker.vdsbroker.AddImageTicketVDSCommand]
(default task-9) [434241fa-037c-44d2-8f83-0a69baa027e4] FINISH,
AddImageTicketVDSCommand, return: StatusOnlyReturn [status=Status [code=0,
message=Done]], log id: 71e97bc0
2019-03-11 18:15:09,057+02 INFO
[org.ovirt.engine.core.bll.storage.disk.image.TransferDiskImageCommand]
(default task-9) [434241fa-037c-44d2-8f83-0a69baa027e4] Started transfer
session with ticket id 455b196c-1b59-44cd-9168-549e6c6732ab, timeout 300
seconds
2019-03-11 18:15:09,057+02 INFO
[org.ovirt.engine.core.bll.storage.disk.image.TransferDiskImageCommand]
(default task-9) [434241fa-037c-44d2-8f83-0a69baa027e4] Adding image ticket
to ovirt-imageio-proxy, id 455b196c-1b59-44cd-9168-549e6c6732ab
2019-03-11 18:15:09,367+02 INFO
[org.ovirt.engine.core.bll.storage.disk.image.ImageTransferUpdater]
(default task-9) [434241fa-037c-44d2-8f83-0a69baa027e4] Updating image
transfer 485b99d4-75d9-45bb-acf5-2ac050fbf115 (image
441abdc8-6cb1-49a4-903f-a1ec0ed88429) phase to Transferring
2019-03-11 18:15:09,506+02 INFO
[org.ovirt.engine.core.bll.storage.disk.image.TransferDiskImageCommand]
(default task-9) [434241fa-037c-44d2-8f83-0a69baa027e4] Returning from
proceedCommandExecution after starting transfer session for image transfer
command '485b99d4-75d9-45bb-acf5-2ac050fbf115'
2019-03-11 18:15:09,950+02 INFO
[org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector]
(default task-9) [434241fa-037c-44d2-8f83-0a69baa027e4] EVENT_ID:
TRANSFER_IMAGE_INITIATED(1,031), Image Download with disk OVF_STORE was
initiated by admin@internal-authz.
2019-03-11 18:15:10,243+02 INFO
[org.ovirt.engine.core.vdsbroker.vdsbroker.GetImageTicketVDSCommand]
(EE-ManagedThreadFactory-engineScheduled-Thread-70)
[434241fa-037c-44d2-8f83-0a69baa027e4] START,
GetImageTicketVDSCommand(HostName = ovirt2.localdomain,
GetImageTicketVDSCommandParameters:{hostId='a06f6597-323b-4f60-83a9-699cf5f768eb',
ticketId='455b196c-1b59-44cd-9168-549e6c6732ab', timeout='null'}), log
id:
13d2369a
2019-03-11 18:15:10,249+02 INFO
[org.ovirt.engine.core.vdsbroker.vdsbroker.GetImageTicketVDSCommand]
(EE-ManagedThreadFactory-engineScheduled-Thread-70)
[434241fa-037c-44d2-8f83-0a69baa027e4] FINISH, GetImageTicketVDSCommand,
return:
org.ovirt.engine.core.common.businessentities.storage.ImageTicketInformation@64cde7e5,
log id: 13d2369a
2019-03-11 18:15:14,306+02 INFO
[org.ovirt.engine.core.vdsbroker.vdsbroker.GetImageTicketVDSCommand]
(EE-ManagedThreadFactory-engineScheduled-Thread-99)
[434241fa-037c-44d2-8f83-0a69baa027e4] START,
GetImageTicketVDSCommand(HostName = ovirt2.localdomain,
GetImageTicketVDSCommandParameters:{hostId='a06f6597-323b-4f60-83a9-699cf5f768eb',
ticketId='455b196c-1b59-44cd-9168-549e6c6732ab', timeout='null'}), log
id:
312c2d23
2019-03-11 18:15:14,312+02 INFO
[org.ovirt.engine.core.vdsbroker.vdsbroker.GetImageTicketVDSCommand]
(EE-ManagedThreadFactory-engineScheduled-Thread-99)
[434241fa-037c-44d2-8f83-0a69baa027e4] FINISH, GetImageTicketVDSCommand,
return:
org.ovirt.engine.core.common.businessentities.storage.ImageTicketInformation@650be7e7,
log id: 312c2d23
2019-03-11 18:15:22,506+02 INFO
[org.ovirt.engine.core.vdsbroker.vdsbroker.GetImageTicketVDSCommand]
(EE-ManagedThreadFactory-engineScheduled-Thread-73)
[434241fa-037c-44d2-8f83-0a69baa027e4] START,
GetImageTicketVDSCommand(HostName = ovirt2.localdomain,
GetImageTicketVDSCommandParameters:{hostId='a06f6597-323b-4f60-83a9-699cf5f768eb',
ticketId='455b196c-1b59-44cd-9168-549e6c6732ab', timeout='null'}), log
id:
10aa5a31
2019-03-11 18:15:22,512+02 INFO
[org.ovirt.engine.core.vdsbroker.vdsbroker.GetImageTicketVDSCommand]
(EE-ManagedThreadFactory-engineScheduled-Thread-73)
[434241fa-037c-44d2-8f83-0a69baa027e4] FINISH, GetImageTicketVDSCommand,
return:
org.ovirt.engine.core.common.businessentities.storage.ImageTicketInformation@650be7ef,
log id: 10aa5a31
2019-03-11 18:15:32,600+02 INFO
[org.ovirt.engine.core.vdsbroker.vdsbroker.GetImageTicketVDSCommand]
(EE-ManagedThreadFactory-engineScheduled-Thread-88)
[434241fa-037c-44d2-8f83-0a69baa027e4] START,
GetImageTicketVDSCommand(HostName = ovirt2.localdomain,
GetImageTicketVDSCommandParameters:{hostId='a06f6597-323b-4f60-83a9-699cf5f768eb',
ticketId='455b196c-1b59-44cd-9168-549e6c6732ab', timeout='null'}), log
id:
2f26f139
2019-03-11 18:15:32,606+02 INFO
[org.ovirt.engine.core.vdsbroker.vdsbroker.GetImageTicketVDSCommand]
(EE-ManagedThreadFactory-engineScheduled-Thread-88)
[434241fa-037c-44d2-8f83-0a69baa027e4] FINISH, GetImageTicketVDSCommand,
return:
org.ovirt.engine.core.common.businessentities.storage.ImageTicketInformation@650be7f9,
log id: 2f26f139
2019-03-11 18:15:42,618+02 INFO
[org.ovirt.engine.core.vdsbroker.vdsbroker.GetImageTicketVDSCommand]
(EE-ManagedThreadFactory-engineScheduled-Thread-27)
[434241fa-037c-44d2-8f83-0a69baa027e4] START,
GetImageTicketVDSCommand(HostName = ovirt2.localdomain,
GetImageTicketVDSCommandParameters:{hostId='a06f6597-323b-4f60-83a9-699cf5f768eb',
ticketId='455b196c-1b59-44cd-9168-549e6c6732ab', timeout='null'}), log
id:
7134e0db
2019-03-11 18:15:42,625+02 INFO
[org.ovirt.engine.core.vdsbroker.vdsbroker.GetImageTicketVDSCommand]
(EE-ManagedThreadFactory-engineScheduled-Thread-27)
[434241fa-037c-44d2-8f83-0a69baa027e4] FINISH, GetImageTicketVDSCommand,
return:
org.ovirt.engine.core.common.businessentities.storage.ImageTicketInformation@650be803,
log id: 7134e0db
2019-03-11 18:15:52,636+02 INFO
[org.ovirt.engine.core.vdsbroker.vdsbroker.GetImageTicketVDSCommand]
(EE-ManagedThreadFactory-engineScheduled-Thread-64)
[434241fa-037c-44d2-8f83-0a69baa027e4] START,
GetImageTicketVDSCommand(HostName = ovirt2.localdomain,
GetImageTicketVDSCommandParameters:{hostId='a06f6597-323b-4f60-83a9-699cf5f768eb',
ticketId='455b196c-1b59-44cd-9168-549e6c6732ab', timeout='null'}), log
id:
71140561
2019-03-11 18:15:52,642+02 INFO
[org.ovirt.engine.core.vdsbroker.vdsbroker.GetImageTicketVDSCommand]
(EE-ManagedThreadFactory-engineScheduled-Thread-64)
[434241fa-037c-44d2-8f83-0a69baa027e4] FINISH, GetImageTicketVDSCommand,
return:
org.ovirt.engine.core.common.businessentities.storage.ImageTicketInformation@650be80d,
log id: 71140561
2019-03-11 18:16:02,665+02 INFO
[org.ovirt.engine.core.vdsbroker.vdsbroker.GetImageTicketVDSCommand]
(EE-ManagedThreadFactory-engineScheduled-Thread-31)
[434241fa-037c-44d2-8f83-0a69baa027e4] START,
GetImageTicketVDSCommand(HostName = ovirt2.localdomain,
GetImageTicketVDSCommandParameters:{hostId='a06f6597-323b-4f60-83a9-699cf5f768eb',
ticketId='455b196c-1b59-44cd-9168-549e6c6732ab', timeout='null'}), log
id:
392976c
2019-03-11 18:16:02,671+02 INFO
[org.ovirt.engine.core.vdsbroker.vdsbroker.GetImageTicketVDSCommand]
(EE-ManagedThreadFactory-engineScheduled-Thread-31)
[434241fa-037c-44d2-8f83-0a69baa027e4] FINISH, GetImageTicketVDSCommand,
return:
org.ovirt.engine.core.common.businessentities.storage.ImageTicketInformation@650be817,
log id: 392976c
2019-03-11 18:16:12,683+02 INFO
[org.ovirt.engine.core.bll.storage.disk.image.TransferDiskImageCommand]
(EE-ManagedThreadFactory-engineScheduled-Thread-52)
[434241fa-037c-44d2-8f83-0a69baa027e4] Renewing transfer ticket for
Download disk 'OVF_STORE' (disk id:
'441abdc8-6cb1-49a4-903f-a1ec0ed88429',
image id: 'c3309fc0-8707-4de1-903d-8d4bbb024f81')
2019-03-11 18:16:12,683+02 INFO
[org.ovirt.engine.core.vdsbroker.vdsbroker.ExtendImageTicketVDSCommand]
(EE-ManagedThreadFactory-engineScheduled-Thread-52)
[434241fa-037c-44d2-8f83-0a69baa027e4] START,
ExtendImageTicketVDSCommand(HostName = ovirt2.localdomain,
ExtendImageTicketVDSCommandParameters:{hostId='a06f6597-323b-4f60-83a9-699cf5f768eb',
ticketId='455b196c-1b59-44cd-9168-549e6c6732ab', timeout='300'}), log id:
76c5ae5b
2019-03-11 18:16:12,690+02 INFO
[org.ovirt.engine.core.vdsbroker.vdsbroker.ExtendImageTicketVDSCommand]
(EE-ManagedThreadFactory-engineScheduled-Thread-52)
[434241fa-037c-44d2-8f83-0a69baa027e4] FINISH, ExtendImageTicketVDSCommand,
return: StatusOnlyReturn [status=Status [code=0, message=Done]], log id:
76c5ae5b
2019-03-11 18:16:12,690+02 INFO
[org.ovirt.engine.core.bll.storage.disk.image.TransferDiskImageCommand]
(EE-ManagedThreadFactory-engineScheduled-Thread-52)
[434241fa-037c-44d2-8f83-0a69baa027e4] Transfer session with ticket id
455b196c-1b59-44cd-9168-549e6c6732ab extended, timeout 300 seconds
2019-03-11 18:16:12,780+02 INFO
[org.ovirt.engine.core.vdsbroker.vdsbroker.GetImageTicketVDSCommand]
(EE-ManagedThreadFactory-engineScheduled-Thread-52)
[434241fa-037c-44d2-8f83-0a69baa027e4] START,
GetImageTicketVDSCommand(HostName = ovirt2.localdomain,
GetImageTicketVDSCommandParameters:{hostId='a06f6597-323b-4f60-83a9-699cf5f768eb',
ticketId='455b196c-1b59-44cd-9168-549e6c6732ab', timeout='null'}), log
id:
69f5186e
2019-03-11 18:16:12,785+02 INFO
[org.ovirt.engine.core.vdsbroker.vdsbroker.GetImageTicketVDSCommand]
(EE-ManagedThreadFactory-engineScheduled-Thread-52)
[434241fa-037c-44d2-8f83-0a69baa027e4] FINISH, GetImageTicketVDSCommand,
return:
org.ovirt.engine.core.common.businessentities.storage.ImageTicketInformation@650be821,
log id: 69f5186e
2019-03-11 18:16:12,886+02 ERROR
[org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector]
(EE-ManagedThreadFactory-engineScheduled-Thread-52)
[434241fa-037c-44d2-8f83-0a69baa027e4] EVENT_ID:
DOWNLOAD_IMAGE_CANCELED_TIMEOUT(1,072), Download was canceled by system.
Reason: timeout due to transfer inactivity.
2019-03-11 18:16:12,887+02 INFO
[org.ovirt.engine.core.bll.storage.disk.image.ImageTransferUpdater]
(EE-ManagedThreadFactory-engineScheduled-Thread-52)
[434241fa-037c-44d2-8f83-0a69baa027e4] Updating image transfer
485b99d4-75d9-45bb-acf5-2ac050fbf115 (image
441abdc8-6cb1-49a4-903f-a1ec0ed88429) phase to Cancelled
2019-03-11 18:16:22,998+02 INFO
[org.ovirt.engine.core.bll.storage.disk.image.TransferDiskImageCommand]
(EE-ManagedThreadFactory-engineScheduled-Thread-34)
[434241fa-037c-44d2-8f83-0a69baa027e4] Transfer cancelled for Download disk
'OVF_STORE' (disk id: '441abdc8-6cb1-49a4-903f-a1ec0ed88429', image id:
'c3309fc0-8707-4de1-903d-8d4bbb024f81')
2019-03-11 18:16:23,001+02 INFO
[org.ovirt.engine.core.bll.storage.disk.image.ImageTransferUpdater]
(EE-ManagedThreadFactory-engineScheduled-Thread-34)
[434241fa-037c-44d2-8f83-0a69baa027e4] Updating image transfer
485b99d4-75d9-45bb-acf5-2ac050fbf115 (image
441abdc8-6cb1-49a4-903f-a1ec0ed88429) phase to Finalizing Failure
2019-03-11 18:16:33,251+02 ERROR
[org.ovirt.engine.core.bll.storage.disk.image.TransferDiskImageCommand]
(EE-ManagedThreadFactory-engineScheduled-Thread-7)
[434241fa-037c-44d2-8f83-0a69baa027e4] Finalizing failed transfer. Download
disk 'OVF_STORE' (disk id: '441abdc8-6cb1-49a4-903f-a1ec0ed88429', image
id: 'c3309fc0-8707-4de1-903d-8d4bbb024f81')
2019-03-11 18:16:33,252+02 INFO
[org.ovirt.engine.core.vdsbroker.vdsbroker.RemoveImageTicketVDSCommand]
(EE-ManagedThreadFactory-engineScheduled-Thread-7)
[434241fa-037c-44d2-8f83-0a69baa027e4] START,
RemoveImageTicketVDSCommand(HostName = ovirt2.localdomain,
RemoveImageTicketVDSCommandParameters:{hostId='a06f6597-323b-4f60-83a9-699cf5f768eb',
ticketId='455b196c-1b59-44cd-9168-549e6c6732ab', timeout='null'}), log
id:
45197335
2019-03-11 18:16:33,257+02 INFO
[org.ovirt.engine.core.vdsbroker.vdsbroker.RemoveImageTicketVDSCommand]
(EE-ManagedThreadFactory-engineScheduled-Thread-7)
[434241fa-037c-44d2-8f83-0a69baa027e4] FINISH, RemoveImageTicketVDSCommand,
return: StatusOnlyReturn [status=Status [code=0, message=Done]], log id:
45197335
2019-03-11 18:16:33,257+02 INFO
[org.ovirt.engine.core.bll.storage.disk.image.TransferDiskImageCommand]
(EE-ManagedThreadFactory-engineScheduled-Thread-7)
[434241fa-037c-44d2-8f83-0a69baa027e4] Successfully stopped image transfer
session for ticket '455b196c-1b59-44cd-9168-549e6c6732ab'
2019-03-11 18:16:33,257+02 INFO
[org.ovirt.engine.core.bll.storage.disk.image.TransferDiskImageCommand]
(EE-ManagedThreadFactory-engineScheduled-Thread-7)
[434241fa-037c-44d2-8f83-0a69baa027e4] Removing image ticket from
ovirt-imageio-proxy, id 455b196c-1b59-44cd-9168-549e6c6732ab
2019-03-11 18:16:33,539+02 INFO
[org.ovirt.engine.core.vdsbroker.vdsbroker.TeardownImageVDSCommand]
(EE-ManagedThreadFactory-engineScheduled-Thread-7)
[434241fa-037c-44d2-8f83-0a69baa027e4] START,
TeardownImageVDSCommand(HostName = ovirt2.localdomain,
ImageActionsVDSCommandParameters:{hostId='a06f6597-323b-4f60-83a9-699cf5f768eb'}),
log id: 4d308b60
2019-03-11 18:16:33,543+02 INFO
[org.ovirt.engine.core.vdsbroker.vdsbroker.TeardownImageVDSCommand]
(EE-ManagedThreadFactory-engineScheduled-Thread-7)
[434241fa-037c-44d2-8f83-0a69baa027e4] FINISH, TeardownImageVDSCommand,
return: StatusReturn:{status='Status [code=0, message=Done]'}, log id:
4d308b60
2019-03-11 18:16:33,544+02 INFO
[org.ovirt.engine.core.bll.storage.disk.image.ImageTransferUpdater]
(EE-ManagedThreadFactory-engineScheduled-Thread-7)
[434241fa-037c-44d2-8f83-0a69baa027e4] Updating image transfer
485b99d4-75d9-45bb-acf5-2ac050fbf115 (image
441abdc8-6cb1-49a4-903f-a1ec0ed88429) phase to Finished Failure
2019-03-11 18:16:43,704+02 ERROR
[org.ovirt.engine.core.bll.storage.disk.image.TransferDiskImageCommand]
(EE-ManagedThreadFactory-engineScheduled-Thread-74)
[434241fa-037c-44d2-8f83-0a69baa027e4] Transfer failed. Download disk
'OVF_STORE' (disk id: '441abdc8-6cb1-49a4-903f-a1ec0ed88429', image id:
'c3309fc0-8707-4de1-903d-8d4bbb024f81')
2019-03-11 18:16:44,813+02 INFO
[org.ovirt.engine.core.bll.storage.disk.image.ImageTransferUpdater]
(EE-ManagedThreadFactory-engineScheduled-Thread-72)
[434241fa-037c-44d2-8f83-0a69baa027e4] Updating image transfer
485b99d4-75d9-45bb-acf5-2ac050fbf115 (image
441abdc8-6cb1-49a4-903f-a1ec0ed88429) phase to Finished Failure
2019-03-11 18:16:44,928+02 ERROR
[org.ovirt.engine.core.bll.storage.disk.image.TransferDiskImageCommand]
(EE-ManagedThreadFactory-engineScheduled-Thread-72)
[434241fa-037c-44d2-8f83-0a69baa027e4] Failed to transfer disk
'00000000-0000-0000-0000-000000000000' (command id
'485b99d4-75d9-45bb-acf5-2ac050fbf115')
2019-03-11 18:16:44,929+02 INFO
[org.ovirt.engine.core.bll.storage.disk.image.TransferDiskImageCommand]
(EE-ManagedThreadFactory-engineScheduled-Thread-72)
[434241fa-037c-44d2-8f83-0a69baa027e4] Ending command
'org.ovirt.engine.core.bll.storage.disk.image.TransferDiskImageCommand'
successfully.
2019-03-11 18:16:44,929+02 WARN
[org.ovirt.engine.core.bll.lock.InMemoryLockManager]
(EE-ManagedThreadFactory-engineScheduled-Thread-72)
[434241fa-037c-44d2-8f83-0a69baa027e4] Trying to release a shared lock for
key: '441abdc8-6cb1-49a4-903f-a1ec0ed88429DISK' , but lock does not exist
2019-03-11 18:16:44,929+02 INFO
[org.ovirt.engine.core.bll.storage.disk.image.TransferDiskImageCommand]
(EE-ManagedThreadFactory-engineScheduled-Thread-72)
[434241fa-037c-44d2-8f83-0a69baa027e4] Lock freed to object
'EngineLock:{exclusiveLocks='',
sharedLocks='[441abdc8-6cb1-49a4-903f-a1ec0ed88429=DISK]'}'
2019-03-11 18:16:45,312+02 INFO
[org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector]
(EE-ManagedThreadFactory-engineScheduled-Thread-72)
[434241fa-037c-44d2-8f83-0a69baa027e4] EVENT_ID:
TRANSFER_IMAGE_CANCELLED(1,033), Image Download with disk OVF_STORE was
cancelled.
I'd suggest to check ovirt-imageio and vdsm logs on ovirt2.localdomain
about the same time.
Best Regards,
Strahil Nikolov
В вторник, 12 март 2019 г., 14:54:33 ч. Гринуич+2, Strahil Nikolov <
hunter86_bg(a)yahoo.com> написа:
>Can you please check engine.log and vdsm.log to try understanding why the
upload of the >OVF_STORE content is failing on your environment?
>I fear it could cause other issues in the future.
The failure for 94ade632-6ecc-4901-8cec-8e39f3d69cb0 is because I didn't
click the save button (google chrome os has issues with ovirt and this will
be avoided):
2019-03-10 05:11:33,066+02 ERROR
[org.ovirt.engine.core.bll.storage.disk.image.TransferDiskImageCommand]
(EE-ManagedThreadFactory-engineScheduled-Thread-77)
[b25001bb-dc7f-4ff0-9223-e63b6f3
8c5e2] Transfer failed. Download disk 'OVF_STORE' (disk id:
'94ade632-6ecc-4901-8cec-8e39f3d69cb0', image id:
'9460fc4b-54f3-48e3-b7b6-da962321ecf4')
2019-03-10 05:11:34,215+02 INFO
[org.ovirt.engine.core.bll.storage.disk.image.ImageTransferUpdater]
(EE-ManagedThreadFactory-engineScheduled-Thread-69)
[b25001bb-dc7f-4ff0-9223-e63b6f38c5e2] Updating image transfer
2773daf9-5920-404d-8a5b-6f04e431a9aa (image
94ade632-6ecc-4901-8cec-8e39f3d69cb0) phase to Finished Failure
2019-03-10 05:11:34,329+02 ERROR
[org.ovirt.engine.core.bll.storage.disk.image.TransferDiskImageCommand]
(EE-ManagedThreadFactory-engineScheduled-Thread-69)
[b25001bb-dc7f-4ff0-9223-e63b6f38c5e2] Failed to transfer disk
'00000000-0000-0000-0000-000000000000' (command id
'2773daf9-5920-404d-8a5b-6f04e431a9aa')
2019-03-10 05:11:34,331+02 INFO
[org.ovirt.engine.core.bll.storage.disk.image.TransferDiskImageCommand]
(EE-ManagedThreadFactory-engineScheduled-Thread-69)
[b25001bb-dc7f-4ff0-9223-e63b6f38c5e2] Ending command
'org.ovirt.engine.core.bll.storage.disk.image.TransferDiskImageCommand'
successfully.
2019-03-10 05:11:34,331+02 INFO
[org.ovirt.engine.core.bll.storage.disk.image.TransferDiskImageCommand]
(EE-ManagedThreadFactory-engineScheduled-Thread-69)
[b25001bb-dc7f-4ff0-9223-e63b6f38c5e2] Lock freed to object
'EngineLock:{exclusiveLocks='',
sharedLocks='[94ade632-6ecc-4901-8cec-8e39f3d69cb0=DISK]'}'
2019-03-10 05:11:34,705+02 INFO
[org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector]
(EE-ManagedThreadFactory-engineScheduled-Thread-69)
[b25001bb-dc7f-4ff0-9223-e63b6f38c5e2] EVENT_ID:
TRANSFER_IMAGE_CANCELLED(1,033), Image Download with disk OVF_STORE was
cancelled.
And the failure for 441abdc8-6cb1-49a4-903f-a1ec0ed88429 happened on
Fedora28 and the logs report:
2019-03-11 18:16:33,251+02 ERROR
[org.ovirt.engine.core.bll.storage.disk.image.TransferDiskImageCommand]
(EE-ManagedThreadFactory-engineScheduled-Thread-7)
[434241fa-037c-44d2-8f83-0a69baa027e4] Finalizing failed transfer. Download
disk 'OVF_STORE' (disk id: '441abdc8-6cb1-49a4-903f-a1ec0ed88429', image
id: 'c3309fc0-8707-4de1-903d-8d4bbb024f81')
2019-03-11 18:16:43,704+02 ERROR
[org.ovirt.engine.core.bll.storage.disk.image.TransferDiskImageCommand]
(EE-ManagedThreadFactory-engineScheduled-Thread-74)
[434241fa-037c-44d2-8f83-0a69baa027e4] Transfer failed. Download disk
'OVF_STORE' (disk id: '441abdc8-6cb1-49a4-903f-a1ec0ed88429', image id:
'c3309fc0-8707-4de1-903d-8d4bbb024f81')
2019-03-11 18:16:44,929+02 WARN
[org.ovirt.engine.core.bll.lock.InMemoryLockManager]
(EE-ManagedThreadFactory-engineScheduled-Thread-72)
[434241fa-037c-44d2-8f83-0a69baa027e4] Trying to release a shared lock for
key: '441abdc8-6cb1-49a4-903f-a1ec0ed88429DISK' , but lock does not exist
For the Cancelled event - I think it shouldn't go into this "Failed" state
as the user has cancelled the action.
For the second - i have no explanation.
Now comes the question - what should be done in order to fix that.
Best Regards,
Strahil Nikolov