OK, so I have tried to test-restore a file from storage (GCD, but it probably doesn’t matter). Admittedly, I used quite an extreme case of restoring 250GB file out of 800GB storage, but some things don’t quite check out or I have questions about. Here are some log snippets:
2022-06-09 03:50:20.560 INFO RESTORE_START Restoring /mnt/NAS1/Temp to revision 5
2022-06-09 05:17:52.046 INFO DOWNLOAD_PROGRESS Downloaded chunk 1 size 2592726, 0B/s 00:00:00 0.0%
It did take about 1.5 hours in between RESTORE_START until actual chunk download started. So what is happening in that timeframe? There was no significant ingress/egress activity on the router.
Transfer speed/ETA is borked, I think it takes into account this 1.5 hours during which nothing happened. -stats reports 0B/s for downloads which is definitely incorrect (download looks to be about 6-7MB/s according to router stats). It eventually picks up - to 1B/s and 2B/s onwards
2022-06-09 05:22:47.615 INFO DOWNLOAD_PROGRESS Downloaded chunk 334 size 2573734, 0B/s 00:00:00 0.6%
2022-06-09 05:22:48.179 INFO DOWNLOAD_PROGRESS Downloaded chunk 333 size 4196292, 0B/s 00:00:00 0.6%
2022-06-09 05:22:48.808 INFO DOWNLOAD_PROGRESS Downloaded chunk 332 size 16777216, 0B/s 00:00:00 0.6%
2022-06-09 05:22:49.833 INFO DOWNLOAD_PROGRESS Downloaded chunk 335 size 14150015, 1B/s 2943499 days 18:19:57 0.6%
2022-06-09 05:22:50.326 INFO DOWNLOAD_PROGRESS Downloaded chunk 336 size 1230363, 1B/s 2943485 days 12:33:54 0.6%
2022-06-09 05:22:51.172 INFO DOWNLOAD_PROGRESS Downloaded chunk 338 size 3120998, 1B/s 2943449 days 09:37:16 0.6%
…
2022-06-09 05:27:32.474 INFO DOWNLOAD_PROGRESS Downloaded chunk 665 size 1486058, 1B/s 2924490 days 18:00:55 1.2%
2022-06-09 05:27:32.797 INFO DOWNLOAD_PROGRESS Downloaded chunk 666 size 2160463, 1B/s 2924465 days 17:53:12 1.2%
2022-06-09 05:27:33.036 INFO DOWNLOAD_PROGRESS Downloaded chunk 667 size 2186859, 1B/s 2924440 days 10:25:33 1.2%
2022-06-09 05:27:34.351 INFO DOWNLOAD_PROGRESS Downloaded chunk 668 size 6201784, 1B/s 2924368 days 15:42:29 1.2%
2022-06-09 05:27:34.406 INFO DOWNLOAD_PROGRESS Downloaded chunk 671 size 1467061, 2B/s 1462175 days 20:05:44 1.2%
2022-06-09 05:27:35.669 INFO DOWNLOAD_PROGRESS Downloaded chunk 670 size 5379579, 2B/s 1462144 days 16:55:55 1.2%
2022-06-09 05:27:36.815 INFO DOWNLOAD_PROGRESS Downloaded chunk 672 size 5126146, 2B/s 1462115 days 00:58:02 1.2%
…
2022-06-09 05:32:15.184 INFO DOWNLOAD_PROGRESS Downloaded chunk 991 size 1106953, 2B/s 1452634 days 04:42:00 1.9%
2022-06-09 05:32:16.687 INFO DOWNLOAD_PROGRESS Downloaded chunk 990 size 3241003, 2B/s 1452615 days 10:33:38 1.9%
2022-06-09 05:32:16.815 INFO DOWNLOAD_PROGRESS Downloaded chunk 992 size 4504124, 3B/s 968392 days 21:59:31 1.9%
2022-06-09 05:32:16.854 INFO DOWNLOAD_PROGRESS Downloaded chunk 993 size 3543819, 3B/s 968379 days 05:51:38 1.9%