"./sync.test -test.v -test.timeout 1h0m0s -remote TestHubic: -verbose -test.run '^TestServerSideMove$'" - Starting (try 2/5)
2022/02/10 05:37:21 DEBUG : Creating backend with remote "TestHubic:rclone-test-vuzivig7guginuf7nidiheg4"
2022/02/10 05:37:21 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2022/02/10 05:37:21 DEBUG : Hubic: Got swift credentials (expiry 2022-02-11 06:23:39 +0100 +0100 in 23h46m17.320079673s)
2022/02/10 05:37:21 DEBUG : Creating backend with remote "/tmp/rclone4243161057"
=== RUN   TestServerSideMove
    run.go:181: Remote "Hubic Swift container rclone-test-vuzivig7guginuf7nidiheg4", Local "Local file system at /tmp/rclone4243161057", Modify Window "1ns"
2022/02/10 05:37:21 DEBUG : Creating backend with remote "TestHubic:rclone-test-fovuped7dukusux3yekasah2"
2022/02/10 05:37:21 DEBUG : Hubic: Got swift credentials (expiry 2022-02-11 06:23:39 +0100 +0100 in 23h46m17.010906852s)
2022/02/10 05:37:22 INFO  : Swift container rclone-test-vuzivig7guginuf7nidiheg4: Container "rclone-test-vuzivig7guginuf7nidiheg4" created
    sync_test.go:1215: Server side move (if possible) Hubic Swift container rclone-test-vuzivig7guginuf7nidiheg4 -> Hubic Swift container rclone-test-fovuped7dukusux3yekasah2
2022/02/10 05:37:27 INFO  : Swift container rclone-test-fovuped7dukusux3yekasah2: Container "rclone-test-fovuped7dukusux3yekasah2" created
2022/02/10 05:37:32 DEBUG : Hubic Swift container rclone-test-fovuped7dukusux3yekasah2: Waiting for checks to finish
2022/02/10 05:37:32 DEBUG : potato3: Sizes differ (src 68 vs dst 60)
2022/02/10 05:37:32 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/02/10 05:37:32 DEBUG : empty space: Unchanged skipping
2022/02/10 05:37:33 INFO  : empty space: Deleted
2022/02/10 05:37:33 DEBUG : Hubic Swift container rclone-test-fovuped7dukusux3yekasah2: Waiting for transfers to finish
2022/02/10 05:37:34 DEBUG : potato2: md5 = d6548b156ea68a4e003e786df99eee76 OK
2022/02/10 05:37:34 INFO  : potato2: Copied (server-side copy)
2022/02/10 05:37:34 DEBUG : potato3: md5 = b1a53993579b830006f2e1610415c32c OK
2022/02/10 05:37:34 INFO  : potato3: Copied (server-side copy)
2022/02/10 05:37:35 INFO  : potato2: Deleted
2022/02/10 05:37:35 INFO  : potato3: Deleted
2022/02/10 05:37:36 DEBUG : Creating backend with remote "TestHubic:rclone-test-nivagic1leyejac2dubayuv2"
2022/02/10 05:37:36 DEBUG : Hubic: Got swift credentials (expiry 2022-02-11 06:23:39 +0100 +0100 in 23h46m2.734238712s)
2022/02/10 05:37:36 DEBUG : Hubic Swift container rclone-test-nivagic1leyejac2dubayuv2: Waiting for checks to finish
2022/02/10 05:37:36 DEBUG : Hubic Swift container rclone-test-nivagic1leyejac2dubayuv2: Waiting for transfers to finish
2022/02/10 05:37:36 INFO  : Swift container rclone-test-nivagic1leyejac2dubayuv2: Container "rclone-test-nivagic1leyejac2dubayuv2" created
2022/02/10 05:37:38 DEBUG : empty space: md5 = 336d5ebc5436534e61d16e63ddfca327 OK
2022/02/10 05:37:38 INFO  : empty space: Copied (server-side copy)
2022/02/10 05:37:38 DEBUG : potato2: md5 = d6548b156ea68a4e003e786df99eee76 OK
2022/02/10 05:37:38 INFO  : potato2: Copied (server-side copy)
2022/02/10 05:37:38 DEBUG : potato3: md5 = b1a53993579b830006f2e1610415c32c OK
2022/02/10 05:37:38 INFO  : potato3: Copied (server-side copy)
2022/02/10 05:37:39 INFO  : empty space: Deleted
2022/02/10 05:37:39 INFO  : potato2: Deleted
2022/02/10 05:37:39 INFO  : potato3: Deleted
    fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3
    fstest.go:293: Sleeping for 6s just to make sure
