On Wed, Nov 21, 2018 at 4:26 PM Gianluca Cecchi <gianluca.cecchi@gmail.com> wrote:

[snip]
 
Anyway also in 1.1 format, if I try to upload It keeps sending the whole 66Gb and now it goes in paused after about 1 minute (at rate of 60MB/s) that lets me think that once arrived at the 4.2Gb size it has problems...

Any hint?

Thanks,
Gianluca  



In image-proxy.log of engine

(Thread-83 ) ERROR 2018-11-21 16:22:40,402 web:136:web:(log_error) ERROR [10.4.23.16] PUT /images/8e9b2b60-4d5a-4dd8-bd49-8f85ebc6349e [500] Failed response from host: 500 {"explanation": "The server has either erred or is incapable of performing the requested operation.", "code": 500, "detail": "[Errno 22] Invalid argument", "title": "Internal Server Error"} [request=0.006663]
Traceback (most recent call last):
  File "/usr/lib64/python2.7/site-packages/ovirt_imageio_common/web.py", line 99, in __call__
    resp = self.dispatch(request, clock)
  File "/usr/lib64/python2.7/site-packages/ovirt_imageio_common/web.py", line 128, in dispatch
    return method(*match.groups())
  File "/usr/lib/python2.7/site-packages/ovirt_imageio_proxy/http_helper.py", line 86, in wrapper
    return func(self, *args)
  File "/usr/lib/python2.7/site-packages/ovirt_imageio_proxy/http_helper.py", line 59, in wrapper
    ret = func(self, *args)
  File "/usr/lib/python2.7/site-packages/ovirt_imageio_proxy/images.py", line 153, in put
    read_timeout=self.config.imaged_read_timeout_sec)
  File "/usr/lib/python2.7/site-packages/ovirt_imageio_proxy/images.py", line 239, in make_imaged_request
    raise exc.status_map[imaged_resp.status_code](s)
HTTPInternalServerError: Failed response from host: 500 {"explanation": "The server has either erred or is incapable of performing the requested operation.", "code": 500, "detail": "[Errno 22] Invalid argument", "title": "Internal Server Error"}

Inside daemon.log of host involved I have several lines of this type during the transfer

2018-11-21 16:22:40,359 INFO    (Thread-93) [images] [10.4.192.79] WRITE size=0 offset=4928307200 flush=True ticket=8e9b2b60-4d5a-4dd8-bd49-8f85ebc6349e
2018-11-21 16:22:40,379 INFO    (Thread-94) [images] [10.4.192.79] WRITE size=0 offset=5033164800 flush=True ticket=8e9b2b60-4d5a-4dd8-bd49-8f85ebc6349e
2018-11-21 16:22:40,400 INFO    (Thread-95) [images] [10.4.192.79] WRITE size=0 offset=5138022400 flush=True ticket=8e9b2b60-4d5a-4dd8-bd49-8f85ebc6349e

and then:

2018-11-21 16:22:40,400 ERROR   (Thread-95) [web] ERROR [10.4.192.79] PUT /images/8e9b2b60-4d5a-4dd8-bd49-8f85ebc6349e [500] [Errno 22] Invalid argument [request=0.000435, operation=0.000109, sync=0.0
00007]
Traceback (most recent call last):
  File "/usr/lib64/python2.7/site-packages/ovirt_imageio_common/web.py", line 99, in __call__
    resp = self.dispatch(request, clock)
  File "/usr/lib64/python2.7/site-packages/ovirt_imageio_common/web.py", line 128, in dispatch
    return method(*match.groups())
  File "/usr/lib/python2.7/site-packages/ovirt_imageio_daemon/server.py", line 258, in put
    ticket.run(op)
  File "/usr/lib/python2.7/site-packages/ovirt_imageio_daemon/tickets.py", line 107, in run
    operation.run()
  File "/usr/lib64/python2.7/site-packages/ovirt_imageio_common/directio.py", line 81, in run
    self._run()
  File "/usr/lib64/python2.7/site-packages/ovirt_imageio_common/directio.py", line 176, in _run
    remaining = self._seek_before_first_block(dst)
  File "/usr/lib64/python2.7/site-packages/ovirt_imageio_common/directio.py", line 195, in _seek_before_first_block
    dst.seek(self._offset)
  File "/usr/lib64/python2.7/site-packages/ovirt_imageio_common/directio.py", line 445, in seek
    return self._fio.seek(pos, how)
IOError: [Errno 22] Invalid argument
2018-11-21 16:22:43,435 INFO    (Thread-96) [images] [10.4.192.79] OPTIONS ticket=8e9b2b60-4d5a-4dd8-bd49-8f85ebc6349e
2018-11-21 16:22:43,452 INFO    (Thread-97) [images] [10.4.192.79] WRITE size=0 offset=5138022400 flush=True ticket=8e9b2b60-4d5a-4dd8-bd49-8f85ebc6349e