"./sync.test -test.v -test.timeout 1h0m0s -remote TestHubic: -verbose -test.run '^(TestServerSideMove|TestServerSideMoveDeleteEmptySourceDirs|TestSyncBackupDir|TestSyncBackupDirWithSuffix|TestSyncCopyDest)$'" - Starting (try 2/5)
2020/07/24 09:53:54 DEBUG : Hubic: Got swift credentials (expiry 2020-07-25 05:00:57 +0200 +0200 in 17h7m2.193018232s)
=== RUN   TestServerSideMove
2020/07/24 09:53:56 DEBUG : Hubic: Got swift credentials (expiry 2020-07-25 05:00:57 +0200 +0200 in 17h7m0.187816779s)
2020/07/24 09:53:56 INFO  : Swift container rclone-test-qobevup3higewov1puhuroc7: Container "rclone-test-qobevup3higewov1puhuroc7" created
2020/07/24 09:54:01 INFO  : Swift container rclone-test-minakox9jeditup3huzixel3: Container "rclone-test-minakox9jeditup3huzixel3" created
2020/07/24 09:54:05 DEBUG : potato3: Sizes differ (src 68 vs dst 60)
2020/07/24 09:54:05 DEBUG : Hubic Swift container rclone-test-minakox9jeditup3huzixel3: Waiting for checks to finish
2020/07/24 09:54:05 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns)
2020/07/24 09:54:05 DEBUG : empty space: Unchanged skipping
2020/07/24 09:54:06 INFO  : empty space: Deleted
2020/07/24 09:54:06 DEBUG : Hubic Swift container rclone-test-minakox9jeditup3huzixel3: Waiting for transfers to finish
2020/07/24 09:54:07 DEBUG : potato2: MD5 = d6548b156ea68a4e003e786df99eee76 OK
2020/07/24 09:54:07 INFO  : potato2: Copied (server side copy)
2020/07/24 09:54:07 DEBUG : potato3: MD5 = b1a53993579b830006f2e1610415c32c OK
2020/07/24 09:54:07 INFO  : potato3: Copied (server side copy)
2020/07/24 09:54:08 INFO  : potato2: Deleted
2020/07/24 09:54:08 INFO  : potato3: Deleted
2020/07/24 09:54:15 DEBUG : Hubic: Got swift credentials (expiry 2020-07-25 05:00:57 +0200 +0200 in 17h6m41.497070457s)
2020/07/24 09:54:16 DEBUG : Hubic Swift container rclone-test-hamuhar2puniric3hijozig7: Waiting for checks to finish
2020/07/24 09:54:16 DEBUG : Hubic Swift container rclone-test-hamuhar2puniric3hijozig7: Waiting for transfers to finish
2020/07/24 09:54:16 INFO  : Swift container rclone-test-hamuhar2puniric3hijozig7: Container "rclone-test-hamuhar2puniric3hijozig7" created
2020/07/24 09:54:17 DEBUG : potato2: MD5 = d6548b156ea68a4e003e786df99eee76 OK
2020/07/24 09:54:17 INFO  : potato2: Copied (server side copy)
2020/07/24 09:54:18 INFO  : potato2: Deleted
2020/07/24 09:54:18 DEBUG : potato3: MD5 = b1a53993579b830006f2e1610415c32c OK
2020/07/24 09:54:18 INFO  : potato3: Copied (server side copy)
2020/07/24 09:54:18 DEBUG : empty space: MD5 = 336d5ebc5436534e61d16e63ddfca327 OK
2020/07/24 09:54:18 INFO  : empty space: Copied (server side copy)
2020/07/24 09:54:19 INFO  : potato3: Deleted
2020/07/24 09:54:19 INFO  : empty space: Deleted
2020/07/24 09:54:20 DEBUG : Hubic Swift container rclone-test-hamuhar2puniric3hijozig7: Purge remote
2020/07/24 09:54:20 DEBUG : Waiting for deletions to finish
2020/07/24 09:54:21 INFO  : potato2: Deleted
2020/07/24 09:54:21 INFO  : potato3: Deleted
2020/07/24 09:54:21 INFO  : empty space: Deleted
2020/07/24 09:54:21 INFO  : Swift container rclone-test-hamuhar2puniric3hijozig7: Container "rclone-test-hamuhar2puniric3hijozig7" removed
2020/07/24 09:54:21 DEBUG : Hubic Swift container rclone-test-minakox9jeditup3huzixel3: Purge remote
2020/07/24 09:54:21 DEBUG : Waiting for deletions to finish
2020/07/24 09:54:22 INFO  : Swift container rclone-test-minakox9jeditup3huzixel3: Container "rclone-test-minakox9jeditup3huzixel3" removed
--- PASS: TestServerSideMove (27.48s)
    run.go:176: Remote "Hubic Swift container rclone-test-qobevup3higewov1puhuroc7", Local "Local file system at /tmp/rclone910261613", Modify Window "1ns"
    sync_test.go:1191: Server side move (if possible) Hubic Swift container rclone-test-qobevup3higewov1puhuroc7 -> Hubic Swift container rclone-test-minakox9jeditup3huzixel3
    fstest.go:295: Sleeping for 1s for list eventual consistency: 1/3
    fstest.go:289: Sleeping for 6s just to make sure
