"./sync.test -test.v -test.timeout 1h0m0s -remote TestHubic: -verbose" - Starting (try 1/5) 2022/01/12 05:43:12 DEBUG : Creating backend with remote "TestHubic:rclone-test-kikikak6puqugin8mijovak3" 2022/01/12 05:43:12 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2022/01/12 05:43:13 DEBUG : Hubic: Got swift credentials (expiry 2022-01-13 06:04:38 +0100 +0100 in 23h21m24.881703579s) 2022/01/12 05:43:13 DEBUG : Creating backend with remote "/tmp/rclone3342894971" === 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-kikikak6puqugin8mijovak3", Local "Local file system at /tmp/rclone3342894971", Modify Window "1ns" 2022/01/12 05:43:13 INFO : Swift container rclone-test-kikikak6puqugin8mijovak3: Container "rclone-test-kikikak6puqugin8mijovak3" created 2022/01/12 05:43:13 DEBUG : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3: Waiting for checks to finish 2022/01/12 05:43:13 DEBUG : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3: Waiting for transfers to finish 2022/01/12 05:43:13 NOTICE: sub dir/hello world: Skipped copy as --dry-run is set (size 11) --- PASS: TestCopyWithDryRun (0.58s) === RUN TestCopy run.go:181: Remote "Hubic Swift container rclone-test-kikikak6puqugin8mijovak3", Local "Local file system at /tmp/rclone3342894971", Modify Window "1ns" 2022/01/12 05:43:13 DEBUG : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3: Waiting for checks to finish 2022/01/12 05:43:13 DEBUG : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3: Waiting for transfers to finish 2022/01/12 05:43:15 DEBUG : sub dir/hello world: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2022/01/12 05:43:15 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopy (2.40s) === RUN TestCopyMissingDirectory run.go:181: Remote "Hubic Swift container rclone-test-kikikak6puqugin8mijovak3", Local "Local file system at /tmp/rclone3342894971", Modify Window "1ns" 2022/01/12 05:43:16 DEBUG : Creating backend with remote "/non-existing" 2022/01/12 05:43:16 ERROR : Local file system at /non-existing: error reading source root directory: directory not found 2022/01/12 05:43:16 DEBUG : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3: Waiting for checks to finish 2022/01/12 05:43:16 DEBUG : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3: Waiting for transfers to finish --- PASS: TestCopyMissingDirectory (0.15s) === RUN TestCopyNoTraverse run.go:181: Remote "Hubic Swift container rclone-test-kikikak6puqugin8mijovak3", Local "Local file system at /tmp/rclone3342894971", Modify Window "1ns" 2022/01/12 05:43:16 DEBUG : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3: Waiting for checks to finish 2022/01/12 05:43:16 DEBUG : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3: Waiting for transfers to finish 2022/01/12 05:43:17 DEBUG : sub dir/hello world: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2022/01/12 05:43:17 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopyNoTraverse (2.77s) === RUN TestCopyCheckFirst run.go:181: Remote "Hubic Swift container rclone-test-kikikak6puqugin8mijovak3", Local "Local file system at /tmp/rclone3342894971", Modify Window "1ns" 2022/01/12 05:43:19 INFO : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3: Running all checks before starting transfers 2022/01/12 05:43:19 DEBUG : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3: Waiting for checks to finish 2022/01/12 05:43:19 INFO : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3: Checks finished, now starting transfers 2022/01/12 05:43:19 DEBUG : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3: Waiting for transfers to finish 2022/01/12 05:43:20 DEBUG : sub dir/hello world: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2022/01/12 05:43:20 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopyCheckFirst (2.44s) === RUN TestSyncNoTraverse run.go:181: Remote "Hubic Swift container rclone-test-kikikak6puqugin8mijovak3", Local "Local file system at /tmp/rclone3342894971", Modify Window "1ns" 2022/01/12 05:43:21 ERROR : Ignoring --no-traverse with sync 2022/01/12 05:43:21 DEBUG : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3: Waiting for checks to finish 2022/01/12 05:43:21 DEBUG : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3: Waiting for transfers to finish 2022/01/12 05:43:23 DEBUG : sub dir/hello world: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2022/01/12 05:43:23 INFO : sub dir/hello world: Copied (new) 2022/01/12 05:43:23 DEBUG : Waiting for deletions to finish --- PASS: TestSyncNoTraverse (2.71s) === RUN TestCopyWithDepth run.go:181: Remote "Hubic Swift container rclone-test-kikikak6puqugin8mijovak3", Local "Local file system at /tmp/rclone3342894971", Modify Window "1ns" 2022/01/12 05:43:24 DEBUG : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3: Waiting for checks to finish 2022/01/12 05:43:24 DEBUG : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3: Waiting for transfers to finish 2022/01/12 05:43:25 DEBUG : hello world2: md5 = cc2c857f89648dbd139d7b2a6665957d OK 2022/01/12 05:43:25 INFO : hello world2: Copied (new) --- PASS: TestCopyWithDepth (2.42s) === RUN TestCopyWithFilesFrom run.go:181: Remote "Hubic Swift container rclone-test-kikikak6puqugin8mijovak3", Local "Local file system at /tmp/rclone3342894971", Modify Window "1ns" 2022/01/12 05:43:26 DEBUG : hello world2: Excluded 2022/01/12 05:43:26 DEBUG : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3: Waiting for checks to finish 2022/01/12 05:43:26 DEBUG : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3: Waiting for transfers to finish 2022/01/12 05:43:28 DEBUG : potato2: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2022/01/12 05:43:28 INFO : potato2: Copied (new) --- PASS: TestCopyWithFilesFrom (2.90s) === RUN TestCopyWithFilesFromAndNoTraverse run.go:181: Remote "Hubic Swift container rclone-test-kikikak6puqugin8mijovak3", Local "Local file system at /tmp/rclone3342894971", Modify Window "1ns" 2022/01/12 05:43:29 DEBUG : potato2: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:43:29 DEBUG : potato2: Unchanged skipping 2022/01/12 05:43:29 DEBUG : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3: Waiting for checks to finish 2022/01/12 05:43:29 DEBUG : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3: Waiting for transfers 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:184 sync_test.go:187 Error: Should be true Test: TestCopyWithFilesFromAndNoTraverse Messages: listing wrong, want potato2 (11) got fstest.go:204: Not found "potato2" fstest.go:207: Error Trace: fstest.go:207 fstest.go:311 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:184 sync_test.go:187 Error: Not equal: expected: 0 actual : 1 Test: TestCopyWithFilesFromAndNoTraverse Messages: 1 objects not found --- FAIL: TestCopyWithFilesFromAndNoTraverse (7.31s) === RUN TestCopyEmptyDirectories run.go:181: Remote "Hubic Swift container rclone-test-kikikak6puqugin8mijovak3", Local "Local file system at /tmp/rclone3342894971", Modify Window "1ns" 2022/01/12 05:43:36 DEBUG : sub dir2: Making directory 2022/01/12 05:43:36 DEBUG : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3: Waiting for checks to finish 2022/01/12 05:43:36 DEBUG : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3: Waiting for transfers to finish 2022/01/12 05:43:38 DEBUG : sub dir/hello world: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2022/01/12 05:43:38 INFO : sub dir/hello world: Copied (new) 2022/01/12 05:43:38 DEBUG : sub dir2: Making directory 2022/01/12 05:43:38 DEBUG : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3: copied 1 directories fstest.go:251: Filtering empty directory "sub dir2" --- PASS: TestCopyEmptyDirectories (3.05s) === RUN TestMoveEmptyDirectories run.go:181: Remote "Hubic Swift container rclone-test-kikikak6puqugin8mijovak3", Local "Local file system at /tmp/rclone3342894971", Modify Window "1ns" 2022/01/12 05:43:39 DEBUG : sub dir2: Making directory 2022/01/12 05:43:39 DEBUG : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3: Waiting for checks to finish 2022/01/12 05:43:39 DEBUG : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3: Waiting for transfers to finish 2022/01/12 05:43:41 DEBUG : sub dir/hello world: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2022/01/12 05:43:41 INFO : sub dir/hello world: Copied (new) 2022/01/12 05:43:41 INFO : sub dir/hello world: Deleted 2022/01/12 05:43:41 DEBUG : sub dir: Making directory 2022/01/12 05:43:41 DEBUG : sub dir2: Making directory 2022/01/12 05:43:41 DEBUG : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3: copied 2 directories fstest.go:251: Filtering empty directory "sub dir2" --- PASS: TestMoveEmptyDirectories (2.82s) === RUN TestSyncEmptyDirectories run.go:181: Remote "Hubic Swift container rclone-test-kikikak6puqugin8mijovak3", Local "Local file system at /tmp/rclone3342894971", Modify Window "1ns" 2022/01/12 05:43:42 DEBUG : sub dir2: Making directory 2022/01/12 05:43:42 DEBUG : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3: Waiting for checks to finish 2022/01/12 05:43:42 DEBUG : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3: Waiting for transfers to finish 2022/01/12 05:43:44 DEBUG : sub dir/hello world: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2022/01/12 05:43:44 INFO : sub dir/hello world: Copied (new) 2022/01/12 05:43:44 DEBUG : sub dir2: Making directory 2022/01/12 05:43:44 DEBUG : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3: copied 1 directories 2022/01/12 05:43:44 DEBUG : Waiting for deletions to finish fstest.go:251: Filtering empty directory "sub dir2" --- PASS: TestSyncEmptyDirectories (2.75s) === RUN TestServerSideCopy run.go:181: Remote "Hubic Swift container rclone-test-kikikak6puqugin8mijovak3", Local "Local file system at /tmp/rclone3342894971", Modify Window "1ns" 2022/01/12 05:43:47 DEBUG : Creating backend with remote "TestHubic:rclone-test-qikeqef1yibokog2puvukes9" 2022/01/12 05:43:47 DEBUG : Hubic: Got swift credentials (expiry 2022-01-13 06:04:38 +0100 +0100 in 23h20m50.580395407s) sync_test.go:275: Server side copy (if possible) Hubic Swift container rclone-test-kikikak6puqugin8mijovak3 -> Hubic Swift container rclone-test-qikeqef1yibokog2puvukes9 2022/01/12 05:43:47 DEBUG : Hubic Swift container rclone-test-qikeqef1yibokog2puvukes9: Waiting for checks to finish 2022/01/12 05:43:47 DEBUG : Hubic Swift container rclone-test-qikeqef1yibokog2puvukes9: Waiting for transfers to finish 2022/01/12 05:43:48 INFO : Swift container rclone-test-qikeqef1yibokog2puvukes9: Container "rclone-test-qikeqef1yibokog2puvukes9" created 2022/01/12 05:43:50 DEBUG : sub dir/hello world: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2022/01/12 05:43:50 INFO : sub dir/hello world: Copied (server-side copy) 2022/01/12 05:43:50 DEBUG : Hubic Swift container rclone-test-qikeqef1yibokog2puvukes9: Purge remote 2022/01/12 05:43:50 DEBUG : Waiting for deletions to finish 2022/01/12 05:43:51 INFO : sub dir/hello world: Deleted 2022/01/12 05:43:52 INFO : Swift container rclone-test-qikeqef1yibokog2puvukes9: Container "rclone-test-qikeqef1yibokog2puvukes9" removed --- PASS: TestServerSideCopy (7.88s) === RUN TestCopyAfterDelete run.go:181: Remote "Hubic Swift container rclone-test-kikikak6puqugin8mijovak3", Local "Local file system at /tmp/rclone3342894971", Modify Window "1ns" 2022/01/12 05:43:55 ERROR : : error listing: directory not found 2022/01/12 05:43:55 DEBUG : Local file system at /tmp/rclone3342894971: Making directory 2022/01/12 05:43:56 DEBUG : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3: Waiting for checks to finish 2022/01/12 05:43:56 DEBUG : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3: Waiting for transfers to finish --- PASS: TestCopyAfterDelete (3.79s) === RUN TestCopyRedownload run.go:181: Remote "Hubic Swift container rclone-test-kikikak6puqugin8mijovak3", Local "Local file system at /tmp/rclone3342894971", Modify Window "1ns" 2022/01/12 05:43:58 DEBUG : Local file system at /tmp/rclone3342894971: Waiting for checks to finish 2022/01/12 05:43:58 DEBUG : Local file system at /tmp/rclone3342894971: Waiting for transfers to finish 2022/01/12 05:43:58 DEBUG : sub dir/hello world: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2022/01/12 05:43:58 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopyRedownload (2.96s) === RUN TestSyncBasedOnCheckSum run.go:181: Remote "Hubic Swift container rclone-test-kikikak6puqugin8mijovak3", Local "Local file system at /tmp/rclone3342894971", Modify Window "1ns" 2022/01/12 05:44:00 DEBUG : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3: Waiting for checks to finish 2022/01/12 05:44:00 DEBUG : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3: Waiting for transfers to finish 2022/01/12 05:44:01 DEBUG : check sum: md5 = 336d5ebc5436534e61d16e63ddfca327 OK 2022/01/12 05:44:01 INFO : check sum: Copied (new) 2022/01/12 05:44:01 DEBUG : Waiting for deletions to finish 2022/01/12 05:44:01 DEBUG : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3: Waiting for checks to finish 2022/01/12 05:44:01 DEBUG : check sum: md5 = 336d5ebc5436534e61d16e63ddfca327 OK 2022/01/12 05:44:01 DEBUG : check sum: Size and md5 of src and dst objects identical 2022/01/12 05:44:01 DEBUG : check sum: Unchanged skipping 2022/01/12 05:44:01 DEBUG : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3: Waiting for transfers to finish 2022/01/12 05:44:01 DEBUG : Waiting for deletions to finish 2022/01/12 05:44:01 INFO : There was nothing to transfer --- PASS: TestSyncBasedOnCheckSum (2.86s) === RUN TestSyncSizeOnly run.go:181: Remote "Hubic Swift container rclone-test-kikikak6puqugin8mijovak3", Local "Local file system at /tmp/rclone3342894971", Modify Window "1ns" 2022/01/12 05:44:02 DEBUG : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3: Waiting for checks to finish 2022/01/12 05:44:02 DEBUG : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3: Waiting for transfers to finish 2022/01/12 05:44:04 DEBUG : sizeonly: md5 = 8ee2027983915ec78acc45027d874316 OK 2022/01/12 05:44:04 INFO : sizeonly: Copied (new) 2022/01/12 05:44:04 DEBUG : Waiting for deletions to finish 2022/01/12 05:44:04 DEBUG : sizeonly: Sizes identical 2022/01/12 05:44:04 DEBUG : sizeonly: Unchanged skipping 2022/01/12 05:44:04 DEBUG : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3: Waiting for checks to finish 2022/01/12 05:44:04 DEBUG : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3: Waiting for transfers to finish 2022/01/12 05:44:04 DEBUG : Waiting for deletions to finish 2022/01/12 05:44:04 INFO : There was nothing to transfer --- PASS: TestSyncSizeOnly (2.30s) === RUN TestSyncIgnoreSize run.go:181: Remote "Hubic Swift container rclone-test-kikikak6puqugin8mijovak3", Local "Local file system at /tmp/rclone3342894971", Modify Window "1ns" 2022/01/12 05:44:05 DEBUG : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3: Waiting for checks to finish 2022/01/12 05:44:05 DEBUG : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3: Waiting for transfers to finish 2022/01/12 05:44:06 DEBUG : ignore-size: md5 = 98bf7d8c15784f0a3d63204441e1e2aa OK 2022/01/12 05:44:06 INFO : ignore-size: Copied (new) 2022/01/12 05:44:06 DEBUG : Waiting for deletions to finish 2022/01/12 05:44:06 DEBUG : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3: Waiting for checks to finish 2022/01/12 05:44:06 DEBUG : ignore-size: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:44:06 DEBUG : ignore-size: Unchanged skipping 2022/01/12 05:44:06 DEBUG : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3: Waiting for transfers to finish 2022/01/12 05:44:06 DEBUG : Waiting for deletions to finish 2022/01/12 05:44:06 INFO : There was nothing to transfer --- PASS: TestSyncIgnoreSize (2.81s) === RUN TestSyncIgnoreTimes run.go:181: Remote "Hubic Swift container rclone-test-kikikak6puqugin8mijovak3", Local "Local file system at /tmp/rclone3342894971", Modify Window "1ns" 2022/01/12 05:44:09 DEBUG : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3: Waiting for checks to finish 2022/01/12 05:44:09 DEBUG : existing: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:44:09 DEBUG : existing: Unchanged skipping 2022/01/12 05:44:09 DEBUG : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3: Waiting for transfers to finish 2022/01/12 05:44:09 DEBUG : Waiting for deletions to finish 2022/01/12 05:44:09 INFO : There was nothing to transfer 2022/01/12 05:44:10 DEBUG : existing: Transferring unconditionally as --ignore-times is in use 2022/01/12 05:44:10 DEBUG : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3: Waiting for checks to finish 2022/01/12 05:44:10 DEBUG : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3: Waiting for transfers to finish 2022/01/12 05:44:11 DEBUG : existing: md5 = 8ee2027983915ec78acc45027d874316 OK 2022/01/12 05:44:11 INFO : existing: Copied (replaced existing) 2022/01/12 05:44:11 DEBUG : Waiting for deletions to finish --- PASS: TestSyncIgnoreTimes (4.90s) === RUN TestSyncIgnoreExisting run.go:181: Remote "Hubic Swift container rclone-test-kikikak6puqugin8mijovak3", Local "Local file system at /tmp/rclone3342894971", Modify Window "1ns" 2022/01/12 05:44:13 DEBUG : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3: Waiting for checks to finish 2022/01/12 05:44:13 DEBUG : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3: Waiting for transfers to finish 2022/01/12 05:44:14 DEBUG : existing: md5 = 8ee2027983915ec78acc45027d874316 OK 2022/01/12 05:44:14 INFO : existing: Copied (new) 2022/01/12 05:44:14 DEBUG : Waiting for deletions to finish 2022/01/12 05:44:15 DEBUG : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3: Waiting for checks to finish 2022/01/12 05:44:15 DEBUG : existing: Destination exists, skipping 2022/01/12 05:44:15 DEBUG : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3: Waiting for transfers to finish 2022/01/12 05:44:15 DEBUG : Waiting for deletions to finish 2022/01/12 05:44:15 INFO : There was nothing to transfer --- PASS: TestSyncIgnoreExisting (3.28s) === RUN TestSyncIgnoreErrors run.go:181: Remote "Hubic Swift container rclone-test-kikikak6puqugin8mijovak3", Local "Local file system at /tmp/rclone3342894971", Modify Window "1ns" 2022/01/12 05:44:19 DEBUG : d: Making directory fstest.go:251: Filtering empty directory "d" 2022/01/12 05:44:19 DEBUG : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3: Waiting for checks to finish 2022/01/12 05:44:19 DEBUG : c/non empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:44:19 DEBUG : c/non empty space: Unchanged skipping 2022/01/12 05:44:19 DEBUG : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3: Waiting for transfers to finish 2022/01/12 05:44:21 DEBUG : a/potato2: md5 = d6548b156ea68a4e003e786df99eee76 OK 2022/01/12 05:44:21 INFO : a/potato2: Copied (new) 2022/01/12 05:44:21 DEBUG : Waiting for deletions to finish 2022/01/12 05:44:21 INFO : b/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: TestSyncIgnoreErrors (15.27s) === RUN TestSyncAfterChangingModtimeOnly run.go:181: Remote "Hubic Swift container rclone-test-kikikak6puqugin8mijovak3", Local "Local file system at /tmp/rclone3342894971", Modify Window "1ns" 2022/01/12 05:44:33 DEBUG : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3: Waiting for checks to finish 2022/01/12 05:44:33 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/01/12 05:44:33 DEBUG : empty space: md5 = 336d5ebc5436534e61d16e63ddfca327 OK 2022/01/12 05:44:33 NOTICE: empty space: Skipped update modification time as --dry-run is set (size 1) 2022/01/12 05:44:33 DEBUG : empty space: Unchanged skipping 2022/01/12 05:44:33 DEBUG : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3: Waiting for transfers to finish 2022/01/12 05:44:33 DEBUG : Waiting for deletions to finish 2022/01/12 05:44:33 INFO : There was nothing to transfer 2022/01/12 05:44:33 DEBUG : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3: Waiting for checks to finish 2022/01/12 05:44:33 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/01/12 05:44:33 DEBUG : empty space: md5 = 336d5ebc5436534e61d16e63ddfca327 OK 2022/01/12 05:44:34 INFO : empty space: Updated modification time in destination 2022/01/12 05:44:34 DEBUG : empty space: Unchanged skipping 2022/01/12 05:44:34 DEBUG : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3: Waiting for transfers to finish 2022/01/12 05:44:34 DEBUG : Waiting for deletions to finish 2022/01/12 05:44:34 INFO : There was nothing to transfer --- PASS: TestSyncAfterChangingModtimeOnly (3.89s) === RUN TestSyncAfterChangingModtimeOnlyWithNoUpdateModTime run.go:181: Remote "Hubic Swift container rclone-test-kikikak6puqugin8mijovak3", Local "Local file system at /tmp/rclone3342894971", Modify Window "1ns" 2022/01/12 05:44:36 DEBUG : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3: Waiting for checks to finish 2022/01/12 05:44:36 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/01/12 05:44:36 DEBUG : empty space: md5 = 336d5ebc5436534e61d16e63ddfca327 OK 2022/01/12 05:44:36 DEBUG : empty space: Unchanged skipping 2022/01/12 05:44:36 DEBUG : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3: Waiting for transfers to finish 2022/01/12 05:44:36 DEBUG : Waiting for deletions to finish 2022/01/12 05:44:36 INFO : There was nothing to transfer --- PASS: TestSyncAfterChangingModtimeOnlyWithNoUpdateModTime (2.59s) === RUN TestSyncDoesntUpdateModtime run.go:181: Remote "Hubic Swift container rclone-test-kikikak6puqugin8mijovak3", Local "Local file system at /tmp/rclone3342894971", Modify Window "1ns" 2022/01/12 05:44:39 DEBUG : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3: Waiting for checks to finish 2022/01/12 05:44:39 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/01/12 05:44:39 DEBUG : foo: md5 = acbd18db4cc2f85cedef654fccc4a4d8 (Local file system at /tmp/rclone3342894971) 2022/01/12 05:44:39 DEBUG : foo: md5 = 37b51d194a7513e45b56f6524f2d51f2 (Swift container rclone-test-kikikak6puqugin8mijovak3) 2022/01/12 05:44:39 DEBUG : foo: md5 differ 2022/01/12 05:44:39 DEBUG : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3: Waiting for transfers to finish 2022/01/12 05:44:40 DEBUG : foo: md5 = acbd18db4cc2f85cedef654fccc4a4d8 OK 2022/01/12 05:44:40 INFO : foo: Copied (replaced existing) 2022/01/12 05:44:40 DEBUG : Waiting for deletions to finish --- PASS: TestSyncDoesntUpdateModtime (3.91s) === RUN TestSyncAfterAddingAFile run.go:181: Remote "Hubic Swift container rclone-test-kikikak6puqugin8mijovak3", Local "Local file system at /tmp/rclone3342894971", Modify Window "1ns" 2022/01/12 05:44:43 DEBUG : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3: Waiting for checks to finish 2022/01/12 05:44:43 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:44:43 DEBUG : empty space: Unchanged skipping 2022/01/12 05:44:43 DEBUG : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3: Waiting for transfers to finish 2022/01/12 05:44:45 DEBUG : potato: md5 = d6548b156ea68a4e003e786df99eee76 OK 2022/01/12 05:44:45 INFO : potato: Copied (new) 2022/01/12 05:44:45 DEBUG : Waiting for deletions to finish --- PASS: TestSyncAfterAddingAFile (5.42s) === RUN TestSyncAfterChangingFilesSizeOnly run.go:181: Remote "Hubic Swift container rclone-test-kikikak6puqugin8mijovak3", Local "Local file system at /tmp/rclone3342894971", Modify Window "1ns" 2022/01/12 05:44:49 DEBUG : potato: Sizes differ (src 21 vs dst 60) 2022/01/12 05:44:49 DEBUG : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3: Waiting for checks to finish 2022/01/12 05:44:49 DEBUG : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3: Waiting for transfers to finish 2022/01/12 05:44:50 DEBUG : potato: md5 = 100defcf18c42a1e0dc42a789b107cd2 OK 2022/01/12 05:44:50 INFO : potato: Copied (replaced existing) 2022/01/12 05:44:50 DEBUG : Waiting for deletions to finish --- PASS: TestSyncAfterChangingFilesSizeOnly (5.10s) === RUN TestSyncAfterChangingContentsOnly run.go:181: Remote "Hubic Swift container rclone-test-kikikak6puqugin8mijovak3", Local "Local file system at /tmp/rclone3342894971", Modify Window "1ns" 2022/01/12 05:44:53 DEBUG : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3: Waiting for checks to finish 2022/01/12 05:44:54 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/01/12 05:44:54 DEBUG : potato: md5 = e4cb6955d9106df6263c45fcfc10f163 (Local file system at /tmp/rclone3342894971) 2022/01/12 05:44:54 DEBUG : potato: md5 = 100defcf18c42a1e0dc42a789b107cd2 (Swift container rclone-test-kikikak6puqugin8mijovak3) 2022/01/12 05:44:54 DEBUG : potato: md5 differ 2022/01/12 05:44:54 DEBUG : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3: Waiting for transfers to finish 2022/01/12 05:44:55 DEBUG : potato: md5 = e4cb6955d9106df6263c45fcfc10f163 OK 2022/01/12 05:44:55 INFO : potato: Copied (replaced existing) 2022/01/12 05:44:55 DEBUG : Waiting for deletions to finish --- PASS: TestSyncAfterChangingContentsOnly (4.41s) === RUN TestSyncAfterRemovingAFileAndAddingAFileDryRun run.go:181: Remote "Hubic Swift container rclone-test-kikikak6puqugin8mijovak3", Local "Local file system at /tmp/rclone3342894971", Modify Window "1ns" 2022/01/12 05:44:59 NOTICE: potato2: Skipped copy as --dry-run is set (size 60) 2022/01/12 05:44:59 DEBUG : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3: Waiting for checks to finish 2022/01/12 05:44:59 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:44:59 DEBUG : empty space: Unchanged skipping 2022/01/12 05:44:59 DEBUG : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3: Waiting for transfers to finish 2022/01/12 05:44:59 DEBUG : Waiting for deletions to finish 2022/01/12 05:44:59 NOTICE: potato: Skipped delete as --dry-run is set (size 21) fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:293: Sleeping for 6s just to make sure --- PASS: TestSyncAfterRemovingAFileAndAddingAFileDryRun (12.35s) === RUN TestSyncAfterRemovingAFileAndAddingAFile run.go:181: Remote "Hubic Swift container rclone-test-kikikak6puqugin8mijovak3", Local "Local file system at /tmp/rclone3342894971", Modify Window "1ns" 2022/01/12 05:45:12 DEBUG : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3: Waiting for checks to finish 2022/01/12 05:45:12 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:45:12 DEBUG : empty space: Unchanged skipping 2022/01/12 05:45:12 DEBUG : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3: Waiting for transfers to finish 2022/01/12 05:45:13 DEBUG : potato2: md5 = d6548b156ea68a4e003e786df99eee76 OK 2022/01/12 05:45:13 INFO : potato2: Copied (new) 2022/01/12 05:45:13 DEBUG : Waiting for deletions to finish 2022/01/12 05:45:14 INFO : potato: Deleted --- PASS: TestSyncAfterRemovingAFileAndAddingAFile (7.97s) === RUN TestSyncAfterRemovingAFileAndAddingAFileSubDir run.go:181: Remote "Hubic Swift container rclone-test-kikikak6puqugin8mijovak3", Local "Local file system at /tmp/rclone3342894971", Modify Window "1ns" 2022/01/12 05:45:20 DEBUG : d: Making directory 2022/01/12 05:45:20 DEBUG : d/e: Making directory fstest.go:251: Filtering empty directory "d" fstest.go:251: Filtering empty directory "d/e" 2022/01/12 05:45:20 DEBUG : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3: Waiting for checks to finish 2022/01/12 05:45:20 DEBUG : c/non empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:45:20 DEBUG : c/non empty space: Unchanged skipping 2022/01/12 05:45:20 DEBUG : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3: Waiting for transfers to finish 2022/01/12 05:45:21 DEBUG : a/potato2: md5 = d6548b156ea68a4e003e786df99eee76 OK 2022/01/12 05:45:21 INFO : a/potato2: Copied (new) 2022/01/12 05:45:21 DEBUG : Waiting for deletions to finish 2022/01/12 05:45:22 INFO : b/potato: Deleted --- PASS: TestSyncAfterRemovingAFileAndAddingAFileSubDir (7.82s) === RUN TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors run.go:181: Remote "Hubic Swift container rclone-test-kikikak6puqugin8mijovak3", Local "Local file system at /tmp/rclone3342894971", Modify Window "1ns" 2022/01/12 05:45:27 DEBUG : d: Making directory fstest.go:251: Filtering empty directory "d" 2022/01/12 05:45:28 DEBUG : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3: Waiting for checks to finish 2022/01/12 05:45:28 DEBUG : c/non empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:45:28 DEBUG : c/non empty space: Unchanged skipping 2022/01/12 05:45:28 DEBUG : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3: Waiting for transfers to finish 2022/01/12 05:45:29 DEBUG : a/potato2: md5 = d6548b156ea68a4e003e786df99eee76 OK 2022/01/12 05:45:29 INFO : a/potato2: Copied (new) 2022/01/12 05:45:29 ERROR : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3: not deleting files as there were IO errors 2022/01/12 05:45:29 ERROR : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3: not deleting directories as there were IO errors fstest.go:251: Filtering empty directory "d" --- PASS: TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors (7.70s) === RUN TestSyncDeleteAfter --- PASS: TestSyncDeleteAfter (0.00s) === RUN TestSyncDeleteDuring run.go:181: Remote "Hubic Swift container rclone-test-kikikak6puqugin8mijovak3", Local "Local file system at /tmp/rclone3342894971", Modify Window "1ns" 2022/01/12 05:45:35 DEBUG : Waiting for deletions to finish 2022/01/12 05:45:35 DEBUG : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3: Waiting for checks to finish 2022/01/12 05:45:35 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:45:35 DEBUG : empty space: Unchanged skipping 2022/01/12 05:45:35 DEBUG : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3: Waiting for transfers to finish 2022/01/12 05:45:37 INFO : potato: Deleted 2022/01/12 05:45:37 DEBUG : potato2: md5 = d6548b156ea68a4e003e786df99eee76 OK 2022/01/12 05:45:37 INFO : potato2: Copied (new) --- PASS: TestSyncDeleteDuring (6.98s) === RUN TestSyncDeleteBefore run.go:181: Remote "Hubic Swift container rclone-test-kikikak6puqugin8mijovak3", Local "Local file system at /tmp/rclone3342894971", Modify Window "1ns" 2022/01/12 05:45:43 DEBUG : Waiting for deletions to finish 2022/01/12 05:45:43 DEBUG : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3: Waiting for checks to finish 2022/01/12 05:45:43 DEBUG : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3: Waiting for transfers to finish 2022/01/12 05:45:44 INFO : potato: Deleted 2022/01/12 05:45:44 DEBUG : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3: Waiting for checks to finish 2022/01/12 05:45:44 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:45:44 DEBUG : empty space: Unchanged skipping 2022/01/12 05:45:44 DEBUG : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3: Waiting for transfers to finish 2022/01/12 05:45:45 DEBUG : potato2: md5 = d6548b156ea68a4e003e786df99eee76 OK 2022/01/12 05:45:45 INFO : potato2: Copied (new) --- PASS: TestSyncDeleteBefore (8.34s) === RUN TestCopyDeleteBefore run.go:181: Remote "Hubic Swift container rclone-test-kikikak6puqugin8mijovak3", Local "Local file system at /tmp/rclone3342894971", Modify Window "1ns" 2022/01/12 05:45:49 DEBUG : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3: Waiting for checks to finish 2022/01/12 05:45:49 DEBUG : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3: Waiting for transfers to finish 2022/01/12 05:45:51 DEBUG : potato2: md5 = 9519bc2e940e1696ea7365b4b81adedc OK 2022/01/12 05:45:51 INFO : potato2: Copied (new) --- PASS: TestCopyDeleteBefore (5.92s) === RUN TestSyncWithExclude run.go:181: Remote "Hubic Swift container rclone-test-kikikak6puqugin8mijovak3", Local "Local file system at /tmp/rclone3342894971", Modify Window "1ns" 2022/01/12 05:45:56 DEBUG : enormous: Excluded 2022/01/12 05:45:56 DEBUG : potato2: Excluded 2022/01/12 05:45:57 DEBUG : potato2: Excluded 2022/01/12 05:45:57 DEBUG : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3: Waiting for checks to finish 2022/01/12 05:45:57 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:45:57 DEBUG : empty space: Unchanged skipping 2022/01/12 05:45:57 DEBUG : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3: Waiting for transfers to finish 2022/01/12 05:45:57 DEBUG : Waiting for deletions to finish 2022/01/12 05:45:57 INFO : There was nothing to transfer 2022/01/12 05:45:57 DEBUG : enormous: Excluded 2022/01/12 05:45:57 DEBUG : potato2: Excluded 2022/01/12 05:45:57 DEBUG : potato2: Excluded 2022/01/12 05:45:57 DEBUG : Local file system at /tmp/rclone3342894971: Waiting for checks to finish 2022/01/12 05:45:57 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:45:57 DEBUG : empty space: Unchanged skipping 2022/01/12 05:45:57 DEBUG : Local file system at /tmp/rclone3342894971: Waiting for transfers to finish 2022/01/12 05:45:57 DEBUG : Waiting for deletions to finish 2022/01/12 05:45:57 INFO : There was nothing to transfer --- PASS: TestSyncWithExclude (5.50s) === RUN TestSyncWithExcludeAndDeleteExcluded run.go:181: Remote "Hubic Swift container rclone-test-kikikak6puqugin8mijovak3", Local "Local file system at /tmp/rclone3342894971", Modify Window "1ns" 2022/01/12 05:46:04 DEBUG : enormous: Excluded 2022/01/12 05:46:04 DEBUG : potato2: Excluded 2022/01/12 05:46:04 DEBUG : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3: Waiting for checks to finish 2022/01/12 05:46:04 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:46:04 DEBUG : empty space: Unchanged skipping 2022/01/12 05:46:04 DEBUG : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3: Waiting for transfers to finish 2022/01/12 05:46:04 DEBUG : Waiting for deletions to finish 2022/01/12 05:46:05 INFO : potato2: Deleted 2022/01/12 05:46:05 INFO : enormous: Deleted 2022/01/12 05:46:05 INFO : There was nothing to transfer 2022/01/12 05:46:05 DEBUG : Local file system at /tmp/rclone3342894971: Waiting for checks to finish 2022/01/12 05:46:05 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:46:05 DEBUG : empty space: Unchanged skipping 2022/01/12 05:46:05 DEBUG : Local file system at /tmp/rclone3342894971: Waiting for transfers to finish 2022/01/12 05:46:05 DEBUG : Waiting for deletions to finish 2022/01/12 05:46:05 INFO : potato2: Deleted 2022/01/12 05:46:05 INFO : enormous: Deleted 2022/01/12 05:46:05 INFO : There was nothing to transfer --- PASS: TestSyncWithExcludeAndDeleteExcluded (7.17s) === RUN TestSyncWithUpdateOlder run.go:181: Remote "Hubic Swift container rclone-test-kikikak6puqugin8mijovak3", Local "Local file system at /tmp/rclone3342894971", Modify Window "1ns" 2022/01/12 05:46:12 DEBUG : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3: Waiting for checks to finish 2022/01/12 05:46:12 DEBUG : four: Sizes differ (src 4 vs dst 8) 2022/01/12 05:46:12 DEBUG : four: Destination mod time is within 1s of source but files differ, transferring 2022/01/12 05:46:12 DEBUG : two: md5 = b8a9f715dbb64fd5c56e7783c6820a61 (Local file system at /tmp/rclone3342894971) 2022/01/12 05:46:12 DEBUG : two: md5 = 0f82d86afa0f5dc965c5c15aca58dcfb (Swift container rclone-test-kikikak6puqugin8mijovak3) 2022/01/12 05:46:12 DEBUG : two: md5 differ 2022/01/12 05:46:12 DEBUG : three: Sizes identical 2022/01/12 05:46:12 DEBUG : three: Destination mod time is within 1s of source and files identical, skipping 2022/01/12 05:46:12 DEBUG : one: Destination is newer than source, skipping 2022/01/12 05:46:12 DEBUG : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3: Waiting for transfers to finish 2022/01/12 05:46:14 DEBUG : four: md5 = 8cbad96aced40b3838dd9f07f6ef5772 OK 2022/01/12 05:46:14 INFO : four: Copied (replaced existing) 2022/01/12 05:46:14 DEBUG : two: md5 = b8a9f715dbb64fd5c56e7783c6820a61 OK 2022/01/12 05:46:14 INFO : two: Copied (replaced existing) 2022/01/12 05:46:14 DEBUG : five: md5 = 30056e1cab7a61d256fc8edd970d14f5 OK 2022/01/12 05:46:14 INFO : five: Copied (new) 2022/01/12 05:46:14 DEBUG : Waiting for deletions to finish 2022/01/12 05:46:15 DEBUG : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3: Waiting for checks to finish 2022/01/12 05:46:15 DEBUG : four: md5 = 8cbad96aced40b3838dd9f07f6ef5772 OK 2022/01/12 05:46:15 DEBUG : four: Size and md5 of src and dst objects identical 2022/01/12 05:46:15 DEBUG : four: Destination mod time is within 1s of source and files identical, skipping 2022/01/12 05:46:15 DEBUG : one: Destination is newer than source, skipping 2022/01/12 05:46:15 DEBUG : five: md5 = 30056e1cab7a61d256fc8edd970d14f5 OK 2022/01/12 05:46:15 DEBUG : five: Size and md5 of src and dst objects identical 2022/01/12 05:46:15 DEBUG : five: Destination mod time is within 1s of source and files identical, skipping 2022/01/12 05:46:15 DEBUG : three: md5 = 35d6d33467aae9a2e3dccb4b6b027878 (Local file system at /tmp/rclone3342894971) 2022/01/12 05:46:15 DEBUG : three: md5 = 413af0de1f97a2155acf2b8b26ab36e2 (Swift container rclone-test-kikikak6puqugin8mijovak3) 2022/01/12 05:46:15 DEBUG : three: md5 differ 2022/01/12 05:46:15 DEBUG : three: Destination mod time is within 1s of source but files differ, transferring 2022/01/12 05:46:15 DEBUG : two: md5 = b8a9f715dbb64fd5c56e7783c6820a61 OK 2022/01/12 05:46:15 DEBUG : two: Size and md5 of src and dst objects identical 2022/01/12 05:46:15 DEBUG : two: Destination mod time is within 1s of source and files identical, skipping 2022/01/12 05:46:15 DEBUG : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3: Waiting for transfers to finish 2022/01/12 05:46:17 DEBUG : three: md5 = 35d6d33467aae9a2e3dccb4b6b027878 OK 2022/01/12 05:46:17 INFO : three: Copied (replaced existing) 2022/01/12 05:46:17 DEBUG : Waiting for deletions to finish --- PASS: TestSyncWithUpdateOlder (16.12s) === 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-kikikak6puqugin8mijovak3", Local "Local file system at /tmp/rclone3342894971", Modify Window "1ns" sync_test.go:1058: Can track renames: true 2022/01/12 05:46:22 INFO : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3: Making map for --track-renames 2022/01/12 05:46:22 INFO : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3: Finished making map for --track-renames 2022/01/12 05:46:22 DEBUG : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3: Waiting for checks to finish 2022/01/12 05:46:22 DEBUG : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3: Waiting for renames to finish 2022/01/12 05:46:22 DEBUG : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3: Waiting for transfers to finish 2022/01/12 05:46:24 DEBUG : potato: md5 = 7f6fa9ddec9bfebda9510af0323fd696 OK 2022/01/12 05:46:24 INFO : potato: Copied (new) 2022/01/12 05:46:24 DEBUG : yam: md5 = 48dc443644c4b89556dc06834b46451d OK 2022/01/12 05:46:24 INFO : yam: Copied (new) 2022/01/12 05:46:24 DEBUG : Waiting for deletions to finish 2022/01/12 05:46:24 INFO : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3: Making map for --track-renames 2022/01/12 05:46:24 DEBUG : potato: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:46:24 DEBUG : potato: Unchanged skipping 2022/01/12 05:46:24 INFO : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3: Finished making map for --track-renames 2022/01/12 05:46:24 DEBUG : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3: Waiting for checks to finish 2022/01/12 05:46:24 DEBUG : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3: Waiting for renames to finish 2022/01/12 05:46:26 DEBUG : yam: md5 = 48dc443644c4b89556dc06834b46451d OK 2022/01/12 05:46:26 INFO : yam: Copied (server-side copy) to: yaml 2022/01/12 05:46:27 INFO : yam: Deleted 2022/01/12 05:46:27 INFO : yaml: Renamed from "yam" 2022/01/12 05:46:27 DEBUG : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3: Waiting for transfers to finish 2022/01/12 05:46:27 DEBUG : Waiting for deletions to finish --- PASS: TestSyncWithTrackRenames (7.26s) === RUN TestParseRenamesStrategyModtime --- PASS: TestParseRenamesStrategyModtime (0.00s) === RUN TestRenamesStrategyModtime --- PASS: TestRenamesStrategyModtime (0.00s) === RUN TestSyncWithTrackRenamesStrategyModtime run.go:181: Remote "Hubic Swift container rclone-test-kikikak6puqugin8mijovak3", Local "Local file system at /tmp/rclone3342894971", Modify Window "1ns" sync_test.go:1127: Can track renames: true 2022/01/12 05:46:29 INFO : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3: Making map for --track-renames 2022/01/12 05:46:29 INFO : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3: Finished making map for --track-renames 2022/01/12 05:46:29 DEBUG : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3: Waiting for checks to finish 2022/01/12 05:46:29 DEBUG : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3: Waiting for renames to finish 2022/01/12 05:46:29 DEBUG : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3: Waiting for transfers to finish 2022/01/12 05:46:31 DEBUG : potato: md5 = 7f6fa9ddec9bfebda9510af0323fd696 OK 2022/01/12 05:46:31 INFO : potato: Copied (new) 2022/01/12 05:46:31 DEBUG : yam: md5 = 48dc443644c4b89556dc06834b46451d OK 2022/01/12 05:46:31 INFO : yam: Copied (new) 2022/01/12 05:46:31 DEBUG : Waiting for deletions to finish 2022/01/12 05:46:32 INFO : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3: Making map for --track-renames 2022/01/12 05:46:32 INFO : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3: Finished making map for --track-renames 2022/01/12 05:46:32 DEBUG : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3: Waiting for checks to finish 2022/01/12 05:46:32 DEBUG : potato: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:46:32 DEBUG : potato: Unchanged skipping 2022/01/12 05:46:32 DEBUG : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3: Waiting for renames to finish 2022/01/12 05:46:34 DEBUG : yam: md5 = 48dc443644c4b89556dc06834b46451d OK 2022/01/12 05:46:34 INFO : yam: Copied (server-side copy) to: yaml 2022/01/12 05:46:34 INFO : yam: Deleted 2022/01/12 05:46:34 INFO : yaml: Renamed from "yam" 2022/01/12 05:46:34 DEBUG : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3: Waiting for transfers to finish 2022/01/12 05:46:34 DEBUG : Waiting for deletions to finish --- PASS: TestSyncWithTrackRenamesStrategyModtime (7.17s) === RUN TestSyncWithTrackRenamesStrategyLeaf run.go:181: Remote "Hubic Swift container rclone-test-kikikak6puqugin8mijovak3", Local "Local file system at /tmp/rclone3342894971", Modify Window "1ns" sync_test.go:1163: Can track renames: true 2022/01/12 05:46:37 INFO : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3: Making map for --track-renames 2022/01/12 05:46:37 INFO : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3: Finished making map for --track-renames 2022/01/12 05:46:37 DEBUG : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3: Waiting for checks to finish 2022/01/12 05:46:37 DEBUG : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3: Waiting for renames to finish 2022/01/12 05:46:37 DEBUG : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3: Waiting for transfers to finish 2022/01/12 05:46:38 DEBUG : sub/yam: md5 = 48dc443644c4b89556dc06834b46451d OK 2022/01/12 05:46:38 INFO : sub/yam: Copied (new) 2022/01/12 05:46:38 DEBUG : potato: md5 = 7f6fa9ddec9bfebda9510af0323fd696 OK 2022/01/12 05:46:38 INFO : potato: Copied (new) 2022/01/12 05:46:38 DEBUG : Waiting for deletions to finish 2022/01/12 05:46:38 INFO : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3: Making map for --track-renames 2022/01/12 05:46:38 INFO : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3: Finished making map for --track-renames 2022/01/12 05:46:38 DEBUG : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3: Waiting for checks to finish 2022/01/12 05:46:39 DEBUG : potato: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:46:39 DEBUG : potato: Unchanged skipping 2022/01/12 05:46:39 DEBUG : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3: Waiting for renames to finish 2022/01/12 05:46:41 DEBUG : sub/yam: md5 = 48dc443644c4b89556dc06834b46451d OK 2022/01/12 05:46:41 INFO : sub/yam: Copied (server-side copy) to: yam 2022/01/12 05:46:42 INFO : sub/yam: Deleted 2022/01/12 05:46:42 INFO : yam: Renamed from "sub/yam" 2022/01/12 05:46:42 DEBUG : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3: Waiting for transfers to finish 2022/01/12 05:46:42 DEBUG : Waiting for deletions to finish --- PASS: TestSyncWithTrackRenamesStrategyLeaf (7.26s) === RUN TestMoveWithDeleteEmptySrcDirs run.go:181: Remote "Hubic Swift container rclone-test-kikikak6puqugin8mijovak3", Local "Local file system at /tmp/rclone3342894971", Modify Window "1ns" 2022/01/12 05:46:44 DEBUG : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3: Waiting for checks to finish 2022/01/12 05:46:44 DEBUG : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3: Waiting for transfers to finish 2022/01/12 05:46:45 DEBUG : nested/sub dir/file: md5 = 83d3784ea62518eafc60e98d84f877ad OK 2022/01/12 05:46:45 INFO : nested/sub dir/file: Copied (new) 2022/01/12 05:46:45 INFO : nested/sub dir/file: Deleted 2022/01/12 05:46:46 DEBUG : sub dir/hello world: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2022/01/12 05:46:46 INFO : sub dir/hello world: Copied (new) 2022/01/12 05:46:46 INFO : sub dir/hello world: Deleted 2022/01/12 05:46:46 INFO : sub dir: Removing directory 2022/01/12 05:46:46 INFO : nested/sub dir: Removing directory 2022/01/12 05:46:46 INFO : nested: Removing directory 2022/01/12 05:46:46 DEBUG : Local file system at /tmp/rclone3342894971: deleted 3 directories --- PASS: TestMoveWithDeleteEmptySrcDirs (3.74s) === RUN TestMoveWithoutDeleteEmptySrcDirs run.go:181: Remote "Hubic Swift container rclone-test-kikikak6puqugin8mijovak3", Local "Local file system at /tmp/rclone3342894971", Modify Window "1ns" 2022/01/12 05:46:48 DEBUG : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3: Waiting for checks to finish 2022/01/12 05:46:48 DEBUG : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3: Waiting for transfers to finish 2022/01/12 05:46:49 DEBUG : nested/sub dir/file: md5 = 83d3784ea62518eafc60e98d84f877ad OK 2022/01/12 05:46:49 INFO : nested/sub dir/file: Copied (new) 2022/01/12 05:46:49 INFO : nested/sub dir/file: Deleted 2022/01/12 05:46:49 DEBUG : sub dir/hello world: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2022/01/12 05:46:49 INFO : sub dir/hello world: Copied (new) 2022/01/12 05:46:49 INFO : sub dir/hello world: Deleted --- PASS: TestMoveWithoutDeleteEmptySrcDirs (3.77s) === RUN TestMoveWithIgnoreExisting run.go:181: Remote "Hubic Swift container rclone-test-kikikak6puqugin8mijovak3", Local "Local file system at /tmp/rclone3342894971", Modify Window "1ns" 2022/01/12 05:46:51 DEBUG : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3: Waiting for checks to finish 2022/01/12 05:46:51 DEBUG : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3: Waiting for transfers to finish 2022/01/12 05:46:53 DEBUG : existing: md5 = 8ee2027983915ec78acc45027d874316 OK 2022/01/12 05:46:53 INFO : existing: Copied (new) 2022/01/12 05:46:53 INFO : existing: Deleted 2022/01/12 05:46:54 DEBUG : existing-b: md5 = 006f87892f47ef9aa60fa5ed01a440fb OK 2022/01/12 05:46:54 INFO : existing-b: Copied (new) 2022/01/12 05:46:54 INFO : existing-b: Deleted 2022/01/12 05:46:54 DEBUG : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3: Waiting for checks to finish 2022/01/12 05:46:54 DEBUG : existing: Destination exists, skipping 2022/01/12 05:46:54 DEBUG : existing: Not removing source file as destination file exists and --ignore-existing is set 2022/01/12 05:46:54 DEBUG : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3: Waiting for transfers to finish 2022/01/12 05:46:54 INFO : There was nothing to transfer --- PASS: TestMoveWithIgnoreExisting (4.59s) === RUN TestServerSideMove run.go:181: Remote "Hubic Swift container rclone-test-kikikak6puqugin8mijovak3", Local "Local file system at /tmp/rclone3342894971", Modify Window "1ns" 2022/01/12 05:46:56 DEBUG : Creating backend with remote "TestHubic:rclone-test-zihizap0pujizic3sehavuw5" 2022/01/12 05:46:56 DEBUG : Hubic: Got swift credentials (expiry 2022-01-13 06:04:38 +0100 +0100 in 23h17m41.277843956s) sync_test.go:1215: Server side move (if possible) Hubic Swift container rclone-test-kikikak6puqugin8mijovak3 -> Hubic Swift container rclone-test-zihizap0pujizic3sehavuw5 2022/01/12 05:47:01 INFO : Swift container rclone-test-zihizap0pujizic3sehavuw5: Container "rclone-test-zihizap0pujizic3sehavuw5" created 2022/01/12 05:47:04 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2022/01/12 05:47:04 DEBUG : Hubic Swift container rclone-test-zihizap0pujizic3sehavuw5: Waiting for checks to finish 2022/01/12 05:47:04 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:47:04 DEBUG : empty space: Unchanged skipping 2022/01/12 05:47:05 INFO : empty space: Deleted 2022/01/12 05:47:05 DEBUG : Hubic Swift container rclone-test-zihizap0pujizic3sehavuw5: Waiting for transfers to finish 2022/01/12 05:47:06 DEBUG : potato2: md5 = d6548b156ea68a4e003e786df99eee76 OK 2022/01/12 05:47:06 INFO : potato2: Copied (server-side copy) 2022/01/12 05:47:06 DEBUG : potato3: md5 = b1a53993579b830006f2e1610415c32c OK 2022/01/12 05:47:06 INFO : potato3: Copied (server-side copy) 2022/01/12 05:47:07 INFO : potato2: Deleted 2022/01/12 05:47:07 INFO : potato3: Deleted 2022/01/12 05:47:07 DEBUG : Creating backend with remote "TestHubic:rclone-test-yabikuk9yewadob3dohuguc7" 2022/01/12 05:47:07 DEBUG : Hubic: Got swift credentials (expiry 2022-01-13 06:04:38 +0100 +0100 in 23h17m30.181299839s) 2022/01/12 05:47:08 DEBUG : Hubic Swift container rclone-test-yabikuk9yewadob3dohuguc7: Waiting for checks to finish 2022/01/12 05:47:08 DEBUG : Hubic Swift container rclone-test-yabikuk9yewadob3dohuguc7: Waiting for transfers to finish 2022/01/12 05:47:08 INFO : Swift container rclone-test-yabikuk9yewadob3dohuguc7: Container "rclone-test-yabikuk9yewadob3dohuguc7" created 2022/01/12 05:47:09 DEBUG : potato2: md5 = d6548b156ea68a4e003e786df99eee76 OK 2022/01/12 05:47:09 INFO : potato2: Copied (server-side copy) 2022/01/12 05:47:09 DEBUG : potato3: md5 = b1a53993579b830006f2e1610415c32c OK 2022/01/12 05:47:09 INFO : potato3: Copied (server-side copy) 2022/01/12 05:47:10 DEBUG : empty space: md5 = 336d5ebc5436534e61d16e63ddfca327 OK 2022/01/12 05:47:10 INFO : empty space: Copied (server-side copy) 2022/01/12 05:47:10 INFO : potato2: Deleted 2022/01/12 05:47:10 INFO : potato3: Deleted 2022/01/12 05:47:10 INFO : empty space: Deleted 2022/01/12 05:47:11 DEBUG : Hubic Swift container rclone-test-yabikuk9yewadob3dohuguc7: Purge remote 2022/01/12 05:47:11 DEBUG : Waiting for deletions to finish 2022/01/12 05:47:11 INFO : potato2: Deleted 2022/01/12 05:47:12 INFO : empty space: Deleted 2022/01/12 05:47:12 INFO : potato3: Deleted 2022/01/12 05:47:12 INFO : Swift container rclone-test-yabikuk9yewadob3dohuguc7: Container "rclone-test-yabikuk9yewadob3dohuguc7" removed 2022/01/12 05:47:12 DEBUG : Hubic Swift container rclone-test-zihizap0pujizic3sehavuw5: Purge remote 2022/01/12 05:47:12 DEBUG : Waiting for deletions to finish 2022/01/12 05:47:12 INFO : Swift container rclone-test-zihizap0pujizic3sehavuw5: Container "rclone-test-zihizap0pujizic3sehavuw5" removed --- PASS: TestServerSideMove (15.97s) === RUN TestServerSideMoveWithFilter run.go:181: Remote "Hubic Swift container rclone-test-kikikak6puqugin8mijovak3", Local "Local file system at /tmp/rclone3342894971", Modify Window "1ns" 2022/01/12 05:47:12 DEBUG : Creating backend with remote "TestHubic:rclone-test-denobuy9rohenew3kikikif4" 2022/01/12 05:47:12 DEBUG : Hubic: Got swift credentials (expiry 2022-01-13 06:04:38 +0100 +0100 in 23h17m25.335272918s) sync_test.go:1215: Server side move (if possible) Hubic Swift container rclone-test-kikikak6puqugin8mijovak3 -> Hubic Swift container rclone-test-denobuy9rohenew3kikikif4 2022/01/12 05:47:17 INFO : Swift container rclone-test-denobuy9rohenew3kikikif4: Container "rclone-test-denobuy9rohenew3kikikif4" created 2022/01/12 05:47:21 DEBUG : empty space: Excluded 2022/01/12 05:47:21 DEBUG : empty space: Excluded 2022/01/12 05:47:21 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2022/01/12 05:47:21 DEBUG : Hubic Swift container rclone-test-denobuy9rohenew3kikikif4: Waiting for checks to finish 2022/01/12 05:47:21 DEBUG : Hubic Swift container rclone-test-denobuy9rohenew3kikikif4: Waiting for transfers to finish 2022/01/12 05:47:23 DEBUG : potato2: md5 = d6548b156ea68a4e003e786df99eee76 OK 2022/01/12 05:47:23 INFO : potato2: Copied (server-side copy) 2022/01/12 05:47:23 DEBUG : potato3: md5 = b1a53993579b830006f2e1610415c32c OK 2022/01/12 05:47:23 INFO : potato3: Copied (server-side copy) 2022/01/12 05:47:24 INFO : potato2: Deleted 2022/01/12 05:47:24 INFO : potato3: Deleted 2022/01/12 05:47:24 DEBUG : Creating backend with remote "TestHubic:rclone-test-wiruhiw3qigafub0vafofat4" 2022/01/12 05:47:25 DEBUG : Hubic: Got swift credentials (expiry 2022-01-13 06:04:38 +0100 +0100 in 23h17m12.873651196s) 2022/01/12 05:47:25 DEBUG : empty space: Excluded 2022/01/12 05:47:25 DEBUG : Hubic Swift container rclone-test-wiruhiw3qigafub0vafofat4: Waiting for checks to finish 2022/01/12 05:47:25 DEBUG : Hubic Swift container rclone-test-wiruhiw3qigafub0vafofat4: Waiting for transfers to finish 2022/01/12 05:47:25 INFO : Swift container rclone-test-wiruhiw3qigafub0vafofat4: Container "rclone-test-wiruhiw3qigafub0vafofat4" created 2022/01/12 05:47:27 DEBUG : potato3: md5 = b1a53993579b830006f2e1610415c32c OK 2022/01/12 05:47:27 INFO : potato3: Copied (server-side copy) 2022/01/12 05:47:27 DEBUG : potato2: md5 = d6548b156ea68a4e003e786df99eee76 OK 2022/01/12 05:47:27 INFO : potato2: Copied (server-side copy) 2022/01/12 05:47:27 INFO : potato3: Deleted 2022/01/12 05:47:28 INFO : potato2: Deleted 2022/01/12 05:47:28 DEBUG : Hubic Swift container rclone-test-wiruhiw3qigafub0vafofat4: Purge remote 2022/01/12 05:47:28 DEBUG : Waiting for deletions to finish 2022/01/12 05:47:29 INFO : potato3: Deleted 2022/01/12 05:47:29 INFO : potato2: Deleted 2022/01/12 05:47:29 INFO : Swift container rclone-test-wiruhiw3qigafub0vafofat4: Container "rclone-test-wiruhiw3qigafub0vafofat4" removed 2022/01/12 05:47:29 DEBUG : Hubic Swift container rclone-test-denobuy9rohenew3kikikif4: Purge remote 2022/01/12 05:47:29 DEBUG : Waiting for deletions to finish 2022/01/12 05:47:30 INFO : empty space: Deleted 2022/01/12 05:47:30 INFO : Swift container rclone-test-denobuy9rohenew3kikikif4: Container "rclone-test-denobuy9rohenew3kikikif4" removed --- PASS: TestServerSideMoveWithFilter (19.44s) === RUN TestServerSideMoveDeleteEmptySourceDirs run.go:181: Remote "Hubic Swift container rclone-test-kikikak6puqugin8mijovak3", Local "Local file system at /tmp/rclone3342894971", Modify Window "1ns" 2022/01/12 05:47:31 DEBUG : Creating backend with remote "TestHubic:rclone-test-gugapuh5yoquven1larusab9" 2022/01/12 05:47:32 DEBUG : Hubic: Got swift credentials (expiry 2022-01-13 06:04:38 +0100 +0100 in 23h17m5.874270063s) 2022/01/12 05:47:36 DEBUG : tomatoDir: Making directory sync_test.go:1215: Server side move (if possible) Hubic Swift container rclone-test-kikikak6puqugin8mijovak3 -> Hubic Swift container rclone-test-gugapuh5yoquven1larusab9 2022/01/12 05:47:37 INFO : Swift container rclone-test-gugapuh5yoquven1larusab9: Container "rclone-test-gugapuh5yoquven1larusab9" created 2022/01/12 05:47:40 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2022/01/12 05:47:40 DEBUG : Hubic Swift container rclone-test-gugapuh5yoquven1larusab9: Waiting for checks to finish 2022/01/12 05:47:40 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:47:40 DEBUG : empty space: Unchanged skipping 2022/01/12 05:47:41 INFO : empty space: Deleted 2022/01/12 05:47:41 DEBUG : Hubic Swift container rclone-test-gugapuh5yoquven1larusab9: Waiting for transfers to finish 2022/01/12 05:47:41 DEBUG : potato2: md5 = d6548b156ea68a4e003e786df99eee76 OK 2022/01/12 05:47:41 INFO : potato2: Copied (server-side copy) 2022/01/12 05:47:42 DEBUG : potato3: md5 = b1a53993579b830006f2e1610415c32c OK 2022/01/12 05:47:42 INFO : potato3: Copied (server-side copy) 2022/01/12 05:47:42 INFO : potato2: Deleted 2022/01/12 05:47:43 INFO : potato3: Deleted 2022/01/12 05:47:43 DEBUG : Creating backend with remote "TestHubic:rclone-test-xexosox7xogutem9lusogez6" 2022/01/12 05:47:43 DEBUG : Hubic: Got swift credentials (expiry 2022-01-13 06:04:38 +0100 +0100 in 23h16m54.285257004s) 2022/01/12 05:47:43 DEBUG : tomatoDir: Making directory 2022/01/12 05:47:43 DEBUG : Hubic Swift container rclone-test-xexosox7xogutem9lusogez6: Waiting for checks to finish 2022/01/12 05:47:43 DEBUG : Hubic Swift container rclone-test-xexosox7xogutem9lusogez6: Waiting for transfers to finish 2022/01/12 05:47:43 INFO : Swift container rclone-test-xexosox7xogutem9lusogez6: Container "rclone-test-xexosox7xogutem9lusogez6" created 2022/01/12 05:47:45 DEBUG : empty space: md5 = 336d5ebc5436534e61d16e63ddfca327 OK 2022/01/12 05:47:45 INFO : empty space: Copied (server-side copy) 2022/01/12 05:47:45 DEBUG : potato3: md5 = b1a53993579b830006f2e1610415c32c OK 2022/01/12 05:47:45 INFO : potato3: Copied (server-side copy) 2022/01/12 05:47:45 DEBUG : potato2: md5 = d6548b156ea68a4e003e786df99eee76 OK 2022/01/12 05:47:45 INFO : potato2: Copied (server-side copy) 2022/01/12 05:47:46 INFO : empty space: Deleted 2022/01/12 05:47:46 INFO : potato3: Deleted 2022/01/12 05:47:46 INFO : potato2: Deleted 2022/01/12 05:47:47 DEBUG : Hubic Swift container rclone-test-xexosox7xogutem9lusogez6: Purge remote 2022/01/12 05:47:47 DEBUG : Waiting for deletions to finish 2022/01/12 05:47:47 INFO : potato2: Deleted 2022/01/12 05:47:47 INFO : empty space: Deleted 2022/01/12 05:47:48 INFO : potato3: Deleted 2022/01/12 05:47:48 INFO : Swift container rclone-test-xexosox7xogutem9lusogez6: Container "rclone-test-xexosox7xogutem9lusogez6" removed 2022/01/12 05:47:48 DEBUG : Hubic Swift container rclone-test-gugapuh5yoquven1larusab9: Purge remote 2022/01/12 05:47:48 DEBUG : Waiting for deletions to finish 2022/01/12 05:47:48 INFO : Swift container rclone-test-gugapuh5yoquven1larusab9: Container "rclone-test-gugapuh5yoquven1larusab9" removed --- PASS: TestServerSideMoveDeleteEmptySourceDirs (16.46s) === RUN TestServerSideMoveOverlap run.go:181: Remote "Hubic Swift container rclone-test-kikikak6puqugin8mijovak3", Local "Local file system at /tmp/rclone3342894971", Modify Window "1ns" 2022/01/12 05:47:48 DEBUG : Creating backend with remote "TestHubic:rclone-test-kikikak6puqugin8mijovak3/rclone-move-test" 2022/01/12 05:47:48 DEBUG : Hubic: Got swift credentials (expiry 2022-01-13 06:04:38 +0100 +0100 in 23h16m49.473444849s) --- PASS: TestServerSideMoveOverlap (3.05s) === RUN TestSyncOverlap run.go:181: Remote "Hubic Swift container rclone-test-kikikak6puqugin8mijovak3", Local "Local file system at /tmp/rclone3342894971", Modify Window "1ns" 2022/01/12 05:47:51 DEBUG : Creating backend with remote "TestHubic:rclone-test-kikikak6puqugin8mijovak3/rclone-sync-test" 2022/01/12 05:47:51 DEBUG : Hubic: Got swift credentials (expiry 2022-01-13 06:04:38 +0100 +0100 in 23h16m46.344362368s) --- PASS: TestSyncOverlap (0.56s) === RUN TestSyncCompareDest run.go:181: Remote "Hubic Swift container rclone-test-kikikak6puqugin8mijovak3", Local "Local file system at /tmp/rclone3342894971", Modify Window "1ns" 2022/01/12 05:47:51 DEBUG : Creating backend with remote "TestHubic:rclone-test-kikikak6puqugin8mijovak3/dst" 2022/01/12 05:47:52 DEBUG : Hubic: Got swift credentials (expiry 2022-01-13 06:04:38 +0100 +0100 in 23h16m45.815925206s) 2022/01/12 05:47:52 DEBUG : Creating backend with remote "TestHubic:rclone-test-kikikak6puqugin8mijovak3/CompareDest" 2022/01/12 05:47:54 DEBUG : Hubic: Got swift credentials (expiry 2022-01-13 06:04:38 +0100 +0100 in 23h16m43.819427508s) 2022/01/12 05:47:54 DEBUG : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3 path dst: Waiting for checks to finish 2022/01/12 05:47:54 DEBUG : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3 path dst: Waiting for transfers to finish 2022/01/12 05:47:56 DEBUG : one: md5 = f97c5d29941bfb1b2fdab0874906ab82 OK 2022/01/12 05:47:56 INFO : one: Copied (new) 2022/01/12 05:47:56 DEBUG : Waiting for deletions to finish 2022/01/12 05:47:56 DEBUG : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3 path dst: Waiting for checks to finish 2022/01/12 05:47:56 DEBUG : one: Sizes differ (src 5 vs dst 3) 2022/01/12 05:47:56 DEBUG : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3 path dst: Waiting for transfers to finish 2022/01/12 05:47:58 DEBUG : one: md5 = 07912d142f5d63ee918b34796b5a2432 OK 2022/01/12 05:47:58 INFO : one: Copied (replaced existing) 2022/01/12 05:47:58 DEBUG : Waiting for deletions to finish 2022/01/12 05:48:01 DEBUG : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3 path dst: Waiting for checks to finish 2022/01/12 05:48:01 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:48:01 DEBUG : one: Destination found in --compare-dest, skipping 2022/01/12 05:48:01 DEBUG : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3 path dst: Waiting for transfers to finish 2022/01/12 05:48:01 DEBUG : Waiting for deletions to finish 2022/01/12 05:48:01 INFO : There was nothing to transfer 2022/01/12 05:48:03 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:48:03 DEBUG : two: Destination found in --compare-dest, skipping 2022/01/12 05:48:03 DEBUG : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3 path dst: Waiting for checks to finish 2022/01/12 05:48:04 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:48:04 DEBUG : one: Destination found in --compare-dest, skipping 2022/01/12 05:48:04 DEBUG : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3 path dst: Waiting for transfers to finish 2022/01/12 05:48:04 DEBUG : Waiting for deletions to finish 2022/01/12 05:48:04 INFO : There was nothing to transfer 2022/01/12 05:48:04 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:48:04 DEBUG : two: Destination found in --compare-dest, skipping 2022/01/12 05:48:04 DEBUG : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3 path dst: Waiting for checks to finish 2022/01/12 05:48:04 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:48:04 DEBUG : one: Destination found in --compare-dest, skipping 2022/01/12 05:48:04 DEBUG : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3 path dst: Waiting for transfers to finish 2022/01/12 05:48:04 DEBUG : Waiting for deletions to finish 2022/01/12 05:48:04 INFO : There was nothing to transfer 2022/01/12 05:48:04 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/01/12 05:48:04 DEBUG : two: md5 = b8a9f715dbb64fd5c56e7783c6820a61 OK 2022/01/12 05:48:04 DEBUG : two: Destination found in --compare-dest, skipping 2022/01/12 05:48:04 DEBUG : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3 path dst: Waiting for checks to finish 2022/01/12 05:48:04 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:48:04 DEBUG : one: Destination found in --compare-dest, skipping 2022/01/12 05:48:04 DEBUG : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3 path dst: Waiting for transfers to finish 2022/01/12 05:48:04 DEBUG : Waiting for deletions to finish 2022/01/12 05:48:04 INFO : There was nothing to transfer 2022/01/12 05:48:05 DEBUG : two: Sizes differ (src 5 vs dst 3) 2022/01/12 05:48:05 DEBUG : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3 path dst: Waiting for checks to finish 2022/01/12 05:48:05 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:48:05 DEBUG : one: Destination found in --compare-dest, skipping 2022/01/12 05:48:05 DEBUG : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3 path dst: Waiting for transfers to finish 2022/01/12 05:48:07 DEBUG : two: md5 = 2379e4ce8c3380e996ab0509f17069ad OK 2022/01/12 05:48:07 INFO : two: Copied (new) 2022/01/12 05:48:07 DEBUG : Waiting for deletions to finish --- PASS: TestSyncCompareDest (19.74s) === RUN TestSyncMultipleCompareDest run.go:181: Remote "Hubic Swift container rclone-test-kikikak6puqugin8mijovak3", Local "Local file system at /tmp/rclone3342894971", Modify Window "1ns" 2022/01/12 05:48:16 DEBUG : Creating backend with remote "TestHubic:rclone-test-kikikak6puqugin8mijovak3/dest" 2022/01/12 05:48:16 DEBUG : Hubic: Got swift credentials (expiry 2022-01-13 06:04:38 +0100 +0100 in 23h16m21.467733761s) 2022/01/12 05:48:16 DEBUG : Creating backend with remote "TestHubic:rclone-test-kikikak6puqugin8mijovak3/pre-dest1" 2022/01/12 05:48:17 DEBUG : Hubic: Got swift credentials (expiry 2022-01-13 06:04:38 +0100 +0100 in 23h16m20.802526289s) 2022/01/12 05:48:17 DEBUG : Creating backend with remote "TestHubic:rclone-test-kikikak6puqugin8mijovak3/pre-dest2" 2022/01/12 05:48:17 DEBUG : Hubic: Got swift credentials (expiry 2022-01-13 06:04:38 +0100 +0100 in 23h16m20.178772177s) 2022/01/12 05:48:18 DEBUG : 1: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:48:18 DEBUG : 1: Destination found in --compare-dest, skipping 2022/01/12 05:48:18 DEBUG : 2: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:48:18 DEBUG : 2: Destination found in --compare-dest, skipping 2022/01/12 05:48:18 DEBUG : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3 path dest: Waiting for checks to finish 2022/01/12 05:48:18 DEBUG : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3 path dest: Waiting for transfers to finish 2022/01/12 05:48:20 DEBUG : 3: md5 = eccbc87e4b5ce2fe28308fd9f2a7baf3 OK 2022/01/12 05:48:20 INFO : 3: Copied (new) 2022/01/12 05:48:20 DEBUG : Waiting for deletions to finish --- PASS: TestSyncMultipleCompareDest (12.17s) === RUN TestSyncCopyDest run.go:181: Remote "Hubic Swift container rclone-test-kikikak6puqugin8mijovak3", Local "Local file system at /tmp/rclone3342894971", Modify Window "1ns" 2022/01/12 05:48:23 DEBUG : Creating backend with remote "TestHubic:rclone-test-kikikak6puqugin8mijovak3/dst" 2022/01/12 05:48:24 DEBUG : Hubic: Got swift credentials (expiry 2022-01-13 06:04:38 +0100 +0100 in 23h16m13.84761961s) 2022/01/12 05:48:24 DEBUG : Creating backend with remote "TestHubic:rclone-test-kikikak6puqugin8mijovak3/CopyDest" 2022/01/12 05:48:24 DEBUG : Hubic: Got swift credentials (expiry 2022-01-13 06:04:38 +0100 +0100 in 23h16m13.362233207s) 2022/01/12 05:48:25 DEBUG : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3 path dst: Waiting for checks to finish 2022/01/12 05:48:25 DEBUG : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3 path dst: Waiting for transfers to finish 2022/01/12 05:48:26 DEBUG : one: md5 = f97c5d29941bfb1b2fdab0874906ab82 OK 2022/01/12 05:48:26 INFO : one: Copied (new) 2022/01/12 05:48:26 DEBUG : Waiting for deletions to finish 2022/01/12 05:48:27 DEBUG : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3 path dst: Waiting for checks to finish 2022/01/12 05:48:27 DEBUG : one: Sizes differ (src 5 vs dst 3) 2022/01/12 05:48:27 DEBUG : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3 path dst: Waiting for transfers to finish 2022/01/12 05:48:28 DEBUG : one: md5 = 07912d142f5d63ee918b34796b5a2432 OK 2022/01/12 05:48:28 INFO : one: Copied (replaced existing) 2022/01/12 05:48:28 DEBUG : Waiting for deletions to finish 2022/01/12 05:48:32 DEBUG : Creating backend with remote "TestHubic:rclone-test-kikikak6puqugin8mijovak3/BackupDir" 2022/01/12 05:48:32 DEBUG : Hubic: Got swift credentials (expiry 2022-01-13 06:04:38 +0100 +0100 in 23h16m5.424315184s) 2022/01/12 05:48:33 DEBUG : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3 path dst: Waiting for checks to finish 2022/01/12 05:48:33 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:48:33 DEBUG : one: Sizes differ (src 5 vs dst 3) 2022/01/12 05:48:33 INFO : Swift container rclone-test-kikikak6puqugin8mijovak3 path BackupDir: Container "rclone-test-kikikak6puqugin8mijovak3" created 2022/01/12 05:48:35 DEBUG : one: md5 = f97c5d29941bfb1b2fdab0874906ab82 OK 2022/01/12 05:48:35 INFO : one: Copied (server-side copy) 2022/01/12 05:48:36 INFO : one: Deleted 2022/01/12 05:48:37 DEBUG : one: md5 = 07912d142f5d63ee918b34796b5a2432 OK 2022/01/12 05:48:37 INFO : one: Copied (server-side copy) 2022/01/12 05:48:37 DEBUG : one: Destination found in --copy-dest, using server-side copy 2022/01/12 05:48:37 DEBUG : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3 path dst: Waiting for transfers to finish 2022/01/12 05:48:37 DEBUG : Waiting for deletions to finish 2022/01/12 05:48:39 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:48:42 DEBUG : two: md5 = b8a9f715dbb64fd5c56e7783c6820a61 OK 2022/01/12 05:48:42 INFO : two: Copied (server-side copy) 2022/01/12 05:48:42 DEBUG : two: Destination found in --copy-dest, using server-side copy 2022/01/12 05:48:42 DEBUG : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3 path dst: Waiting for checks to finish 2022/01/12 05:48:42 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:48:42 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:48:42 DEBUG : one: Unchanged skipping 2022/01/12 05:48:42 DEBUG : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3 path dst: Waiting for transfers to finish 2022/01/12 05:48:42 DEBUG : Waiting for deletions to finish 2022/01/12 05:48:42 DEBUG : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3 path dst: Waiting for checks to finish 2022/01/12 05:48:42 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:48:42 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:48:42 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:48:42 DEBUG : one: Unchanged skipping 2022/01/12 05:48:42 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:48:42 DEBUG : two: Unchanged skipping 2022/01/12 05:48:42 DEBUG : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3 path dst: Waiting for transfers to finish 2022/01/12 05:48:42 DEBUG : Waiting for deletions to finish 2022/01/12 05:48:42 INFO : There was nothing to transfer 2022/01/12 05:48:45 DEBUG : three: Sizes differ (src 7 vs dst 5) 2022/01/12 05:48:45 DEBUG : three: Destination not found in --copy-dest 2022/01/12 05:48:45 DEBUG : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3 path dst: Waiting for checks to finish 2022/01/12 05:48:45 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:48:45 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:48:45 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:48:45 DEBUG : two: Unchanged skipping 2022/01/12 05:48:45 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:48:45 DEBUG : one: Unchanged skipping 2022/01/12 05:48:45 DEBUG : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3 path dst: Waiting for transfers to finish 2022/01/12 05:48:46 DEBUG : three: md5 = 1bccb9dccb3e9f6a3f9d2a8bdb54b7f5 OK 2022/01/12 05:48:46 INFO : three: Copied (new) 2022/01/12 05:48:46 DEBUG : Waiting for deletions to finish --- PASS: TestSyncCopyDest (30.15s) === RUN TestSyncBackupDir run.go:181: Remote "Hubic Swift container rclone-test-kikikak6puqugin8mijovak3", Local "Local file system at /tmp/rclone3342894971", Modify Window "1ns" 2022/01/12 05:48:59 DEBUG : Creating backend with remote "TestHubic:rclone-test-kikikak6puqugin8mijovak3/dst" 2022/01/12 05:49:00 DEBUG : Hubic: Got swift credentials (expiry 2022-01-13 06:04:38 +0100 +0100 in 23h15m37.812936057s) 2022/01/12 05:49:00 DEBUG : Creating backend with remote "TestHubic:rclone-test-kikikak6puqugin8mijovak3/backup" 2022/01/12 05:49:00 DEBUG : Hubic: Got swift credentials (expiry 2022-01-13 06:04:38 +0100 +0100 in 23h15m37.404951784s) 2022/01/12 05:49:00 DEBUG : one: Sizes differ (src 4 vs dst 3) 2022/01/12 05:49:00 DEBUG : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3 path dst: Waiting for checks to finish 2022/01/12 05:49:01 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:49:01 DEBUG : two: Unchanged skipping 2022/01/12 05:49:01 INFO : Swift container rclone-test-kikikak6puqugin8mijovak3 path backup: Container "rclone-test-kikikak6puqugin8mijovak3" created 2022/01/12 05:49:03 DEBUG : one: md5 = f97c5d29941bfb1b2fdab0874906ab82 OK 2022/01/12 05:49:03 INFO : one: Copied (server-side copy) 2022/01/12 05:49:03 INFO : one: Deleted 2022/01/12 05:49:03 DEBUG : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3 path dst: Waiting for transfers to finish 2022/01/12 05:49:05 DEBUG : one: md5 = c7957179c41f69d44f217a108c7915d8 OK 2022/01/12 05:49:05 INFO : one: Copied (new) 2022/01/12 05:49:05 DEBUG : Waiting for deletions to finish 2022/01/12 05:49:07 DEBUG : three.txt: md5 = 35d6d33467aae9a2e3dccb4b6b027878 OK 2022/01/12 05:49:07 INFO : three.txt: Copied (server-side copy) 2022/01/12 05:49:08 INFO : three.txt: Deleted 2022/01/12 05:49:08 INFO : three.txt: Moved into backup dir fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:299: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:299: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:306: Error Trace: fstest.go:306 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1753 sync_test.go:1781 Error: Should be true Test: TestSyncBackupDir Messages: listing wrong, want backup/one (3), backup/three.txt (5), dst/one (4), dst/two (3) got backup/one (3), dst/one (4), dst/two (3) fstest.go:204: Not found "backup/three.txt" fstest.go:207: Error Trace: fstest.go:207 fstest.go:311 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1753 sync_test.go:1781 Error: Not equal: expected: 0 actual : 1 Test: TestSyncBackupDir Messages: 1 objects not found fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:299: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:299: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:306: Error Trace: fstest.go:306 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1759 sync_test.go:1781 Error: Should be true Test: TestSyncBackupDir Messages: listing wrong, want backup/one (3), backup/three.txt (5), dst/one (4), dst/three.txt (6), dst/two (3) got backup/one (3), dst/one (4), dst/three.txt (6), dst/two (3) fstest.go:204: Not found "backup/three.txt" fstest.go:207: Error Trace: fstest.go:207 fstest.go:311 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1759 sync_test.go:1781 Error: Not equal: expected: 0 actual : 1 Test: TestSyncBackupDir Messages: 1 objects not found 2022/01/12 05:49:24 DEBUG : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3 path dst: Waiting for checks to finish 2022/01/12 05:49:24 DEBUG : one: Sizes differ (src 5 vs dst 4) 2022/01/12 05:49:25 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:49:25 DEBUG : two: Unchanged skipping 2022/01/12 05:49:27 DEBUG : one: md5 = c7957179c41f69d44f217a108c7915d8 OK 2022/01/12 05:49:27 INFO : one: Copied (server-side copy) 2022/01/12 05:49:27 INFO : one: Deleted 2022/01/12 05:49:27 DEBUG : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3 path dst: Waiting for transfers to finish 2022/01/12 05:49:29 DEBUG : one: md5 = 0f93e81041f0cab37c37a05ae998b219 OK 2022/01/12 05:49:29 INFO : one: Copied (new) 2022/01/12 05:49:29 DEBUG : Waiting for deletions to finish 2022/01/12 05:49:31 DEBUG : three.txt: md5 = 91341eed84691a83caea73aa785736d5 OK 2022/01/12 05:49:31 INFO : three.txt: Copied (server-side copy) 2022/01/12 05:49:32 INFO : three.txt: Deleted 2022/01/12 05:49:32 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 --- FAIL: TestSyncBackupDir (49.16s) === RUN TestSyncBackupDirWithSuffix run.go:181: Remote "Hubic Swift container rclone-test-kikikak6puqugin8mijovak3", Local "Local file system at /tmp/rclone3342894971", Modify Window "1ns" 2022/01/12 05:49:49 DEBUG : Creating backend with remote "TestHubic:rclone-test-kikikak6puqugin8mijovak3/dst" 2022/01/12 05:49:49 DEBUG : Hubic: Got swift credentials (expiry 2022-01-13 06:04:38 +0100 +0100 in 23h14m48.436446576s) 2022/01/12 05:49:49 DEBUG : Creating backend with remote "TestHubic:rclone-test-kikikak6puqugin8mijovak3/backup" 2022/01/12 05:49:50 DEBUG : Hubic: Got swift credentials (expiry 2022-01-13 06:04:38 +0100 +0100 in 23h14m47.831178219s) 2022/01/12 05:49:50 DEBUG : one: Sizes differ (src 4 vs dst 3) 2022/01/12 05:49:50 DEBUG : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3 path dst: Waiting for checks to finish 2022/01/12 05:49:50 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:49:50 DEBUG : two: Unchanged skipping 2022/01/12 05:49:50 INFO : Swift container rclone-test-kikikak6puqugin8mijovak3 path backup: Container "rclone-test-kikikak6puqugin8mijovak3" created 2022/01/12 05:49:52 DEBUG : one: md5 = f97c5d29941bfb1b2fdab0874906ab82 OK 2022/01/12 05:49:52 INFO : one: Copied (server-side copy) to: one.bak 2022/01/12 05:49:53 INFO : one: Deleted 2022/01/12 05:49:53 DEBUG : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3 path dst: Waiting for transfers to finish 2022/01/12 05:49:54 DEBUG : one: md5 = c7957179c41f69d44f217a108c7915d8 OK 2022/01/12 05:49:54 INFO : one: Copied (new) 2022/01/12 05:49:54 DEBUG : Waiting for deletions to finish 2022/01/12 05:49:56 DEBUG : three.txt: md5 = 35d6d33467aae9a2e3dccb4b6b027878 OK 2022/01/12 05:49:56 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2022/01/12 05:49:57 INFO : three.txt: Deleted 2022/01/12 05:49:57 INFO : three.txt: Moved into backup dir 2022/01/12 05:50:00 DEBUG : one: Sizes differ (src 5 vs dst 4) 2022/01/12 05:50:00 DEBUG : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3 path dst: Waiting for checks to finish 2022/01/12 05:50:00 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:50:00 DEBUG : two: Unchanged skipping 2022/01/12 05:50:01 DEBUG : one: md5 = c7957179c41f69d44f217a108c7915d8 OK 2022/01/12 05:50:01 INFO : one: Copied (server-side copy) to: one.bak 2022/01/12 05:50:02 INFO : one: Deleted 2022/01/12 05:50:02 DEBUG : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3 path dst: Waiting for transfers to finish 2022/01/12 05:50:04 DEBUG : one: md5 = 0f93e81041f0cab37c37a05ae998b219 OK 2022/01/12 05:50:04 INFO : one: Copied (new) 2022/01/12 05:50:04 DEBUG : Waiting for deletions to finish 2022/01/12 05:50:05 DEBUG : three.txt: md5 = 91341eed84691a83caea73aa785736d5 OK 2022/01/12 05:50:05 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2022/01/12 05:50:06 INFO : three.txt: Deleted 2022/01/12 05:50:06 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirWithSuffix (27.92s) === RUN TestSyncBackupDirWithSuffixKeepExtension run.go:181: Remote "Hubic Swift container rclone-test-kikikak6puqugin8mijovak3", Local "Local file system at /tmp/rclone3342894971", Modify Window "1ns" 2022/01/12 05:50:16 DEBUG : Creating backend with remote "TestHubic:rclone-test-kikikak6puqugin8mijovak3/dst" 2022/01/12 05:50:16 DEBUG : Hubic: Got swift credentials (expiry 2022-01-13 06:04:38 +0100 +0100 in 23h14m21.199940372s) 2022/01/12 05:50:17 DEBUG : Creating backend with remote "TestHubic:rclone-test-kikikak6puqugin8mijovak3/backup" 2022/01/12 05:50:17 DEBUG : Hubic: Got swift credentials (expiry 2022-01-13 06:04:38 +0100 +0100 in 23h14m20.712628617s) 2022/01/12 05:50:17 DEBUG : one: Sizes differ (src 4 vs dst 3) 2022/01/12 05:50:17 DEBUG : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3 path dst: Waiting for checks to finish 2022/01/12 05:50:17 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:50:17 DEBUG : two: Unchanged skipping 2022/01/12 05:50:18 INFO : Swift container rclone-test-kikikak6puqugin8mijovak3 path backup: Container "rclone-test-kikikak6puqugin8mijovak3" created 2022/01/12 05:50:20 DEBUG : one: md5 = f97c5d29941bfb1b2fdab0874906ab82 OK 2022/01/12 05:50:20 INFO : one: Copied (server-side copy) to: one-2019-01-01 2022/01/12 05:50:21 INFO : one: Deleted 2022/01/12 05:50:21 DEBUG : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3 path dst: Waiting for transfers to finish 2022/01/12 05:50:22 DEBUG : one: md5 = c7957179c41f69d44f217a108c7915d8 OK 2022/01/12 05:50:22 INFO : one: Copied (new) 2022/01/12 05:50:22 DEBUG : Waiting for deletions to finish 2022/01/12 05:50:24 DEBUG : three.txt: md5 = 35d6d33467aae9a2e3dccb4b6b027878 OK 2022/01/12 05:50:24 INFO : three.txt: Copied (server-side copy) to: three-2019-01-01.txt 2022/01/12 05:50:25 INFO : three.txt: Deleted 2022/01/12 05:50:25 INFO : three.txt: Moved into backup dir 2022/01/12 05:50:27 DEBUG : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3 path dst: Waiting for checks to finish 2022/01/12 05:50:27 DEBUG : one: Sizes differ (src 5 vs dst 4) 2022/01/12 05:50:27 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:50:27 DEBUG : two: Unchanged skipping 2022/01/12 05:50:29 DEBUG : one: md5 = c7957179c41f69d44f217a108c7915d8 OK 2022/01/12 05:50:29 INFO : one: Copied (server-side copy) to: one-2019-01-01 2022/01/12 05:50:30 INFO : one: Deleted 2022/01/12 05:50:30 DEBUG : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3 path dst: Waiting for transfers to finish 2022/01/12 05:50:32 DEBUG : one: md5 = 0f93e81041f0cab37c37a05ae998b219 OK 2022/01/12 05:50:32 INFO : one: Copied (new) 2022/01/12 05:50:32 DEBUG : Waiting for deletions to finish 2022/01/12 05:50:33 DEBUG : three.txt: md5 = 91341eed84691a83caea73aa785736d5 OK 2022/01/12 05:50:33 INFO : three.txt: Copied (server-side copy) to: three-2019-01-01.txt 2022/01/12 05:50:34 INFO : three.txt: Deleted 2022/01/12 05:50:34 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirWithSuffixKeepExtension (27.68s) === RUN TestSyncBackupDirSuffixOnly run.go:181: Remote "Hubic Swift container rclone-test-kikikak6puqugin8mijovak3", Local "Local file system at /tmp/rclone3342894971", Modify Window "1ns" 2022/01/12 05:50:44 DEBUG : Creating backend with remote "TestHubic:rclone-test-kikikak6puqugin8mijovak3/dst" 2022/01/12 05:50:44 DEBUG : Config file has changed externaly - reloading 2022/01/12 05:50:45 DEBUG : Hubic: Got swift credentials (expiry 2022-01-13 06:04:38 +0100 +0100 in 23h13m52.781700399s) 2022/01/12 05:50:45 DEBUG : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3 path dst: Waiting for checks to finish 2022/01/12 05:50:45 DEBUG : one: Sizes differ (src 4 vs dst 3) 2022/01/12 05:50:45 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:50:45 DEBUG : two: Unchanged skipping 2022/01/12 05:50:47 DEBUG : one: md5 = f97c5d29941bfb1b2fdab0874906ab82 OK 2022/01/12 05:50:47 INFO : one: Copied (server-side copy) to: one.bak 2022/01/12 05:50:48 INFO : one: Deleted 2022/01/12 05:50:48 DEBUG : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3 path dst: Waiting for transfers to finish 2022/01/12 05:50:49 DEBUG : one: md5 = c7957179c41f69d44f217a108c7915d8 OK 2022/01/12 05:50:49 INFO : one: Copied (new) 2022/01/12 05:50:49 DEBUG : Waiting for deletions to finish 2022/01/12 05:50:52 DEBUG : three.txt: md5 = 35d6d33467aae9a2e3dccb4b6b027878 OK 2022/01/12 05:50:52 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2022/01/12 05:50:52 INFO : three.txt: Deleted 2022/01/12 05:50:52 INFO : three.txt: Moved into backup dir 2022/01/12 05:50:55 DEBUG : one.bak: Excluded 2022/01/12 05:50:55 DEBUG : three.txt.bak: Excluded 2022/01/12 05:50:55 DEBUG : one: Sizes differ (src 5 vs dst 4) 2022/01/12 05:50:55 DEBUG : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3 path dst: Waiting for checks to finish 2022/01/12 05:50:55 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:50:55 DEBUG : two: Unchanged skipping 2022/01/12 05:50:56 DEBUG : one: md5 = c7957179c41f69d44f217a108c7915d8 OK 2022/01/12 05:50:56 INFO : one: Copied (server-side copy) to: one.bak 2022/01/12 05:50:57 INFO : one: Deleted 2022/01/12 05:50:57 DEBUG : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3 path dst: Waiting for transfers to finish 2022/01/12 05:50:59 DEBUG : one: md5 = 0f93e81041f0cab37c37a05ae998b219 OK 2022/01/12 05:50:59 INFO : one: Copied (new) 2022/01/12 05:50:59 DEBUG : Waiting for deletions to finish 2022/01/12 05:51:01 DEBUG : three.txt: md5 = 91341eed84691a83caea73aa785736d5 OK 2022/01/12 05:51:01 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2022/01/12 05:51:02 INFO : three.txt: Deleted 2022/01/12 05:51:02 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirSuffixOnly (27.58s) === RUN TestSyncSuffix run.go:181: Remote "Hubic Swift container rclone-test-kikikak6puqugin8mijovak3", Local "Local file system at /tmp/rclone3342894971", Modify Window "1ns" 2022/01/12 05:51:12 DEBUG : Creating backend with remote "TestHubic:rclone-test-kikikak6puqugin8mijovak3/dst" 2022/01/12 05:51:12 DEBUG : Hubic: Got swift credentials (expiry 2022-01-13 06:04:38 +0100 +0100 in 23h13m25.025888592s) 2022/01/12 05:51:13 DEBUG : one: Sizes differ (src 4 vs dst 3) 2022/01/12 05:51:13 INFO : Swift container rclone-test-kikikak6puqugin8mijovak3 path dst: Container "rclone-test-kikikak6puqugin8mijovak3" created 2022/01/12 05:51:14 DEBUG : one: md5 = f97c5d29941bfb1b2fdab0874906ab82 OK 2022/01/12 05:51:14 INFO : one: Copied (server-side copy) to: one.bak 2022/01/12 05:51:15 INFO : one: Deleted 2022/01/12 05:51:17 DEBUG : one: md5 = c7957179c41f69d44f217a108c7915d8 OK 2022/01/12 05:51:17 INFO : one: Copied (new) 2022/01/12 05:51:17 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:51:17 DEBUG : two: Unchanged skipping 2022/01/12 05:51:17 DEBUG : three.txt: Sizes differ (src 6 vs dst 5) 2022/01/12 05:51:19 DEBUG : three.txt: md5 = 35d6d33467aae9a2e3dccb4b6b027878 OK 2022/01/12 05:51:19 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2022/01/12 05:51:20 INFO : three.txt: Deleted 2022/01/12 05:51:21 DEBUG : three.txt: md5 = 91341eed84691a83caea73aa785736d5 OK 2022/01/12 05:51:21 INFO : three.txt: Copied (new) 2022/01/12 05:51:22 DEBUG : one: Sizes differ (src 5 vs dst 4) 2022/01/12 05:51:24 DEBUG : one: md5 = c7957179c41f69d44f217a108c7915d8 OK 2022/01/12 05:51:24 INFO : one: Copied (server-side copy) to: one.bak 2022/01/12 05:51:25 INFO : one: Deleted 2022/01/12 05:51:26 DEBUG : one: md5 = 0f93e81041f0cab37c37a05ae998b219 OK 2022/01/12 05:51:26 INFO : one: Copied (new) 2022/01/12 05:51:26 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:51:26 DEBUG : two: Unchanged skipping 2022/01/12 05:51:26 DEBUG : three.txt: Sizes differ (src 19 vs dst 6) 2022/01/12 05:51:29 DEBUG : three.txt: md5 = 91341eed84691a83caea73aa785736d5 OK 2022/01/12 05:51:29 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2022/01/12 05:51:29 INFO : three.txt: Deleted 2022/01/12 05:51:31 DEBUG : three.txt: md5 = 423e01c5e84a30d129b97aedb6e219a9 OK 2022/01/12 05:51:31 INFO : three.txt: Copied (new) --- PASS: TestSyncSuffix (30.27s) === RUN TestSyncSuffixKeepExtension run.go:181: Remote "Hubic Swift container rclone-test-kikikak6puqugin8mijovak3", Local "Local file system at /tmp/rclone3342894971", Modify Window "1ns" 2022/01/12 05:51:42 DEBUG : Creating backend with remote "TestHubic:rclone-test-kikikak6puqugin8mijovak3/dst" 2022/01/12 05:51:42 DEBUG : Hubic: Got swift credentials (expiry 2022-01-13 06:04:38 +0100 +0100 in 23h12m55.010946722s) 2022/01/12 05:51:43 DEBUG : one: Sizes differ (src 4 vs dst 3) 2022/01/12 05:51:43 INFO : Swift container rclone-test-kikikak6puqugin8mijovak3 path dst: Container "rclone-test-kikikak6puqugin8mijovak3" created 2022/01/12 05:51:46 DEBUG : one: md5 = f97c5d29941bfb1b2fdab0874906ab82 OK 2022/01/12 05:51:46 INFO : one: Copied (server-side copy) to: one-2019-01-01 2022/01/12 05:51:47 INFO : one: Deleted 2022/01/12 05:51:49 DEBUG : one: md5 = c7957179c41f69d44f217a108c7915d8 OK 2022/01/12 05:51:49 INFO : one: Copied (new) 2022/01/12 05:51:49 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:51:49 DEBUG : two: Unchanged skipping 2022/01/12 05:51:49 DEBUG : three.txt: Sizes differ (src 6 vs dst 5) 2022/01/12 05:51:50 DEBUG : three.txt: md5 = 35d6d33467aae9a2e3dccb4b6b027878 OK 2022/01/12 05:51:50 INFO : three.txt: Copied (server-side copy) to: three-2019-01-01.txt 2022/01/12 05:51:51 INFO : three.txt: Deleted 2022/01/12 05:51:52 DEBUG : three.txt: md5 = 91341eed84691a83caea73aa785736d5 OK 2022/01/12 05:51:52 INFO : three.txt: Copied (new) 2022/01/12 05:51:53 DEBUG : one: Sizes differ (src 5 vs dst 4) 2022/01/12 05:51:55 DEBUG : one: md5 = c7957179c41f69d44f217a108c7915d8 OK 2022/01/12 05:51:55 INFO : one: Copied (server-side copy) to: one-2019-01-01 2022/01/12 05:51:56 INFO : one: Deleted 2022/01/12 05:51:58 DEBUG : one: md5 = 0f93e81041f0cab37c37a05ae998b219 OK 2022/01/12 05:51:58 INFO : one: Copied (new) 2022/01/12 05:51:58 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:51:58 DEBUG : two: Unchanged skipping 2022/01/12 05:51:58 DEBUG : three.txt: Sizes differ (src 19 vs dst 6) 2022/01/12 05:52:00 DEBUG : three.txt: md5 = 91341eed84691a83caea73aa785736d5 OK 2022/01/12 05:52:00 INFO : three.txt: Copied (server-side copy) to: three-2019-01-01.txt 2022/01/12 05:52:01 INFO : three.txt: Deleted 2022/01/12 05:52:02 DEBUG : three.txt: md5 = 423e01c5e84a30d129b97aedb6e219a9 OK 2022/01/12 05:52:02 INFO : three.txt: Copied (new) --- PASS: TestSyncSuffixKeepExtension (31.42s) === RUN TestSyncUTFNorm run.go:181: Remote "Hubic Swift container rclone-test-kikikak6puqugin8mijovak3", Local "Local file system at /tmp/rclone3342894971", Modify Window "1ns" 2022/01/12 05:52:09 DEBUG : Testêé: Sizes differ (src 14 vs dst 18) 2022/01/12 05:52:09 DEBUG : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3: Waiting for checks to finish 2022/01/12 05:52:09 DEBUG : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3: Waiting for transfers to finish 2022/01/12 05:52:11 DEBUG : Testêé: md5 = ce114e4501d2f4e2dcea3e17b546f339 OK 2022/01/12 05:52:11 INFO : Testêé: Copied (replaced existing) to: Testêé 2022/01/12 05:52:11 DEBUG : Waiting for deletions to finish --- PASS: TestSyncUTFNorm (4.44s) === RUN TestSyncImmutable run.go:181: Remote "Hubic Swift container rclone-test-kikikak6puqugin8mijovak3", Local "Local file system at /tmp/rclone3342894971", Modify Window "1ns" 2022/01/12 05:52:12 DEBUG : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3: Waiting for checks to finish 2022/01/12 05:52:12 DEBUG : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3: Waiting for transfers to finish 2022/01/12 05:52:13 DEBUG : existing: md5 = 8ee2027983915ec78acc45027d874316 OK 2022/01/12 05:52:13 INFO : existing: Copied (new) 2022/01/12 05:52:13 DEBUG : Waiting for deletions to finish 2022/01/12 05:52:14 DEBUG : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3: Waiting for checks to finish 2022/01/12 05:52:14 DEBUG : existing: Sizes differ (src 8 vs dst 6) 2022/01/12 05:52:14 ERROR : existing: Source and destination exist but do not match: immutable file modified 2022/01/12 05:52:14 DEBUG : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3: Waiting for transfers to finish 2022/01/12 05:52:14 ERROR : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3: not deleting files as there were IO errors 2022/01/12 05:52:14 ERROR : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3: not deleting directories as there were IO errors --- PASS: TestSyncImmutable (2.95s) === RUN TestSyncIgnoreCase run.go:181: Remote "Hubic Swift container rclone-test-kikikak6puqugin8mijovak3", Local "Local file system at /tmp/rclone3342894971", Modify Window "1ns" 2022/01/12 05:52:17 DEBUG : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3: Waiting for checks to finish 2022/01/12 05:52:17 DEBUG : existing: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:52:17 DEBUG : existing: Unchanged skipping 2022/01/12 05:52:17 DEBUG : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3: Waiting for transfers to finish 2022/01/12 05:52:17 DEBUG : Waiting for deletions to finish 2022/01/12 05:52:17 INFO : There was nothing to transfer --- PASS: TestSyncIgnoreCase (2.88s) === RUN TestMaxTransfer === RUN TestMaxTransfer/Hard run.go:181: Remote "Hubic Swift container rclone-test-kikikak6puqugin8mijovak3", Local "Local file system at /tmp/rclone3342894971", 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-kikikak6puqugin8mijovak3", Local "Local file system at /tmp/rclone3342894971", 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-kikikak6puqugin8mijovak3", Local "Local file system at /tmp/rclone3342894971", Modify Window "1ns" sync_test.go:1989: This test only runs on local --- PASS: TestMaxTransfer (0.27s) --- SKIP: TestMaxTransfer/Hard (0.10s) --- SKIP: TestMaxTransfer/Soft (0.09s) --- SKIP: TestMaxTransfer/Cautious (0.09s) === RUN TestSyncConcurrentDelete run.go:181: Remote "Hubic Swift container rclone-test-kikikak6puqugin8mijovak3", Local "Local file system at /tmp/rclone3342894971", Modify Window "1ns" fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:299: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:299: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:306: Error Trace: fstest.go:306 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:2050 sync_test.go:2062 Error: Should be true Test: TestSyncConcurrentDelete Messages: listing wrong, want both0 (6), both1 (6), both10 (6), both11 (6), both12 (6), both13 (6), both14 (6), both15 (6), both16 (6), both17 (6), both18 (6), both19 (6), both2 (6), both3 (6), both4 (6), both5 (6), both6 (6), both7 (6), both8 (6), both9 (6), only0 (6), only1 (6), only10 (6), only11 (6), only12 (6), only13 (6), only14 (6), only15 (6), only16 (6), only17 (6), only18 (6), only19 (6), only2 (6), only3 (6), only4 (6), only5 (6), only6 (6), only7 (6), only8 (6), only9 (6) got 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), 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) fstest.go:204: Not found "only1" fstest.go:207: Error Trace: fstest.go:207 fstest.go:311 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:2050 sync_test.go:2062 Error: Not equal: expected: 0 actual : 1 Test: TestSyncConcurrentDelete Messages: 1 objects not found 2022/01/12 05:53:34 DEBUG : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3: Waiting for checks to finish 2022/01/12 05:53:34 DEBUG : both1: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:53:34 DEBUG : both1: Unchanged skipping 2022/01/12 05:53:34 DEBUG : both0: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:53:34 DEBUG : both0: Unchanged skipping 2022/01/12 05:53:34 DEBUG : both10: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:53:34 DEBUG : both10: Unchanged skipping 2022/01/12 05:53:34 DEBUG : both11: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:53:34 DEBUG : both11: Unchanged skipping 2022/01/12 05:53:34 DEBUG : both12: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:53:34 DEBUG : both12: Unchanged skipping 2022/01/12 05:53:34 DEBUG : both13: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:53:34 DEBUG : both13: Unchanged skipping 2022/01/12 05:53:34 DEBUG : both14: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:53:34 DEBUG : both14: Unchanged skipping 2022/01/12 05:53:34 DEBUG : both16: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:53:34 DEBUG : both16: Unchanged skipping 2022/01/12 05:53:34 DEBUG : both18: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:53:34 DEBUG : both18: Unchanged skipping 2022/01/12 05:53:34 DEBUG : both19: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:53:34 DEBUG : both19: Unchanged skipping 2022/01/12 05:53:34 DEBUG : both3: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:53:34 DEBUG : both3: Unchanged skipping 2022/01/12 05:53:34 DEBUG : both2: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:53:34 DEBUG : both2: Unchanged skipping 2022/01/12 05:53:34 DEBUG : both5: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:53:34 DEBUG : both5: Unchanged skipping 2022/01/12 05:53:34 DEBUG : both4: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:53:34 DEBUG : both4: Unchanged skipping 2022/01/12 05:53:34 DEBUG : both7: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:53:34 DEBUG : both7: Unchanged skipping 2022/01/12 05:53:34 DEBUG : both6: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:53:34 DEBUG : both6: Unchanged skipping 2022/01/12 05:53:34 DEBUG : both17: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:53:34 DEBUG : both17: Unchanged skipping 2022/01/12 05:53:34 DEBUG : both15: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:53:34 DEBUG : both15: Unchanged skipping 2022/01/12 05:53:34 DEBUG : both9: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:53:34 DEBUG : both9: Unchanged skipping 2022/01/12 05:53:35 DEBUG : both8: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:53:35 DEBUG : both8: Unchanged skipping 2022/01/12 05:53:35 DEBUG : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3: Waiting for transfers to finish 2022/01/12 05:53:35 DEBUG : Waiting for deletions to finish 2022/01/12 05:53:35 INFO : only13: Deleted 2022/01/12 05:53:35 INFO : only10: Deleted 2022/01/12 05:53:36 INFO : only0: Deleted 2022/01/12 05:53:36 INFO : only3: Deleted 2022/01/12 05:53:36 INFO : only9: Deleted 2022/01/12 05:53:36 INFO : only6: Deleted 2022/01/12 05:53:37 INFO : only17: Deleted 2022/01/12 05:53:37 INFO : only16: Deleted 2022/01/12 05:53:37 INFO : only2: Deleted 2022/01/12 05:53:37 INFO : only18: Deleted 2022/01/12 05:53:38 INFO : only12: Deleted 2022/01/12 05:53:38 INFO : only11: Deleted 2022/01/12 05:53:38 INFO : only14: Deleted 2022/01/12 05:53:38 INFO : only15: Deleted 2022/01/12 05:53:39 INFO : only4: Deleted 2022/01/12 05:53:39 INFO : only5: Deleted 2022/01/12 05:53:39 INFO : only8: Deleted 2022/01/12 05:53:39 INFO : only19: Deleted 2022/01/12 05:53:39 INFO : only7: Deleted 2022/01/12 05:53:39 INFO : There was nothing to transfer --- FAIL: TestSyncConcurrentDelete (102.02s) === RUN TestSyncConcurrentTruncate run.go:181: Remote "Hubic Swift container rclone-test-kikikak6puqugin8mijovak3", Local "Local file system at /tmp/rclone3342894971", Modify Window "1ns" 2022/01/12 05:55:06 DEBUG : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3: Waiting for checks to finish 2022/01/12 05:55:06 DEBUG : both1: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:55:06 DEBUG : both1: Unchanged skipping 2022/01/12 05:55:06 DEBUG : both0: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:55:06 DEBUG : both0: Unchanged skipping 2022/01/12 05:55:06 DEBUG : both10: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:55:06 DEBUG : both10: Unchanged skipping 2022/01/12 05:55:06 DEBUG : both12: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:55:06 DEBUG : both12: Unchanged skipping 2022/01/12 05:55:06 DEBUG : both11: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:55:06 DEBUG : both11: Unchanged skipping 2022/01/12 05:55:06 DEBUG : both13: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:55:06 DEBUG : both13: Unchanged skipping 2022/01/12 05:55:06 DEBUG : both15: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:55:06 DEBUG : both15: Unchanged skipping 2022/01/12 05:55:06 DEBUG : both14: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:55:06 DEBUG : both14: Unchanged skipping 2022/01/12 05:55:06 DEBUG : both16: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:55:06 DEBUG : both16: Unchanged skipping 2022/01/12 05:55:06 DEBUG : both17: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:55:06 DEBUG : both17: Unchanged skipping 2022/01/12 05:55:06 DEBUG : both19: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:55:06 DEBUG : both19: Unchanged skipping 2022/01/12 05:55:06 DEBUG : both18: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:55:06 DEBUG : both18: Unchanged skipping 2022/01/12 05:55:06 DEBUG : both2: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:55:06 DEBUG : both2: Unchanged skipping 2022/01/12 05:55:06 DEBUG : both3: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:55:06 DEBUG : both3: Unchanged skipping 2022/01/12 05:55:06 DEBUG : both4: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:55:06 DEBUG : both4: Unchanged skipping 2022/01/12 05:55:06 DEBUG : both6: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:55:06 DEBUG : both6: Unchanged skipping 2022/01/12 05:55:06 DEBUG : both5: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:55:06 DEBUG : both5: Unchanged skipping 2022/01/12 05:55:06 DEBUG : only0: Sizes differ (src 0 vs dst 6) 2022/01/12 05:55:06 DEBUG : only1: Sizes differ (src 0 vs dst 6) 2022/01/12 05:55:06 DEBUG : only10: Sizes differ (src 0 vs dst 6) 2022/01/12 05:55:06 DEBUG : only11: Sizes differ (src 0 vs dst 6) 2022/01/12 05:55:06 DEBUG : only12: Sizes differ (src 0 vs dst 6) 2022/01/12 05:55:06 DEBUG : only13: Sizes differ (src 0 vs dst 6) 2022/01/12 05:55:06 DEBUG : only14: Sizes differ (src 0 vs dst 6) 2022/01/12 05:55:06 DEBUG : only15: Sizes differ (src 0 vs dst 6) 2022/01/12 05:55:06 DEBUG : only16: Sizes differ (src 0 vs dst 6) 2022/01/12 05:55:06 DEBUG : only17: Sizes differ (src 0 vs dst 6) 2022/01/12 05:55:06 DEBUG : only18: Sizes differ (src 0 vs dst 6) 2022/01/12 05:55:06 DEBUG : only19: Sizes differ (src 0 vs dst 6) 2022/01/12 05:55:06 DEBUG : only2: Sizes differ (src 0 vs dst 6) 2022/01/12 05:55:06 DEBUG : only3: Sizes differ (src 0 vs dst 6) 2022/01/12 05:55:06 DEBUG : only4: Sizes differ (src 0 vs dst 6) 2022/01/12 05:55:06 DEBUG : only5: Sizes differ (src 0 vs dst 6) 2022/01/12 05:55:06 DEBUG : only6: Sizes differ (src 0 vs dst 6) 2022/01/12 05:55:06 DEBUG : only7: Sizes differ (src 0 vs dst 6) 2022/01/12 05:55:06 DEBUG : only8: Sizes differ (src 0 vs dst 6) 2022/01/12 05:55:06 DEBUG : only9: Sizes differ (src 0 vs dst 6) 2022/01/12 05:55:06 DEBUG : both7: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:55:06 DEBUG : both7: Unchanged skipping 2022/01/12 05:55:06 DEBUG : both8: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:55:06 DEBUG : both8: Unchanged skipping 2022/01/12 05:55:06 DEBUG : both9: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:55:06 DEBUG : both9: Unchanged skipping 2022/01/12 05:55:06 DEBUG : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3: Waiting for transfers to finish 2022/01/12 05:55:08 DEBUG : only10: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2022/01/12 05:55:08 INFO : only10: Copied (replaced existing) 2022/01/12 05:55:08 DEBUG : only0: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2022/01/12 05:55:08 INFO : only0: Copied (replaced existing) 2022/01/12 05:55:08 DEBUG : only1: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2022/01/12 05:55:08 INFO : only1: Copied (replaced existing) 2022/01/12 05:55:08 DEBUG : only11: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2022/01/12 05:55:08 INFO : only11: Copied (replaced existing) 2022/01/12 05:55:09 DEBUG : only12: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2022/01/12 05:55:09 INFO : only12: Copied (replaced existing) 2022/01/12 05:55:10 DEBUG : only14: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2022/01/12 05:55:10 INFO : only14: Copied (replaced existing) 2022/01/12 05:55:10 DEBUG : only13: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2022/01/12 05:55:10 INFO : only13: Copied (replaced existing) 2022/01/12 05:55:10 DEBUG : only15: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2022/01/12 05:55:10 INFO : only15: Copied (replaced existing) 2022/01/12 05:55:11 DEBUG : only17: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2022/01/12 05:55:11 INFO : only17: Copied (replaced existing) 2022/01/12 05:55:11 DEBUG : only16: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2022/01/12 05:55:11 INFO : only16: Copied (replaced existing) 2022/01/12 05:55:11 DEBUG : only18: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2022/01/12 05:55:11 INFO : only18: Copied (replaced existing) 2022/01/12 05:55:11 DEBUG : only19: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2022/01/12 05:55:11 INFO : only19: Copied (replaced existing) 2022/01/12 05:55:12 DEBUG : only2: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2022/01/12 05:55:12 INFO : only2: Copied (replaced existing) 2022/01/12 05:55:13 DEBUG : only4: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2022/01/12 05:55:13 INFO : only4: Copied (replaced existing) 2022/01/12 05:55:13 DEBUG : only5: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2022/01/12 05:55:13 INFO : only5: Copied (replaced existing) 2022/01/12 05:55:13 DEBUG : only6: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2022/01/12 05:55:13 INFO : only6: Copied (replaced existing) 2022/01/12 05:55:14 DEBUG : only3: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2022/01/12 05:55:14 INFO : only3: Copied (replaced existing) 2022/01/12 05:55:14 DEBUG : only7: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2022/01/12 05:55:14 INFO : only7: Copied (replaced existing) 2022/01/12 05:55:14 DEBUG : only9: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2022/01/12 05:55:14 INFO : only9: Copied (replaced existing) 2022/01/12 05:55:15 DEBUG : only8: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2022/01/12 05:55:15 INFO : only8: Copied (replaced existing) 2022/01/12 05:55:15 DEBUG : Waiting for deletions to finish --- PASS: TestSyncConcurrentTruncate (114.42s) FAIL 2022/01/12 05:55:55 DEBUG : Hubic Swift container rclone-test-kikikak6puqugin8mijovak3: Purge remote 2022/01/12 05:55:55 DEBUG : Waiting for deletions to finish 2022/01/12 05:55:55 INFO : Swift container rclone-test-kikikak6puqugin8mijovak3: Container "rclone-test-kikikak6puqugin8mijovak3" removed "./sync.test -test.v -test.timeout 1h0m0s -remote TestHubic: -verbose" - Finished ERROR in 12m42.387882852s (try 1/5): exit status 1: Failed [TestCopyWithFilesFromAndNoTraverse TestSyncBackupDir TestSyncConcurrentDelete]