Ovirt: Upload Disk Snapshots problems with iSCSI storage and Java SDK
Description of the problem: I am not able to restore (upload) a VM when its disk (Either Thin provisioning or Thick provisioned) is located in an iSCSI storage and this VM has at least one snapshot larger than 1Gb. I am using REST API through Java SDK. I am using the same upload procedure described here: https://ovirt.org/develop/release-management/features/storage/backup-restore..., of course, making little adjustments to disk format (COW, Sparse.. Depending on the type of the VM to be restored). When I try restore any VM with this conditions, all snapshot disks with a size smaller than 1Gb are uploaded correctly. But when I am uploading a snapshot disk with a size bigger than 1Gb, the following error ocurrs: The error triggered from Java SDK is: "The server response was 403 in the range request {bytes 1073741824-1207959551/4831838208}" In the host's imageio log: "2019-05-07 14:26:30,253 WARNING (Thread-71960) [web] ERROR [172.19.33.146] PUT /images/8d353735-0a29-463c-b772-ec37f451e2e9 [403] Requested range out of allowed range [request=0.000488]" For example (real scenario), I have these snapshot disks from a vm: 2.1G DiskSnap_47c4e157-edd8-4bfc-b838-4d57ac8396bd_33b3e74a-cc6b-474a-97cc-960891716110_0.img -> First snap chain -> OK upload 1.1G DiskSnap_47c4e157-edd8-4bfc-b838-4d57ac8396bd_9349e4dd-85b3-4145-8674-bb3f39546020_1.img -> Ok upload 1.1G DiskSnap_47c4e157-edd8-4bfc-b838-4d57ac8396bd_4d10cb58-9f52-4d64-b6e9-ead4bed4c6a6_2.img -> Ok upload 4.6G DiskSnap_47c4e157-edd8-4bfc-b838-4d57ac8396bd_429cbe6d-1148-4a84-861a-56ad6902859e_3.img -> Last snap chain -> Fail upload with previous error [Qemu-img info in attached file: qemu-imgs-iSCSI.txt] In the restore process, when I create the disk(In this case), I put the following values: Disk: { Name:T_iSCSI_Thin_Disk1_restore, Id:null, Interface:VIRTIO_SCSI, Format:COW, WipeAfterDelete: false, Shareable: false, Sparse: true, Boot:true, Activo:true, Sizes:{ Initial:4294967296, Actual:null, Total:null, Provisioned: 4294967296 } } And the disk created is: Disk: { Name:T_iSCSI_Thin_Disk1_restore, Id:aac28ff3-abdb-4bf9-beed-c08e7e19b0ba, Image:c8654d05-a18c-47bd-ab0b-f4d746e23efb, Format:COW, WipeAfterDelete: false, Shareable: false, Sparse: true, Sizes:{ Initial:null, Actual:0, Total:0, Provisioned: 4294967296 } } Therefore, the first upload can finish correctly. However, when I create snapshots with this disk, the snapshot disks have these parameters: DiskSnapshot: { Id: 29dd6a18-c17c-4938-be67-d7af6de713ec, Disk:aac28ff3-abdb-4bf9-beed-c08e7e19b0ba, Snapshot:ceb2f29e-1ae8-441c-a5a7-836308cfeb8d, Sizes:{ Actual:1073741824 Provisioned:4294967296 Total: 0 Initial: null } } DiskSnapshot: { Id: 69620911-d490-4872-b353-ba29a762ea3e, Disk:aac28ff3-abdb-4bf9-beed-c08e7e19b0ba, Snapshot:c35423d5-ddd6-46f2-a5d7-0080941c3f30, Sizes:{ Actual:1073741824 Provisioned:4294967296 Total: 0 Initial: null } } DiskSnapshot: { Id: c8654d05-a18c-47bd-ab0b-f4d746e23efb, Disk:aac28ff3-abdb-4bf9-beed-c08e7e19b0ba, Snapshot:fc135efd-a67b-4eb3-bfdb-9090aa3b267e, Sizes:{ Actual:4831838208 Provisioned:4294967296 Total: 0 Initial: null } } DiskSnapshot: { Id: e3389916-4e80-4593-a116-3484f295ff7f, Disk:aac28ff3-abdb-4bf9-beed-c08e7e19b0ba, Snapshot:74aff5e7-e1c1-4f51-ae31-ea44caa180ef, Sizes:{ Actual:1073741824 Provisioned:4294967296 Total: 0 Initial: null } } As we can observe with these values, the actual size of snapshot disks indicates 1Gb, and when I try upload a snapshot disk (Bigger than 1.1Gb), the system doesn't allow to complete the upload, launching the mentionated previous error. However, using NFS storage, the previous error doesn't ocurr. The system allows me upload disks bigger than 1 Gb, and when the upload finishes, the system refreshs the actualSize to actual value. For example (real scenario), I have these snapshot disks from a vm: 3.1G DiskSnap_985a02a0-0752-420a-a2fb-a3e89d1ac3bc_3dffb5f4-3f9c-410c-8b97-133922721f9a_0.img -> First snap chain -> OK upload 22M DiskSnap_985a02a0-0752-420a-a2fb-a3e89d1ac3bc_d3307b3c-f930-4faa-90cb-6aedd38bea93_1.img -> Ok upload 31M DiskSnap_985a02a0-0752-420a-a2fb-a3e89d1ac3bc_aa8648dd-d6c0-4d9d-8373-6764e806359f_2.img -> Ok upload 1.3G DiskSnap_985a02a0-0752-420a-a2fb-a3e89d1ac3bc_2cf31d95-3b86-4d97-b39b-672d0066f503_3.img -> Last snap chain -> OK upload [Qemu-img info in attached file: qemu-imgs-NFS.txt] The restore with NFS storage is the same process but I change the format disk and sparse. With following values: Disk: { Name:985a02a0-0752-420a-a2fb-a3e89d1ac3bc_restore, Id:null, Interface:VIRTIO_SCSI, Format:RAW, WipeAfterDelete: false, Shareable: false, Sparse: true, Boot:true, Activo:true, Sizes:{ Initial:3221225472, Actual:null, Total:null, Provisioned: 3221225472 } } And the disk created was: Disk: { Name:985a02a0-0752-420a-a2fb-a3e89d1ac3bc_restore, Id:f2a89f5a-d8ac-4ea4-b17c-591cd86855e9, Image:9c80aead-88de-48f9-be7e-0fde917e7f55, Format:RAW, WipeAfterDelete: false, Shareable: false, Sparse: true, Sizes:{ Initial:null, Actual:0, Total:0, Provisioned: 3221225472 } } When I create snapshots with this disk, the snapshot disks have these parameters: DiskSnapshot: { Id: 87c4cb08-502a-4937-bbba-6d455395e990, Disk:f2a89f5a-d8ac-4ea4-b17c-591cd86855e9, Snapshot:dd257190-ef50-4990-a61f-40d84e2b08e9, Sizes:{ Actual:200704 Provisioned:3221225472 Total: 0 Initial: null } } DiskSnapshot: { Id: e7d6f1fb-ab37-45f8-8e23-5964ed194581, Disk:f2a89f5a-d8ac-4ea4-b17c-591cd86855e9, Snapshot:b9f3f3d3-710e-4ce2-b05f-563227f5ec04, Sizes:{ Actual:200704 Provisioned:3221225472 Total: 0 Initial: null } } DiskSnapshot: { Id: 8ef6f502-d00f-4fa5-b407-2cdc0e876045, Disk:f2a89f5a-d8ac-4ea4-b17c-591cd86855e9, Snapshot:274994de-5c2b-4b38-965f-4cadea3e0db3, Sizes:{ Actual:200704 Provisioned:3221225472 Total: 0 Initial: null } } DiskSnapshot: { Id: 9c80aead-88de-48f9-be7e-0fde917e7f55, Disk:f2a89f5a-d8ac-4ea4-b17c-591cd86855e9, Snapshot:f1d7382d-4427-4e21-9544-1b3cd85f23ae, Sizes:{ Actual:0 Provisioned:3221225472 Total: 0 Initial: null } } As we can observe with these values, the actual size of snapshot disks indicates 196Kb, and when I try upload any snapshot disk (Even more 1.1Gb), the system allows upload all content disk and when it is finalished, it changes actual and total size. When the restore ends, I obtain these values: Disk: { Name:985a02a0-0752-420a-a2fb-a3e89d1ac3bc_restoreBacula, Id:f2a89f5a-d8ac-4ea4-b17c-591cd86855e9, Image:8ef6f502-d00f-4fa5-b407-2cdc0e876045, Format:COW, WipeAfterDelete: false, Shareable: false, Status: OK, Sparse: true, Sizes:{ Initial:null, Actual:1318850560, Total:4593303552, Provisioned: 3221225472 } } DiskSnapshot: { Id: 87c4cb08-502a-4937-bbba-6d455395e990, Disk:f2a89f5a-d8ac-4ea4-b17c-591cd86855e9, Snapshot:dd257190-ef50-4990-a61f-40d84e2b08e9, Sizes:{ Actual:22282240 Provisioned:3221225472 Total: 0 Initial: null } } DiskSnapshot: { Id: e7d6f1fb-ab37-45f8-8e23-5964ed194581, Disk:f2a89f5a-d8ac-4ea4-b17c-591cd86855e9, Snapshot:b9f3f3d3-710e-4ce2-b05f-563227f5ec04, Sizes:{ Actual:32374784 Provisioned:3221225472 Total: 0 Initial: null } } DiskSnapshot: { Id: 9c80aead-88de-48f9-be7e-0fde917e7f55, Disk:f2a89f5a-d8ac-4ea4-b17c-591cd86855e9, Snapshot:f1d7382d-4427-4e21-9544-1b3cd85f23ae, Sizes:{ Actual:3219795968 Provisioned:3221225472 Total: 0 Initial: null } } There is a disk snapshot less since at the end of the restoration I merge the last snapshot. But the values showed changes in actual Size in the DiskSnapshot. Could you help me, please? Is there a different procedure to the one described in https://ovirt.org/develop/release-management/features/storage/backup-restore... to work with iSCSI storage or is this simply a bug? How reproducible: Backup and restore VM with snapshots in an iSCSI storage, using Java SDK. Steps to Reproduce: 1.- Follow steps indicated in https://ovirt.org/develop/release-management/features/storage/backup-restore... to backup a VM allocated iSCSI Storage and containing snapshots bigger than 1.1GB 2.- Try to restore it using the instructions of that documment Actual results: Upload of images of disk snapshots bigger than 1.1Gb do not suceed ant the restore process fails. Expected results: Restored vm
Qemu-imgs-iSCSI.txt qemu-img info DiskSnap_47c4e157-edd8-4bfc-b838-4d57ac8396bd_33b3e74a-cc6b-474a-97cc-960891716110_0.img image: DiskSnap_47c4e157-edd8-4bfc-b838-4d57ac8396bd_33b3e74a-cc6b-474a-97cc-960891716110_0.img file format: qcow2 virtual size: 4.0G (4294967296 bytes) disk size: 2.0G cluster_size: 65536 Format specific information: compat: 1.1 lazy refcounts: false refcount bits: 16 corrupt: false qemu-img info DiskSnap_47c4e157-edd8-4bfc-b838-4d57ac8396bd_429cbe6d-1148-4a84-861a-56ad6902859e_3.img image: DiskSnap_47c4e157-edd8-4bfc-b838-4d57ac8396bd_429cbe6d-1148-4a84-861a-56ad6902859e_3.img file format: qcow2 virtual size: 4.0G (4294967296 bytes) disk size: 4.5G cluster_size: 65536 backing file: 1bef6e30-e4c5-4fea-928a-38a14ef8ffd2 backing file format: qcow2 Format specific information: compat: 1.1 lazy refcounts: false refcount bits: 16 corrupt: false qemu-img info DiskSnap_47c4e157-edd8-4bfc-b838-4d57ac8396bd_4d10cb58-9f52-4d64-b6e9-ead4bed4c6a6_2.img image: DiskSnap_47c4e157-edd8-4bfc-b838-4d57ac8396bd_4d10cb58-9f52-4d64-b6e9-ead4bed4c6a6_2.img file format: qcow2 virtual size: 4.0G (4294967296 bytes) disk size: 1.0G cluster_size: 65536 backing file: ef3f98b9-82c7-4e51-a4e6-8c214051842d backing file format: qcow2 Format specific information: compat: 1.1 lazy refcounts: false refcount bits: 16 corrupt: false qemu-img info DiskSnap_47c4e157-edd8-4bfc-b838-4d57ac8396bd_9349e4dd-85b3-4145-8674-bb3f39546020_1.img image: DiskSnap_47c4e157-edd8-4bfc-b838-4d57ac8396bd_9349e4dd-85b3-4145-8674-bb3f39546020_1.img file format: qcow2 virtual size: 4.0G (4294967296 bytes) disk size: 1.0G cluster_size: 65536 backing file: ca794a5c-2edc-43e1-8aed-079886e91166 backing file format: qcow2 Format specific information: compat: 1.1 lazy refcounts: false refcount bits: 16 corrupt: false
qemu-imgs-NFS.txt qemu-img info DiskSnap_985a02a0-0752-420a-a2fb-a3e89d1ac3bc_2cf31d95-3b86-4d97-b39b-672d0066f503_3.img image: DiskSnap_985a02a0-0752-420a-a2fb-a3e89d1ac3bc_2cf31d95-3b86-4d97-b39b-672d0066f503_3.img file format: qcow2 virtual size: 3.0G (3221225472 bytes) disk size: 1.2G cluster_size: 65536 backing file: d3caa82f-416a-412b-8597-6b370ecb3490 backing file format: qcow2 Format specific information: compat: 1.1 lazy refcounts: false refcount bits: 16 corrupt: false qemu-img info DiskSnap_985a02a0-0752-420a-a2fb-a3e89d1ac3bc_3dffb5f4-3f9c-410c-8b97-133922721f9a_0.img image: DiskSnap_985a02a0-0752-420a-a2fb-a3e89d1ac3bc_3dffb5f4-3f9c-410c-8b97-133922721f9a_0.img file format: raw virtual size: 3.0G (3221225472 bytes) disk size: 3.0G qemu-img info DiskSnap_985a02a0-0752-420a-a2fb-a3e89d1ac3bc_aa8648dd-d6c0-4d9d-8373-6764e806359f_2.img image: DiskSnap_985a02a0-0752-420a-a2fb-a3e89d1ac3bc_aa8648dd-d6c0-4d9d-8373-6764e806359f_2.img file format: qcow2 virtual size: 3.0G (3221225472 bytes) disk size: 31M cluster_size: 65536 backing file: 08fbf611-4980-451f-8596-906455521ff9 backing file format: qcow2 Format specific information: compat: 1.1 lazy refcounts: false refcount bits: 16 corrupt: false qemu-img info DiskSnap_985a02a0-0752-420a-a2fb-a3e89d1ac3bc_d3307b3c-f930-4faa-90cb-6aedd38bea93_1.img image: DiskSnap_985a02a0-0752-420a-a2fb-a3e89d1ac3bc_d3307b3c-f930-4faa-90cb-6aedd38bea93_1.img file format: qcow2 virtual size: 3.0G (3221225472 bytes) disk size: 21M cluster_size: 65536 backing file: 6eef66c5-69b6-416b-8a5d-6b4b29fc4404 backing file format: raw Format specific information: compat: 1.1 lazy refcounts: false refcount bits: 16 corrupt: false
host's-imageiot-log 2019-05-07 14:11:57,232 INFO (Thread-71751) [tickets] [local] ADD ticket={u'url': u'file:///rhev/data-center/mnt/blockSD/b25016d4-7abf-4d4b-b089-2878097cf592/images/6b300c05-bd21-4148-af9a-6de9cdead8e5/a44bf3cf-c889-46ad-bfe2-d7bc9751ed46', u'ops': [u'write'], u'uuid': u'61d044ee-a07d-4c60-8643-a48eb164565a', u'timeout': 23, u'size': 4831838208} 2019-05-07 14:11:58,669 INFO (Thread-71752) [tickets] [local] EXTEND timeout=23 ticket=61d044ee-a07d-4c60-8643-a48eb164565a 2019-05-07 14:12:02,700 INFO (Thread-71754) [tickets] [local] EXTEND timeout=23 ticket=61d044ee-a07d-4c60-8643-a48eb164565a 2019-05-07 14:12:10,772 INFO (Thread-71756) [tickets] [local] EXTEND timeout=23 ticket=61d044ee-a07d-4c60-8643-a48eb164565a 2019-05-07 14:12:20,828 INFO (Thread-71758) [tickets] [local] EXTEND timeout=23 ticket=61d044ee-a07d-4c60-8643-a48eb164565a 2019-05-07 14:12:24,416 INFO (Thread-71760) [images] [172.19.33.146] WRITE size=134217728 offset=0 flush=True ticket=61d044ee-a07d-4c60-8643-a48eb164565a 2019-05-07 14:12:30,878 INFO (Thread-71761) [tickets] [local] EXTEND timeout=23 ticket=61d044ee-a07d-4c60-8643-a48eb164565a 2019-05-07 14:12:37,534 INFO (Thread-71763) [images] [172.19.33.146] WRITE size=134217728 offset=134217728 flush=True ticket=61d044ee-a07d-4c60-8643-a48eb164565a 2019-05-07 14:12:40,967 INFO (Thread-71764) [tickets] [local] EXTEND timeout=23 ticket=61d044ee-a07d-4c60-8643-a48eb164565a 2019-05-07 14:12:50,805 INFO (Thread-71766) [images] [172.19.33.146] WRITE size=134217728 offset=268435456 flush=True ticket=61d044ee-a07d-4c60-8643-a48eb164565a 2019-05-07 14:12:51,064 INFO (Thread-71767) [tickets] [local] EXTEND timeout=23 ticket=61d044ee-a07d-4c60-8643-a48eb164565a 2019-05-07 14:13:01,137 INFO (Thread-71769) [tickets] [local] EXTEND timeout=23 ticket=61d044ee-a07d-4c60-8643-a48eb164565a 2019-05-07 14:13:06,422 INFO (Thread-71771) [images] [172.19.33.146] WRITE size=134217728 offset=402653184 flush=True ticket=61d044ee-a07d-4c60-8643-a48eb164565a 2019-05-07 14:13:11,219 INFO (Thread-71772) [tickets] [local] EXTEND timeout=23 ticket=61d044ee-a07d-4c60-8643-a48eb164565a 2019-05-07 14:13:20,700 INFO (Thread-71774) [images] [172.19.33.146] WRITE size=134217728 offset=536870912 flush=True ticket=61d044ee-a07d-4c60-8643-a48eb164565a 2019-05-07 14:13:21,303 INFO (Thread-71775) [tickets] [local] EXTEND timeout=23 ticket=61d044ee-a07d-4c60-8643-a48eb164565a 2019-05-07 14:13:31,360 INFO (Thread-71777) [tickets] [local] EXTEND timeout=23 ticket=61d044ee-a07d-4c60-8643-a48eb164565a 2019-05-07 14:13:34,831 INFO (Thread-71779) [images] [172.19.33.146] WRITE size=134217728 offset=671088640 flush=True ticket=61d044ee-a07d-4c60-8643-a48eb164565a 2019-05-07 14:13:41,442 INFO (Thread-71780) [tickets] [local] EXTEND timeout=23 ticket=61d044ee-a07d-4c60-8643-a48eb164565a 2019-05-07 14:13:49,343 INFO (Thread-71782) [images] [172.19.33.146] WRITE size=134217728 offset=805306368 flush=True ticket=61d044ee-a07d-4c60-8643-a48eb164565a 2019-05-07 14:13:51,524 INFO (Thread-71783) [tickets] [local] EXTEND timeout=23 ticket=61d044ee-a07d-4c60-8643-a48eb164565a 2019-05-07 14:14:01,601 INFO (Thread-71785) [tickets] [local] EXTEND timeout=23 ticket=61d044ee-a07d-4c60-8643-a48eb164565a 2019-05-07 14:14:03,887 INFO (Thread-71787) [images] [172.19.33.146] WRITE size=134217728 offset=939524096 flush=True ticket=61d044ee-a07d-4c60-8643-a48eb164565a 2019-05-07 14:14:11,686 INFO (Thread-71788) [tickets] [local] EXTEND timeout=23 ticket=61d044ee-a07d-4c60-8643-a48eb164565a 2019-05-07 14:14:16,959 INFO (Thread-71790) [images] [172.19.33.146] WRITE size=134217728 offset=1073741824 flush=True ticket=61d044ee-a07d-4c60-8643-a48eb164565a 2019-05-07 14:14:21,763 INFO (Thread-71791) [tickets] [local] EXTEND timeout=23 ticket=61d044ee-a07d-4c60-8643-a48eb164565a 2019-05-07 14:14:31,602 INFO (Thread-71793) [images] [172.19.33.146] WRITE size=134217728 offset=1207959552 flush=True ticket=61d044ee-a07d-4c60-8643-a48eb164565a 2019-05-07 14:14:31,973 INFO (Thread-71794) [tickets] [local] EXTEND timeout=23 ticket=61d044ee-a07d-4c60-8643-a48eb164565a 2019-05-07 14:14:42,177 INFO (Thread-71796) [tickets] [local] EXTEND timeout=23 ticket=61d044ee-a07d-4c60-8643-a48eb164565a 2019-05-07 14:14:46,099 INFO (Thread-71798) [images] [172.19.33.146] WRITE size=134217728 offset=1342177280 flush=True ticket=61d044ee-a07d-4c60-8643-a48eb164565a 2019-05-07 14:14:52,251 INFO (Thread-71799) [tickets] [local] EXTEND timeout=23 ticket=61d044ee-a07d-4c60-8643-a48eb164565a 2019-05-07 14:15:00,061 INFO (Thread-71801) [images] [172.19.33.146] WRITE size=134217728 offset=1476395008 flush=True ticket=61d044ee-a07d-4c60-8643-a48eb164565a 2019-05-07 14:15:02,329 INFO (Thread-71802) [tickets] [local] EXTEND timeout=23 ticket=61d044ee-a07d-4c60-8643-a48eb164565a 2019-05-07 14:15:12,472 INFO (Thread-71804) [tickets] [local] EXTEND timeout=23 ticket=61d044ee-a07d-4c60-8643-a48eb164565a 2019-05-07 14:15:15,134 INFO (Thread-71806) [images] [172.19.33.146] WRITE size=134217728 offset=1610612736 flush=True ticket=61d044ee-a07d-4c60-8643-a48eb164565a 2019-05-07 14:15:22,604 INFO (Thread-71807) [tickets] [local] EXTEND timeout=23 ticket=61d044ee-a07d-4c60-8643-a48eb164565a 2019-05-07 14:15:28,223 INFO (Thread-71809) [images] [172.19.33.146] WRITE size=134217728 offset=1744830464 flush=True ticket=61d044ee-a07d-4c60-8643-a48eb164565a 2019-05-07 14:15:32,709 INFO (Thread-71810) [tickets] [local] EXTEND timeout=23 ticket=61d044ee-a07d-4c60-8643-a48eb164565a 2019-05-07 14:15:41,687 INFO (Thread-71812) [images] [172.19.33.146] WRITE size=134217728 offset=1879048192 flush=True ticket=61d044ee-a07d-4c60-8643-a48eb164565a 2019-05-07 14:15:42,812 INFO (Thread-71813) [tickets] [local] EXTEND timeout=23 ticket=61d044ee-a07d-4c60-8643-a48eb164565a 2019-05-07 14:15:52,981 INFO (Thread-71815) [tickets] [local] EXTEND timeout=23 ticket=61d044ee-a07d-4c60-8643-a48eb164565a 2019-05-07 14:15:54,662 INFO (Thread-71817) [images] [172.19.33.146] WRITE size=134217728 offset=2013265920 flush=True ticket=61d044ee-a07d-4c60-8643-a48eb164565a 2019-05-07 14:16:03,121 INFO (Thread-71818) [tickets] [local] EXTEND timeout=23 ticket=61d044ee-a07d-4c60-8643-a48eb164565a 2019-05-07 14:16:13,216 INFO (Thread-71820) [tickets] [local] REMOVE ticket=61d044ee-a07d-4c60-8643-a48eb164565a 2019-05-07 14:16:52,444 INFO (Thread-71821) [tickets] [local] ADD ticket={u'url': u'file:///rhev/data-center/mnt/blockSD/b25016d4-7abf-4d4b-b089-2878097cf592/images/6b300c05-bd21-4148-af9a-6de9cdead8e5/335fddf6-5dee-4bce-b06f-57967c9155b0', u'ops': [u'write'], u'uuid': u'53530fbe-0a70-406f-99d3-658d6be954f7', u'timeout': 23, u'size': 1073741824} 2019-05-07 14:16:52,503 INFO (Thread-71822) [tickets] [local] EXTEND timeout=23 ticket=53530fbe-0a70-406f-99d3-658d6be954f7 2019-05-07 14:16:54,548 INFO (Thread-71824) [tickets] [local] EXTEND timeout=23 ticket=53530fbe-0a70-406f-99d3-658d6be954f7 2019-05-07 14:16:58,621 INFO (Thread-71826) [tickets] [local] EXTEND timeout=23 ticket=53530fbe-0a70-406f-99d3-658d6be954f7 2019-05-07 14:17:06,716 INFO (Thread-71828) [tickets] [local] EXTEND timeout=23 ticket=53530fbe-0a70-406f-99d3-658d6be954f7 2019-05-07 14:17:16,814 INFO (Thread-71830) [tickets] [local] EXTEND timeout=23 ticket=53530fbe-0a70-406f-99d3-658d6be954f7 2019-05-07 14:17:18,952 INFO (Thread-71832) [images] [172.19.33.146] WRITE size=134217728 offset=0 flush=True ticket=53530fbe-0a70-406f-99d3-658d6be954f7 2019-05-07 14:17:26,985 INFO (Thread-71833) [tickets] [local] EXTEND timeout=23 ticket=53530fbe-0a70-406f-99d3-658d6be954f7 2019-05-07 14:17:32,164 INFO (Thread-71835) [images] [172.19.33.146] WRITE size=134217728 offset=134217728 flush=True ticket=53530fbe-0a70-406f-99d3-658d6be954f7 2019-05-07 14:17:37,152 INFO (Thread-71836) [tickets] [local] EXTEND timeout=23 ticket=53530fbe-0a70-406f-99d3-658d6be954f7 2019-05-07 14:17:45,358 INFO (Thread-71838) [images] [172.19.33.146] WRITE size=134217728 offset=268435456 flush=True ticket=53530fbe-0a70-406f-99d3-658d6be954f7 2019-05-07 14:17:47,369 INFO (Thread-71839) [tickets] [local] EXTEND timeout=23 ticket=53530fbe-0a70-406f-99d3-658d6be954f7 2019-05-07 14:17:57,530 INFO (Thread-71841) [tickets] [local] EXTEND timeout=23 ticket=53530fbe-0a70-406f-99d3-658d6be954f7 2019-05-07 14:17:58,491 INFO (Thread-71843) [images] [172.19.33.146] WRITE size=134217728 offset=402653184 flush=True ticket=53530fbe-0a70-406f-99d3-658d6be954f7 2019-05-07 14:18:07,650 INFO (Thread-71844) [tickets] [local] EXTEND timeout=23 ticket=53530fbe-0a70-406f-99d3-658d6be954f7 2019-05-07 14:18:11,474 INFO (Thread-71846) [images] [172.19.33.146] WRITE size=134217728 offset=536870912 flush=True ticket=53530fbe-0a70-406f-99d3-658d6be954f7 2019-05-07 14:18:17,776 INFO (Thread-71847) [tickets] [local] EXTEND timeout=23 ticket=53530fbe-0a70-406f-99d3-658d6be954f7 2019-05-07 14:18:24,596 INFO (Thread-71849) [images] [172.19.33.146] WRITE size=134217728 offset=671088640 flush=True ticket=53530fbe-0a70-406f-99d3-658d6be954f7 2019-05-07 14:18:27,898 INFO (Thread-71850) [tickets] [local] EXTEND timeout=23 ticket=53530fbe-0a70-406f-99d3-658d6be954f7 2019-05-07 14:18:38,072 INFO (Thread-71852) [tickets] [local] EXTEND timeout=23 ticket=53530fbe-0a70-406f-99d3-658d6be954f7 2019-05-07 14:18:38,166 INFO (Thread-71854) [images] [172.19.33.146] WRITE size=134217728 offset=805306368 flush=True ticket=53530fbe-0a70-406f-99d3-658d6be954f7 2019-05-07 14:18:48,146 INFO (Thread-71855) [tickets] [local] EXTEND timeout=23 ticket=53530fbe-0a70-406f-99d3-658d6be954f7 2019-05-07 14:18:51,250 INFO (Thread-71857) [images] [172.19.33.146] WRITE size=134217728 offset=939524096 flush=True ticket=53530fbe-0a70-406f-99d3-658d6be954f7 2019-05-07 14:18:58,277 INFO (Thread-71858) [tickets] [local] EXTEND timeout=23 ticket=53530fbe-0a70-406f-99d3-658d6be954f7 2019-05-07 14:19:08,400 INFO (Thread-71860) [tickets] [local] REMOVE ticket=53530fbe-0a70-406f-99d3-658d6be954f7 2019-05-07 14:19:49,103 INFO (Thread-71861) [tickets] [local] ADD ticket={u'url': u'file:///rhev/data-center/mnt/blockSD/b25016d4-7abf-4d4b-b089-2878097cf592/images/6b300c05-bd21-4148-af9a-6de9cdead8e5/c0eb69c0-7ce0-487b-b418-c5e3b0323741', u'ops': [u'write'], u'uuid': u'e7833c43-de03-42a3-8526-ae35fd445678', u'timeout': 23, u'size': 1073741824} 2019-05-07 14:19:50,987 INFO (Thread-71862) [tickets] [local] EXTEND timeout=23 ticket=e7833c43-de03-42a3-8526-ae35fd445678 2019-05-07 14:19:55,070 INFO (Thread-71864) [tickets] [local] EXTEND timeout=23 ticket=e7833c43-de03-42a3-8526-ae35fd445678 2019-05-07 14:20:03,180 INFO (Thread-71866) [tickets] [local] EXTEND timeout=23 ticket=e7833c43-de03-42a3-8526-ae35fd445678 2019-05-07 14:20:13,294 INFO (Thread-71868) [tickets] [local] EXTEND timeout=23 ticket=e7833c43-de03-42a3-8526-ae35fd445678 2019-05-07 14:20:15,660 INFO (Thread-71870) [images] [172.19.33.146] WRITE size=134217728 offset=0 flush=True ticket=e7833c43-de03-42a3-8526-ae35fd445678 2019-05-07 14:20:23,410 INFO (Thread-71871) [tickets] [local] EXTEND timeout=23 ticket=e7833c43-de03-42a3-8526-ae35fd445678 2019-05-07 14:20:28,747 INFO (Thread-71873) [images] [172.19.33.146] WRITE size=134217728 offset=134217728 flush=True ticket=e7833c43-de03-42a3-8526-ae35fd445678 2019-05-07 14:20:33,765 INFO (Thread-71874) [tickets] [local] EXTEND timeout=23 ticket=e7833c43-de03-42a3-8526-ae35fd445678 2019-05-07 14:20:41,855 INFO (Thread-71876) [images] [172.19.33.146] WRITE size=134217728 offset=268435456 flush=True ticket=e7833c43-de03-42a3-8526-ae35fd445678 2019-05-07 14:20:43,971 INFO (Thread-71877) [tickets] [local] EXTEND timeout=23 ticket=e7833c43-de03-42a3-8526-ae35fd445678 2019-05-07 14:20:54,122 INFO (Thread-71879) [tickets] [local] EXTEND timeout=23 ticket=e7833c43-de03-42a3-8526-ae35fd445678 2019-05-07 14:20:54,899 INFO (Thread-71881) [images] [172.19.33.146] WRITE size=134217728 offset=402653184 flush=True ticket=e7833c43-de03-42a3-8526-ae35fd445678 2019-05-07 14:21:04,190 INFO (Thread-71882) [tickets] [local] EXTEND timeout=23 ticket=e7833c43-de03-42a3-8526-ae35fd445678 2019-05-07 14:21:08,022 INFO (Thread-71884) [images] [172.19.33.146] WRITE size=134217728 offset=536870912 flush=True ticket=e7833c43-de03-42a3-8526-ae35fd445678 2019-05-07 14:21:14,334 INFO (Thread-71885) [tickets] [local] EXTEND timeout=23 ticket=e7833c43-de03-42a3-8526-ae35fd445678 2019-05-07 14:21:21,435 INFO (Thread-71887) [images] [172.19.33.146] WRITE size=134217728 offset=671088640 flush=True ticket=e7833c43-de03-42a3-8526-ae35fd445678 2019-05-07 14:21:24,451 INFO (Thread-71888) [tickets] [local] EXTEND timeout=23 ticket=e7833c43-de03-42a3-8526-ae35fd445678 2019-05-07 14:21:34,609 INFO (Thread-71890) [tickets] [local] EXTEND timeout=23 ticket=e7833c43-de03-42a3-8526-ae35fd445678 2019-05-07 14:21:34,618 INFO (Thread-71891) [images] [172.19.33.146] WRITE size=134217728 offset=805306368 flush=True ticket=e7833c43-de03-42a3-8526-ae35fd445678 2019-05-07 14:21:44,940 INFO (Thread-71893) [tickets] [local] EXTEND timeout=23 ticket=e7833c43-de03-42a3-8526-ae35fd445678 2019-05-07 14:21:47,785 INFO (Thread-71895) [images] [172.19.33.146] WRITE size=134217728 offset=939524096 flush=True ticket=e7833c43-de03-42a3-8526-ae35fd445678 2019-05-07 14:21:55,120 INFO (Thread-71896) [tickets] [local] EXTEND timeout=23 ticket=e7833c43-de03-42a3-8526-ae35fd445678 2019-05-07 14:22:05,242 INFO (Thread-71898) [tickets] [local] REMOVE ticket=e7833c43-de03-42a3-8526-ae35fd445678 2019-05-07 14:22:45,487 INFO (Thread-71899) [tickets] [local] ADD ticket={u'url': u'file:///rhev/data-center/mnt/blockSD/b25016d4-7abf-4d4b-b089-2878097cf592/images/6b300c05-bd21-4148-af9a-6de9cdead8e5/11564b7d-06af-4328-85a6-01b885d84816', u'ops': [u'write'], u'uuid': u'8d353735-0a29-463c-b772-ec37f451e2e9', u'timeout': 23, u'size': 1073741824} 2019-05-07 14:22:47,055 INFO (Thread-71900) [tickets] [local] EXTEND timeout=23 ticket=8d353735-0a29-463c-b772-ec37f451e2e9 2019-05-07 14:22:51,154 INFO (Thread-71902) [tickets] [local] EXTEND timeout=23 ticket=8d353735-0a29-463c-b772-ec37f451e2e9 2019-05-07 14:22:59,274 INFO (Thread-71904) [tickets] [local] EXTEND timeout=23 ticket=8d353735-0a29-463c-b772-ec37f451e2e9 2019-05-07 14:23:09,385 INFO (Thread-71906) [tickets] [local] EXTEND timeout=23 ticket=8d353735-0a29-463c-b772-ec37f451e2e9 2019-05-07 14:23:12,497 INFO (Thread-71908) [images] [172.19.33.146] WRITE size=134217728 offset=0 flush=True ticket=8d353735-0a29-463c-b772-ec37f451e2e9 2019-05-07 14:23:19,481 INFO (Thread-71909) [tickets] [local] EXTEND timeout=23 ticket=8d353735-0a29-463c-b772-ec37f451e2e9 2019-05-07 14:23:26,131 INFO (Thread-71911) [images] [172.19.33.146] WRITE size=134217728 offset=134217728 flush=True ticket=8d353735-0a29-463c-b772-ec37f451e2e9 2019-05-07 14:23:29,556 INFO (Thread-71912) [tickets] [local] EXTEND timeout=23 ticket=8d353735-0a29-463c-b772-ec37f451e2e9 2019-05-07 14:23:39,402 INFO (Thread-71914) [images] [172.19.33.146] WRITE size=134217728 offset=268435456 flush=True ticket=8d353735-0a29-463c-b772-ec37f451e2e9 2019-05-07 14:23:39,663 INFO (Thread-71915) [tickets] [local] EXTEND timeout=23 ticket=8d353735-0a29-463c-b772-ec37f451e2e9 2019-05-07 14:23:49,902 INFO (Thread-71917) [tickets] [local] EXTEND timeout=23 ticket=8d353735-0a29-463c-b772-ec37f451e2e9 2019-05-07 14:23:52,372 INFO (Thread-71919) [images] [172.19.33.146] WRITE size=134217728 offset=402653184 flush=True ticket=8d353735-0a29-463c-b772-ec37f451e2e9 2019-05-07 14:24:00,033 INFO (Thread-71920) [tickets] [local] EXTEND timeout=23 ticket=8d353735-0a29-463c-b772-ec37f451e2e9 2019-05-07 14:24:05,476 INFO (Thread-71922) [images] [172.19.33.146] WRITE size=134217728 offset=536870912 flush=True ticket=8d353735-0a29-463c-b772-ec37f451e2e9 2019-05-07 14:24:10,171 INFO (Thread-71923) [tickets] [local] EXTEND timeout=23 ticket=8d353735-0a29-463c-b772-ec37f451e2e9 2019-05-07 14:24:18,598 INFO (Thread-71925) [images] [172.19.33.146] WRITE size=134217728 offset=671088640 flush=True ticket=8d353735-0a29-463c-b772-ec37f451e2e9 2019-05-07 14:24:20,349 INFO (Thread-71926) [tickets] [local] EXTEND timeout=23 ticket=8d353735-0a29-463c-b772-ec37f451e2e9 2019-05-07 14:24:30,450 INFO (Thread-71928) [tickets] [local] EXTEND timeout=23 ticket=8d353735-0a29-463c-b772-ec37f451e2e9 2019-05-07 14:24:31,735 INFO (Thread-71930) [images] [172.19.33.146] WRITE size=134217728 offset=805306368 flush=True ticket=8d353735-0a29-463c-b772-ec37f451e2e9 2019-05-07 14:24:40,502 INFO (Thread-71931) [tickets] [local] EXTEND timeout=23 ticket=8d353735-0a29-463c-b772-ec37f451e2e9 2019-05-07 14:24:44,967 INFO (Thread-71933) [images] [172.19.33.146] WRITE size=134217728 offset=939524096 flush=True ticket=8d353735-0a29-463c-b772-ec37f451e2e9 2019-05-07 14:24:50,654 INFO (Thread-71934) [tickets] [local] EXTEND timeout=23 ticket=8d353735-0a29-463c-b772-ec37f451e2e9 2019-05-07 14:24:58,330 WARNING (Thread-71936) [web] ERROR [172.19.33.146] PUT /images/8d353735-0a29-463c-b772-ec37f451e2e9 [403] Requested range out of allowed range [request=0.001231] 2019-05-07 14:24:58,367 WARNING (Thread-71937) [web] ERROR [172.19.33.146] PUT /images/8d353735-0a29-463c-b772-ec37f451e2e9 [403] Requested range out of allowed range [request=0.000377] 2019-05-07 14:25:00,768 INFO (Thread-71938) [tickets] [local] EXTEND timeout=23 ticket=8d353735-0a29-463c-b772-ec37f451e2e9 2019-05-07 14:25:10,839 INFO (Thread-71940) [tickets] [local] EXTEND timeout=23 ticket=8d353735-0a29-463c-b772-ec37f451e2e9 2019-05-07 14:25:20,969 INFO (Thread-71942) [tickets] [local] EXTEND timeout=23 ticket=8d353735-0a29-463c-b772-ec37f451e2e9 2019-05-07 14:25:28,565 INFO (Thread-71944) [images] [172.19.33.146] WRITE size=0 offset=1073741824 flush=True ticket=8d353735-0a29-463c-b772-ec37f451e2e9 2019-05-07 14:25:29,947 WARNING (Thread-71945) [web] ERROR [172.19.33.146] PUT /images/8d353735-0a29-463c-b772-ec37f451e2e9 [403] Requested range out of allowed range [request=0.000439] 2019-05-07 14:25:29,995 WARNING (Thread-71946) [web] ERROR [172.19.33.146] PUT /images/8d353735-0a29-463c-b772-ec37f451e2e9 [403] Requested range out of allowed range [request=0.000374] 2019-05-07 14:25:31,114 INFO (Thread-71947) [tickets] [local] EXTEND timeout=23 ticket=8d353735-0a29-463c-b772-ec37f451e2e9 2019-05-07 14:25:41,252 INFO (Thread-71949) [tickets] [local] EXTEND timeout=23 ticket=8d353735-0a29-463c-b772-ec37f451e2e9 2019-05-07 14:25:51,368 INFO (Thread-71951) [tickets] [local] EXTEND timeout=23 ticket=8d353735-0a29-463c-b772-ec37f451e2e9 2019-05-07 14:26:00,183 WARNING (Thread-71953) [web] ERROR [172.19.33.146] PUT /images/8d353735-0a29-463c-b772-ec37f451e2e9 [403] Requested range out of allowed range [request=0.000317] 2019-05-07 14:26:01,442 INFO (Thread-71954) [tickets] [local] EXTEND timeout=23 ticket=8d353735-0a29-463c-b772-ec37f451e2e9 2019-05-07 14:26:11,586 INFO (Thread-71956) [tickets] [local] EXTEND timeout=23 ticket=8d353735-0a29-463c-b772-ec37f451e2e9 2019-05-07 14:26:21,630 INFO (Thread-71958) [tickets] [local] EXTEND timeout=23 ticket=8d353735-0a29-463c-b772-ec37f451e2e9 2019-05-07 14:26:30,253 WARNING (Thread-71960) [web] ERROR [172.19.33.146] PUT /images/8d353735-0a29-463c-b772-ec37f451e2e9 [403] Requested range out of allowed range [request=0.000488] 2019-05-07 14:26:31,789 INFO (Thread-71961) [tickets] [local] EXTEND timeout=23 ticket=8d353735-0a29-463c-b772-ec37f451e2e9
On Wed, May 8, 2019 at 1:56 PM <francisco.garcia@wbsgo.com> wrote:
host's-imageiot-log
2019-05-07 14:11:57,232 INFO (Thread-71751) [tickets] [local] ADD ticket={u'url': u'file:///rhev/data-center/mnt/blockSD/b25016d4-7abf-4d4b-b089-2878097cf592/images/6b300c05-bd21-4148-af9a-6de9cdead8e5/a44bf3cf-c889-46ad-bfe2-d7bc9751ed46', u'ops': [u'write'], u'uuid': u'61d044ee-a07d-4c60-8643-a48eb164565a', u'timeout': 23, u'size': 4831838208} 2019-05-07 14:11:58,669 INFO (Thread-71752) [tickets] [local] EXTEND timeout=23 ticket=61d044ee-a07d-4c60-8643-a48eb164565a 2019-05-07 14:12:02,700 INFO (Thread-71754) [tickets] [local] EXTEND timeout=23 ticket=61d044ee-a07d-4c60-8643-a48eb164565a 2019-05-07 14:12:10,772 INFO (Thread-71756) [tickets] [local] EXTEND timeout=23 ticket=61d044ee-a07d-4c60-8643-a48eb164565a 2019-05-07 14:12:20,828 INFO (Thread-71758) [tickets] [local] EXTEND timeout=23 ticket=61d044ee-a07d-4c60-8643-a48eb164565a 2019-05-07 14:12:24,416 INFO (Thread-71760) [images] [172.19.33.146] WRITE size=134217728 offset=0 flush=True ticket=61d044ee-a07d-4c60-8643-a48eb164565a 2019-05-07 14:12:30,878 INFO (Thread-71761) [tickets] [local] EXTEND timeout=23 ticket=61d044ee-a07d-4c60-8643-a48eb164565a 2019-05-07 14:12:37,534 INFO (Thread-71763) [images] [172.19.33.146] WRITE size=134217728 offset=134217728 flush=True ticket=61d044ee-a07d-4c60-8643-a48eb164565a 2019-05-07 14:12:40,967 INFO (Thread-71764) [tickets] [local] EXTEND timeout=23 ticket=61d044ee-a07d-4c60-8643-a48eb164565a 2019-05-07 14:12:50,805 INFO (Thread-71766) [images] [172.19.33.146] WRITE size=134217728 offset=268435456 flush=True ticket=61d044ee-a07d-4c60-8643-a48eb164565a 2019-05-07 14:12:51,064 INFO (Thread-71767) [tickets] [local] EXTEND timeout=23 ticket=61d044ee-a07d-4c60-8643-a48eb164565a 2019-05-07 14:13:01,137 INFO (Thread-71769) [tickets] [local] EXTEND timeout=23 ticket=61d044ee-a07d-4c60-8643-a48eb164565a 2019-05-07 14:13:06,422 INFO (Thread-71771) [images] [172.19.33.146] WRITE size=134217728 offset=402653184 flush=True ticket=61d044ee-a07d-4c60-8643-a48eb164565a 2019-05-07 14:13:11,219 INFO (Thread-71772) [tickets] [local] EXTEND timeout=23 ticket=61d044ee-a07d-4c60-8643-a48eb164565a 2019-05-07 14:13:20,700 INFO (Thread-71774) [images] [172.19.33.146] WRITE size=134217728 offset=536870912 flush=True ticket=61d044ee-a07d-4c60-8643-a48eb164565a 2019-05-07 14:13:21,303 INFO (Thread-71775) [tickets] [local] EXTEND timeout=23 ticket=61d044ee-a07d-4c60-8643-a48eb164565a 2019-05-07 14:13:31,360 INFO (Thread-71777) [tickets] [local] EXTEND timeout=23 ticket=61d044ee-a07d-4c60-8643-a48eb164565a 2019-05-07 14:13:34,831 INFO (Thread-71779) [images] [172.19.33.146] WRITE size=134217728 offset=671088640 flush=True ticket=61d044ee-a07d-4c60-8643-a48eb164565a 2019-05-07 14:13:41,442 INFO (Thread-71780) [tickets] [local] EXTEND timeout=23 ticket=61d044ee-a07d-4c60-8643-a48eb164565a 2019-05-07 14:13:49,343 INFO (Thread-71782) [images] [172.19.33.146] WRITE size=134217728 offset=805306368 flush=True ticket=61d044ee-a07d-4c60-8643-a48eb164565a 2019-05-07 14:13:51,524 INFO (Thread-71783) [tickets] [local] EXTEND timeout=23 ticket=61d044ee-a07d-4c60-8643-a48eb164565a 2019-05-07 14:14:01,601 INFO (Thread-71785) [tickets] [local] EXTEND timeout=23 ticket=61d044ee-a07d-4c60-8643-a48eb164565a 2019-05-07 14:14:03,887 INFO (Thread-71787) [images] [172.19.33.146] WRITE size=134217728 offset=939524096 flush=True ticket=61d044ee-a07d-4c60-8643-a48eb164565a 2019-05-07 14:14:11,686 INFO (Thread-71788) [tickets] [local] EXTEND timeout=23 ticket=61d044ee-a07d-4c60-8643-a48eb164565a 2019-05-07 14:14:16,959 INFO (Thread-71790) [images] [172.19.33.146] WRITE size=134217728 offset=1073741824 flush=True ticket=61d044ee-a07d-4c60-8643-a48eb164565a 2019-05-07 14:14:21,763 INFO (Thread-71791) [tickets] [local] EXTEND timeout=23 ticket=61d044ee-a07d-4c60-8643-a48eb164565a 2019-05-07 14:14:31,602 INFO (Thread-71793) [images] [172.19.33.146] WRITE size=134217728 offset=1207959552 flush=True ticket=61d044ee-a07d-4c60-8643-a48eb164565a 2019-05-07 14:14:31,973 INFO (Thread-71794) [tickets] [local] EXTEND timeout=23 ticket=61d044ee-a07d-4c60-8643-a48eb164565a 2019-05-07 14:14:42,177 INFO (Thread-71796) [tickets] [local] EXTEND timeout=23 ticket=61d044ee-a07d-4c60-8643-a48eb164565a 2019-05-07 14:14:46,099 INFO (Thread-71798) [images] [172.19.33.146] WRITE size=134217728 offset=1342177280 flush=True ticket=61d044ee-a07d-4c60-8643-a48eb164565a 2019-05-07 14:14:52,251 INFO (Thread-71799) [tickets] [local] EXTEND timeout=23 ticket=61d044ee-a07d-4c60-8643-a48eb164565a 2019-05-07 14:15:00,061 INFO (Thread-71801) [images] [172.19.33.146] WRITE size=134217728 offset=1476395008 flush=True ticket=61d044ee-a07d-4c60-8643-a48eb164565a 2019-05-07 14:15:02,329 INFO (Thread-71802) [tickets] [local] EXTEND timeout=23 ticket=61d044ee-a07d-4c60-8643-a48eb164565a 2019-05-07 14:15:12,472 INFO (Thread-71804) [tickets] [local] EXTEND timeout=23 ticket=61d044ee-a07d-4c60-8643-a48eb164565a 2019-05-07 14:15:15,134 INFO (Thread-71806) [images] [172.19.33.146] WRITE size=134217728 offset=1610612736 flush=True ticket=61d044ee-a07d-4c60-8643-a48eb164565a 2019-05-07 14:15:22,604 INFO (Thread-71807) [tickets] [local] EXTEND timeout=23 ticket=61d044ee-a07d-4c60-8643-a48eb164565a 2019-05-07 14:15:28,223 INFO (Thread-71809) [images] [172.19.33.146] WRITE size=134217728 offset=1744830464 flush=True ticket=61d044ee-a07d-4c60-8643-a48eb164565a 2019-05-07 14:15:32,709 INFO (Thread-71810) [tickets] [local] EXTEND timeout=23 ticket=61d044ee-a07d-4c60-8643-a48eb164565a 2019-05-07 14:15:41,687 INFO (Thread-71812) [images] [172.19.33.146] WRITE size=134217728 offset=1879048192 flush=True ticket=61d044ee-a07d-4c60-8643-a48eb164565a 2019-05-07 14:15:42,812 INFO (Thread-71813) [tickets] [local] EXTEND timeout=23 ticket=61d044ee-a07d-4c60-8643-a48eb164565a 2019-05-07 14:15:52,981 INFO (Thread-71815) [tickets] [local] EXTEND timeout=23 ticket=61d044ee-a07d-4c60-8643-a48eb164565a 2019-05-07 14:15:54,662 INFO (Thread-71817) [images] [172.19.33.146] WRITE size=134217728 offset=2013265920 flush=True ticket=61d044ee-a07d-4c60-8643-a48eb164565a 2019-05-07 14:16:03,121 INFO (Thread-71818) [tickets] [local] EXTEND timeout=23 ticket=61d044ee-a07d-4c60-8643-a48eb164565a 2019-05-07 14:16:13,216 INFO (Thread-71820) [tickets] [local] REMOVE ticket=61d044ee-a07d-4c60-8643-a48eb164565a 2019-05-07 14:16:52,444 INFO (Thread-71821) [tickets] [local] ADD ticket={u'url': u'file:///rhev/data-center/mnt/blockSD/b25016d4-7abf-4d4b-b089-2878097cf592/images/6b300c05-bd21-4148-af9a-6de9cdead8e5/335fddf6-5dee-4bce-b06f-57967c9155b0', u'ops': [u'write'], u'uuid': u'53530fbe-0a70-406f-99d3-658d6be954f7', u'timeout': 23, u'size': 1073741824} 2019-05-07 14:16:52,503 INFO (Thread-71822) [tickets] [local] EXTEND timeout=23 ticket=53530fbe-0a70-406f-99d3-658d6be954f7 2019-05-07 14:16:54,548 INFO (Thread-71824) [tickets] [local] EXTEND timeout=23 ticket=53530fbe-0a70-406f-99d3-658d6be954f7 2019-05-07 14:16:58,621 INFO (Thread-71826) [tickets] [local] EXTEND timeout=23 ticket=53530fbe-0a70-406f-99d3-658d6be954f7 2019-05-07 14:17:06,716 INFO (Thread-71828) [tickets] [local] EXTEND timeout=23 ticket=53530fbe-0a70-406f-99d3-658d6be954f7 2019-05-07 14:17:16,814 INFO (Thread-71830) [tickets] [local] EXTEND timeout=23 ticket=53530fbe-0a70-406f-99d3-658d6be954f7 2019-05-07 14:17:18,952 INFO (Thread-71832) [images] [172.19.33.146] WRITE size=134217728 offset=0 flush=True ticket=53530fbe-0a70-406f-99d3-658d6be954f7 2019-05-07 14:17:26,985 INFO (Thread-71833) [tickets] [local] EXTEND timeout=23 ticket=53530fbe-0a70-406f-99d3-658d6be954f7 2019-05-07 14:17:32,164 INFO (Thread-71835) [images] [172.19.33.146] WRITE size=134217728 offset=134217728 flush=True ticket=53530fbe-0a70-406f-99d3-658d6be954f7 2019-05-07 14:17:37,152 INFO (Thread-71836) [tickets] [local] EXTEND timeout=23 ticket=53530fbe-0a70-406f-99d3-658d6be954f7 2019-05-07 14:17:45,358 INFO (Thread-71838) [images] [172.19.33.146] WRITE size=134217728 offset=268435456 flush=True ticket=53530fbe-0a70-406f-99d3-658d6be954f7 2019-05-07 14:17:47,369 INFO (Thread-71839) [tickets] [local] EXTEND timeout=23 ticket=53530fbe-0a70-406f-99d3-658d6be954f7 2019-05-07 14:17:57,530 INFO (Thread-71841) [tickets] [local] EXTEND timeout=23 ticket=53530fbe-0a70-406f-99d3-658d6be954f7 2019-05-07 14:17:58,491 INFO (Thread-71843) [images] [172.19.33.146] WRITE size=134217728 offset=402653184 flush=True ticket=53530fbe-0a70-406f-99d3-658d6be954f7 2019-05-07 14:18:07,650 INFO (Thread-71844) [tickets] [local] EXTEND timeout=23 ticket=53530fbe-0a70-406f-99d3-658d6be954f7 2019-05-07 14:18:11,474 INFO (Thread-71846) [images] [172.19.33.146] WRITE size=134217728 offset=536870912 flush=True ticket=53530fbe-0a70-406f-99d3-658d6be954f7 2019-05-07 14:18:17,776 INFO (Thread-71847) [tickets] [local] EXTEND timeout=23 ticket=53530fbe-0a70-406f-99d3-658d6be954f7 2019-05-07 14:18:24,596 INFO (Thread-71849) [images] [172.19.33.146] WRITE size=134217728 offset=671088640 flush=True ticket=53530fbe-0a70-406f-99d3-658d6be954f7 2019-05-07 14:18:27,898 INFO (Thread-71850) [tickets] [local] EXTEND timeout=23 ticket=53530fbe-0a70-406f-99d3-658d6be954f7 2019-05-07 14:18:38,072 INFO (Thread-71852) [tickets] [local] EXTEND timeout=23 ticket=53530fbe-0a70-406f-99d3-658d6be954f7 2019-05-07 14:18:38,166 INFO (Thread-71854) [images] [172.19.33.146] WRITE size=134217728 offset=805306368 flush=True ticket=53530fbe-0a70-406f-99d3-658d6be954f7 2019-05-07 14:18:48,146 INFO (Thread-71855) [tickets] [local] EXTEND timeout=23 ticket=53530fbe-0a70-406f-99d3-658d6be954f7 2019-05-07 14:18:51,250 INFO (Thread-71857) [images] [172.19.33.146] WRITE size=134217728 offset=939524096 flush=True ticket=53530fbe-0a70-406f-99d3-658d6be954f7 2019-05-07 14:18:58,277 INFO (Thread-71858) [tickets] [local] EXTEND timeout=23 ticket=53530fbe-0a70-406f-99d3-658d6be954f7 2019-05-07 14:19:08,400 INFO (Thread-71860) [tickets] [local] REMOVE ticket=53530fbe-0a70-406f-99d3-658d6be954f7 2019-05-07 14:19:49,103 INFO (Thread-71861) [tickets] [local] ADD ticket={u'url': u'file:///rhev/data-center/mnt/blockSD/b25016d4-7abf-4d4b-b089-2878097cf592/images/6b300c05-bd21-4148-af9a-6de9cdead8e5/c0eb69c0-7ce0-487b-b418-c5e3b0323741', u'ops': [u'write'], u'uuid': u'e7833c43-de03-42a3-8526-ae35fd445678', u'timeout': 23, u'size': 1073741824} 2019-05-07 14:19:50,987 INFO (Thread-71862) [tickets] [local] EXTEND timeout=23 ticket=e7833c43-de03-42a3-8526-ae35fd445678 2019-05-07 14:19:55,070 INFO (Thread-71864) [tickets] [local] EXTEND timeout=23 ticket=e7833c43-de03-42a3-8526-ae35fd445678 2019-05-07 14:20:03,180 INFO (Thread-71866) [tickets] [local] EXTEND timeout=23 ticket=e7833c43-de03-42a3-8526-ae35fd445678 2019-05-07 14:20:13,294 INFO (Thread-71868) [tickets] [local] EXTEND timeout=23 ticket=e7833c43-de03-42a3-8526-ae35fd445678 2019-05-07 14:20:15,660 INFO (Thread-71870) [images] [172.19.33.146] WRITE size=134217728 offset=0 flush=True ticket=e7833c43-de03-42a3-8526-ae35fd445678 2019-05-07 14:20:23,410 INFO (Thread-71871) [tickets] [local] EXTEND timeout=23 ticket=e7833c43-de03-42a3-8526-ae35fd445678 2019-05-07 14:20:28,747 INFO (Thread-71873) [images] [172.19.33.146] WRITE size=134217728 offset=134217728 flush=True ticket=e7833c43-de03-42a3-8526-ae35fd445678 2019-05-07 14:20:33,765 INFO (Thread-71874) [tickets] [local] EXTEND timeout=23 ticket=e7833c43-de03-42a3-8526-ae35fd445678 2019-05-07 14:20:41,855 INFO (Thread-71876) [images] [172.19.33.146] WRITE size=134217728 offset=268435456 flush=True ticket=e7833c43-de03-42a3-8526-ae35fd445678 2019-05-07 14:20:43,971 INFO (Thread-71877) [tickets] [local] EXTEND timeout=23 ticket=e7833c43-de03-42a3-8526-ae35fd445678 2019-05-07 14:20:54,122 INFO (Thread-71879) [tickets] [local] EXTEND timeout=23 ticket=e7833c43-de03-42a3-8526-ae35fd445678 2019-05-07 14:20:54,899 INFO (Thread-71881) [images] [172.19.33.146] WRITE size=134217728 offset=402653184 flush=True ticket=e7833c43-de03-42a3-8526-ae35fd445678 2019-05-07 14:21:04,190 INFO (Thread-71882) [tickets] [local] EXTEND timeout=23 ticket=e7833c43-de03-42a3-8526-ae35fd445678 2019-05-07 14:21:08,022 INFO (Thread-71884) [images] [172.19.33.146] WRITE size=134217728 offset=536870912 flush=True ticket=e7833c43-de03-42a3-8526-ae35fd445678 2019-05-07 14:21:14,334 INFO (Thread-71885) [tickets] [local] EXTEND timeout=23 ticket=e7833c43-de03-42a3-8526-ae35fd445678 2019-05-07 14:21:21,435 INFO (Thread-71887) [images] [172.19.33.146] WRITE size=134217728 offset=671088640 flush=True ticket=e7833c43-de03-42a3-8526-ae35fd445678 2019-05-07 14:21:24,451 INFO (Thread-71888) [tickets] [local] EXTEND timeout=23 ticket=e7833c43-de03-42a3-8526-ae35fd445678 2019-05-07 14:21:34,609 INFO (Thread-71890) [tickets] [local] EXTEND timeout=23 ticket=e7833c43-de03-42a3-8526-ae35fd445678 2019-05-07 14:21:34,618 INFO (Thread-71891) [images] [172.19.33.146] WRITE size=134217728 offset=805306368 flush=True ticket=e7833c43-de03-42a3-8526-ae35fd445678 2019-05-07 14:21:44,940 INFO (Thread-71893) [tickets] [local] EXTEND timeout=23 ticket=e7833c43-de03-42a3-8526-ae35fd445678 2019-05-07 14:21:47,785 INFO (Thread-71895) [images] [172.19.33.146] WRITE size=134217728 offset=939524096 flush=True ticket=e7833c43-de03-42a3-8526-ae35fd445678 2019-05-07 14:21:55,120 INFO (Thread-71896) [tickets] [local] EXTEND timeout=23 ticket=e7833c43-de03-42a3-8526-ae35fd445678 2019-05-07 14:22:05,242 INFO (Thread-71898) [tickets] [local] REMOVE ticket=e7833c43-de03-42a3-8526-ae35fd445678 2019-05-07 14:22:45,487 INFO (Thread-71899) [tickets] [local] ADD ticket={u'url': u'file:///rhev/data-center/mnt/blockSD/b25016d4-7abf-4d4b-b089-2878097cf592/images/6b300c05-bd21-4148-af9a-6de9cdead8e5/11564b7d-06af-4328-85a6-01b885d84816', u'ops': [u'write'], u'uuid': u'8d353735-0a29-463c-b772-ec37f451e2e9', u'timeout': 23, u'size': 1073741824}
You create a disk without initials_size, so engine create 1g disk, and sent ticket size=1073741824 This will you write up to 1073741824 bytes to this disk, since imageio cannot extend the disk.
2019-05-07 14:22:47,055 INFO (Thread-71900) [tickets] [local] EXTEND timeout=23 ticket=8d353735-0a29-463c-b772-ec37f451e2e9 2019-05-07 14:22:51,154 INFO (Thread-71902) [tickets] [local] EXTEND timeout=23 ticket=8d353735-0a29-463c-b772-ec37f451e2e9 2019-05-07 14:22:59,274 INFO (Thread-71904) [tickets] [local] EXTEND timeout=23 ticket=8d353735-0a29-463c-b772-ec37f451e2e9 2019-05-07 14:23:09,385 INFO (Thread-71906) [tickets] [local] EXTEND timeout=23 ticket=8d353735-0a29-463c-b772-ec37f451e2e9 2019-05-07 14:23:12,497 INFO (Thread-71908) [images] [172.19.33.146] WRITE size=134217728 offset=0 flush=True ticket=8d353735-0a29-463c-b772-ec37f451e2e9 2019-05-07 14:23:19,481 INFO (Thread-71909) [tickets] [local] EXTEND timeout=23 ticket=8d353735-0a29-463c-b772-ec37f451e2e9 2019-05-07 14:23:26,131 INFO (Thread-71911) [images] [172.19.33.146] WRITE size=134217728 offset=134217728 flush=True ticket=8d353735-0a29-463c-b772-ec37f451e2e9 2019-05-07 14:23:29,556 INFO (Thread-71912) [tickets] [local] EXTEND timeout=23 ticket=8d353735-0a29-463c-b772-ec37f451e2e9 2019-05-07 14:23:39,402 INFO (Thread-71914) [images] [172.19.33.146] WRITE size=134217728 offset=268435456 flush=True ticket=8d353735-0a29-463c-b772-ec37f451e2e9 2019-05-07 14:23:39,663 INFO (Thread-71915) [tickets] [local] EXTEND timeout=23 ticket=8d353735-0a29-463c-b772-ec37f451e2e9 2019-05-07 14:23:49,902 INFO (Thread-71917) [tickets] [local] EXTEND timeout=23 ticket=8d353735-0a29-463c-b772-ec37f451e2e9 2019-05-07 14:23:52,372 INFO (Thread-71919) [images] [172.19.33.146] WRITE size=134217728 offset=402653184 flush=True ticket=8d353735-0a29-463c-b772-ec37f451e2e9 2019-05-07 14:24:00,033 INFO (Thread-71920) [tickets] [local] EXTEND timeout=23 ticket=8d353735-0a29-463c-b772-ec37f451e2e9 2019-05-07 14:24:05,476 INFO (Thread-71922) [images] [172.19.33.146] WRITE size=134217728 offset=536870912 flush=True ticket=8d353735-0a29-463c-b772-ec37f451e2e9 2019-05-07 14:24:10,171 INFO (Thread-71923) [tickets] [local] EXTEND timeout=23 ticket=8d353735-0a29-463c-b772-ec37f451e2e9 2019-05-07 14:24:18,598 INFO (Thread-71925) [images] [172.19.33.146] WRITE size=134217728 offset=671088640 flush=True ticket=8d353735-0a29-463c-b772-ec37f451e2e9 2019-05-07 14:24:20,349 INFO (Thread-71926) [tickets] [local] EXTEND timeout=23 ticket=8d353735-0a29-463c-b772-ec37f451e2e9 2019-05-07 14:24:30,450 INFO (Thread-71928) [tickets] [local] EXTEND timeout=23 ticket=8d353735-0a29-463c-b772-ec37f451e2e9 2019-05-07 14:24:31,735 INFO (Thread-71930) [images] [172.19.33.146] WRITE size=134217728 offset=805306368 flush=True ticket=8d353735-0a29-463c-b772-ec37f451e2e9 2019-05-07 14:24:40,502 INFO (Thread-71931) [tickets] [local] EXTEND timeout=23 ticket=8d353735-0a29-463c-b772-ec37f451e2e9 2019-05-07 14:24:44,967 INFO (Thread-71933) [images] [172.19.33.146] WRITE size=134217728 offset=939524096 flush=True ticket=8d353735-0a29-463c-b772-ec37f451e2e9
You try to write after 1g... 2019-05-07 14:24:50,654 INFO (Thread-71934) [tickets] [local] EXTEND
timeout=23 ticket=8d353735-0a29-463c-b772-ec37f451e2e9 2019-05-07 14:24:58,330 WARNING (Thread-71936) [web] ERROR [172.19.33.146] PUT /images/8d353735-0a29-463c-b772-ec37f451e2e9 [403] Requested range out of allowed range [request=0.001231]
imageio treat this as forbidden operation. In 4.3.z we will return here nicer and mode useful 416 "request cannot be satisfied": See https://bugzilla.redhat.com/1512315 Nir
2019-05-07 14:24:58,367 WARNING (Thread-71937) [web] ERROR [172.19.33.146] PUT /images/8d353735-0a29-463c-b772-ec37f451e2e9 [403] Requested range out of allowed range [request=0.000377] 2019-05-07 14:25:00,768 INFO (Thread-71938) [tickets] [local] EXTEND timeout=23 ticket=8d353735-0a29-463c-b772-ec37f451e2e9 2019-05-07 14:25:10,839 INFO (Thread-71940) [tickets] [local] EXTEND timeout=23 ticket=8d353735-0a29-463c-b772-ec37f451e2e9 2019-05-07 14:25:20,969 INFO (Thread-71942) [tickets] [local] EXTEND timeout=23 ticket=8d353735-0a29-463c-b772-ec37f451e2e9 2019-05-07 14:25:28,565 INFO (Thread-71944) [images] [172.19.33.146] WRITE size=0 offset=1073741824 flush=True ticket=8d353735-0a29-463c-b772-ec37f451e2e9 2019-05-07 14:25:29,947 WARNING (Thread-71945) [web] ERROR [172.19.33.146] PUT /images/8d353735-0a29-463c-b772-ec37f451e2e9 [403] Requested range out of allowed range [request=0.000439] 2019-05-07 14:25:29,995 WARNING (Thread-71946) [web] ERROR [172.19.33.146] PUT /images/8d353735-0a29-463c-b772-ec37f451e2e9 [403] Requested range out of allowed range [request=0.000374] 2019-05-07 14:25:31,114 INFO (Thread-71947) [tickets] [local] EXTEND timeout=23 ticket=8d353735-0a29-463c-b772-ec37f451e2e9 2019-05-07 14:25:41,252 INFO (Thread-71949) [tickets] [local] EXTEND timeout=23 ticket=8d353735-0a29-463c-b772-ec37f451e2e9 2019-05-07 14:25:51,368 INFO (Thread-71951) [tickets] [local] EXTEND timeout=23 ticket=8d353735-0a29-463c-b772-ec37f451e2e9 2019-05-07 14:26:00,183 WARNING (Thread-71953) [web] ERROR [172.19.33.146] PUT /images/8d353735-0a29-463c-b772-ec37f451e2e9 [403] Requested range out of allowed range [request=0.000317] 2019-05-07 14:26:01,442 INFO (Thread-71954) [tickets] [local] EXTEND timeout=23 ticket=8d353735-0a29-463c-b772-ec37f451e2e9 2019-05-07 14:26:11,586 INFO (Thread-71956) [tickets] [local] EXTEND timeout=23 ticket=8d353735-0a29-463c-b772-ec37f451e2e9 2019-05-07 14:26:21,630 INFO (Thread-71958) [tickets] [local] EXTEND timeout=23 ticket=8d353735-0a29-463c-b772-ec37f451e2e9 2019-05-07 14:26:30,253 WARNING (Thread-71960) [web] ERROR [172.19.33.146] PUT /images/8d353735-0a29-463c-b772-ec37f451e2e9 [403] Requested range out of allowed range [request=0.000488] 2019-05-07 14:26:31,789 INFO (Thread-71961) [tickets] [local] EXTEND timeout=23 ticket=8d353735-0a29-463c-b772-ec37f451e2e9 _______________________________________________ Devel mailing list -- devel@ovirt.org To unsubscribe send an email to devel-leave@ovirt.org Privacy Statement: https://www.ovirt.org/site/privacy-policy/ oVirt Code of Conduct: https://www.ovirt.org/community/about/community-guidelines/ List Archives: https://lists.ovirt.org/archives/list/devel@ovirt.org/message/7Y6RIEBNWT3BYW...
On Wed, May 8, 2019 at 1:51 PM <francisco.garcia@wbsgo.com> wrote:
Description of the problem: I am not able to restore (upload) a VM when its disk (Either Thin provisioning or Thick provisioned) is located in an iSCSI storage and this VM has at least one snapshot larger than 1Gb.
You need to set the disk initial_size to the size of the file you upload. For example, see how upload_disk.py example: https://github.com/oVirt/ovirt-engine-sdk/blob/master/sdk/examples/upload_di... image_size = os.path.getsize(args.filename) disks_service = connection.system_service().disks_service() disk = disks_service.add( disk=types.Disk( name=os.path.basename(args.filename), content_type=image_info["content_type"], description='Uploaded disk', format=new_disk_format, initial_size=image_size, provisioned_size=image_info["virtual-size"], sparse=new_disk_format == types.DiskFormat.COW, storage_domains=[ types.StorageDomain( name=args.sd_name ) ] ) )
I am using REST API through Java SDK. I am using the same upload procedure described here: https://ovirt.org/develop/release-management/features/storage/backup-restore..., of course, making little adjustments to disk format (COW, Sparse.. Depending on the type of the VM to be restored). When I try restore any VM with this conditions, all snapshot disks with a size smaller than 1Gb are uploaded correctly. But when I am uploading a snapshot disk with a size bigger than 1Gb, the following error ocurrs:
The error triggered from Java SDK is: "The server response was 403 in the range request {bytes 1073741824-1207959551/4831838208}" In the host's imageio log: "2019-05-07 14:26:30,253 WARNING (Thread-71960) [web] ERROR [172.19.33.146] PUT /images/8d353735-0a29-463c-b772-ec37f451e2e9 [403] Requested range out of allowed range [request=0.000488]"
For example (real scenario), I have these snapshot disks from a vm: 2.1G DiskSnap_47c4e157-edd8-4bfc-b838-4d57ac8396bd_33b3e74a-cc6b-474a-97cc-960891716110_0.img -> First snap chain -> OK upload 1.1G DiskSnap_47c4e157-edd8-4bfc-b838-4d57ac8396bd_9349e4dd-85b3-4145-8674-bb3f39546020_1.img -> Ok upload 1.1G DiskSnap_47c4e157-edd8-4bfc-b838-4d57ac8396bd_4d10cb58-9f52-4d64-b6e9-ead4bed4c6a6_2.img -> Ok upload 4.6G DiskSnap_47c4e157-edd8-4bfc-b838-4d57ac8396bd_429cbe6d-1148-4a84-861a-56ad6902859e_3.img -> Last snap chain -> Fail upload with previous error [Qemu-img info in attached file: qemu-imgs-iSCSI.txt]
In the restore process, when I create the disk(In this case), I put the following values: Disk: { Name:T_iSCSI_Thin_Disk1_restore, Id:null, Interface:VIRTIO_SCSI, Format:COW, WipeAfterDelete: false, Shareable: false, Sparse: true, Boot:true, Activo:true, Sizes:{ Initial:4294967296, Actual:null, Total:null, Provisioned: 4294967296 } }
And the disk created is: Disk: { Name:T_iSCSI_Thin_Disk1_restore, Id:aac28ff3-abdb-4bf9-beed-c08e7e19b0ba, Image:c8654d05-a18c-47bd-ab0b-f4d746e23efb, Format:COW, WipeAfterDelete: false, Shareable: false, Sparse: true, Sizes:{ Initial:null, Actual:0, Total:0, Provisioned: 4294967296 } }
Therefore, the first upload can finish correctly. However, when I create snapshots with this disk, the snapshot disks have these parameters: DiskSnapshot: { Id: 29dd6a18-c17c-4938-be67-d7af6de713ec, Disk:aac28ff3-abdb-4bf9-beed-c08e7e19b0ba, Snapshot:ceb2f29e-1ae8-441c-a5a7-836308cfeb8d, Sizes:{ Actual:1073741824 Provisioned:4294967296 Total: 0 Initial: null
this allocates 1g, may not be enough for the upload }
} DiskSnapshot: { Id: 69620911-d490-4872-b353-ba29a762ea3e, Disk:aac28ff3-abdb-4bf9-beed-c08e7e19b0ba, Snapshot:c35423d5-ddd6-46f2-a5d7-0080941c3f30, Sizes:{ Actual:1073741824 Provisioned:4294967296 Total: 0 Initial: null } } DiskSnapshot: { Id: c8654d05-a18c-47bd-ab0b-f4d746e23efb, Disk:aac28ff3-abdb-4bf9-beed-c08e7e19b0ba, Snapshot:fc135efd-a67b-4eb3-bfdb-9090aa3b267e, Sizes:{ Actual:4831838208 Provisioned:4294967296 Total: 0 Initial: null } } DiskSnapshot: { Id: e3389916-4e80-4593-a116-3484f295ff7f, Disk:aac28ff3-abdb-4bf9-beed-c08e7e19b0ba, Snapshot:74aff5e7-e1c1-4f51-ae31-ea44caa180ef, Sizes:{ Actual:1073741824 Provisioned:4294967296 Total: 0 Initial: null } }
As we can observe with these values, the actual size of snapshot disks indicates 1Gb, and when I try upload a snapshot disk (Bigger than 1.1Gb), the system doesn't allow to complete the upload, launching the mentionated previous error.
However, using NFS storage, the previous error doesn't ocurr. The system allows me upload disks bigger than 1 Gb, and when the upload finishes, the system refreshs the actualSize to actual value.
For example (real scenario), I have these snapshot disks from a vm: 3.1G DiskSnap_985a02a0-0752-420a-a2fb-a3e89d1ac3bc_3dffb5f4-3f9c-410c-8b97-133922721f9a_0.img -> First snap chain -> OK upload 22M DiskSnap_985a02a0-0752-420a-a2fb-a3e89d1ac3bc_d3307b3c-f930-4faa-90cb-6aedd38bea93_1.img -> Ok upload 31M DiskSnap_985a02a0-0752-420a-a2fb-a3e89d1ac3bc_aa8648dd-d6c0-4d9d-8373-6764e806359f_2.img -> Ok upload 1.3G DiskSnap_985a02a0-0752-420a-a2fb-a3e89d1ac3bc_2cf31d95-3b86-4d97-b39b-672d0066f503_3.img -> Last snap chain -> OK upload [Qemu-img info in attached file: qemu-imgs-NFS.txt]
The restore with NFS storage is the same process but I change the format disk and sparse. With following values:
Disk: { Name:985a02a0-0752-420a-a2fb-a3e89d1ac3bc_restore, Id:null, Interface:VIRTIO_SCSI, Format:RAW, WipeAfterDelete: false, Shareable: false, Sparse: true, Boot:true, Activo:true, Sizes:{ Initial:3221225472, Actual:null, Total:null, Provisioned: 3221225472 } }
And the disk created was: Disk: { Name:985a02a0-0752-420a-a2fb-a3e89d1ac3bc_restore, Id:f2a89f5a-d8ac-4ea4-b17c-591cd86855e9, Image:9c80aead-88de-48f9-be7e-0fde917e7f55, Format:RAW, WipeAfterDelete: false, Shareable: false, Sparse: true, Sizes:{ Initial:null, Actual:0, Total:0, Provisioned: 3221225472 } }
When I create snapshots with this disk, the snapshot disks have these parameters:
DiskSnapshot: { Id: 87c4cb08-502a-4937-bbba-6d455395e990, Disk:f2a89f5a-d8ac-4ea4-b17c-591cd86855e9, Snapshot:dd257190-ef50-4990-a61f-40d84e2b08e9, Sizes:{ Actual:200704 Provisioned:3221225472 Total: 0 Initial: null } } DiskSnapshot: { Id: e7d6f1fb-ab37-45f8-8e23-5964ed194581, Disk:f2a89f5a-d8ac-4ea4-b17c-591cd86855e9, Snapshot:b9f3f3d3-710e-4ce2-b05f-563227f5ec04, Sizes:{ Actual:200704 Provisioned:3221225472 Total: 0 Initial: null } } DiskSnapshot: { Id: 8ef6f502-d00f-4fa5-b407-2cdc0e876045, Disk:f2a89f5a-d8ac-4ea4-b17c-591cd86855e9, Snapshot:274994de-5c2b-4b38-965f-4cadea3e0db3, Sizes:{ Actual:200704 Provisioned:3221225472 Total: 0 Initial: null } } DiskSnapshot: { Id: 9c80aead-88de-48f9-be7e-0fde917e7f55, Disk:f2a89f5a-d8ac-4ea4-b17c-591cd86855e9, Snapshot:f1d7382d-4427-4e21-9544-1b3cd85f23ae, Sizes:{ Actual:0 Provisioned:3221225472 Total: 0 Initial: null } }
As we can observe with these values, the actual size of snapshot disks indicates 196Kb, and when I try upload any snapshot disk (Even more 1.1Gb), the system allows upload all content disk and when it is finalished, it changes actual and total size. When the restore ends, I obtain these values:
Disk: { Name:985a02a0-0752-420a-a2fb-a3e89d1ac3bc_restoreBacula, Id:f2a89f5a-d8ac-4ea4-b17c-591cd86855e9, Image:8ef6f502-d00f-4fa5-b407-2cdc0e876045, Format:COW, WipeAfterDelete: false, Shareable: false, Status: OK, Sparse: true, Sizes:{ Initial:null, Actual:1318850560, Total:4593303552, Provisioned: 3221225472 } }
DiskSnapshot: { Id: 87c4cb08-502a-4937-bbba-6d455395e990, Disk:f2a89f5a-d8ac-4ea4-b17c-591cd86855e9, Snapshot:dd257190-ef50-4990-a61f-40d84e2b08e9, Sizes:{ Actual:22282240 Provisioned:3221225472 Total: 0 Initial: null } } DiskSnapshot: { Id: e7d6f1fb-ab37-45f8-8e23-5964ed194581, Disk:f2a89f5a-d8ac-4ea4-b17c-591cd86855e9, Snapshot:b9f3f3d3-710e-4ce2-b05f-563227f5ec04, Sizes:{ Actual:32374784 Provisioned:3221225472 Total: 0 Initial: null } } DiskSnapshot: { Id: 9c80aead-88de-48f9-be7e-0fde917e7f55, Disk:f2a89f5a-d8ac-4ea4-b17c-591cd86855e9, Snapshot:f1d7382d-4427-4e21-9544-1b3cd85f23ae, Sizes:{ Actual:3219795968 Provisioned:3221225472 Total: 0 Initial: null } }
There is a disk snapshot less since at the end of the restoration I merge the last snapshot. But the values showed changes in actual Size in the DiskSnapshot.
Could you help me, please? Is there a different procedure to the one described in https://ovirt.org/develop/release-management/features/storage/backup-restore... to work with iSCSI storage or is this simply a bug?
How reproducible: Backup and restore VM with snapshots in an iSCSI storage, using Java SDK.
Steps to Reproduce: 1.- Follow steps indicated in https://ovirt.org/develop/release-management/features/storage/backup-restore... to backup a VM allocated iSCSI Storage and containing snapshots bigger than 1.1GB 2.- Try to restore it using the instructions of that documment
Actual results: Upload of images of disk snapshots bigger than 1.1Gb do not suceed ant the restore process fails.
Expected results: Restored vm _______________________________________________ Devel mailing list -- devel@ovirt.org To unsubscribe send an email to devel-leave@ovirt.org Privacy Statement: https://www.ovirt.org/site/privacy-policy/ oVirt Code of Conduct: https://www.ovirt.org/community/about/community-guidelines/ List Archives: https://lists.ovirt.org/archives/list/devel@ovirt.org/message/OZWADBWM2RWJ2D...
Hello Nir, thanks for your response, How ever, I already put initial size when I create the disk. I show the values than the disk contains before create: Disk: { Name:T_iSCSI_Thin_Disk1_restore, Id:null, Interface:VIRTIO_SCSI, Format:COW, WipeAfterDelete: false, Shareable: false, Sparse: true, Boot:true, Active:true, Sizes:{ Initial:4294967296, Actual:null, Total:null, Provisioned: 4294967296 } } After I created the disk(WIth initial Size and provisioned Size, previously showed). I create the snapshots, and these snapshot's images are created with an actual size with 1Gb(I don't known how I can put an actual size to these images). So, I think the problem isn't when I create the disk, else it maybe when I create the snapshot-chain. I don't have a problems when I upload the first image(with size 2Gb) of disk than is the "base" of snapshot-chain, else I have a problem when I upload a disk Snapshot(other image of snapshot-chain) bigger than 1Gb. How ever, the same process with NFS storages works correctly. Thanks for your time :) Fran
On Thu, May 9, 2019 at 11:46 AM <francisco.garcia@wbsgo.com> wrote:
Hello Nir, thanks for your response, How ever, I already put initial size when I create the disk.
The disk is just the base volume... I show the values than the disk contains before create:
Disk: { Name:T_iSCSI_Thin_Disk1_restore, Id:null, Interface:VIRTIO_SCSI, Format:COW, WipeAfterDelete: false, Shareable: false, Sparse: true, Boot:true, Active:true, Sizes:{ Initial:4294967296, Actual:null, Total:null, Provisioned: 4294967296 } } After I created the disk(WIth initial Size and provisioned Size, previously showed). I create the snapshots, and these snapshot's images are created with an actual size with 1Gb(I don't known how I can put an actual size to these images). So, I think the problem isn't when I create the disk, else it maybe when I create the snapshot-chain.
Every layer in the snapshot chain is another disk, and there you can specify the initial size. Copying from: https://github.com/oVirt/ovirt-engine-sdk/blob/master/sdk/examples/upload_di... # Add the new snapshot: snapshot = snapshots_service.add( types.Snapshot( description=description, disk_attachments=[ types.DiskAttachment( disk=types.Disk( id=disk_id, image_id=image_id # sizes for every snapshot should work here ) ) ] ), )
I don't have a problems when I upload the first image(with size 2Gb) of disk than is the "base" of snapshot-chain, else I have a problem when I upload a disk Snapshot(other image of snapshot-chain) bigger than 1Gb.
How ever, the same process with NFS storages works correctly.
Because on NFS there is no need to allocate storage, the file system does this for you. But with block storage you must specify the initial size of the disk. Nir
Hello Nir, Thanks for your response and your time. I tried put the initial_size when I create the snapshot, but it hasn't worked. I sent an email to devel@ovirt.org with 3 files attached. My code.java: List<Snapshot> snapshots = new ArrayList<Snapshot>(); // Se inicia en 1 porque el índice 0 está ActiveVM for (int i = 1; i < snapsFromBackup.size(); ++i) { Snapshot s = snapsFromBackup.get(i); List<DiskAttachment> disksAtt = new ArrayList<DiskAttachment>(); for (DiskConfig cd : configBackup.getDisks(s)) { DiskAttachment dAt = new DiskAttachmentBuilder() .disk(new DiskBuilder() .id(disksRestored.get(cd.getId()).id()) .initialSize(new BigInteger("2147483648")) .build()) // .imageId(newImageId) No le ponemos imageId para que no entre en conflicto con una vm antigua .build(); disksAtt.add(dAt); } // Creamos el snapshot con la misma descripción que tenía. SnapshotBuilder snapB = new SnapshotBuilder() .description(s.description()).diskAttachments(disksAtt).vm(vmRestore); Snapshot newSnap = SnapshotWBS.create(snapB.build()); logger.debug("Snapshot created: { id:" + newSnap.id() + ", description: " + newSnap.description() + "}"); } I'm trying in RHVM 4.2 version. Regards, Fran
The request SDK to RHVM: 2019-05-13 11:48:47,741 [main] DEBUG (LoggingManagedHttpClientConnection.java:133) - http-outgoing-5 >> POST /ovirt-engine/api/vms/46cffed6-2124-4292-919d-12ca2bd0d3c9/snapshots HTTP/1.1 2019-05-13 11:48:47,744 [main] DEBUG (LoggingManagedHttpClientConnection.java:136) - http-outgoing-5 >> Version: 4 2019-05-13 11:48:47,747 [main] DEBUG (LoggingManagedHttpClientConnection.java:136) - http-outgoing-5 >> Content-type: application/xml 2019-05-13 11:48:47,750 [main] DEBUG (LoggingManagedHttpClientConnection.java:136) - http-outgoing-5 >> User-Agent: JavaSDK 2019-05-13 11:48:47,754 [main] DEBUG (LoggingManagedHttpClientConnection.java:136) - http-outgoing-5 >> Accept: application/xml 2019-05-13 11:48:47,759 [main] DEBUG (LoggingManagedHttpClientConnection.java:136) - http-outgoing-5 >> Authorization: Bearer -JDDQLx_lt87l61A6ZhA_jGEV3fR-R_gcEvLj_gqdVeTKMtJGtOfBxwK7qR979fNZC9pILP26Dwry3Jao8RzMg 2019-05-13 11:48:47,763 [main] DEBUG (LoggingManagedHttpClientConnection.java:136) - http-outgoing-5 >> Content-Length: 12335 2019-05-13 11:48:47,766 [main] DEBUG (LoggingManagedHttpClientConnection.java:136) - http-outgoing-5 >> Host: raspfm.dtic.ua.es 2019-05-13 11:48:47,770 [main] DEBUG (LoggingManagedHttpClientConnection.java:136) - http-outgoing-5 >> Connection: Keep-Alive 2019-05-13 11:48:47,775 [main] DEBUG (LoggingManagedHttpClientConnection.java:136) - http-outgoing-5 >> Accept-Encoding: gzip,deflate 2019-05-13 11:48:47,782 [main] DEBUG (Wire.java:73) - http-outgoing-5 >> "POST /ovirt-engine/api/vms/46cffed6-2124-4292-919d-12ca2bd0d3c9/snapshots HTTP/1.1[\r][\n]" 2019-05-13 11:48:47,786 [main] DEBUG (Wire.java:73) - http-outgoing-5 >> "Version: 4[\r][\n]" 2019-05-13 11:48:47,790 [main] DEBUG (Wire.java:73) - http-outgoing-5 >> "Content-type: application/xml[\r][\n]" 2019-05-13 11:48:47,795 [main] DEBUG (Wire.java:73) - http-outgoing-5 >> "User-Agent: JavaSDK[\r][\n]" 2019-05-13 11:48:47,799 [main] DEBUG (Wire.java:73) - http-outgoing-5 >> "Accept: application/xml[\r][\n]" 2019-05-13 11:48:47,804 [main] DEBUG (Wire.java:73) - http-outgoing-5 >> "Authorization: Bearer -JDDQLx_lt87l61A6ZhA_jGEV3fR-R_gcEvLj_gqdVeTKMtJGtOfBxwK7qR979fNZC9pILP26Dwry3Jao8RzMg[\r][\n]" 2019-05-13 11:48:47,810 [main] DEBUG (Wire.java:73) - http-outgoing-5 >> "Content-Length: 12335[\r][\n]" 2019-05-13 11:48:47,816 [main] DEBUG (Wire.java:73) - http-outgoing-5 >> "Host: raspfm.dtic.ua.es[\r][\n]" 2019-05-13 11:48:47,822 [main] DEBUG (Wire.java:73) - http-outgoing-5 >> "Connection: Keep-Alive[\r][\n]" 2019-05-13 11:48:47,829 [main] DEBUG (Wire.java:73) - http-outgoing-5 >> "Accept-Encoding: gzip,deflate[\r][\n]" 2019-05-13 11:48:47,834 [main] DEBUG (Wire.java:73) - http-outgoing-5 >> "[\r][\n]" 2019-05-13 11:48:47,874 [main] DEBUG (Wire.java:73) - http-outgoing-5 >> "<snapshot>[\n]" 2019-05-13 11:48:47,878 [main] DEBUG (Wire.java:73) - http-outgoing-5 >> " <description>Snap1 10M</description>[\n]" 2019-05-13 11:48:47,885 [main] DEBUG (Wire.java:73) - http-outgoing-5 >> " <disk_attachments>[\n]" 2019-05-13 11:48:47,890 [main] DEBUG (Wire.java:73) - http-outgoing-5 >> " <disk_attachment>[\n]" 2019-05-13 11:48:47,894 [main] DEBUG (Wire.java:73) - http-outgoing-5 >> " <disk id="67ff4ada-da2e-43d1-b74d-dbdab57b9210">[\n]" 2019-05-13 11:48:47,898 [main] DEBUG (Wire.java:73) - http-outgoing-5 >> " <initial_size>2147483648</initial_size>[\n]" 2019-05-13 11:48:47,902 [main] DEBUG (Wire.java:73) - http-outgoing-5 >> " </disk>[\n]" 2019-05-13 11:48:47,906 [main] DEBUG (Wire.java:73) - http-outgoing-5 >> " </disk_attachment>[\n]" 2019-05-13 11:48:47,910 [main] DEBUG (Wire.java:73) - http-outgoing-5 >> " </disk_attachments>[\n]" 2019-05-13 11:48:47,915 [main] DEBUG (Wire.java:73) - http-outgoing-5 >> " <vm href="/ovirt-engine/api/vms/46cffed6-2124-4292-919d-12ca2bd0d3c9" id="46cffed6-2124-4292-919d-12ca2bd0d3c9">[\n]" 2019-05-13 11:48:47,920 [main] DEBUG (Wire.java:73) - http-outgoing-5 >> " <bios>[\n]" 2019-05-13 11:48:47,927 [main] DEBUG (Wire.java:73) - http-outgoing-5 >> " <boot_menu>[\n]" 2019-05-13 11:48:47,931 [main] DEBUG (Wire.java:73) - http-outgoing-5 >> " <enabled>false</enabled>[\n]" 2019-05-13 11:48:47,935 [main] DEBUG (Wire.java:73) - http-outgoing-5 >> " </boot_menu>[\n]" 2019-05-13 11:48:47,938 [main] DEBUG (Wire.java:73) - http-outgoing-5 >> " </bios>[\n]" 2019-05-13 11:48:47,946 [main] DEBUG (Wire.java:73) - http-outgoing-5 >> " <cluster href="/ovirt-engine/api/clusters/63b5e3d5-6ce8-47fc-bd20-454d784bb123" id="63b5e3d5-6ce8-47fc-bd20-454d784bb123"></cluster>[\n]" 2019-05-13 11:48:47,949 [main] DEBUG (Wire.java:73) - http-outgoing-5 >> " <console>[\n]" 2019-05-13 11:48:47,953 [main] DEBUG (Wire.java:73) - http-outgoing-5 >> " <enabled>false</enabled>[\n]" 2019-05-13 11:48:47,957 [main] DEBUG (Wire.java:73) - http-outgoing-5 >> " </console>[\n]" 2019-05-13 11:48:47,963 [main] DEBUG (Wire.java:73) - http-outgoing-5 >> " <cpu>[\n]" 2019-05-13 11:48:47,969 [main] DEBUG (Wire.java:73) - http-outgoing-5 >> " <architecture>x86_64</architecture>[\n]" 2019-05-13 11:48:47,975 [main] DEBUG (Wire.java:73) - http-outgoing-5 >> " <topology>[\n]" 2019-05-13 11:48:47,980 [main] DEBUG (Wire.java:73) - http-outgoing-5 >> " <cores>1</cores>[\n]" 2019-05-13 11:48:47,987 [main] DEBUG (Wire.java:73) - http-outgoing-5 >> " <sockets>1</sockets>[\n]" 2019-05-13 11:48:47,993 [main] DEBUG (Wire.java:73) - http-outgoing-5 >> " <threads>1</threads>[\n]" 2019-05-13 11:48:47,999 [main] DEBUG (Wire.java:73) - http-outgoing-5 >> " </topology>[\n]" 2019-05-13 11:48:48,004 [main] DEBUG (Wire.java:73) - http-outgoing-5 >> " </cpu>[\n]" 2019-05-13 11:48:48,014 [main] DEBUG (Wire.java:73) - http-outgoing-5 >> " <cpu_profile href="/ovirt-engine/api/cpuprofiles/7e378f59-6d9c-4989-b4c2-9a1ca1be213a" id="7e378f59-6d9c-4989-b4c2-9a1ca1be213a"></cpu_profile>[\n]" 2019-05-13 11:48:48,019 [main] DEBUG (Wire.java:73) - http-outgoing-5 >> " <cpu_shares>0</cpu_shares>[\n]" 2019-05-13 11:48:48,025 [main] DEBUG (Wire.java:73) - http-outgoing-5 >> " <creation_time>2019-05-13T09:46:54.328Z</creation_time>[\n]" 2019-05-13 11:48:48,030 [main] DEBUG (Wire.java:73) - http-outgoing-5 >> " <delete_protected>false</delete_protected>[\n]" 2019-05-13 11:48:48,033 [main] DEBUG (Wire.java:73) - http-outgoing-5 >> " <display>[\n]" 2019-05-13 11:48:48,038 [main] DEBUG (Wire.java:73) - http-outgoing-5 >> " <allow_override>false</allow_override>[\n]" 2019-05-13 11:48:48,042 [main] DEBUG (Wire.java:73) - http-outgoing-5 >> " <copy_paste_enabled>true</copy_paste_enabled>[\n]" 2019-05-13 11:48:48,046 [main] DEBUG (Wire.java:73) - http-outgoing-5 >> " <disconnect_action>LOCK_SCREEN</disconnect_action>[\n]" 2019-05-13 11:48:48,051 [main] DEBUG (Wire.java:73) - http-outgoing-5 >> " <file_transfer_enabled>true</file_transfer_enabled>[\n]" 2019-05-13 11:48:48,056 [main] DEBUG (Wire.java:73) - http-outgoing-5 >> " <monitors>1</monitors>[\n]" 2019-05-13 11:48:48,060 [main] DEBUG (Wire.java:73) - http-outgoing-5 >> " <single_qxl_pci>false</single_qxl_pci>[\n]" 2019-05-13 11:48:48,064 [main] DEBUG (Wire.java:73) - http-outgoing-5 >> " <smartcard_enabled>false</smartcard_enabled>[\n]" 2019-05-13 11:48:48,067 [main] DEBUG (Wire.java:73) - http-outgoing-5 >> " <type>spice</type>[\n]" 2019-05-13 11:48:48,072 [main] DEBUG (Wire.java:73) - http-outgoing-5 >> " </display>[\n]" 2019-05-13 11:48:48,076 [main] DEBUG (Wire.java:73) - http-outgoing-5 >> " <high_availability>[\n]" 2019-05-13 11:48:48,081 [main] DEBUG (Wire.java:73) - http-outgoing-5 >> " <enabled>false</enabled>[\n]" 2019-05-13 11:48:48,085 [main] DEBUG (Wire.java:73) - http-outgoing-5 >> " <priority>1</priority>[\n]" 2019-05-13 11:48:48,092 [main] DEBUG (Wire.java:73) - http-outgoing-5 >> " </high_availability>[\n]" 2019-05-13 11:48:48,096 [main] DEBUG (Wire.java:73) - http-outgoing-5 >> " <initialization>[\n]" 2019-05-13 11:48:48,102 [main] DEBUG (Wire.java:73) - http-outgoing-5 >> " <configuration>[\n]" 2019-05-13 11:48:48,238 [main] DEBUG (Wire.java:73) - http-outgoing-5 >> " <data><?xml version="1.0" encoding="UTF-8"?><ovf:Envelope xmlns:ovf="http://schemas.dmtf.org/ovf/envelope/1/" xmlns:rasd="http://schemas.dmtf.org/wbem/wscim/1/cim-schema/2/CIM_ResourceAllocationSett..." xmlns:vssd="http://schemas.dmtf.org/wbem/wscim/1/cim-schema/2/CIM_VirtualSystemSettingDa..." xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance" ovf:version="4.1.0.0"><References></References><NetworkSection><Info>List of networks</Info></NetworkSection><Section xsi:type="ovf:DiskSection_Type"><Info>List of Virtual Disks</Info></Section><Content ovf:id="out" xsi:type="ovf:VirtualSystem_Type"><Name>0T_iSCSI_Thin_restore_java200</Name><CreationDate>2019/05/13 09:46:54</CreationDate><ExportDate>2019/05/13 09:46:54</ExportDate><DeleteProtected>false</DeleteProtected><SsoMethod >guest_agent</SsoMethod><IsSmartcardEnabled>false</IsSmartcardEnabled><TimeZone>Etc/GMT</TimeZone><default_boot_sequence>0</default_boot_sequence><Generation>1</Generation><ClusterCompatibilityVersion>4.2</ClusterCompatibilityVersion><VmType>0</VmType><ResumeBehavior>AUTO_RESUME</ResumeBehavior><MinAllocatedMem>1024</MinAllocatedMem><IsStateless>false</IsStateless><IsRunAndPause>false</IsRunAndPause><AutoStartup>false</AutoStartup><Priority>1</Priority><CreatedByUserId>6f79fa00-73af-11e8-8b4b-b827eb7e5e47</CreatedByUserId><MigrationSupport>0</MigrationSupport><IsBootMenuEnabled>false</IsBootMenuEnabled><IsSpiceFileTransferEnabled>true</IsSpiceFileTransferEnabled><IsSpiceCopyPasteEnabled>true</IsSpiceCopyPasteEnabled><AllowConsoleReconnect>false</AllowConsoleReconne ct><ConsoleDisconnectAction>LOCK_SCREEN</ConsoleDisconnectAction><CustomEmulatedMachine></CustomEmulatedMachine><CustomCpuName></CustomCpuName><PredefinedProperties></PredefinedProperties><UserDefinedProperties></UserDefinedProperties><MaxMemorySizeMb>4096</MaxMemorySizeMb><ClusterName>cTest</ClusterName><TemplateId>00000000-0000-0000-0000-000000000000</TemplateId><TemplateName>Blank</TemplateName><IsInitilized>false</IsInitilized><Origin>3</Origin><quota_id>2713c5f1-df7d-4f90-bd8a-280d1fbb69c7</quota_id><DefaultDisplayType>1</DefaultDisplayType><TrustedService>false</TrustedService><OriginalTemplateId>00000000-0000-0000-0000-000000000000</OriginalTemplateId><OriginalTemplateName>Blank</OriginalTemplateName><UseHostCpu>false</UseHostCpu><UseLatestVersion>false</UseLatest Version><StopTime>2019/05/13 09:46:54</StopTime><Section ovf:id="46cffed6-2124-4292-919d-12ca2bd0d3c9" ovf:required="false" xsi:type="ovf:OperatingSystemSection_Type"><Info>Guest Operating System</Info><Description>other</Description></Section><Section xsi:type="ovf:VirtualHardwareSection_Type"><Info>1 CPU, 1024 Memory</Info><System><vssd:VirtualSystemType>ENGINE 4.1.0.0</vssd:VirtualSystemType></System><Item><rasd:Caption>1 virtual cpu</rasd:Caption><rasd:Description>Number of virtual CPU</rasd:Description><rasd:InstanceId>1</rasd:InstanceId><rasd:ResourceType>3</rasd:ResourceType><rasd:num_of_sockets>1</rasd:num_of_sockets><rasd:cpu_per_socket>1</rasd:cpu_per_socket><rasd:threads_per_cpu>1</rasd:threads_per_cpu><rasd:max_num_of_vcpus>16</rasd:max_num_of_vcpus><rasd:VirtualQuantity>1& lt;/rasd:VirtualQuantity></Item><Item><rasd:Caption>1024 MB of memory</rasd:Caption><rasd:Description>Memory Size</rasd:Description><rasd:InstanceId>2</rasd:InstanceId><rasd:ResourceType>4</rasd:ResourceType><rasd:AllocationUnits>MegaBytes</rasd:AllocationUnits><rasd:VirtualQuantity>1024</rasd:VirtualQuantity></Item><Item><rasd:Caption>USB Controller</rasd:Caption><rasd:InstanceId>3</rasd:InstanceId><rasd:ResourceType>23</rasd:ResourceType><rasd:UsbPolicy>DISABLED</rasd:UsbPolicy></Item><Item><rasd:Caption>Graphical Controller</rasd:Caption><rasd:InstanceId>7e48a244-01d9-48c2-897a-04c6fdc1519e</rasd:InstanceId><rasd:ResourceType>20</rasd:ResourceType><rasd:VirtualQuantity>1</rasd:VirtualQuantity><rasd:SinglePciQxl>false</rasd:SinglePciQxl><Type>video</Type> ;<Device>qxl</Device><rasd:Address></rasd:Address><BootOrder>0</BootOrder><IsPlugged>true</IsPlugged><IsReadOnly>false</IsReadOnly><Alias></Alias><SpecParams><vgamem>16384</vgamem><heads>1</heads><vram>8192</vram><ram>65536</ram></SpecParams></Item><Item><rasd:Caption>Graphical Framebuffer</rasd:Caption><rasd:InstanceId>d07f2052-fc55-4feb-a18c-b8f7c2404ab6</rasd:InstanceId><rasd:ResourceType>26</rasd:ResourceType><Type>graphics</Type><Device>spice</Device><rasd:Address></rasd:Address><BootOrder>0</BootOrder><IsPlugged>true</IsPlugged><IsReadOnly>false</IsReadOnly><Alias></Alias></Item><Item><rasd:Caption>CDROM</rasd:Caption><rasd:InstanceId>bf84dde8-813b-4e06-97fb-ae25700d30b5</rasd:Instance Id><rasd:ResourceType>15</rasd:ResourceType><Type>disk</Type><Device>cdrom</Device><rasd:Address></rasd:Address><BootOrder>0</BootOrder><IsPlugged>true</IsPlugged><IsReadOnly>true</IsReadOnly><Alias></Alias><SpecParams><path></path></SpecParams></Item><Item><rasd:ResourceType>0</rasd:ResourceType><rasd:InstanceId>9ed97edc-056a-4ccf-9f19-dafe1b26fb03</rasd:InstanceId><Type>controller</Type><Device>virtio-serial</Device><rasd:Address></rasd:Address><BootOrder>0</BootOrder><IsPlugged>true</IsPlugged><IsReadOnly>false</IsReadOnly><Alias>58ca7b19-0071-00c0-01d6-000000000212</Alias></Item><Item><rasd:ResourceType>0</rasd:ResourceType><rasd:InstanceId>16f1d1e7-26ba-4655-a498-9b5a7114cdfb</rasd:InstanceId><Type> ;balloon</Type><Device>memballoon</Device><rasd:Address></rasd:Address><BootOrder>0</BootOrder><IsPlugged>true</IsPlugged><IsReadOnly>true</IsReadOnly><Alias></Alias><SpecParams><model>virtio</model></SpecParams></Item><Item><rasd:ResourceType>0</rasd:ResourceType><rasd:InstanceId>254e9dcc-f9e6-4df1-b055-c138b0253539</rasd:InstanceId><Type>rng</Type><Device>virtio</Device><rasd:Address></rasd:Address><BootOrder>0</BootOrder><IsPlugged>true</IsPlugged><IsReadOnly>false</IsReadOnly><Alias></Alias><SpecParams><source>urandom</source></SpecParams></Item><Item><rasd:ResourceType>0</rasd:ResourceType><rasd:InstanceId>e4306f89-3539-4404-ae1f-f0f45bcf867d</rasd:InstanceId><Type>controller</Type><Dev ice>virtio-scsi</Device><rasd:Address></rasd:Address><BootOrder>0</BootOrder><IsPlugged>true</IsPlugged><IsReadOnly>false</IsReadOnly><Alias></Alias></Item></Section><Section xsi:type="ovf:SnapshotsSection_Type"><Snapshot ovf:id="ab2f81a7-71ce-4c7f-b8f9-6041dbc86490"><Type>ACTIVE</Type><Description>Active VM</Description><CreationDate>2019/05/13 09:46:54</CreationDate></Snapshot></Section></Content></ovf:Envelope></data>[\n]" 2019-05-13 11:48:48,243 [main] DEBUG (Wire.java:73) - http-outgoing-5 >> " <type>ovf</type>[\n]" 2019-05-13 11:48:48,247 [main] DEBUG (Wire.java:73) - http-outgoing-5 >> " </configuration>[\n]" 2019-05-13 11:48:48,252 [main] DEBUG (Wire.java:73) - http-outgoing-5 >> " </initialization>[\n]" 2019-05-13 11:48:48,256 [main] DEBUG (Wire.java:73) - http-outgoing-5 >> " <io>[\n]" 2019-05-13 11:48:48,260 [main] DEBUG (Wire.java:73) - http-outgoing-5 >> " <threads>0</threads>[\n]" 2019-05-13 11:48:48,264 [main] DEBUG (Wire.java:73) - http-outgoing-5 >> " </io>[\n]" 2019-05-13 11:48:48,270 [main] DEBUG (Wire.java:73) - http-outgoing-5 >> " <large_icon href="/ovirt-engine/api/icons/053932a7-772c-0fca-8e30-5836d41e31d5" id="053932a7-772c-0fca-8e30-5836d41e31d5"></large_icon>[\n]" 2019-05-13 11:48:48,274 [main] DEBUG (Wire.java:73) - http-outgoing-5 >> " <memory>1073741824</memory>[\n]" 2019-05-13 11:48:48,278 [main] DEBUG (Wire.java:73) - http-outgoing-5 >> " <memory_policy>[\n]" 2019-05-13 11:48:48,282 [main] DEBUG (Wire.java:73) - http-outgoing-5 >> " <ballooning>true</ballooning>[\n]" 2019-05-13 11:48:48,286 [main] DEBUG (Wire.java:73) - http-outgoing-5 >> " <guaranteed>1073741824</guaranteed>[\n]" 2019-05-13 11:48:48,291 [main] DEBUG (Wire.java:73) - http-outgoing-5 >> " <max>4294967296</max>[\n]" 2019-05-13 11:48:48,295 [main] DEBUG (Wire.java:73) - http-outgoing-5 >> " </memory_policy>[\n]" 2019-05-13 11:48:48,299 [main] DEBUG (Wire.java:73) - http-outgoing-5 >> " <migration>[\n]" 2019-05-13 11:48:48,303 [main] DEBUG (Wire.java:73) - http-outgoing-5 >> " <auto_converge>inherit</auto_converge>[\n]" 2019-05-13 11:48:48,310 [main] DEBUG (Wire.java:73) - http-outgoing-5 >> " <compressed>inherit</compressed>[\n]" 2019-05-13 11:48:48,316 [main] DEBUG (Wire.java:73) - http-outgoing-5 >> " </migration>[\n]" 2019-05-13 11:48:48,320 [main] DEBUG (Wire.java:73) - http-outgoing-5 >> " <migration_downtime>-1</migration_downtime>[\n]" 2019-05-13 11:48:48,325 [main] DEBUG (Wire.java:73) - http-outgoing-5 >> " <name>0T_iSCSI_Thin_restore_java200</name>[\n]" 2019-05-13 11:48:48,333 [main] DEBUG (Wire.java:73) - http-outgoing-5 >> " <next_run_configuration_exists>false</next_run_configuration_exists>[\n]" 2019-05-13 11:48:48,337 [main] DEBUG (Wire.java:73) - http-outgoing-5 >> " <numa_tune_mode>interleave</numa_tune_mode>[\n]" 2019-05-13 11:48:48,342 [main] DEBUG (Wire.java:73) - http-outgoing-5 >> " <origin>ovirt</origin>[\n]" 2019-05-13 11:48:48,351 [main] DEBUG (Wire.java:73) - http-outgoing-5 >> " <original_template href="/ovirt-engine/api/templates/00000000-0000-0000-0000-000000000000" id="00000000-0000-0000-0000-000000000000"></original_template>[\n]" 2019-05-13 11:48:48,354 [main] DEBUG (Wire.java:73) - http-outgoing-5 >> " <os>[\n]" 2019-05-13 11:48:48,360 [main] DEBUG (Wire.java:73) - http-outgoing-5 >> " <boot>[\n]" 2019-05-13 11:48:48,367 [main] DEBUG (Wire.java:73) - http-outgoing-5 >> " <devices>[\n]" 2019-05-13 11:48:48,371 [main] DEBUG (Wire.java:73) - http-outgoing-5 >> " <device>hd</device>[\n]" 2019-05-13 11:48:48,375 [main] DEBUG (Wire.java:73) - http-outgoing-5 >> " </devices>[\n]" 2019-05-13 11:48:48,378 [main] DEBUG (Wire.java:73) - http-outgoing-5 >> " </boot>[\n]" 2019-05-13 11:48:48,383 [main] DEBUG (Wire.java:73) - http-outgoing-5 >> " <type>other</type>[\n]" 2019-05-13 11:48:48,387 [main] DEBUG (Wire.java:73) - http-outgoing-5 >> " </os>[\n]" 2019-05-13 11:48:48,391 [main] DEBUG (Wire.java:73) - http-outgoing-5 >> " <placement_policy>[\n]" 2019-05-13 11:48:48,395 [main] DEBUG (Wire.java:73) - http-outgoing-5 >> " <affinity>migratable</affinity>[\n]" 2019-05-13 11:48:48,399 [main] DEBUG (Wire.java:73) - http-outgoing-5 >> " </placement_policy>[\n]" 2019-05-13 11:48:48,406 [main] DEBUG (Wire.java:73) - http-outgoing-5 >> " <quota id="2713c5f1-df7d-4f90-bd8a-280d1fbb69c7"></quota>[\n]" 2019-05-13 11:48:48,409 [main] DEBUG (Wire.java:73) - http-outgoing-5 >> " <rng_device>[\n]" 2019-05-13 11:48:48,413 [main] DEBUG (Wire.java:73) - http-outgoing-5 >> " <source>urandom</source>[\n]" 2019-05-13 11:48:48,419 [main] DEBUG (Wire.java:73) - http-outgoing-5 >> " </rng_device>[\n]" 2019-05-13 11:48:48,425 [main] DEBUG (Wire.java:73) - http-outgoing-5 >> " <small_icon href="/ovirt-engine/api/icons/2b0724f3-a357-9ae0-2ae3-49e7d06a9046" id="2b0724f3-a357-9ae0-2ae3-49e7d06a9046"></small_icon>[\n]" 2019-05-13 11:48:48,429 [main] DEBUG (Wire.java:73) - http-outgoing-5 >> " <soundcard_enabled>false</soundcard_enabled>[\n]" 2019-05-13 11:48:48,433 [main] DEBUG (Wire.java:73) - http-outgoing-5 >> " <sso>[\n]" 2019-05-13 11:48:48,437 [main] DEBUG (Wire.java:73) - http-outgoing-5 >> " <methods>[\n]" 2019-05-13 11:48:48,442 [main] DEBUG (Wire.java:73) - http-outgoing-5 >> " <method id="guest_agent"></method>[\n]" 2019-05-13 11:48:48,448 [main] DEBUG (Wire.java:73) - http-outgoing-5 >> " </methods>[\n]" 2019-05-13 11:48:48,454 [main] DEBUG (Wire.java:73) - http-outgoing-5 >> " </sso>[\n]" 2019-05-13 11:48:48,460 [main] DEBUG (Wire.java:73) - http-outgoing-5 >> " <start_paused>false</start_paused>[\n]" 2019-05-13 11:48:48,466 [main] DEBUG (Wire.java:73) - http-outgoing-5 >> " <stateless>false</stateless>[\n]" 2019-05-13 11:48:48,473 [main] DEBUG (Wire.java:73) - http-outgoing-5 >> " <status>down</status>[\n]" 2019-05-13 11:48:48,478 [main] DEBUG (Wire.java:73) - http-outgoing-5 >> " <stop_time>2019-05-13T09:46:54.331Z</stop_time>[\n]" 2019-05-13 11:48:48,483 [main] DEBUG (Wire.java:73) - http-outgoing-5 >> " <storage_error_resume_behaviour>auto_resume</storage_error_resume_behaviour>[\n]" 2019-05-13 11:48:48,489 [main] DEBUG (Wire.java:73) - http-outgoing-5 >> " <template href="/ovirt-engine/api/templates/00000000-0000-0000-0000-000000000000" id="00000000-0000-0000-0000-000000000000"></template>[\n]" 2019-05-13 11:48:48,494 [main] DEBUG (Wire.java:73) - http-outgoing-5 >> " <time_zone>[\n]" 2019-05-13 11:48:48,498 [main] DEBUG (Wire.java:73) - http-outgoing-5 >> " <name>Etc/GMT</name>[\n]" 2019-05-13 11:48:48,505 [main] DEBUG (Wire.java:73) - http-outgoing-5 >> " </time_zone>[\n]" 2019-05-13 11:48:48,510 [main] DEBUG (Wire.java:73) - http-outgoing-5 >> " <type>desktop</type>[\n]" 2019-05-13 11:48:48,513 [main] DEBUG (Wire.java:73) - http-outgoing-5 >> " <usb>[\n]" 2019-05-13 11:48:48,517 [main] DEBUG (Wire.java:73) - http-outgoing-5 >> " <enabled>false</enabled>[\n]" 2019-05-13 11:48:48,522 [main] DEBUG (Wire.java:73) - http-outgoing-5 >> " </usb>[\n]" 2019-05-13 11:48:48,526 [main] DEBUG (Wire.java:73) - http-outgoing-5 >> " <virtio_scsi>[\n]" 2019-05-13 11:48:48,529 [main] DEBUG (Wire.java:73) - http-outgoing-5 >> " <enabled>true</enabled>[\n]" 2019-05-13 11:48:48,533 [main] DEBUG (Wire.java:73) - http-outgoing-5 >> " </virtio_scsi>[\n]" 2019-05-13 11:48:48,538 [main] DEBUG (Wire.java:73) - http-outgoing-5 >> " </vm>[\n]" 2019-05-13 11:48:48,541 [main] DEBUG (Wire.java:87) - http-outgoing-5 >> "</snapshot>" 2019-05-13 11:48:54,660 [main] DEBUG (Wire.java:73) - http-outgoing-5 << "HTTP/1.1 202 Accepted[\r][\n]" 2019-05-13 11:48:54,664 [main] DEBUG (Wire.java:73) - http-outgoing-5 << "Date: Mon, 13 May 2019 09:48:54 GMT[\r][\n]" 2019-05-13 11:48:54,669 [main] DEBUG (Wire.java:73) - http-outgoing-5 << "Server: Apache/2.4.6 (Red Hat Enterprise Linux) OpenSSL/1.0.2k-fips[\r][\n]" 2019-05-13 11:48:54,672 [main] DEBUG (Wire.java:73) - http-outgoing-5 << "Content-Encoding: gzip[\r][\n]" 2019-05-13 11:48:54,676 [main] DEBUG (Wire.java:73) - http-outgoing-5 << "Content-Type: application/xml;charset=UTF-8[\r][\n]" 2019-05-13 11:48:54,679 [main] DEBUG (Wire.java:73) - http-outgoing-5 << "Content-Length: 2904[\r][\n]" 2019-05-13 11:48:54,683 [main] DEBUG (Wire.java:73) - http-outgoing-5 << "Correlation-Id: 1c187591-558a-4d24-9ab6-79efa0e2ca77[\r][\n]" 2019-05-13 11:48:54,687 [main] DEBUG (Wire.java:73) - http-outgoing-5 << "Keep-Alive: timeout=5, max=100[\r][\n]" 2019-05-13 11:48:54,691 [main] DEBUG (Wire.java:73) - http-outgoing-5 << "Connection: Keep-Alive[\r][\n]" 2019-05-13 11:48:54,694 [main] DEBUG (Wire.java:73) - http-outgoing-5 << "[\r][\n]" 20 2019-05-13 11:48:54,699 [main] DEBUG (LoggingManagedHttpClientConnection.java:122) - http-outgoing-5 << HTTP/1.1 202 Accepted 2019-05-13 11:48:54,702 [main] DEBUG (LoggingManagedHttpClientConnection.java:125) - http-outgoing-5 << Date: Mon, 13 May 2019 09:48:54 GMT 2019-05-13 11:48:54,705 [main] DEBUG (LoggingManagedHttpClientConnection.java:125) - http-outgoing-5 << Server: Apache/2.4.6 (Red Hat Enterprise Linux) OpenSSL/1.0.2k-fips 2019-05-13 11:48:54,708 [main] DEBUG (LoggingManagedHttpClientConnection.java:125) - http-outgoing-5 << Content-Encoding: gzip 2019-05-13 11:48:54,711 [main] DEBUG (LoggingManagedHttpClientConnection.java:125) - http-outgoing-5 << Content-Type: application/xml;charset=UTF-8 2019-05-13 11:48:54,714 [main] DEBUG (LoggingManagedHttpClientConnection.java:125) - http-outgoing-5 << Content-Length: 2904 2019-05-13 11:48:54,717 [main] DEBUG (LoggingManagedHttpClientConnection.java:125) - http-outgoing-5 << Correlation-Id: 1c187591-558a-4d24-9ab6-79efa0e2ca77 2019-05-13 11:48:54,722 [main] DEBUG (LoggingManagedHttpClientConnection.java:125) - http-outgoing-5 << Keep-Alive: timeout=5, max=100 2019-05-13 11:48:54,728 [main] DEBUG (LoggingManagedHttpClientConnection.java:125) - http-outgoing-5 << Connection: Keep-Alive 2019-05-13 11:48:54,741 [main] DEBUG (MainClientExec.java:285) - Connection can be kept alive for 5000 MILLISECONDS
Disk: { Name:T_iSCSI_Thin_Disk1_restoreBacula, Id:67ff4ada-da2e-43d1-b74d-dbdab57b9210, Image:79c99658-2af6-4aa1-8e39-fe11c2cc3a03, Format:COW, WipeAfterDelete: false, Shareable: false, Status: OK, Sparse: true, Sizes:{ Initial:null, Actual:1073741824, Total:9126805504, Provisioned: 4294967296 } } Have 4 diskSnapshots: DiskSnapshot: { Id: 2e99af63-0de7-4cee-bd8f-b4dcac33312d, Disk:67ff4ada-da2e-43d1-b74d-dbdab57b9210, Snapshot:ab2f81a7-71ce-4c7f-b8f9-6041dbc86490, Sizes:{ Actual:4831838208 Provisioned:4294967296 Total: 0 Initial: null } } DiskSnapshot: { Id: 7926358c-54e3-4daa-a520-064399009d87, Disk:67ff4ada-da2e-43d1-b74d-dbdab57b9210, Snapshot:f7bb4f26-2e40-402a-9722-d3720795832d, Sizes:{ Actual:1073741824 Provisioned:4294967296 Total: 0 Initial: null } } DiskSnapshot: { Id: 80ebc673-8166-4631-abd3-590b97ede109, Disk:67ff4ada-da2e-43d1-b74d-dbdab57b9210, Snapshot:6815e09a-d2fa-453b-a79f-4aa5958d5c70, Sizes:{ Actual:1073741824 Provisioned:4294967296 Total: 0 Initial: null } } DiskSnapshot: { Id: ce87c2ed-d18a-499a-bcfa-c1735267ec57, Disk:67ff4ada-da2e-43d1-b74d-dbdab57b9210, Snapshot:59084568-5bee-4134-b109-488bfb0dada0, Sizes:{ Actual:1073741824 Provisioned:4294967296 Total: 0 Initial: null } } Sorry for this extend message, but I don't know how attach files here =S
On Thu, May 9, 2019 at 11:46 AM <francisco.garcia@wbsgo.com> wrote:
Hello Nir, thanks for your response, How ever, I already put initial size when I create the disk. I show the values than the disk contains before create: Disk: { Name:T_iSCSI_Thin_Disk1_restore, Id:null, Interface:VIRTIO_SCSI, Format:COW, WipeAfterDelete: false, Shareable: false, Sparse: true, Boot:true, Active:true, Sizes:{ Initial:4294967296, Actual:null, Total:null, Provisioned: 4294967296 } } After I created the disk(WIth initial Size and provisioned Size, previously showed). I create the snapshots, and these snapshot's images are created with an actual size with 1Gb(I don't known how I can put an actual size to these images). So, I think the problem isn't when I create the disk, else it maybe when I create the snapshot-chain.
I don't have a problems when I upload the first image(with size 2Gb) of disk than is the "base" of snapshot-chain, else I have a problem when I upload a disk Snapshot(other image of snapshot-chain) bigger than 1Gb.
How ever, the same process with NFS storages works correctly.
Thanks for your time :)
Daniel, can you check this?
Fran _______________________________________________ Devel mailing list -- devel@ovirt.org To unsubscribe send an email to devel-leave@ovirt.org Privacy Statement: https://www.ovirt.org/site/privacy-policy/ oVirt Code of Conduct: https://www.ovirt.org/community/about/community-guidelines/ List Archives: https://lists.ovirt.org/archives/list/devel@ovirt.org/message/XKOVEHG6FEDN2L...
Hi Nir and Daniel, I am trying this case(Restore with snapshots' size > 1Gb in iSCSI storage) in RHV4.3, and I am getting the same error. I try to create the diskSnapshots with same sizes (with initialSize > 1Gb, when it is needed) and they are created with initialSize = 1Gb. Regards, Fran
On Fri, May 17, 2019 at 1:29 PM <francisco.garcia@wbsgo.com> wrote:
Hi Nir and Daniel,
I am trying this case(Restore with snapshots' size > 1Gb in iSCSI storage) in RHV4.3, and I am getting the same error.
I try to create the diskSnapshots with same sizes (with initialSize > 1Gb, when it is needed) and they are created with initialSize = 1Gb.
Please file ovirt-engine bug for this.
Regards,
Fran _______________________________________________ Devel mailing list -- devel@ovirt.org To unsubscribe send an email to devel-leave@ovirt.org Privacy Statement: https://www.ovirt.org/site/privacy-policy/ oVirt Code of Conduct: https://www.ovirt.org/community/about/community-guidelines/ List Archives: https://lists.ovirt.org/archives/list/devel@ovirt.org/message/HEIWY7AUIKSZE3...
Hello Nir, I attach files in a compress tar.gz(engine.log, vdsm.log and image-io.log, for if they help somethin): https://storagecloud.wbsgo.com/storagecloud/index.php/s/cQ6y9iIQn3PzRfE Sorry for the link, but I don't known attach files in this site =S Thanks for your time Regards, Fran
Hello Nir, I had understood that you want the ovirt-engine log. Sorry. I just created the bug in the bugzilla: https://bugzilla.redhat.com/show_bug.cgi?id=1707707 Thanks for your time, Regards, Fran
participants (2)
-
francisco.garcia＠wbsgo.com -
Nir Soffer