On Tue, Feb 8, 2022 at 3:27 PM Richard W.M. Jones <rjones(a)redhat.com&gt; wrote: > virt-v2v 1.44.2: > Complete log: http://oirase.annexia.org/tmp/virt-v2v-1.44.2-rhv-upload.log > > [ 63.1] Copying disk 1/1 > ... > transfer 7aecd359-3706-49f5-8a0c-c8799f7b100a is finalizing_success > transfer 7aecd359-3706-49f5-8a0c-c8799f7b100a is finished_success > transfer 7aecd359-3706-49f5-8a0c-c8799f7b100a finalized in 6.105 seconds > [ 89.2] Creating output metadata > [ 89.8] Finishing off > > virt-v2v 1.45.97: > Complete log: http://oirase.annexia.org/tmp/virt-v2v-1.45.97-rhv-upload.log > > [ 71.0] Copying disk 1/1 > [ 82.7] Creating output metadata > ... > transfer 6ea3d724-16f9-4bda-a33e-69a783480abc is finalizing_success > transfer 6ea3d724-16f9-4bda-a33e-69a783480abc is finished_success > transfer 6ea3d724-16f9-4bda-a33e-69a783480abc finalized in 61.552 seconds > [ 144.9] Finishing off This happens because virt-v2v try to finalize the transfer *before* closing the connections to imageio server. Current imageio release mark the a ticket as canceled, but will not remove it if the ticket has open connections from clients. If the clients are idle, the connection closes after 60 seconds, so when engine tries again to remove the ticket, the operation succeeds.

Upstream version improved this flow to wait only for ongoing requests. If there are no ongoing requests the ticket is removed immediately, ignoring the open connections. The connections will be closed when the client closes the connection on when reading from the socket times out. You can test upstream version from here: https://github.com/oVirt/ovirt-imageio/actions/runs/1811058094 But if you want to be compatible with released imageio version, you must close the connections to imageio *before* finalizing the transfers. We already discussed this issue last year, when we handled the bug when vddk blocks for many minutes during block status and imageio connection is droped, and you opened a bug for this. > It's not a completely like-for-like comparison because the rhv-upload > backend changed a lot between these versions. In particular if I was > going to pick some suspicious change it would be my refactoring here > which was supposed to be neutral but maybe isn't: > > https://github.com/libguestfs/virt-v2v/commit/143a22860216b94d3a817061930... > > Unfortunately this commit is almost impossible to revert because of > deep restructuring of surrounding code. The refactoring is not the issue, the issue is that we do not terminate the output nbdkit before finalizing. For some output we cannot do this since we want to query nbdkit for more info (e.g block status) but for rhv upload we don't have anything to do with nbkdit instance and we must close the connection before we finalize, so we should close the output right after we finish the copy. > Another idea: > > Old virt-v2v uses qemu-img convert which does not flush by default. > New virt-v2v uses nbdcopy with the --flush option, so it will call > imageio PATCH /path ... "op":"flush" at the end. However removing > nbdcopy --flush didn't help very much (only a couple of seconds off). Calling flush is right, we cannot remove it. Although there is bug in nbdkit, and if flushes during close() flow even if you did not send a flush. > Do you have any other ideas? > > What exactly does imageio do during the finalizing_success state? The flow is this: - engine send request to vdsm to delete the ticket - vdsm connects to imageio control socket and send DELETE /tickets/{ticket-id} - imageio mark the ticket as canceled, so no new request can succeed and no new connection to attach to the ticket - imageio mark all ongoing requests as canceled, so if the request is in read/write loop, it will abort on the the next read/write complete - upstream: imageio waits until all ongoing operations complete - release: imageio waits until all connections are closed - if the ticket could not be removed in 60 seconds, imageio returns 409 Conflict - imageio returns 200 OK - engine retries the delete if it failed - when engine succeeds and finish other finalization tasks, the image transfer will be reported as finished. I would expect the flow to time out on virt-v2v side, since we use 60 seconds timeout, and when removing a ticket times out, it takes 60 seconds, so you should not succeed to finalize in 61 seconds. There is a bug in ovirt engine that cause this flow to succeed. Nir