I've been experimenting with sparse streams and found a bug. If you try to download a volume which doesn't support sparseness here's what happens: # virsh vol-download --sparse /dev/disk/by-path/ip-XX.XX.XX.XX:3260-iscsi-iqn.2017-03.com.blah:server-lun-0 /mnt/floppy/blah.raw # echo $? 0 # ls -lhs /mnt/floppy/bla.raw 0 -rw-r--r-- 1 root root 0 May 30 12:40 /mnt/floppy/bla.raw That's not good. iSCSI doesn't know anything about sparseness so an error is expected here. Fortunately, the fix is fairly simple: # virsh vol-download --sparse /dev/disk/by-path/ip-XX.XX.XX.XX:3260-iscsi-iqn.2017-03.com.blah:server-lun-0 /mnt/floppy/bla.raw error: cannot close volume /dev/disk/by-path/ip-XX.XX.XX.XX:3260-iscsi-iqn.2017-03.com.blah:server-lun-0 error: Unable to seek to data: Invalid argument Michal Privoznik (2): virfdstream: Check for thread error more frequently fdstream: Report error from the I/O thread daemon/stream.c | 18 ++++++++++++------ src/util/virfdstream.c | 22 ++++++++++++++++++++-- 2 files changed, 32 insertions(+), 8 deletions(-) -- 2.13.0 -- libvir-list mailing list libvir-list@redhat.com https://www.redhat.com/mailman/listinfo/libvir-list
On Tue, May 30, 2017 at 12:44:21PM +0200, Michal Privoznik wrote:
>I've been experimenting with sparse streams and found a bug. If you try to
>download a volume which doesn't support sparseness here's what happens:
>
># virsh vol-download --sparse /dev/disk/by-path/ip-XX.XX.XX.XX:3260-iscsi-iqn.2017-03.com.blah:server-lun-0 /mnt/floppy/blah.raw
>
># echo $?
>0
># ls -lhs /mnt/floppy/bla.raw
>0 -rw-r--r-- 1 root root 0 May 30 12:40 /mnt/floppy/bla.raw
>
>That's not good. iSCSI doesn't know anything about sparseness so an error is
>expected here. Fortunately, the fix is fairly simple:
>
># virsh vol-download --sparse /dev/disk/by-path/ip-XX.XX.XX.XX:3260-iscsi-iqn.2017-03.com.blah:server-lun-0 /mnt/floppy/bla.raw
>error: cannot close volume /dev/disk/by-path/ip-XX.XX.XX.XX:3260-iscsi-iqn.2017-03.com.blah:server-lun-0
>error: Unable to seek to data: Invalid argument
>
I'm also getting confusing errors when there is no space on the
destination:
error: cannot receive data from volume fedora.img
error: An error occurred, but the cause is unknown
But that's not related to the sparse streams (unless it was caused by
making the iohelper a thread).
... few moments later after /me tries just a thing or two ...
Well, this made me try out few more things and I've found out few
things. I'm not sure what's related to your patches and what's not, so
here's the rundown, and I'll let you decide:
- vol-download --sparse --offset $source_file_size --length 1
/path/to/source.file destination.file
- Every now and then (not always) it gets stuck waiting for the
daemon to receive data (see backtrace below), but the daemon is not
waiting for anything, it's just some weird race. We can try
debugging it with wireshark later. That file ends with a hole.
Thread 1 (Thread 0x7f1d2b434880 (LWP 28584)):
#0 0x00007f1d2796efbd in poll () at ../sysdeps/unix/syscall-template.S:84
#1 0x00007f1d2a806ee3 in poll (__timeout=5000, __nfds=2, __fds=0x7ffe9effd640) at /usr/include/bits/poll2.h:46
#2 virNetClientIOEventLoop (client=client@entry=0x563525bb06d0, thiscall=thiscall@entry=0x563525badc00) at rpc/virnetclient.c:1664
#3 0x00007f1d2a8074d3 in virNetClientIO (client=client@entry=0x563525bb06d0, thiscall=0x563525badc00) at rpc/virnetclient.c:1957
#4 0x00007f1d2a80780e in virNetClientSendInternal (client=client@entry=0x563525bb06d0, msg=msg@entry=0x563525bb03d0, expectReply=expectReply@entry=true, nonBlock=nonBlock@entry=false) at rpc/virnetclient.c:2132
#5 0x00007f1d2a808dfc in virNetClientSendWithReplyStream (client=client@entry=0x563525bb06d0, msg=msg@entry=0x563525bb03d0, st=st@entry=0x563525bade10) at rpc/virnetclient.c:2236
#6 0x00007f1d2a80ab2d in virNetClientStreamRecvPacket (st=st@entry=0x563525bade10, client=0x563525bb06d0, data=data@entry=0x7f1d20686010 "", nbytes=nbytes@entry=262120, nonblock=false, flags=32766, flags@entry=1) at rpc/virnetclientstream.c:499
#7 0x00007f1d2a7e0e3e in remoteStreamRecvFlags (st=0x563525badc60, data=0x7f1d20686010 "", nbytes=262120, flags=1) at remote/remote_driver.c:5664
#8 0x00007f1d2a7c8347 in virStreamRecvFlags (stream=stream@entry=0x563525badc60, data=0x7f1d20686010 "", nbytes=nbytes@entry=262120, flags=flags@entry=1) at libvirt-stream.c:361
#9 0x00007f1d2a7c9b7f in virStreamSparseRecvAll (stream=stream@entry=0x563525badc60, handler=0x563525760196 <virshStreamSink>, holeHandler=0x56352576020b <virshStreamSkip>, opaque=opaque@entry=0x7ffe9effd954) at libvirt-stream.c:964
#10 0x000056352576232e in cmdVolDownload (ctl=0x7ffe9effda40, cmd=<optimized out>) at virsh-volume.c:834
#11 0x00005635257662f1 in vshCommandRun (ctl=0x7ffe9effda40, cmd=0x563525bacf40) at vsh.c:1327
#12 0x000056352572aee2 in main (argc=9, argv=<optimized out>) at virsh.c:929
Trying to reproduce yet another one, the command gets stuck even with
different offsets.
- vol-download --sparse --offset $X --length 1
/path/to/source.file destination.file
- This does not respect the length if:
X > $source_file_size - $last_hole_size
The size ends up being $source_file_size - $X
I'm afraid to try more things, but I can provide more info for these if
you want.
Have a nice day,
Martin
--
libvir-list mailing list
libvir-list@redhat.com
https://www.redhat.com/mailman/listinfo/libvir-list
On 05/31/2017 01:03 PM, Martin Kletzander wrote: > On Tue, May 30, 2017 at 12:44:21PM +0200, Michal Privoznik wrote: >> I've been experimenting with sparse streams and found a bug. If you >> try to >> download a volume which doesn't support sparseness here's what happens: >> >> # virsh vol-download --sparse >> /dev/disk/by-path/ip-XX.XX.XX.XX:3260-iscsi-iqn.2017-03.com.blah:server-lun-0 >> /mnt/floppy/blah.raw >> >> # echo $? >> 0 >> # ls -lhs /mnt/floppy/bla.raw >> 0 -rw-r--r-- 1 root root 0 May 30 12:40 /mnt/floppy/bla.raw >> >> That's not good. iSCSI doesn't know anything about sparseness so an >> error is >> expected here. Fortunately, the fix is fairly simple: >> >> # virsh vol-download --sparse >> /dev/disk/by-path/ip-XX.XX.XX.XX:3260-iscsi-iqn.2017-03.com.blah:server-lun-0 >> /mnt/floppy/bla.raw >> error: cannot close volume >> /dev/disk/by-path/ip-XX.XX.XX.XX:3260-iscsi-iqn.2017-03.com.blah:server-lun-0 >> >> error: Unable to seek to data: Invalid argument >> > > I'm also getting confusing errors when there is no space on the > destination: > error: cannot receive data from volume fedora.img > error: An error occurred, but the cause is unknown Looks like one of the callbacks is not reporting errors. > > But that's not related to the sparse streams (unless it was caused by > making the iohelper a thread). > > ... few moments later after /me tries just a thing or two ... > > Well, this made me try out few more things and I've found out few > things. I'm not sure what's related to your patches and what's not, so > here's the rundown, and I'll let you decide: > > - vol-download --sparse --offset $source_file_size --length 1 > /path/to/source.file destination.file > > - Every now and then (not always) it gets stuck waiting for the > daemon to receive data (see backtrace below), but the daemon is not > waiting for anything, it's just some weird race. We can try > debugging it with wireshark later. That file ends with a hole. > > Thread 1 (Thread 0x7f1d2b434880 (LWP 28584)): > #0 0x00007f1d2796efbd in poll () at ../sysdeps/unix/syscall-template.S:84 > #1 0x00007f1d2a806ee3 in poll (__timeout=5000, __nfds=2, > __fds=0x7ffe9effd640) at /usr/include/bits/poll2.h:46 > #2 virNetClientIOEventLoop (client=client@entry=0x563525bb06d0, > thiscall=thiscall@entry=0x563525badc00) at rpc/virnetclient.c:1664 > #3 0x00007f1d2a8074d3 in virNetClientIO > (client=client@entry=0x563525bb06d0, thiscall=0x563525badc00) at > rpc/virnetclient.c:1957 > #4 0x00007f1d2a80780e in virNetClientSendInternal > (client=client@entry=0x563525bb06d0, msg=msg@entry=0x563525bb03d0, > expectReply=expectReply@entry=true, nonBlock=nonBlock@entry=false) at > rpc/virnetclient.c:2132 > #5 0x00007f1d2a808dfc in virNetClientSendWithReplyStream > (client=client@entry=0x563525bb06d0, msg=msg@entry=0x563525bb03d0, > st=st@entry=0x563525bade10) at rpc/virnetclient.c:2236 > #6 0x00007f1d2a80ab2d in virNetClientStreamRecvPacket > (st=st@entry=0x563525bade10, client=0x563525bb06d0, > data=data@entry=0x7f1d20686010 "", nbytes=nbytes@entry=262120, > nonblock=false, flags=32766, flags@entry=1) at rpc/virnetclientstream.c:499 > #7 0x00007f1d2a7e0e3e in remoteStreamRecvFlags (st=0x563525badc60, > data=0x7f1d20686010 "", nbytes=262120, flags=1) at > remote/remote_driver.c:5664 > #8 0x00007f1d2a7c8347 in virStreamRecvFlags > (stream=stream@entry=0x563525badc60, data=0x7f1d20686010 "", > nbytes=nbytes@entry=262120, flags=flags@entry=1) at libvirt-stream.c:361 > #9 0x00007f1d2a7c9b7f in virStreamSparseRecvAll > (stream=stream@entry=0x563525badc60, handler=0x563525760196 > <virshStreamSink>, holeHandler=0x56352576020b <virshStreamSkip>, > opaque=opaque@entry=0x7ffe9effd954) at libvirt-stream.c:964 > #10 0x000056352576232e in cmdVolDownload (ctl=0x7ffe9effda40, > cmd=<optimized out>) at virsh-volume.c:834 > #11 0x00005635257662f1 in vshCommandRun (ctl=0x7ffe9effda40, > cmd=0x563525bacf40) at vsh.c:1327 > #12 0x000056352572aee2 in main (argc=9, argv=<optimized out>) at > virsh.c:929 > > Trying to reproduce yet another one, the command gets stuck even with > different offsets. > > - vol-download --sparse --offset $X --length 1 > /path/to/source.file destination.file > > - This does not respect the length if: > X > $source_file_size - $last_hole_size > > The size ends up being $source_file_size - $X > Okay, I'll look into these. Thanks. > > > I'm afraid to try more things, but I can provide more info for these if > you want. Don't be! At least somebody is testing the feature. Thanks. Anyway, I'll send v2 on 1/2. Michal -- libvir-list mailing list libvir-list@redhat.com https://www.redhat.com/mailman/listinfo/libvir-list
On Wednesday, 31 May 2017 13:03:38 CEST Martin Kletzander wrote: > - vol-download --sparse --offset $source_file_size --length 1 > /path/to/source.file destination.file > > - Every now and then (not always) it gets stuck waiting for the > daemon to receive data (see backtrace below), but the daemon is not > waiting for anything, it's just some weird race. We can try > debugging it with wireshark later. That file ends with a hole. > > Thread 1 (Thread 0x7f1d2b434880 (LWP 28584)): > #0 0x00007f1d2796efbd in poll () at ../sysdeps/unix/syscall-template.S:84 > #1 0x00007f1d2a806ee3 in poll (__timeout=5000, __nfds=2, __fds=0x7ffe9effd640) at /usr/include/bits/poll2.h:46 > #2 virNetClientIOEventLoop (client=client@entry=0x563525bb06d0, thiscall=thiscall@entry=0x563525badc00) at rpc/virnetclient.c:1664 > #3 0x00007f1d2a8074d3 in virNetClientIO (client=client@entry=0x563525bb06d0, thiscall=0x563525badc00) at rpc/virnetclient.c:1957 > #4 0x00007f1d2a80780e in virNetClientSendInternal (client=client@entry=0x563525bb06d0, msg=msg@entry=0x563525bb03d0, expectReply=expectReply@entry=true, nonBlock=nonBlock@entry=false) at rpc/virnetclient.c:2132 > #5 0x00007f1d2a808dfc in virNetClientSendWithReplyStream (client=client@entry=0x563525bb06d0, msg=msg@entry=0x563525bb03d0, st=st@entry=0x563525bade10) at rpc/virnetclient.c:2236 > #6 0x00007f1d2a80ab2d in virNetClientStreamRecvPacket (st=st@entry=0x563525bade10, client=0x563525bb06d0, data=data@entry=0x7f1d20686010 "", nbytes=nbytes@entry=262120, nonblock=false, flags=32766, flags@entry=1) at rpc/virnetclientstream.c:499 > #7 0x00007f1d2a7e0e3e in remoteStreamRecvFlags (st=0x563525badc60, data=0x7f1d20686010 "", nbytes=262120, flags=1) at remote/remote_driver.c:5664 > #8 0x00007f1d2a7c8347 in virStreamRecvFlags (stream=stream@entry=0x563525badc60, data=0x7f1d20686010 "", nbytes=nbytes@entry=262120, flags=flags@entry=1) at libvirt-stream.c:361 > #9 0x00007f1d2a7c9b7f in virStreamSparseRecvAll (stream=stream@entry=0x563525badc60, handler=0x563525760196 <virshStreamSink>, holeHandler=0x56352576020b <virshStreamSkip>, opaque=opaque@entry=0x7ffe9effd954) at libvirt-stream.c:964 > #10 0x000056352576232e in cmdVolDownload (ctl=0x7ffe9effda40, cmd=<optimized out>) at virsh-volume.c:834 > #11 0x00005635257662f1 in vshCommandRun (ctl=0x7ffe9effda40, cmd=0x563525bacf40) at vsh.c:1327 > #12 0x000056352572aee2 in main (argc=9, argv=<optimized out>) at virsh.c:929 > > Trying to reproduce yet another one, the command gets stuck even with > different offsets. > > - vol-download --sparse --offset $X --length 1 > /path/to/source.file destination.file > > - This does not respect the length if: > X > $source_file_size - $last_hole_size > > The size ends up being $source_file_size - $X Humble suggestion here: what about turning the simple scenarios above as proper tests? -- Pino Toscano-- libvir-list mailing list libvir-list@redhat.com https://www.redhat.com/mailman/listinfo/libvir-list
On Wed, May 31, 2017 at 03:08:16PM +0200, Pino Toscano wrote: >On Wednesday, 31 May 2017 13:03:38 CEST Martin Kletzander wrote: >> - vol-download --sparse --offset $source_file_size --length 1 >> /path/to/source.file destination.file >> >> - Every now and then (not always) it gets stuck waiting for the >> daemon to receive data (see backtrace below), but the daemon is not >> waiting for anything, it's just some weird race. We can try >> debugging it with wireshark later. That file ends with a hole. >> >> Thread 1 (Thread 0x7f1d2b434880 (LWP 28584)): >> #0 0x00007f1d2796efbd in poll () at ../sysdeps/unix/syscall-template.S:84 >> #1 0x00007f1d2a806ee3 in poll (__timeout=5000, __nfds=2, __fds=0x7ffe9effd640) at /usr/include/bits/poll2.h:46 >> #2 virNetClientIOEventLoop (client=client@entry=0x563525bb06d0, thiscall=thiscall@entry=0x563525badc00) at rpc/virnetclient.c:1664 >> #3 0x00007f1d2a8074d3 in virNetClientIO (client=client@entry=0x563525bb06d0, thiscall=0x563525badc00) at rpc/virnetclient.c:1957 >> #4 0x00007f1d2a80780e in virNetClientSendInternal (client=client@entry=0x563525bb06d0, msg=msg@entry=0x563525bb03d0, expectReply=expectReply@entry=true, nonBlock=nonBlock@entry=false) at rpc/virnetclient.c:2132 >> #5 0x00007f1d2a808dfc in virNetClientSendWithReplyStream (client=client@entry=0x563525bb06d0, msg=msg@entry=0x563525bb03d0, st=st@entry=0x563525bade10) at rpc/virnetclient.c:2236 >> #6 0x00007f1d2a80ab2d in virNetClientStreamRecvPacket (st=st@entry=0x563525bade10, client=0x563525bb06d0, data=data@entry=0x7f1d20686010 "", nbytes=nbytes@entry=262120, nonblock=false, flags=32766, flags@entry=1) at rpc/virnetclientstream.c:499 >> #7 0x00007f1d2a7e0e3e in remoteStreamRecvFlags (st=0x563525badc60, data=0x7f1d20686010 "", nbytes=262120, flags=1) at remote/remote_driver.c:5664 >> #8 0x00007f1d2a7c8347 in virStreamRecvFlags (stream=stream@entry=0x563525badc60, data=0x7f1d20686010 "", nbytes=nbytes@entry=262120, flags=flags@entry=1) at libvirt-stream.c:361 >> #9 0x00007f1d2a7c9b7f in virStreamSparseRecvAll (stream=stream@entry=0x563525badc60, handler=0x563525760196 <virshStreamSink>, holeHandler=0x56352576020b <virshStreamSkip>, opaque=opaque@entry=0x7ffe9effd954) at libvirt-stream.c:964 >> #10 0x000056352576232e in cmdVolDownload (ctl=0x7ffe9effda40, cmd=<optimized out>) at virsh-volume.c:834 >> #11 0x00005635257662f1 in vshCommandRun (ctl=0x7ffe9effda40, cmd=0x563525bacf40) at vsh.c:1327 >> #12 0x000056352572aee2 in main (argc=9, argv=<optimized out>) at virsh.c:929 >> >> Trying to reproduce yet another one, the command gets stuck even with >> different offsets. >> >> - vol-download --sparse --offset $X --length 1 >> /path/to/source.file destination.file >> >> - This does not respect the length if: >> X > $source_file_size - $last_hole_size >> >> The size ends up being $source_file_size - $X > >Humble suggestion here: what about turning the simple scenarios above >as proper tests? > The problem here is that after designing the test and writing it, we also have to mock all accesses to the source and destination files and report how the result looks, etc. And I didn't get to virStreams even, that's only sparse files. We could instead do integration testing of this, which would be easier, however you can only do that on a filesystem that you know keeps holes, plus the hole sizes can be different based on the block size, the files can be way different based on adaptive allocations, etc. There are so many factors for this that it is not easy (I'm not saying it's impossible). If I had lot of free time, this could fit in somehow. Also after I upgrade the virfilewrapper, it will be easier to control the behaviour of the file-access functions way more delicately. But patches are welcome! ;) >-- >Pino Toscano >-- >libvir-list mailing list >libvir-list@redhat.com >https://www.redhat.com/mailman/listinfo/libvir-list -- libvir-list mailing list libvir-list@redhat.com https://www.redhat.com/mailman/listinfo/libvir-list
On 05/31/2017 01:03 PM, Martin Kletzander wrote: > On Tue, May 30, 2017 at 12:44:21PM +0200, Michal Privoznik wrote: >> I've been experimenting with sparse streams and found a bug. If you >> try to >> download a volume which doesn't support sparseness here's what happens: >> >> # virsh vol-download --sparse >> /dev/disk/by-path/ip-XX.XX.XX.XX:3260-iscsi-iqn.2017-03.com.blah:server-lun-0 >> /mnt/floppy/blah.raw >> >> # echo $? >> 0 >> # ls -lhs /mnt/floppy/bla.raw >> 0 -rw-r--r-- 1 root root 0 May 30 12:40 /mnt/floppy/bla.raw >> >> That's not good. iSCSI doesn't know anything about sparseness so an >> error is >> expected here. Fortunately, the fix is fairly simple: >> >> # virsh vol-download --sparse >> /dev/disk/by-path/ip-XX.XX.XX.XX:3260-iscsi-iqn.2017-03.com.blah:server-lun-0 >> /mnt/floppy/bla.raw >> error: cannot close volume >> /dev/disk/by-path/ip-XX.XX.XX.XX:3260-iscsi-iqn.2017-03.com.blah:server-lun-0 >> >> error: Unable to seek to data: Invalid argument >> > > I'm also getting confusing errors when there is no space on the > destination: > error: cannot receive data from volume fedora.img > error: An error occurred, but the cause is unknown I have a fix for this (will send shortly). > > But that's not related to the sparse streams (unless it was caused by > making the iohelper a thread). > > ... few moments later after /me tries just a thing or two ... > > Well, this made me try out few more things and I've found out few > things. I'm not sure what's related to your patches and what's not, so > here's the rundown, and I'll let you decide: > > - vol-download --sparse --offset $source_file_size --length 1 > /path/to/source.file destination.file > > - Every now and then (not always) it gets stuck waiting for the > daemon to receive data (see backtrace below), but the daemon is not > waiting for anything, it's just some weird race. We can try > debugging it with wireshark later. That file ends with a hole. But I do not have a fix for this one. Frankly, I have no idea what is going on. Looks to me like: a) the daemon reaches the end of the stream, but doesn't call virStreamFinish b) client reads all the incoming data from the stream, and after that finds incoming queue empty so it sends "gimme more data" packet to the daemon c) the I/O thread in the daemon has died already (it had read everything it was supposed to), so it closes the write end of the pipe d) the read end of the pipe is not added to the event loop since we are not expecting more data in the stream Frankly, I have no idea what is really going on or how to fix it. So if somebody else can take a look I'd appreciate it. Michal -- libvir-list mailing list libvir-list@redhat.com https://www.redhat.com/mailman/listinfo/libvir-list
© 2016 - 2024 Red Hat, Inc.