On Sat, Jan 23, 2021 at 12:45 AM Nir Soffer <nirsof@gmail.com> wrote: Here is an example nbdkit output: hw_id = 'd8635601-ea8b-4c5c-a624-41bd72b862d6' datacenter = ost host.id = '9de3098d-89e8-444c-95ed-0fc2ea74e170' disk.id = '6263dab4-e299-43c4-8f10-9a227b9bed01' transfer.id = 'fc27844a-00bc-4035-afd6-83c9a456cae4' using https connection using 4 connections using unix socket '\x00/org/ovirt/imageio' using unix socket '\x00/org/ovirt/imageio' using unix socket '\x00/org/ovirt/imageio' using unix socket '\x00/org/ovirt/imageio' imageio features: flush=True zero=True unix_socket='\x00/org/ovirt/imageio'max_readers=8 max_writers=8 nbdkit: python[1]: debug: python: open returned handle 0x7f17840016d0 nbdkit: python[1]: debug: python: prepare readonly=0 nbdkit: python[1]: debug: python: get_size nbdkit: python[1]: debug: python: can_write nbdkit: python[1]: debug: python: can_zero nbdkit: python[1]: debug: python: can_fast_zero nbdkit: python[1]: debug: python: can_trim nbdkit: python[1]: debug: python: can_fua nbdkit: python[1]: debug: python: can_flush nbdkit: python[1]: debug: python: is_rotational nbdkit: python[1]: debug: python: can_multi_conn nbdkit: python[1]: debug: python: can_cache nbdkit: python[1]: debug: python: can_extents nbdkit: python[1]: debug: newstyle negotiation: flags: export 0xcd nbdkit: python[1]: debug: newstyle negotiation: NBD_OPT_GO: ignoring NBD_INFO_* request 3 (NBD_INFO_BLOCK_SIZE) nbdkit: python[1]: debug: handshake complete, processing requests with 8 threads nbdkit: debug: starting worker thread python.1 nbdkit: debug: starting worker thread python.4 nbdkit: debug: starting worker thread python.6 nbdkit: debug: starting worker thread python.5 nbdkit: debug: starting worker thread python.0 nbdkit: debug: starting worker thread python.3 nbdkit: debug: starting worker thread python.7 nbdkit: debug: starting worker thread python.2 (100.00/100%) nbdkit: python.1: debug: client sent NBD_CMD_DISC, closing connection nbdkit: python.1: debug: exiting worker thread python.1 nbdkit: python.4: debug: exiting worker thread python.4 nbdkit: python.5: debug: exiting worker thread python.5 nbdkit: python.2: debug: exiting worker thread python.2 nbdkit: python.3: debug: exiting worker thread python.3 nbdkit: python.0: debug: exiting worker thread python.0 nbdkit: python.7: debug: exiting worker thread python.7 nbdkit: python.6: debug: exiting worker thread python.6 nbdkit: virtual copying rate: 6518.0 M bits/sec python[1]: debug: python: finalize nbdkit: python[1]: debug: python: close finalizing transfer fc27844a-00bc-4035-afd6-83c9a456cae4 transfer fc27844a-00bc-4035-afd6-83c9a456cae4 finalized in 2.031 seconds Example imageio log: 2021-01-23 00:44:38,239 INFO (Thread-427) [http] OPEN connection=427 client=local 2021-01-23 00:44:38,240 INFO (Thread-427) [tickets] [local] ADD ticket={'dirty': False, 'ops': ['write'], 'size': 6442450944, 'sparse': True, 'transfer_id': 'fc27844a-00bc-4035-afd6-83c9a456cae4', 'uuid': 'ecbcd8a1-eeca-4c1d-97d6-b907a0c3999b', 'timeout': 300, 'url': 'nbd:unix:/run/vdsm/nbd/ecbcd8a1-eeca-4c1d-97d6-b907a0c3999b.sock'} 2021-01-23 00:44:38,240 INFO (Thread-427) [http] CLOSE connection=427 client=local [connection 1 ops, 0.000776 s] [dispatch 1 ops, 0.000259 s] 2021-01-23 00:44:38,375 INFO (Thread-428) [http] OPEN connection=428 client=local 2021-01-23 00:44:38,376 INFO (Thread-428) [http] CLOSE connection=428 client=local [connection 1 ops, 0.000708 s] [dispatch 1 ops, 0.000187 s] 2021-01-23 00:44:39,276 INFO (Thread-429) [http] OPEN connection=429 client=::ffff:192.168.122.23 2021-01-23 00:44:39,276 INFO (Thread-429) [images] [::ffff:192.168.122.23] OPTIONS ticket=ecbcd8a1-eeca-4c1d-97d6-b907a0c3999b 2021-01-23 00:44:39,277 INFO (Thread-429) [backends.nbd] Open backend address='/run/vdsm/nbd/ecbcd8a1-eeca-4c1d-97d6-b907a0c3999b.sock' export_name='' sparse=True max_connections=8 2021-01-23 00:44:39,277 INFO (Thread-429) [backends.nbd] Close backend address='/run/vdsm/nbd/ecbcd8a1-eeca-4c1d-97d6-b907a0c3999b.sock' 2021-01-23 00:44:39,278 INFO (Thread-429) [http] CLOSE connection=429 client=::ffff:192.168.122.23 [connection 1 ops, 0.001338 s] [dispatch 1 ops, 0.000824 s] 2021-01-23 00:44:39,287 INFO (Thread-430) [http] OPEN connection=430 client=local 2021-01-23 00:44:39,288 INFO (Thread-431) [http] OPEN connection=431 client=local 2021-01-23 00:44:39,289 INFO (Thread-432) [http] OPEN connection=432 client=local 2021-01-23 00:44:39,289 INFO (Thread-433) [http] OPEN connection=433 client=local 2021-01-23 00:44:39,290 INFO (Thread-430) [backends.nbd] Open backend address='/run/vdsm/nbd/ecbcd8a1-eeca-4c1d-97d6-b907a0c3999b.sock' export_name='' sparse=True max_connections=8 2021-01-23 00:44:39,290 INFO (Thread-431) [backends.nbd] Open backend address='/run/vdsm/nbd/ecbcd8a1-eeca-4c1d-97d6-b907a0c3999b.sock' export_name='' sparse=True max_connections=8 2021-01-23 00:44:39,291 INFO (Thread-432) [backends.nbd] Open backend address='/run/vdsm/nbd/ecbcd8a1-eeca-4c1d-97d6-b907a0c3999b.sock' export_name='' sparse=True max_connections=8 2021-01-23 00:44:39,294 INFO (Thread-433) [backends.nbd] Open backend address='/run/vdsm/nbd/ecbcd8a1-eeca-4c1d-97d6-b907a0c3999b.sock' export_name='' sparse=True max_connections=8 2021-01-23 00:44:40,399 INFO (Thread-434) [http] OPEN connection=434 client=local 2021-01-23 00:44:40,401 INFO (Thread-434) [http] CLOSE connection=434 client=local [connection 1 ops, 0.001947 s] [dispatch 1 ops, 0.000121 s] 2021-01-23 00:44:43,154 INFO (Thread-432) [images] [local] FLUSH ticket=ecbcd8a1-eeca-4c1d-97d6-b907a0c3999b 2021-01-23 00:44:43,155 INFO (Thread-430) [images] [local] FLUSH ticket=ecbcd8a1-eeca-4c1d-97d6-b907a0c3999b 2021-01-23 00:44:43,156 INFO (Thread-433) [images] [local] FLUSH ticket=ecbcd8a1-eeca-4c1d-97d6-b907a0c3999b 2021-01-23 00:44:43,157 INFO (Thread-431) [images] [local] FLUSH ticket=ecbcd8a1-eeca-4c1d-97d6-b907a0c3999b 2021-01-23 00:44:43,158 INFO (Thread-432) [images] [local] FLUSH ticket=ecbcd8a1-eeca-4c1d-97d6-b907a0c3999b 2021-01-23 00:44:43,159 INFO (Thread-430) [images] [local] FLUSH ticket=ecbcd8a1-eeca-4c1d-97d6-b907a0c3999b 2021-01-23 00:44:43,160 INFO (Thread-433) [images] [local] FLUSH ticket=ecbcd8a1-eeca-4c1d-97d6-b907a0c3999b 2021-01-23 00:44:43,161 INFO (Thread-431) [images] [local] FLUSH ticket=ecbcd8a1-eeca-4c1d-97d6-b907a0c3999b 2021-01-23 00:44:43,164 INFO (Thread-431) [backends.nbd] Close backend address='/run/vdsm/nbd/ecbcd8a1-eeca-4c1d-97d6-b907a0c3999b.sock' 2021-01-23 00:44:43,164 INFO (Thread-431) [http] CLOSE connection=431 client=local [connection 1 ops, 3.875194 s] [dispatch 554 ops, 2.995257 s] [zero 62 ops, 0.139778 s, 793.44 MiB, 5.54 GiB/s] [zero.zero 62 ops, 0.138456 s, 793.44 MiB, 5.60 GiB/s] [write 490 ops, 2.655315 s, 328.25 MiB, 123.62 MiB/s] [write.read 490 ops, 0.570355 s, 328.25 MiB, 575.52 MiB/s] [write.write 490 ops, 2.057818 s, 328.25 MiB, 159.51 MiB/s] [flush 2 ops, 0.000371 s] 2021-01-23 00:44:43,164 INFO (Thread-433) [backends.nbd] Close backend address='/run/vdsm/nbd/ecbcd8a1-eeca-4c1d-97d6-b907a0c3999b.sock' 2021-01-23 00:44:43,165 INFO (Thread-433) [http] CLOSE connection=433 client=local [connection 1 ops, 3.874932 s] [dispatch 554 ops, 3.111739 s] [zero 67 ops, 0.137024 s, 1.20 GiB, 8.75 GiB/s] [zero.zero 67 ops, 0.135575 s, 1.20 GiB, 8.84 GiB/s] [write 485 ops, 2.770474 s, 337.38 MiB, 121.78 MiB/s] [write.read 485 ops, 0.537087 s, 337.38 MiB, 628.16 MiB/s] [write.write 485 ops, 2.209982 s, 337.38 MiB, 152.66 MiB/s] [flush 2 ops, 0.000434 s] 2021-01-23 00:44:43,165 INFO (Thread-430) [backends.nbd] Close backend address='/run/vdsm/nbd/ecbcd8a1-eeca-4c1d-97d6-b907a0c3999b.sock' 2021-01-23 00:44:43,166 INFO (Thread-430) [http] CLOSE connection=430 client=local [connection 1 ops, 3.877590 s] [dispatch 542 ops, 3.105687 s] [zero 78 ops, 0.171958 s, 1.35 GiB, 7.86 GiB/s] [zero.zero 78 ops, 0.170382 s, 1.35 GiB, 7.93 GiB/s] [write 462 ops, 2.754178 s, 338.25 MiB, 122.81 MiB/s] [write.read 462 ops, 0.565677 s, 338.25 MiB, 597.96 MiB/s] [write.write 462 ops, 2.161230 s, 338.25 MiB, 156.51 MiB/s] [flush 2 ops, 0.000213 s] 2021-01-23 00:44:43,166 INFO (Thread-432) [backends.nbd] Close backend address='/run/vdsm/nbd/ecbcd8a1-eeca-4c1d-97d6-b907a0c3999b.sock' 2021-01-23 00:44:43,167 INFO (Thread-432) [http] CLOSE connection=432 client=local [connection 1 ops, 3.876394 s] [dispatch 541 ops, 3.029115 s] [zero 70 ops, 0.139170 s, 1.36 GiB, 9.80 GiB/s] [zero.zero 70 ops, 0.137861 s, 1.36 GiB, 9.90 GiB/s] [write 469 ops, 2.709666 s, 338.56 MiB, 124.95 MiB/s] [write.read 469 ops, 0.539536 s, 338.56 MiB, 627.51 MiB/s] [write.write 469 ops, 2.146016 s, 338.56 MiB, 157.76 MiB/s] [flush 2 ops, 0.000305 s] 2021-01-23 00:44:44,440 INFO (Thread-435) [http] OPEN connection=435 client=local 2021-01-23 00:44:44,440 INFO (Thread-435) [tickets] [local] REMOVE ticket=ecbcd8a1-eeca-4c1d-97d6-b907a0c3999b 2021-01-23 00:44:44,441 INFO (Thread-435) [http] CLOSE connection=435 client=local [connection 1 ops, 0.000628 s] [dispatch 1 ops, 0.000202 s]