=== RUN   TestServerSideMoveDeleteEmptySourceDirs
2020/07/24 09:54:22 DEBUG : Hubic: Got swift credentials (expiry 2020-07-25 05:00:57 +0200 +0200 in 17h6m34.619018844s)
2020/07/24 09:54:26 DEBUG : tomatoDir: Making directory
2020/07/24 09:54:27 INFO  : Swift container rclone-test-xonewup8yeqereh7jejutuj4: Container "rclone-test-xonewup8yeqereh7jejutuj4" created
2020/07/24 09:54:30 DEBUG : potato3: Sizes differ (src 68 vs dst 60)
2020/07/24 09:54:30 DEBUG : Hubic Swift container rclone-test-xonewup8yeqereh7jejutuj4: Waiting for checks to finish
2020/07/24 09:54:30 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns)
2020/07/24 09:54:30 DEBUG : empty space: Unchanged skipping
2020/07/24 09:54:31 INFO  : empty space: Deleted
2020/07/24 09:54:31 DEBUG : Hubic Swift container rclone-test-xonewup8yeqereh7jejutuj4: Waiting for transfers to finish
2020/07/24 09:54:32 DEBUG : potato2: MD5 = d6548b156ea68a4e003e786df99eee76 OK
2020/07/24 09:54:32 INFO  : potato2: Copied (server side copy)
2020/07/24 09:54:33 INFO  : potato2: Deleted
2020/07/24 09:54:33 DEBUG : potato3: MD5 = b1a53993579b830006f2e1610415c32c OK
2020/07/24 09:54:33 INFO  : potato3: Copied (server side copy)
2020/07/24 09:54:34 INFO  : potato3: Deleted
2020/07/24 09:54:34 DEBUG : Hubic: Got swift credentials (expiry 2020-07-25 05:00:57 +0200 +0200 in 17h6m22.381572232s)
2020/07/24 09:54:34 DEBUG : tomatoDir: Making directory
2020/07/24 09:54:35 DEBUG : Hubic Swift container rclone-test-tezukon9tugixag5humizig4: Waiting for checks to finish
2020/07/24 09:54:35 DEBUG : Hubic Swift container rclone-test-tezukon9tugixag5humizig4: Waiting for transfers to finish
2020/07/24 09:54:35 INFO  : Swift container rclone-test-tezukon9tugixag5humizig4: Container "rclone-test-tezukon9tugixag5humizig4" created
2020/07/24 09:54:36 DEBUG : empty space: MD5 = 336d5ebc5436534e61d16e63ddfca327 OK
2020/07/24 09:54:36 INFO  : empty space: Copied (server side copy)
2020/07/24 09:54:36 DEBUG : potato3: MD5 = b1a53993579b830006f2e1610415c32c OK
2020/07/24 09:54:36 INFO  : potato3: Copied (server side copy)
2020/07/24 09:54:37 INFO  : empty space: Deleted
2020/07/24 09:54:37 INFO  : potato3: Deleted
2020/07/24 09:54:49 DEBUG : potato2: MD5 = d6548b156ea68a4e003e786df99eee76 OK
2020/07/24 09:54:49 INFO  : potato2: Copied (server side copy)
2020/07/24 09:54:50 INFO  : potato2: Deleted
2020/07/24 09:54:50 DEBUG : Hubic Swift container rclone-test-tezukon9tugixag5humizig4: Purge remote
2020/07/24 09:54:50 DEBUG : Waiting for deletions to finish
2020/07/24 09:54:52 INFO  : potato3: Deleted
2020/07/24 09:54:52 INFO  : empty space: Deleted
2020/07/24 09:54:52 INFO  : potato2: Deleted
2020/07/24 09:54:52 INFO  : Swift container rclone-test-tezukon9tugixag5humizig4: Container "rclone-test-tezukon9tugixag5humizig4" removed
2020/07/24 09:54:52 DEBUG : Hubic Swift container rclone-test-xonewup8yeqereh7jejutuj4: Purge remote
2020/07/24 09:54:52 DEBUG : Waiting for deletions to finish
2020/07/24 09:54:52 INFO  : Swift container rclone-test-xonewup8yeqereh7jejutuj4: Container "rclone-test-xonewup8yeqereh7jejutuj4" removed
--- PASS: TestServerSideMoveDeleteEmptySourceDirs (30.62s)
    run.go:176: Remote "Hubic Swift container rclone-test-qobevup3higewov1puhuroc7", Local "Local file system at /tmp/rclone910261613", Modify Window "1ns"
    sync_test.go:1191: Server side move (if possible) Hubic Swift container rclone-test-qobevup3higewov1puhuroc7 -> Hubic Swift container rclone-test-xonewup8yeqereh7jejutuj4
