"./sync.test -test.v -test.timeout 1h0m0s -remote TestHubic: -verbose" - Starting (try 1/5) 2022/04/15 05:00:20 DEBUG : Creating backend with remote "TestHubic:rclone-test-depocay7niqeyum5puxihog3" 2022/04/15 05:00:20 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2022/04/15 05:00:21 DEBUG : Hubic: Got swift credentials (expiry 2022-04-16 07:00:21 +0200 +0200 in 23h59m59.213809855s) 2022/04/15 05:00:21 DEBUG : Config file has changed externaly - reloading 2022/04/15 05:00:21 DEBUG : Creating backend with remote "/tmp/rclone4150802735" === 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-depocay7niqeyum5puxihog3", Local "Local file system at /tmp/rclone4150802735", Modify Window "1ns" 2022/04/15 05:00:22 INFO : Swift container rclone-test-depocay7niqeyum5puxihog3: Container "rclone-test-depocay7niqeyum5puxihog3" created 2022/04/15 05:00:22 NOTICE: sub dir/hello world: Skipped copy as --dry-run is set (size 11) 2022/04/15 05:00:22 DEBUG : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3: Waiting for checks to finish 2022/04/15 05:00:22 DEBUG : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3: Waiting for transfers to finish --- PASS: TestCopyWithDryRun (0.51s) === RUN TestCopy run.go:181: Remote "Hubic Swift container rclone-test-depocay7niqeyum5puxihog3", Local "Local file system at /tmp/rclone4150802735", Modify Window "1ns" 2022/04/15 05:00:22 DEBUG : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3: Waiting for checks to finish 2022/04/15 05:00:22 DEBUG : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3: Waiting for transfers to finish 2022/04/15 05:00:24 DEBUG : sub dir/hello world: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2022/04/15 05:00:24 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopy (3.26s) === RUN TestCopyMissingDirectory run.go:181: Remote "Hubic Swift container rclone-test-depocay7niqeyum5puxihog3", Local "Local file system at /tmp/rclone4150802735", Modify Window "1ns" 2022/04/15 05:00:25 DEBUG : Creating backend with remote "/non-existing" 2022/04/15 05:00:25 ERROR : Local file system at /non-existing: error reading source root directory: directory not found 2022/04/15 05:00:25 DEBUG : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3: Waiting for checks to finish 2022/04/15 05:00:25 DEBUG : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3: Waiting for transfers to finish --- PASS: TestCopyMissingDirectory (0.14s) === RUN TestCopyNoTraverse run.go:181: Remote "Hubic Swift container rclone-test-depocay7niqeyum5puxihog3", Local "Local file system at /tmp/rclone4150802735", Modify Window "1ns" 2022/04/15 05:00:25 DEBUG : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3: Waiting for checks to finish 2022/04/15 05:00:25 DEBUG : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3: Waiting for transfers to finish 2022/04/15 05:00:27 DEBUG : sub dir/hello world: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2022/04/15 05:00:27 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopyNoTraverse (3.76s) === RUN TestCopyCheckFirst run.go:181: Remote "Hubic Swift container rclone-test-depocay7niqeyum5puxihog3", Local "Local file system at /tmp/rclone4150802735", Modify Window "1ns" 2022/04/15 05:00:29 INFO : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3: Running all checks before starting transfers 2022/04/15 05:00:29 DEBUG : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3: Waiting for checks to finish 2022/04/15 05:00:29 INFO : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3: Checks finished, now starting transfers 2022/04/15 05:00:29 DEBUG : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3: Waiting for transfers to finish 2022/04/15 05:00:31 DEBUG : sub dir/hello world: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2022/04/15 05:00:31 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: TestCopyCheckFirst (10.05s) === RUN TestSyncNoTraverse run.go:181: Remote "Hubic Swift container rclone-test-depocay7niqeyum5puxihog3", Local "Local file system at /tmp/rclone4150802735", Modify Window "1ns" 2022/04/15 05:00:39 ERROR : Ignoring --no-traverse with sync 2022/04/15 05:00:39 DEBUG : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3: Waiting for checks to finish 2022/04/15 05:00:39 DEBUG : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3: Waiting for transfers to finish 2022/04/15 05:00:41 DEBUG : sub dir/hello world: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2022/04/15 05:00:41 INFO : sub dir/hello world: Copied (new) 2022/04/15 05:00:41 DEBUG : Waiting for deletions to finish --- PASS: TestSyncNoTraverse (3.01s) === RUN TestCopyWithDepth run.go:181: Remote "Hubic Swift container rclone-test-depocay7niqeyum5puxihog3", Local "Local file system at /tmp/rclone4150802735", Modify Window "1ns" 2022/04/15 05:00:42 DEBUG : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3: Waiting for checks to finish 2022/04/15 05:00:42 DEBUG : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3: Waiting for transfers to finish 2022/04/15 05:00:44 DEBUG : hello world2: md5 = cc2c857f89648dbd139d7b2a6665957d OK 2022/04/15 05:00:44 INFO : hello world2: Copied (new) --- PASS: TestCopyWithDepth (2.86s) === RUN TestCopyWithFilesFrom run.go:181: Remote "Hubic Swift container rclone-test-depocay7niqeyum5puxihog3", Local "Local file system at /tmp/rclone4150802735", Modify Window "1ns" 2022/04/15 05:00:45 DEBUG : hello world2: Excluded 2022/04/15 05:00:45 DEBUG : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3: Waiting for checks to finish 2022/04/15 05:00:45 DEBUG : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3: Waiting for transfers to finish 2022/04/15 05:00:47 DEBUG : potato2: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2022/04/15 05:00:47 INFO : potato2: Copied (new) --- PASS: TestCopyWithFilesFrom (3.41s) === RUN TestCopyWithFilesFromAndNoTraverse run.go:181: Remote "Hubic Swift container rclone-test-depocay7niqeyum5puxihog3", Local "Local file system at /tmp/rclone4150802735", Modify Window "1ns" 2022/04/15 05:00:49 DEBUG : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3: Waiting for checks to finish 2022/04/15 05:00:49 DEBUG : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3: Waiting for transfers to finish 2022/04/15 05:00:50 DEBUG : potato2: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2022/04/15 05:00:50 INFO : potato2: Copied (new) --- PASS: TestCopyWithFilesFromAndNoTraverse (3.24s) === RUN TestCopyEmptyDirectories run.go:181: Remote "Hubic Swift container rclone-test-depocay7niqeyum5puxihog3", Local "Local file system at /tmp/rclone4150802735", Modify Window "1ns" 2022/04/15 05:00:52 DEBUG : sub dir2: Making directory 2022/04/15 05:00:52 DEBUG : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3: Waiting for checks to finish 2022/04/15 05:00:52 DEBUG : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3: Waiting for transfers to finish 2022/04/15 05:00:53 DEBUG : sub dir/hello world: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2022/04/15 05:00:53 INFO : sub dir/hello world: Copied (new) 2022/04/15 05:00:53 DEBUG : sub dir2: Making directory 2022/04/15 05:00:53 DEBUG : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3: copied 1 directories fstest.go:251: Filtering empty directory "sub dir2" --- PASS: TestCopyEmptyDirectories (3.24s) === RUN TestMoveEmptyDirectories run.go:181: Remote "Hubic Swift container rclone-test-depocay7niqeyum5puxihog3", Local "Local file system at /tmp/rclone4150802735", Modify Window "1ns" 2022/04/15 05:00:55 DEBUG : sub dir2: Making directory 2022/04/15 05:00:55 DEBUG : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3: Waiting for checks to finish 2022/04/15 05:00:55 DEBUG : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3: Waiting for transfers to finish 2022/04/15 05:00:56 DEBUG : sub dir/hello world: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2022/04/15 05:00:56 INFO : sub dir/hello world: Copied (new) 2022/04/15 05:00:56 INFO : sub dir/hello world: Deleted 2022/04/15 05:00:56 DEBUG : sub dir: Making directory 2022/04/15 05:00:56 DEBUG : sub dir2: Making directory 2022/04/15 05:00:56 DEBUG : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3: copied 2 directories fstest.go:251: Filtering empty directory "sub dir2" fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:293: Sleeping for 6s just to make sure --- PASS: TestMoveEmptyDirectories (10.05s) === RUN TestSyncEmptyDirectories run.go:181: Remote "Hubic Swift container rclone-test-depocay7niqeyum5puxihog3", Local "Local file system at /tmp/rclone4150802735", Modify Window "1ns" 2022/04/15 05:01:05 DEBUG : sub dir2: Making directory 2022/04/15 05:01:05 DEBUG : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3: Waiting for checks to finish 2022/04/15 05:01:05 DEBUG : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3: Waiting for transfers to finish 2022/04/15 05:01:07 DEBUG : sub dir/hello world: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2022/04/15 05:01:07 INFO : sub dir/hello world: Copied (new) 2022/04/15 05:01:07 DEBUG : sub dir2: Making directory 2022/04/15 05:01:07 DEBUG : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3: copied 1 directories 2022/04/15 05:01:07 DEBUG : Waiting for deletions to finish fstest.go:251: Filtering empty directory "sub dir2" --- PASS: TestSyncEmptyDirectories (3.39s) === RUN TestServerSideCopy run.go:181: Remote "Hubic Swift container rclone-test-depocay7niqeyum5puxihog3", Local "Local file system at /tmp/rclone4150802735", Modify Window "1ns" 2022/04/15 05:01:10 DEBUG : Creating backend with remote "TestHubic:rclone-test-wapocol7lofezag2kedizog3" 2022/04/15 05:01:10 DEBUG : Hubic: Got swift credentials (expiry 2022-04-16 07:00:21 +0200 +0200 in 23h59m10.43370622s) sync_test.go:275: Server side copy (if possible) Hubic Swift container rclone-test-depocay7niqeyum5puxihog3 -> Hubic Swift container rclone-test-wapocol7lofezag2kedizog3 2022/04/15 05:01:10 DEBUG : Hubic Swift container rclone-test-wapocol7lofezag2kedizog3: Waiting for checks to finish 2022/04/15 05:01:10 DEBUG : Hubic Swift container rclone-test-wapocol7lofezag2kedizog3: Waiting for transfers to finish 2022/04/15 05:01:10 INFO : Swift container rclone-test-wapocol7lofezag2kedizog3: Container "rclone-test-wapocol7lofezag2kedizog3" created 2022/04/15 05:01:13 DEBUG : sub dir/hello world: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2022/04/15 05:01:13 INFO : sub dir/hello world: Copied (server-side copy) 2022/04/15 05:01:13 DEBUG : Hubic Swift container rclone-test-wapocol7lofezag2kedizog3: Purge remote 2022/04/15 05:01:13 DEBUG : Waiting for deletions to finish 2022/04/15 05:01:14 INFO : sub dir/hello world: Deleted 2022/04/15 05:01:15 INFO : Swift container rclone-test-wapocol7lofezag2kedizog3: Container "rclone-test-wapocol7lofezag2kedizog3" removed --- PASS: TestServerSideCopy (7.49s) === RUN TestCopyAfterDelete run.go:181: Remote "Hubic Swift container rclone-test-depocay7niqeyum5puxihog3", Local "Local file system at /tmp/rclone4150802735", Modify Window "1ns" 2022/04/15 05:01:17 ERROR : : error listing: directory not found 2022/04/15 05:01:17 DEBUG : Local file system at /tmp/rclone4150802735: Making directory 2022/04/15 05:01:17 DEBUG : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3: Waiting for checks to finish 2022/04/15 05:01:17 DEBUG : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3: Waiting for transfers to finish --- PASS: TestCopyAfterDelete (2.99s) === RUN TestCopyRedownload run.go:181: Remote "Hubic Swift container rclone-test-depocay7niqeyum5puxihog3", Local "Local file system at /tmp/rclone4150802735", Modify Window "1ns" 2022/04/15 05:01:21 DEBUG : Local file system at /tmp/rclone4150802735: Waiting for checks to finish 2022/04/15 05:01:21 DEBUG : Local file system at /tmp/rclone4150802735: Waiting for transfers to finish 2022/04/15 05:01:21 DEBUG : sub dir/hello world: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2022/04/15 05:01:21 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopyRedownload (3.38s) === RUN TestSyncBasedOnCheckSum run.go:181: Remote "Hubic Swift container rclone-test-depocay7niqeyum5puxihog3", Local "Local file system at /tmp/rclone4150802735", Modify Window "1ns" 2022/04/15 05:01:22 DEBUG : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3: Waiting for checks to finish 2022/04/15 05:01:22 DEBUG : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3: Waiting for transfers to finish 2022/04/15 05:01:24 DEBUG : check sum: md5 = 336d5ebc5436534e61d16e63ddfca327 OK 2022/04/15 05:01:24 INFO : check sum: Copied (new) 2022/04/15 05:01:24 DEBUG : Waiting for deletions to finish 2022/04/15 05:01:24 DEBUG : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3: Waiting for checks to finish 2022/04/15 05:01:24 DEBUG : check sum: md5 = 336d5ebc5436534e61d16e63ddfca327 OK 2022/04/15 05:01:24 DEBUG : check sum: Size and md5 of src and dst objects identical 2022/04/15 05:01:24 DEBUG : check sum: Unchanged skipping 2022/04/15 05:01:24 DEBUG : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3: Waiting for transfers to finish 2022/04/15 05:01:24 DEBUG : Waiting for deletions to finish 2022/04/15 05:01:24 INFO : There was nothing to transfer --- PASS: TestSyncBasedOnCheckSum (3.38s) === RUN TestSyncSizeOnly run.go:181: Remote "Hubic Swift container rclone-test-depocay7niqeyum5puxihog3", Local "Local file system at /tmp/rclone4150802735", Modify Window "1ns" 2022/04/15 05:01:26 DEBUG : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3: Waiting for checks to finish 2022/04/15 05:01:26 DEBUG : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3: Waiting for transfers to finish 2022/04/15 05:01:28 DEBUG : sizeonly: md5 = 8ee2027983915ec78acc45027d874316 OK 2022/04/15 05:01:28 INFO : sizeonly: Copied (new) 2022/04/15 05:01:28 DEBUG : Waiting for deletions to finish 2022/04/15 05:01:28 DEBUG : sizeonly: Sizes identical 2022/04/15 05:01:28 DEBUG : sizeonly: Unchanged skipping 2022/04/15 05:01:28 DEBUG : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3: Waiting for checks to finish 2022/04/15 05:01:28 DEBUG : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3: Waiting for transfers to finish 2022/04/15 05:01:28 DEBUG : Waiting for deletions to finish 2022/04/15 05:01:28 INFO : There was nothing to transfer --- PASS: TestSyncSizeOnly (4.14s) === RUN TestSyncIgnoreSize run.go:181: Remote "Hubic Swift container rclone-test-depocay7niqeyum5puxihog3", Local "Local file system at /tmp/rclone4150802735", Modify Window "1ns" 2022/04/15 05:01:30 DEBUG : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3: Waiting for checks to finish 2022/04/15 05:01:30 DEBUG : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3: Waiting for transfers to finish 2022/04/15 05:01:32 DEBUG : ignore-size: md5 = 98bf7d8c15784f0a3d63204441e1e2aa OK 2022/04/15 05:01:32 INFO : ignore-size: Copied (new) 2022/04/15 05:01:32 DEBUG : Waiting for deletions to finish 2022/04/15 05:01:32 DEBUG : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3: Waiting for checks to finish 2022/04/15 05:01:32 DEBUG : ignore-size: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/15 05:01:32 DEBUG : ignore-size: Unchanged skipping 2022/04/15 05:01:32 DEBUG : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3: Waiting for transfers to finish 2022/04/15 05:01:32 DEBUG : Waiting for deletions to finish 2022/04/15 05:01:32 INFO : There was nothing to transfer --- PASS: TestSyncIgnoreSize (4.07s) === RUN TestSyncIgnoreTimes run.go:181: Remote "Hubic Swift container rclone-test-depocay7niqeyum5puxihog3", Local "Local file system at /tmp/rclone4150802735", Modify Window "1ns" 2022/04/15 05:01:35 DEBUG : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3: Waiting for checks to finish 2022/04/15 05:01:35 DEBUG : existing: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/15 05:01:35 DEBUG : existing: Unchanged skipping 2022/04/15 05:01:35 DEBUG : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3: Waiting for transfers to finish 2022/04/15 05:01:35 DEBUG : Waiting for deletions to finish 2022/04/15 05:01:35 INFO : There was nothing to transfer 2022/04/15 05:01:36 DEBUG : existing: Transferring unconditionally as --ignore-times is in use 2022/04/15 05:01:36 DEBUG : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3: Waiting for checks to finish 2022/04/15 05:01:36 DEBUG : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3: Waiting for transfers to finish 2022/04/15 05:01:37 DEBUG : existing: md5 = 8ee2027983915ec78acc45027d874316 OK 2022/04/15 05:01:37 INFO : existing: Copied (replaced existing) 2022/04/15 05:01:37 DEBUG : Waiting for deletions to finish --- PASS: TestSyncIgnoreTimes (4.88s) === RUN TestSyncIgnoreExisting run.go:181: Remote "Hubic Swift container rclone-test-depocay7niqeyum5puxihog3", Local "Local file system at /tmp/rclone4150802735", Modify Window "1ns" 2022/04/15 05:01:39 DEBUG : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3: Waiting for checks to finish 2022/04/15 05:01:39 DEBUG : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3: Waiting for transfers to finish 2022/04/15 05:01:40 DEBUG : existing: md5 = 8ee2027983915ec78acc45027d874316 OK 2022/04/15 05:01:40 INFO : existing: Copied (new) 2022/04/15 05:01:40 DEBUG : Waiting for deletions to finish 2022/04/15 05:01:41 DEBUG : existing: Destination exists, skipping 2022/04/15 05:01:41 DEBUG : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3: Waiting for checks to finish 2022/04/15 05:01:41 DEBUG : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3: Waiting for transfers to finish 2022/04/15 05:01:41 DEBUG : Waiting for deletions to finish 2022/04/15 05:01:41 INFO : There was nothing to transfer --- PASS: TestSyncIgnoreExisting (3.42s) === RUN TestSyncIgnoreErrors run.go:181: Remote "Hubic Swift container rclone-test-depocay7niqeyum5puxihog3", Local "Local file system at /tmp/rclone4150802735", Modify Window "1ns" 2022/04/15 05:01:47 DEBUG : d: Making directory fstest.go:251: Filtering empty directory "d" 2022/04/15 05:01:47 DEBUG : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3: Waiting for checks to finish 2022/04/15 05:01:47 DEBUG : c/non empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/15 05:01:47 DEBUG : c/non empty space: Unchanged skipping 2022/04/15 05:01:47 DEBUG : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3: Waiting for transfers to finish 2022/04/15 05:01:49 DEBUG : a/potato2: md5 = d6548b156ea68a4e003e786df99eee76 OK 2022/04/15 05:01:49 INFO : a/potato2: Copied (new) 2022/04/15 05:01:49 DEBUG : Waiting for deletions to finish 2022/04/15 05:01:51 INFO : b/potato: Deleted --- PASS: TestSyncIgnoreErrors (11.23s) === RUN TestSyncAfterChangingModtimeOnly run.go:181: Remote "Hubic Swift container rclone-test-depocay7niqeyum5puxihog3", Local "Local file system at /tmp/rclone4150802735", Modify Window "1ns" 2022/04/15 05:01:55 DEBUG : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3: Waiting for checks to finish 2022/04/15 05:01:56 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 2022/04/15 05:01:56 DEBUG : empty space: md5 = 336d5ebc5436534e61d16e63ddfca327 OK 2022/04/15 05:01:56 NOTICE: empty space: Skipped update modification time as --dry-run is set (size 1) 2022/04/15 05:01:56 DEBUG : empty space: Unchanged skipping 2022/04/15 05:01:56 DEBUG : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3: Waiting for transfers to finish 2022/04/15 05:01:56 DEBUG : Waiting for deletions to finish 2022/04/15 05:01:56 INFO : There was nothing to transfer 2022/04/15 05:01:56 DEBUG : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3: Waiting for checks to finish 2022/04/15 05:01:56 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 2022/04/15 05:01:56 DEBUG : empty space: md5 = 336d5ebc5436534e61d16e63ddfca327 OK 2022/04/15 05:01:57 INFO : empty space: Updated modification time in destination 2022/04/15 05:01:57 DEBUG : empty space: Unchanged skipping 2022/04/15 05:01:57 DEBUG : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3: Waiting for transfers to finish 2022/04/15 05:01:57 DEBUG : Waiting for deletions to finish 2022/04/15 05:01:57 INFO : There was nothing to transfer --- PASS: TestSyncAfterChangingModtimeOnly (5.15s) === RUN TestSyncAfterChangingModtimeOnlyWithNoUpdateModTime run.go:181: Remote "Hubic Swift container rclone-test-depocay7niqeyum5puxihog3", Local "Local file system at /tmp/rclone4150802735", Modify Window "1ns" 2022/04/15 05:02:01 DEBUG : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3: Waiting for checks to finish 2022/04/15 05:02:01 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 2022/04/15 05:02:01 DEBUG : empty space: md5 = 336d5ebc5436534e61d16e63ddfca327 OK 2022/04/15 05:02:01 DEBUG : empty space: Unchanged skipping 2022/04/15 05:02:01 DEBUG : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3: Waiting for transfers to finish 2022/04/15 05:02:01 DEBUG : Waiting for deletions to finish 2022/04/15 05:02:01 INFO : There was nothing to transfer --- PASS: TestSyncAfterChangingModtimeOnlyWithNoUpdateModTime (3.70s) === RUN TestSyncDoesntUpdateModtime run.go:181: Remote "Hubic Swift container rclone-test-depocay7niqeyum5puxihog3", Local "Local file system at /tmp/rclone4150802735", Modify Window "1ns" 2022/04/15 05:02:04 DEBUG : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3: Waiting for checks to finish 2022/04/15 05:02:04 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 2022/04/15 05:02:04 DEBUG : foo: md5 = acbd18db4cc2f85cedef654fccc4a4d8 (Local file system at /tmp/rclone4150802735) 2022/04/15 05:02:04 DEBUG : foo: md5 = 37b51d194a7513e45b56f6524f2d51f2 (Swift container rclone-test-depocay7niqeyum5puxihog3) 2022/04/15 05:02:04 DEBUG : foo: md5 differ 2022/04/15 05:02:04 DEBUG : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3: Waiting for transfers to finish 2022/04/15 05:02:06 DEBUG : foo: md5 = acbd18db4cc2f85cedef654fccc4a4d8 OK 2022/04/15 05:02:06 INFO : foo: Copied (replaced existing) 2022/04/15 05:02:06 DEBUG : Waiting for deletions to finish --- PASS: TestSyncDoesntUpdateModtime (5.43s) === RUN TestSyncAfterAddingAFile run.go:181: Remote "Hubic Swift container rclone-test-depocay7niqeyum5puxihog3", Local "Local file system at /tmp/rclone4150802735", Modify Window "1ns" 2022/04/15 05:02:09 DEBUG : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3: Waiting for checks to finish 2022/04/15 05:02:09 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/15 05:02:09 DEBUG : empty space: Unchanged skipping 2022/04/15 05:02:09 DEBUG : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3: Waiting for transfers to finish 2022/04/15 05:02:12 DEBUG : potato: md5 = d6548b156ea68a4e003e786df99eee76 OK 2022/04/15 05:02:12 INFO : potato: Copied (new) 2022/04/15 05:02:12 DEBUG : Waiting for deletions to finish --- PASS: TestSyncAfterAddingAFile (7.29s) === RUN TestSyncAfterChangingFilesSizeOnly run.go:181: Remote "Hubic Swift container rclone-test-depocay7niqeyum5puxihog3", Local "Local file system at /tmp/rclone4150802735", Modify Window "1ns" 2022/04/15 05:02:17 DEBUG : potato: Sizes differ (src 21 vs dst 60) 2022/04/15 05:02:17 DEBUG : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3: Waiting for checks to finish 2022/04/15 05:02:17 DEBUG : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3: Waiting for transfers to finish 2022/04/15 05:02:18 DEBUG : potato: md5 = 100defcf18c42a1e0dc42a789b107cd2 OK 2022/04/15 05:02:18 INFO : potato: Copied (replaced existing) 2022/04/15 05:02:18 DEBUG : Waiting for deletions to finish --- PASS: TestSyncAfterChangingFilesSizeOnly (4.59s) === RUN TestSyncAfterChangingContentsOnly run.go:181: Remote "Hubic Swift container rclone-test-depocay7niqeyum5puxihog3", Local "Local file system at /tmp/rclone4150802735", Modify Window "1ns" 2022/04/15 05:02:21 DEBUG : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3: Waiting for checks to finish 2022/04/15 05:02:21 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 2022/04/15 05:02:21 DEBUG : potato: md5 = e4cb6955d9106df6263c45fcfc10f163 (Local file system at /tmp/rclone4150802735) 2022/04/15 05:02:21 DEBUG : potato: md5 = 100defcf18c42a1e0dc42a789b107cd2 (Swift container rclone-test-depocay7niqeyum5puxihog3) 2022/04/15 05:02:21 DEBUG : potato: md5 differ 2022/04/15 05:02:21 DEBUG : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3: Waiting for transfers to finish 2022/04/15 05:02:23 DEBUG : potato: md5 = e4cb6955d9106df6263c45fcfc10f163 OK 2022/04/15 05:02:23 INFO : potato: Copied (replaced existing) 2022/04/15 05:02:23 DEBUG : Waiting for deletions to finish --- PASS: TestSyncAfterChangingContentsOnly (4.79s) === RUN TestSyncAfterRemovingAFileAndAddingAFileDryRun run.go:181: Remote "Hubic Swift container rclone-test-depocay7niqeyum5puxihog3", Local "Local file system at /tmp/rclone4150802735", Modify Window "1ns" 2022/04/15 05:02:28 NOTICE: potato2: Skipped copy as --dry-run is set (size 60) 2022/04/15 05:02:28 DEBUG : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3: Waiting for checks to finish 2022/04/15 05:02:28 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/15 05:02:28 DEBUG : empty space: Unchanged skipping 2022/04/15 05:02:28 DEBUG : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3: Waiting for transfers to finish 2022/04/15 05:02:28 DEBUG : Waiting for deletions to finish 2022/04/15 05:02:28 NOTICE: potato: Skipped delete as --dry-run is set (size 21) --- PASS: TestSyncAfterRemovingAFileAndAddingAFileDryRun (6.44s) === RUN TestSyncAfterRemovingAFileAndAddingAFile run.go:181: Remote "Hubic Swift container rclone-test-depocay7niqeyum5puxihog3", Local "Local file system at /tmp/rclone4150802735", Modify Window "1ns" 2022/04/15 05:02:34 DEBUG : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3: Waiting for checks to finish 2022/04/15 05:02:34 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/15 05:02:34 DEBUG : empty space: Unchanged skipping 2022/04/15 05:02:34 DEBUG : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3: Waiting for transfers to finish 2022/04/15 05:02:36 DEBUG : potato2: md5 = d6548b156ea68a4e003e786df99eee76 OK 2022/04/15 05:02:36 INFO : potato2: Copied (new) 2022/04/15 05:02:36 DEBUG : Waiting for deletions to finish 2022/04/15 05:02:37 INFO : potato: Deleted fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:293: Sleeping for 6s just to make sure --- PASS: TestSyncAfterRemovingAFileAndAddingAFile (16.08s) === RUN TestSyncAfterRemovingAFileAndAddingAFileSubDir run.go:181: Remote "Hubic Swift container rclone-test-depocay7niqeyum5puxihog3", Local "Local file system at /tmp/rclone4150802735", Modify Window "1ns" 2022/04/15 05:02:50 DEBUG : d: Making directory 2022/04/15 05:02:50 DEBUG : d/e: Making directory fstest.go:251: Filtering empty directory "d" fstest.go:251: Filtering empty directory "d/e" 2022/04/15 05:02:50 DEBUG : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3: Waiting for checks to finish 2022/04/15 05:02:50 DEBUG : c/non empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/15 05:02:50 DEBUG : c/non empty space: Unchanged skipping 2022/04/15 05:02:50 DEBUG : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3: Waiting for transfers to finish 2022/04/15 05:02:53 DEBUG : a/potato2: md5 = d6548b156ea68a4e003e786df99eee76 OK 2022/04/15 05:02:53 INFO : a/potato2: Copied (new) 2022/04/15 05:02:53 DEBUG : Waiting for deletions to finish 2022/04/15 05:02:54 INFO : b/potato: Deleted --- PASS: TestSyncAfterRemovingAFileAndAddingAFileSubDir (9.52s) === RUN TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors run.go:181: Remote "Hubic Swift container rclone-test-depocay7niqeyum5puxihog3", Local "Local file system at /tmp/rclone4150802735", Modify Window "1ns" 2022/04/15 05:02:59 DEBUG : d: Making directory fstest.go:251: Filtering empty directory "d" 2022/04/15 05:03:00 DEBUG : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3: Waiting for checks to finish 2022/04/15 05:03:00 DEBUG : c/non empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/15 05:03:00 DEBUG : c/non empty space: Unchanged skipping 2022/04/15 05:03:00 DEBUG : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3: Waiting for transfers to finish 2022/04/15 05:03:01 DEBUG : a/potato2: md5 = d6548b156ea68a4e003e786df99eee76 OK 2022/04/15 05:03:01 INFO : a/potato2: Copied (new) 2022/04/15 05:03:01 ERROR : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3: not deleting files as there were IO errors 2022/04/15 05:03:01 ERROR : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3: not deleting directories as there were IO errors fstest.go:251: Filtering empty directory "d" fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:293: Sleeping for 6s just to make sure --- PASS: TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors (15.89s) === RUN TestSyncDeleteAfter --- PASS: TestSyncDeleteAfter (0.00s) === RUN TestSyncDeleteDuring run.go:181: Remote "Hubic Swift container rclone-test-depocay7niqeyum5puxihog3", Local "Local file system at /tmp/rclone4150802735", Modify Window "1ns" 2022/04/15 05:03:15 DEBUG : Waiting for deletions to finish 2022/04/15 05:03:16 DEBUG : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3: Waiting for checks to finish 2022/04/15 05:03:16 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/15 05:03:16 DEBUG : empty space: Unchanged skipping 2022/04/15 05:03:16 DEBUG : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3: Waiting for transfers to finish 2022/04/15 05:03:17 INFO : potato: Deleted 2022/04/15 05:03:17 DEBUG : potato2: md5 = d6548b156ea68a4e003e786df99eee76 OK 2022/04/15 05:03:17 INFO : potato2: Copied (new) --- PASS: TestSyncDeleteDuring (7.81s) === RUN TestSyncDeleteBefore run.go:181: Remote "Hubic Swift container rclone-test-depocay7niqeyum5puxihog3", Local "Local file system at /tmp/rclone4150802735", Modify Window "1ns" 2022/04/15 05:03:24 DEBUG : Waiting for deletions to finish 2022/04/15 05:03:25 DEBUG : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3: Waiting for checks to finish 2022/04/15 05:03:25 DEBUG : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3: Waiting for transfers to finish 2022/04/15 05:03:26 INFO : potato: Deleted 2022/04/15 05:03:26 DEBUG : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3: Waiting for checks to finish 2022/04/15 05:03:26 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/15 05:03:26 DEBUG : empty space: Unchanged skipping 2022/04/15 05:03:26 DEBUG : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3: Waiting for transfers to finish 2022/04/15 05:03:28 DEBUG : potato2: md5 = d6548b156ea68a4e003e786df99eee76 OK 2022/04/15 05:03:28 INFO : potato2: Copied (new) --- PASS: TestSyncDeleteBefore (10.27s) === RUN TestCopyDeleteBefore run.go:181: Remote "Hubic Swift container rclone-test-depocay7niqeyum5puxihog3", Local "Local file system at /tmp/rclone4150802735", Modify Window "1ns" 2022/04/15 05:03:32 DEBUG : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3: Waiting for checks to finish 2022/04/15 05:03:32 DEBUG : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3: Waiting for transfers to finish 2022/04/15 05:03:34 DEBUG : potato2: md5 = 9519bc2e940e1696ea7365b4b81adedc OK 2022/04/15 05:03:34 INFO : potato2: Copied (new) --- PASS: TestCopyDeleteBefore (6.34s) === RUN TestSyncWithExclude run.go:181: Remote "Hubic Swift container rclone-test-depocay7niqeyum5puxihog3", Local "Local file system at /tmp/rclone4150802735", Modify Window "1ns" 2022/04/15 05:03:40 DEBUG : enormous: Excluded 2022/04/15 05:03:40 DEBUG : potato2: Excluded 2022/04/15 05:03:40 DEBUG : potato2: Excluded 2022/04/15 05:03:40 DEBUG : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3: Waiting for checks to finish 2022/04/15 05:03:40 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/15 05:03:40 DEBUG : empty space: Unchanged skipping 2022/04/15 05:03:40 DEBUG : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3: Waiting for transfers to finish 2022/04/15 05:03:40 DEBUG : Waiting for deletions to finish 2022/04/15 05:03:40 INFO : There was nothing to transfer 2022/04/15 05:03:40 DEBUG : enormous: Excluded 2022/04/15 05:03:40 DEBUG : potato2: Excluded 2022/04/15 05:03:40 DEBUG : potato2: Excluded 2022/04/15 05:03:40 DEBUG : Local file system at /tmp/rclone4150802735: Waiting for checks to finish 2022/04/15 05:03:40 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/15 05:03:40 DEBUG : empty space: Unchanged skipping 2022/04/15 05:03:40 DEBUG : Local file system at /tmp/rclone4150802735: Waiting for transfers to finish 2022/04/15 05:03:40 DEBUG : Waiting for deletions to finish 2022/04/15 05:03:40 INFO : There was nothing to transfer --- PASS: TestSyncWithExclude (6.35s) === RUN TestSyncWithExcludeAndDeleteExcluded run.go:181: Remote "Hubic Swift container rclone-test-depocay7niqeyum5puxihog3", Local "Local file system at /tmp/rclone4150802735", Modify Window "1ns" 2022/04/15 05:03:48 DEBUG : enormous: Excluded 2022/04/15 05:03:48 DEBUG : potato2: Excluded 2022/04/15 05:03:48 DEBUG : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3: Waiting for checks to finish 2022/04/15 05:03:48 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/15 05:03:48 DEBUG : empty space: Unchanged skipping 2022/04/15 05:03:48 DEBUG : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3: Waiting for transfers to finish 2022/04/15 05:03:48 DEBUG : Waiting for deletions to finish 2022/04/15 05:03:49 INFO : enormous: Deleted 2022/04/15 05:03:49 INFO : potato2: Deleted 2022/04/15 05:03:49 INFO : There was nothing to transfer 2022/04/15 05:03:49 DEBUG : Local file system at /tmp/rclone4150802735: Waiting for checks to finish 2022/04/15 05:03:49 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/15 05:03:49 DEBUG : empty space: Unchanged skipping 2022/04/15 05:03:49 DEBUG : Local file system at /tmp/rclone4150802735: Waiting for transfers to finish 2022/04/15 05:03:49 DEBUG : Waiting for deletions to finish 2022/04/15 05:03:49 INFO : enormous: Deleted 2022/04/15 05:03:49 INFO : potato2: Deleted 2022/04/15 05:03:49 INFO : There was nothing to transfer --- PASS: TestSyncWithExcludeAndDeleteExcluded (7.83s) === RUN TestSyncWithUpdateOlder run.go:181: Remote "Hubic Swift container rclone-test-depocay7niqeyum5puxihog3", Local "Local file system at /tmp/rclone4150802735", Modify Window "1ns" 2022/04/15 05:04:06 DEBUG : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3: Waiting for checks to finish 2022/04/15 05:04:06 DEBUG : three: Sizes identical 2022/04/15 05:04:06 DEBUG : three: Destination mod time is within 1s of source and files identical, skipping 2022/04/15 05:04:06 DEBUG : one: Destination is newer than source, skipping 2022/04/15 05:04:06 DEBUG : two: md5 = b8a9f715dbb64fd5c56e7783c6820a61 (Local file system at /tmp/rclone4150802735) 2022/04/15 05:04:06 DEBUG : two: md5 = 0f82d86afa0f5dc965c5c15aca58dcfb (Swift container rclone-test-depocay7niqeyum5puxihog3) 2022/04/15 05:04:06 DEBUG : two: md5 differ 2022/04/15 05:04:06 DEBUG : four: Sizes differ (src 4 vs dst 8) 2022/04/15 05:04:06 DEBUG : four: Destination mod time is within 1s of source but files differ, transferring 2022/04/15 05:04:06 DEBUG : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3: Waiting for transfers to finish 2022/04/15 05:04:08 DEBUG : four: md5 = 8cbad96aced40b3838dd9f07f6ef5772 OK 2022/04/15 05:04:08 INFO : four: Copied (replaced existing) 2022/04/15 05:04:08 DEBUG : five: md5 = 30056e1cab7a61d256fc8edd970d14f5 OK 2022/04/15 05:04:08 INFO : five: Copied (new) 2022/04/15 05:04:09 DEBUG : two: md5 = b8a9f715dbb64fd5c56e7783c6820a61 OK 2022/04/15 05:04:09 INFO : two: Copied (replaced existing) 2022/04/15 05:04:09 DEBUG : Waiting for deletions to finish 2022/04/15 05:04:09 DEBUG : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3: Waiting for checks to finish 2022/04/15 05:04:09 DEBUG : four: md5 = 8cbad96aced40b3838dd9f07f6ef5772 OK 2022/04/15 05:04:09 DEBUG : four: Size and md5 of src and dst objects identical 2022/04/15 05:04:09 DEBUG : four: Destination mod time is within 1s of source and files identical, skipping 2022/04/15 05:04:09 DEBUG : three: md5 = 35d6d33467aae9a2e3dccb4b6b027878 (Local file system at /tmp/rclone4150802735) 2022/04/15 05:04:09 DEBUG : three: md5 = 413af0de1f97a2155acf2b8b26ab36e2 (Swift container rclone-test-depocay7niqeyum5puxihog3) 2022/04/15 05:04:09 DEBUG : three: md5 differ 2022/04/15 05:04:09 DEBUG : three: Destination mod time is within 1s of source but files differ, transferring 2022/04/15 05:04:10 DEBUG : five: md5 = 30056e1cab7a61d256fc8edd970d14f5 OK 2022/04/15 05:04:10 DEBUG : five: Size and md5 of src and dst objects identical 2022/04/15 05:04:10 DEBUG : five: Destination mod time is within 1s of source and files identical, skipping 2022/04/15 05:04:10 DEBUG : two: md5 = b8a9f715dbb64fd5c56e7783c6820a61 OK 2022/04/15 05:04:10 DEBUG : two: Size and md5 of src and dst objects identical 2022/04/15 05:04:10 DEBUG : two: Destination mod time is within 1s of source and files identical, skipping 2022/04/15 05:04:10 DEBUG : one: Destination is newer than source, skipping 2022/04/15 05:04:10 DEBUG : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3: Waiting for transfers to finish 2022/04/15 05:04:11 DEBUG : three: md5 = 35d6d33467aae9a2e3dccb4b6b027878 OK 2022/04/15 05:04:11 INFO : three: Copied (replaced existing) 2022/04/15 05:04:11 DEBUG : Waiting for deletions to finish --- PASS: TestSyncWithUpdateOlder (26.98s) === 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-depocay7niqeyum5puxihog3", Local "Local file system at /tmp/rclone4150802735", Modify Window "1ns" sync_test.go:1058: Can track renames: true 2022/04/15 05:04:18 INFO : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3: Making map for --track-renames 2022/04/15 05:04:18 INFO : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3: Finished making map for --track-renames 2022/04/15 05:04:18 DEBUG : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3: Waiting for checks to finish 2022/04/15 05:04:18 DEBUG : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3: Waiting for renames to finish 2022/04/15 05:04:18 DEBUG : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3: Waiting for transfers to finish 2022/04/15 05:04:19 DEBUG : potato: md5 = 7f6fa9ddec9bfebda9510af0323fd696 OK 2022/04/15 05:04:19 INFO : potato: Copied (new) 2022/04/15 05:04:20 DEBUG : yam: md5 = 48dc443644c4b89556dc06834b46451d OK 2022/04/15 05:04:20 INFO : yam: Copied (new) 2022/04/15 05:04:20 DEBUG : Waiting for deletions to finish 2022/04/15 05:04:20 INFO : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3: Making map for --track-renames 2022/04/15 05:04:20 INFO : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3: Finished making map for --track-renames 2022/04/15 05:04:20 DEBUG : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3: Waiting for checks to finish 2022/04/15 05:04:20 DEBUG : potato: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/15 05:04:20 DEBUG : potato: Unchanged skipping 2022/04/15 05:04:20 DEBUG : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3: Waiting for renames to finish 2022/04/15 05:04:22 DEBUG : yam: md5 = 48dc443644c4b89556dc06834b46451d OK 2022/04/15 05:04:22 INFO : yam: Copied (server-side copy) to: yaml 2022/04/15 05:04:23 INFO : yam: Deleted 2022/04/15 05:04:23 INFO : yaml: Renamed from "yam" 2022/04/15 05:04:23 DEBUG : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3: Waiting for transfers to finish 2022/04/15 05:04:23 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: TestSyncWithTrackRenames (15.25s) === RUN TestParseRenamesStrategyModtime --- PASS: TestParseRenamesStrategyModtime (0.00s) === RUN TestRenamesStrategyModtime --- PASS: TestRenamesStrategyModtime (0.00s) === RUN TestSyncWithTrackRenamesStrategyModtime run.go:181: Remote "Hubic Swift container rclone-test-depocay7niqeyum5puxihog3", Local "Local file system at /tmp/rclone4150802735", Modify Window "1ns" sync_test.go:1127: Can track renames: true 2022/04/15 05:04:33 INFO : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3: Making map for --track-renames 2022/04/15 05:04:33 INFO : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3: Finished making map for --track-renames 2022/04/15 05:04:33 DEBUG : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3: Waiting for checks to finish 2022/04/15 05:04:33 DEBUG : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3: Waiting for renames to finish 2022/04/15 05:04:33 DEBUG : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3: Waiting for transfers to finish 2022/04/15 05:04:35 DEBUG : yam: md5 = 48dc443644c4b89556dc06834b46451d OK 2022/04/15 05:04:35 INFO : yam: Copied (new) 2022/04/15 05:04:35 DEBUG : potato: md5 = 7f6fa9ddec9bfebda9510af0323fd696 OK 2022/04/15 05:04:35 INFO : potato: Copied (new) 2022/04/15 05:04:35 DEBUG : Waiting for deletions to finish 2022/04/15 05:04:35 INFO : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3: Making map for --track-renames 2022/04/15 05:04:35 INFO : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3: Finished making map for --track-renames 2022/04/15 05:04:35 DEBUG : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3: Waiting for checks to finish 2022/04/15 05:04:35 DEBUG : potato: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/15 05:04:35 DEBUG : potato: Unchanged skipping 2022/04/15 05:04:35 DEBUG : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3: Waiting for renames to finish 2022/04/15 05:04:37 DEBUG : yam: md5 = 48dc443644c4b89556dc06834b46451d OK 2022/04/15 05:04:37 INFO : yam: Copied (server-side copy) to: yaml 2022/04/15 05:04:38 INFO : yam: Deleted 2022/04/15 05:04:38 INFO : yaml: Renamed from "yam" 2022/04/15 05:04:38 DEBUG : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3: Waiting for transfers to finish 2022/04/15 05:04:38 DEBUG : Waiting for deletions to finish --- PASS: TestSyncWithTrackRenamesStrategyModtime (7.62s) === RUN TestSyncWithTrackRenamesStrategyLeaf run.go:181: Remote "Hubic Swift container rclone-test-depocay7niqeyum5puxihog3", Local "Local file system at /tmp/rclone4150802735", Modify Window "1ns" sync_test.go:1163: Can track renames: true 2022/04/15 05:04:41 INFO : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3: Making map for --track-renames 2022/04/15 05:04:41 INFO : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3: Finished making map for --track-renames 2022/04/15 05:04:41 DEBUG : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3: Waiting for checks to finish 2022/04/15 05:04:41 DEBUG : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3: Waiting for renames to finish 2022/04/15 05:04:41 DEBUG : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3: Waiting for transfers to finish 2022/04/15 05:04:42 DEBUG : sub/yam: md5 = 48dc443644c4b89556dc06834b46451d OK 2022/04/15 05:04:42 INFO : sub/yam: Copied (new) 2022/04/15 05:04:43 DEBUG : potato: md5 = 7f6fa9ddec9bfebda9510af0323fd696 OK 2022/04/15 05:04:43 INFO : potato: Copied (new) 2022/04/15 05:04:43 DEBUG : Waiting for deletions to finish 2022/04/15 05:04:43 INFO : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3: Making map for --track-renames 2022/04/15 05:04:43 INFO : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3: Finished making map for --track-renames 2022/04/15 05:04:43 DEBUG : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3: Waiting for checks to finish 2022/04/15 05:04:43 DEBUG : potato: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/15 05:04:43 DEBUG : potato: Unchanged skipping 2022/04/15 05:04:43 DEBUG : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3: Waiting for renames to finish 2022/04/15 05:04:45 DEBUG : sub/yam: md5 = 48dc443644c4b89556dc06834b46451d OK 2022/04/15 05:04:45 INFO : sub/yam: Copied (server-side copy) to: yam 2022/04/15 05:04:46 INFO : sub/yam: Deleted 2022/04/15 05:04:46 INFO : yam: Renamed from "sub/yam" 2022/04/15 05:04:46 DEBUG : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3: Waiting for transfers to finish 2022/04/15 05:04:46 DEBUG : Waiting for deletions to finish --- PASS: TestSyncWithTrackRenamesStrategyLeaf (8.08s) === RUN TestMoveWithDeleteEmptySrcDirs run.go:181: Remote "Hubic Swift container rclone-test-depocay7niqeyum5puxihog3", Local "Local file system at /tmp/rclone4150802735", Modify Window "1ns" 2022/04/15 05:04:49 DEBUG : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3: Waiting for checks to finish 2022/04/15 05:04:49 DEBUG : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3: Waiting for transfers to finish 2022/04/15 05:04:50 DEBUG : sub dir/hello world: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2022/04/15 05:04:50 INFO : sub dir/hello world: Copied (new) 2022/04/15 05:04:50 INFO : sub dir/hello world: Deleted 2022/04/15 05:04:51 DEBUG : nested/sub dir/file: md5 = 83d3784ea62518eafc60e98d84f877ad OK 2022/04/15 05:04:51 INFO : nested/sub dir/file: Copied (new) 2022/04/15 05:04:51 INFO : nested/sub dir/file: Deleted 2022/04/15 05:04:51 INFO : sub dir: Removing directory 2022/04/15 05:04:51 INFO : nested/sub dir: Removing directory 2022/04/15 05:04:51 INFO : nested: Removing directory 2022/04/15 05:04:51 DEBUG : Local file system at /tmp/rclone4150802735: deleted 3 directories --- PASS: TestMoveWithDeleteEmptySrcDirs (4.94s) === RUN TestMoveWithoutDeleteEmptySrcDirs run.go:181: Remote "Hubic Swift container rclone-test-depocay7niqeyum5puxihog3", Local "Local file system at /tmp/rclone4150802735", Modify Window "1ns" 2022/04/15 05:04:54 DEBUG : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3: Waiting for checks to finish 2022/04/15 05:04:54 DEBUG : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3: Waiting for transfers to finish 2022/04/15 05:04:55 DEBUG : sub dir/hello world: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2022/04/15 05:04:55 INFO : sub dir/hello world: Copied (new) 2022/04/15 05:04:55 INFO : sub dir/hello world: Deleted 2022/04/15 05:04:56 DEBUG : nested/sub dir/file: md5 = 83d3784ea62518eafc60e98d84f877ad OK 2022/04/15 05:04:56 INFO : nested/sub dir/file: Copied (new) 2022/04/15 05:04:56 INFO : nested/sub dir/file: Deleted --- PASS: TestMoveWithoutDeleteEmptySrcDirs (4.49s) === RUN TestMoveWithIgnoreExisting run.go:181: Remote "Hubic Swift container rclone-test-depocay7niqeyum5puxihog3", Local "Local file system at /tmp/rclone4150802735", Modify Window "1ns" 2022/04/15 05:04:58 DEBUG : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3: Waiting for checks to finish 2022/04/15 05:04:58 DEBUG : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3: Waiting for transfers to finish 2022/04/15 05:05:00 DEBUG : existing-b: md5 = 006f87892f47ef9aa60fa5ed01a440fb OK 2022/04/15 05:05:00 INFO : existing-b: Copied (new) 2022/04/15 05:05:00 INFO : existing-b: Deleted 2022/04/15 05:05:00 DEBUG : existing: md5 = 8ee2027983915ec78acc45027d874316 OK 2022/04/15 05:05:00 INFO : existing: Copied (new) 2022/04/15 05:05:00 INFO : existing: Deleted 2022/04/15 05:05:00 DEBUG : existing: Destination exists, skipping 2022/04/15 05:05:00 DEBUG : existing: Not removing source file as destination file exists and --ignore-existing is set 2022/04/15 05:05:00 DEBUG : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3: Waiting for checks to finish 2022/04/15 05:05:00 DEBUG : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3: Waiting for transfers to finish 2022/04/15 05:05:00 INFO : There was nothing to transfer --- PASS: TestMoveWithIgnoreExisting (4.54s) === RUN TestServerSideMove run.go:181: Remote "Hubic Swift container rclone-test-depocay7niqeyum5puxihog3", Local "Local file system at /tmp/rclone4150802735", Modify Window "1ns" 2022/04/15 05:05:03 DEBUG : Creating backend with remote "TestHubic:rclone-test-joqikej4lacanov9lesowun9" 2022/04/15 05:05:03 DEBUG : Config file has changed externaly - reloading 2022/04/15 05:05:03 DEBUG : Hubic: Got swift credentials (expiry 2022-04-16 07:00:21 +0200 +0200 in 23h55m17.663619316s) sync_test.go:1215: Server side move (if possible) Hubic Swift container rclone-test-depocay7niqeyum5puxihog3 -> Hubic Swift container rclone-test-joqikej4lacanov9lesowun9 2022/04/15 05:05:09 INFO : Swift container rclone-test-joqikej4lacanov9lesowun9: Container "rclone-test-joqikej4lacanov9lesowun9" created 2022/04/15 05:05:13 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2022/04/15 05:05:13 DEBUG : Hubic Swift container rclone-test-joqikej4lacanov9lesowun9: Waiting for checks to finish 2022/04/15 05:05:13 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/15 05:05:13 DEBUG : empty space: Unchanged skipping 2022/04/15 05:05:14 INFO : empty space: Deleted 2022/04/15 05:05:14 DEBUG : Hubic Swift container rclone-test-joqikej4lacanov9lesowun9: Waiting for transfers to finish 2022/04/15 05:05:15 DEBUG : potato3: md5 = b1a53993579b830006f2e1610415c32c OK 2022/04/15 05:05:15 INFO : potato3: Copied (server-side copy) 2022/04/15 05:05:15 DEBUG : potato2: md5 = d6548b156ea68a4e003e786df99eee76 OK 2022/04/15 05:05:15 INFO : potato2: Copied (server-side copy) 2022/04/15 05:05:16 INFO : potato3: Deleted 2022/04/15 05:05: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 2022/04/15 05:05:24 DEBUG : Creating backend with remote "TestHubic:rclone-test-cuvucec6wusepod7miyiful6" 2022/04/15 05:05:24 DEBUG : Config file has changed externaly - reloading 2022/04/15 05:05:24 DEBUG : Hubic: Got swift credentials (expiry 2022-04-16 07:00:21 +0200 +0200 in 23h54m56.467625168s) 2022/04/15 05:05:24 DEBUG : Hubic Swift container rclone-test-cuvucec6wusepod7miyiful6: Waiting for checks to finish 2022/04/15 05:05:24 DEBUG : Hubic Swift container rclone-test-cuvucec6wusepod7miyiful6: Waiting for transfers to finish 2022/04/15 05:05:24 INFO : Swift container rclone-test-cuvucec6wusepod7miyiful6: Container "rclone-test-cuvucec6wusepod7miyiful6" created 2022/04/15 05:05:26 DEBUG : empty space: md5 = 336d5ebc5436534e61d16e63ddfca327 OK 2022/04/15 05:05:26 INFO : empty space: Copied (server-side copy) 2022/04/15 05:05:26 DEBUG : potato3: md5 = b1a53993579b830006f2e1610415c32c OK 2022/04/15 05:05:26 INFO : potato3: Copied (server-side copy) 2022/04/15 05:05:27 DEBUG : potato2: md5 = d6548b156ea68a4e003e786df99eee76 OK 2022/04/15 05:05:27 INFO : potato2: Copied (server-side copy) 2022/04/15 05:05:27 INFO : empty space: Deleted 2022/04/15 05:05:27 INFO : potato3: Deleted 2022/04/15 05:05:28 INFO : potato2: Deleted 2022/04/15 05:05:28 DEBUG : Hubic Swift container rclone-test-cuvucec6wusepod7miyiful6: Purge remote 2022/04/15 05:05:28 DEBUG : Waiting for deletions to finish 2022/04/15 05:05:29 INFO : empty space: Deleted 2022/04/15 05:05:29 INFO : potato2: Deleted 2022/04/15 05:05:29 INFO : potato3: Deleted 2022/04/15 05:05:29 INFO : Swift container rclone-test-cuvucec6wusepod7miyiful6: Container "rclone-test-cuvucec6wusepod7miyiful6" removed 2022/04/15 05:05:29 DEBUG : Hubic Swift container rclone-test-joqikej4lacanov9lesowun9: Purge remote 2022/04/15 05:05:29 DEBUG : Waiting for deletions to finish 2022/04/15 05:05:29 INFO : Swift container rclone-test-joqikej4lacanov9lesowun9: Container "rclone-test-joqikej4lacanov9lesowun9" removed --- PASS: TestServerSideMove (26.91s) === RUN TestServerSideMoveWithFilter run.go:181: Remote "Hubic Swift container rclone-test-depocay7niqeyum5puxihog3", Local "Local file system at /tmp/rclone4150802735", Modify Window "1ns" 2022/04/15 05:05:29 DEBUG : Creating backend with remote "TestHubic:rclone-test-nivixil5yepuhuj6kahetej5" 2022/04/15 05:05:30 DEBUG : Hubic: Got swift credentials (expiry 2022-04-16 07:00:21 +0200 +0200 in 23h54m50.773360747s) sync_test.go:1215: Server side move (if possible) Hubic Swift container rclone-test-depocay7niqeyum5puxihog3 -> Hubic Swift container rclone-test-nivixil5yepuhuj6kahetej5 2022/04/15 05:05:35 INFO : Swift container rclone-test-nivixil5yepuhuj6kahetej5: Container "rclone-test-nivixil5yepuhuj6kahetej5" created 2022/04/15 05:05:40 DEBUG : empty space: Excluded 2022/04/15 05:05:40 DEBUG : empty space: Excluded 2022/04/15 05:05:40 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2022/04/15 05:05:40 DEBUG : Hubic Swift container rclone-test-nivixil5yepuhuj6kahetej5: Waiting for checks to finish 2022/04/15 05:05:40 DEBUG : Hubic Swift container rclone-test-nivixil5yepuhuj6kahetej5: Waiting for transfers to finish 2022/04/15 05:05:43 DEBUG : potato3: md5 = b1a53993579b830006f2e1610415c32c OK 2022/04/15 05:05:43 INFO : potato3: Copied (server-side copy) 2022/04/15 05:05:44 INFO : potato3: Deleted 2022/04/15 05:05:44 DEBUG : potato2: md5 = d6548b156ea68a4e003e786df99eee76 OK 2022/04/15 05:05:44 INFO : potato2: Copied (server-side copy) 2022/04/15 05:05:45 INFO : potato2: Deleted 2022/04/15 05:05:45 DEBUG : Creating backend with remote "TestHubic:rclone-test-rajilej5wukimik8jixexef7" 2022/04/15 05:05:46 DEBUG : Hubic: Got swift credentials (expiry 2022-04-16 07:00:21 +0200 +0200 in 23h54m34.891043565s) 2022/04/15 05:05:46 DEBUG : empty space: Excluded 2022/04/15 05:05:46 DEBUG : Hubic Swift container rclone-test-rajilej5wukimik8jixexef7: Waiting for checks to finish 2022/04/15 05:05:46 DEBUG : Hubic Swift container rclone-test-rajilej5wukimik8jixexef7: Waiting for transfers to finish 2022/04/15 05:05:46 INFO : Swift container rclone-test-rajilej5wukimik8jixexef7: Container "rclone-test-rajilej5wukimik8jixexef7" created 2022/04/15 05:05:48 DEBUG : potato2: md5 = d6548b156ea68a4e003e786df99eee76 OK 2022/04/15 05:05:48 INFO : potato2: Copied (server-side copy) 2022/04/15 05:05:48 DEBUG : potato3: md5 = b1a53993579b830006f2e1610415c32c OK 2022/04/15 05:05:48 INFO : potato3: Copied (server-side copy) 2022/04/15 05:05:49 INFO : potato3: Deleted 2022/04/15 05:05:49 INFO : potato2: Deleted 2022/04/15 05:05:50 DEBUG : Hubic Swift container rclone-test-rajilej5wukimik8jixexef7: Purge remote 2022/04/15 05:05:50 DEBUG : Waiting for deletions to finish 2022/04/15 05:05:51 INFO : potato2: Deleted 2022/04/15 05:05:51 INFO : potato3: Deleted 2022/04/15 05:05:51 INFO : Swift container rclone-test-rajilej5wukimik8jixexef7: Container "rclone-test-rajilej5wukimik8jixexef7" removed 2022/04/15 05:05:51 DEBUG : Hubic Swift container rclone-test-nivixil5yepuhuj6kahetej5: Purge remote 2022/04/15 05:05:51 DEBUG : Waiting for deletions to finish 2022/04/15 05:05:52 INFO : empty space: Deleted 2022/04/15 05:05:52 INFO : Swift container rclone-test-nivixil5yepuhuj6kahetej5: Container "rclone-test-nivixil5yepuhuj6kahetej5" removed --- PASS: TestServerSideMoveWithFilter (24.04s) === RUN TestServerSideMoveDeleteEmptySourceDirs run.go:181: Remote "Hubic Swift container rclone-test-depocay7niqeyum5puxihog3", Local "Local file system at /tmp/rclone4150802735", Modify Window "1ns" 2022/04/15 05:05:54 DEBUG : Creating backend with remote "TestHubic:rclone-test-xagagov4vipejoj4jenalib0" 2022/04/15 05:05:54 DEBUG : Hubic: Got swift credentials (expiry 2022-04-16 07:00:21 +0200 +0200 in 23h54m26.775131599s) 2022/04/15 05:05:59 DEBUG : tomatoDir: Making directory sync_test.go:1215: Server side move (if possible) Hubic Swift container rclone-test-depocay7niqeyum5puxihog3 -> Hubic Swift container rclone-test-xagagov4vipejoj4jenalib0 2022/04/15 05:05:59 INFO : Swift container rclone-test-xagagov4vipejoj4jenalib0: Container "rclone-test-xagagov4vipejoj4jenalib0" created 2022/04/15 05:06:04 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2022/04/15 05:06:04 DEBUG : Hubic Swift container rclone-test-xagagov4vipejoj4jenalib0: Waiting for checks to finish 2022/04/15 05:06:04 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/15 05:06:04 DEBUG : empty space: Unchanged skipping 2022/04/15 05:06:05 INFO : empty space: Deleted 2022/04/15 05:06:05 DEBUG : Hubic Swift container rclone-test-xagagov4vipejoj4jenalib0: Waiting for transfers to finish 2022/04/15 05:06:06 DEBUG : potato2: md5 = d6548b156ea68a4e003e786df99eee76 OK 2022/04/15 05:06:06 INFO : potato2: Copied (server-side copy) 2022/04/15 05:06:06 DEBUG : potato3: md5 = b1a53993579b830006f2e1610415c32c OK 2022/04/15 05:06:06 INFO : potato3: Copied (server-side copy) 2022/04/15 05:06:07 INFO : potato2: Deleted 2022/04/15 05:06:07 INFO : potato3: Deleted 2022/04/15 05:06:08 DEBUG : Creating backend with remote "TestHubic:rclone-test-sazuxoh0domiyer9nutiqaq1" 2022/04/15 05:06:08 DEBUG : Hubic: Got swift credentials (expiry 2022-04-16 07:00:21 +0200 +0200 in 23h54m12.568700281s) 2022/04/15 05:06:08 DEBUG : tomatoDir: Making directory 2022/04/15 05:06:08 DEBUG : Hubic Swift container rclone-test-sazuxoh0domiyer9nutiqaq1: Waiting for checks to finish 2022/04/15 05:06:08 DEBUG : Hubic Swift container rclone-test-sazuxoh0domiyer9nutiqaq1: Waiting for transfers to finish 2022/04/15 05:06:08 INFO : Swift container rclone-test-sazuxoh0domiyer9nutiqaq1: Container "rclone-test-sazuxoh0domiyer9nutiqaq1" created 2022/04/15 05:06:10 DEBUG : potato2: md5 = d6548b156ea68a4e003e786df99eee76 OK 2022/04/15 05:06:10 INFO : potato2: Copied (server-side copy) 2022/04/15 05:06:11 DEBUG : empty space: md5 = 336d5ebc5436534e61d16e63ddfca327 OK 2022/04/15 05:06:11 INFO : empty space: Copied (server-side copy) 2022/04/15 05:06:11 DEBUG : potato3: md5 = b1a53993579b830006f2e1610415c32c OK 2022/04/15 05:06:11 INFO : potato3: Copied (server-side copy) 2022/04/15 05:06:11 INFO : potato2: Deleted 2022/04/15 05:06:12 INFO : potato3: Deleted 2022/04/15 05:06:12 INFO : empty space: Deleted 2022/04/15 05:06:13 DEBUG : Hubic Swift container rclone-test-sazuxoh0domiyer9nutiqaq1: Purge remote 2022/04/15 05:06:13 DEBUG : Waiting for deletions to finish 2022/04/15 05:06:14 INFO : potato2: Deleted 2022/04/15 05:06:14 INFO : potato3: Deleted 2022/04/15 05:06:14 INFO : empty space: Deleted 2022/04/15 05:06:14 INFO : Swift container rclone-test-sazuxoh0domiyer9nutiqaq1: Container "rclone-test-sazuxoh0domiyer9nutiqaq1" removed 2022/04/15 05:06:14 DEBUG : Hubic Swift container rclone-test-xagagov4vipejoj4jenalib0: Purge remote 2022/04/15 05:06:14 DEBUG : Waiting for deletions to finish 2022/04/15 05:06:15 INFO : Swift container rclone-test-xagagov4vipejoj4jenalib0: Container "rclone-test-xagagov4vipejoj4jenalib0" removed --- PASS: TestServerSideMoveDeleteEmptySourceDirs (21.10s) === RUN TestServerSideMoveOverlap run.go:181: Remote "Hubic Swift container rclone-test-depocay7niqeyum5puxihog3", Local "Local file system at /tmp/rclone4150802735", Modify Window "1ns" 2022/04/15 05:06:15 DEBUG : Creating backend with remote "TestHubic:rclone-test-depocay7niqeyum5puxihog3/rclone-move-test" 2022/04/15 05:06:15 DEBUG : Hubic: Got swift credentials (expiry 2022-04-16 07:00:21 +0200 +0200 in 23h54m5.618706481s) --- PASS: TestServerSideMoveOverlap (3.91s) === RUN TestSyncOverlap run.go:181: Remote "Hubic Swift container rclone-test-depocay7niqeyum5puxihog3", Local "Local file system at /tmp/rclone4150802735", Modify Window "1ns" 2022/04/15 05:06:19 DEBUG : Creating backend with remote "TestHubic:rclone-test-depocay7niqeyum5puxihog3/rclone-sync-test" 2022/04/15 05:06:19 DEBUG : Hubic: Got swift credentials (expiry 2022-04-16 07:00:21 +0200 +0200 in 23h54m1.775332785s) --- PASS: TestSyncOverlap (0.63s) === RUN TestSyncCompareDest run.go:181: Remote "Hubic Swift container rclone-test-depocay7niqeyum5puxihog3", Local "Local file system at /tmp/rclone4150802735", Modify Window "1ns" 2022/04/15 05:06:19 DEBUG : Creating backend with remote "TestHubic:rclone-test-depocay7niqeyum5puxihog3/dst" 2022/04/15 05:06:19 DEBUG : Hubic: Got swift credentials (expiry 2022-04-16 07:00:21 +0200 +0200 in 23h54m1.168261675s) 2022/04/15 05:06:20 DEBUG : Creating backend with remote "TestHubic:rclone-test-depocay7niqeyum5puxihog3/CompareDest" 2022/04/15 05:06:20 DEBUG : Hubic: Got swift credentials (expiry 2022-04-16 07:00:21 +0200 +0200 in 23h54m0.331145073s) 2022/04/15 05:06:21 DEBUG : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3 path dst: Waiting for checks to finish 2022/04/15 05:06:21 DEBUG : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3 path dst: Waiting for transfers to finish 2022/04/15 05:06:23 DEBUG : one: md5 = f97c5d29941bfb1b2fdab0874906ab82 OK 2022/04/15 05:06:23 INFO : one: Copied (new) 2022/04/15 05:06:23 DEBUG : Waiting for deletions to finish 2022/04/15 05:06:23 DEBUG : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3 path dst: Waiting for checks to finish 2022/04/15 05:06:23 DEBUG : one: Sizes differ (src 5 vs dst 3) 2022/04/15 05:06:23 DEBUG : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3 path dst: Waiting for transfers to finish 2022/04/15 05:06:25 DEBUG : one: md5 = 07912d142f5d63ee918b34796b5a2432 OK 2022/04/15 05:06:25 INFO : one: Copied (replaced existing) 2022/04/15 05:06:25 DEBUG : Waiting for deletions to finish 2022/04/15 05:06:30 DEBUG : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3 path dst: Waiting for checks to finish 2022/04/15 05:06:30 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/15 05:06:30 DEBUG : one: Destination found in --compare-dest, skipping 2022/04/15 05:06:30 DEBUG : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3 path dst: Waiting for transfers to finish 2022/04/15 05:06:30 DEBUG : Waiting for deletions to finish 2022/04/15 05:06:30 INFO : There was nothing to transfer 2022/04/15 05:06:32 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/15 05:06:32 DEBUG : two: Destination found in --compare-dest, skipping 2022/04/15 05:06:32 DEBUG : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3 path dst: Waiting for checks to finish 2022/04/15 05:06:32 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/15 05:06:32 DEBUG : one: Destination found in --compare-dest, skipping 2022/04/15 05:06:32 DEBUG : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3 path dst: Waiting for transfers to finish 2022/04/15 05:06:32 DEBUG : Waiting for deletions to finish 2022/04/15 05:06:32 INFO : There was nothing to transfer 2022/04/15 05:06:33 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/15 05:06:33 DEBUG : two: Destination found in --compare-dest, skipping 2022/04/15 05:06:33 DEBUG : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3 path dst: Waiting for checks to finish 2022/04/15 05:06:33 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/15 05:06:33 DEBUG : one: Destination found in --compare-dest, skipping 2022/04/15 05:06:33 DEBUG : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3 path dst: Waiting for transfers to finish 2022/04/15 05:06:33 DEBUG : Waiting for deletions to finish 2022/04/15 05:06:33 INFO : There was nothing to transfer 2022/04/15 05:06:33 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 2022/04/15 05:06:33 DEBUG : two: md5 = b8a9f715dbb64fd5c56e7783c6820a61 OK 2022/04/15 05:06:33 DEBUG : two: Destination found in --compare-dest, skipping 2022/04/15 05:06:33 DEBUG : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3 path dst: Waiting for checks to finish 2022/04/15 05:06:33 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/15 05:06:33 DEBUG : one: Destination found in --compare-dest, skipping 2022/04/15 05:06:33 DEBUG : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3 path dst: Waiting for transfers to finish 2022/04/15 05:06:33 DEBUG : Waiting for deletions to finish 2022/04/15 05:06:33 INFO : There was nothing to transfer 2022/04/15 05:06:34 DEBUG : two: Sizes differ (src 5 vs dst 3) 2022/04/15 05:06:34 DEBUG : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3 path dst: Waiting for checks to finish 2022/04/15 05:06:34 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/15 05:06:34 DEBUG : one: Destination found in --compare-dest, skipping 2022/04/15 05:06:34 DEBUG : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3 path dst: Waiting for transfers to finish 2022/04/15 05:06:36 DEBUG : two: md5 = 2379e4ce8c3380e996ab0509f17069ad OK 2022/04/15 05:06:36 INFO : two: Copied (new) 2022/04/15 05:06:36 DEBUG : Waiting for deletions to finish --- PASS: TestSyncCompareDest (22.46s) === RUN TestSyncMultipleCompareDest run.go:181: Remote "Hubic Swift container rclone-test-depocay7niqeyum5puxihog3", Local "Local file system at /tmp/rclone4150802735", Modify Window "1ns" 2022/04/15 05:06:45 DEBUG : Creating backend with remote "TestHubic:rclone-test-depocay7niqeyum5puxihog3/dest" 2022/04/15 05:06:46 DEBUG : Hubic: Got swift credentials (expiry 2022-04-16 07:00:21 +0200 +0200 in 23h53m34.922195488s) 2022/04/15 05:06:46 DEBUG : Creating backend with remote "TestHubic:rclone-test-depocay7niqeyum5puxihog3/pre-dest1" 2022/04/15 05:06:46 DEBUG : Hubic: Got swift credentials (expiry 2022-04-16 07:00:21 +0200 +0200 in 23h53m34.365070865s) 2022/04/15 05:06:47 DEBUG : Creating backend with remote "TestHubic:rclone-test-depocay7niqeyum5puxihog3/pre-dest2" 2022/04/15 05:06:47 DEBUG : Hubic: Got swift credentials (expiry 2022-04-16 07:00:21 +0200 +0200 in 23h53m33.67657344s) 2022/04/15 05:06:47 DEBUG : 1: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/15 05:06:47 DEBUG : 1: Destination found in --compare-dest, skipping 2022/04/15 05:06:48 DEBUG : 2: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/15 05:06:48 DEBUG : 2: Destination found in --compare-dest, skipping 2022/04/15 05:06:48 DEBUG : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3 path dest: Waiting for checks to finish 2022/04/15 05:06:48 DEBUG : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3 path dest: Waiting for transfers to finish 2022/04/15 05:06:50 DEBUG : 3: md5 = eccbc87e4b5ce2fe28308fd9f2a7baf3 OK 2022/04/15 05:06:50 INFO : 3: Copied (new) 2022/04/15 05:06:50 DEBUG : Waiting for deletions to finish --- PASS: TestSyncMultipleCompareDest (11.61s) === RUN TestSyncCopyDest run.go:181: Remote "Hubic Swift container rclone-test-depocay7niqeyum5puxihog3", Local "Local file system at /tmp/rclone4150802735", Modify Window "1ns" 2022/04/15 05:06:53 DEBUG : Creating backend with remote "TestHubic:rclone-test-depocay7niqeyum5puxihog3/dst" 2022/04/15 05:06:53 DEBUG : Hubic: Got swift credentials (expiry 2022-04-16 07:00:21 +0200 +0200 in 23h53m27.074576026s) 2022/04/15 05:06:54 DEBUG : Creating backend with remote "TestHubic:rclone-test-depocay7niqeyum5puxihog3/CopyDest" 2022/04/15 05:06:54 DEBUG : Hubic: Got swift credentials (expiry 2022-04-16 07:00:21 +0200 +0200 in 23h53m26.609810394s) 2022/04/15 05:06:56 DEBUG : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3 path dst: Waiting for checks to finish 2022/04/15 05:06:56 DEBUG : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3 path dst: Waiting for transfers to finish 2022/04/15 05:06:57 DEBUG : one: md5 = f97c5d29941bfb1b2fdab0874906ab82 OK 2022/04/15 05:06:57 INFO : one: Copied (new) 2022/04/15 05:06:57 DEBUG : Waiting for deletions to finish 2022/04/15 05:06:58 DEBUG : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3 path dst: Waiting for checks to finish 2022/04/15 05:06:58 DEBUG : one: Sizes differ (src 5 vs dst 3) 2022/04/15 05:06:58 DEBUG : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3 path dst: Waiting for transfers to finish 2022/04/15 05:06:59 DEBUG : one: md5 = 07912d142f5d63ee918b34796b5a2432 OK 2022/04/15 05:06:59 INFO : one: Copied (replaced existing) 2022/04/15 05:06:59 DEBUG : Waiting for deletions to finish 2022/04/15 05:07:04 DEBUG : Creating backend with remote "TestHubic:rclone-test-depocay7niqeyum5puxihog3/BackupDir" 2022/04/15 05:07:04 DEBUG : Hubic: Got swift credentials (expiry 2022-04-16 07:00:21 +0200 +0200 in 23h53m16.283186452s) 2022/04/15 05:07:05 DEBUG : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3 path dst: Waiting for checks to finish 2022/04/15 05:07:05 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/15 05:07:05 DEBUG : one: Sizes differ (src 5 vs dst 3) 2022/04/15 05:07:05 INFO : Swift container rclone-test-depocay7niqeyum5puxihog3 path BackupDir: Container "rclone-test-depocay7niqeyum5puxihog3" created 2022/04/15 05:07:07 DEBUG : one: md5 = f97c5d29941bfb1b2fdab0874906ab82 OK 2022/04/15 05:07:07 INFO : one: Copied (server-side copy) 2022/04/15 05:07:08 INFO : one: Deleted 2022/04/15 05:07:10 DEBUG : one: md5 = 07912d142f5d63ee918b34796b5a2432 OK 2022/04/15 05:07:10 INFO : one: Copied (server-side copy) 2022/04/15 05:07:10 DEBUG : one: Destination found in --copy-dest, using server-side copy 2022/04/15 05:07:10 DEBUG : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3 path dst: Waiting for transfers to finish 2022/04/15 05:07:10 DEBUG : Waiting for deletions to finish 2022/04/15 05:07:12 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/15 05:07:13 DEBUG : two: md5 = b8a9f715dbb64fd5c56e7783c6820a61 OK 2022/04/15 05:07:13 INFO : two: Copied (server-side copy) 2022/04/15 05:07:13 DEBUG : two: Destination found in --copy-dest, using server-side copy 2022/04/15 05:07:13 DEBUG : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3 path dst: Waiting for checks to finish 2022/04/15 05:07:13 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/15 05:07:13 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/15 05:07:13 DEBUG : one: Unchanged skipping 2022/04/15 05:07:13 DEBUG : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3 path dst: Waiting for transfers to finish 2022/04/15 05:07:13 DEBUG : Waiting for deletions to finish 2022/04/15 05:07:14 DEBUG : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3 path dst: Waiting for checks to finish 2022/04/15 05:07:14 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/15 05:07:14 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/15 05:07:14 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/15 05:07:14 DEBUG : one: Unchanged skipping 2022/04/15 05:07:14 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/15 05:07:14 DEBUG : two: Unchanged skipping 2022/04/15 05:07:14 DEBUG : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3 path dst: Waiting for transfers to finish 2022/04/15 05:07:14 DEBUG : Waiting for deletions to finish 2022/04/15 05:07:14 INFO : There was nothing to transfer 2022/04/15 05:07:16 DEBUG : three: Sizes differ (src 7 vs dst 5) 2022/04/15 05:07:16 DEBUG : three: Destination not found in --copy-dest 2022/04/15 05:07:16 DEBUG : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3 path dst: Waiting for checks to finish 2022/04/15 05:07:16 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/15 05:07:16 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/15 05:07:16 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/15 05:07:16 DEBUG : two: Unchanged skipping 2022/04/15 05:07:16 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/15 05:07:16 DEBUG : one: Unchanged skipping 2022/04/15 05:07:16 DEBUG : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3 path dst: Waiting for transfers to finish 2022/04/15 05:07:18 DEBUG : three: md5 = 1bccb9dccb3e9f6a3f9d2a8bdb54b7f5 OK 2022/04/15 05:07:18 INFO : three: Copied (new) 2022/04/15 05:07:18 DEBUG : Waiting for deletions to finish --- PASS: TestSyncCopyDest (32.37s) === RUN TestSyncBackupDir run.go:181: Remote "Hubic Swift container rclone-test-depocay7niqeyum5puxihog3", Local "Local file system at /tmp/rclone4150802735", Modify Window "1ns" 2022/04/15 05:07:31 DEBUG : Creating backend with remote "TestHubic:rclone-test-depocay7niqeyum5puxihog3/dst" 2022/04/15 05:07:31 DEBUG : Hubic: Got swift credentials (expiry 2022-04-16 07:00:21 +0200 +0200 in 23h52m49.457185066s) 2022/04/15 05:07:31 DEBUG : Creating backend with remote "TestHubic:rclone-test-depocay7niqeyum5puxihog3/backup" 2022/04/15 05:07:31 DEBUG : Hubic: Got swift credentials (expiry 2022-04-16 07:00:21 +0200 +0200 in 23h52m49.031543656s) 2022/04/15 05:07:32 DEBUG : one: Sizes differ (src 4 vs dst 3) 2022/04/15 05:07:32 DEBUG : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3 path dst: Waiting for checks to finish 2022/04/15 05:07:32 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/15 05:07:32 DEBUG : two: Unchanged skipping 2022/04/15 05:07:33 INFO : Swift container rclone-test-depocay7niqeyum5puxihog3 path backup: Container "rclone-test-depocay7niqeyum5puxihog3" created 2022/04/15 05:07:35 DEBUG : one: md5 = f97c5d29941bfb1b2fdab0874906ab82 OK 2022/04/15 05:07:35 INFO : one: Copied (server-side copy) 2022/04/15 05:07:36 INFO : one: Deleted 2022/04/15 05:07:36 DEBUG : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3 path dst: Waiting for transfers to finish 2022/04/15 05:07:38 DEBUG : one: md5 = c7957179c41f69d44f217a108c7915d8 OK 2022/04/15 05:07:38 INFO : one: Copied (new) 2022/04/15 05:07:38 DEBUG : Waiting for deletions to finish 2022/04/15 05:07:41 DEBUG : three.txt: md5 = 35d6d33467aae9a2e3dccb4b6b027878 OK 2022/04/15 05:07:41 INFO : three.txt: Copied (server-side copy) 2022/04/15 05:07:42 INFO : three.txt: Deleted 2022/04/15 05:07:42 INFO : three.txt: Moved into backup dir 2022/04/15 05:07:45 DEBUG : one: Sizes differ (src 5 vs dst 4) 2022/04/15 05:07:45 DEBUG : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3 path dst: Waiting for checks to finish 2022/04/15 05:07:45 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/15 05:07:45 DEBUG : two: Unchanged skipping 2022/04/15 05:07:47 DEBUG : one: md5 = c7957179c41f69d44f217a108c7915d8 OK 2022/04/15 05:07:47 INFO : one: Copied (server-side copy) 2022/04/15 05:07:48 INFO : one: Deleted 2022/04/15 05:07:48 DEBUG : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3 path dst: Waiting for transfers to finish 2022/04/15 05:07:49 DEBUG : one: md5 = 0f93e81041f0cab37c37a05ae998b219 OK 2022/04/15 05:07:49 INFO : one: Copied (new) 2022/04/15 05:07:49 DEBUG : Waiting for deletions to finish 2022/04/15 05:07:53 DEBUG : three.txt: md5 = 91341eed84691a83caea73aa785736d5 OK 2022/04/15 05:07:53 INFO : three.txt: Copied (server-side copy) 2022/04/15 05:07:54 INFO : three.txt: Deleted 2022/04/15 05:07:54 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:293: Sleeping for 7s just to make sure --- PASS: TestSyncBackupDir (43.14s) === RUN TestSyncBackupDirWithSuffix run.go:181: Remote "Hubic Swift container rclone-test-depocay7niqeyum5puxihog3", Local "Local file system at /tmp/rclone4150802735", Modify Window "1ns" 2022/04/15 05:08:14 DEBUG : Creating backend with remote "TestHubic:rclone-test-depocay7niqeyum5puxihog3/dst" 2022/04/15 05:08:14 DEBUG : Hubic: Got swift credentials (expiry 2022-04-16 07:00:21 +0200 +0200 in 23h52m6.317777373s) 2022/04/15 05:08:15 DEBUG : Creating backend with remote "TestHubic:rclone-test-depocay7niqeyum5puxihog3/backup" 2022/04/15 05:08:15 DEBUG : Hubic: Got swift credentials (expiry 2022-04-16 07:00:21 +0200 +0200 in 23h52m5.798813479s) 2022/04/15 05:08:15 DEBUG : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3 path dst: Waiting for checks to finish 2022/04/15 05:08:15 DEBUG : one: Sizes differ (src 4 vs dst 3) 2022/04/15 05:08:15 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/15 05:08:15 DEBUG : two: Unchanged skipping 2022/04/15 05:08:15 INFO : Swift container rclone-test-depocay7niqeyum5puxihog3 path backup: Container "rclone-test-depocay7niqeyum5puxihog3" created 2022/04/15 05:08:20 DEBUG : one: md5 = f97c5d29941bfb1b2fdab0874906ab82 OK 2022/04/15 05:08:20 INFO : one: Copied (server-side copy) to: one.bak 2022/04/15 05:08:21 INFO : one: Deleted 2022/04/15 05:08:21 DEBUG : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3 path dst: Waiting for transfers to finish 2022/04/15 05:08:22 DEBUG : one: md5 = c7957179c41f69d44f217a108c7915d8 OK 2022/04/15 05:08:22 INFO : one: Copied (new) 2022/04/15 05:08:22 DEBUG : Waiting for deletions to finish 2022/04/15 05:08:24 DEBUG : three.txt: md5 = 35d6d33467aae9a2e3dccb4b6b027878 OK 2022/04/15 05:08:24 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2022/04/15 05:08:25 INFO : three.txt: Deleted 2022/04/15 05:08:25 INFO : three.txt: Moved into backup dir 2022/04/15 05:08:28 DEBUG : one: Sizes differ (src 5 vs dst 4) 2022/04/15 05:08:28 DEBUG : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3 path dst: Waiting for checks to finish 2022/04/15 05:08:28 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/15 05:08:28 DEBUG : two: Unchanged skipping 2022/04/15 05:08:30 DEBUG : one: md5 = c7957179c41f69d44f217a108c7915d8 OK 2022/04/15 05:08:30 INFO : one: Copied (server-side copy) to: one.bak 2022/04/15 05:08:31 INFO : one: Deleted 2022/04/15 05:08:31 DEBUG : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3 path dst: Waiting for transfers to finish 2022/04/15 05:08:33 DEBUG : one: md5 = 0f93e81041f0cab37c37a05ae998b219 OK 2022/04/15 05:08:33 INFO : one: Copied (new) 2022/04/15 05:08:33 DEBUG : Waiting for deletions to finish 2022/04/15 05:08:35 DEBUG : three.txt: md5 = 91341eed84691a83caea73aa785736d5 OK 2022/04/15 05:08:35 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2022/04/15 05:08:36 INFO : three.txt: Deleted 2022/04/15 05:08:36 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirWithSuffix (32.48s) === RUN TestSyncBackupDirWithSuffixKeepExtension run.go:181: Remote "Hubic Swift container rclone-test-depocay7niqeyum5puxihog3", Local "Local file system at /tmp/rclone4150802735", Modify Window "1ns" 2022/04/15 05:08:46 DEBUG : Creating backend with remote "TestHubic:rclone-test-depocay7niqeyum5puxihog3/dst" 2022/04/15 05:08:47 DEBUG : Hubic: Got swift credentials (expiry 2022-04-16 07:00:21 +0200 +0200 in 23h51m33.980131827s) 2022/04/15 05:08:47 DEBUG : Creating backend with remote "TestHubic:rclone-test-depocay7niqeyum5puxihog3/backup" 2022/04/15 05:08:47 DEBUG : Hubic: Got swift credentials (expiry 2022-04-16 07:00:21 +0200 +0200 in 23h51m33.527911657s) 2022/04/15 05:08:48 DEBUG : one: Sizes differ (src 4 vs dst 3) 2022/04/15 05:08:48 DEBUG : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3 path dst: Waiting for checks to finish 2022/04/15 05:08:48 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/15 05:08:48 DEBUG : two: Unchanged skipping 2022/04/15 05:08:49 INFO : Swift container rclone-test-depocay7niqeyum5puxihog3 path backup: Container "rclone-test-depocay7niqeyum5puxihog3" created 2022/04/15 05:08:51 DEBUG : one: md5 = f97c5d29941bfb1b2fdab0874906ab82 OK 2022/04/15 05:08:51 INFO : one: Copied (server-side copy) to: one-2019-01-01 2022/04/15 05:08:52 INFO : one: Deleted 2022/04/15 05:08:52 DEBUG : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3 path dst: Waiting for transfers to finish 2022/04/15 05:08:53 DEBUG : one: md5 = c7957179c41f69d44f217a108c7915d8 OK 2022/04/15 05:08:53 INFO : one: Copied (new) 2022/04/15 05:08:53 DEBUG : Waiting for deletions to finish 2022/04/15 05:08:55 DEBUG : three.txt: md5 = 35d6d33467aae9a2e3dccb4b6b027878 OK 2022/04/15 05:08:55 INFO : three.txt: Copied (server-side copy) to: three-2019-01-01.txt 2022/04/15 05:08:56 INFO : three.txt: Deleted 2022/04/15 05:08:56 INFO : three.txt: Moved into backup dir 2022/04/15 05:08:59 DEBUG : one: Sizes differ (src 5 vs dst 4) 2022/04/15 05:08:59 DEBUG : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3 path dst: Waiting for checks to finish 2022/04/15 05:08:59 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/15 05:08:59 DEBUG : two: Unchanged skipping 2022/04/15 05:09:00 DEBUG : one: md5 = c7957179c41f69d44f217a108c7915d8 OK 2022/04/15 05:09:00 INFO : one: Copied (server-side copy) to: one-2019-01-01 2022/04/15 05:09:01 INFO : one: Deleted 2022/04/15 05:09:01 DEBUG : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3 path dst: Waiting for transfers to finish 2022/04/15 05:09:03 DEBUG : one: md5 = 0f93e81041f0cab37c37a05ae998b219 OK 2022/04/15 05:09:03 INFO : one: Copied (new) 2022/04/15 05:09:03 DEBUG : Waiting for deletions to finish 2022/04/15 05:09:05 DEBUG : three.txt: md5 = 91341eed84691a83caea73aa785736d5 OK 2022/04/15 05:09:05 INFO : three.txt: Copied (server-side copy) to: three-2019-01-01.txt 2022/04/15 05:09:06 INFO : three.txt: Deleted 2022/04/15 05:09:06 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirWithSuffixKeepExtension (30.30s) === RUN TestSyncBackupDirSuffixOnly run.go:181: Remote "Hubic Swift container rclone-test-depocay7niqeyum5puxihog3", Local "Local file system at /tmp/rclone4150802735", Modify Window "1ns" 2022/04/15 05:09:16 DEBUG : Creating backend with remote "TestHubic:rclone-test-depocay7niqeyum5puxihog3/dst" 2022/04/15 05:09:17 DEBUG : Hubic: Got swift credentials (expiry 2022-04-16 07:00:21 +0200 +0200 in 23h51m3.871032477s) 2022/04/15 05:09:17 DEBUG : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3 path dst: Waiting for checks to finish 2022/04/15 05:09:17 DEBUG : one: Sizes differ (src 4 vs dst 3) 2022/04/15 05:09:17 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/15 05:09:17 DEBUG : two: Unchanged skipping 2022/04/15 05:09:19 DEBUG : one: md5 = f97c5d29941bfb1b2fdab0874906ab82 OK 2022/04/15 05:09:19 INFO : one: Copied (server-side copy) to: one.bak 2022/04/15 05:09:20 INFO : one: Deleted 2022/04/15 05:09:20 DEBUG : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3 path dst: Waiting for transfers to finish 2022/04/15 05:09:22 DEBUG : one: md5 = c7957179c41f69d44f217a108c7915d8 OK 2022/04/15 05:09:22 INFO : one: Copied (new) 2022/04/15 05:09:22 DEBUG : Waiting for deletions to finish 2022/04/15 05:09:25 DEBUG : three.txt: md5 = 35d6d33467aae9a2e3dccb4b6b027878 OK 2022/04/15 05:09:25 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2022/04/15 05:09:26 INFO : three.txt: Deleted 2022/04/15 05:09:26 INFO : three.txt: Moved into backup dir 2022/04/15 05:09:28 DEBUG : one.bak: Excluded 2022/04/15 05:09:28 DEBUG : three.txt.bak: Excluded 2022/04/15 05:09:28 DEBUG : one: Sizes differ (src 5 vs dst 4) 2022/04/15 05:09:28 DEBUG : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3 path dst: Waiting for checks to finish 2022/04/15 05:09:28 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/15 05:09:28 DEBUG : two: Unchanged skipping 2022/04/15 05:09:30 DEBUG : one: md5 = c7957179c41f69d44f217a108c7915d8 OK 2022/04/15 05:09:30 INFO : one: Copied (server-side copy) to: one.bak 2022/04/15 05:09:31 INFO : one: Deleted 2022/04/15 05:09:31 DEBUG : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3 path dst: Waiting for transfers to finish 2022/04/15 05:09:33 DEBUG : one: md5 = 0f93e81041f0cab37c37a05ae998b219 OK 2022/04/15 05:09:33 INFO : one: Copied (new) 2022/04/15 05:09:33 DEBUG : Waiting for deletions to finish 2022/04/15 05:09:35 DEBUG : three.txt: md5 = 91341eed84691a83caea73aa785736d5 OK 2022/04/15 05:09:35 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2022/04/15 05:09:36 INFO : three.txt: Deleted 2022/04/15 05:09:36 INFO : three.txt: Moved into backup dir fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:293: Sleeping for 6s just to make sure --- PASS: TestSyncBackupDirSuffixOnly (36.52s) === RUN TestSyncSuffix run.go:181: Remote "Hubic Swift container rclone-test-depocay7niqeyum5puxihog3", Local "Local file system at /tmp/rclone4150802735", Modify Window "1ns" 2022/04/15 05:09:54 DEBUG : Creating backend with remote "TestHubic:rclone-test-depocay7niqeyum5puxihog3/dst" 2022/04/15 05:09:54 DEBUG : Config file has changed externaly - reloading 2022/04/15 05:09:54 DEBUG : Hubic: Got swift credentials (expiry 2022-04-16 07:00:21 +0200 +0200 in 23h50m26.377883414s) 2022/04/15 05:09:54 DEBUG : one: Sizes differ (src 4 vs dst 3) 2022/04/15 05:09:55 INFO : Swift container rclone-test-depocay7niqeyum5puxihog3 path dst: Container "rclone-test-depocay7niqeyum5puxihog3" created 2022/04/15 05:09:57 DEBUG : one: md5 = f97c5d29941bfb1b2fdab0874906ab82 OK 2022/04/15 05:09:57 INFO : one: Copied (server-side copy) to: one.bak 2022/04/15 05:09:58 INFO : one: Deleted 2022/04/15 05:09:59 DEBUG : one: md5 = c7957179c41f69d44f217a108c7915d8 OK 2022/04/15 05:09:59 INFO : one: Copied (new) 2022/04/15 05:09:59 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/15 05:09:59 DEBUG : two: Unchanged skipping 2022/04/15 05:09:59 DEBUG : three.txt: Sizes differ (src 6 vs dst 5) 2022/04/15 05:10:02 DEBUG : three.txt: md5 = 35d6d33467aae9a2e3dccb4b6b027878 OK 2022/04/15 05:10:02 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2022/04/15 05:10:03 INFO : three.txt: Deleted 2022/04/15 05:10:05 DEBUG : three.txt: md5 = 91341eed84691a83caea73aa785736d5 OK 2022/04/15 05:10:05 INFO : three.txt: Copied (new) 2022/04/15 05:10:05 DEBUG : one: Sizes differ (src 5 vs dst 4) 2022/04/15 05:10:07 DEBUG : one: md5 = c7957179c41f69d44f217a108c7915d8 OK 2022/04/15 05:10:07 INFO : one: Copied (server-side copy) to: one.bak 2022/04/15 05:10:09 INFO : one: Deleted 2022/04/15 05:10:10 DEBUG : one: md5 = 0f93e81041f0cab37c37a05ae998b219 OK 2022/04/15 05:10:10 INFO : one: Copied (new) 2022/04/15 05:10:10 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/15 05:10:10 DEBUG : two: Unchanged skipping 2022/04/15 05:10:10 DEBUG : three.txt: Sizes differ (src 19 vs dst 6) 2022/04/15 05:10:12 DEBUG : three.txt: md5 = 91341eed84691a83caea73aa785736d5 OK 2022/04/15 05:10:12 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2022/04/15 05:10:13 INFO : three.txt: Deleted 2022/04/15 05:10:15 DEBUG : three.txt: md5 = 423e01c5e84a30d129b97aedb6e219a9 OK 2022/04/15 05:10:15 INFO : three.txt: Copied (new) --- PASS: TestSyncSuffix (33.33s) === RUN TestSyncSuffixKeepExtension run.go:181: Remote "Hubic Swift container rclone-test-depocay7niqeyum5puxihog3", Local "Local file system at /tmp/rclone4150802735", Modify Window "1ns" 2022/04/15 05:10:29 DEBUG : Creating backend with remote "TestHubic:rclone-test-depocay7niqeyum5puxihog3/dst" 2022/04/15 05:10:29 DEBUG : Hubic: Got swift credentials (expiry 2022-04-16 07:00:21 +0200 +0200 in 23h49m51.677115488s) 2022/04/15 05:10:29 DEBUG : one: Sizes differ (src 4 vs dst 3) 2022/04/15 05:10:30 INFO : Swift container rclone-test-depocay7niqeyum5puxihog3 path dst: Container "rclone-test-depocay7niqeyum5puxihog3" created 2022/04/15 05:10:32 DEBUG : one: md5 = f97c5d29941bfb1b2fdab0874906ab82 OK 2022/04/15 05:10:32 INFO : one: Copied (server-side copy) to: one-2019-01-01 2022/04/15 05:10:33 INFO : one: Deleted 2022/04/15 05:10:35 DEBUG : one: md5 = c7957179c41f69d44f217a108c7915d8 OK 2022/04/15 05:10:35 INFO : one: Copied (new) 2022/04/15 05:10:36 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/15 05:10:36 DEBUG : two: Unchanged skipping 2022/04/15 05:10:36 DEBUG : three.txt: Sizes differ (src 6 vs dst 5) 2022/04/15 05:10:38 DEBUG : three.txt: md5 = 35d6d33467aae9a2e3dccb4b6b027878 OK 2022/04/15 05:10:38 INFO : three.txt: Copied (server-side copy) to: three-2019-01-01.txt 2022/04/15 05:10:39 INFO : three.txt: Deleted 2022/04/15 05:10:40 DEBUG : three.txt: md5 = 91341eed84691a83caea73aa785736d5 OK 2022/04/15 05:10:40 INFO : three.txt: Copied (new) 2022/04/15 05:10:41 DEBUG : one: Sizes differ (src 5 vs dst 4) 2022/04/15 05:10:43 DEBUG : one: md5 = c7957179c41f69d44f217a108c7915d8 OK 2022/04/15 05:10:43 INFO : one: Copied (server-side copy) to: one-2019-01-01 2022/04/15 05:10:44 INFO : one: Deleted 2022/04/15 05:10:46 DEBUG : one: md5 = 0f93e81041f0cab37c37a05ae998b219 OK 2022/04/15 05:10:46 INFO : one: Copied (new) 2022/04/15 05:10:46 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/15 05:10:46 DEBUG : two: Unchanged skipping 2022/04/15 05:10:46 DEBUG : three.txt: Sizes differ (src 19 vs dst 6) 2022/04/15 05:10:48 DEBUG : three.txt: md5 = 91341eed84691a83caea73aa785736d5 OK 2022/04/15 05:10:48 INFO : three.txt: Copied (server-side copy) to: three-2019-01-01.txt 2022/04/15 05:10:49 INFO : three.txt: Deleted 2022/04/15 05:10:50 DEBUG : three.txt: md5 = 423e01c5e84a30d129b97aedb6e219a9 OK 2022/04/15 05:10:50 INFO : three.txt: Copied (new) --- PASS: TestSyncSuffixKeepExtension (34.71s) === RUN TestSyncUTFNorm run.go:181: Remote "Hubic Swift container rclone-test-depocay7niqeyum5puxihog3", Local "Local file system at /tmp/rclone4150802735", Modify Window "1ns" 2022/04/15 05:10:58 DEBUG : Testêé: Sizes differ (src 14 vs dst 18) 2022/04/15 05:10:58 DEBUG : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3: Waiting for checks to finish 2022/04/15 05:10:58 DEBUG : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3: Waiting for transfers to finish 2022/04/15 05:11:00 DEBUG : Testêé: md5 = ce114e4501d2f4e2dcea3e17b546f339 OK 2022/04/15 05:11:00 INFO : Testêé: Copied (replaced existing) to: Testêé 2022/04/15 05:11:00 DEBUG : Waiting for deletions to finish --- PASS: TestSyncUTFNorm (5.36s) === RUN TestSyncImmutable run.go:181: Remote "Hubic Swift container rclone-test-depocay7niqeyum5puxihog3", Local "Local file system at /tmp/rclone4150802735", Modify Window "1ns" 2022/04/15 05:11:02 DEBUG : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3: Waiting for checks to finish 2022/04/15 05:11:02 DEBUG : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3: Waiting for transfers to finish 2022/04/15 05:11:04 DEBUG : existing: md5 = 8ee2027983915ec78acc45027d874316 OK 2022/04/15 05:11:04 INFO : existing: Copied (new) 2022/04/15 05:11:04 DEBUG : Waiting for deletions to finish 2022/04/15 05:11:04 DEBUG : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3: Waiting for checks to finish 2022/04/15 05:11:04 DEBUG : existing: Sizes differ (src 8 vs dst 6) 2022/04/15 05:11:04 ERROR : existing: Source and destination exist but do not match: immutable file modified 2022/04/15 05:11:04 DEBUG : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3: Waiting for transfers to finish 2022/04/15 05:11:04 ERROR : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3: not deleting files as there were IO errors 2022/04/15 05:11:04 ERROR : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3: not deleting directories as there were IO errors fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:293: Sleeping for 6s just to make sure --- PASS: TestSyncImmutable (10.91s) === RUN TestSyncIgnoreCase run.go:181: Remote "Hubic Swift container rclone-test-depocay7niqeyum5puxihog3", Local "Local file system at /tmp/rclone4150802735", Modify Window "1ns" 2022/04/15 05:11:14 DEBUG : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3: Waiting for checks to finish 2022/04/15 05:11:14 DEBUG : existing: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/15 05:11:14 DEBUG : existing: Unchanged skipping 2022/04/15 05:11:14 DEBUG : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3: Waiting for transfers to finish 2022/04/15 05:11:14 DEBUG : Waiting for deletions to finish 2022/04/15 05:11:14 INFO : There was nothing to transfer --- PASS: TestSyncIgnoreCase (3.34s) === RUN TestMaxTransfer === RUN TestMaxTransfer/Hard run.go:181: Remote "Hubic Swift container rclone-test-depocay7niqeyum5puxihog3", Local "Local file system at /tmp/rclone4150802735", 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-depocay7niqeyum5puxihog3", Local "Local file system at /tmp/rclone4150802735", 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-depocay7niqeyum5puxihog3", Local "Local file system at /tmp/rclone4150802735", Modify Window "1ns" sync_test.go:1989: This test only runs on local --- PASS: TestMaxTransfer (0.33s) --- SKIP: TestMaxTransfer/Hard (0.09s) --- SKIP: TestMaxTransfer/Soft (0.09s) --- SKIP: TestMaxTransfer/Cautious (0.15s) === RUN TestSyncConcurrentDelete run.go:181: Remote "Hubic Swift container rclone-test-depocay7niqeyum5puxihog3", Local "Local file system at /tmp/rclone4150802735", Modify Window "1ns" 2022/04/15 05:12:29 DEBUG : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3: Waiting for checks to finish 2022/04/15 05:12:29 DEBUG : both0: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/15 05:12:29 DEBUG : both0: Unchanged skipping 2022/04/15 05:12:30 DEBUG : both10: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/15 05:12:30 DEBUG : both10: Unchanged skipping 2022/04/15 05:12:30 DEBUG : both1: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/15 05:12:30 DEBUG : both1: Unchanged skipping 2022/04/15 05:12:30 DEBUG : both12: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/15 05:12:30 DEBUG : both12: Unchanged skipping 2022/04/15 05:12:30 DEBUG : both11: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/15 05:12:30 DEBUG : both11: Unchanged skipping 2022/04/15 05:12:30 DEBUG : both13: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/15 05:12:30 DEBUG : both13: Unchanged skipping 2022/04/15 05:12:30 DEBUG : both14: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/15 05:12:30 DEBUG : both14: Unchanged skipping 2022/04/15 05:12:30 DEBUG : both15: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/15 05:12:30 DEBUG : both15: Unchanged skipping 2022/04/15 05:12:30 DEBUG : both16: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/15 05:12:30 DEBUG : both16: Unchanged skipping 2022/04/15 05:12:30 DEBUG : both17: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/15 05:12:30 DEBUG : both17: Unchanged skipping 2022/04/15 05:12:30 DEBUG : both18: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/15 05:12:30 DEBUG : both18: Unchanged skipping 2022/04/15 05:12:30 DEBUG : both2: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/15 05:12:30 DEBUG : both2: Unchanged skipping 2022/04/15 05:12:30 DEBUG : both5: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/15 05:12:30 DEBUG : both5: Unchanged skipping 2022/04/15 05:12:30 DEBUG : both4: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/15 05:12:30 DEBUG : both4: Unchanged skipping 2022/04/15 05:12:30 DEBUG : both3: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/15 05:12:30 DEBUG : both3: Unchanged skipping 2022/04/15 05:12:30 DEBUG : both6: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/15 05:12:30 DEBUG : both6: Unchanged skipping 2022/04/15 05:12:30 DEBUG : both7: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/15 05:12:30 DEBUG : both7: Unchanged skipping 2022/04/15 05:12:30 DEBUG : both8: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/15 05:12:30 DEBUG : both8: Unchanged skipping 2022/04/15 05:12:30 DEBUG : both9: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/15 05:12:30 DEBUG : both9: Unchanged skipping 2022/04/15 05:12:30 DEBUG : both19: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/15 05:12:30 DEBUG : both19: Unchanged skipping 2022/04/15 05:12:30 DEBUG : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3: Waiting for transfers to finish 2022/04/15 05:12:30 DEBUG : Waiting for deletions to finish 2022/04/15 05:12:31 INFO : only11: Deleted 2022/04/15 05:12:31 INFO : only10: Deleted 2022/04/15 05:12:31 INFO : only13: Deleted 2022/04/15 05:12:31 INFO : only2: Deleted 2022/04/15 05:12:32 INFO : only6: Deleted 2022/04/15 05:12:32 INFO : only9: Deleted 2022/04/15 05:12:32 INFO : only1: Deleted 2022/04/15 05:12:32 INFO : only3: Deleted 2022/04/15 05:12:33 INFO : only17: Deleted 2022/04/15 05:12:33 INFO : only5: Deleted 2022/04/15 05:12:34 INFO : only16: Deleted 2022/04/15 05:12:34 INFO : only8: Deleted 2022/04/15 05:12:34 INFO : only12: Deleted 2022/04/15 05:12:35 INFO : only15: Deleted 2022/04/15 05:12:35 INFO : only0: Deleted 2022/04/15 05:12:35 INFO : only7: Deleted 2022/04/15 05:12:36 INFO : only14: Deleted 2022/04/15 05:12:36 INFO : only19: Deleted 2022/04/15 05:12:36 INFO : only18: Deleted 2022/04/15 05:12:36 INFO : only4: Deleted 2022/04/15 05:12:36 INFO : There was nothing to transfer --- PASS: TestSyncConcurrentDelete (104.59s) === RUN TestSyncConcurrentTruncate run.go:181: Remote "Hubic Swift container rclone-test-depocay7niqeyum5puxihog3", Local "Local file system at /tmp/rclone4150802735", 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), both3 (6), both4 (6), both5 (6), both6 (6), both7 (6), both8 (6), both9 (6), only0 (6), only1 (6), only10 (6), only11 (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) fstest.go:204: Not found "only12" 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 : 1 Test: TestSyncConcurrentTruncate Messages: 1 objects not found 2022/04/15 05:14:19 DEBUG : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3: Waiting for checks to finish 2022/04/15 05:14:19 DEBUG : both11: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/15 05:14:19 DEBUG : both11: Unchanged skipping 2022/04/15 05:14:19 DEBUG : both0: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/15 05:14:19 DEBUG : both0: Unchanged skipping 2022/04/15 05:14:19 DEBUG : both12: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/15 05:14:19 DEBUG : both12: Unchanged skipping 2022/04/15 05:14:19 DEBUG : both10: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/15 05:14:19 DEBUG : both10: Unchanged skipping 2022/04/15 05:14:19 DEBUG : both13: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/15 05:14:19 DEBUG : both13: Unchanged skipping 2022/04/15 05:14:19 DEBUG : both1: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/15 05:14:19 DEBUG : both1: Unchanged skipping 2022/04/15 05:14:19 DEBUG : both15: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/15 05:14:19 DEBUG : both15: Unchanged skipping 2022/04/15 05:14:19 DEBUG : both14: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/15 05:14:19 DEBUG : both14: Unchanged skipping 2022/04/15 05:14:19 DEBUG : both17: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/15 05:14:19 DEBUG : both17: Unchanged skipping 2022/04/15 05:14:19 DEBUG : both19: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/15 05:14:19 DEBUG : both19: Unchanged skipping 2022/04/15 05:14:19 DEBUG : both16: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/15 05:14:19 DEBUG : both16: Unchanged skipping 2022/04/15 05:14:19 DEBUG : both18: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/15 05:14:19 DEBUG : both18: Unchanged skipping 2022/04/15 05:14:19 DEBUG : both2: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/15 05:14:19 DEBUG : both2: Unchanged skipping 2022/04/15 05:14:19 DEBUG : both3: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/15 05:14:19 DEBUG : both3: Unchanged skipping 2022/04/15 05:14:19 DEBUG : both5: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/15 05:14:19 DEBUG : both5: Unchanged skipping 2022/04/15 05:14:19 DEBUG : both4: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/15 05:14:19 DEBUG : both4: Unchanged skipping 2022/04/15 05:14:19 DEBUG : both7: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/15 05:14:19 DEBUG : both7: Unchanged skipping 2022/04/15 05:14:19 DEBUG : only0: Sizes differ (src 0 vs dst 6) 2022/04/15 05:14:19 DEBUG : only1: Sizes differ (src 0 vs dst 6) 2022/04/15 05:14:19 DEBUG : only10: Sizes differ (src 0 vs dst 6) 2022/04/15 05:14:19 DEBUG : only11: Sizes differ (src 0 vs dst 6) 2022/04/15 05:14:19 DEBUG : only13: Sizes differ (src 0 vs dst 6) 2022/04/15 05:14:19 DEBUG : only14: Sizes differ (src 0 vs dst 6) 2022/04/15 05:14:19 DEBUG : only15: Sizes differ (src 0 vs dst 6) 2022/04/15 05:14:19 DEBUG : only16: Sizes differ (src 0 vs dst 6) 2022/04/15 05:14:19 DEBUG : only17: Sizes differ (src 0 vs dst 6) 2022/04/15 05:14:19 DEBUG : only18: Sizes differ (src 0 vs dst 6) 2022/04/15 05:14:19 DEBUG : only19: Sizes differ (src 0 vs dst 6) 2022/04/15 05:14:19 DEBUG : only2: Sizes differ (src 0 vs dst 6) 2022/04/15 05:14:19 DEBUG : only3: Sizes differ (src 0 vs dst 6) 2022/04/15 05:14:19 DEBUG : only4: Sizes differ (src 0 vs dst 6) 2022/04/15 05:14:19 DEBUG : only5: Sizes differ (src 0 vs dst 6) 2022/04/15 05:14:19 DEBUG : only6: Sizes differ (src 0 vs dst 6) 2022/04/15 05:14:19 DEBUG : only7: Sizes differ (src 0 vs dst 6) 2022/04/15 05:14:19 DEBUG : only8: Sizes differ (src 0 vs dst 6) 2022/04/15 05:14:19 DEBUG : only9: Sizes differ (src 0 vs dst 6) 2022/04/15 05:14:19 DEBUG : both6: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/15 05:14:19 DEBUG : both6: Unchanged skipping 2022/04/15 05:14:19 DEBUG : both8: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/15 05:14:19 DEBUG : both8: Unchanged skipping 2022/04/15 05:14:19 DEBUG : both9: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/15 05:14:19 DEBUG : both9: Unchanged skipping 2022/04/15 05:14:19 DEBUG : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3: Waiting for transfers to finish 2022/04/15 05:14:21 DEBUG : only12: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2022/04/15 05:14:21 INFO : only12: Copied (new) 2022/04/15 05:14:21 DEBUG : only10: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2022/04/15 05:14:21 INFO : only10: Copied (replaced existing) 2022/04/15 05:14:21 DEBUG : only1: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2022/04/15 05:14:21 INFO : only1: Copied (replaced existing) 2022/04/15 05:14:21 DEBUG : only0: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2022/04/15 05:14:21 INFO : only0: Copied (replaced existing) 2022/04/15 05:14:22 DEBUG : only11: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2022/04/15 05:14:22 INFO : only11: Copied (replaced existing) 2022/04/15 05:14:23 DEBUG : only13: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2022/04/15 05:14:23 INFO : only13: Copied (replaced existing) 2022/04/15 05:14:23 DEBUG : only15: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2022/04/15 05:14:23 INFO : only15: Copied (replaced existing) 2022/04/15 05:14:23 DEBUG : only14: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2022/04/15 05:14:23 INFO : only14: Copied (replaced existing) 2022/04/15 05:14:24 DEBUG : only16: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2022/04/15 05:14:24 INFO : only16: Copied (replaced existing) 2022/04/15 05:14:25 DEBUG : only19: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2022/04/15 05:14:25 INFO : only19: Copied (replaced existing) 2022/04/15 05:14:26 DEBUG : only2: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2022/04/15 05:14:26 INFO : only2: Copied (replaced existing) 2022/04/15 05:14:27 DEBUG : only3: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2022/04/15 05:14:27 INFO : only3: Copied (replaced existing) 2022/04/15 05:14:28 DEBUG : only4: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2022/04/15 05:14:28 INFO : only4: Copied (replaced existing) 2022/04/15 05:14:28 DEBUG : only5: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2022/04/15 05:14:28 INFO : only5: Copied (replaced existing) 2022/04/15 05:14:29 DEBUG : only18: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2022/04/15 05:14:29 INFO : only18: Copied (replaced existing) 2022/04/15 05:14:29 DEBUG : only6: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2022/04/15 05:14:29 INFO : only6: Copied (replaced existing) 2022/04/15 05:14:30 DEBUG : only8: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2022/04/15 05:14:30 INFO : only8: Copied (replaced existing) 2022/04/15 05:14:30 DEBUG : only7: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2022/04/15 05:14:30 INFO : only7: Copied (replaced existing) 2022/04/15 05:14:31 DEBUG : only9: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2022/04/15 05:14:31 INFO : only9: Copied (replaced existing) 2022/04/15 05:14:34 DEBUG : only17: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2022/04/15 05:14:34 INFO : only17: Copied (replaced existing) 2022/04/15 05:14:34 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 (0), only10 (0), only11 (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) fstest.go:204: Not found "only12" fstest.go:207: Error Trace: fstest.go:207 fstest.go:311 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:2058 sync_test.go:2066 Error: Not equal: expected: 0 actual : 1 Test: TestSyncConcurrentTruncate Messages: 1 objects not found --- FAIL: TestSyncConcurrentTruncate (149.78s) FAIL 2022/04/15 05:15:30 DEBUG : Hubic Swift container rclone-test-depocay7niqeyum5puxihog3: Purge remote 2022/04/15 05:15:30 DEBUG : Waiting for deletions to finish 2022/04/15 05:15:31 DEBUG : pacer: low level retry 1/10 (error Container Not Empty) 2022/04/15 05:15:31 DEBUG : pacer: Rate limited, increasing sleep to 10ms 2022/04/15 05:15:31 DEBUG : pacer: low level retry 2/10 (error Container Not Empty) 2022/04/15 05:15:31 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2022/04/15 05:15:31 DEBUG : pacer: low level retry 3/10 (error Container Not Empty) 2022/04/15 05:15:31 DEBUG : pacer: Rate limited, increasing sleep to 40ms 2022/04/15 05:15:31 DEBUG : pacer: low level retry 4/10 (error Container Not Empty) 2022/04/15 05:15:31 DEBUG : pacer: Rate limited, increasing sleep to 80ms 2022/04/15 05:15:31 DEBUG : pacer: low level retry 5/10 (error Container Not Empty) 2022/04/15 05:15:31 DEBUG : pacer: Rate limited, increasing sleep to 160ms 2022/04/15 05:15:31 DEBUG : pacer: low level retry 6/10 (error Container Not Empty) 2022/04/15 05:15:31 DEBUG : pacer: Rate limited, increasing sleep to 320ms 2022/04/15 05:15:31 DEBUG : pacer: low level retry 7/10 (error Container Not Empty) 2022/04/15 05:15:31 DEBUG : pacer: Rate limited, increasing sleep to 640ms 2022/04/15 05:15:31 DEBUG : pacer: low level retry 8/10 (error Container Not Empty) 2022/04/15 05:15:31 DEBUG : pacer: Rate limited, increasing sleep to 1.28s 2022/04/15 05:15:32 DEBUG : pacer: low level retry 9/10 (error Container Not Empty) 2022/04/15 05:15:32 DEBUG : pacer: Rate limited, increasing sleep to 2s 2022/04/15 05:15:33 DEBUG : pacer: low level retry 10/10 (error Container Not Empty) 2022/04/15 05:15:33 purge failed: Container Not Empty "./sync.test -test.v -test.timeout 1h0m0s -remote TestHubic: -verbose" - Finished ERROR in 15m12.986177448s (try 1/5): exit status 1: Failed [TestSyncConcurrentTruncate]