"./sync.test -test.v -test.timeout 1h0m0s -remote TestHubic: -verbose" - Starting (try 1/5) 2021/12/23 05:50:52 DEBUG : Creating backend with remote "TestHubic:rclone-test-peqiyuw2qoqukop6jucerup5" 2021/12/23 05:50:52 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2021/12/23 05:50:53 DEBUG : Hubic: Got swift credentials (expiry 2021-12-24 06:50:53 +0100 +0100 in 23h59m59.400293538s) 2021/12/23 05:50:53 DEBUG : Creating backend with remote "/tmp/rclone168706856" === RUN TestPipe --- PASS: TestPipe (0.00s) === RUN TestPipeConcurrent --- PASS: TestPipeConcurrent (0.02s) === RUN TestPipeOrderBy === RUN TestPipeOrderBy/#00 === RUN TestPipeOrderBy/size === RUN TestPipeOrderBy/name === RUN TestPipeOrderBy/modtime === RUN TestPipeOrderBy/size,ascending === RUN TestPipeOrderBy/name,asc === RUN TestPipeOrderBy/modtime,ascending === RUN TestPipeOrderBy/size,descending === RUN TestPipeOrderBy/name,desc === RUN TestPipeOrderBy/modtime,descending === RUN TestPipeOrderBy/size,mixed,50 === RUN TestPipeOrderBy/size,mixed,51 --- PASS: TestPipeOrderBy (0.00s) --- PASS: TestPipeOrderBy/#00 (0.00s) --- PASS: TestPipeOrderBy/size (0.00s) --- PASS: TestPipeOrderBy/name (0.00s) --- PASS: TestPipeOrderBy/modtime (0.00s) --- PASS: TestPipeOrderBy/size,ascending (0.00s) --- PASS: TestPipeOrderBy/name,asc (0.00s) --- PASS: TestPipeOrderBy/modtime,ascending (0.00s) --- PASS: TestPipeOrderBy/size,descending (0.00s) --- PASS: TestPipeOrderBy/name,desc (0.00s) --- PASS: TestPipeOrderBy/modtime,descending (0.00s) --- PASS: TestPipeOrderBy/size,mixed,50 (0.00s) --- PASS: TestPipeOrderBy/size,mixed,51 (0.00s) === RUN TestNewLess === RUN TestNewLess/blankOK === RUN TestNewLess/tooManyParts === RUN TestNewLess/tooManyParts2 === RUN TestNewLess/badMixed === RUN TestNewLess/unknownComparison === RUN TestNewLess/unknownSortDirection === RUN TestNewLess/size === RUN TestNewLess/name === RUN TestNewLess/modtime === RUN TestNewLess/size,ascending === RUN TestNewLess/name,asc === RUN TestNewLess/modtime,ascending === RUN TestNewLess/size,descending === RUN TestNewLess/name,desc === RUN TestNewLess/modtime,descending === RUN TestNewLess/modtime,mixed === RUN TestNewLess/modtime,mixed,30 --- PASS: TestNewLess (0.00s) --- PASS: TestNewLess/blankOK (0.00s) --- PASS: TestNewLess/tooManyParts (0.00s) --- PASS: TestNewLess/tooManyParts2 (0.00s) --- PASS: TestNewLess/badMixed (0.00s) --- PASS: TestNewLess/unknownComparison (0.00s) --- PASS: TestNewLess/unknownSortDirection (0.00s) --- PASS: TestNewLess/size (0.00s) --- PASS: TestNewLess/name (0.00s) --- PASS: TestNewLess/modtime (0.00s) --- PASS: TestNewLess/size,ascending (0.00s) --- PASS: TestNewLess/name,asc (0.00s) --- PASS: TestNewLess/modtime,ascending (0.00s) --- PASS: TestNewLess/size,descending (0.00s) --- PASS: TestNewLess/name,desc (0.00s) --- PASS: TestNewLess/modtime,descending (0.00s) --- PASS: TestNewLess/modtime,mixed (0.00s) --- PASS: TestNewLess/modtime,mixed,30 (0.00s) === RUN TestRcCopy rc_test.go:16: Skipping test on non local remote --- SKIP: TestRcCopy (0.00s) === RUN TestRcMove rc_test.go:16: Skipping test on non local remote --- SKIP: TestRcMove (0.00s) === RUN TestRcSync rc_test.go:16: Skipping test on non local remote --- SKIP: TestRcSync (0.00s) === RUN TestCopyWithDryRun run.go:181: Remote "Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5", Local "Local file system at /tmp/rclone168706856", Modify Window "1ns" 2021/12/23 05:50:54 INFO : Swift container rclone-test-peqiyuw2qoqukop6jucerup5: Container "rclone-test-peqiyuw2qoqukop6jucerup5" created 2021/12/23 05:50:54 DEBUG : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5: Waiting for checks to finish 2021/12/23 05:50:54 NOTICE: sub dir/hello world: Skipped copy as --dry-run is set (size 11) 2021/12/23 05:50:54 DEBUG : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5: Waiting for transfers to finish --- PASS: TestCopyWithDryRun (1.64s) === RUN TestCopy run.go:181: Remote "Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5", Local "Local file system at /tmp/rclone168706856", Modify Window "1ns" 2021/12/23 05:50:56 DEBUG : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5: Waiting for checks to finish 2021/12/23 05:50:56 DEBUG : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5: Waiting for transfers to finish 2021/12/23 05:50:57 DEBUG : sub dir/hello world: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2021/12/23 05:50:57 INFO : sub dir/hello world: Copied (new) fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:293: Sleeping for 6s just to make sure --- PASS: TestCopy (12.71s) === RUN TestCopyMissingDirectory run.go:181: Remote "Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5", Local "Local file system at /tmp/rclone168706856", Modify Window "1ns" 2021/12/23 05:51:07 DEBUG : Creating backend with remote "/non-existing" 2021/12/23 05:51:08 ERROR : Local file system at /non-existing: error reading source root directory: directory not found 2021/12/23 05:51:08 DEBUG : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5: Waiting for checks to finish 2021/12/23 05:51:08 DEBUG : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5: Waiting for transfers to finish --- PASS: TestCopyMissingDirectory (0.51s) === RUN TestCopyNoTraverse run.go:181: Remote "Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5", Local "Local file system at /tmp/rclone168706856", Modify Window "1ns" 2021/12/23 05:51:08 DEBUG : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5: Waiting for checks to finish 2021/12/23 05:51:08 DEBUG : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5: Waiting for transfers to finish 2021/12/23 05:51:10 DEBUG : sub dir/hello world: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2021/12/23 05:51:10 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopyNoTraverse (5.11s) === RUN TestCopyCheckFirst run.go:181: Remote "Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5", Local "Local file system at /tmp/rclone168706856", Modify Window "1ns" 2021/12/23 05:51:13 INFO : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5: Running all checks before starting transfers 2021/12/23 05:51:13 DEBUG : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5: Waiting for checks to finish 2021/12/23 05:51:13 INFO : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5: Checks finished, now starting transfers 2021/12/23 05:51:13 DEBUG : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5: Waiting for transfers to finish 2021/12/23 05:51:15 DEBUG : sub dir/hello world: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2021/12/23 05:51:15 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopyCheckFirst (4.42s) === RUN TestSyncNoTraverse run.go:181: Remote "Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5", Local "Local file system at /tmp/rclone168706856", Modify Window "1ns" 2021/12/23 05:51:18 ERROR : Ignoring --no-traverse with sync 2021/12/23 05:51:18 DEBUG : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5: Waiting for checks to finish 2021/12/23 05:51:18 DEBUG : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5: Waiting for transfers to finish 2021/12/23 05:51:20 DEBUG : sub dir/hello world: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2021/12/23 05:51:20 INFO : sub dir/hello world: Copied (new) 2021/12/23 05:51:20 DEBUG : Waiting for deletions to finish fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:293: Sleeping for 6s just to make sure --- PASS: TestSyncNoTraverse (12.97s) === RUN TestCopyWithDepth run.go:181: Remote "Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5", Local "Local file system at /tmp/rclone168706856", Modify Window "1ns" 2021/12/23 05:51:31 DEBUG : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5: Waiting for checks to finish 2021/12/23 05:51:31 DEBUG : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5: Waiting for transfers to finish 2021/12/23 05:51:33 DEBUG : hello world2: md5 = cc2c857f89648dbd139d7b2a6665957d OK 2021/12/23 05:51:33 INFO : hello world2: Copied (new) --- PASS: TestCopyWithDepth (5.59s) === RUN TestCopyWithFilesFrom run.go:181: Remote "Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5", Local "Local file system at /tmp/rclone168706856", Modify Window "1ns" 2021/12/23 05:51:36 DEBUG : hello world2: Excluded 2021/12/23 05:51:36 DEBUG : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5: Waiting for checks to finish 2021/12/23 05:51:36 DEBUG : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5: Waiting for transfers to finish 2021/12/23 05:51:39 DEBUG : potato2: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2021/12/23 05:51:39 INFO : potato2: Copied (new) --- PASS: TestCopyWithFilesFrom (4.43s) === RUN TestCopyWithFilesFromAndNoTraverse run.go:181: Remote "Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5", Local "Local file system at /tmp/rclone168706856", Modify Window "1ns" 2021/12/23 05:51:41 DEBUG : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5: Waiting for checks to finish 2021/12/23 05:51:41 DEBUG : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5: Waiting for transfers to finish 2021/12/23 05:51:43 DEBUG : potato2: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2021/12/23 05:51:43 INFO : potato2: Copied (new) fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:293: Sleeping for 6s just to make sure --- PASS: TestCopyWithFilesFromAndNoTraverse (11.74s) === RUN TestCopyEmptyDirectories run.go:181: Remote "Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5", Local "Local file system at /tmp/rclone168706856", Modify Window "1ns" 2021/12/23 05:51:52 DEBUG : sub dir2: Making directory 2021/12/23 05:51:53 DEBUG : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5: Waiting for checks to finish 2021/12/23 05:51:53 DEBUG : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5: Waiting for transfers to finish 2021/12/23 05:51:55 DEBUG : sub dir/hello world: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2021/12/23 05:51:55 INFO : sub dir/hello world: Copied (new) 2021/12/23 05:51:55 DEBUG : sub dir2: Making directory 2021/12/23 05:51:55 DEBUG : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5: copied 1 directories fstest.go:251: Filtering empty directory "sub dir2" --- PASS: TestCopyEmptyDirectories (4.99s) === RUN TestMoveEmptyDirectories run.go:181: Remote "Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5", Local "Local file system at /tmp/rclone168706856", Modify Window "1ns" 2021/12/23 05:51:57 DEBUG : sub dir2: Making directory 2021/12/23 05:51:58 DEBUG : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5: Waiting for checks to finish 2021/12/23 05:51:58 DEBUG : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5: Waiting for transfers to finish 2021/12/23 05:52:00 DEBUG : sub dir/hello world: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2021/12/23 05:52:00 INFO : sub dir/hello world: Copied (new) 2021/12/23 05:52:00 INFO : sub dir/hello world: Deleted 2021/12/23 05:52:00 DEBUG : sub dir2: Making directory 2021/12/23 05:52:00 DEBUG : sub dir: Making directory 2021/12/23 05:52:00 DEBUG : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5: copied 2 directories fstest.go:251: Filtering empty directory "sub dir2" --- PASS: TestMoveEmptyDirectories (5.30s) === RUN TestSyncEmptyDirectories run.go:181: Remote "Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5", Local "Local file system at /tmp/rclone168706856", Modify Window "1ns" 2021/12/23 05:52:03 DEBUG : sub dir2: Making directory 2021/12/23 05:52:03 DEBUG : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5: Waiting for checks to finish 2021/12/23 05:52:03 DEBUG : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5: Waiting for transfers to finish 2021/12/23 05:52:05 DEBUG : sub dir/hello world: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2021/12/23 05:52:05 INFO : sub dir/hello world: Copied (new) 2021/12/23 05:52:05 DEBUG : sub dir2: Making directory 2021/12/23 05:52:05 DEBUG : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5: copied 1 directories 2021/12/23 05:52:05 DEBUG : Waiting for deletions to finish fstest.go:251: Filtering empty directory "sub dir2" --- PASS: TestSyncEmptyDirectories (4.83s) === RUN TestServerSideCopy run.go:181: Remote "Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5", Local "Local file system at /tmp/rclone168706856", Modify Window "1ns" 2021/12/23 05:52:10 DEBUG : Creating backend with remote "TestHubic:rclone-test-dojajat9vimiqur8cahodip7" 2021/12/23 05:52:10 DEBUG : Hubic: Got swift credentials (expiry 2021-12-24 06:50:53 +0100 +0100 in 23h58m42.669491087s) sync_test.go:275: Server side copy (if possible) Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5 -> Hubic Swift container rclone-test-dojajat9vimiqur8cahodip7 2021/12/23 05:52:11 DEBUG : Hubic Swift container rclone-test-dojajat9vimiqur8cahodip7: Waiting for checks to finish 2021/12/23 05:52:11 DEBUG : Hubic Swift container rclone-test-dojajat9vimiqur8cahodip7: Waiting for transfers to finish 2021/12/23 05:52:11 INFO : Swift container rclone-test-dojajat9vimiqur8cahodip7: Container "rclone-test-dojajat9vimiqur8cahodip7" created 2021/12/23 05:52:12 DEBUG : sub dir/hello world: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2021/12/23 05:52:12 INFO : sub dir/hello world: Copied (server-side copy) 2021/12/23 05:52:12 DEBUG : Hubic Swift container rclone-test-dojajat9vimiqur8cahodip7: Purge remote 2021/12/23 05:52:12 DEBUG : Waiting for deletions to finish 2021/12/23 05:52:13 INFO : sub dir/hello world: Deleted 2021/12/23 05:52:13 INFO : Swift container rclone-test-dojajat9vimiqur8cahodip7: Container "rclone-test-dojajat9vimiqur8cahodip7" removed --- PASS: TestServerSideCopy (7.82s) === RUN TestCopyAfterDelete run.go:181: Remote "Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5", Local "Local file system at /tmp/rclone168706856", Modify Window "1ns" 2021/12/23 05:52:17 ERROR : : error listing: directory not found 2021/12/23 05:52:19 DEBUG : Local file system at /tmp/rclone168706856: Making directory 2021/12/23 05:52:20 DEBUG : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5: Waiting for checks to finish 2021/12/23 05:52:20 DEBUG : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5: Waiting for transfers to finish --- PASS: TestCopyAfterDelete (6.74s) === RUN TestCopyRedownload run.go:181: Remote "Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5", Local "Local file system at /tmp/rclone168706856", Modify Window "1ns" fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:299: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:299: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:306: Error Trace: fstest.go:306 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:309 Error: Should be true Test: TestCopyRedownload Messages: listing wrong, want sub dir/hello world (11) got fstest.go:204: Not found "sub dir/hello world" fstest.go:207: Error Trace: fstest.go:207 fstest.go:311 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:309 Error: Not equal: expected: 0 actual : 1 Test: TestCopyRedownload Messages: 1 objects not found 2021/12/23 05:52:34 DEBUG : Local file system at /tmp/rclone168706856: Waiting for checks to finish 2021/12/23 05:52:34 DEBUG : Local file system at /tmp/rclone168706856: Waiting for transfers to finish 2021/12/23 05:52:34 ERROR : : error listing: directory not found fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 2021/12/23 05:52:35 ERROR : : error listing: directory not found fstest.go:299: Sleeping for 2s for list eventual consistency: 2/3 2021/12/23 05:52:37 ERROR : : error listing: directory not found fstest.go:299: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:306: Error Trace: fstest.go:306 fstest.go:339 run.go:352 sync_test.go:315 Error: Should be true Test: TestCopyRedownload Messages: listing wrong, want sub dir/hello world (11) got fstest.go:204: Not found "sub dir/hello world" fstest.go:207: Error Trace: fstest.go:207 fstest.go:311 fstest.go:339 run.go:352 sync_test.go:315 Error: Not equal: expected: 0 actual : 1 Test: TestCopyRedownload Messages: 1 objects not found --- FAIL: TestCopyRedownload (19.49s) === RUN TestSyncBasedOnCheckSum run.go:181: Remote "Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5", Local "Local file system at /tmp/rclone168706856", Modify Window "1ns" 2021/12/23 05:52:42 DEBUG : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5: Waiting for checks to finish 2021/12/23 05:52:42 DEBUG : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5: Waiting for transfers to finish 2021/12/23 05:52:43 DEBUG : check sum: md5 = 336d5ebc5436534e61d16e63ddfca327 OK 2021/12/23 05:52:43 INFO : check sum: Copied (new) 2021/12/23 05:52:43 DEBUG : Waiting for deletions to finish 2021/12/23 05:52:44 DEBUG : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5: Waiting for checks to finish 2021/12/23 05:52:44 DEBUG : check sum: md5 = 336d5ebc5436534e61d16e63ddfca327 OK 2021/12/23 05:52:44 DEBUG : check sum: Size and md5 of src and dst objects identical 2021/12/23 05:52:44 DEBUG : check sum: Unchanged skipping 2021/12/23 05:52:44 DEBUG : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5: Waiting for transfers to finish 2021/12/23 05:52:44 DEBUG : Waiting for deletions to finish 2021/12/23 05:52:44 INFO : There was nothing to transfer fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:299: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:299: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:306: Error Trace: fstest.go:306 fstest.go:339 run.go:174 run.go:376 sync_test.go:351 Error: Should be true Test: TestSyncBasedOnCheckSum Messages: listing wrong, want got check sum (1) fstest.go:192: Error Trace: fstest.go:192 fstest.go:309 fstest.go:339 run.go:174 run.go:376 sync_test.go:351 Error: Should be true Test: TestSyncBasedOnCheckSum Messages: Unexpected file "check sum" --- FAIL: TestSyncBasedOnCheckSum (13.07s) === RUN TestSyncSizeOnly run.go:181: Remote "Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5", Local "Local file system at /tmp/rclone168706856", Modify Window "1ns" 2021/12/23 05:52:55 DEBUG : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5: Waiting for checks to finish 2021/12/23 05:52:55 DEBUG : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5: Waiting for transfers to finish 2021/12/23 05:52:57 DEBUG : sizeonly: md5 = 8ee2027983915ec78acc45027d874316 OK 2021/12/23 05:52:57 INFO : sizeonly: Copied (new) 2021/12/23 05:52:57 DEBUG : Waiting for deletions to finish 2021/12/23 05:52:59 ERROR : check sum: Couldn't delete: Object Not Found 2021/12/23 05:52:59 ERROR : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5: not deleting directories as there were IO errors sync_test.go:368: Error Trace: sync_test.go:368 Error: Received unexpected error: failed to delete 1 files Test: TestSyncSizeOnly --- FAIL: TestSyncSizeOnly (7.13s) === RUN TestSyncIgnoreSize run.go:181: Remote "Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5", Local "Local file system at /tmp/rclone168706856", Modify Window "1ns" 2021/12/23 05:53:02 DEBUG : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5: Waiting for checks to finish 2021/12/23 05:53:02 DEBUG : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5: Waiting for transfers to finish 2021/12/23 05:53:04 DEBUG : ignore-size: md5 = 98bf7d8c15784f0a3d63204441e1e2aa OK 2021/12/23 05:53:04 INFO : ignore-size: Copied (new) 2021/12/23 05:53:04 DEBUG : Waiting for deletions to finish 2021/12/23 05:53:04 DEBUG : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5: Waiting for checks to finish 2021/12/23 05:53:04 DEBUG : ignore-size: Size and modification time the same (differ by 0s, within tolerance 1ns) 2021/12/23 05:53:04 DEBUG : ignore-size: Unchanged skipping 2021/12/23 05:53:04 DEBUG : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5: Waiting for transfers to finish 2021/12/23 05:53:04 DEBUG : Waiting for deletions to finish 2021/12/23 05:53:04 INFO : There was nothing to transfer --- PASS: TestSyncIgnoreSize (4.81s) === RUN TestSyncIgnoreTimes run.go:181: Remote "Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5", Local "Local file system at /tmp/rclone168706856", Modify Window "1ns" 2021/12/23 05:53:10 DEBUG : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5: Waiting for checks to finish 2021/12/23 05:53:10 DEBUG : existing: Size and modification time the same (differ by 0s, within tolerance 1ns) 2021/12/23 05:53:10 DEBUG : existing: Unchanged skipping 2021/12/23 05:53:10 DEBUG : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5: Waiting for transfers to finish 2021/12/23 05:53:10 DEBUG : Waiting for deletions to finish 2021/12/23 05:53:10 INFO : There was nothing to transfer 2021/12/23 05:53:10 DEBUG : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5: Waiting for checks to finish 2021/12/23 05:53:10 DEBUG : existing: Transferring unconditionally as --ignore-times is in use 2021/12/23 05:53:10 DEBUG : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5: Waiting for transfers to finish 2021/12/23 05:53:13 DEBUG : existing: md5 = 8ee2027983915ec78acc45027d874316 OK 2021/12/23 05:53:13 INFO : existing: Copied (replaced existing) 2021/12/23 05:53:13 DEBUG : Waiting for deletions to finish --- PASS: TestSyncIgnoreTimes (8.38s) === RUN TestSyncIgnoreExisting run.go:181: Remote "Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5", Local "Local file system at /tmp/rclone168706856", Modify Window "1ns" 2021/12/23 05:53:15 DEBUG : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5: Waiting for checks to finish 2021/12/23 05:53:15 DEBUG : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5: Waiting for transfers to finish 2021/12/23 05:53:17 DEBUG : existing: md5 = 8ee2027983915ec78acc45027d874316 OK 2021/12/23 05:53:17 INFO : existing: Copied (new) 2021/12/23 05:53:17 DEBUG : Waiting for deletions to finish 2021/12/23 05:53:18 DEBUG : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5: Waiting for checks to finish 2021/12/23 05:53:18 DEBUG : existing: Destination exists, skipping 2021/12/23 05:53:18 DEBUG : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5: Waiting for transfers to finish 2021/12/23 05:53:18 DEBUG : Waiting for deletions to finish 2021/12/23 05:53:18 INFO : There was nothing to transfer --- PASS: TestSyncIgnoreExisting (9.11s) === RUN TestSyncIgnoreErrors run.go:181: Remote "Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5", Local "Local file system at /tmp/rclone168706856", Modify Window "1ns" 2021/12/23 05:53:28 DEBUG : d: Making directory fstest.go:251: Filtering empty directory "d" 2021/12/23 05:53:30 DEBUG : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5: Waiting for checks to finish 2021/12/23 05:53:30 DEBUG : c/non empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2021/12/23 05:53:30 DEBUG : c/non empty space: Unchanged skipping 2021/12/23 05:53:30 DEBUG : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5: Waiting for transfers to finish 2021/12/23 05:53:32 DEBUG : a/potato2: md5 = d6548b156ea68a4e003e786df99eee76 OK 2021/12/23 05:53:32 INFO : a/potato2: Copied (new) 2021/12/23 05:53:32 DEBUG : Waiting for deletions to finish 2021/12/23 05:53:33 INFO : b/potato: Deleted fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:299: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:299: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:306: Error Trace: fstest.go:306 fstest.go:339 run.go:174 run.go:376 sync_test.go:538 Error: Should be true Test: TestSyncIgnoreErrors Messages: listing wrong, want got c/non empty space (5) fstest.go:192: Error Trace: fstest.go:192 fstest.go:309 fstest.go:339 run.go:174 run.go:376 sync_test.go:538 Error: Should be true Test: TestSyncIgnoreErrors Messages: Unexpected file "c/non empty space" --- FAIL: TestSyncIgnoreErrors (23.27s) === RUN TestSyncAfterChangingModtimeOnly run.go:181: Remote "Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5", Local "Local file system at /tmp/rclone168706856", Modify Window "1ns" fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:299: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:299: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:306: Error Trace: fstest.go:306 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:549 Error: Should be true Test: TestSyncAfterChangingModtimeOnly Messages: listing wrong, want empty space (1) got c/non empty space (5), empty space (1) fstest.go:192: Error Trace: fstest.go:192 fstest.go:309 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:549 Error: Should be true Test: TestSyncAfterChangingModtimeOnly Messages: Unexpected file "c/non empty space" 2021/12/23 05:53:59 DEBUG : empty space: Modification times differ by -95456h54m52.62345679s: 2011-12-25 12:59:59.123456789 +0000 UTC, 2001-02-03 04:05:06.499999999 +0000 UTC 2021/12/23 05:53:59 DEBUG : empty space: md5 = 336d5ebc5436534e61d16e63ddfca327 OK 2021/12/23 05:53:59 NOTICE: empty space: Skipped update modification time as --dry-run is set (size 1) 2021/12/23 05:53:59 DEBUG : empty space: Unchanged skipping 2021/12/23 05:53:59 DEBUG : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5: Waiting for checks to finish 2021/12/23 05:53:59 DEBUG : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5: Waiting for transfers to finish 2021/12/23 05:53:59 DEBUG : Waiting for deletions to finish 2021/12/23 05:53:59 NOTICE: c/non empty space: Skipped delete as --dry-run is set (size 5) 2021/12/23 05:53:59 INFO : There was nothing to transfer fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:299: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:299: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:306: Error Trace: fstest.go:306 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:558 Error: Should be true Test: TestSyncAfterChangingModtimeOnly Messages: listing wrong, want empty space (1) got c/non empty space (5), empty space (1) fstest.go:192: Error Trace: fstest.go:192 fstest.go:309 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:558 Error: Should be true Test: TestSyncAfterChangingModtimeOnly Messages: Unexpected file "c/non empty space" 2021/12/23 05:54:08 DEBUG : empty space: Modification times differ by -95456h54m52.62345679s: 2011-12-25 12:59:59.123456789 +0000 UTC, 2001-02-03 04:05:06.499999999 +0000 UTC 2021/12/23 05:54:08 DEBUG : empty space: md5 = 336d5ebc5436534e61d16e63ddfca327 OK 2021/12/23 05:54:08 DEBUG : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5: Waiting for checks to finish 2021/12/23 05:54:09 INFO : empty space: Updated modification time in destination 2021/12/23 05:54:09 DEBUG : empty space: Unchanged skipping 2021/12/23 05:54:09 DEBUG : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5: Waiting for transfers to finish 2021/12/23 05:54:09 DEBUG : Waiting for deletions to finish 2021/12/23 05:54:10 ERROR : c/non empty space: Couldn't delete: Object Not Found 2021/12/23 05:54:10 ERROR : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5: not deleting directories as there were IO errors sync_test.go:564: Error Trace: sync_test.go:564 Error: Received unexpected error: failed to delete 1 files Test: TestSyncAfterChangingModtimeOnly fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:299: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:299: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:306: Error Trace: fstest.go:306 fstest.go:339 run.go:174 run.go:376 panic.go:642 testing.go:756 sync_test.go:564 Error: Should be true Test: TestSyncAfterChangingModtimeOnly Messages: listing wrong, want got empty space (1) fstest.go:192: Error Trace: fstest.go:192 fstest.go:309 fstest.go:339 run.go:174 run.go:376 panic.go:642 testing.go:756 sync_test.go:564 Error: Should be true Test: TestSyncAfterChangingModtimeOnly Messages: Unexpected file "empty space" --- FAIL: TestSyncAfterChangingModtimeOnly (32.29s) === RUN TestSyncAfterChangingModtimeOnlyWithNoUpdateModTime run.go:181: Remote "Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5", Local "Local file system at /tmp/rclone168706856", Modify Window "1ns" 2021/12/23 05:54:22 DEBUG : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5: Waiting for checks to finish 2021/12/23 05:54:22 DEBUG : empty space: Modification times differ by -95456h54m52.62345679s: 2011-12-25 12:59:59.123456789 +0000 UTC, 2001-02-03 04:05:06.499999999 +0000 UTC 2021/12/23 05:54:22 DEBUG : empty space: md5 = 336d5ebc5436534e61d16e63ddfca327 OK 2021/12/23 05:54:22 DEBUG : empty space: Unchanged skipping 2021/12/23 05:54:22 DEBUG : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5: Waiting for transfers to finish 2021/12/23 05:54:22 DEBUG : Waiting for deletions to finish 2021/12/23 05:54:22 INFO : There was nothing to transfer --- PASS: TestSyncAfterChangingModtimeOnlyWithNoUpdateModTime (5.88s) === RUN TestSyncDoesntUpdateModtime run.go:181: Remote "Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5", Local "Local file system at /tmp/rclone168706856", Modify Window "1ns" 2021/12/23 05:54:28 DEBUG : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5: Waiting for checks to finish 2021/12/23 05:54:28 DEBUG : foo: Modification times differ by -95456h54m52.62345679s: 2011-12-25 12:59:59.123456789 +0000 UTC, 2001-02-03 04:05:06.499999999 +0000 UTC 2021/12/23 05:54:28 DEBUG : foo: md5 = acbd18db4cc2f85cedef654fccc4a4d8 (Local file system at /tmp/rclone168706856) 2021/12/23 05:54:28 DEBUG : foo: md5 = 37b51d194a7513e45b56f6524f2d51f2 (Swift container rclone-test-peqiyuw2qoqukop6jucerup5) 2021/12/23 05:54:28 DEBUG : foo: md5 differ 2021/12/23 05:54:28 DEBUG : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5: Waiting for transfers to finish 2021/12/23 05:54:31 DEBUG : foo: md5 = acbd18db4cc2f85cedef654fccc4a4d8 OK 2021/12/23 05:54:31 INFO : foo: Copied (replaced existing) 2021/12/23 05:54:31 DEBUG : Waiting for deletions to finish --- PASS: TestSyncDoesntUpdateModtime (7.47s) === RUN TestSyncAfterAddingAFile run.go:181: Remote "Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5", Local "Local file system at /tmp/rclone168706856", Modify Window "1ns" fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:293: Sleeping for 6s just to make sure 2021/12/23 05:54:43 DEBUG : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5: Waiting for checks to finish 2021/12/23 05:54:43 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2021/12/23 05:54:43 DEBUG : empty space: Unchanged skipping 2021/12/23 05:54:43 DEBUG : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5: Waiting for transfers to finish 2021/12/23 05:54:45 DEBUG : potato: md5 = d6548b156ea68a4e003e786df99eee76 OK 2021/12/23 05:54:45 INFO : potato: Copied (new) 2021/12/23 05:54:45 DEBUG : Waiting for deletions to finish --- PASS: TestSyncAfterAddingAFile (15.32s) === RUN TestSyncAfterChangingFilesSizeOnly run.go:181: Remote "Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5", Local "Local file system at /tmp/rclone168706856", Modify Window "1ns" 2021/12/23 05:54:52 DEBUG : potato: Sizes differ (src 21 vs dst 60) 2021/12/23 05:54:52 DEBUG : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5: Waiting for checks to finish 2021/12/23 05:54:52 DEBUG : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5: Waiting for transfers to finish 2021/12/23 05:54:55 DEBUG : potato: md5 = 100defcf18c42a1e0dc42a789b107cd2 OK 2021/12/23 05:54:55 INFO : potato: Copied (replaced existing) 2021/12/23 05:54:55 DEBUG : Waiting for deletions to finish fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:293: Sleeping for 6s just to make sure --- PASS: TestSyncAfterChangingFilesSizeOnly (18.13s) === RUN TestSyncAfterChangingContentsOnly run.go:181: Remote "Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5", Local "Local file system at /tmp/rclone168706856", Modify Window "1ns" 2021/12/23 05:55:09 DEBUG : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5: Waiting for checks to finish 2021/12/23 05:55:09 DEBUG : potato: Modification times differ by 119h59m59.876543211s: 2011-12-25 12:59:59.123456789 +0000 UTC, 2011-12-30 12:59:59 +0000 UTC 2021/12/23 05:55:09 DEBUG : potato: md5 = e4cb6955d9106df6263c45fcfc10f163 (Local file system at /tmp/rclone168706856) 2021/12/23 05:55:09 DEBUG : potato: md5 = 100defcf18c42a1e0dc42a789b107cd2 (Swift container rclone-test-peqiyuw2qoqukop6jucerup5) 2021/12/23 05:55:09 DEBUG : potato: md5 differ 2021/12/23 05:55:09 DEBUG : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5: Waiting for transfers to finish 2021/12/23 05:55:11 DEBUG : potato: md5 = e4cb6955d9106df6263c45fcfc10f163 OK 2021/12/23 05:55:11 INFO : potato: Copied (replaced existing) 2021/12/23 05:55:11 DEBUG : Waiting for deletions to finish --- PASS: TestSyncAfterChangingContentsOnly (7.27s) === RUN TestSyncAfterRemovingAFileAndAddingAFileDryRun run.go:181: Remote "Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5", Local "Local file system at /tmp/rclone168706856", Modify Window "1ns" 2021/12/23 05:55:18 NOTICE: potato2: Skipped copy as --dry-run is set (size 60) 2021/12/23 05:55:18 DEBUG : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5: Waiting for checks to finish 2021/12/23 05:55:18 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2021/12/23 05:55:18 DEBUG : empty space: Unchanged skipping 2021/12/23 05:55:18 DEBUG : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5: Waiting for transfers to finish 2021/12/23 05:55:18 DEBUG : Waiting for deletions to finish 2021/12/23 05:55:18 NOTICE: potato: Skipped delete as --dry-run is set (size 21) --- PASS: TestSyncAfterRemovingAFileAndAddingAFileDryRun (8.20s) === RUN TestSyncAfterRemovingAFileAndAddingAFile run.go:181: Remote "Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5", Local "Local file system at /tmp/rclone168706856", Modify Window "1ns" 2021/12/23 05:55:27 DEBUG : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5: Waiting for checks to finish 2021/12/23 05:55:27 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2021/12/23 05:55:27 DEBUG : empty space: Unchanged skipping 2021/12/23 05:55:27 DEBUG : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5: Waiting for transfers to finish 2021/12/23 05:55:30 DEBUG : potato2: md5 = d6548b156ea68a4e003e786df99eee76 OK 2021/12/23 05:55:30 INFO : potato2: Copied (new) 2021/12/23 05:55:30 DEBUG : Waiting for deletions to finish 2021/12/23 05:55:31 INFO : potato: Deleted fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:299: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:299: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:306: Error Trace: fstest.go:306 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:713 sync_test.go:717 Error: Should be true Test: TestSyncAfterRemovingAFileAndAddingAFile Messages: listing wrong, want empty space (1), potato2 (60) got empty space (1), potato2 (60), sub dir/hello world (11) fstest.go:192: Error Trace: fstest.go:192 fstest.go:309 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:713 sync_test.go:717 Error: Should be true Test: TestSyncAfterRemovingAFileAndAddingAFile Messages: Unexpected file "sub dir/hello world" --- FAIL: TestSyncAfterRemovingAFileAndAddingAFile (21.40s) === RUN TestSyncAfterRemovingAFileAndAddingAFileSubDir run.go:181: Remote "Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5", Local "Local file system at /tmp/rclone168706856", Modify Window "1ns" 2021/12/23 05:55:48 DEBUG : d: Making directory 2021/12/23 05:55:48 DEBUG : d/e: Making directory fstest.go:251: Filtering empty directory "d" fstest.go:251: Filtering empty directory "d/e" 2021/12/23 05:55:49 DEBUG : c/non empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2021/12/23 05:55:49 DEBUG : c/non empty space: Unchanged skipping 2021/12/23 05:55:50 DEBUG : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5: Waiting for checks to finish 2021/12/23 05:55:50 DEBUG : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5: Waiting for transfers to finish 2021/12/23 05:55:52 DEBUG : a/potato2: md5 = d6548b156ea68a4e003e786df99eee76 OK 2021/12/23 05:55:52 INFO : a/potato2: Copied (new) 2021/12/23 05:55:52 DEBUG : Waiting for deletions to finish 2021/12/23 05:55:53 INFO : b/potato: Deleted --- PASS: TestSyncAfterRemovingAFileAndAddingAFileSubDir (13.26s) === RUN TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors run.go:181: Remote "Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5", Local "Local file system at /tmp/rclone168706856", Modify Window "1ns" 2021/12/23 05:56:02 DEBUG : d: Making directory fstest.go:251: Filtering empty directory "d" 2021/12/23 05:56:02 DEBUG : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5: Waiting for checks to finish 2021/12/23 05:56:02 DEBUG : c/non empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2021/12/23 05:56:02 DEBUG : c/non empty space: Unchanged skipping 2021/12/23 05:56:02 DEBUG : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5: Waiting for transfers to finish 2021/12/23 05:56:04 DEBUG : a/potato2: md5 = d6548b156ea68a4e003e786df99eee76 OK 2021/12/23 05:56:04 INFO : a/potato2: Copied (new) 2021/12/23 05:56:04 ERROR : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5: not deleting files as there were IO errors 2021/12/23 05:56:04 ERROR : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5: not deleting directories as there were IO errors fstest.go:251: Filtering empty directory "d" --- PASS: TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors (13.29s) === RUN TestSyncDeleteAfter --- PASS: TestSyncDeleteAfter (0.00s) === RUN TestSyncDeleteDuring run.go:181: Remote "Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5", Local "Local file system at /tmp/rclone168706856", Modify Window "1ns" 2021/12/23 05:56:15 DEBUG : Waiting for deletions to finish 2021/12/23 05:56:15 DEBUG : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5: Waiting for checks to finish 2021/12/23 05:56:15 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2021/12/23 05:56:15 DEBUG : empty space: Unchanged skipping 2021/12/23 05:56:15 DEBUG : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5: Waiting for transfers to finish 2021/12/23 05:56:16 INFO : potato: Deleted 2021/12/23 05:56:18 DEBUG : potato2: md5 = d6548b156ea68a4e003e786df99eee76 OK 2021/12/23 05:56:18 INFO : potato2: Copied (new) --- PASS: TestSyncDeleteDuring (10.89s) === RUN TestSyncDeleteBefore run.go:181: Remote "Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5", Local "Local file system at /tmp/rclone168706856", Modify Window "1ns" 2021/12/23 05:56:25 DEBUG : Waiting for deletions to finish 2021/12/23 05:56:25 DEBUG : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5: Waiting for checks to finish 2021/12/23 05:56:25 DEBUG : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5: Waiting for transfers to finish 2021/12/23 05:56:26 INFO : potato: Deleted 2021/12/23 05:56:28 DEBUG : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5: Waiting for checks to finish 2021/12/23 05:56:28 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2021/12/23 05:56:28 DEBUG : empty space: Unchanged skipping 2021/12/23 05:56:28 DEBUG : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5: Waiting for transfers to finish 2021/12/23 05:56:31 DEBUG : potato2: md5 = d6548b156ea68a4e003e786df99eee76 OK 2021/12/23 05:56:31 INFO : potato2: Copied (new) fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:299: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:299: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:306: Error Trace: fstest.go:306 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:713 sync_test.go:877 Error: Should be true Test: TestSyncDeleteBefore Messages: listing wrong, want empty space (1), potato2 (60) got empty space (1) fstest.go:204: Not found "potato2" fstest.go:207: Error Trace: fstest.go:207 fstest.go:311 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:713 sync_test.go:877 Error: Not equal: expected: 0 actual : 1 Test: TestSyncDeleteBefore Messages: 1 objects not found --- FAIL: TestSyncDeleteBefore (21.04s) === RUN TestCopyDeleteBefore run.go:181: Remote "Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5", Local "Local file system at /tmp/rclone168706856", Modify Window "1ns" fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:299: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:299: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:306: Error Trace: fstest.go:306 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:891 Error: Should be true Test: TestCopyDeleteBefore Messages: listing wrong, want potato (21) got fstest.go:204: Not found "potato" fstest.go:207: Error Trace: fstest.go:207 fstest.go:311 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:891 Error: Not equal: expected: 0 actual : 1 Test: TestCopyDeleteBefore Messages: 1 objects not found 2021/12/23 05:56:53 DEBUG : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5: Waiting for checks to finish 2021/12/23 05:56:53 DEBUG : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5: Waiting for transfers to finish 2021/12/23 05:56:55 DEBUG : potato2: md5 = 9519bc2e940e1696ea7365b4b81adedc OK 2021/12/23 05:56:55 INFO : potato2: Copied (new) fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:299: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:299: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:306: Error Trace: fstest.go:306 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:898 Error: Should be true Test: TestCopyDeleteBefore Messages: listing wrong, want potato (21), potato2 (19) got fstest.go:204: Not found "potato2" fstest.go:204: Not found "potato" fstest.go:207: Error Trace: fstest.go:207 fstest.go:311 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:898 Error: Not equal: expected: 0 actual : 2 Test: TestCopyDeleteBefore Messages: 2 objects not found --- FAIL: TestCopyDeleteBefore (22.81s) === RUN TestSyncWithExclude run.go:181: Remote "Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5", Local "Local file system at /tmp/rclone168706856", Modify Window "1ns" fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:299: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:299: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:306: Error Trace: fstest.go:306 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:910 Error: Should be true Test: TestSyncWithExclude Messages: listing wrong, want empty space (1), potato2 (60) got empty space (1), potato (21), potato2 (60) fstest.go:192: Error Trace: fstest.go:192 fstest.go:309 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:910 Error: Should be true Test: TestSyncWithExclude Messages: Unexpected file "potato" 2021/12/23 05:57:16 DEBUG : enormous: Excluded 2021/12/23 05:57:16 DEBUG : potato2: Excluded 2021/12/23 05:57:16 DEBUG : potato2: Excluded 2021/12/23 05:57:16 DEBUG : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5: Waiting for checks to finish 2021/12/23 05:57:16 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2021/12/23 05:57:16 DEBUG : empty space: Unchanged skipping 2021/12/23 05:57:16 DEBUG : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5: Waiting for transfers to finish 2021/12/23 05:57:16 DEBUG : Waiting for deletions to finish 2021/12/23 05:57:17 INFO : potato: Deleted 2021/12/23 05:57:17 INFO : There was nothing to transfer 2021/12/23 05:57:17 DEBUG : enormous: Excluded 2021/12/23 05:57:17 DEBUG : potato2: Excluded 2021/12/23 05:57:18 DEBUG : potato2: Excluded 2021/12/23 05:57:18 DEBUG : Local file system at /tmp/rclone168706856: Waiting for checks to finish 2021/12/23 05:57:18 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2021/12/23 05:57:18 DEBUG : empty space: Unchanged skipping 2021/12/23 05:57:18 DEBUG : Local file system at /tmp/rclone168706856: Waiting for transfers to finish 2021/12/23 05:57:18 DEBUG : Waiting for deletions to finish 2021/12/23 05:57:18 INFO : There was nothing to transfer fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:299: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:299: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:306: Error Trace: fstest.go:306 fstest.go:339 run.go:174 run.go:376 sync_test.go:929 Error: Should be true Test: TestSyncWithExclude Messages: listing wrong, want got potato2 (60) fstest.go:192: Error Trace: fstest.go:192 fstest.go:309 fstest.go:339 run.go:174 run.go:376 sync_test.go:929 Error: Should be true Test: TestSyncWithExclude Messages: Unexpected file "potato2" --- FAIL: TestSyncWithExclude (24.53s) === RUN TestSyncWithExcludeAndDeleteExcluded run.go:181: Remote "Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5", Local "Local file system at /tmp/rclone168706856", Modify Window "1ns" 2021/12/23 05:57:36 DEBUG : enormous: Excluded 2021/12/23 05:57:36 DEBUG : potato2: Excluded 2021/12/23 05:57:37 DEBUG : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5: Waiting for checks to finish 2021/12/23 05:57:37 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2021/12/23 05:57:37 DEBUG : empty space: Unchanged skipping 2021/12/23 05:57:37 DEBUG : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5: Waiting for transfers to finish 2021/12/23 05:57:37 DEBUG : Waiting for deletions to finish 2021/12/23 05:57:38 INFO : potato2: Deleted 2021/12/23 05:57:38 INFO : enormous: Deleted 2021/12/23 05:57:38 INFO : There was nothing to transfer 2021/12/23 05:57:39 DEBUG : Local file system at /tmp/rclone168706856: Waiting for checks to finish 2021/12/23 05:57:39 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2021/12/23 05:57:39 DEBUG : empty space: Unchanged skipping 2021/12/23 05:57:39 DEBUG : Local file system at /tmp/rclone168706856: Waiting for transfers to finish 2021/12/23 05:57:39 DEBUG : Waiting for deletions to finish 2021/12/23 05:57:39 INFO : enormous: Deleted 2021/12/23 05:57:39 INFO : potato2: Deleted 2021/12/23 05:57:39 INFO : There was nothing to transfer --- PASS: TestSyncWithExcludeAndDeleteExcluded (13.68s) === RUN TestSyncWithUpdateOlder run.go:181: Remote "Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5", Local "Local file system at /tmp/rclone168706856", Modify Window "1ns" 2021/12/23 05:57:54 DEBUG : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5: Waiting for checks to finish 2021/12/23 05:57:54 DEBUG : three: Sizes identical 2021/12/23 05:57:54 DEBUG : three: Destination mod time is within 1s of source and files identical, skipping 2021/12/23 05:57:54 DEBUG : two: md5 = b8a9f715dbb64fd5c56e7783c6820a61 (Local file system at /tmp/rclone168706856) 2021/12/23 05:57:54 DEBUG : two: md5 = 0f82d86afa0f5dc965c5c15aca58dcfb (Swift container rclone-test-peqiyuw2qoqukop6jucerup5) 2021/12/23 05:57:54 DEBUG : two: md5 differ 2021/12/23 05:57:54 DEBUG : four: Sizes differ (src 4 vs dst 8) 2021/12/23 05:57:54 DEBUG : four: Destination mod time is within 1s of source but files differ, transferring 2021/12/23 05:57:54 DEBUG : one: Destination is newer than source, skipping 2021/12/23 05:57:54 DEBUG : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5: Waiting for transfers to finish 2021/12/23 05:57:56 DEBUG : five: md5 = 30056e1cab7a61d256fc8edd970d14f5 OK 2021/12/23 05:57:56 INFO : five: Copied (new) 2021/12/23 05:57:56 DEBUG : two: md5 = b8a9f715dbb64fd5c56e7783c6820a61 OK 2021/12/23 05:57:56 INFO : two: Copied (replaced existing) 2021/12/23 05:57:56 DEBUG : four: md5 = 8cbad96aced40b3838dd9f07f6ef5772 OK 2021/12/23 05:57:56 INFO : four: Copied (replaced existing) 2021/12/23 05:57:56 DEBUG : Waiting for deletions to finish 2021/12/23 05:57:57 DEBUG : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5: Waiting for checks to finish 2021/12/23 05:57:58 DEBUG : five: md5 = 30056e1cab7a61d256fc8edd970d14f5 OK 2021/12/23 05:57:58 DEBUG : five: Size and md5 of src and dst objects identical 2021/12/23 05:57:58 DEBUG : five: Destination mod time is within 1s of source and files identical, skipping 2021/12/23 05:57:58 DEBUG : one: Destination is newer than source, skipping 2021/12/23 05:57:58 DEBUG : two: md5 = b8a9f715dbb64fd5c56e7783c6820a61 OK 2021/12/23 05:57:58 DEBUG : two: Size and md5 of src and dst objects identical 2021/12/23 05:57:58 DEBUG : two: Destination mod time is within 1s of source and files identical, skipping 2021/12/23 05:57:58 DEBUG : three: md5 = 35d6d33467aae9a2e3dccb4b6b027878 (Local file system at /tmp/rclone168706856) 2021/12/23 05:57:58 DEBUG : three: md5 = 413af0de1f97a2155acf2b8b26ab36e2 (Swift container rclone-test-peqiyuw2qoqukop6jucerup5) 2021/12/23 05:57:58 DEBUG : three: md5 differ 2021/12/23 05:57:58 DEBUG : three: Destination mod time is within 1s of source but files differ, transferring 2021/12/23 05:57:58 DEBUG : four: md5 = 8cbad96aced40b3838dd9f07f6ef5772 OK 2021/12/23 05:57:58 DEBUG : four: Size and md5 of src and dst objects identical 2021/12/23 05:57:58 DEBUG : four: Destination mod time is within 1s of source and files identical, skipping 2021/12/23 05:57:58 DEBUG : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5: Waiting for transfers to finish 2021/12/23 05:58:00 DEBUG : three: md5 = 35d6d33467aae9a2e3dccb4b6b027878 OK 2021/12/23 05:58:00 INFO : three: Copied (replaced existing) 2021/12/23 05:58:00 DEBUG : Waiting for deletions to finish --- PASS: TestSyncWithUpdateOlder (24.60s) === RUN TestSyncWithMaxDuration sync_test.go:1009: Skipping test on non local remote --- SKIP: TestSyncWithMaxDuration (0.00s) === RUN TestSyncWithTrackRenames run.go:181: Remote "Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5", Local "Local file system at /tmp/rclone168706856", Modify Window "1ns" sync_test.go:1058: Can track renames: true 2021/12/23 05:58:08 INFO : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5: Making map for --track-renames 2021/12/23 05:58:08 INFO : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5: Finished making map for --track-renames 2021/12/23 05:58:08 DEBUG : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5: Waiting for checks to finish 2021/12/23 05:58:08 DEBUG : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5: Waiting for renames to finish 2021/12/23 05:58:08 DEBUG : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5: Waiting for transfers to finish 2021/12/23 05:58:10 DEBUG : yam: md5 = 48dc443644c4b89556dc06834b46451d OK 2021/12/23 05:58:10 INFO : yam: Copied (new) 2021/12/23 05:58:11 DEBUG : potato: md5 = 7f6fa9ddec9bfebda9510af0323fd696 OK 2021/12/23 05:58:11 INFO : potato: Copied (new) 2021/12/23 05:58:11 DEBUG : Waiting for deletions to finish 2021/12/23 05:58:12 INFO : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5: Making map for --track-renames 2021/12/23 05:58:12 DEBUG : potato: Size and modification time the same (differ by 0s, within tolerance 1ns) 2021/12/23 05:58:12 DEBUG : potato: Unchanged skipping 2021/12/23 05:58:12 INFO : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5: Finished making map for --track-renames 2021/12/23 05:58:12 DEBUG : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5: Waiting for checks to finish 2021/12/23 05:58:12 DEBUG : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5: Waiting for renames to finish 2021/12/23 05:58:14 DEBUG : yam: md5 = 48dc443644c4b89556dc06834b46451d OK 2021/12/23 05:58:14 INFO : yam: Copied (server-side copy) to: yaml 2021/12/23 05:58:15 INFO : yam: Deleted 2021/12/23 05:58:15 INFO : yaml: Renamed from "yam" 2021/12/23 05:58:15 DEBUG : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5: Waiting for transfers to finish 2021/12/23 05:58:15 DEBUG : Waiting for deletions to finish --- PASS: TestSyncWithTrackRenames (11.90s) === RUN TestParseRenamesStrategyModtime --- PASS: TestParseRenamesStrategyModtime (0.00s) === RUN TestRenamesStrategyModtime --- PASS: TestRenamesStrategyModtime (0.00s) === RUN TestSyncWithTrackRenamesStrategyModtime run.go:181: Remote "Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5", Local "Local file system at /tmp/rclone168706856", Modify Window "1ns" sync_test.go:1127: Can track renames: true 2021/12/23 05:58:19 INFO : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5: Making map for --track-renames 2021/12/23 05:58:19 INFO : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5: Finished making map for --track-renames 2021/12/23 05:58:19 DEBUG : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5: Waiting for checks to finish 2021/12/23 05:58:19 DEBUG : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5: Waiting for renames to finish 2021/12/23 05:58:19 DEBUG : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5: Waiting for transfers to finish 2021/12/23 05:58:21 DEBUG : potato: md5 = 7f6fa9ddec9bfebda9510af0323fd696 OK 2021/12/23 05:58:21 INFO : potato: Copied (new) 2021/12/23 05:58:21 DEBUG : yam: md5 = 48dc443644c4b89556dc06834b46451d OK 2021/12/23 05:58:21 INFO : yam: Copied (new) 2021/12/23 05:58:21 DEBUG : Waiting for deletions to finish 2021/12/23 05:58:22 INFO : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5: Making map for --track-renames 2021/12/23 05:58:22 INFO : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5: Finished making map for --track-renames 2021/12/23 05:58:22 DEBUG : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5: Waiting for checks to finish 2021/12/23 05:58:22 DEBUG : potato: Size and modification time the same (differ by 0s, within tolerance 1ns) 2021/12/23 05:58:22 DEBUG : potato: Unchanged skipping 2021/12/23 05:58:22 DEBUG : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5: Waiting for renames to finish 2021/12/23 05:58:24 DEBUG : yam: md5 = 48dc443644c4b89556dc06834b46451d OK 2021/12/23 05:58:24 INFO : yam: Copied (server-side copy) to: yaml 2021/12/23 05:58:25 INFO : yam: Deleted 2021/12/23 05:58:25 INFO : yaml: Renamed from "yam" 2021/12/23 05:58:25 DEBUG : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5: Waiting for transfers to finish 2021/12/23 05:58:25 DEBUG : Waiting for deletions to finish --- PASS: TestSyncWithTrackRenamesStrategyModtime (10.64s) === RUN TestSyncWithTrackRenamesStrategyLeaf run.go:181: Remote "Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5", Local "Local file system at /tmp/rclone168706856", Modify Window "1ns" sync_test.go:1163: Can track renames: true 2021/12/23 05:58:30 INFO : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5: Making map for --track-renames 2021/12/23 05:58:30 INFO : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5: Finished making map for --track-renames 2021/12/23 05:58:30 DEBUG : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5: Waiting for checks to finish 2021/12/23 05:58:30 DEBUG : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5: Waiting for renames to finish 2021/12/23 05:58:30 DEBUG : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5: Waiting for transfers to finish 2021/12/23 05:58:33 DEBUG : sub/yam: md5 = 48dc443644c4b89556dc06834b46451d OK 2021/12/23 05:58:33 INFO : sub/yam: Copied (new) 2021/12/23 05:58:33 DEBUG : potato: md5 = 7f6fa9ddec9bfebda9510af0323fd696 OK 2021/12/23 05:58:33 INFO : potato: Copied (new) 2021/12/23 05:58:33 DEBUG : Waiting for deletions to finish fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:299: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:299: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:306: Error Trace: fstest.go:306 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1171 Error: Should be true Test: TestSyncWithTrackRenamesStrategyLeaf Messages: listing wrong, want potato (14), sub/yam (11) got potato (14) fstest.go:204: Not found "sub/yam" fstest.go:207: Error Trace: fstest.go:207 fstest.go:311 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1171 Error: Not equal: expected: 0 actual : 1 Test: TestSyncWithTrackRenamesStrategyLeaf Messages: 1 objects not found 2021/12/23 05:58:41 INFO : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5: Making map for --track-renames 2021/12/23 05:58:41 INFO : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5: Finished making map for --track-renames 2021/12/23 05:58:41 DEBUG : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5: Waiting for checks to finish 2021/12/23 05:58:41 DEBUG : potato: Size and modification time the same (differ by 0s, within tolerance 1ns) 2021/12/23 05:58:41 DEBUG : potato: Unchanged skipping 2021/12/23 05:58:41 DEBUG : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5: Waiting for renames to finish 2021/12/23 05:58:41 DEBUG : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5: Waiting for transfers to finish 2021/12/23 05:58:43 DEBUG : yam: md5 = 48dc443644c4b89556dc06834b46451d OK 2021/12/23 05:58:43 INFO : yam: Copied (new) 2021/12/23 05:58:43 DEBUG : Waiting for deletions to finish sync_test.go:1185: Error Trace: sync_test.go:1185 Error: Not equal: expected: true actual : false Test: TestSyncWithTrackRenamesStrategyLeaf Messages: canTrackRenames=true, renames=0 fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:299: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:299: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:306: Error Trace: fstest.go:306 fstest.go:339 run.go:174 run.go:376 sync_test.go:1187 Error: Should be true Test: TestSyncWithTrackRenamesStrategyLeaf Messages: listing wrong, want got yam (11) fstest.go:192: Error Trace: fstest.go:192 fstest.go:309 fstest.go:339 run.go:174 run.go:376 sync_test.go:1187 Error: Should be true Test: TestSyncWithTrackRenamesStrategyLeaf Messages: Unexpected file "yam" --- FAIL: TestSyncWithTrackRenamesStrategyLeaf (23.60s) === RUN TestMoveWithDeleteEmptySrcDirs run.go:181: Remote "Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5", Local "Local file system at /tmp/rclone168706856", Modify Window "1ns" 2021/12/23 05:58:54 DEBUG : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5: Waiting for checks to finish 2021/12/23 05:58:54 DEBUG : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5: Waiting for transfers to finish 2021/12/23 05:58:55 DEBUG : nested/sub dir/file: md5 = 83d3784ea62518eafc60e98d84f877ad OK 2021/12/23 05:58:55 INFO : nested/sub dir/file: Copied (new) 2021/12/23 05:58:55 INFO : nested/sub dir/file: Deleted 2021/12/23 05:58:57 DEBUG : sub dir/hello world: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2021/12/23 05:58:57 INFO : sub dir/hello world: Copied (new) 2021/12/23 05:58:57 INFO : sub dir/hello world: Deleted 2021/12/23 05:58:57 INFO : sub dir: Removing directory 2021/12/23 05:58:57 INFO : nested/sub dir: Removing directory 2021/12/23 05:58:57 INFO : nested: Removing directory 2021/12/23 05:58:57 DEBUG : Local file system at /tmp/rclone168706856: deleted 3 directories fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:299: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:299: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:306: Error Trace: fstest.go:306 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1285 Error: Should be true Test: TestMoveWithDeleteEmptySrcDirs Messages: listing wrong, want nested/sub dir/file (6), sub dir/hello world (11) got nested/sub dir/file (6), sub dir/hello world (11), yam (11) fstest.go:192: Error Trace: fstest.go:192 fstest.go:309 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1285 Error: Should be true Test: TestMoveWithDeleteEmptySrcDirs Messages: Unexpected file "yam" run.go:126: removing file "yam" failed - try 1/3: Object Not Found run.go:126: removing file "yam" failed - try 2/3: Object Not Found run.go:126: removing file "yam" failed - try 3/3: Object Not Found run.go:129: removing file "yam" failed: Object Not Found --- FAIL: TestMoveWithDeleteEmptySrcDirs (21.90s) === RUN TestMoveWithoutDeleteEmptySrcDirs run.go:181: Remote "Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5", Local "Local file system at /tmp/rclone168706856", Modify Window "1ns" 2021/12/23 05:59:15 DEBUG : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5: Waiting for checks to finish 2021/12/23 05:59:15 DEBUG : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5: Waiting for transfers to finish 2021/12/23 05:59:17 DEBUG : nested/sub dir/file: md5 = 83d3784ea62518eafc60e98d84f877ad OK 2021/12/23 05:59:17 INFO : nested/sub dir/file: Copied (new) 2021/12/23 05:59:17 INFO : nested/sub dir/file: Deleted 2021/12/23 05:59:18 DEBUG : sub dir/hello world: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2021/12/23 05:59:18 INFO : sub dir/hello world: Copied (new) 2021/12/23 05:59:18 INFO : sub dir/hello world: Deleted fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:293: Sleeping for 6s just to make sure --- PASS: TestMoveWithoutDeleteEmptySrcDirs (14.99s) === RUN TestMoveWithIgnoreExisting run.go:181: Remote "Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5", Local "Local file system at /tmp/rclone168706856", Modify Window "1ns" 2021/12/23 05:59:30 DEBUG : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5: Waiting for checks to finish 2021/12/23 05:59:30 DEBUG : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5: Waiting for transfers to finish 2021/12/23 05:59:32 DEBUG : existing-b: md5 = 006f87892f47ef9aa60fa5ed01a440fb OK 2021/12/23 05:59:32 INFO : existing-b: Copied (new) 2021/12/23 05:59:32 INFO : existing-b: Deleted 2021/12/23 05:59:32 DEBUG : existing: md5 = 8ee2027983915ec78acc45027d874316 OK 2021/12/23 05:59:32 INFO : existing: Copied (new) 2021/12/23 05:59:32 INFO : existing: Deleted fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:299: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:299: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:306: Error Trace: fstest.go:306 fstest.go:339 run.go:362 sync_test.go:1329 Error: Should be true Test: TestMoveWithIgnoreExisting Messages: listing wrong, want existing (6), existing-b (6) got existing (6), existing-b (6), sub/yam (11) fstest.go:192: Error Trace: fstest.go:192 fstest.go:309 fstest.go:339 run.go:362 sync_test.go:1329 Error: Should be true Test: TestMoveWithIgnoreExisting Messages: Unexpected file "sub/yam" 2021/12/23 05:59:42 DEBUG : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5: Waiting for checks to finish 2021/12/23 05:59:42 DEBUG : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5: Waiting for transfers to finish 2021/12/23 05:59:44 DEBUG : existing: md5 = 7862f8c359da0df7eff16e1130e998f9 OK 2021/12/23 05:59:44 INFO : existing: Copied (new) 2021/12/23 05:59:44 INFO : existing: Deleted fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:299: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:299: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:306: Error Trace: fstest.go:306 fstest.go:339 run.go:352 sync_test.go:1344 Error: Should be true Test: TestMoveWithIgnoreExisting Messages: listing wrong, want existing (11) got fstest.go:204: Not found "existing" fstest.go:207: Error Trace: fstest.go:207 fstest.go:311 fstest.go:339 run.go:352 sync_test.go:1344 Error: Not equal: expected: 0 actual : 1 Test: TestMoveWithIgnoreExisting Messages: 1 objects not found fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:299: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:299: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:306: Error Trace: fstest.go:306 fstest.go:339 run.go:362 sync_test.go:1352 Error: Should be true Test: TestMoveWithIgnoreExisting Messages: listing wrong, want existing (6), existing-b (6) got existing (11), existing-b (6), sub/yam (11) fstest.go:144: Error Trace: fstest.go:144 fstest.go:150 fstest.go:196 fstest.go:309 fstest.go:339 run.go:362 sync_test.go:1352 Error: Should be true Test: TestMoveWithIgnoreExisting Messages: Swift container rclone-test-peqiyuw2qoqukop6jucerup5/existing: md5 hash incorrect - expecting "8ee2027983915ec78acc45027d874316" got "7862f8c359da0df7eff16e1130e998f9" fstest.go:151: Error Trace: fstest.go:151 fstest.go:196 fstest.go:309 fstest.go:339 run.go:362 sync_test.go:1352 Error: Not equal: expected: 6 actual : 11 Test: TestMoveWithIgnoreExisting Messages: existing: size incorrect file=6 vs obj=11 fstest.go:128: Error Trace: fstest.go:128 fstest.go:133 fstest.go:152 fstest.go:196 fstest.go:309 fstest.go:339 run.go:362 sync_test.go:1352 Error: Should be true Test: TestMoveWithIgnoreExisting Messages: existing: Modification time difference too big |-95456h54m52.62345679s| > 1ns (want 2001-02-03 04:05:06.499999999 +0000 UTC vs got 2011-12-25 12:59:59.123456789 +0000 UTC) (precision 1ns) fstest.go:192: Error Trace: fstest.go:192 fstest.go:309 fstest.go:339 run.go:362 sync_test.go:1352 Error: Should be true Test: TestMoveWithIgnoreExisting Messages: Unexpected file "sub/yam" --- FAIL: TestMoveWithIgnoreExisting (33.31s) === RUN TestServerSideMove run.go:181: Remote "Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5", Local "Local file system at /tmp/rclone168706856", Modify Window "1ns" 2021/12/23 06:00:04 DEBUG : Creating backend with remote "TestHubic:rclone-test-kolunep8kejicon6vumejiq9" 2021/12/23 06:00:04 DEBUG : Hubic: Got swift credentials (expiry 2021-12-24 06:50:53 +0100 +0100 in 23h50m48.676483863s) sync_test.go:1215: Server side move (if possible) Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5 -> Hubic Swift container rclone-test-kolunep8kejicon6vumejiq9 2021/12/23 06:00:11 INFO : Swift container rclone-test-kolunep8kejicon6vumejiq9: Container "rclone-test-kolunep8kejicon6vumejiq9" created 2021/12/23 06:00:14 DEBUG : Hubic Swift container rclone-test-kolunep8kejicon6vumejiq9: Waiting for checks to finish 2021/12/23 06:00:14 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2021/12/23 06:00:15 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2021/12/23 06:00:15 DEBUG : empty space: Unchanged skipping 2021/12/23 06:00:15 INFO : empty space: Deleted 2021/12/23 06:00:15 DEBUG : Hubic Swift container rclone-test-kolunep8kejicon6vumejiq9: Waiting for transfers to finish 2021/12/23 06:00:16 DEBUG : potato3: md5 = b1a53993579b830006f2e1610415c32c OK 2021/12/23 06:00:16 INFO : potato3: Copied (server-side copy) 2021/12/23 06:00:16 DEBUG : potato2: md5 = d6548b156ea68a4e003e786df99eee76 OK 2021/12/23 06:00:16 INFO : potato2: Copied (server-side copy) 2021/12/23 06:00:17 INFO : potato3: Deleted 2021/12/23 06:00:17 INFO : potato2: Deleted fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:293: Sleeping for 6s just to make sure 2021/12/23 06:00:25 DEBUG : Creating backend with remote "TestHubic:rclone-test-gesifac2wevojex8pixojuq8" 2021/12/23 06:00:26 DEBUG : Hubic: Got swift credentials (expiry 2021-12-24 06:50:53 +0100 +0100 in 23h50m26.894204422s) 2021/12/23 06:00:26 DEBUG : Hubic Swift container rclone-test-gesifac2wevojex8pixojuq8: Waiting for checks to finish 2021/12/23 06:00:26 DEBUG : Hubic Swift container rclone-test-gesifac2wevojex8pixojuq8: Waiting for transfers to finish 2021/12/23 06:00:26 INFO : Swift container rclone-test-gesifac2wevojex8pixojuq8: Container "rclone-test-gesifac2wevojex8pixojuq8" created 2021/12/23 06:00:28 DEBUG : potato3: md5 = b1a53993579b830006f2e1610415c32c OK 2021/12/23 06:00:28 INFO : potato3: Copied (server-side copy) 2021/12/23 06:00:28 DEBUG : potato2: md5 = d6548b156ea68a4e003e786df99eee76 OK 2021/12/23 06:00:28 INFO : potato2: Copied (server-side copy) 2021/12/23 06:00:29 DEBUG : empty space: md5 = 336d5ebc5436534e61d16e63ddfca327 OK 2021/12/23 06:00:29 INFO : empty space: Copied (server-side copy) 2021/12/23 06:00:29 INFO : potato3: Deleted 2021/12/23 06:00:29 INFO : potato2: Deleted 2021/12/23 06:00:30 INFO : empty space: Deleted 2021/12/23 06:00:30 DEBUG : Hubic Swift container rclone-test-gesifac2wevojex8pixojuq8: Purge remote 2021/12/23 06:00:30 DEBUG : Waiting for deletions to finish 2021/12/23 06:00:31 INFO : potato3: Deleted 2021/12/23 06:00:31 INFO : potato2: Deleted 2021/12/23 06:00:31 INFO : empty space: Deleted 2021/12/23 06:00:32 INFO : Swift container rclone-test-gesifac2wevojex8pixojuq8: Container "rclone-test-gesifac2wevojex8pixojuq8" removed 2021/12/23 06:00:32 DEBUG : Hubic Swift container rclone-test-kolunep8kejicon6vumejiq9: Purge remote 2021/12/23 06:00:32 DEBUG : Waiting for deletions to finish 2021/12/23 06:00:32 INFO : Swift container rclone-test-kolunep8kejicon6vumejiq9: Container "rclone-test-kolunep8kejicon6vumejiq9" removed --- PASS: TestServerSideMove (28.32s) === RUN TestServerSideMoveWithFilter run.go:181: Remote "Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5", Local "Local file system at /tmp/rclone168706856", Modify Window "1ns" 2021/12/23 06:00:32 DEBUG : Creating backend with remote "TestHubic:rclone-test-neragay8nabetaq5doqorad0" 2021/12/23 06:00:32 DEBUG : Hubic: Got swift credentials (expiry 2021-12-24 06:50:53 +0100 +0100 in 23h50m20.319594966s) sync_test.go:1215: Server side move (if possible) Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5 -> Hubic Swift container rclone-test-neragay8nabetaq5doqorad0 2021/12/23 06:00:39 INFO : Swift container rclone-test-neragay8nabetaq5doqorad0: Container "rclone-test-neragay8nabetaq5doqorad0" created 2021/12/23 06:00:44 DEBUG : empty space: Excluded 2021/12/23 06:00:44 DEBUG : empty space: Excluded 2021/12/23 06:00:44 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2021/12/23 06:00:44 DEBUG : Hubic Swift container rclone-test-neragay8nabetaq5doqorad0: Waiting for checks to finish 2021/12/23 06:00:44 DEBUG : Hubic Swift container rclone-test-neragay8nabetaq5doqorad0: Waiting for transfers to finish 2021/12/23 06:00:46 DEBUG : potato3: md5 = b1a53993579b830006f2e1610415c32c OK 2021/12/23 06:00:46 INFO : potato3: Copied (server-side copy) 2021/12/23 06:00:47 DEBUG : potato2: md5 = d6548b156ea68a4e003e786df99eee76 OK 2021/12/23 06:00:47 INFO : potato2: Copied (server-side copy) 2021/12/23 06:00:47 INFO : potato3: Deleted 2021/12/23 06:00:47 INFO : potato2: Deleted 2021/12/23 06:00:49 DEBUG : Creating backend with remote "TestHubic:rclone-test-zuguqeb5kilaxij8wuhomax4" 2021/12/23 06:00:49 DEBUG : Hubic: Got swift credentials (expiry 2021-12-24 06:50:53 +0100 +0100 in 23h50m3.345951111s) 2021/12/23 06:00:49 DEBUG : empty space: Excluded 2021/12/23 06:00:49 DEBUG : Hubic Swift container rclone-test-zuguqeb5kilaxij8wuhomax4: Waiting for checks to finish 2021/12/23 06:00:49 DEBUG : Hubic Swift container rclone-test-zuguqeb5kilaxij8wuhomax4: Waiting for transfers to finish 2021/12/23 06:00:50 INFO : Swift container rclone-test-zuguqeb5kilaxij8wuhomax4: Container "rclone-test-zuguqeb5kilaxij8wuhomax4" created 2021/12/23 06:00:51 DEBUG : potato3: md5 = b1a53993579b830006f2e1610415c32c OK 2021/12/23 06:00:51 INFO : potato3: Copied (server-side copy) 2021/12/23 06:00:51 DEBUG : potato2: md5 = d6548b156ea68a4e003e786df99eee76 OK 2021/12/23 06:00:51 INFO : potato2: Copied (server-side copy) 2021/12/23 06:00:52 INFO : potato3: Deleted 2021/12/23 06:00:52 INFO : potato2: Deleted 2021/12/23 06:00:53 DEBUG : Hubic Swift container rclone-test-zuguqeb5kilaxij8wuhomax4: Purge remote 2021/12/23 06:00:53 DEBUG : Waiting for deletions to finish 2021/12/23 06:00:54 INFO : potato2: Deleted 2021/12/23 06:00:54 INFO : potato3: Deleted 2021/12/23 06:00:54 DEBUG : pacer: low level retry 1/10 (error Container Not Empty) 2021/12/23 06:00:54 DEBUG : pacer: Rate limited, increasing sleep to 10ms 2021/12/23 06:00:54 DEBUG : pacer: low level retry 2/10 (error Container Not Empty) 2021/12/23 06:00:54 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2021/12/23 06:00:54 DEBUG : pacer: low level retry 3/10 (error Container Not Empty) 2021/12/23 06:00:54 DEBUG : pacer: Rate limited, increasing sleep to 40ms 2021/12/23 06:00:55 DEBUG : pacer: low level retry 4/10 (error Container Not Empty) 2021/12/23 06:00:55 DEBUG : pacer: Rate limited, increasing sleep to 80ms 2021/12/23 06:00:55 DEBUG : pacer: low level retry 5/10 (error Container Not Empty) 2021/12/23 06:00:55 DEBUG : pacer: Rate limited, increasing sleep to 160ms 2021/12/23 06:00:55 DEBUG : pacer: Reducing sleep to 120ms 2021/12/23 06:00:55 purge failed: Container Not Found 2021/12/23 06:00:55 DEBUG : Hubic Swift container rclone-test-neragay8nabetaq5doqorad0: Purge remote 2021/12/23 06:00:55 DEBUG : Waiting for deletions to finish 2021/12/23 06:00:56 INFO : empty space: Deleted 2021/12/23 06:00:57 INFO : Swift container rclone-test-neragay8nabetaq5doqorad0: Container "rclone-test-neragay8nabetaq5doqorad0" removed fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:293: Sleeping for 6s just to make sure --- PASS: TestServerSideMoveWithFilter (34.18s) === RUN TestServerSideMoveDeleteEmptySourceDirs run.go:181: Remote "Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5", Local "Local file system at /tmp/rclone168706856", Modify Window "1ns" 2021/12/23 06:01:06 DEBUG : Creating backend with remote "TestHubic:rclone-test-wajoqod9buvoqon2kozixim8" 2021/12/23 06:01:06 DEBUG : Hubic: Got swift credentials (expiry 2021-12-24 06:50:53 +0100 +0100 in 23h49m46.080073788s) 2021/12/23 06:01:14 DEBUG : tomatoDir: Making directory fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:299: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:299: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:306: Error Trace: fstest.go:306 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1213 sync_test.go:1389 Error: Should be true Test: TestServerSideMoveDeleteEmptySourceDirs Messages: listing wrong, want empty space (1), potato2 (60), potato3 (68) got empty space (1), potato2 (60) fstest.go:204: Not found "potato3" fstest.go:207: Error Trace: fstest.go:207 fstest.go:311 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1213 sync_test.go:1389 Error: Not equal: expected: 0 actual : 1 Test: TestServerSideMoveDeleteEmptySourceDirs Messages: 1 objects not found sync_test.go:1215: Server side move (if possible) Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5 -> Hubic Swift container rclone-test-wajoqod9buvoqon2kozixim8 2021/12/23 06:01:22 INFO : Swift container rclone-test-wajoqod9buvoqon2kozixim8: Container "rclone-test-wajoqod9buvoqon2kozixim8" created 2021/12/23 06:01:25 DEBUG : Hubic Swift container rclone-test-wajoqod9buvoqon2kozixim8: Waiting for checks to finish 2021/12/23 06:01:25 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2021/12/23 06:01:26 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2021/12/23 06:01:26 DEBUG : empty space: Unchanged skipping 2021/12/23 06:01:26 INFO : empty space: Deleted 2021/12/23 06:01:26 DEBUG : Hubic Swift container rclone-test-wajoqod9buvoqon2kozixim8: Waiting for transfers to finish 2021/12/23 06:01:27 DEBUG : potato2: md5 = d6548b156ea68a4e003e786df99eee76 OK 2021/12/23 06:01:27 INFO : potato2: Copied (server-side copy) 2021/12/23 06:01:27 DEBUG : potato3: md5 = b1a53993579b830006f2e1610415c32c OK 2021/12/23 06:01:27 INFO : potato3: Copied (server-side copy) 2021/12/23 06:01:28 INFO : potato2: Deleted 2021/12/23 06:01:28 INFO : potato3: Deleted 2021/12/23 06:01:30 DEBUG : Creating backend with remote "TestHubic:rclone-test-toviwab5mefokec6damemom3" 2021/12/23 06:01:30 DEBUG : Hubic: Got swift credentials (expiry 2021-12-24 06:50:53 +0100 +0100 in 23h49m22.210709645s) 2021/12/23 06:01:30 DEBUG : tomatoDir: Making directory 2021/12/23 06:01:31 DEBUG : Hubic Swift container rclone-test-toviwab5mefokec6damemom3: Waiting for checks to finish 2021/12/23 06:01:31 DEBUG : Hubic Swift container rclone-test-toviwab5mefokec6damemom3: Waiting for transfers to finish 2021/12/23 06:01:32 INFO : Swift container rclone-test-toviwab5mefokec6damemom3: Container "rclone-test-toviwab5mefokec6damemom3" created 2021/12/23 06:01:34 DEBUG : empty space: md5 = 336d5ebc5436534e61d16e63ddfca327 OK 2021/12/23 06:01:34 INFO : empty space: Copied (server-side copy) 2021/12/23 06:01:34 DEBUG : potato3: md5 = b1a53993579b830006f2e1610415c32c OK 2021/12/23 06:01:34 INFO : potato3: Copied (server-side copy) 2021/12/23 06:01:34 DEBUG : potato2: md5 = d6548b156ea68a4e003e786df99eee76 OK 2021/12/23 06:01:34 INFO : potato2: Copied (server-side copy) 2021/12/23 06:01:35 INFO : empty space: Deleted 2021/12/23 06:01:35 INFO : potato3: Deleted 2021/12/23 06:01:35 INFO : potato2: Deleted 2021/12/23 06:01:36 DEBUG : Hubic Swift container rclone-test-toviwab5mefokec6damemom3: Purge remote 2021/12/23 06:01:36 DEBUG : Waiting for deletions to finish 2021/12/23 06:01:37 INFO : empty space: Deleted 2021/12/23 06:01:37 INFO : potato2: Deleted 2021/12/23 06:01:37 INFO : potato3: Deleted 2021/12/23 06:01:37 INFO : Swift container rclone-test-toviwab5mefokec6damemom3: Container "rclone-test-toviwab5mefokec6damemom3" removed 2021/12/23 06:01:37 DEBUG : Hubic Swift container rclone-test-wajoqod9buvoqon2kozixim8: Purge remote 2021/12/23 06:01:37 DEBUG : Waiting for deletions to finish 2021/12/23 06:01:38 INFO : Swift container rclone-test-wajoqod9buvoqon2kozixim8: Container "rclone-test-wajoqod9buvoqon2kozixim8" removed --- FAIL: TestServerSideMoveDeleteEmptySourceDirs (32.26s) === RUN TestServerSideMoveOverlap run.go:181: Remote "Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5", Local "Local file system at /tmp/rclone168706856", Modify Window "1ns" 2021/12/23 06:01:38 DEBUG : Creating backend with remote "TestHubic:rclone-test-peqiyuw2qoqukop6jucerup5/rclone-move-test" 2021/12/23 06:01:39 DEBUG : Hubic: Got swift credentials (expiry 2021-12-24 06:50:53 +0100 +0100 in 23h49m13.962303822s) --- PASS: TestServerSideMoveOverlap (4.91s) === RUN TestSyncOverlap run.go:181: Remote "Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5", Local "Local file system at /tmp/rclone168706856", Modify Window "1ns" 2021/12/23 06:01:43 DEBUG : Creating backend with remote "TestHubic:rclone-test-peqiyuw2qoqukop6jucerup5/rclone-sync-test" 2021/12/23 06:01:44 DEBUG : Hubic: Got swift credentials (expiry 2021-12-24 06:50:53 +0100 +0100 in 23h49m8.983147646s) --- PASS: TestSyncOverlap (1.16s) === RUN TestSyncCompareDest run.go:181: Remote "Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5", Local "Local file system at /tmp/rclone168706856", Modify Window "1ns" 2021/12/23 06:01:44 DEBUG : Creating backend with remote "TestHubic:rclone-test-peqiyuw2qoqukop6jucerup5/dst" 2021/12/23 06:01:45 DEBUG : Hubic: Got swift credentials (expiry 2021-12-24 06:50:53 +0100 +0100 in 23h49m7.893541903s) 2021/12/23 06:01:45 DEBUG : Creating backend with remote "TestHubic:rclone-test-peqiyuw2qoqukop6jucerup5/CompareDest" 2021/12/23 06:01:45 DEBUG : Hubic: Got swift credentials (expiry 2021-12-24 06:50:53 +0100 +0100 in 23h49m7.276552482s) 2021/12/23 06:01:47 DEBUG : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5 path dst: Waiting for checks to finish 2021/12/23 06:01:47 DEBUG : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5 path dst: Waiting for transfers to finish 2021/12/23 06:01:51 DEBUG : one: md5 = f97c5d29941bfb1b2fdab0874906ab82 OK 2021/12/23 06:01:51 INFO : one: Copied (new) 2021/12/23 06:01:51 DEBUG : Waiting for deletions to finish 2021/12/23 06:01:52 DEBUG : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5 path dst: Waiting for checks to finish 2021/12/23 06:01:52 DEBUG : one: Sizes differ (src 5 vs dst 3) 2021/12/23 06:01:52 DEBUG : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5 path dst: Waiting for transfers to finish 2021/12/23 06:01:54 DEBUG : one: md5 = 07912d142f5d63ee918b34796b5a2432 OK 2021/12/23 06:01:54 INFO : one: Copied (replaced existing) 2021/12/23 06:01:54 DEBUG : Waiting for deletions to finish 2021/12/23 06:02:03 DEBUG : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5 path dst: Waiting for checks to finish 2021/12/23 06:02:03 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2021/12/23 06:02:03 DEBUG : one: Destination found in --compare-dest, skipping 2021/12/23 06:02:03 DEBUG : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5 path dst: Waiting for transfers to finish 2021/12/23 06:02:03 DEBUG : Waiting for deletions to finish 2021/12/23 06:02:03 INFO : There was nothing to transfer 2021/12/23 06:02:07 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2021/12/23 06:02:07 DEBUG : two: Destination found in --compare-dest, skipping 2021/12/23 06:02:07 DEBUG : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5 path dst: Waiting for checks to finish 2021/12/23 06:02:07 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2021/12/23 06:02:07 DEBUG : one: Destination found in --compare-dest, skipping 2021/12/23 06:02:07 DEBUG : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5 path dst: Waiting for transfers to finish 2021/12/23 06:02:07 DEBUG : Waiting for deletions to finish 2021/12/23 06:02:07 INFO : There was nothing to transfer 2021/12/23 06:02:09 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2021/12/23 06:02:09 DEBUG : two: Destination found in --compare-dest, skipping 2021/12/23 06:02:09 DEBUG : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5 path dst: Waiting for checks to finish 2021/12/23 06:02:09 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2021/12/23 06:02:09 DEBUG : one: Destination found in --compare-dest, skipping 2021/12/23 06:02:09 DEBUG : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5 path dst: Waiting for transfers to finish 2021/12/23 06:02:09 DEBUG : Waiting for deletions to finish 2021/12/23 06:02:09 INFO : There was nothing to transfer 2021/12/23 06:02:11 DEBUG : two: Modification times differ by -119h59m59.876543211s: 2011-12-30 12:59:59 +0000 UTC, 2011-12-25 12:59:59.123456789 +0000 UTC 2021/12/23 06:02:11 DEBUG : two: md5 = b8a9f715dbb64fd5c56e7783c6820a61 OK 2021/12/23 06:02:11 DEBUG : two: Destination found in --compare-dest, skipping 2021/12/23 06:02:11 DEBUG : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5 path dst: Waiting for checks to finish 2021/12/23 06:02:11 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2021/12/23 06:02:11 DEBUG : one: Destination found in --compare-dest, skipping 2021/12/23 06:02:11 DEBUG : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5 path dst: Waiting for transfers to finish 2021/12/23 06:02:11 DEBUG : Waiting for deletions to finish 2021/12/23 06:02:11 INFO : There was nothing to transfer 2021/12/23 06:02:13 DEBUG : two: Sizes differ (src 5 vs dst 3) 2021/12/23 06:02:13 DEBUG : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5 path dst: Waiting for checks to finish 2021/12/23 06:02:13 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2021/12/23 06:02:13 DEBUG : one: Destination found in --compare-dest, skipping 2021/12/23 06:02:13 DEBUG : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5 path dst: Waiting for transfers to finish 2021/12/23 06:02:16 DEBUG : two: md5 = 2379e4ce8c3380e996ab0509f17069ad OK 2021/12/23 06:02:16 INFO : two: Copied (new) 2021/12/23 06:02:16 DEBUG : Waiting for deletions to finish --- PASS: TestSyncCompareDest (36.40s) === RUN TestSyncMultipleCompareDest run.go:181: Remote "Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5", Local "Local file system at /tmp/rclone168706856", Modify Window "1ns" 2021/12/23 06:02:26 DEBUG : Creating backend with remote "TestHubic:rclone-test-peqiyuw2qoqukop6jucerup5/dest" 2021/12/23 06:02:26 DEBUG : Config file has changed externaly - reloading 2021/12/23 06:02:26 DEBUG : Hubic: Got swift credentials (expiry 2021-12-24 06:50:53 +0100 +0100 in 23h48m26.142520718s) 2021/12/23 06:02:27 DEBUG : Creating backend with remote "TestHubic:rclone-test-peqiyuw2qoqukop6jucerup5/pre-dest1" 2021/12/23 06:02:27 DEBUG : Hubic: Got swift credentials (expiry 2021-12-24 06:50:53 +0100 +0100 in 23h48m25.460990613s) 2021/12/23 06:02:27 DEBUG : Creating backend with remote "TestHubic:rclone-test-peqiyuw2qoqukop6jucerup5/pre-dest2" 2021/12/23 06:02:28 DEBUG : Hubic: Got swift credentials (expiry 2021-12-24 06:50:53 +0100 +0100 in 23h48m24.907663429s) 2021/12/23 06:02:28 DEBUG : 1: Size and modification time the same (differ by 0s, within tolerance 1ns) 2021/12/23 06:02:28 DEBUG : 1: Destination found in --compare-dest, skipping 2021/12/23 06:02:28 DEBUG : 2: Size and modification time the same (differ by 0s, within tolerance 1ns) 2021/12/23 06:02:28 DEBUG : 2: Destination found in --compare-dest, skipping 2021/12/23 06:02:29 DEBUG : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5 path dest: Waiting for checks to finish 2021/12/23 06:02:29 DEBUG : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5 path dest: Waiting for transfers to finish 2021/12/23 06:02:31 DEBUG : 3: md5 = eccbc87e4b5ce2fe28308fd9f2a7baf3 OK 2021/12/23 06:02:31 INFO : 3: Copied (new) 2021/12/23 06:02:31 DEBUG : Waiting for deletions to finish --- PASS: TestSyncMultipleCompareDest (14.45s) === RUN TestSyncCopyDest run.go:181: Remote "Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5", Local "Local file system at /tmp/rclone168706856", Modify Window "1ns" 2021/12/23 06:02:35 DEBUG : Creating backend with remote "TestHubic:rclone-test-peqiyuw2qoqukop6jucerup5/dst" 2021/12/23 06:02:35 DEBUG : Hubic: Got swift credentials (expiry 2021-12-24 06:50:53 +0100 +0100 in 23h48m17.061414196s) 2021/12/23 06:02:36 DEBUG : Creating backend with remote "TestHubic:rclone-test-peqiyuw2qoqukop6jucerup5/CopyDest" 2021/12/23 06:02:36 DEBUG : Hubic: Got swift credentials (expiry 2021-12-24 06:50:53 +0100 +0100 in 23h48m16.56076413s) 2021/12/23 06:02:37 DEBUG : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5 path dst: Waiting for checks to finish 2021/12/23 06:02:37 DEBUG : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5 path dst: Waiting for transfers to finish 2021/12/23 06:02:40 DEBUG : one: md5 = f97c5d29941bfb1b2fdab0874906ab82 OK 2021/12/23 06:02:40 INFO : one: Copied (new) 2021/12/23 06:02:40 DEBUG : Waiting for deletions to finish fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:293: Sleeping for 6s just to make sure 2021/12/23 06:02:48 DEBUG : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5 path dst: Waiting for checks to finish 2021/12/23 06:02:48 DEBUG : one: Sizes differ (src 5 vs dst 3) 2021/12/23 06:02:48 DEBUG : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5 path dst: Waiting for transfers to finish 2021/12/23 06:02:51 DEBUG : one: md5 = 07912d142f5d63ee918b34796b5a2432 OK 2021/12/23 06:02:51 INFO : one: Copied (replaced existing) 2021/12/23 06:02:51 DEBUG : Waiting for deletions to finish fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:293: Sleeping for 6s just to make sure 2021/12/23 06:03:06 DEBUG : Creating backend with remote "TestHubic:rclone-test-peqiyuw2qoqukop6jucerup5/BackupDir" 2021/12/23 06:03:06 DEBUG : Hubic: Got swift credentials (expiry 2021-12-24 06:50:53 +0100 +0100 in 23h47m46.765928808s) 2021/12/23 06:03:08 DEBUG : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5 path dst: Waiting for checks to finish 2021/12/23 06:03:08 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2021/12/23 06:03:08 DEBUG : one: Modification times differ by -95456h54m52.62345679s: 2011-12-25 12:59:59.123456789 +0000 UTC, 2001-02-03 04:05:06.499999999 +0000 UTC 2021/12/23 06:03:08 DEBUG : one: md5 = 07912d142f5d63ee918b34796b5a2432 (Local file system at /tmp/rclone168706856) 2021/12/23 06:03:08 DEBUG : one: md5 = f97c5d29941bfb1b2fdab0874906ab82 (Swift container rclone-test-peqiyuw2qoqukop6jucerup5 path dst) 2021/12/23 06:03:08 DEBUG : one: md5 differ 2021/12/23 06:03:08 INFO : Swift container rclone-test-peqiyuw2qoqukop6jucerup5 path BackupDir: Container "rclone-test-peqiyuw2qoqukop6jucerup5" created 2021/12/23 06:03:11 DEBUG : one: md5 = f97c5d29941bfb1b2fdab0874906ab82 OK 2021/12/23 06:03:11 INFO : one: Copied (server-side copy) 2021/12/23 06:03:12 INFO : one: Deleted 2021/12/23 06:03:14 DEBUG : one: md5 = 07912d142f5d63ee918b34796b5a2432 OK 2021/12/23 06:03:14 INFO : one: Copied (server-side copy) 2021/12/23 06:03:14 DEBUG : one: Destination found in --copy-dest, using server-side copy 2021/12/23 06:03:14 DEBUG : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5 path dst: Waiting for transfers to finish 2021/12/23 06:03:14 DEBUG : Waiting for deletions to finish 2021/12/23 06:03:18 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2021/12/23 06:03:20 DEBUG : two: md5 = b8a9f715dbb64fd5c56e7783c6820a61 OK 2021/12/23 06:03:20 INFO : two: Copied (server-side copy) 2021/12/23 06:03:20 DEBUG : two: Destination found in --copy-dest, using server-side copy 2021/12/23 06:03:20 DEBUG : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5 path dst: Waiting for checks to finish 2021/12/23 06:03:20 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2021/12/23 06:03:20 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2021/12/23 06:03:20 DEBUG : one: Unchanged skipping 2021/12/23 06:03:20 DEBUG : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5 path dst: Waiting for transfers to finish 2021/12/23 06:03:20 DEBUG : Waiting for deletions to finish 2021/12/23 06:03:24 DEBUG : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5 path dst: Waiting for checks to finish 2021/12/23 06:03:24 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2021/12/23 06:03:24 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2021/12/23 06:03:24 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2021/12/23 06:03:24 DEBUG : one: Unchanged skipping 2021/12/23 06:03:24 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2021/12/23 06:03:24 DEBUG : two: Unchanged skipping 2021/12/23 06:03:24 DEBUG : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5 path dst: Waiting for transfers to finish 2021/12/23 06:03:24 DEBUG : Waiting for deletions to finish 2021/12/23 06:03:24 INFO : There was nothing to transfer 2021/12/23 06:03:29 DEBUG : three: Sizes differ (src 7 vs dst 5) 2021/12/23 06:03:29 DEBUG : three: Destination not found in --copy-dest 2021/12/23 06:03:29 DEBUG : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5 path dst: Waiting for checks to finish 2021/12/23 06:03:29 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2021/12/23 06:03:29 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2021/12/23 06:03:29 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2021/12/23 06:03:29 DEBUG : two: Unchanged skipping 2021/12/23 06:03:29 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2021/12/23 06:03:29 DEBUG : one: Unchanged skipping 2021/12/23 06:03:29 DEBUG : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5 path dst: Waiting for transfers to finish 2021/12/23 06:03:33 DEBUG : three: md5 = 1bccb9dccb3e9f6a3f9d2a8bdb54b7f5 OK 2021/12/23 06:03:33 INFO : three: Copied (new) 2021/12/23 06:03:33 DEBUG : Waiting for deletions to finish fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:299: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:293: Sleeping for 7s just to make sure --- PASS: TestSyncCopyDest (76.17s) === RUN TestSyncBackupDir run.go:181: Remote "Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5", Local "Local file system at /tmp/rclone168706856", Modify Window "1ns" fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:299: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:299: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:306: Error Trace: fstest.go:306 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1732 sync_test.go:1781 Error: Should be true Test: TestSyncBackupDir Messages: listing wrong, want dst/one (3), dst/three.txt (5), dst/two (3) got dst/three.txt (5) fstest.go:204: Not found "dst/one" fstest.go:204: Not found "dst/two" fstest.go:207: Error Trace: fstest.go:207 fstest.go:311 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1732 sync_test.go:1781 Error: Not equal: expected: 0 actual : 2 Test: TestSyncBackupDir Messages: 2 objects not found 2021/12/23 06:04:07 DEBUG : Creating backend with remote "TestHubic:rclone-test-peqiyuw2qoqukop6jucerup5/dst" 2021/12/23 06:04:07 DEBUG : Hubic: Got swift credentials (expiry 2021-12-24 06:50:53 +0100 +0100 in 23h46m45.472353599s) 2021/12/23 06:04:07 DEBUG : Creating backend with remote "TestHubic:rclone-test-peqiyuw2qoqukop6jucerup5/backup" 2021/12/23 06:04:07 DEBUG : Hubic: Got swift credentials (expiry 2021-12-24 06:50:53 +0100 +0100 in 23h46m45.001808348s) 2021/12/23 06:04:08 DEBUG : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5 path dst: Waiting for checks to finish 2021/12/23 06:04:08 DEBUG : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5 path dst: Waiting for transfers to finish 2021/12/23 06:04:10 DEBUG : two: md5 = b8a9f715dbb64fd5c56e7783c6820a61 OK 2021/12/23 06:04:10 INFO : two: Copied (new) 2021/12/23 06:04:10 DEBUG : one: md5 = c7957179c41f69d44f217a108c7915d8 OK 2021/12/23 06:04:10 INFO : one: Copied (new) 2021/12/23 06:04:10 DEBUG : Waiting for deletions to finish 2021/12/23 06:04:11 INFO : Swift container rclone-test-peqiyuw2qoqukop6jucerup5 path backup: Container "rclone-test-peqiyuw2qoqukop6jucerup5" created 2021/12/23 06:04:13 DEBUG : three.txt: md5 = 35d6d33467aae9a2e3dccb4b6b027878 OK 2021/12/23 06:04:13 INFO : three.txt: Copied (server-side copy) 2021/12/23 06:04:14 INFO : three.txt: Deleted 2021/12/23 06:04:14 INFO : three.txt: Moved into backup dir fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:299: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:299: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:306: Error Trace: fstest.go:306 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1753 sync_test.go:1781 Error: Should be true Test: TestSyncBackupDir Messages: listing wrong, want backup/one (3), backup/three.txt (5), dst/one (4), dst/two (3) got backup/three.txt (5), dst/one (4), dst/two (3) fstest.go:204: Not found "backup/one" fstest.go:207: Error Trace: fstest.go:207 fstest.go:311 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1753 sync_test.go:1781 Error: Not equal: expected: 0 actual : 1 Test: TestSyncBackupDir Messages: 1 objects not found fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:299: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:299: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:306: Error Trace: fstest.go:306 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1759 sync_test.go:1781 Error: Should be true Test: TestSyncBackupDir Messages: listing wrong, want backup/one (3), backup/three.txt (5), dst/one (4), dst/three.txt (6), dst/two (3) got backup/three.txt (5), dst/one (4), dst/three.txt (6), dst/two (3) fstest.go:204: Not found "backup/one" fstest.go:207: Error Trace: fstest.go:207 fstest.go:311 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1759 sync_test.go:1781 Error: Not equal: expected: 0 actual : 1 Test: TestSyncBackupDir Messages: 1 objects not found 2021/12/23 06:04:37 DEBUG : one: Sizes differ (src 5 vs dst 4) 2021/12/23 06:04:37 DEBUG : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5 path dst: Waiting for checks to finish 2021/12/23 06:04:37 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2021/12/23 06:04:37 DEBUG : two: Unchanged skipping 2021/12/23 06:04:39 DEBUG : one: md5 = c7957179c41f69d44f217a108c7915d8 OK 2021/12/23 06:04:39 INFO : one: Copied (server-side copy) 2021/12/23 06:04:40 INFO : one: Deleted 2021/12/23 06:04:40 DEBUG : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5 path dst: Waiting for transfers to finish 2021/12/23 06:04:44 DEBUG : one: md5 = 0f93e81041f0cab37c37a05ae998b219 OK 2021/12/23 06:04:44 INFO : one: Copied (new) 2021/12/23 06:04:44 DEBUG : Waiting for deletions to finish 2021/12/23 06:04:46 DEBUG : three.txt: md5 = 91341eed84691a83caea73aa785736d5 OK 2021/12/23 06:04:46 INFO : three.txt: Copied (server-side copy) 2021/12/23 06:04:47 INFO : three.txt: Deleted 2021/12/23 06:04:47 INFO : three.txt: Moved into backup dir --- FAIL: TestSyncBackupDir (62.08s) === RUN TestSyncBackupDirWithSuffix run.go:181: Remote "Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5", Local "Local file system at /tmp/rclone168706856", Modify Window "1ns" fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:299: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:299: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:306: Error Trace: fstest.go:306 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1732 sync_test.go:1784 Error: Should be true Test: TestSyncBackupDirWithSuffix Messages: listing wrong, want dst/one (3), dst/three.txt (5), dst/two (3) got dst/three.txt (5), dst/two (3) fstest.go:204: Not found "dst/one" fstest.go:207: Error Trace: fstest.go:207 fstest.go:311 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1732 sync_test.go:1784 Error: Not equal: expected: 0 actual : 1 Test: TestSyncBackupDirWithSuffix Messages: 1 objects not found 2021/12/23 06:05:08 DEBUG : Creating backend with remote "TestHubic:rclone-test-peqiyuw2qoqukop6jucerup5/dst" 2021/12/23 06:05:08 DEBUG : Hubic: Got swift credentials (expiry 2021-12-24 06:50:53 +0100 +0100 in 23h45m44.776349067s) 2021/12/23 06:05:08 DEBUG : Creating backend with remote "TestHubic:rclone-test-peqiyuw2qoqukop6jucerup5/backup" 2021/12/23 06:05:08 DEBUG : Hubic: Got swift credentials (expiry 2021-12-24 06:50:53 +0100 +0100 in 23h45m44.150600752s) 2021/12/23 06:05:10 DEBUG : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5 path dst: Waiting for checks to finish 2021/12/23 06:05:10 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2021/12/23 06:05:10 DEBUG : two: Unchanged skipping 2021/12/23 06:05:10 DEBUG : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5 path dst: Waiting for transfers to finish 2021/12/23 06:05:13 DEBUG : one: md5 = c7957179c41f69d44f217a108c7915d8 OK 2021/12/23 06:05:13 INFO : one: Copied (new) 2021/12/23 06:05:13 DEBUG : Waiting for deletions to finish 2021/12/23 06:05:13 INFO : Swift container rclone-test-peqiyuw2qoqukop6jucerup5 path backup: Container "rclone-test-peqiyuw2qoqukop6jucerup5" created 2021/12/23 06:05:16 DEBUG : three.txt: md5 = 35d6d33467aae9a2e3dccb4b6b027878 OK 2021/12/23 06:05:16 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2021/12/23 06:05:17 INFO : three.txt: Deleted 2021/12/23 06:05:17 INFO : three.txt: Moved into backup dir fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:299: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:299: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:306: Error Trace: fstest.go:306 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1753 sync_test.go:1784 Error: Should be true Test: TestSyncBackupDirWithSuffix Messages: listing wrong, want backup/one.bak (3), backup/three.txt.bak (5), dst/one (4), dst/two (3) got backup/three.txt.bak (5), dst/two (3) fstest.go:204: Not found "backup/one.bak" fstest.go:204: Not found "dst/one" fstest.go:207: Error Trace: fstest.go:207 fstest.go:311 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1753 sync_test.go:1784 Error: Not equal: expected: 0 actual : 2 Test: TestSyncBackupDirWithSuffix Messages: 2 objects not found fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:299: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:299: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:306: Error Trace: fstest.go:306 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1759 sync_test.go:1784 Error: Should be true Test: TestSyncBackupDirWithSuffix Messages: listing wrong, want backup/one.bak (3), backup/three.txt.bak (5), dst/one (4), dst/three.txt (6), dst/two (3) got backup/three.txt.bak (5), dst/three.txt (6), dst/two (3) fstest.go:204: Not found "dst/one" fstest.go:204: Not found "backup/one.bak" fstest.go:207: Error Trace: fstest.go:207 fstest.go:311 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1759 sync_test.go:1784 Error: Not equal: expected: 0 actual : 2 Test: TestSyncBackupDirWithSuffix Messages: 2 objects not found 2021/12/23 06:05:36 DEBUG : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5 path dst: Waiting for checks to finish 2021/12/23 06:05:36 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2021/12/23 06:05:36 DEBUG : two: Unchanged skipping 2021/12/23 06:05:36 DEBUG : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5 path dst: Waiting for transfers to finish 2021/12/23 06:05:38 DEBUG : one: md5 = 0f93e81041f0cab37c37a05ae998b219 OK 2021/12/23 06:05:38 INFO : one: Copied (new) 2021/12/23 06:05:38 DEBUG : Waiting for deletions to finish 2021/12/23 06:05:40 DEBUG : three.txt: md5 = 91341eed84691a83caea73aa785736d5 OK 2021/12/23 06:05:40 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2021/12/23 06:05:41 INFO : three.txt: Deleted 2021/12/23 06:05:41 INFO : three.txt: Moved into backup dir fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:299: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:299: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:306: Error Trace: fstest.go:306 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1778 sync_test.go:1784 Error: Should be true Test: TestSyncBackupDirWithSuffix Messages: listing wrong, want backup/one.bak (4), backup/three.txt.bak (6), dst/one (5), dst/two (3) got backup/three.txt.bak (6), dst/one (5), dst/two (3) fstest.go:204: Not found "backup/one.bak" fstest.go:207: Error Trace: fstest.go:207 fstest.go:311 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1778 sync_test.go:1784 Error: Not equal: expected: 0 actual : 1 Test: TestSyncBackupDirWithSuffix Messages: 1 objects not found --- FAIL: TestSyncBackupDirWithSuffix (59.20s) === RUN TestSyncBackupDirWithSuffixKeepExtension run.go:181: Remote "Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5", Local "Local file system at /tmp/rclone168706856", Modify Window "1ns" 2021/12/23 06:06:00 DEBUG : Creating backend with remote "TestHubic:rclone-test-peqiyuw2qoqukop6jucerup5/dst" 2021/12/23 06:06:00 DEBUG : Hubic: Got swift credentials (expiry 2021-12-24 06:50:53 +0100 +0100 in 23h44m52.576063829s) 2021/12/23 06:06:00 DEBUG : Creating backend with remote "TestHubic:rclone-test-peqiyuw2qoqukop6jucerup5/backup" 2021/12/23 06:06:00 DEBUG : Hubic: Got swift credentials (expiry 2021-12-24 06:50:53 +0100 +0100 in 23h44m52.115752032s) 2021/12/23 06:06:01 DEBUG : one: Sizes differ (src 4 vs dst 3) 2021/12/23 06:06:01 DEBUG : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5 path dst: Waiting for checks to finish 2021/12/23 06:06:01 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2021/12/23 06:06:01 DEBUG : two: Unchanged skipping 2021/12/23 06:06:02 INFO : Swift container rclone-test-peqiyuw2qoqukop6jucerup5 path backup: Container "rclone-test-peqiyuw2qoqukop6jucerup5" created 2021/12/23 06:06:04 DEBUG : one: md5 = f97c5d29941bfb1b2fdab0874906ab82 OK 2021/12/23 06:06:04 INFO : one: Copied (server-side copy) to: one-2019-01-01 2021/12/23 06:06:05 INFO : one: Deleted 2021/12/23 06:06:05 DEBUG : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5 path dst: Waiting for transfers to finish 2021/12/23 06:06:07 DEBUG : one: md5 = c7957179c41f69d44f217a108c7915d8 OK 2021/12/23 06:06:07 INFO : one: Copied (new) 2021/12/23 06:06:07 DEBUG : Waiting for deletions to finish 2021/12/23 06:06:09 DEBUG : three.txt: md5 = 35d6d33467aae9a2e3dccb4b6b027878 OK 2021/12/23 06:06:09 INFO : three.txt: Copied (server-side copy) to: three-2019-01-01.txt 2021/12/23 06:06:10 INFO : three.txt: Deleted 2021/12/23 06:06:10 INFO : three.txt: Moved into backup dir fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:299: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:299: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:306: Error Trace: fstest.go:306 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1759 sync_test.go:1787 Error: Should be true Test: TestSyncBackupDirWithSuffixKeepExtension Messages: listing wrong, want backup/one-2019-01-01 (3), backup/three-2019-01-01.txt (5), dst/one (4), dst/three.txt (6), dst/two (3) got backup/one-2019-01-01 (3), backup/three-2019-01-01.txt (5), dst/one (4), dst/two (3) fstest.go:204: Not found "dst/three.txt" fstest.go:207: Error Trace: fstest.go:207 fstest.go:311 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1759 sync_test.go:1787 Error: Not equal: expected: 0 actual : 1 Test: TestSyncBackupDirWithSuffixKeepExtension Messages: 1 objects not found 2021/12/23 06:06:21 DEBUG : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5 path dst: Waiting for checks to finish 2021/12/23 06:06:21 DEBUG : one: Sizes differ (src 5 vs dst 4) 2021/12/23 06:06:21 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2021/12/23 06:06:21 DEBUG : two: Unchanged skipping 2021/12/23 06:06:23 DEBUG : one: md5 = c7957179c41f69d44f217a108c7915d8 OK 2021/12/23 06:06:23 INFO : one: Copied (server-side copy) to: one-2019-01-01 2021/12/23 06:06:24 INFO : one: Deleted 2021/12/23 06:06:24 DEBUG : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5 path dst: Waiting for transfers to finish 2021/12/23 06:06:26 DEBUG : one: md5 = 0f93e81041f0cab37c37a05ae998b219 OK 2021/12/23 06:06:26 INFO : one: Copied (new) 2021/12/23 06:06:26 DEBUG : Waiting for deletions to finish fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:299: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:299: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:306: Error Trace: fstest.go:306 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1778 sync_test.go:1787 Error: Should be true Test: TestSyncBackupDirWithSuffixKeepExtension Messages: listing wrong, want backup/one-2019-01-01 (4), backup/three-2019-01-01.txt (6), dst/one (5), dst/two (3) got backup/one-2019-01-01 (4), backup/three-2019-01-01.txt (5), dst/one (5), dst/two (3) fstest.go:144: Error Trace: fstest.go:144 fstest.go:150 fstest.go:196 fstest.go:309 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1778 sync_test.go:1787 Error: Should be true Test: TestSyncBackupDirWithSuffixKeepExtension Messages: Swift container rclone-test-peqiyuw2qoqukop6jucerup5/backup/three-2019-01-01.txt: md5 hash incorrect - expecting "91341eed84691a83caea73aa785736d5" got "35d6d33467aae9a2e3dccb4b6b027878" fstest.go:151: Error Trace: fstest.go:151 fstest.go:196 fstest.go:309 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1778 sync_test.go:1787 Error: Not equal: expected: 6 actual : 5 Test: TestSyncBackupDirWithSuffixKeepExtension Messages: backup/three-2019-01-01.txt: size incorrect file=6 vs obj=5 fstest.go:128: Error Trace: fstest.go:128 fstest.go:133 fstest.go:152 fstest.go:196 fstest.go:309 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1778 sync_test.go:1787 Error: Should be true Test: TestSyncBackupDirWithSuffixKeepExtension Messages: backup/three-2019-01-01.txt: Modification time difference too big |95456h54m52.62345679s| > 1ns (want 2011-12-25 12:59:59.123456789 +0000 UTC vs got 2001-02-03 04:05:06.499999999 +0000 UTC) (precision 1ns) --- FAIL: TestSyncBackupDirWithSuffixKeepExtension (45.53s) === RUN TestSyncBackupDirSuffixOnly run.go:181: Remote "Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5", Local "Local file system at /tmp/rclone168706856", Modify Window "1ns" 2021/12/23 06:06:46 DEBUG : Creating backend with remote "TestHubic:rclone-test-peqiyuw2qoqukop6jucerup5/dst" 2021/12/23 06:06:46 DEBUG : Hubic: Got swift credentials (expiry 2021-12-24 06:50:53 +0100 +0100 in 23h44m6.044546983s) 2021/12/23 06:06:47 DEBUG : one: Sizes differ (src 4 vs dst 3) 2021/12/23 06:06:47 DEBUG : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5 path dst: Waiting for checks to finish 2021/12/23 06:06:47 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2021/12/23 06:06:47 DEBUG : two: Unchanged skipping 2021/12/23 06:06:49 DEBUG : one: md5 = f97c5d29941bfb1b2fdab0874906ab82 OK 2021/12/23 06:06:49 INFO : one: Copied (server-side copy) to: one.bak 2021/12/23 06:06:50 INFO : one: Deleted 2021/12/23 06:06:50 DEBUG : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5 path dst: Waiting for transfers to finish 2021/12/23 06:06:54 DEBUG : one: md5 = c7957179c41f69d44f217a108c7915d8 OK 2021/12/23 06:06:54 INFO : one: Copied (new) 2021/12/23 06:06:54 DEBUG : Waiting for deletions to finish 2021/12/23 06:06:55 DEBUG : three.txt: md5 = 35d6d33467aae9a2e3dccb4b6b027878 OK 2021/12/23 06:06:55 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2021/12/23 06:06:56 INFO : three.txt: Deleted 2021/12/23 06:06:56 INFO : three.txt: Moved into backup dir fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:299: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:299: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:306: Error Trace: fstest.go:306 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1753 sync_test.go:1790 Error: Should be true Test: TestSyncBackupDirSuffixOnly Messages: listing wrong, want dst/one (4), dst/one.bak (3), dst/three.txt.bak (5), dst/two (3) got dst/one.bak (3), dst/three.txt.bak (5), dst/two (3) fstest.go:204: Not found "dst/one" fstest.go:207: Error Trace: fstest.go:207 fstest.go:311 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1753 sync_test.go:1790 Error: Not equal: expected: 0 actual : 1 Test: TestSyncBackupDirSuffixOnly Messages: 1 objects not found fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:299: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:299: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:306: Error Trace: fstest.go:306 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1759 sync_test.go:1790 Error: Should be true Test: TestSyncBackupDirSuffixOnly Messages: listing wrong, want dst/one (4), dst/one.bak (3), dst/three.txt (6), dst/three.txt.bak (5), dst/two (3) got dst/one.bak (3), dst/three.txt (6), dst/three.txt.bak (5), dst/two (3) fstest.go:204: Not found "dst/one" fstest.go:207: Error Trace: fstest.go:207 fstest.go:311 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1759 sync_test.go:1790 Error: Not equal: expected: 0 actual : 1 Test: TestSyncBackupDirSuffixOnly Messages: 1 objects not found 2021/12/23 06:07:17 DEBUG : one.bak: Excluded 2021/12/23 06:07:17 DEBUG : three.txt.bak: Excluded 2021/12/23 06:07:17 DEBUG : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5 path dst: Waiting for checks to finish 2021/12/23 06:07:17 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2021/12/23 06:07:17 DEBUG : two: Unchanged skipping 2021/12/23 06:07:17 DEBUG : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5 path dst: Waiting for transfers to finish 2021/12/23 06:07:19 DEBUG : one: md5 = 0f93e81041f0cab37c37a05ae998b219 OK 2021/12/23 06:07:19 INFO : one: Copied (new) 2021/12/23 06:07:19 DEBUG : Waiting for deletions to finish 2021/12/23 06:07:22 DEBUG : three.txt: md5 = 91341eed84691a83caea73aa785736d5 OK 2021/12/23 06:07:22 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2021/12/23 06:07:23 INFO : three.txt: Deleted 2021/12/23 06:07:23 INFO : three.txt: Moved into backup dir fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:299: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:299: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:306: Error Trace: fstest.go:306 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1778 sync_test.go:1790 Error: Should be true Test: TestSyncBackupDirSuffixOnly Messages: listing wrong, want dst/one (5), dst/one.bak (4), dst/three.txt.bak (6), dst/two (3) got dst/one (5), dst/one.bak (3), dst/three.txt.bak (6), dst/two (3) fstest.go:144: Error Trace: fstest.go:144 fstest.go:150 fstest.go:196 fstest.go:309 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1778 sync_test.go:1790 Error: Should be true Test: TestSyncBackupDirSuffixOnly Messages: Swift container rclone-test-peqiyuw2qoqukop6jucerup5/dst/one.bak: md5 hash incorrect - expecting "c7957179c41f69d44f217a108c7915d8" got "f97c5d29941bfb1b2fdab0874906ab82" fstest.go:151: Error Trace: fstest.go:151 fstest.go:196 fstest.go:309 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1778 sync_test.go:1790 Error: Not equal: expected: 4 actual : 3 Test: TestSyncBackupDirSuffixOnly Messages: dst/one.bak: size incorrect file=4 vs obj=3 fstest.go:128: Error Trace: fstest.go:128 fstest.go:133 fstest.go:152 fstest.go:196 fstest.go:309 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1778 sync_test.go:1790 Error: Should be true Test: TestSyncBackupDirSuffixOnly Messages: dst/one.bak: Modification time difference too big |95456h54m52.62345679s| > 1ns (want 2011-12-25 12:59:59.123456789 +0000 UTC vs got 2001-02-03 04:05:06.499999999 +0000 UTC) (precision 1ns) --- FAIL: TestSyncBackupDirSuffixOnly (57.23s) === RUN TestSyncSuffix run.go:181: Remote "Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5", Local "Local file system at /tmp/rclone168706856", Modify Window "1ns" fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:299: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:299: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:306: Error Trace: fstest.go:306 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1817 sync_test.go:1875 Error: Should be true Test: TestSyncSuffix Messages: listing wrong, want dst/one (3), dst/three.txt (5), dst/two (3) got dst/three.txt (5), dst/two (3) fstest.go:204: Not found "dst/one" fstest.go:207: Error Trace: fstest.go:207 fstest.go:311 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1817 sync_test.go:1875 Error: Not equal: expected: 0 actual : 1 Test: TestSyncSuffix Messages: 1 objects not found 2021/12/23 06:07:52 DEBUG : Creating backend with remote "TestHubic:rclone-test-peqiyuw2qoqukop6jucerup5/dst" 2021/12/23 06:07:52 DEBUG : Hubic: Got swift credentials (expiry 2021-12-24 06:50:53 +0100 +0100 in 23h43m0.099165669s) 2021/12/23 06:07:53 DEBUG : one: Sizes differ (src 4 vs dst 3) 2021/12/23 06:07:53 INFO : Swift container rclone-test-peqiyuw2qoqukop6jucerup5 path dst: Container "rclone-test-peqiyuw2qoqukop6jucerup5" created 2021/12/23 06:07:56 DEBUG : one: md5 = f97c5d29941bfb1b2fdab0874906ab82 OK 2021/12/23 06:07:56 INFO : one: Copied (server-side copy) to: one.bak 2021/12/23 06:07:57 INFO : one: Deleted 2021/12/23 06:08:01 DEBUG : one: md5 = c7957179c41f69d44f217a108c7915d8 OK 2021/12/23 06:08:01 INFO : one: Copied (new) 2021/12/23 06:08:01 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2021/12/23 06:08:01 DEBUG : two: Unchanged skipping 2021/12/23 06:08:01 DEBUG : three.txt: Sizes differ (src 6 vs dst 5) 2021/12/23 06:08:02 DEBUG : three.txt: md5 = 35d6d33467aae9a2e3dccb4b6b027878 OK 2021/12/23 06:08:02 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2021/12/23 06:08:03 INFO : three.txt: Deleted 2021/12/23 06:08:06 DEBUG : three.txt: md5 = 91341eed84691a83caea73aa785736d5 OK 2021/12/23 06:08:06 INFO : three.txt: Copied (new) 2021/12/23 06:08:08 DEBUG : one: Sizes differ (src 5 vs dst 4) 2021/12/23 06:08:09 DEBUG : one: md5 = c7957179c41f69d44f217a108c7915d8 OK 2021/12/23 06:08:09 INFO : one: Copied (server-side copy) to: one.bak 2021/12/23 06:08:10 INFO : one: Deleted 2021/12/23 06:08:13 DEBUG : one: md5 = 0f93e81041f0cab37c37a05ae998b219 OK 2021/12/23 06:08:13 INFO : one: Copied (new) 2021/12/23 06:08:13 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2021/12/23 06:08:13 DEBUG : two: Unchanged skipping 2021/12/23 06:08:13 DEBUG : three.txt: Sizes differ (src 19 vs dst 6) 2021/12/23 06:08:15 DEBUG : three.txt: md5 = 91341eed84691a83caea73aa785736d5 OK 2021/12/23 06:08:15 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2021/12/23 06:08:16 INFO : three.txt: Deleted 2021/12/23 06:08:19 DEBUG : three.txt: md5 = 423e01c5e84a30d129b97aedb6e219a9 OK 2021/12/23 06:08:19 INFO : three.txt: Copied (new) --- FAIL: TestSyncSuffix (50.00s) === RUN TestSyncSuffixKeepExtension run.go:181: Remote "Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5", Local "Local file system at /tmp/rclone168706856", Modify Window "1ns" fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:299: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:299: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:306: Error Trace: fstest.go:306 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1817 sync_test.go:1876 Error: Should be true Test: TestSyncSuffixKeepExtension Messages: listing wrong, want dst/one (3), dst/three.txt (5), dst/two (3) got dst/three.txt (5), dst/two (3) fstest.go:204: Not found "dst/one" fstest.go:207: Error Trace: fstest.go:207 fstest.go:311 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1817 sync_test.go:1876 Error: Not equal: expected: 0 actual : 1 Test: TestSyncSuffixKeepExtension Messages: 1 objects not found 2021/12/23 06:08:40 DEBUG : Creating backend with remote "TestHubic:rclone-test-peqiyuw2qoqukop6jucerup5/dst" 2021/12/23 06:08:41 DEBUG : Hubic: Got swift credentials (expiry 2021-12-24 06:50:53 +0100 +0100 in 23h42m11.721112472s) 2021/12/23 06:08:41 DEBUG : one: Sizes differ (src 4 vs dst 3) 2021/12/23 06:08:42 INFO : Swift container rclone-test-peqiyuw2qoqukop6jucerup5 path dst: Container "rclone-test-peqiyuw2qoqukop6jucerup5" created 2021/12/23 06:08:44 DEBUG : one: md5 = f97c5d29941bfb1b2fdab0874906ab82 OK 2021/12/23 06:08:44 INFO : one: Copied (server-side copy) to: one-2019-01-01 2021/12/23 06:08:45 INFO : one: Deleted 2021/12/23 06:08:47 DEBUG : one: md5 = c7957179c41f69d44f217a108c7915d8 OK 2021/12/23 06:08:47 INFO : one: Copied (new) 2021/12/23 06:08:47 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2021/12/23 06:08:47 DEBUG : two: Unchanged skipping 2021/12/23 06:08:47 DEBUG : three.txt: Sizes differ (src 6 vs dst 5) 2021/12/23 06:08:50 DEBUG : three.txt: md5 = 35d6d33467aae9a2e3dccb4b6b027878 OK 2021/12/23 06:08:50 INFO : three.txt: Copied (server-side copy) to: three-2019-01-01.txt 2021/12/23 06:08:51 INFO : three.txt: Deleted 2021/12/23 06:08:53 DEBUG : three.txt: md5 = 91341eed84691a83caea73aa785736d5 OK 2021/12/23 06:08:53 INFO : three.txt: Copied (new) 2021/12/23 06:08:54 DEBUG : one: Sizes differ (src 5 vs dst 4) 2021/12/23 06:08:56 DEBUG : one: md5 = c7957179c41f69d44f217a108c7915d8 OK 2021/12/23 06:08:56 INFO : one: Copied (server-side copy) to: one-2019-01-01 2021/12/23 06:08:57 INFO : one: Deleted 2021/12/23 06:09:00 DEBUG : one: md5 = 0f93e81041f0cab37c37a05ae998b219 OK 2021/12/23 06:09:00 INFO : one: Copied (new) 2021/12/23 06:09:00 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2021/12/23 06:09:00 DEBUG : two: Unchanged skipping 2021/12/23 06:09:00 DEBUG : three.txt: Sizes differ (src 19 vs dst 6) 2021/12/23 06:09:01 DEBUG : three.txt: md5 = 91341eed84691a83caea73aa785736d5 OK 2021/12/23 06:09:01 INFO : three.txt: Copied (server-side copy) to: three-2019-01-01.txt 2021/12/23 06:09:02 INFO : three.txt: Deleted 2021/12/23 06:09:04 DEBUG : three.txt: md5 = 423e01c5e84a30d129b97aedb6e219a9 OK 2021/12/23 06:09:04 INFO : three.txt: Copied (new) --- FAIL: TestSyncSuffixKeepExtension (44.58s) === RUN TestSyncUTFNorm run.go:181: Remote "Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5", Local "Local file system at /tmp/rclone168706856", Modify Window "1ns" 2021/12/23 06:09:12 DEBUG : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5: Waiting for checks to finish 2021/12/23 06:09:12 DEBUG : Testêé: Sizes differ (src 14 vs dst 18) 2021/12/23 06:09:12 DEBUG : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5: Waiting for transfers to finish 2021/12/23 06:09:14 DEBUG : Testêé: md5 = ce114e4501d2f4e2dcea3e17b546f339 OK 2021/12/23 06:09:14 INFO : Testêé: Copied (replaced existing) to: Testêé 2021/12/23 06:09:14 DEBUG : Waiting for deletions to finish --- PASS: TestSyncUTFNorm (7.00s) === RUN TestSyncImmutable run.go:181: Remote "Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5", Local "Local file system at /tmp/rclone168706856", Modify Window "1ns" 2021/12/23 06:09:18 DEBUG : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5: Waiting for checks to finish 2021/12/23 06:09:18 DEBUG : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5: Waiting for transfers to finish 2021/12/23 06:09:20 DEBUG : existing: md5 = 8ee2027983915ec78acc45027d874316 OK 2021/12/23 06:09:20 INFO : existing: Copied (new) 2021/12/23 06:09:20 DEBUG : Waiting for deletions to finish 2021/12/23 06:09:22 DEBUG : existing: Sizes differ (src 8 vs dst 6) 2021/12/23 06:09:22 ERROR : existing: Source and destination exist but do not match: immutable file modified 2021/12/23 06:09:22 DEBUG : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5: Waiting for checks to finish 2021/12/23 06:09:22 DEBUG : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5: Waiting for transfers to finish 2021/12/23 06:09:22 ERROR : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5: not deleting files as there were IO errors 2021/12/23 06:09:22 ERROR : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5: not deleting directories as there were IO errors --- PASS: TestSyncImmutable (6.53s) === RUN TestSyncIgnoreCase run.go:181: Remote "Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5", Local "Local file system at /tmp/rclone168706856", Modify Window "1ns" 2021/12/23 06:09:27 DEBUG : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5: Waiting for checks to finish 2021/12/23 06:09:27 DEBUG : existing: Size and modification time the same (differ by 0s, within tolerance 1ns) 2021/12/23 06:09:27 DEBUG : existing: Unchanged skipping 2021/12/23 06:09:27 DEBUG : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5: Waiting for transfers to finish 2021/12/23 06:09:27 DEBUG : Waiting for deletions to finish 2021/12/23 06:09:27 INFO : There was nothing to transfer --- PASS: TestSyncIgnoreCase (5.89s) === RUN TestMaxTransfer === RUN TestMaxTransfer/Hard run.go:181: Remote "Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5", Local "Local file system at /tmp/rclone168706856", Modify Window "1ns" sync_test.go:1989: This test only runs on local === RUN TestMaxTransfer/Soft run.go:181: Remote "Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5", Local "Local file system at /tmp/rclone168706856", Modify Window "1ns" sync_test.go:1989: This test only runs on local === RUN TestMaxTransfer/Cautious run.go:181: Remote "Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5", Local "Local file system at /tmp/rclone168706856", Modify Window "1ns" sync_test.go:1989: This test only runs on local --- PASS: TestMaxTransfer (1.53s) --- SKIP: TestMaxTransfer/Hard (0.50s) --- SKIP: TestMaxTransfer/Soft (0.56s) --- SKIP: TestMaxTransfer/Cautious (0.47s) === RUN TestSyncConcurrentDelete run.go:181: Remote "Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5", Local "Local file system at /tmp/rclone168706856", Modify Window "1ns" fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:299: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:299: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:306: Error Trace: fstest.go:306 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:2050 sync_test.go:2062 Error: Should be true Test: TestSyncConcurrentDelete Messages: listing wrong, want both0 (6), both1 (6), both10 (6), both11 (6), both12 (6), both13 (6), both14 (6), both15 (6), both16 (6), both17 (6), both18 (6), both19 (6), both2 (6), both3 (6), both4 (6), both5 (6), both6 (6), both7 (6), both8 (6), both9 (6), only0 (6), only1 (6), only10 (6), only11 (6), only12 (6), only13 (6), only14 (6), only15 (6), only16 (6), only17 (6), only18 (6), only19 (6), only2 (6), only3 (6), only4 (6), only5 (6), only6 (6), only7 (6), only8 (6), only9 (6) got both1 (6), both10 (6), both11 (6), both12 (6), both13 (6), both14 (6), both15 (6), both16 (6), both17 (6), both18 (6), both19 (6), both2 (6), both3 (6), both4 (6), both5 (6), both6 (6), both7 (6), both8 (6), both9 (6), only1 (6), only10 (6), only11 (6), only12 (6), only13 (6), only14 (6), only15 (6), only16 (6), only17 (6), only18 (6), only19 (6), only2 (6), only3 (6), only4 (6), only5 (6), only6 (6), only7 (6), only8 (6) fstest.go:204: Not found "only0" fstest.go:204: Not found "only9" fstest.go:204: Not found "both0" fstest.go:207: Error Trace: fstest.go:207 fstest.go:311 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:2050 sync_test.go:2062 Error: Not equal: expected: 0 actual : 3 Test: TestSyncConcurrentDelete Messages: 3 objects not found 2021/12/23 06:11:17 DEBUG : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5: Waiting for checks to finish 2021/12/23 06:11:17 DEBUG : both11: Size and modification time the same (differ by 0s, within tolerance 1ns) 2021/12/23 06:11:17 DEBUG : both11: Unchanged skipping 2021/12/23 06:11:17 DEBUG : both1: Size and modification time the same (differ by 0s, within tolerance 1ns) 2021/12/23 06:11:17 DEBUG : both1: Unchanged skipping 2021/12/23 06:11:17 DEBUG : both10: Size and modification time the same (differ by 0s, within tolerance 1ns) 2021/12/23 06:11:17 DEBUG : both10: Unchanged skipping 2021/12/23 06:11:17 DEBUG : both12: Size and modification time the same (differ by 0s, within tolerance 1ns) 2021/12/23 06:11:17 DEBUG : both12: Unchanged skipping 2021/12/23 06:11:17 DEBUG : both13: Size and modification time the same (differ by 0s, within tolerance 1ns) 2021/12/23 06:11:17 DEBUG : both13: Unchanged skipping 2021/12/23 06:11:17 DEBUG : both15: Size and modification time the same (differ by 0s, within tolerance 1ns) 2021/12/23 06:11:17 DEBUG : both15: Unchanged skipping 2021/12/23 06:11:17 DEBUG : both14: Size and modification time the same (differ by 0s, within tolerance 1ns) 2021/12/23 06:11:17 DEBUG : both14: Unchanged skipping 2021/12/23 06:11:17 DEBUG : both16: Size and modification time the same (differ by 0s, within tolerance 1ns) 2021/12/23 06:11:17 DEBUG : both16: Unchanged skipping 2021/12/23 06:11:17 DEBUG : both17: Size and modification time the same (differ by 0s, within tolerance 1ns) 2021/12/23 06:11:17 DEBUG : both17: Unchanged skipping 2021/12/23 06:11:17 DEBUG : both18: Size and modification time the same (differ by 0s, within tolerance 1ns) 2021/12/23 06:11:17 DEBUG : both18: Unchanged skipping 2021/12/23 06:11:17 DEBUG : both19: Size and modification time the same (differ by 0s, within tolerance 1ns) 2021/12/23 06:11:17 DEBUG : both19: Unchanged skipping 2021/12/23 06:11:17 DEBUG : both3: Size and modification time the same (differ by 0s, within tolerance 1ns) 2021/12/23 06:11:17 DEBUG : both3: Unchanged skipping 2021/12/23 06:11:17 DEBUG : both2: Size and modification time the same (differ by 0s, within tolerance 1ns) 2021/12/23 06:11:17 DEBUG : both2: Unchanged skipping 2021/12/23 06:11:17 DEBUG : both5: Size and modification time the same (differ by 0s, within tolerance 1ns) 2021/12/23 06:11:17 DEBUG : both5: Unchanged skipping 2021/12/23 06:11:17 DEBUG : both4: Size and modification time the same (differ by 0s, within tolerance 1ns) 2021/12/23 06:11:17 DEBUG : both4: Unchanged skipping 2021/12/23 06:11:17 DEBUG : both7: Size and modification time the same (differ by 0s, within tolerance 1ns) 2021/12/23 06:11:17 DEBUG : both7: Unchanged skipping 2021/12/23 06:11:17 DEBUG : both6: Size and modification time the same (differ by 0s, within tolerance 1ns) 2021/12/23 06:11:17 DEBUG : both6: Unchanged skipping 2021/12/23 06:11:17 DEBUG : both9: Size and modification time the same (differ by 0s, within tolerance 1ns) 2021/12/23 06:11:17 DEBUG : both9: Unchanged skipping 2021/12/23 06:11:17 DEBUG : both8: Size and modification time the same (differ by 0s, within tolerance 1ns) 2021/12/23 06:11:17 DEBUG : both8: Unchanged skipping 2021/12/23 06:11:17 DEBUG : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5: Waiting for transfers to finish 2021/12/23 06:11:18 DEBUG : both0: md5 = 8ee2027983915ec78acc45027d874316 OK 2021/12/23 06:11:18 INFO : both0: Copied (new) 2021/12/23 06:11:18 DEBUG : Waiting for deletions to finish 2021/12/23 06:11:19 INFO : only1: Deleted 2021/12/23 06:11:19 INFO : only17: Deleted 2021/12/23 06:11:19 INFO : only19: Deleted 2021/12/23 06:11:19 INFO : only10: Deleted 2021/12/23 06:11:20 INFO : only16: Deleted 2021/12/23 06:11:20 INFO : only6: Deleted 2021/12/23 06:11:20 INFO : only18: Deleted 2021/12/23 06:11:20 INFO : only11: Deleted 2021/12/23 06:11:21 INFO : only13: Deleted 2021/12/23 06:11:21 INFO : only7: Deleted 2021/12/23 06:11:21 INFO : only5: Deleted 2021/12/23 06:11:21 INFO : only15: Deleted 2021/12/23 06:11:22 INFO : only3: Deleted 2021/12/23 06:11:22 INFO : only2: Deleted 2021/12/23 06:11:22 INFO : only4: Deleted 2021/12/23 06:11:22 INFO : only8: Deleted 2021/12/23 06:11:23 INFO : only12: Deleted 2021/12/23 06:11:23 INFO : only14: Deleted fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:299: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:299: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:306: Error Trace: fstest.go:306 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:2058 sync_test.go:2062 Error: Should be true Test: TestSyncConcurrentDelete Messages: listing wrong, want both0 (6), both1 (6), both10 (6), both11 (6), both12 (6), both13 (6), both14 (6), both15 (6), both16 (6), both17 (6), both18 (6), both19 (6), both2 (6), both3 (6), both4 (6), both5 (6), both6 (6), both7 (6), both8 (6), both9 (6) got both0 (6), both1 (6), both10 (6), both11 (6), both12 (6), both13 (6), both14 (6), both15 (6), both16 (6), both17 (6), both18 (6), both19 (6), both2 (6), both3 (6), both4 (6), both5 (6), both6 (6), both7 (6), both8 (6), both9 (6), only14 (6) fstest.go:192: Error Trace: fstest.go:192 fstest.go:309 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:2058 sync_test.go:2062 Error: Should be true Test: TestSyncConcurrentDelete Messages: Unexpected file "only14" run.go:126: removing file "only14" failed - try 1/3: Object Not Found run.go:126: removing file "only14" failed - try 2/3: Object Not Found run.go:126: removing file "only14" failed - try 3/3: Object Not Found run.go:129: removing file "only14" failed: Object Not Found fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:299: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:299: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:306: Error Trace: fstest.go:306 fstest.go:339 run.go:174 run.go:376 sync_test.go:2059 sync_test.go:2062 Error: Should be true Test: TestSyncConcurrentDelete Messages: listing wrong, want got only9 (6) fstest.go:192: Error Trace: fstest.go:192 fstest.go:309 fstest.go:339 run.go:174 run.go:376 sync_test.go:2059 sync_test.go:2062 Error: Should be true Test: TestSyncConcurrentDelete Messages: Unexpected file "only9" --- FAIL: TestSyncConcurrentDelete (157.27s) === RUN TestSyncConcurrentTruncate run.go:181: Remote "Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5", Local "Local file system at /tmp/rclone168706856", Modify Window "1ns" fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:299: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:299: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:306: Error Trace: fstest.go:306 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:2050 sync_test.go:2066 Error: Should be true Test: TestSyncConcurrentTruncate Messages: listing wrong, want both0 (6), both1 (6), both10 (6), both11 (6), both12 (6), both13 (6), both14 (6), both15 (6), both16 (6), both17 (6), both18 (6), both19 (6), both2 (6), both3 (6), both4 (6), both5 (6), both6 (6), both7 (6), both8 (6), both9 (6), only0 (6), only1 (6), only10 (6), only11 (6), only12 (6), only13 (6), only14 (6), only15 (6), only16 (6), only17 (6), only18 (6), only19 (6), only2 (6), only3 (6), only4 (6), only5 (6), only6 (6), only7 (6), only8 (6), only9 (6) got both0 (6), both1 (6), both10 (6), both11 (6), both12 (6), both13 (6), both14 (6), both15 (6), both16 (6), both17 (6), both18 (6), both19 (6), both2 (6), both4 (6), both5 (6), both6 (6), both7 (6), both8 (6), both9 (6), only0 (6), only1 (6), only10 (6), only11 (6), only12 (6), only14 (6), only15 (6), only16 (6), only17 (6), only18 (6), only19 (6), only2 (6), only3 (6), only4 (6), only5 (6), only6 (6), only7 (6), only8 (6), only9 (6) fstest.go:204: Not found "both3" fstest.go:204: Not found "only13" fstest.go:207: Error Trace: fstest.go:207 fstest.go:311 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:2050 sync_test.go:2066 Error: Not equal: expected: 0 actual : 2 Test: TestSyncConcurrentTruncate Messages: 2 objects not found 2021/12/23 06:13:50 DEBUG : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5: Waiting for checks to finish 2021/12/23 06:13:50 DEBUG : both0: Size and modification time the same (differ by 0s, within tolerance 1ns) 2021/12/23 06:13:50 DEBUG : both0: Unchanged skipping 2021/12/23 06:13:50 DEBUG : both1: Size and modification time the same (differ by 0s, within tolerance 1ns) 2021/12/23 06:13:50 DEBUG : both1: Unchanged skipping 2021/12/23 06:13:50 DEBUG : both12: Size and modification time the same (differ by 0s, within tolerance 1ns) 2021/12/23 06:13:50 DEBUG : both12: Unchanged skipping 2021/12/23 06:13:50 DEBUG : both11: Size and modification time the same (differ by 0s, within tolerance 1ns) 2021/12/23 06:13:50 DEBUG : both11: Unchanged skipping 2021/12/23 06:13:50 DEBUG : both10: Size and modification time the same (differ by 0s, within tolerance 1ns) 2021/12/23 06:13:50 DEBUG : both10: Unchanged skipping 2021/12/23 06:13:50 DEBUG : both13: Size and modification time the same (differ by 0s, within tolerance 1ns) 2021/12/23 06:13:50 DEBUG : both13: Unchanged skipping 2021/12/23 06:13:50 DEBUG : both14: Size and modification time the same (differ by 0s, within tolerance 1ns) 2021/12/23 06:13:50 DEBUG : both14: Unchanged skipping 2021/12/23 06:13:50 DEBUG : both17: Size and modification time the same (differ by 0s, within tolerance 1ns) 2021/12/23 06:13:50 DEBUG : both17: Unchanged skipping 2021/12/23 06:13:50 DEBUG : both15: Size and modification time the same (differ by 0s, within tolerance 1ns) 2021/12/23 06:13:50 DEBUG : both15: Unchanged skipping 2021/12/23 06:13:50 DEBUG : both16: Size and modification time the same (differ by 0s, within tolerance 1ns) 2021/12/23 06:13:50 DEBUG : both16: Unchanged skipping 2021/12/23 06:13:50 DEBUG : both18: Size and modification time the same (differ by 0s, within tolerance 1ns) 2021/12/23 06:13:50 DEBUG : both18: Unchanged skipping 2021/12/23 06:13:50 DEBUG : both4: Size and modification time the same (differ by 0s, within tolerance 1ns) 2021/12/23 06:13:50 DEBUG : both4: Unchanged skipping 2021/12/23 06:13:50 DEBUG : both2: Size and modification time the same (differ by 0s, within tolerance 1ns) 2021/12/23 06:13:50 DEBUG : both2: Unchanged skipping 2021/12/23 06:13:50 DEBUG : both19: Size and modification time the same (differ by 0s, within tolerance 1ns) 2021/12/23 06:13:50 DEBUG : both19: Unchanged skipping 2021/12/23 06:13:50 DEBUG : both5: Size and modification time the same (differ by 0s, within tolerance 1ns) 2021/12/23 06:13:50 DEBUG : both5: Unchanged skipping 2021/12/23 06:13:50 DEBUG : both6: Size and modification time the same (differ by 0s, within tolerance 1ns) 2021/12/23 06:13:50 DEBUG : both6: Unchanged skipping 2021/12/23 06:13:50 DEBUG : only0: Sizes differ (src 0 vs dst 6) 2021/12/23 06:13:50 DEBUG : only1: Sizes differ (src 0 vs dst 6) 2021/12/23 06:13:50 DEBUG : only10: Sizes differ (src 0 vs dst 6) 2021/12/23 06:13:50 DEBUG : only11: Sizes differ (src 0 vs dst 6) 2021/12/23 06:13:50 DEBUG : only12: Sizes differ (src 0 vs dst 6) 2021/12/23 06:13:50 DEBUG : only14: Sizes differ (src 0 vs dst 6) 2021/12/23 06:13:50 DEBUG : only15: Sizes differ (src 0 vs dst 6) 2021/12/23 06:13:50 DEBUG : only16: Sizes differ (src 0 vs dst 6) 2021/12/23 06:13:50 DEBUG : only17: Sizes differ (src 0 vs dst 6) 2021/12/23 06:13:50 DEBUG : only18: Sizes differ (src 0 vs dst 6) 2021/12/23 06:13:50 DEBUG : only19: Sizes differ (src 0 vs dst 6) 2021/12/23 06:13:50 DEBUG : only2: Sizes differ (src 0 vs dst 6) 2021/12/23 06:13:50 DEBUG : only3: Sizes differ (src 0 vs dst 6) 2021/12/23 06:13:50 DEBUG : only4: Sizes differ (src 0 vs dst 6) 2021/12/23 06:13:50 DEBUG : only5: Sizes differ (src 0 vs dst 6) 2021/12/23 06:13:50 DEBUG : only6: Sizes differ (src 0 vs dst 6) 2021/12/23 06:13:50 DEBUG : only7: Sizes differ (src 0 vs dst 6) 2021/12/23 06:13:50 DEBUG : only8: Sizes differ (src 0 vs dst 6) 2021/12/23 06:13:50 DEBUG : only9: Sizes differ (src 0 vs dst 6) 2021/12/23 06:13:50 DEBUG : both8: Size and modification time the same (differ by 0s, within tolerance 1ns) 2021/12/23 06:13:50 DEBUG : both8: Unchanged skipping 2021/12/23 06:13:50 DEBUG : both7: Size and modification time the same (differ by 0s, within tolerance 1ns) 2021/12/23 06:13:50 DEBUG : both7: Unchanged skipping 2021/12/23 06:13:50 DEBUG : both9: Size and modification time the same (differ by 0s, within tolerance 1ns) 2021/12/23 06:13:50 DEBUG : both9: Unchanged skipping 2021/12/23 06:13:50 DEBUG : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5: Waiting for transfers to finish 2021/12/23 06:13:52 DEBUG : only13: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2021/12/23 06:13:52 INFO : only13: Copied (new) 2021/12/23 06:13:52 DEBUG : only1: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2021/12/23 06:13:52 INFO : only1: Copied (replaced existing) 2021/12/23 06:13:52 DEBUG : both3: md5 = 8ee2027983915ec78acc45027d874316 OK 2021/12/23 06:13:52 INFO : both3: Copied (new) 2021/12/23 06:13:53 DEBUG : only0: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2021/12/23 06:13:53 INFO : only0: Copied (replaced existing) 2021/12/23 06:13:54 DEBUG : only10: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2021/12/23 06:13:54 INFO : only10: Copied (replaced existing) 2021/12/23 06:13:54 DEBUG : only12: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2021/12/23 06:13:54 INFO : only12: Copied (replaced existing) 2021/12/23 06:13:55 DEBUG : only14: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2021/12/23 06:13:55 INFO : only14: Copied (replaced existing) 2021/12/23 06:13:55 DEBUG : only11: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2021/12/23 06:13:55 INFO : only11: Copied (replaced existing) 2021/12/23 06:13:56 DEBUG : only15: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2021/12/23 06:13:56 INFO : only15: Copied (replaced existing) 2021/12/23 06:13:58 DEBUG : only16: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2021/12/23 06:13:58 INFO : only16: Copied (replaced existing) 2021/12/23 06:13:58 DEBUG : only19: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2021/12/23 06:13:58 INFO : only19: Copied (replaced existing) 2021/12/23 06:13:58 DEBUG : only18: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2021/12/23 06:13:58 INFO : only18: Copied (replaced existing) 2021/12/23 06:13:59 DEBUG : only17: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2021/12/23 06:13:59 INFO : only17: Copied (replaced existing) 2021/12/23 06:14:00 DEBUG : only3: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2021/12/23 06:14:00 INFO : only3: Copied (replaced existing) 2021/12/23 06:14:00 DEBUG : only2: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2021/12/23 06:14:00 INFO : only2: Copied (replaced existing) 2021/12/23 06:14:00 DEBUG : only4: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2021/12/23 06:14:00 INFO : only4: Copied (replaced existing) 2021/12/23 06:14:01 DEBUG : only5: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2021/12/23 06:14:01 INFO : only5: Copied (replaced existing) 2021/12/23 06:14:02 DEBUG : only7: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2021/12/23 06:14:02 INFO : only7: Copied (replaced existing) 2021/12/23 06:14:03 DEBUG : only9: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2021/12/23 06:14:03 INFO : only9: Copied (replaced existing) 2021/12/23 06:14:03 DEBUG : only8: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2021/12/23 06:14:03 INFO : only8: Copied (replaced existing) 2021/12/23 06:14:03 DEBUG : only6: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2021/12/23 06:14:03 INFO : only6: Copied (replaced existing) 2021/12/23 06:14:03 DEBUG : Waiting for deletions to finish fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:299: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:299: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:306: Error Trace: fstest.go:306 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:2058 sync_test.go:2066 Error: Should be true Test: TestSyncConcurrentTruncate Messages: listing wrong, want both0 (6), both1 (6), both10 (6), both11 (6), both12 (6), both13 (6), both14 (6), both15 (6), both16 (6), both17 (6), both18 (6), both19 (6), both2 (6), both3 (6), both4 (6), both5 (6), both6 (6), both7 (6), both8 (6), both9 (6), only0 (0), only1 (0), only10 (0), only11 (0), only12 (0), only13 (0), only14 (0), only15 (0), only16 (0), only17 (0), only18 (0), only19 (0), only2 (0), only3 (0), only4 (0), only5 (0), only6 (0), only7 (0), only8 (0), only9 (0) got both0 (6), both1 (6), both10 (6), both11 (6), both12 (6), both13 (6), both14 (6), both15 (6), both16 (6), both17 (6), both18 (6), both19 (6), both2 (6), both3 (6), both4 (6), both5 (6), both6 (6), both7 (6), both8 (6), both9 (6), only0 (0), only1 (6), only10 (0), only11 (0), only12 (0), only13 (0), only14 (0), only15 (0), only16 (6), only17 (0), only18 (0), only19 (0), only2 (0), only3 (0), only4 (0), only5 (0), only6 (0), only7 (0), only8 (0), only9 (0) --- FAIL: TestSyncConcurrentTruncate (165.92s) FAIL 2021/12/23 06:14:54 DEBUG : Hubic Swift container rclone-test-peqiyuw2qoqukop6jucerup5: Purge remote 2021/12/23 06:14:54 DEBUG : Waiting for deletions to finish 2021/12/23 06:14:55 INFO : Swift container rclone-test-peqiyuw2qoqukop6jucerup5: Container "rclone-test-peqiyuw2qoqukop6jucerup5" removed "./sync.test -test.v -test.timeout 1h0m0s -remote TestHubic: -verbose" - Finished ERROR in 24m3.286926366s (try 1/5): exit status 1: Failed [TestCopyRedownload TestSyncBasedOnCheckSum TestSyncSizeOnly TestSyncIgnoreErrors TestSyncAfterChangingModtimeOnly TestSyncAfterRemovingAFileAndAddingAFile TestSyncDeleteBefore TestCopyDeleteBefore TestSyncWithExclude TestSyncWithTrackRenamesStrategyLeaf TestMoveWithDeleteEmptySrcDirs TestMoveWithIgnoreExisting TestServerSideMoveDeleteEmptySourceDirs TestSyncBackupDir TestSyncBackupDirWithSuffix TestSyncBackupDirWithSuffixKeepExtension TestSyncBackupDirSuffixOnly TestSyncSuffix TestSyncSuffixKeepExtension TestSyncConcurrentDelete TestSyncConcurrentTruncate]