=== RUN   TestSyncCopyDest
2020/07/24 09:54:52 DEBUG : Hubic: Got swift credentials (expiry 2020-07-25 05:00:57 +0200 +0200 in 17h6m4.003460331s)
2020/07/24 09:54:53 DEBUG : Hubic: Got swift credentials (expiry 2020-07-25 05:00:57 +0200 +0200 in 17h6m3.260017538s)
2020/07/24 09:54:55 DEBUG : Hubic Swift container rclone-test-qobevup3higewov1puhuroc7 path dst: Waiting for checks to finish
2020/07/24 09:54:55 DEBUG : Hubic Swift container rclone-test-qobevup3higewov1puhuroc7 path dst: Waiting for transfers to finish
2020/07/24 09:54:56 INFO  : one: Copied (new)
2020/07/24 09:54:56 DEBUG : Waiting for deletions to finish
2020/07/24 09:54:56 DEBUG : Hubic Swift container rclone-test-qobevup3higewov1puhuroc7 path dst: Waiting for checks to finish
2020/07/24 09:54:57 DEBUG : one: Sizes differ (src 5 vs dst 3)
2020/07/24 09:54:57 DEBUG : Hubic Swift container rclone-test-qobevup3higewov1puhuroc7 path dst: Waiting for transfers to finish
2020/07/24 09:54:59 INFO  : one: Copied (replaced existing)
2020/07/24 09:54:59 DEBUG : Waiting for deletions to finish
2020/07/24 09:55:02 DEBUG : Hubic: Got swift credentials (expiry 2020-07-25 05:00:57 +0200 +0200 in 17h5m54.9816903s)
2020/07/24 09:55:02 DEBUG : Hubic Swift container rclone-test-qobevup3higewov1puhuroc7 path dst: Waiting for checks to finish
2020/07/24 09:55:03 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns)
2020/07/24 09:55:03 DEBUG : one: Sizes differ (src 5 vs dst 3)
2020/07/24 09:55:03 INFO  : Swift container rclone-test-qobevup3higewov1puhuroc7 path BackupDir: Container "rclone-test-qobevup3higewov1puhuroc7" created
2020/07/24 09:55:05 DEBUG : one: MD5 = f97c5d29941bfb1b2fdab0874906ab82 OK
2020/07/24 09:55:05 INFO  : one: Copied (server side copy)
2020/07/24 09:55:06 INFO  : one: Deleted
2020/07/24 09:55:08 DEBUG : one: MD5 = 07912d142f5d63ee918b34796b5a2432 OK
2020/07/24 09:55:08 INFO  : one: Copied (server side copy)
2020/07/24 09:55:08 DEBUG : one: Destination found in --copy-dest, using server side copy
2020/07/24 09:55:08 DEBUG : Hubic Swift container rclone-test-qobevup3higewov1puhuroc7 path dst: Waiting for transfers to finish
2020/07/24 09:55:08 DEBUG : Waiting for deletions to finish
2020/07/24 09:55:09 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns)
2020/07/24 09:55:11 DEBUG : two: MD5 = b8a9f715dbb64fd5c56e7783c6820a61 OK
2020/07/24 09:55:11 INFO  : two: Copied (server side copy)
2020/07/24 09:55:11 DEBUG : two: Destination found in --copy-dest, using server side copy
2020/07/24 09:55:11 DEBUG : Hubic Swift container rclone-test-qobevup3higewov1puhuroc7 path dst: Waiting for checks to finish
2020/07/24 09:55:11 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns)
2020/07/24 09:55:11 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns)
2020/07/24 09:55:11 DEBUG : one: Unchanged skipping
2020/07/24 09:55:11 DEBUG : Hubic Swift container rclone-test-qobevup3higewov1puhuroc7 path dst: Waiting for transfers to finish
2020/07/24 09:55:11 DEBUG : Waiting for deletions to finish
2020/07/24 09:55:11 DEBUG : Hubic Swift container rclone-test-qobevup3higewov1puhuroc7 path dst: Waiting for checks to finish
2020/07/24 09:55:11 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns)
2020/07/24 09:55:11 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns)
2020/07/24 09:55:11 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns)
2020/07/24 09:55:11 DEBUG : one: Unchanged skipping
2020/07/24 09:55:11 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns)
2020/07/24 09:55:11 DEBUG : two: Unchanged skipping
2020/07/24 09:55:11 DEBUG : Hubic Swift container rclone-test-qobevup3higewov1puhuroc7 path dst: Waiting for transfers to finish
2020/07/24 09:55:11 DEBUG : Waiting for deletions to finish
2020/07/24 09:55:11 INFO  : There was nothing to transfer
2020/07/24 09:55:14 DEBUG : three: Sizes differ (src 7 vs dst 5)
2020/07/24 09:55:14 DEBUG : three: Destination not found in --copy-dest
2020/07/24 09:55:14 DEBUG : Hubic Swift container rclone-test-qobevup3higewov1puhuroc7 path dst: Waiting for checks to finish
2020/07/24 09:55:14 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns)
2020/07/24 09:55:14 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns)
2020/07/24 09:55:14 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns)
2020/07/24 09:55:14 DEBUG : one: Unchanged skipping
2020/07/24 09:55:14 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns)
2020/07/24 09:55:14 DEBUG : two: Unchanged skipping
2020/07/24 09:55:14 DEBUG : Hubic Swift container rclone-test-qobevup3higewov1puhuroc7 path dst: Waiting for transfers to finish
2020/07/24 09:55:15 INFO  : three: Copied (new)
2020/07/24 09:55:15 DEBUG : Waiting for deletions to finish
--- PASS: TestSyncCopyDest (31.86s)
    run.go:176: Remote "Hubic Swift container rclone-test-qobevup3higewov1puhuroc7", Local "Local file system at /tmp/rclone910261613", Modify Window "1ns"