2022/02/10 05:37:48 DEBUG : Hubic Swift container rclone-test-nivagic1leyejac2dubayuv2: Purge remote
2022/02/10 05:37:48 DEBUG : Waiting for deletions to finish
2022/02/10 05:37:49 INFO  : empty space: Deleted
2022/02/10 05:37:49 INFO  : potato3: Deleted
2022/02/10 05:37:49 INFO  : potato2: Deleted
2022/02/10 05:37:49 DEBUG : pacer: low level retry 1/10 (error Container Not Empty)
2022/02/10 05:37:49 DEBUG : pacer: Rate limited, increasing sleep to 10ms
2022/02/10 05:37:49 DEBUG : pacer: low level retry 2/10 (error Container Not Empty)
2022/02/10 05:37:49 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2022/02/10 05:37:50 DEBUG : pacer: low level retry 3/10 (error Container Not Empty)
2022/02/10 05:37:50 DEBUG : pacer: Rate limited, increasing sleep to 40ms
2022/02/10 05:37:50 DEBUG : pacer: low level retry 4/10 (error Container Not Empty)
2022/02/10 05:37:50 DEBUG : pacer: Rate limited, increasing sleep to 80ms
2022/02/10 05:37:50 DEBUG : pacer: low level retry 5/10 (error Container Not Empty)
2022/02/10 05:37:50 DEBUG : pacer: Rate limited, increasing sleep to 160ms
2022/02/10 05:37:50 DEBUG : pacer: low level retry 6/10 (error Container Not Empty)
2022/02/10 05:37:50 DEBUG : pacer: Rate limited, increasing sleep to 320ms
2022/02/10 05:37:50 DEBUG : pacer: low level retry 7/10 (error Container Not Empty)
2022/02/10 05:37:50 DEBUG : pacer: Rate limited, increasing sleep to 640ms
2022/02/10 05:37:50 DEBUG : pacer: low level retry 8/10 (error Container Not Empty)
2022/02/10 05:37:50 DEBUG : pacer: Rate limited, increasing sleep to 1.28s
2022/02/10 05:37:51 DEBUG : pacer: Reducing sleep to 960ms
2022/02/10 05:37:51 purge failed: Container Not Found
2022/02/10 05:37:51 DEBUG : Hubic Swift container rclone-test-fovuped7dukusux3yekasah2: Purge remote
2022/02/10 05:37:51 DEBUG : Waiting for deletions to finish
2022/02/10 05:37:51 INFO  : Swift container rclone-test-fovuped7dukusux3yekasah2: Container "rclone-test-fovuped7dukusux3yekasah2" removed
--- PASS: TestServerSideMove (30.25s)
PASS
2022/02/10 05:37:51 DEBUG : Hubic Swift container rclone-test-vuzivig7guginuf7nidiheg4: Purge remote
2022/02/10 05:37:51 DEBUG : Waiting for deletions to finish
2022/02/10 05:37:52 INFO  : Swift container rclone-test-vuzivig7guginuf7nidiheg4: Container "rclone-test-vuzivig7guginuf7nidiheg4" removed
"./sync.test -test.v -test.timeout 1h0m0s -remote TestHubic: -verbose -test.run '^TestServerSideMove$'" - Finished OK in 30.645849722s (try 2/5)