=== RUN   TestSyncBackupDir
2020/07/24 09:55:30 DEBUG : Hubic: Got swift credentials (expiry 2020-07-25 05:00:57 +0200 +0200 in 17h5m26.534182306s)
2020/07/24 09:55:31 DEBUG : Hubic: Got swift credentials (expiry 2020-07-25 05:00:57 +0200 +0200 in 17h5m25.895484096s)
2020/07/24 09:55:31 DEBUG : Hubic Swift container rclone-test-qobevup3higewov1puhuroc7 path dst: Waiting for checks to finish
2020/07/24 09:55:31 DEBUG : one: Sizes differ (src 4 vs dst 3)
2020/07/24 09:55:31 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns)
2020/07/24 09:55:31 DEBUG : two: Unchanged skipping
2020/07/24 09:55:32 INFO  : Swift container rclone-test-qobevup3higewov1puhuroc7 path backup: Container "rclone-test-qobevup3higewov1puhuroc7" created
2020/07/24 09:55:34 DEBUG : one: MD5 = f97c5d29941bfb1b2fdab0874906ab82 OK
2020/07/24 09:55:34 INFO  : one: Copied (server side copy)
2020/07/24 09:55:35 INFO  : one: Deleted
2020/07/24 09:55:35 DEBUG : Hubic Swift container rclone-test-qobevup3higewov1puhuroc7 path dst: Waiting for transfers to finish
2020/07/24 09:55:36 INFO  : one: Copied (new)
2020/07/24 09:55:36 DEBUG : Waiting for deletions to finish
2020/07/24 09:55:39 DEBUG : three.txt: MD5 = 35d6d33467aae9a2e3dccb4b6b027878 OK
2020/07/24 09:55:39 INFO  : three.txt: Copied (server side copy)
2020/07/24 09:55:40 INFO  : three.txt: Deleted
2020/07/24 09:55:40 INFO  : three.txt: Moved into backup dir
2020/07/24 09:55:42 DEBUG : one: Sizes differ (src 5 vs dst 4)
2020/07/24 09:55:42 DEBUG : Hubic Swift container rclone-test-qobevup3higewov1puhuroc7 path dst: Waiting for checks to finish
2020/07/24 09:55:42 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns)
2020/07/24 09:55:42 DEBUG : two: Unchanged skipping
2020/07/24 09:55:44 DEBUG : one: MD5 = c7957179c41f69d44f217a108c7915d8 OK
2020/07/24 09:55:44 INFO  : one: Copied (server side copy)
2020/07/24 09:55:45 INFO  : one: Deleted
2020/07/24 09:55:45 DEBUG : Hubic Swift container rclone-test-qobevup3higewov1puhuroc7 path dst: Waiting for transfers to finish
2020/07/24 09:55:47 INFO  : one: Copied (new)
2020/07/24 09:55:47 DEBUG : Waiting for deletions to finish
2020/07/24 09:55:48 DEBUG : three.txt: MD5 = 91341eed84691a83caea73aa785736d5 OK
2020/07/24 09:55:48 INFO  : three.txt: Copied (server side copy)
2020/07/24 09:55:49 INFO  : three.txt: Deleted
2020/07/24 09:55:49 INFO  : three.txt: Moved into backup dir
--- PASS: TestSyncBackupDir (30.38s)
    run.go:176: Remote "Hubic Swift container rclone-test-qobevup3higewov1puhuroc7", Local "Local file system at /tmp/rclone910261613", Modify Window "1ns"
=== RUN   TestSyncBackupDirWithSuffix
2020/07/24 09:56:00 DEBUG : Hubic: Got swift credentials (expiry 2020-07-25 05:00:57 +0200 +0200 in 17h4m56.984354273s)
2020/07/24 09:56:00 DEBUG : Hubic: Got swift credentials (expiry 2020-07-25 05:00:57 +0200 +0200 in 17h4m56.348128972s)
2020/07/24 09:56:01 DEBUG : one: Sizes differ (src 4 vs dst 3)
2020/07/24 09:56:01 DEBUG : Hubic Swift container rclone-test-qobevup3higewov1puhuroc7 path dst: Waiting for checks to finish
2020/07/24 09:56:01 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns)
2020/07/24 09:56:01 DEBUG : two: Unchanged skipping
2020/07/24 09:56:01 INFO  : Swift container rclone-test-qobevup3higewov1puhuroc7 path backup: Container "rclone-test-qobevup3higewov1puhuroc7" created
2020/07/24 09:56:04 DEBUG : one: MD5 = f97c5d29941bfb1b2fdab0874906ab82 OK
2020/07/24 09:56:04 INFO  : one: Copied (server side copy)
2020/07/24 09:56:04 INFO  : one: Deleted
2020/07/24 09:56:04 DEBUG : Hubic Swift container rclone-test-qobevup3higewov1puhuroc7 path dst: Waiting for transfers to finish
2020/07/24 09:56:06 INFO  : one: Copied (new)
2020/07/24 09:56:06 DEBUG : Waiting for deletions to finish
2020/07/24 09:56:08 DEBUG : three.txt: MD5 = 35d6d33467aae9a2e3dccb4b6b027878 OK
2020/07/24 09:56:08 INFO  : three.txt: Copied (server side copy)
2020/07/24 09:56:09 INFO  : three.txt: Deleted
2020/07/24 09:56:09 INFO  : three.txt: Moved into backup dir
2020/07/24 09:56:11 DEBUG : Hubic Swift container rclone-test-qobevup3higewov1puhuroc7 path dst: Waiting for checks to finish
2020/07/24 09:56:11 DEBUG : one: Sizes differ (src 5 vs dst 4)
2020/07/24 09:56:11 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns)
2020/07/24 09:56:11 DEBUG : two: Unchanged skipping
2020/07/24 09:56:13 DEBUG : one: MD5 = c7957179c41f69d44f217a108c7915d8 OK
2020/07/24 09:56:13 INFO  : one: Copied (server side copy)
2020/07/24 09:56:14 INFO  : one: Deleted
2020/07/24 09:56:14 DEBUG : Hubic Swift container rclone-test-qobevup3higewov1puhuroc7 path dst: Waiting for transfers to finish
2020/07/24 09:56:15 INFO  : one: Copied (new)
2020/07/24 09:56:15 DEBUG : Waiting for deletions to finish
2020/07/24 09:56:17 DEBUG : three.txt: MD5 = 91341eed84691a83caea73aa785736d5 OK
2020/07/24 09:56:17 INFO  : three.txt: Copied (server side copy)
2020/07/24 09:56:18 INFO  : three.txt: Deleted
2020/07/24 09:56:18 INFO  : three.txt: Moved into backup dir
--- PASS: TestSyncBackupDirWithSuffix (28.44s)
    run.go:176: Remote "Hubic Swift container rclone-test-qobevup3higewov1puhuroc7", Local "Local file system at /tmp/rclone910261613", Modify Window "1ns"
PASS
2020/07/24 09:56:23 DEBUG : Hubic Swift container rclone-test-qobevup3higewov1puhuroc7: Purge remote
2020/07/24 09:56:23 DEBUG : Waiting for deletions to finish
2020/07/24 09:56:23 INFO  : Swift container rclone-test-qobevup3higewov1puhuroc7: Container "rclone-test-qobevup3higewov1puhuroc7" removed
"./sync.test -test.v -test.timeout 1h0m0s -remote TestHubic: -verbose -test.run '^(TestServerSideMove|TestServerSideMoveDeleteEmptySourceDirs|TestSyncBackupDir|TestSyncBackupDirWithSuffix|TestSyncCopyDest)$'" - Finished OK in 2m29.113249054s (try 2/5)