"./sync.test -test.v -test.timeout 1h0m0s -remote TestS3R2: -verbose -test.run '^(TestCopy|TestCopyAfterDelete|TestCopyCheckFirst|TestCopyDeleteBefore|TestCopyEmptyDirectories|TestCopyNoTraverse|TestCopyRedownload|TestCopyWithDepth|TestCopyWithFilesFrom|TestCopyWithFilesFromAndNoTraverse|TestMoveEmptyDirectories|TestMoveWithDeleteEmptySrcDirs|TestMoveWithIgnoreExisting|TestMoveWithoutDeleteEmptySrcDirs|TestServerSideCopy|TestServerSideMove|TestServerSideMoveDeleteEmptySourceDirs|TestServerSideMoveOverlap|TestServerSideMoveWithFilter|TestSyncAfterAddingAFile|TestSyncAfterChangingContentsOnly|TestSyncAfterChangingFilesSizeOnly|TestSyncAfterChangingModtimeOnly|TestSyncAfterChangingModtimeOnlyWithNoUpdateModTime|TestSyncAfterRemovingAFileAndAddingAFile|TestSyncAfterRemovingAFileAndAddingAFileDryRun|TestSyncAfterRemovingAFileAndAddingAFileSubDir|TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors|TestSyncBackupDir|TestSyncBackupDirSuffixOnly|TestSyncBackupDirWithSuffix|TestSyncBackupDirWithSuffixKeepExtension|TestSyncBasedOnCheckSum|TestSyncCompareDest|TestSyncConcurrentDelete|TestSyncConcurrentTruncate|TestSyncCopyDest|TestSyncDeleteBefore|TestSyncDeleteDuring|TestSyncDoesntUpdateModtime|TestSyncEmptyDirectories|TestSyncIgnoreCase|TestSyncIgnoreErrors|TestSyncIgnoreExisting|TestSyncIgnoreSize|TestSyncIgnoreTimes|TestSyncImmutable|TestSyncMultipleCompareDest|TestSyncNoTraverse|TestSyncSizeOnly|TestSyncSuffix|TestSyncSuffixKeepExtension|TestSyncUTFNorm|TestSyncWithExclude|TestSyncWithExcludeAndDeleteExcluded|TestSyncWithTrackRenames|TestSyncWithTrackRenamesStrategyLeaf|TestSyncWithTrackRenamesStrategyModtime|TestSyncWithUpdateOlder)$'" - Starting (try 5/5) 2022/06/17 08:55:14 DEBUG : Creating backend with remote "TestS3R2:rclone-test-qexesid7zumeguc1wefuwul1" 2022/06/17 08:55:14 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2022/06/17 08:55:14 DEBUG : Creating backend with remote "/tmp/rclone2984337896" === RUN TestCopy run.go:181: Remote "S3 bucket rclone-test-qexesid7zumeguc1wefuwul1", Local "Local file system at /tmp/rclone2984337896", Modify Window "1ns" 2022/06/17 08:55:15 INFO : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1: Bucket "rclone-test-qexesid7zumeguc1wefuwul1" created with ACL "private" 2022/06/17 08:55:15 DEBUG : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1: Waiting for checks to finish 2022/06/17 08:55:15 DEBUG : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1: Waiting for transfers to finish 2022/06/17 08:55:16 DEBUG : sub dir/hello world: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2022/06/17 08:55:16 INFO : sub dir/hello world: Copied (new) fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:299: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:299: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:306: Error Trace: fstest.go:306 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:68 Error: Should be true Test: TestCopy Messages: listing wrong, want sub dir/hello world (11) got fstest.go:204: Not found "sub dir/hello world" fstest.go:207: Error Trace: fstest.go:207 fstest.go:311 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:68 Error: Not equal: expected: 0 actual : 1 Test: TestCopy Messages: 1 objects not found --- FAIL: TestCopy (9.00s) === RUN TestCopyNoTraverse run.go:181: Remote "S3 bucket rclone-test-qexesid7zumeguc1wefuwul1", Local "Local file system at /tmp/rclone2984337896", Modify Window "1ns" 2022/06/17 08:55:23 DEBUG : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1: Waiting for checks to finish 2022/06/17 08:55:23 DEBUG : sub dir/hello world: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/06/17 08:55:23 DEBUG : sub dir/hello world: Unchanged skipping 2022/06/17 08:55:23 DEBUG : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1: 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:101 Error: Should be true Test: TestCopyNoTraverse Messages: listing wrong, want sub dir/hello world (11) got fstest.go:204: Not found "sub dir/hello world" fstest.go:207: Error Trace: fstest.go:207 fstest.go:311 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:101 Error: Not equal: expected: 0 actual : 1 Test: TestCopyNoTraverse Messages: 1 objects not found --- FAIL: TestCopyNoTraverse (7.51s) === RUN TestCopyCheckFirst run.go:181: Remote "S3 bucket rclone-test-qexesid7zumeguc1wefuwul1", Local "Local file system at /tmp/rclone2984337896", Modify Window "1ns" 2022/06/17 08:55:30 INFO : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1: Running all checks before starting transfers 2022/06/17 08:55:31 DEBUG : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1: Waiting for checks to finish 2022/06/17 08:55:31 DEBUG : sub dir/hello world: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/06/17 08:55:31 DEBUG : sub dir/hello world: Unchanged skipping 2022/06/17 08:55:31 INFO : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1: Checks finished, now starting transfers 2022/06/17 08:55:31 DEBUG : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1: 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:119 Error: Should be true Test: TestCopyCheckFirst Messages: listing wrong, want sub dir/hello world (11) got fstest.go:204: Not found "sub dir/hello world" fstest.go:207: Error Trace: fstest.go:207 fstest.go:311 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:119 Error: Not equal: expected: 0 actual : 1 Test: TestCopyCheckFirst Messages: 1 objects not found --- FAIL: TestCopyCheckFirst (7.62s) === RUN TestSyncNoTraverse run.go:181: Remote "S3 bucket rclone-test-qexesid7zumeguc1wefuwul1", Local "Local file system at /tmp/rclone2984337896", Modify Window "1ns" 2022/06/17 08:55:38 ERROR : Ignoring --no-traverse with sync 2022/06/17 08:55:38 DEBUG : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1: Waiting for checks to finish 2022/06/17 08:55:38 DEBUG : sub dir/hello world: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/06/17 08:55:38 DEBUG : sub dir/hello world: Unchanged skipping 2022/06/17 08:55:38 DEBUG : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1: Waiting for transfers to finish 2022/06/17 08:55:38 DEBUG : Waiting for deletions to finish 2022/06/17 08:55:38 INFO : There was nothing to transfer fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:299: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:299: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:306: Error Trace: fstest.go:306 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:138 Error: Should be true Test: TestSyncNoTraverse Messages: listing wrong, want sub dir/hello world (11) got fstest.go:204: Not found "sub dir/hello world" fstest.go:207: Error Trace: fstest.go:207 fstest.go:311 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:138 Error: Not equal: expected: 0 actual : 1 Test: TestSyncNoTraverse Messages: 1 objects not found --- FAIL: TestSyncNoTraverse (7.55s) === RUN TestCopyWithDepth run.go:181: Remote "S3 bucket rclone-test-qexesid7zumeguc1wefuwul1", Local "Local file system at /tmp/rclone2984337896", Modify Window "1ns" 2022/06/17 08:55:46 DEBUG : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1: Waiting for checks to finish 2022/06/17 08:55:46 DEBUG : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1: Waiting for transfers to finish 2022/06/17 08:55:46 DEBUG : hello world2: md5 = cc2c857f89648dbd139d7b2a6665957d OK 2022/06/17 08:55:46 INFO : hello world2: Copied (new) fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:299: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:299: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:306: Error Trace: fstest.go:306 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:157 Error: Should be true Test: TestCopyWithDepth Messages: listing wrong, want hello world2 (12) got fstest.go:204: Not found "hello world2" fstest.go:207: Error Trace: fstest.go:207 fstest.go:311 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:157 Error: Not equal: expected: 0 actual : 1 Test: TestCopyWithDepth Messages: 1 objects not found --- FAIL: TestCopyWithDepth (8.39s) === RUN TestCopyWithFilesFrom run.go:181: Remote "S3 bucket rclone-test-qexesid7zumeguc1wefuwul1", Local "Local file system at /tmp/rclone2984337896", Modify Window "1ns" 2022/06/17 08:55:54 DEBUG : hello world2: Excluded 2022/06/17 08:55:54 DEBUG : sub dir: Excluded 2022/06/17 08:55:54 DEBUG : hello world2: Excluded 2022/06/17 08:55:54 DEBUG : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1: Waiting for checks to finish 2022/06/17 08:55:54 DEBUG : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1: Waiting for transfers to finish 2022/06/17 08:55:54 DEBUG : potato2: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2022/06/17 08:55:54 INFO : potato2: Copied (new) fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:299: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:299: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:306: Error Trace: fstest.go:306 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:184 sync_test.go:186 Error: Should be true Test: TestCopyWithFilesFrom 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:186 Error: Not equal: expected: 0 actual : 1 Test: TestCopyWithFilesFrom Messages: 1 objects not found --- FAIL: TestCopyWithFilesFrom (7.89s) === RUN TestCopyWithFilesFromAndNoTraverse run.go:181: Remote "S3 bucket rclone-test-qexesid7zumeguc1wefuwul1", Local "Local file system at /tmp/rclone2984337896", Modify Window "1ns" 2022/06/17 08:56:02 DEBUG : potato2: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/06/17 08:56:02 DEBUG : potato2: Unchanged skipping 2022/06/17 08:56:02 DEBUG : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1: Waiting for checks to finish 2022/06/17 08:56:02 DEBUG : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1: 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.48s) === RUN TestCopyEmptyDirectories run.go:181: Remote "S3 bucket rclone-test-qexesid7zumeguc1wefuwul1", Local "Local file system at /tmp/rclone2984337896", Modify Window "1ns" 2022/06/17 08:56:09 DEBUG : sub dir2: Making directory 2022/06/17 08:56:11 DEBUG : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1: Waiting for checks to finish 2022/06/17 08:56:11 DEBUG : sub dir/hello world: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/06/17 08:56:11 DEBUG : sub dir/hello world: Unchanged skipping 2022/06/17 08:56:11 DEBUG : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1: Waiting for transfers to finish 2022/06/17 08:56:11 DEBUG : sub dir2: Making directory 2022/06/17 08:56:11 DEBUG : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1: copied 1 directories fstest.go:251: Filtering empty directory "sub dir2" fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:299: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:299: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:306: Error Trace: fstest.go:306 fstest.go:339 run.go:362 sync_test.go:202 Error: Should be true Test: TestCopyEmptyDirectories Messages: listing wrong, want sub dir/hello world (11) got fstest.go:204: Not found "sub dir/hello world" fstest.go:207: Error Trace: fstest.go:207 fstest.go:311 fstest.go:339 run.go:362 sync_test.go:202 Error: Not equal: expected: 0 actual : 1 Test: TestCopyEmptyDirectories Messages: 1 objects not found fstest.go:328: Error Trace: fstest.go:328 fstest.go:339 run.go:362 sync_test.go:202 Error: Not equal: expected: []string{"sub dir"} actual : []string{} Diff: --- Expected +++ Actual @@ -1,3 +1,2 @@ -([]string) (len=1) { - (string) (len=7) "sub dir" +([]string) { } Test: TestCopyEmptyDirectories Messages: directories --- FAIL: TestCopyEmptyDirectories (9.07s) === RUN TestMoveEmptyDirectories run.go:181: Remote "S3 bucket rclone-test-qexesid7zumeguc1wefuwul1", Local "Local file system at /tmp/rclone2984337896", Modify Window "1ns" 2022/06/17 08:56:18 DEBUG : sub dir2: Making directory 2022/06/17 08:56:19 DEBUG : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1: Waiting for checks to finish 2022/06/17 08:56:19 DEBUG : sub dir/hello world: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/06/17 08:56:19 DEBUG : sub dir/hello world: Unchanged skipping 2022/06/17 08:56:19 INFO : sub dir/hello world: Deleted 2022/06/17 08:56:19 DEBUG : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1: Waiting for transfers to finish 2022/06/17 08:56:19 DEBUG : sub dir2: Making directory 2022/06/17 08:56:19 DEBUG : sub dir: Making directory 2022/06/17 08:56:19 DEBUG : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1: copied 2 directories fstest.go:251: Filtering empty directory "sub dir2" fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:299: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:299: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:306: Error Trace: fstest.go:306 fstest.go:339 run.go:362 sync_test.go:227 Error: Should be true Test: TestMoveEmptyDirectories Messages: listing wrong, want sub dir/hello world (11) got fstest.go:204: Not found "sub dir/hello world" fstest.go:207: Error Trace: fstest.go:207 fstest.go:311 fstest.go:339 run.go:362 sync_test.go:227 Error: Not equal: expected: 0 actual : 1 Test: TestMoveEmptyDirectories Messages: 1 objects not found fstest.go:328: Error Trace: fstest.go:328 fstest.go:339 run.go:362 sync_test.go:227 Error: Not equal: expected: []string{"sub dir"} actual : []string{} Diff: --- Expected +++ Actual @@ -1,3 +1,2 @@ -([]string) (len=1) { - (string) (len=7) "sub dir" +([]string) { } Test: TestMoveEmptyDirectories Messages: directories --- FAIL: TestMoveEmptyDirectories (7.62s) === RUN TestSyncEmptyDirectories run.go:181: Remote "S3 bucket rclone-test-qexesid7zumeguc1wefuwul1", Local "Local file system at /tmp/rclone2984337896", Modify Window "1ns" 2022/06/17 08:56:26 DEBUG : sub dir2: Making directory 2022/06/17 08:56:26 DEBUG : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1: Waiting for checks to finish 2022/06/17 08:56:26 DEBUG : sub dir/hello world: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/06/17 08:56:26 DEBUG : sub dir/hello world: Unchanged skipping 2022/06/17 08:56:26 DEBUG : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1: Waiting for transfers to finish 2022/06/17 08:56:26 DEBUG : sub dir2: Making directory 2022/06/17 08:56:26 DEBUG : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1: copied 1 directories 2022/06/17 08:56:26 DEBUG : Waiting for deletions to finish 2022/06/17 08:56:26 INFO : potato2: Deleted 2022/06/17 08:56:27 INFO : hello world2: Deleted fstest.go:251: Filtering empty directory "sub dir2" fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:299: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:299: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:306: Error Trace: fstest.go:306 fstest.go:339 run.go:362 sync_test.go:252 Error: Should be true Test: TestSyncEmptyDirectories Messages: listing wrong, want sub dir/hello world (11) got fstest.go:204: Not found "sub dir/hello world" fstest.go:207: Error Trace: fstest.go:207 fstest.go:311 fstest.go:339 run.go:362 sync_test.go:252 Error: Not equal: expected: 0 actual : 1 Test: TestSyncEmptyDirectories Messages: 1 objects not found fstest.go:328: Error Trace: fstest.go:328 fstest.go:339 run.go:362 sync_test.go:252 Error: Not equal: expected: []string{"sub dir"} actual : []string{} Diff: --- Expected +++ Actual @@ -1,3 +1,2 @@ -([]string) (len=1) { - (string) (len=7) "sub dir" +([]string) { } Test: TestSyncEmptyDirectories Messages: directories --- FAIL: TestSyncEmptyDirectories (7.87s) === RUN TestServerSideCopy run.go:181: Remote "S3 bucket rclone-test-qexesid7zumeguc1wefuwul1", Local "Local file system at /tmp/rclone2984337896", 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:270 Error: Should be true Test: TestServerSideCopy Messages: listing wrong, want sub dir/hello world (11) got fstest.go:204: Not found "sub dir/hello world" fstest.go:207: Error Trace: fstest.go:207 fstest.go:311 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:270 Error: Not equal: expected: 0 actual : 1 Test: TestServerSideCopy Messages: 1 objects not found 2022/06/17 08:56:42 DEBUG : Creating backend with remote "TestS3R2:rclone-test-qubezub3safivoz2yafabim6" sync_test.go:275: Server side copy (if possible) S3 bucket rclone-test-qexesid7zumeguc1wefuwul1 -> S3 bucket rclone-test-qubezub3safivoz2yafabim6 2022/06/17 08:56:42 DEBUG : S3 bucket rclone-test-qubezub3safivoz2yafabim6: Waiting for checks to finish 2022/06/17 08:56:42 DEBUG : S3 bucket rclone-test-qubezub3safivoz2yafabim6: Waiting for transfers to finish 2022/06/17 08:56:42 INFO : S3 bucket rclone-test-qubezub3safivoz2yafabim6: Bucket "rclone-test-qubezub3safivoz2yafabim6" created with ACL "private" 2022/06/17 08:56:44 DEBUG : sub dir/hello world: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2022/06/17 08:56:44 INFO : sub dir/hello world: Copied (server-side copy) 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:357 sync_test.go:280 Error: Should be true Test: TestServerSideCopy Messages: listing wrong, want sub dir/hello world (11) got fstest.go:204: Not found "sub dir/hello world" fstest.go:207: Error Trace: fstest.go:207 fstest.go:311 fstest.go:339 fstest.go:357 sync_test.go:280 Error: Not equal: expected: 0 actual : 1 Test: TestServerSideCopy Messages: 1 objects not found 2022/06/17 08:56:51 DEBUG : S3 bucket rclone-test-qubezub3safivoz2yafabim6: Purge dir "" 2022/06/17 08:56:51 purge failed to rmdir "": BucketNotEmpty: The bucket you tried to delete (rclone-test-qubezub3safivoz2yafabim6) is not empty (account 14aad7c9ed489151b51557e321b246cf). status code: 409, request id: , host id: --- FAIL: TestServerSideCopy (17.09s) === RUN TestCopyAfterDelete run.go:181: Remote "S3 bucket rclone-test-qexesid7zumeguc1wefuwul1", Local "Local file system at /tmp/rclone2984337896", Modify Window "1ns" 2022/06/17 08:56:51 ERROR : : error listing: directory 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:291 Error: Should be true Test: TestCopyAfterDelete Messages: listing wrong, want sub dir/hello world (11) got fstest.go:204: Not found "sub dir/hello world" fstest.go:207: Error Trace: fstest.go:207 fstest.go:311 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:291 Error: Not equal: expected: 0 actual : 1 Test: TestCopyAfterDelete Messages: 1 objects not found 2022/06/17 08:56:59 DEBUG : Local file system at /tmp/rclone2984337896: Making directory 2022/06/17 08:56:59 DEBUG : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1: Waiting for checks to finish 2022/06/17 08:56:59 DEBUG : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1: 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:300 Error: Should be true Test: TestCopyAfterDelete Messages: listing wrong, want sub dir/hello world (11) got fstest.go:204: Not found "sub dir/hello world" fstest.go:207: Error Trace: fstest.go:207 fstest.go:311 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:300 Error: Not equal: expected: 0 actual : 1 Test: TestCopyAfterDelete Messages: 1 objects not found --- FAIL: TestCopyAfterDelete (15.05s) === RUN TestCopyRedownload run.go:181: Remote "S3 bucket rclone-test-qexesid7zumeguc1wefuwul1", Local "Local file system at /tmp/rclone2984337896", Modify Window "1ns" fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:299: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:299: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:306: Error Trace: fstest.go:306 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:309 Error: Should be true Test: TestCopyRedownload Messages: listing wrong, want sub dir/hello world (11) got fstest.go:204: Not found "sub dir/hello world" fstest.go:207: Error Trace: fstest.go:207 fstest.go:311 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:309 Error: Not equal: expected: 0 actual : 1 Test: TestCopyRedownload Messages: 1 objects not found 2022/06/17 08:57:14 DEBUG : Local file system at /tmp/rclone2984337896: Waiting for checks to finish 2022/06/17 08:57:14 DEBUG : Local file system at /tmp/rclone2984337896: Waiting for transfers to finish 2022/06/17 08:57:14 DEBUG : sub dir/hello world: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2022/06/17 08:57:14 INFO : sub dir/hello world: Copied (new) --- FAIL: TestCopyRedownload (8.23s) === RUN TestSyncBasedOnCheckSum run.go:181: Remote "S3 bucket rclone-test-qexesid7zumeguc1wefuwul1", Local "Local file system at /tmp/rclone2984337896", Modify Window "1ns" 2022/06/17 08:57:14 DEBUG : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1: Waiting for checks to finish 2022/06/17 08:57:14 DEBUG : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1: Waiting for transfers to finish 2022/06/17 08:57:15 DEBUG : check sum: md5 = 336d5ebc5436534e61d16e63ddfca327 OK 2022/06/17 08:57:15 INFO : check sum: Copied (new) 2022/06/17 08:57:15 DEBUG : Waiting for deletions to finish 2022/06/17 08:57:15 INFO : sub dir/hello world: Deleted fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:299: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:299: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:306: Error Trace: fstest.go:306 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:337 Error: Should be true Test: TestSyncBasedOnCheckSum Messages: listing wrong, want check sum (1) got fstest.go:204: Not found "check sum" fstest.go:207: Error Trace: fstest.go:207 fstest.go:311 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:337 Error: Not equal: expected: 0 actual : 1 Test: TestSyncBasedOnCheckSum Messages: 1 objects not found 2022/06/17 08:57:22 DEBUG : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1: Waiting for checks to finish 2022/06/17 08:57:22 DEBUG : check sum: md5 = 336d5ebc5436534e61d16e63ddfca327 OK 2022/06/17 08:57:22 DEBUG : check sum: Size and md5 of src and dst objects identical 2022/06/17 08:57:22 DEBUG : check sum: Unchanged skipping 2022/06/17 08:57:22 DEBUG : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1: Waiting for transfers to finish 2022/06/17 08:57:22 DEBUG : Waiting for deletions to finish 2022/06/17 08:57:22 INFO : There was nothing to transfer fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:299: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:299: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:306: Error Trace: fstest.go:306 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:350 Error: Should be true Test: TestSyncBasedOnCheckSum Messages: listing wrong, want check sum (1) got fstest.go:204: Not found "check sum" fstest.go:207: Error Trace: fstest.go:207 fstest.go:311 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:350 Error: Not equal: expected: 0 actual : 1 Test: TestSyncBasedOnCheckSum Messages: 1 objects not found --- FAIL: TestSyncBasedOnCheckSum (15.42s) === RUN TestSyncSizeOnly run.go:181: Remote "S3 bucket rclone-test-qexesid7zumeguc1wefuwul1", Local "Local file system at /tmp/rclone2984337896", Modify Window "1ns" 2022/06/17 08:57:30 DEBUG : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1: Waiting for checks to finish 2022/06/17 08:57:30 DEBUG : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1: Waiting for transfers to finish 2022/06/17 08:57:30 DEBUG : sizeonly: md5 = 8ee2027983915ec78acc45027d874316 OK 2022/06/17 08:57:30 INFO : sizeonly: Copied (new) 2022/06/17 08:57:30 DEBUG : Waiting for deletions to finish 2022/06/17 08:57:30 INFO : check sum: Deleted fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:299: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:299: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:306: Error Trace: fstest.go:306 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:372 Error: Should be true Test: TestSyncSizeOnly Messages: listing wrong, want sizeonly (6) got fstest.go:204: Not found "sizeonly" fstest.go:207: Error Trace: fstest.go:207 fstest.go:311 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:372 Error: Not equal: expected: 0 actual : 1 Test: TestSyncSizeOnly Messages: 1 objects not found 2022/06/17 08:57:38 DEBUG : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1: Waiting for checks to finish 2022/06/17 08:57:38 DEBUG : sizeonly: Sizes identical 2022/06/17 08:57:38 DEBUG : sizeonly: Unchanged skipping 2022/06/17 08:57:38 DEBUG : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1: Waiting for transfers to finish 2022/06/17 08:57:38 DEBUG : Waiting for deletions to finish 2022/06/17 08:57:38 INFO : There was nothing to transfer fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:299: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:299: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:306: Error Trace: fstest.go:306 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:385 Error: Should be true Test: TestSyncSizeOnly Messages: listing wrong, want sizeonly (6) got fstest.go:204: Not found "sizeonly" fstest.go:207: Error Trace: fstest.go:207 fstest.go:311 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:385 Error: Not equal: expected: 0 actual : 1 Test: TestSyncSizeOnly Messages: 1 objects not found --- FAIL: TestSyncSizeOnly (15.46s) === RUN TestSyncIgnoreSize run.go:181: Remote "S3 bucket rclone-test-qexesid7zumeguc1wefuwul1", Local "Local file system at /tmp/rclone2984337896", Modify Window "1ns" 2022/06/17 08:57:45 DEBUG : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1: Waiting for checks to finish 2022/06/17 08:57:45 DEBUG : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1: Waiting for transfers to finish 2022/06/17 08:57:46 DEBUG : ignore-size: md5 = 98bf7d8c15784f0a3d63204441e1e2aa OK 2022/06/17 08:57:46 INFO : ignore-size: Copied (new) 2022/06/17 08:57:46 DEBUG : Waiting for deletions to finish 2022/06/17 08:57:46 INFO : sizeonly: Deleted fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:299: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:299: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:306: Error Trace: fstest.go:306 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:407 Error: Should be true Test: TestSyncIgnoreSize Messages: listing wrong, want ignore-size (8) got fstest.go:204: Not found "ignore-size" fstest.go:207: Error Trace: fstest.go:207 fstest.go:311 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:407 Error: Not equal: expected: 0 actual : 1 Test: TestSyncIgnoreSize Messages: 1 objects not found 2022/06/17 08:57:53 DEBUG : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1: Waiting for checks to finish 2022/06/17 08:57:53 DEBUG : ignore-size: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/06/17 08:57:53 DEBUG : ignore-size: Unchanged skipping 2022/06/17 08:57:53 DEBUG : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1: Waiting for transfers to finish 2022/06/17 08:57:53 DEBUG : Waiting for deletions to finish 2022/06/17 08:57:53 INFO : There was nothing to transfer fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:299: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:299: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:306: Error Trace: fstest.go:306 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:420 Error: Should be true Test: TestSyncIgnoreSize Messages: listing wrong, want ignore-size (8) got fstest.go:204: Not found "ignore-size" fstest.go:207: Error Trace: fstest.go:207 fstest.go:311 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:420 Error: Not equal: expected: 0 actual : 1 Test: TestSyncIgnoreSize Messages: 1 objects not found --- FAIL: TestSyncIgnoreSize (15.27s) === RUN TestSyncIgnoreTimes run.go:181: Remote "S3 bucket rclone-test-qexesid7zumeguc1wefuwul1", Local "Local file system at /tmp/rclone2984337896", 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:429 Error: Should be true Test: TestSyncIgnoreTimes Messages: listing wrong, want existing (6) got fstest.go:204: Not found "existing" fstest.go:207: Error Trace: fstest.go:207 fstest.go:311 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:429 Error: Not equal: expected: 0 actual : 1 Test: TestSyncIgnoreTimes Messages: 1 objects not found 2022/06/17 08:58:08 DEBUG : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1: Waiting for checks to finish 2022/06/17 08:58:08 DEBUG : existing: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/06/17 08:58:08 DEBUG : existing: Unchanged skipping 2022/06/17 08:58:08 DEBUG : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1: Waiting for transfers to finish 2022/06/17 08:58:08 DEBUG : Waiting for deletions to finish 2022/06/17 08:58:08 INFO : ignore-size: Deleted 2022/06/17 08:58:08 INFO : There was nothing to transfer 2022/06/17 08:58:08 DEBUG : existing: Transferring unconditionally as --ignore-times is in use 2022/06/17 08:58:08 DEBUG : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1: Waiting for checks to finish 2022/06/17 08:58:08 DEBUG : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1: Waiting for transfers to finish 2022/06/17 08:58:09 DEBUG : existing: md5 = 8ee2027983915ec78acc45027d874316 OK 2022/06/17 08:58:09 INFO : existing: Copied (replaced existing) 2022/06/17 08:58:09 DEBUG : Waiting for deletions to finish fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:299: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:299: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:306: Error Trace: fstest.go:306 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:450 Error: Should be true Test: TestSyncIgnoreTimes Messages: listing wrong, want existing (6) got fstest.go:204: Not found "existing" fstest.go:207: Error Trace: fstest.go:207 fstest.go:311 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:450 Error: Not equal: expected: 0 actual : 1 Test: TestSyncIgnoreTimes Messages: 1 objects not found --- FAIL: TestSyncIgnoreTimes (15.76s) === RUN TestSyncIgnoreExisting run.go:181: Remote "S3 bucket rclone-test-qexesid7zumeguc1wefuwul1", Local "Local file system at /tmp/rclone2984337896", Modify Window "1ns" 2022/06/17 08:58:16 DEBUG : existing: Destination exists, skipping 2022/06/17 08:58:16 DEBUG : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1: Waiting for checks to finish 2022/06/17 08:58:16 DEBUG : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1: Waiting for transfers to finish 2022/06/17 08:58:16 DEBUG : Waiting for deletions to finish 2022/06/17 08:58:16 INFO : There was nothing to transfer fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:299: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:299: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:306: Error Trace: fstest.go:306 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:466 Error: Should be true Test: TestSyncIgnoreExisting Messages: listing wrong, want existing (6) got fstest.go:204: Not found "existing" fstest.go:207: Error Trace: fstest.go:207 fstest.go:311 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:466 Error: Not equal: expected: 0 actual : 1 Test: TestSyncIgnoreExisting Messages: 1 objects not found 2022/06/17 08:58:24 DEBUG : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1: Waiting for checks to finish 2022/06/17 08:58:24 DEBUG : existing: Destination exists, skipping 2022/06/17 08:58:24 DEBUG : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1: Waiting for transfers to finish 2022/06/17 08:58:24 DEBUG : Waiting for deletions to finish 2022/06/17 08:58:24 INFO : There was nothing to transfer fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:299: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:299: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:306: Error Trace: fstest.go:306 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:474 Error: Should be true Test: TestSyncIgnoreExisting Messages: listing wrong, want existing (6) got fstest.go:204: Not found "existing" fstest.go:207: Error Trace: fstest.go:207 fstest.go:311 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:474 Error: Not equal: expected: 0 actual : 1 Test: TestSyncIgnoreExisting Messages: 1 objects not found --- FAIL: TestSyncIgnoreExisting (15.36s) === RUN TestSyncIgnoreErrors run.go:181: Remote "S3 bucket rclone-test-qexesid7zumeguc1wefuwul1", Local "Local file system at /tmp/rclone2984337896", Modify Window "1ns" 2022/06/17 08:58:32 DEBUG : d: Making directory fstest.go:251: Filtering empty directory "d" fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:299: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:299: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:306: Error Trace: fstest.go:306 fstest.go:339 run.go:362 sync_test.go:499 Error: Should be true Test: TestSyncIgnoreErrors Messages: listing wrong, want b/potato (21), c/non empty space (5) got fstest.go:204: Not found "b/potato" fstest.go:204: Not found "c/non empty space" fstest.go:207: Error Trace: fstest.go:207 fstest.go:311 fstest.go:339 run.go:362 sync_test.go:499 Error: Not equal: expected: 0 actual : 2 Test: TestSyncIgnoreErrors Messages: 2 objects not found fstest.go:328: Error Trace: fstest.go:328 fstest.go:339 run.go:362 sync_test.go:499 Error: Not equal: expected: []string{"b", "c"} actual : []string{} Diff: --- Expected +++ Actual @@ -1,4 +1,2 @@ -([]string) (len=2) { - (string) (len=1) "b", - (string) (len=1) "c" +([]string) { } Test: TestSyncIgnoreErrors Messages: directories 2022/06/17 08:58:40 DEBUG : c/non empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/06/17 08:58:40 DEBUG : c/non empty space: Unchanged skipping 2022/06/17 08:58:40 DEBUG : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1: Waiting for checks to finish 2022/06/17 08:58:40 DEBUG : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1: Waiting for transfers to finish 2022/06/17 08:58:40 DEBUG : a/potato2: md5 = d6548b156ea68a4e003e786df99eee76 OK 2022/06/17 08:58:40 INFO : a/potato2: Copied (new) 2022/06/17 08:58:40 DEBUG : Waiting for deletions to finish 2022/06/17 08:58:40 INFO : b/potato: Deleted 2022/06/17 08:58:40 INFO : existing: Deleted fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:299: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:299: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:306: Error Trace: fstest.go:306 fstest.go:339 run.go:362 sync_test.go:527 Error: Should be true Test: TestSyncIgnoreErrors Messages: listing wrong, want a/potato2 (60), c/non empty space (5) got fstest.go:204: Not found "a/potato2" fstest.go:204: Not found "c/non empty space" fstest.go:207: Error Trace: fstest.go:207 fstest.go:311 fstest.go:339 run.go:362 sync_test.go:527 Error: Not equal: expected: 0 actual : 2 Test: TestSyncIgnoreErrors Messages: 2 objects not found fstest.go:328: Error Trace: fstest.go:328 fstest.go:339 run.go:362 sync_test.go:527 Error: Not equal: expected: []string{"a", "c"} actual : []string{} Diff: --- Expected +++ Actual @@ -1,4 +1,2 @@ -([]string) (len=2) { - (string) (len=1) "a", - (string) (len=1) "c" +([]string) { } Test: TestSyncIgnoreErrors Messages: directories --- FAIL: TestSyncIgnoreErrors (16.25s) === RUN TestSyncAfterChangingModtimeOnly run.go:181: Remote "S3 bucket rclone-test-qexesid7zumeguc1wefuwul1", Local "Local file system at /tmp/rclone2984337896", Modify Window "1ns" fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:299: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:299: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:306: Error Trace: fstest.go:306 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:549 Error: Should be true Test: TestSyncAfterChangingModtimeOnly Messages: listing wrong, want empty space (1) got fstest.go:204: Not found "empty space" fstest.go:207: Error Trace: fstest.go:207 fstest.go:311 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:549 Error: Not equal: expected: 0 actual : 1 Test: TestSyncAfterChangingModtimeOnly Messages: 1 objects not found 2022/06/17 08:58:56 DEBUG : empty space: Modification times differ by -95456h54m52.62345679s: 2011-12-25 12:59:59.123456789 +0000 UTC, 2001-02-03 04:05:06.499999999 +0000 UTC 2022/06/17 08:58:56 DEBUG : empty space: md5 = 336d5ebc5436534e61d16e63ddfca327 OK 2022/06/17 08:58:56 NOTICE: empty space: Skipped update modification time as --dry-run is set (size 1) 2022/06/17 08:58:56 DEBUG : empty space: Unchanged skipping 2022/06/17 08:58:56 DEBUG : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1: Waiting for checks to finish 2022/06/17 08:58:56 DEBUG : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1: Waiting for transfers to finish 2022/06/17 08:58:56 DEBUG : Waiting for deletions to finish 2022/06/17 08:58:56 NOTICE: c/non empty space: Skipped delete as --dry-run is set (size 5) 2022/06/17 08:58:56 NOTICE: a/potato2: Skipped delete as --dry-run is set (size 60) 2022/06/17 08:58:56 INFO : There was nothing to transfer fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:299: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:299: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:306: Error Trace: fstest.go:306 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:558 Error: Should be true Test: TestSyncAfterChangingModtimeOnly Messages: listing wrong, want empty space (1) got fstest.go:204: Not found "empty space" fstest.go:207: Error Trace: fstest.go:207 fstest.go:311 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:558 Error: Not equal: expected: 0 actual : 1 Test: TestSyncAfterChangingModtimeOnly Messages: 1 objects not found 2022/06/17 08:59:03 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/06/17 08:59:03 DEBUG : empty space: md5 = 336d5ebc5436534e61d16e63ddfca327 OK 2022/06/17 08:59:03 DEBUG : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1: Waiting for checks to finish 2022/06/17 08:59:03 INFO : empty space: Updated modification time in destination 2022/06/17 08:59:03 DEBUG : empty space: Unchanged skipping 2022/06/17 08:59:03 DEBUG : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1: Waiting for transfers to finish 2022/06/17 08:59:03 DEBUG : Waiting for deletions to finish 2022/06/17 08:59:03 INFO : c/non empty space: Deleted 2022/06/17 08:59:03 INFO : a/potato2: Deleted 2022/06/17 08:59:03 INFO : There was nothing to transfer fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:299: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:299: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:306: Error Trace: fstest.go:306 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:567 Error: Should be true Test: TestSyncAfterChangingModtimeOnly Messages: listing wrong, want empty space (1) got fstest.go:204: Not found "empty space" fstest.go:207: Error Trace: fstest.go:207 fstest.go:311 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:567 Error: Not equal: expected: 0 actual : 1 Test: TestSyncAfterChangingModtimeOnly Messages: 1 objects not found --- FAIL: TestSyncAfterChangingModtimeOnly (22.89s) === RUN TestSyncAfterChangingModtimeOnlyWithNoUpdateModTime run.go:181: Remote "S3 bucket rclone-test-qexesid7zumeguc1wefuwul1", Local "Local file system at /tmp/rclone2984337896", 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:587 Error: Should be true Test: TestSyncAfterChangingModtimeOnlyWithNoUpdateModTime Messages: listing wrong, want empty space (1) got fstest.go:204: Not found "empty space" fstest.go:207: Error Trace: fstest.go:207 fstest.go:311 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:587 Error: Not equal: expected: 0 actual : 1 Test: TestSyncAfterChangingModtimeOnlyWithNoUpdateModTime Messages: 1 objects not found 2022/06/17 08:59:18 DEBUG : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1: Waiting for checks to finish 2022/06/17 08:59:18 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/06/17 08:59:18 DEBUG : empty space: md5 = 336d5ebc5436534e61d16e63ddfca327 OK 2022/06/17 08:59:18 DEBUG : empty space: Unchanged skipping 2022/06/17 08:59:18 DEBUG : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1: Waiting for transfers to finish 2022/06/17 08:59:18 DEBUG : Waiting for deletions to finish 2022/06/17 08:59:18 INFO : There was nothing to transfer fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:299: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:299: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:306: Error Trace: fstest.go:306 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:594 Error: Should be true Test: TestSyncAfterChangingModtimeOnlyWithNoUpdateModTime Messages: listing wrong, want empty space (1) got fstest.go:204: Not found "empty space" fstest.go:207: Error Trace: fstest.go:207 fstest.go:311 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:594 Error: Not equal: expected: 0 actual : 1 Test: TestSyncAfterChangingModtimeOnlyWithNoUpdateModTime Messages: 1 objects not found --- FAIL: TestSyncAfterChangingModtimeOnlyWithNoUpdateModTime (15.19s) === RUN TestSyncDoesntUpdateModtime run.go:181: Remote "S3 bucket rclone-test-qexesid7zumeguc1wefuwul1", Local "Local file system at /tmp/rclone2984337896", 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:609 Error: Should be true Test: TestSyncDoesntUpdateModtime Messages: listing wrong, want foo (3) got fstest.go:204: Not found "foo" fstest.go:207: Error Trace: fstest.go:207 fstest.go:311 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:609 Error: Not equal: expected: 0 actual : 1 Test: TestSyncDoesntUpdateModtime Messages: 1 objects not found 2022/06/17 08:59:33 DEBUG : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1: Waiting for checks to finish 2022/06/17 08:59:34 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/06/17 08:59:34 DEBUG : foo: md5 = acbd18db4cc2f85cedef654fccc4a4d8 (Local file system at /tmp/rclone2984337896) 2022/06/17 08:59:34 DEBUG : foo: md5 = 37b51d194a7513e45b56f6524f2d51f2 (S3 bucket rclone-test-qexesid7zumeguc1wefuwul1) 2022/06/17 08:59:34 DEBUG : foo: md5 differ 2022/06/17 08:59:34 DEBUG : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1: Waiting for transfers to finish 2022/06/17 08:59:34 DEBUG : foo: md5 = acbd18db4cc2f85cedef654fccc4a4d8 OK 2022/06/17 08:59:34 INFO : foo: Copied (replaced existing) 2022/06/17 08:59:34 DEBUG : Waiting for deletions to finish 2022/06/17 08:59:34 INFO : empty space: Deleted fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:299: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:299: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:306: Error Trace: fstest.go:306 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:616 Error: Should be true Test: TestSyncDoesntUpdateModtime Messages: listing wrong, want foo (3) got fstest.go:204: Not found "foo" fstest.go:207: Error Trace: fstest.go:207 fstest.go:311 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:616 Error: Not equal: expected: 0 actual : 1 Test: TestSyncDoesntUpdateModtime Messages: 1 objects not found --- FAIL: TestSyncDoesntUpdateModtime (15.95s) === RUN TestSyncAfterAddingAFile run.go:181: Remote "S3 bucket rclone-test-qexesid7zumeguc1wefuwul1", Local "Local file system at /tmp/rclone2984337896", 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:630 Error: Should be true Test: TestSyncAfterAddingAFile Messages: listing wrong, want empty space (1) got fstest.go:204: Not found "empty space" fstest.go:207: Error Trace: fstest.go:207 fstest.go:311 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:630 Error: Not equal: expected: 0 actual : 1 Test: TestSyncAfterAddingAFile Messages: 1 objects not found 2022/06/17 08:59:49 DEBUG : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1: Waiting for checks to finish 2022/06/17 08:59:49 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/06/17 08:59:49 DEBUG : empty space: Unchanged skipping 2022/06/17 08:59:49 DEBUG : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1: Waiting for transfers to finish 2022/06/17 08:59:50 DEBUG : potato: md5 = d6548b156ea68a4e003e786df99eee76 OK 2022/06/17 08:59:50 INFO : potato: Copied (new) 2022/06/17 08:59:50 DEBUG : Waiting for deletions to finish 2022/06/17 08:59:50 INFO : foo: Deleted fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:299: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:299: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:306: Error Trace: fstest.go:306 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:636 Error: Should be true Test: TestSyncAfterAddingAFile Messages: listing wrong, want empty space (1), potato (60) got fstest.go:204: Not found "empty space" fstest.go:204: Not found "potato" fstest.go:207: Error Trace: fstest.go:207 fstest.go:311 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:636 Error: Not equal: expected: 0 actual : 2 Test: TestSyncAfterAddingAFile Messages: 2 objects not found --- FAIL: TestSyncAfterAddingAFile (15.63s) === RUN TestSyncAfterChangingFilesSizeOnly run.go:181: Remote "S3 bucket rclone-test-qexesid7zumeguc1wefuwul1", Local "Local file system at /tmp/rclone2984337896", 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:645 Error: Should be true Test: TestSyncAfterChangingFilesSizeOnly Messages: listing wrong, want potato (60) got fstest.go:204: Not found "potato" fstest.go:207: Error Trace: fstest.go:207 fstest.go:311 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:645 Error: Not equal: expected: 0 actual : 1 Test: TestSyncAfterChangingFilesSizeOnly Messages: 1 objects not found 2022/06/17 09:00:05 DEBUG : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1: Waiting for checks to finish 2022/06/17 09:00:05 DEBUG : potato: Sizes differ (src 21 vs dst 60) 2022/06/17 09:00:05 DEBUG : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1: Waiting for transfers to finish 2022/06/17 09:00:05 DEBUG : potato: md5 = 100defcf18c42a1e0dc42a789b107cd2 OK 2022/06/17 09:00:05 INFO : potato: Copied (replaced existing) 2022/06/17 09:00:05 DEBUG : Waiting for deletions to finish 2022/06/17 09:00:06 INFO : empty space: Deleted fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:299: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:299: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:306: Error Trace: fstest.go:306 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:652 Error: Should be true Test: TestSyncAfterChangingFilesSizeOnly Messages: listing wrong, want potato (21) got fstest.go:204: Not found "potato" fstest.go:207: Error Trace: fstest.go:207 fstest.go:311 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:652 Error: Not equal: expected: 0 actual : 1 Test: TestSyncAfterChangingFilesSizeOnly Messages: 1 objects not found --- FAIL: TestSyncAfterChangingFilesSizeOnly (15.64s) === RUN TestSyncAfterChangingContentsOnly run.go:181: Remote "S3 bucket rclone-test-qexesid7zumeguc1wefuwul1", Local "Local file system at /tmp/rclone2984337896", 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:669 Error: Should be true Test: TestSyncAfterChangingContentsOnly Messages: listing wrong, want potato (21) got fstest.go:204: Not found "potato" fstest.go:207: Error Trace: fstest.go:207 fstest.go:311 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:669 Error: Not equal: expected: 0 actual : 1 Test: TestSyncAfterChangingContentsOnly Messages: 1 objects not found 2022/06/17 09:00:21 DEBUG : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1: Waiting for checks to finish 2022/06/17 09:00:21 DEBUG : potato: Modification times differ by 119h59m59.876543211s: 2011-12-25 12:59:59.123456789 +0000 UTC, 2011-12-30 12:59:59 +0000 UTC 2022/06/17 09:00:21 DEBUG : potato: md5 = e4cb6955d9106df6263c45fcfc10f163 (Local file system at /tmp/rclone2984337896) 2022/06/17 09:00:21 DEBUG : potato: md5 = 100defcf18c42a1e0dc42a789b107cd2 (S3 bucket rclone-test-qexesid7zumeguc1wefuwul1) 2022/06/17 09:00:21 DEBUG : potato: md5 differ 2022/06/17 09:00:21 DEBUG : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1: Waiting for transfers to finish 2022/06/17 09:00:21 DEBUG : potato: md5 = e4cb6955d9106df6263c45fcfc10f163 OK 2022/06/17 09:00:21 INFO : potato: Copied (replaced existing) 2022/06/17 09:00:21 DEBUG : Waiting for deletions to finish fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:299: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:299: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:306: Error Trace: fstest.go:306 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:676 Error: Should be true Test: TestSyncAfterChangingContentsOnly Messages: listing wrong, want potato (21) got fstest.go:204: Not found "potato" fstest.go:207: Error Trace: fstest.go:207 fstest.go:311 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:676 Error: Not equal: expected: 0 actual : 1 Test: TestSyncAfterChangingContentsOnly Messages: 1 objects not found --- FAIL: TestSyncAfterChangingContentsOnly (15.59s) === RUN TestSyncAfterRemovingAFileAndAddingAFileDryRun run.go:181: Remote "S3 bucket rclone-test-qexesid7zumeguc1wefuwul1", Local "Local file system at /tmp/rclone2984337896", Modify Window "1ns" 2022/06/17 09:00:29 DEBUG : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1: Waiting for checks to finish 2022/06/17 09:00:29 NOTICE: potato2: Skipped copy as --dry-run is set (size 60) 2022/06/17 09:00:30 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/06/17 09:00:30 DEBUG : empty space: Unchanged skipping 2022/06/17 09:00:30 DEBUG : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1: Waiting for transfers to finish 2022/06/17 09:00:30 DEBUG : Waiting for deletions to finish 2022/06/17 09:00:30 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: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:696 Error: Should be true Test: TestSyncAfterRemovingAFileAndAddingAFileDryRun Messages: listing wrong, want empty space (1), potato (21) got fstest.go:204: Not found "empty space" fstest.go:204: Not found "potato" fstest.go:207: Error Trace: fstest.go:207 fstest.go:311 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:696 Error: Not equal: expected: 0 actual : 2 Test: TestSyncAfterRemovingAFileAndAddingAFileDryRun Messages: 2 objects not found --- FAIL: TestSyncAfterRemovingAFileAndAddingAFileDryRun (8.25s) === RUN TestSyncAfterRemovingAFileAndAddingAFile run.go:181: Remote "S3 bucket rclone-test-qexesid7zumeguc1wefuwul1", Local "Local file system at /tmp/rclone2984337896", 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:706 sync_test.go:717 Error: Should be true Test: TestSyncAfterRemovingAFileAndAddingAFile Messages: listing wrong, want empty space (1), potato (21) got fstest.go:204: Not found "potato" fstest.go:204: Not found "empty space" fstest.go:207: Error Trace: fstest.go:207 fstest.go:311 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:706 sync_test.go:717 Error: Not equal: expected: 0 actual : 2 Test: TestSyncAfterRemovingAFileAndAddingAFile Messages: 2 objects not found 2022/06/17 09:00:45 DEBUG : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1: Waiting for checks to finish 2022/06/17 09:00:45 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/06/17 09:00:45 DEBUG : empty space: Unchanged skipping 2022/06/17 09:00:45 DEBUG : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1: Waiting for transfers to finish 2022/06/17 09:00:45 DEBUG : potato2: md5 = d6548b156ea68a4e003e786df99eee76 OK 2022/06/17 09:00:45 INFO : potato2: Copied (new) 2022/06/17 09:00:45 DEBUG : Waiting for deletions to finish 2022/06/17 09:00:45 INFO : potato: Deleted fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:299: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:299: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:306: Error Trace: fstest.go:306 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:713 sync_test.go:717 Error: Should be true Test: TestSyncAfterRemovingAFileAndAddingAFile Messages: listing wrong, want empty space (1), potato2 (60) got fstest.go:204: Not found "potato2" fstest.go:204: Not found "empty space" fstest.go:207: Error Trace: fstest.go:207 fstest.go:311 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:713 sync_test.go:717 Error: Not equal: expected: 0 actual : 2 Test: TestSyncAfterRemovingAFileAndAddingAFile Messages: 2 objects not found --- FAIL: TestSyncAfterRemovingAFileAndAddingAFile (15.88s) === RUN TestSyncAfterRemovingAFileAndAddingAFileSubDir run.go:181: Remote "S3 bucket rclone-test-qexesid7zumeguc1wefuwul1", Local "Local file system at /tmp/rclone2984337896", Modify Window "1ns" 2022/06/17 09:00:53 DEBUG : d: Making directory 2022/06/17 09:00:53 DEBUG : d/e: Making directory fstest.go:251: Filtering empty directory "d" fstest.go:251: Filtering empty directory "d/e" fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:299: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:299: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:306: Error Trace: fstest.go:306 fstest.go:339 run.go:362 sync_test.go:741 sync_test.go:784 Error: Should be true Test: TestSyncAfterRemovingAFileAndAddingAFileSubDir Messages: listing wrong, want b/potato (21), c/non empty space (5) got fstest.go:204: Not found "c/non empty space" fstest.go:204: Not found "b/potato" fstest.go:207: Error Trace: fstest.go:207 fstest.go:311 fstest.go:339 run.go:362 sync_test.go:741 sync_test.go:784 Error: Not equal: expected: 0 actual : 2 Test: TestSyncAfterRemovingAFileAndAddingAFileSubDir Messages: 2 objects not found fstest.go:328: Error Trace: fstest.go:328 fstest.go:339 run.go:362 sync_test.go:741 sync_test.go:784 Error: Not equal: expected: []string{"b", "c"} actual : []string{} Diff: --- Expected +++ Actual @@ -1,4 +1,2 @@ -([]string) (len=2) { - (string) (len=1) "b", - (string) (len=1) "c" +([]string) { } Test: TestSyncAfterRemovingAFileAndAddingAFileSubDir Messages: directories 2022/06/17 09:01:01 DEBUG : c/non empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/06/17 09:01:01 DEBUG : c/non empty space: Unchanged skipping 2022/06/17 09:01:01 DEBUG : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1: Waiting for checks to finish 2022/06/17 09:01:01 DEBUG : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1: Waiting for transfers to finish 2022/06/17 09:01:01 DEBUG : a/potato2: md5 = d6548b156ea68a4e003e786df99eee76 OK 2022/06/17 09:01:01 INFO : a/potato2: Copied (new) 2022/06/17 09:01:01 DEBUG : Waiting for deletions to finish 2022/06/17 09:01:01 INFO : b/potato: Deleted 2022/06/17 09:01:01 INFO : potato2: Deleted 2022/06/17 09:01:01 INFO : empty space: Deleted fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:299: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:299: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:306: Error Trace: fstest.go:306 fstest.go:339 run.go:362 sync_test.go:770 sync_test.go:784 Error: Should be true Test: TestSyncAfterRemovingAFileAndAddingAFileSubDir Messages: listing wrong, want a/potato2 (60), c/non empty space (5) got fstest.go:204: Not found "a/potato2" fstest.go:204: Not found "c/non empty space" fstest.go:207: Error Trace: fstest.go:207 fstest.go:311 fstest.go:339 run.go:362 sync_test.go:770 sync_test.go:784 Error: Not equal: expected: 0 actual : 2 Test: TestSyncAfterRemovingAFileAndAddingAFileSubDir Messages: 2 objects not found fstest.go:328: Error Trace: fstest.go:328 fstest.go:339 run.go:362 sync_test.go:770 sync_test.go:784 Error: Not equal: expected: []string{"a", "c"} actual : []string{} Diff: --- Expected +++ Actual @@ -1,4 +1,2 @@ -([]string) (len=2) { - (string) (len=1) "a", - (string) (len=1) "c" +([]string) { } Test: TestSyncAfterRemovingAFileAndAddingAFileSubDir Messages: directories --- FAIL: TestSyncAfterRemovingAFileAndAddingAFileSubDir (16.11s) === RUN TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors run.go:181: Remote "S3 bucket rclone-test-qexesid7zumeguc1wefuwul1", Local "Local file system at /tmp/rclone2984337896", Modify Window "1ns" 2022/06/17 09:01:10 DEBUG : d: Making directory fstest.go:251: Filtering empty directory "d" fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:299: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:299: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:306: Error Trace: fstest.go:306 fstest.go:339 run.go:362 sync_test.go:808 Error: Should be true Test: TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors Messages: listing wrong, want b/potato (21), c/non empty space (5) got fstest.go:204: Not found "b/potato" fstest.go:204: Not found "c/non empty space" fstest.go:207: Error Trace: fstest.go:207 fstest.go:311 fstest.go:339 run.go:362 sync_test.go:808 Error: Not equal: expected: 0 actual : 2 Test: TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors Messages: 2 objects not found fstest.go:328: Error Trace: fstest.go:328 fstest.go:339 run.go:362 sync_test.go:808 Error: Not equal: expected: []string{"b", "c"} actual : []string{} Diff: --- Expected +++ Actual @@ -1,4 +1,2 @@ -([]string) (len=2) { - (string) (len=1) "b", - (string) (len=1) "c" +([]string) { } Test: TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors Messages: directories 2022/06/17 09:01:17 DEBUG : a/potato2: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/06/17 09:01:17 DEBUG : a/potato2: Unchanged skipping 2022/06/17 09:01:17 DEBUG : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1: Waiting for checks to finish 2022/06/17 09:01:18 DEBUG : c/non empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/06/17 09:01:18 DEBUG : c/non empty space: Unchanged skipping 2022/06/17 09:01:18 DEBUG : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1: Waiting for transfers to finish 2022/06/17 09:01:18 ERROR : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1: not deleting files as there were IO errors 2022/06/17 09:01:18 ERROR : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1: not deleting directories as there were IO errors fstest.go:251: Filtering empty directory "d" fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:299: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:299: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:306: Error Trace: fstest.go:306 fstest.go:339 run.go:362 sync_test.go:837 Error: Should be true Test: TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors Messages: listing wrong, want a/potato2 (60), b/potato (21), c/non empty space (5) got fstest.go:204: Not found "a/potato2" fstest.go:204: Not found "b/potato" fstest.go:204: Not found "c/non empty space" fstest.go:207: Error Trace: fstest.go:207 fstest.go:311 fstest.go:339 run.go:362 sync_test.go:837 Error: Not equal: expected: 0 actual : 3 Test: TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors Messages: 3 objects not found fstest.go:328: Error Trace: fstest.go:328 fstest.go:339 run.go:362 sync_test.go:837 Error: Not equal: expected: []string{"a", "b", "c"} actual : []string{} Diff: --- Expected +++ Actual @@ -1,5 +1,2 @@ -([]string) (len=3) { - (string) (len=1) "a", - (string) (len=1) "b", - (string) (len=1) "c" +([]string) { } Test: TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors Messages: directories --- FAIL: TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors (17.14s) === RUN TestSyncDeleteDuring run.go:181: Remote "S3 bucket rclone-test-qexesid7zumeguc1wefuwul1", Local "Local file system at /tmp/rclone2984337896", 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:706 sync_test.go:868 Error: Should be true Test: TestSyncDeleteDuring Messages: listing wrong, want empty space (1), potato (21) got fstest.go:204: Not found "potato" fstest.go:204: Not found "empty space" fstest.go:207: Error Trace: fstest.go:207 fstest.go:311 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:706 sync_test.go:868 Error: Not equal: expected: 0 actual : 2 Test: TestSyncDeleteDuring Messages: 2 objects not found 2022/06/17 09:01:34 DEBUG : Waiting for deletions to finish 2022/06/17 09:01:34 DEBUG : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1: Waiting for checks to finish 2022/06/17 09:01:34 INFO : c/non empty space: Deleted 2022/06/17 09:01:34 INFO : a/potato2: Deleted 2022/06/17 09:01:34 INFO : b/potato: Deleted 2022/06/17 09:01:35 INFO : potato: Deleted 2022/06/17 09:01:35 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/06/17 09:01:35 DEBUG : empty space: Unchanged skipping 2022/06/17 09:01:35 DEBUG : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1: Waiting for transfers to finish 2022/06/17 09:01:35 DEBUG : potato2: md5 = d6548b156ea68a4e003e786df99eee76 OK 2022/06/17 09:01:35 INFO : potato2: Copied (new) fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:299: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:299: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:306: Error Trace: fstest.go:306 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:713 sync_test.go:868 Error: Should be true Test: TestSyncDeleteDuring Messages: listing wrong, want empty space (1), potato2 (60) got fstest.go:204: Not found "potato2" fstest.go:204: Not found "empty space" fstest.go:207: Error Trace: fstest.go:207 fstest.go:311 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:713 sync_test.go:868 Error: Not equal: expected: 0 actual : 2 Test: TestSyncDeleteDuring Messages: 2 objects not found --- FAIL: TestSyncDeleteDuring (16.21s) === RUN TestSyncDeleteBefore run.go:181: Remote "S3 bucket rclone-test-qexesid7zumeguc1wefuwul1", Local "Local file system at /tmp/rclone2984337896", 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:706 sync_test.go:877 Error: Should be true Test: TestSyncDeleteBefore Messages: listing wrong, want empty space (1), potato (21) got fstest.go:204: Not found "empty space" fstest.go:204: Not found "potato" fstest.go:207: Error Trace: fstest.go:207 fstest.go:311 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:706 sync_test.go:877 Error: Not equal: expected: 0 actual : 2 Test: TestSyncDeleteBefore Messages: 2 objects not found 2022/06/17 09:01:50 DEBUG : Waiting for deletions to finish 2022/06/17 09:01:50 DEBUG : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1: Waiting for checks to finish 2022/06/17 09:01:50 DEBUG : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1: Waiting for transfers to finish 2022/06/17 09:01:50 INFO : potato: Deleted 2022/06/17 09:01:50 DEBUG : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1: Waiting for checks to finish 2022/06/17 09:01:50 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/06/17 09:01:50 DEBUG : empty space: Unchanged skipping 2022/06/17 09:01:51 DEBUG : potato2: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/06/17 09:01:51 DEBUG : potato2: Unchanged skipping 2022/06/17 09:01:51 DEBUG : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1: Waiting for transfers to finish 2022/06/17 09:01:51 INFO : There was nothing to transfer fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:299: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:299: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:306: Error Trace: fstest.go:306 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:713 sync_test.go:877 Error: Should be true Test: TestSyncDeleteBefore Messages: listing wrong, want empty space (1), potato2 (60) got fstest.go:204: Not found "empty space" fstest.go:204: Not found "potato2" fstest.go:207: Error Trace: fstest.go:207 fstest.go:311 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:713 sync_test.go:877 Error: Not equal: expected: 0 actual : 2 Test: TestSyncDeleteBefore Messages: 2 objects not found --- FAIL: TestSyncDeleteBefore (16.76s) === RUN TestCopyDeleteBefore run.go:181: Remote "S3 bucket rclone-test-qexesid7zumeguc1wefuwul1", Local "Local file system at /tmp/rclone2984337896", Modify Window "1ns" fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:299: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:299: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:306: Error Trace: fstest.go:306 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:891 Error: Should be true Test: TestCopyDeleteBefore Messages: listing wrong, want potato (21) got fstest.go:204: Not found "potato" fstest.go:207: Error Trace: fstest.go:207 fstest.go:311 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:891 Error: Not equal: expected: 0 actual : 1 Test: TestCopyDeleteBefore Messages: 1 objects not found 2022/06/17 09:02:07 DEBUG : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1: Waiting for checks to finish 2022/06/17 09:02:07 DEBUG : potato2: Sizes differ (src 19 vs dst 60) 2022/06/17 09:02:07 DEBUG : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1: Waiting for transfers to finish 2022/06/17 09:02:07 DEBUG : potato2: md5 = 9519bc2e940e1696ea7365b4b81adedc OK 2022/06/17 09:02:07 INFO : potato2: Copied (replaced existing) fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:299: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:299: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:306: Error Trace: fstest.go:306 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:898 Error: Should be true Test: TestCopyDeleteBefore Messages: listing wrong, want potato (21), potato2 (19) got fstest.go:204: Not found "potato2" fstest.go:204: Not found "potato" fstest.go:207: Error Trace: fstest.go:207 fstest.go:311 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:898 Error: Not equal: expected: 0 actual : 2 Test: TestCopyDeleteBefore Messages: 2 objects not found --- FAIL: TestCopyDeleteBefore (15.49s) === RUN TestSyncWithExclude run.go:181: Remote "S3 bucket rclone-test-qexesid7zumeguc1wefuwul1", Local "Local file system at /tmp/rclone2984337896", Modify Window "1ns" fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:299: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:299: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:306: Error Trace: fstest.go:306 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:910 Error: Should be true Test: TestSyncWithExclude Messages: listing wrong, want empty space (1), potato2 (60) got fstest.go:204: Not found "potato2" fstest.go:204: Not found "empty space" fstest.go:207: Error Trace: fstest.go:207 fstest.go:311 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:910 Error: Not equal: expected: 0 actual : 2 Test: TestSyncWithExclude Messages: 2 objects not found 2022/06/17 09:02:22 DEBUG : enormous: Excluded 2022/06/17 09:02:22 DEBUG : potato2: Excluded 2022/06/17 09:02:22 DEBUG : potato2: Excluded 2022/06/17 09:02:22 DEBUG : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1: Waiting for checks to finish 2022/06/17 09:02:23 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/06/17 09:02:23 DEBUG : empty space: Unchanged skipping 2022/06/17 09:02:23 DEBUG : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1: Waiting for transfers to finish 2022/06/17 09:02:23 DEBUG : Waiting for deletions to finish 2022/06/17 09:02:23 INFO : potato: Deleted 2022/06/17 09:02:23 INFO : There was nothing to transfer fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:299: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:299: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:306: Error Trace: fstest.go:306 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:921 Error: Should be true Test: TestSyncWithExclude Messages: listing wrong, want empty space (1), potato2 (60) got fstest.go:204: Not found "empty space" 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:921 Error: Not equal: expected: 0 actual : 2 Test: TestSyncWithExclude Messages: 2 objects not found 2022/06/17 09:02:30 DEBUG : enormous: Excluded 2022/06/17 09:02:30 DEBUG : potato2: Excluded 2022/06/17 09:02:30 DEBUG : potato2: Excluded 2022/06/17 09:02:30 DEBUG : Local file system at /tmp/rclone2984337896: Waiting for checks to finish 2022/06/17 09:02:30 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/06/17 09:02:30 DEBUG : empty space: Unchanged skipping 2022/06/17 09:02:30 DEBUG : Local file system at /tmp/rclone2984337896: Waiting for transfers to finish 2022/06/17 09:02:30 DEBUG : Waiting for deletions to finish 2022/06/17 09:02:30 INFO : There was nothing to transfer --- FAIL: TestSyncWithExclude (15.64s) === RUN TestSyncWithExcludeAndDeleteExcluded run.go:181: Remote "S3 bucket rclone-test-qexesid7zumeguc1wefuwul1", Local "Local file system at /tmp/rclone2984337896", 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:939 Error: Should be true Test: TestSyncWithExcludeAndDeleteExcluded Messages: listing wrong, want empty space (1), enormous (100), potato2 (60) got fstest.go:204: Not found "enormous" fstest.go:204: Not found "potato2" fstest.go:204: Not found "empty space" fstest.go:207: Error Trace: fstest.go:207 fstest.go:311 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:939 Error: Not equal: expected: 0 actual : 3 Test: TestSyncWithExcludeAndDeleteExcluded Messages: 3 objects not found 2022/06/17 09:02:39 DEBUG : enormous: Excluded 2022/06/17 09:02:39 DEBUG : potato2: Excluded 2022/06/17 09:02:39 DEBUG : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1: Waiting for checks to finish 2022/06/17 09:02:39 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/06/17 09:02:39 DEBUG : empty space: Unchanged skipping 2022/06/17 09:02:39 DEBUG : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1: Waiting for transfers to finish 2022/06/17 09:02:39 DEBUG : Waiting for deletions to finish 2022/06/17 09:02:39 INFO : enormous: Deleted 2022/06/17 09:02:39 INFO : potato2: Deleted 2022/06/17 09:02:39 INFO : There was nothing to transfer fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:299: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:299: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:306: Error Trace: fstest.go:306 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:951 Error: Should be true Test: TestSyncWithExcludeAndDeleteExcluded Messages: listing wrong, want empty space (1) got fstest.go:204: Not found "empty space" fstest.go:207: Error Trace: fstest.go:207 fstest.go:311 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:951 Error: Not equal: expected: 0 actual : 1 Test: TestSyncWithExcludeAndDeleteExcluded Messages: 1 objects not found 2022/06/17 09:02:47 DEBUG : Local file system at /tmp/rclone2984337896: Waiting for checks to finish 2022/06/17 09:02:47 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/06/17 09:02:47 DEBUG : empty space: Unchanged skipping 2022/06/17 09:02:47 DEBUG : Local file system at /tmp/rclone2984337896: Waiting for transfers to finish 2022/06/17 09:02:47 DEBUG : Waiting for deletions to finish 2022/06/17 09:02:47 INFO : potato2: Deleted 2022/06/17 09:02:47 INFO : enormous: Deleted 2022/06/17 09:02:47 INFO : There was nothing to transfer --- FAIL: TestSyncWithExcludeAndDeleteExcluded (16.57s) === RUN TestSyncWithUpdateOlder run.go:181: Remote "S3 bucket rclone-test-qexesid7zumeguc1wefuwul1", Local "Local file system at /tmp/rclone2984337896", 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:982 Error: Should be true Test: TestSyncWithUpdateOlder Messages: listing wrong, want four (8), one (3), three (5), two (3) got fstest.go:204: Not found "one" fstest.go:204: Not found "two" fstest.go:204: Not found "three" fstest.go:204: Not found "four" fstest.go:207: Error Trace: fstest.go:207 fstest.go:311 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:982 Error: Not equal: expected: 0 actual : 4 Test: TestSyncWithUpdateOlder Messages: 4 objects not found 2022/06/17 09:02:55 DEBUG : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1: Waiting for checks to finish 2022/06/17 09:02:55 DEBUG : four: Sizes differ (src 4 vs dst 8) 2022/06/17 09:02:55 DEBUG : four: Destination mod time is within 1s of source but files differ, transferring 2022/06/17 09:02:55 DEBUG : one: Destination is newer than source, skipping 2022/06/17 09:02:55 DEBUG : two: md5 = b8a9f715dbb64fd5c56e7783c6820a61 (Local file system at /tmp/rclone2984337896) 2022/06/17 09:02:55 DEBUG : two: md5 = 0f82d86afa0f5dc965c5c15aca58dcfb (S3 bucket rclone-test-qexesid7zumeguc1wefuwul1) 2022/06/17 09:02:55 DEBUG : two: md5 differ 2022/06/17 09:02:55 DEBUG : three: Sizes identical 2022/06/17 09:02:55 DEBUG : three: Destination mod time is within 1s of source and files identical, skipping 2022/06/17 09:02:55 DEBUG : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1: Waiting for transfers to finish 2022/06/17 09:02:56 DEBUG : four: md5 = 8cbad96aced40b3838dd9f07f6ef5772 OK 2022/06/17 09:02:56 INFO : four: Copied (replaced existing) 2022/06/17 09:02:56 DEBUG : five: md5 = 30056e1cab7a61d256fc8edd970d14f5 OK 2022/06/17 09:02:56 INFO : five: Copied (new) 2022/06/17 09:02:56 DEBUG : two: md5 = b8a9f715dbb64fd5c56e7783c6820a61 OK 2022/06/17 09:02:56 INFO : two: Copied (replaced existing) 2022/06/17 09:02:56 DEBUG : Waiting for deletions to finish 2022/06/17 09:02:56 INFO : empty space: Deleted fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:299: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:299: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:306: Error Trace: fstest.go:306 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:989 Error: Should be true Test: TestSyncWithUpdateOlder Messages: listing wrong, want five (4), four (4), one (3), three (5), two (3) got fstest.go:204: Not found "one" fstest.go:204: Not found "two" fstest.go:204: Not found "three" fstest.go:204: Not found "four" fstest.go:204: Not found "five" fstest.go:207: Error Trace: fstest.go:207 fstest.go:311 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:989 Error: Not equal: expected: 0 actual : 5 Test: TestSyncWithUpdateOlder Messages: 5 objects not found 2022/06/17 09:03:03 DEBUG : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1: Waiting for checks to finish 2022/06/17 09:03:03 DEBUG : four: md5 = 8cbad96aced40b3838dd9f07f6ef5772 OK 2022/06/17 09:03:03 DEBUG : four: Size and md5 of src and dst objects identical 2022/06/17 09:03:03 DEBUG : four: Destination mod time is within 1s of source and files identical, skipping 2022/06/17 09:03:03 DEBUG : five: md5 = 30056e1cab7a61d256fc8edd970d14f5 OK 2022/06/17 09:03:03 DEBUG : five: Size and md5 of src and dst objects identical 2022/06/17 09:03:03 DEBUG : five: Destination mod time is within 1s of source and files identical, skipping 2022/06/17 09:03:03 DEBUG : one: Destination is newer than source, skipping 2022/06/17 09:03:03 DEBUG : three: md5 = 35d6d33467aae9a2e3dccb4b6b027878 (Local file system at /tmp/rclone2984337896) 2022/06/17 09:03:03 DEBUG : three: md5 = 413af0de1f97a2155acf2b8b26ab36e2 (S3 bucket rclone-test-qexesid7zumeguc1wefuwul1) 2022/06/17 09:03:03 DEBUG : three: md5 differ 2022/06/17 09:03:03 DEBUG : three: Destination mod time is within 1s of source but files differ, transferring 2022/06/17 09:03:03 DEBUG : two: md5 = b8a9f715dbb64fd5c56e7783c6820a61 OK 2022/06/17 09:03:03 DEBUG : two: Size and md5 of src and dst objects identical 2022/06/17 09:03:03 DEBUG : two: Destination mod time is within 1s of source and files identical, skipping 2022/06/17 09:03:03 DEBUG : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1: Waiting for transfers to finish 2022/06/17 09:03:04 DEBUG : three: md5 = 35d6d33467aae9a2e3dccb4b6b027878 OK 2022/06/17 09:03:04 INFO : three: Copied (replaced existing) 2022/06/17 09:03:04 DEBUG : Waiting for deletions to finish fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:299: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:299: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:306: Error Trace: fstest.go:306 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1001 Error: Should be true Test: TestSyncWithUpdateOlder Messages: listing wrong, want five (4), four (4), one (3), three (5), two (3) got fstest.go:204: Not found "one" fstest.go:204: Not found "two" fstest.go:204: Not found "three" fstest.go:204: Not found "four" fstest.go:204: Not found "five" fstest.go:207: Error Trace: fstest.go:207 fstest.go:311 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1001 Error: Not equal: expected: 0 actual : 5 Test: TestSyncWithUpdateOlder Messages: 5 objects not found --- FAIL: TestSyncWithUpdateOlder (24.30s) === RUN TestSyncWithTrackRenames run.go:181: Remote "S3 bucket rclone-test-qexesid7zumeguc1wefuwul1", Local "Local file system at /tmp/rclone2984337896", Modify Window "1ns" sync_test.go:1075: Can track renames: true 2022/06/17 09:03:11 INFO : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1: Making map for --track-renames 2022/06/17 09:03:11 INFO : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1: Finished making map for --track-renames 2022/06/17 09:03:11 DEBUG : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1: Waiting for checks to finish 2022/06/17 09:03:11 DEBUG : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1: Waiting for renames to finish 2022/06/17 09:03:11 DEBUG : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1: Waiting for transfers to finish 2022/06/17 09:03:11 DEBUG : potato: md5 = 7f6fa9ddec9bfebda9510af0323fd696 OK 2022/06/17 09:03:11 INFO : potato: Copied (new) 2022/06/17 09:03:12 DEBUG : yam: md5 = 48dc443644c4b89556dc06834b46451d OK 2022/06/17 09:03:12 INFO : yam: Copied (new) 2022/06/17 09:03:12 DEBUG : Waiting for deletions to finish 2022/06/17 09:03:12 INFO : two: Deleted 2022/06/17 09:03:12 INFO : one: Deleted 2022/06/17 09:03:12 INFO : five: Deleted 2022/06/17 09:03:12 INFO : three: Deleted 2022/06/17 09:03:12 INFO : four: Deleted fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:299: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:299: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:306: Error Trace: fstest.go:306 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1083 Error: Should be true Test: TestSyncWithTrackRenames Messages: listing wrong, want potato (14), yam (11) got fstest.go:204: Not found "potato" fstest.go:204: Not found "yam" fstest.go:207: Error Trace: fstest.go:207 fstest.go:311 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1083 Error: Not equal: expected: 0 actual : 2 Test: TestSyncWithTrackRenames Messages: 2 objects not found 2022/06/17 09:03:19 INFO : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1: Making map for --track-renames 2022/06/17 09:03:19 INFO : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1: Finished making map for --track-renames 2022/06/17 09:03:19 DEBUG : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1: Waiting for checks to finish 2022/06/17 09:03:19 DEBUG : potato: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/06/17 09:03:19 DEBUG : potato: Unchanged skipping 2022/06/17 09:03:19 DEBUG : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1: Waiting for renames to finish 2022/06/17 09:03:20 DEBUG : yam: md5 = 48dc443644c4b89556dc06834b46451d OK 2022/06/17 09:03:20 INFO : yam: Copied (server-side copy) to: yaml 2022/06/17 09:03:20 INFO : yam: Deleted 2022/06/17 09:03:20 INFO : yaml: Renamed from "yam" 2022/06/17 09:03:20 DEBUG : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1: Waiting for transfers to finish 2022/06/17 09:03:20 DEBUG : Waiting for deletions to finish fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:299: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:299: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:306: Error Trace: fstest.go:306 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1092 Error: Should be true Test: TestSyncWithTrackRenames Messages: listing wrong, want potato (14), yaml (11) got fstest.go:204: Not found "potato" fstest.go:204: Not found "yaml" fstest.go:207: Error Trace: fstest.go:207 fstest.go:311 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1092 Error: Not equal: expected: 0 actual : 2 Test: TestSyncWithTrackRenames Messages: 2 objects not found --- FAIL: TestSyncWithTrackRenames (16.65s) === RUN TestSyncWithTrackRenamesStrategyModtime run.go:181: Remote "S3 bucket rclone-test-qexesid7zumeguc1wefuwul1", Local "Local file system at /tmp/rclone2984337896", Modify Window "1ns" sync_test.go:1144: Can track renames: true 2022/06/17 09:03:28 INFO : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1: Making map for --track-renames 2022/06/17 09:03:28 INFO : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1: Finished making map for --track-renames 2022/06/17 09:03:28 DEBUG : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1: Waiting for checks to finish 2022/06/17 09:03:28 DEBUG : potato: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/06/17 09:03:28 DEBUG : potato: Unchanged skipping 2022/06/17 09:03:28 DEBUG : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1: Waiting for renames to finish 2022/06/17 09:03:28 DEBUG : yaml: md5 = 48dc443644c4b89556dc06834b46451d OK 2022/06/17 09:03:28 INFO : yaml: Copied (server-side copy) to: yam 2022/06/17 09:03:28 INFO : yaml: Deleted 2022/06/17 09:03:28 INFO : yam: Renamed from "yaml" 2022/06/17 09:03:28 DEBUG : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1: Waiting for transfers to finish 2022/06/17 09:03:28 DEBUG : Waiting for deletions to finish fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:299: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:299: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:306: Error Trace: fstest.go:306 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1152 Error: Should be true Test: TestSyncWithTrackRenamesStrategyModtime Messages: listing wrong, want potato (14), yam (11) got fstest.go:204: Not found "yam" fstest.go:204: Not found "potato" fstest.go:207: Error Trace: fstest.go:207 fstest.go:311 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1152 Error: Not equal: expected: 0 actual : 2 Test: TestSyncWithTrackRenamesStrategyModtime Messages: 2 objects not found 2022/06/17 09:03:36 INFO : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1: Making map for --track-renames 2022/06/17 09:03:36 INFO : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1: Finished making map for --track-renames 2022/06/17 09:03:36 DEBUG : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1: Waiting for checks to finish 2022/06/17 09:03:36 DEBUG : potato: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/06/17 09:03:36 DEBUG : potato: Unchanged skipping 2022/06/17 09:03:36 DEBUG : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1: Waiting for renames to finish 2022/06/17 09:03:36 DEBUG : yam: md5 = 48dc443644c4b89556dc06834b46451d OK 2022/06/17 09:03:36 INFO : yam: Copied (server-side copy) to: yaml 2022/06/17 09:03:37 INFO : yam: Deleted 2022/06/17 09:03:37 INFO : yaml: Renamed from "yam" 2022/06/17 09:03:37 DEBUG : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1: Waiting for transfers to finish 2022/06/17 09:03:37 DEBUG : Waiting for deletions to finish fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:299: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:299: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:306: Error Trace: fstest.go:306 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1161 Error: Should be true Test: TestSyncWithTrackRenamesStrategyModtime Messages: listing wrong, want potato (14), yaml (11) got fstest.go:204: Not found "potato" fstest.go:204: Not found "yaml" fstest.go:207: Error Trace: fstest.go:207 fstest.go:311 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1161 Error: Not equal: expected: 0 actual : 2 Test: TestSyncWithTrackRenamesStrategyModtime Messages: 2 objects not found --- FAIL: TestSyncWithTrackRenamesStrategyModtime (16.31s) === RUN TestSyncWithTrackRenamesStrategyLeaf run.go:181: Remote "S3 bucket rclone-test-qexesid7zumeguc1wefuwul1", Local "Local file system at /tmp/rclone2984337896", Modify Window "1ns" sync_test.go:1180: Can track renames: true 2022/06/17 09:03:44 INFO : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1: Making map for --track-renames 2022/06/17 09:03:44 INFO : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1: Finished making map for --track-renames 2022/06/17 09:03:44 DEBUG : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1: Waiting for checks to finish 2022/06/17 09:03:44 DEBUG : potato: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/06/17 09:03:44 DEBUG : potato: Unchanged skipping 2022/06/17 09:03:44 DEBUG : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1: Waiting for renames to finish 2022/06/17 09:03:44 DEBUG : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1: Waiting for transfers to finish 2022/06/17 09:03:45 DEBUG : sub/yam: md5 = 48dc443644c4b89556dc06834b46451d OK 2022/06/17 09:03:45 INFO : sub/yam: Copied (new) 2022/06/17 09:03:45 DEBUG : Waiting for deletions to finish 2022/06/17 09:03:45 INFO : yaml: Deleted fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:299: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:299: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:306: Error Trace: fstest.go:306 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1188 Error: Should be true Test: TestSyncWithTrackRenamesStrategyLeaf Messages: listing wrong, want potato (14), sub/yam (11) got fstest.go:204: Not found "potato" fstest.go:204: Not found "sub/yam" fstest.go:207: Error Trace: fstest.go:207 fstest.go:311 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1188 Error: Not equal: expected: 0 actual : 2 Test: TestSyncWithTrackRenamesStrategyLeaf Messages: 2 objects not found 2022/06/17 09:03:52 DEBUG : potato: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/06/17 09:03:52 DEBUG : potato: Unchanged skipping 2022/06/17 09:03:52 INFO : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1: Making map for --track-renames 2022/06/17 09:03:52 INFO : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1: Finished making map for --track-renames 2022/06/17 09:03:52 DEBUG : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1: Waiting for checks to finish 2022/06/17 09:03:52 DEBUG : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1: Waiting for renames to finish 2022/06/17 09:03:53 DEBUG : sub/yam: md5 = 48dc443644c4b89556dc06834b46451d OK 2022/06/17 09:03:53 INFO : sub/yam: Copied (server-side copy) to: yam 2022/06/17 09:03:53 INFO : sub/yam: Deleted 2022/06/17 09:03:53 INFO : yam: Renamed from "sub/yam" 2022/06/17 09:03:53 DEBUG : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1: Waiting for transfers to finish 2022/06/17 09:03:53 DEBUG : Waiting for deletions to finish fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:299: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:299: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:306: Error Trace: fstest.go:306 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1197 Error: Should be true Test: TestSyncWithTrackRenamesStrategyLeaf Messages: listing wrong, want potato (14), yam (11) got fstest.go:204: Not found "potato" fstest.go:204: Not found "yam" fstest.go:207: Error Trace: fstest.go:207 fstest.go:311 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1197 Error: Not equal: expected: 0 actual : 2 Test: TestSyncWithTrackRenamesStrategyLeaf Messages: 2 objects not found --- FAIL: TestSyncWithTrackRenamesStrategyLeaf (16.34s) === RUN TestMoveWithDeleteEmptySrcDirs run.go:181: Remote "S3 bucket rclone-test-qexesid7zumeguc1wefuwul1", Local "Local file system at /tmp/rclone2984337896", Modify Window "1ns" 2022/06/17 09:04:00 DEBUG : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1: Waiting for checks to finish 2022/06/17 09:04:00 DEBUG : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1: Waiting for transfers to finish 2022/06/17 09:04:01 DEBUG : sub dir/hello world: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2022/06/17 09:04:01 INFO : sub dir/hello world: Copied (new) 2022/06/17 09:04:01 INFO : sub dir/hello world: Deleted 2022/06/17 09:04:02 DEBUG : nested/sub dir/file: md5 = 83d3784ea62518eafc60e98d84f877ad OK 2022/06/17 09:04:02 INFO : nested/sub dir/file: Copied (new) 2022/06/17 09:04:02 INFO : nested/sub dir/file: Deleted 2022/06/17 09:04:02 INFO : sub dir: Removing directory 2022/06/17 09:04:02 INFO : nested/sub dir: Removing directory 2022/06/17 09:04:02 INFO : nested: Removing directory 2022/06/17 09:04:02 DEBUG : Local file system at /tmp/rclone2984337896: deleted 3 directories fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:299: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:299: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:306: Error Trace: fstest.go:306 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1302 Error: Should be true Test: TestMoveWithDeleteEmptySrcDirs Messages: listing wrong, want nested/sub dir/file (6), sub dir/hello world (11) got fstest.go:204: Not found "sub dir/hello world" fstest.go:204: Not found "nested/sub dir/file" fstest.go:207: Error Trace: fstest.go:207 fstest.go:311 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1302 Error: Not equal: expected: 0 actual : 2 Test: TestMoveWithDeleteEmptySrcDirs Messages: 2 objects not found --- FAIL: TestMoveWithDeleteEmptySrcDirs (8.80s) === RUN TestMoveWithoutDeleteEmptySrcDirs run.go:181: Remote "S3 bucket rclone-test-qexesid7zumeguc1wefuwul1", Local "Local file system at /tmp/rclone2984337896", Modify Window "1ns" 2022/06/17 09:04:09 DEBUG : sub dir/hello world: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/06/17 09:04:09 DEBUG : sub dir/hello world: Unchanged skipping 2022/06/17 09:04:09 INFO : sub dir/hello world: Deleted 2022/06/17 09:04:09 DEBUG : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1: Waiting for checks to finish 2022/06/17 09:04:09 DEBUG : nested/sub dir/file: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/06/17 09:04:09 DEBUG : nested/sub dir/file: Unchanged skipping 2022/06/17 09:04:09 INFO : nested/sub dir/file: Deleted 2022/06/17 09:04:09 DEBUG : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1: 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:1325 Error: Should be true Test: TestMoveWithoutDeleteEmptySrcDirs Messages: listing wrong, want nested/sub dir/file (6), sub dir/hello world (11) got fstest.go:204: Not found "sub dir/hello world" fstest.go:204: Not found "nested/sub dir/file" fstest.go:207: Error Trace: fstest.go:207 fstest.go:311 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1325 Error: Not equal: expected: 0 actual : 2 Test: TestMoveWithoutDeleteEmptySrcDirs Messages: 2 objects not found --- FAIL: TestMoveWithoutDeleteEmptySrcDirs (7.73s) === RUN TestMoveWithIgnoreExisting run.go:181: Remote "S3 bucket rclone-test-qexesid7zumeguc1wefuwul1", Local "Local file system at /tmp/rclone2984337896", Modify Window "1ns" 2022/06/17 09:04:17 DEBUG : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1: Waiting for checks to finish 2022/06/17 09:04:17 DEBUG : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1: Waiting for transfers to finish 2022/06/17 09:04:17 DEBUG : existing: md5 = 8ee2027983915ec78acc45027d874316 OK 2022/06/17 09:04:17 INFO : existing: Copied (new) 2022/06/17 09:04:17 INFO : existing: Deleted 2022/06/17 09:04:17 DEBUG : existing-b: md5 = 006f87892f47ef9aa60fa5ed01a440fb OK 2022/06/17 09:04:17 INFO : existing-b: Copied (new) 2022/06/17 09:04:17 INFO : existing-b: Deleted fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:299: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:299: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:306: Error Trace: fstest.go:306 fstest.go:339 run.go:362 sync_test.go:1346 Error: Should be true Test: TestMoveWithIgnoreExisting Messages: listing wrong, want existing (6), existing-b (6) got fstest.go:204: Not found "existing" fstest.go:204: Not found "existing-b" fstest.go:207: Error Trace: fstest.go:207 fstest.go:311 fstest.go:339 run.go:362 sync_test.go:1346 Error: Not equal: expected: 0 actual : 2 Test: TestMoveWithIgnoreExisting Messages: 2 objects not found 2022/06/17 09:04:25 DEBUG : existing: Destination exists, skipping 2022/06/17 09:04:25 DEBUG : existing: Not removing source file as destination file exists and --ignore-existing is set 2022/06/17 09:04:25 DEBUG : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1: Waiting for checks to finish 2022/06/17 09:04:25 DEBUG : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1: Waiting for transfers to finish 2022/06/17 09:04:25 INFO : There was nothing to transfer fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:299: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:299: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:306: Error Trace: fstest.go:306 fstest.go:339 run.go:362 sync_test.go:1369 Error: Should be true Test: TestMoveWithIgnoreExisting Messages: listing wrong, want existing (6), existing-b (6) got fstest.go:204: Not found "existing" fstest.go:204: Not found "existing-b" fstest.go:207: Error Trace: fstest.go:207 fstest.go:311 fstest.go:339 run.go:362 sync_test.go:1369 Error: Not equal: expected: 0 actual : 2 Test: TestMoveWithIgnoreExisting Messages: 2 objects not found --- FAIL: TestMoveWithIgnoreExisting (15.05s) === RUN TestServerSideMove run.go:181: Remote "S3 bucket rclone-test-qexesid7zumeguc1wefuwul1", Local "Local file system at /tmp/rclone2984337896", Modify Window "1ns" 2022/06/17 09:04:32 DEBUG : Creating backend with remote "TestS3R2:rclone-test-bopinux4jexunez7kodexat4" 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:1230 sync_test.go:1384 Error: Should be true Test: TestServerSideMove Messages: listing wrong, want empty space (1), potato2 (60), potato3 (68) got fstest.go:204: Not found "empty space" fstest.go:204: Not found "potato2" fstest.go:204: Not found "potato3" fstest.go:207: Error Trace: fstest.go:207 fstest.go:311 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1230 sync_test.go:1384 Error: Not equal: expected: 0 actual : 3 Test: TestServerSideMove Messages: 3 objects not found sync_test.go:1232: Server side move (if possible) S3 bucket rclone-test-qexesid7zumeguc1wefuwul1 -> S3 bucket rclone-test-bopinux4jexunez7kodexat4 2022/06/17 09:04:41 INFO : S3 bucket rclone-test-bopinux4jexunez7kodexat4: Bucket "rclone-test-bopinux4jexunez7kodexat4" created with ACL "private" 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:357 sync_test.go:1237 sync_test.go:1384 Error: Should be true Test: TestServerSideMove Messages: listing wrong, want empty space (1), potato3 (60) got fstest.go:204: Not found "empty space" fstest.go:204: Not found "potato3" fstest.go:207: Error Trace: fstest.go:207 fstest.go:311 fstest.go:339 fstest.go:357 sync_test.go:1237 sync_test.go:1384 Error: Not equal: expected: 0 actual : 2 Test: TestServerSideMove Messages: 2 objects not found 2022/06/17 09:04:49 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2022/06/17 09:04:49 DEBUG : S3 bucket rclone-test-bopinux4jexunez7kodexat4: Waiting for checks to finish 2022/06/17 09:04:49 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/06/17 09:04:49 DEBUG : empty space: Unchanged skipping 2022/06/17 09:04:50 INFO : empty space: Deleted 2022/06/17 09:04:50 DEBUG : S3 bucket rclone-test-bopinux4jexunez7kodexat4: Waiting for transfers to finish 2022/06/17 09:04:50 DEBUG : existing-b: md5 = 006f87892f47ef9aa60fa5ed01a440fb OK 2022/06/17 09:04:50 INFO : existing-b: Copied (server-side copy) 2022/06/17 09:04:50 DEBUG : potato: md5 = 7f6fa9ddec9bfebda9510af0323fd696 OK 2022/06/17 09:04:50 INFO : potato: Copied (server-side copy) 2022/06/17 09:04:50 DEBUG : potato2: md5 = d6548b156ea68a4e003e786df99eee76 OK 2022/06/17 09:04:50 INFO : potato2: Copied (server-side copy) 2022/06/17 09:04:50 INFO : existing-b: Deleted 2022/06/17 09:04:50 INFO : potato2: Deleted 2022/06/17 09:04:50 DEBUG : existing: md5 = 8ee2027983915ec78acc45027d874316 OK 2022/06/17 09:04:50 INFO : existing: Copied (server-side copy) 2022/06/17 09:04:50 INFO : potato: Deleted 2022/06/17 09:04:50 INFO : existing: Deleted 2022/06/17 09:04:51 DEBUG : yam: md5 = 48dc443644c4b89556dc06834b46451d OK 2022/06/17 09:04:51 INFO : yam: Copied (server-side copy) 2022/06/17 09:04:51 DEBUG : potato3: md5 = b1a53993579b830006f2e1610415c32c OK 2022/06/17 09:04:51 INFO : potato3: Copied (server-side copy) 2022/06/17 09:04:51 DEBUG : nested/sub dir/file: md5 = 83d3784ea62518eafc60e98d84f877ad OK 2022/06/17 09:04:51 INFO : nested/sub dir/file: Copied (server-side copy) 2022/06/17 09:04:51 INFO : yam: Deleted 2022/06/17 09:04:51 INFO : potato3: Deleted 2022/06/17 09:04:51 INFO : nested/sub dir/file: Deleted 2022/06/17 09:04:51 DEBUG : sub dir/hello world: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2022/06/17 09:04:51 INFO : sub dir/hello world: Copied (server-side copy) 2022/06/17 09:04:51 INFO : sub dir/hello world: Deleted fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:299: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:299: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:306: Error Trace: fstest.go:306 fstest.go:339 fstest.go:357 sync_test.go:1254 sync_test.go:1384 Error: Should be true Test: TestServerSideMove Messages: listing wrong, want empty space (1), potato2 (60), potato3 (68) got fstest.go:204: Not found "empty space" fstest.go:204: Not found "potato2" fstest.go:204: Not found "potato3" fstest.go:207: Error Trace: fstest.go:207 fstest.go:311 fstest.go:339 fstest.go:357 sync_test.go:1254 sync_test.go:1384 Error: Not equal: expected: 0 actual : 3 Test: TestServerSideMove Messages: 3 objects not found 2022/06/17 09:04:59 DEBUG : Creating backend with remote "TestS3R2:rclone-test-xidonor5tesajex3yozuyat8" 2022/06/17 09:04:59 DEBUG : S3 bucket rclone-test-xidonor5tesajex3yozuyat8: Waiting for checks to finish 2022/06/17 09:04:59 DEBUG : S3 bucket rclone-test-xidonor5tesajex3yozuyat8: Waiting for transfers to finish 2022/06/17 09:04:59 INFO : S3 bucket rclone-test-xidonor5tesajex3yozuyat8: Bucket "rclone-test-xidonor5tesajex3yozuyat8" created with ACL "private" 2022/06/17 09:05:00 DEBUG : empty space: md5 = 336d5ebc5436534e61d16e63ddfca327 OK 2022/06/17 09:05:00 INFO : empty space: Copied (server-side copy) 2022/06/17 09:05:00 DEBUG : existing: md5 = 8ee2027983915ec78acc45027d874316 OK 2022/06/17 09:05:00 INFO : existing: Copied (server-side copy) 2022/06/17 09:05:00 INFO : empty space: Deleted 2022/06/17 09:05:00 DEBUG : existing-b: md5 = 006f87892f47ef9aa60fa5ed01a440fb OK 2022/06/17 09:05:00 INFO : existing-b: Copied (server-side copy) 2022/06/17 09:05:00 INFO : existing: Deleted 2022/06/17 09:05:00 INFO : existing-b: Deleted 2022/06/17 09:05:01 DEBUG : potato: md5 = 7f6fa9ddec9bfebda9510af0323fd696 OK 2022/06/17 09:05:01 INFO : potato: Copied (server-side copy) 2022/06/17 09:05:01 DEBUG : potato2: md5 = d6548b156ea68a4e003e786df99eee76 OK 2022/06/17 09:05:01 INFO : potato2: Copied (server-side copy) 2022/06/17 09:05:01 INFO : potato2: Deleted 2022/06/17 09:05:01 INFO : potato: Deleted 2022/06/17 09:05:01 DEBUG : yam: md5 = 48dc443644c4b89556dc06834b46451d OK 2022/06/17 09:05:01 INFO : yam: Copied (server-side copy) 2022/06/17 09:05:01 DEBUG : potato3: md5 = b1a53993579b830006f2e1610415c32c OK 2022/06/17 09:05:01 INFO : potato3: Copied (server-side copy) 2022/06/17 09:05:01 INFO : potato3: Deleted 2022/06/17 09:05:01 INFO : yam: Deleted 2022/06/17 09:05:01 DEBUG : sub dir/hello world: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2022/06/17 09:05:01 INFO : sub dir/hello world: Copied (server-side copy) 2022/06/17 09:05:01 INFO : sub dir/hello world: Deleted 2022/06/17 09:05:01 DEBUG : nested/sub dir/file: md5 = 83d3784ea62518eafc60e98d84f877ad OK 2022/06/17 09:05:01 INFO : nested/sub dir/file: Copied (server-side copy) 2022/06/17 09:05:02 INFO : nested/sub dir/file: Deleted fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:299: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:299: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:306: Error Trace: fstest.go:306 fstest.go:339 fstest.go:357 sync_test.go:1275 sync_test.go:1384 Error: Should be true Test: TestServerSideMove Messages: listing wrong, want empty space (1), potato2 (60), potato3 (68) got fstest.go:204: Not found "empty space" fstest.go:204: Not found "potato2" fstest.go:204: Not found "potato3" fstest.go:207: Error Trace: fstest.go:207 fstest.go:311 fstest.go:339 fstest.go:357 sync_test.go:1275 sync_test.go:1384 Error: Not equal: expected: 0 actual : 3 Test: TestServerSideMove Messages: 3 objects not found 2022/06/17 09:05:09 DEBUG : S3 bucket rclone-test-xidonor5tesajex3yozuyat8: Purge dir "" 2022/06/17 09:05:09 purge failed to rmdir "": BucketNotEmpty: The bucket you tried to delete (rclone-test-xidonor5tesajex3yozuyat8) is not empty (account 14aad7c9ed489151b51557e321b246cf). status code: 409, request id: , host id: 2022/06/17 09:05:09 DEBUG : S3 bucket rclone-test-bopinux4jexunez7kodexat4: Purge dir "" 2022/06/17 09:05:10 INFO : S3 bucket rclone-test-bopinux4jexunez7kodexat4: Bucket "rclone-test-bopinux4jexunez7kodexat4" deleted --- FAIL: TestServerSideMove (37.80s) === RUN TestServerSideMoveWithFilter run.go:181: Remote "S3 bucket rclone-test-qexesid7zumeguc1wefuwul1", Local "Local file system at /tmp/rclone2984337896", Modify Window "1ns" 2022/06/17 09:05:10 DEBUG : Creating backend with remote "TestS3R2:rclone-test-cokexac7ceragek2kericuf0" 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:1230 sync_test.go:1398 Error: Should be true Test: TestServerSideMoveWithFilter Messages: listing wrong, want empty space (1), potato2 (60), potato3 (68) got fstest.go:204: Not found "empty space" fstest.go:204: Not found "potato2" fstest.go:204: Not found "potato3" fstest.go:207: Error Trace: fstest.go:207 fstest.go:311 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1230 sync_test.go:1398 Error: Not equal: expected: 0 actual : 3 Test: TestServerSideMoveWithFilter Messages: 3 objects not found sync_test.go:1232: Server side move (if possible) S3 bucket rclone-test-qexesid7zumeguc1wefuwul1 -> S3 bucket rclone-test-cokexac7ceragek2kericuf0 2022/06/17 09:05:18 INFO : S3 bucket rclone-test-cokexac7ceragek2kericuf0: Bucket "rclone-test-cokexac7ceragek2kericuf0" created with ACL "private" 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:357 sync_test.go:1237 sync_test.go:1398 Error: Should be true Test: TestServerSideMoveWithFilter Messages: listing wrong, want empty space (1), potato3 (60) got fstest.go:204: Not found "empty space" fstest.go:204: Not found "potato3" fstest.go:207: Error Trace: fstest.go:207 fstest.go:311 fstest.go:339 fstest.go:357 sync_test.go:1237 sync_test.go:1398 Error: Not equal: expected: 0 actual : 2 Test: TestServerSideMoveWithFilter Messages: 2 objects not found 2022/06/17 09:05:26 DEBUG : empty space: Excluded 2022/06/17 09:05:27 DEBUG : empty space: Excluded 2022/06/17 09:05:27 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2022/06/17 09:05:27 DEBUG : S3 bucket rclone-test-cokexac7ceragek2kericuf0: Waiting for checks to finish 2022/06/17 09:05:27 DEBUG : S3 bucket rclone-test-cokexac7ceragek2kericuf0: Waiting for transfers to finish 2022/06/17 09:05:27 DEBUG : potato3: md5 = b1a53993579b830006f2e1610415c32c OK 2022/06/17 09:05:27 INFO : potato3: Copied (server-side copy) 2022/06/17 09:05:27 DEBUG : potato2: md5 = d6548b156ea68a4e003e786df99eee76 OK 2022/06/17 09:05:27 INFO : potato2: Copied (server-side copy) 2022/06/17 09:05:27 INFO : potato3: Deleted 2022/06/17 09:05:27 INFO : potato2: Deleted fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:299: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:299: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:306: Error Trace: fstest.go:306 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1245 sync_test.go:1398 Error: Should be true Test: TestServerSideMoveWithFilter Messages: listing wrong, want empty space (1) got fstest.go:204: Not found "empty space" fstest.go:207: Error Trace: fstest.go:207 fstest.go:311 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1245 sync_test.go:1398 Error: Not equal: expected: 0 actual : 1 Test: TestServerSideMoveWithFilter 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:357 sync_test.go:1254 sync_test.go:1398 Error: Should be true Test: TestServerSideMoveWithFilter Messages: listing wrong, want empty space (1), potato2 (60), potato3 (68) got fstest.go:204: Not found "potato3" fstest.go:204: Not found "empty space" fstest.go:204: Not found "potato2" fstest.go:207: Error Trace: fstest.go:207 fstest.go:311 fstest.go:339 fstest.go:357 sync_test.go:1254 sync_test.go:1398 Error: Not equal: expected: 0 actual : 3 Test: TestServerSideMoveWithFilter Messages: 3 objects not found 2022/06/17 09:05:42 DEBUG : Creating backend with remote "TestS3R2:rclone-test-cajelad2norayaz4rimixuj0" 2022/06/17 09:05:42 DEBUG : empty space: Excluded 2022/06/17 09:05:43 DEBUG : S3 bucket rclone-test-cajelad2norayaz4rimixuj0: Waiting for checks to finish 2022/06/17 09:05:43 DEBUG : S3 bucket rclone-test-cajelad2norayaz4rimixuj0: Waiting for transfers to finish 2022/06/17 09:05:43 INFO : S3 bucket rclone-test-cajelad2norayaz4rimixuj0: Bucket "rclone-test-cajelad2norayaz4rimixuj0" created with ACL "private" 2022/06/17 09:05:44 DEBUG : potato2: md5 = d6548b156ea68a4e003e786df99eee76 OK 2022/06/17 09:05:44 INFO : potato2: Copied (server-side copy) 2022/06/17 09:05:44 INFO : potato2: Deleted 2022/06/17 09:05:44 DEBUG : potato3: md5 = b1a53993579b830006f2e1610415c32c OK 2022/06/17 09:05:44 INFO : potato3: Copied (server-side copy) 2022/06/17 09:05:44 INFO : potato3: Deleted fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:299: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:299: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:306: Error Trace: fstest.go:306 fstest.go:339 fstest.go:357 sync_test.go:1272 sync_test.go:1398 Error: Should be true Test: TestServerSideMoveWithFilter Messages: listing wrong, want potato2 (60), potato3 (68) got fstest.go:204: Not found "potato3" fstest.go:204: Not found "potato2" fstest.go:207: Error Trace: fstest.go:207 fstest.go:311 fstest.go:339 fstest.go:357 sync_test.go:1272 sync_test.go:1398 Error: Not equal: expected: 0 actual : 2 Test: TestServerSideMoveWithFilter Messages: 2 objects not found fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:299: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:299: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:306: Error Trace: fstest.go:306 fstest.go:339 fstest.go:357 sync_test.go:1273 sync_test.go:1398 Error: Should be true Test: TestServerSideMoveWithFilter Messages: listing wrong, want empty space (1) got fstest.go:204: Not found "empty space" fstest.go:207: Error Trace: fstest.go:207 fstest.go:311 fstest.go:339 fstest.go:357 sync_test.go:1273 sync_test.go:1398 Error: Not equal: expected: 0 actual : 1 Test: TestServerSideMoveWithFilter Messages: 1 objects not found 2022/06/17 09:05:59 DEBUG : S3 bucket rclone-test-cajelad2norayaz4rimixuj0: Purge dir "" 2022/06/17 09:05:59 purge failed to rmdir "": BucketNotEmpty: The bucket you tried to delete (rclone-test-cajelad2norayaz4rimixuj0) is not empty (account 14aad7c9ed489151b51557e321b246cf). status code: 409, request id: , host id: 2022/06/17 09:05:59 DEBUG : S3 bucket rclone-test-cokexac7ceragek2kericuf0: Purge dir "" 2022/06/17 09:05:59 purge failed to rmdir "": BucketNotEmpty: The bucket you tried to delete (rclone-test-cokexac7ceragek2kericuf0) is not empty (account 14aad7c9ed489151b51557e321b246cf). status code: 409, request id: , host id: --- FAIL: TestServerSideMoveWithFilter (49.30s) === RUN TestServerSideMoveDeleteEmptySourceDirs run.go:181: Remote "S3 bucket rclone-test-qexesid7zumeguc1wefuwul1", Local "Local file system at /tmp/rclone2984337896", Modify Window "1ns" 2022/06/17 09:05:59 DEBUG : Creating backend with remote "TestS3R2:rclone-test-naboril0zavibeg4sitesex0" 2022/06/17 09:06:00 DEBUG : tomatoDir: Making directory fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:299: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:299: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:306: Error Trace: fstest.go:306 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1230 sync_test.go:1406 Error: Should be true Test: TestServerSideMoveDeleteEmptySourceDirs Messages: listing wrong, want empty space (1), potato2 (60), potato3 (68) got fstest.go:204: Not found "empty space" fstest.go:204: Not found "potato2" fstest.go:204: Not found "potato3" fstest.go:207: Error Trace: fstest.go:207 fstest.go:311 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1230 sync_test.go:1406 Error: Not equal: expected: 0 actual : 3 Test: TestServerSideMoveDeleteEmptySourceDirs Messages: 3 objects not found sync_test.go:1232: Server side move (if possible) S3 bucket rclone-test-qexesid7zumeguc1wefuwul1 -> S3 bucket rclone-test-naboril0zavibeg4sitesex0 2022/06/17 09:06:08 INFO : S3 bucket rclone-test-naboril0zavibeg4sitesex0: Bucket "rclone-test-naboril0zavibeg4sitesex0" created with ACL "private" 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:357 sync_test.go:1237 sync_test.go:1406 Error: Should be true Test: TestServerSideMoveDeleteEmptySourceDirs Messages: listing wrong, want empty space (1), potato3 (60) got fstest.go:204: Not found "empty space" fstest.go:204: Not found "potato3" fstest.go:207: Error Trace: fstest.go:207 fstest.go:311 fstest.go:339 fstest.go:357 sync_test.go:1237 sync_test.go:1406 Error: Not equal: expected: 0 actual : 2 Test: TestServerSideMoveDeleteEmptySourceDirs Messages: 2 objects not found 2022/06/17 09:06:16 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2022/06/17 09:06:16 DEBUG : S3 bucket rclone-test-naboril0zavibeg4sitesex0: Waiting for checks to finish 2022/06/17 09:06:17 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/06/17 09:06:17 DEBUG : empty space: Unchanged skipping 2022/06/17 09:06:17 INFO : empty space: Deleted 2022/06/17 09:06:17 DEBUG : S3 bucket rclone-test-naboril0zavibeg4sitesex0: Waiting for transfers to finish 2022/06/17 09:06:17 DEBUG : potato2: md5 = d6548b156ea68a4e003e786df99eee76 OK 2022/06/17 09:06:17 INFO : potato2: Copied (server-side copy) 2022/06/17 09:06:17 INFO : potato2: Deleted 2022/06/17 09:06:17 DEBUG : potato3: md5 = b1a53993579b830006f2e1610415c32c OK 2022/06/17 09:06:17 INFO : potato3: Copied (server-side copy) 2022/06/17 09:06:17 INFO : potato3: Deleted fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:299: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:299: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:306: Error Trace: fstest.go:306 fstest.go:339 fstest.go:357 sync_test.go:1254 sync_test.go:1406 Error: Should be true Test: TestServerSideMoveDeleteEmptySourceDirs Messages: listing wrong, want empty space (1), potato2 (60), potato3 (68) got fstest.go:204: Not found "empty space" fstest.go:204: Not found "potato2" fstest.go:204: Not found "potato3" fstest.go:207: Error Trace: fstest.go:207 fstest.go:311 fstest.go:339 fstest.go:357 sync_test.go:1254 sync_test.go:1406 Error: Not equal: expected: 0 actual : 3 Test: TestServerSideMoveDeleteEmptySourceDirs Messages: 3 objects not found 2022/06/17 09:06:25 DEBUG : Creating backend with remote "TestS3R2:rclone-test-gekalam3mixiwer2gaciyir7" 2022/06/17 09:06:25 DEBUG : tomatoDir: Making directory 2022/06/17 09:06:26 DEBUG : S3 bucket rclone-test-gekalam3mixiwer2gaciyir7: Waiting for checks to finish 2022/06/17 09:06:26 DEBUG : S3 bucket rclone-test-gekalam3mixiwer2gaciyir7: Waiting for transfers to finish 2022/06/17 09:06:26 INFO : S3 bucket rclone-test-gekalam3mixiwer2gaciyir7: Bucket "rclone-test-gekalam3mixiwer2gaciyir7" created with ACL "private" 2022/06/17 09:06:27 DEBUG : potato2: md5 = d6548b156ea68a4e003e786df99eee76 OK 2022/06/17 09:06:27 INFO : potato2: Copied (server-side copy) 2022/06/17 09:06:27 DEBUG : potato3: md5 = b1a53993579b830006f2e1610415c32c OK 2022/06/17 09:06:27 INFO : potato3: Copied (server-side copy) 2022/06/17 09:06:27 INFO : potato2: Deleted 2022/06/17 09:06:27 INFO : potato3: Deleted 2022/06/17 09:06:27 DEBUG : empty space: md5 = 336d5ebc5436534e61d16e63ddfca327 OK 2022/06/17 09:06:27 INFO : empty space: Copied (server-side copy) 2022/06/17 09:06:27 INFO : empty space: Deleted fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:299: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:299: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:306: Error Trace: fstest.go:306 fstest.go:339 fstest.go:357 sync_test.go:1275 sync_test.go:1406 Error: Should be true Test: TestServerSideMoveDeleteEmptySourceDirs Messages: listing wrong, want empty space (1), potato2 (60), potato3 (68) got fstest.go:204: Not found "empty space" fstest.go:204: Not found "potato2" fstest.go:204: Not found "potato3" fstest.go:207: Error Trace: fstest.go:207 fstest.go:311 fstest.go:339 fstest.go:357 sync_test.go:1275 sync_test.go:1406 Error: Not equal: expected: 0 actual : 3 Test: TestServerSideMoveDeleteEmptySourceDirs Messages: 3 objects not found 2022/06/17 09:06:34 DEBUG : S3 bucket rclone-test-gekalam3mixiwer2gaciyir7: Purge dir "" 2022/06/17 09:06:34 purge failed to rmdir "": BucketNotEmpty: The bucket you tried to delete (rclone-test-gekalam3mixiwer2gaciyir7) is not empty (account 14aad7c9ed489151b51557e321b246cf). status code: 409, request id: , host id: 2022/06/17 09:06:34 DEBUG : S3 bucket rclone-test-naboril0zavibeg4sitesex0: Purge dir "" 2022/06/17 09:06:35 INFO : S3 bucket rclone-test-naboril0zavibeg4sitesex0: Bucket "rclone-test-naboril0zavibeg4sitesex0" deleted --- FAIL: TestServerSideMoveDeleteEmptySourceDirs (35.96s) === RUN TestServerSideMoveOverlap run.go:181: Remote "S3 bucket rclone-test-qexesid7zumeguc1wefuwul1", Local "Local file system at /tmp/rclone2984337896", Modify Window "1ns" 2022/06/17 09:06:35 DEBUG : Creating backend with remote "TestS3R2:rclone-test-qexesid7zumeguc1wefuwul1/rclone-move-test" 2022/06/17 09:11:35 DEBUG : pacer: low level retry 1/1 (error RequestError: send request failed caused by: Put "https://14aad7c9ed489151b51557e321b246cf.r2.cloudflarestorage.com/rclone-test-qexesid7zumeguc1wefuwul1/potato2": read tcp [2a01:4f9:c011:405e::1]:34116->[2606:4700::6812:85a]:443: i/o timeout) 2022/06/17 09:11:35 DEBUG : pacer: Rate limited, increasing sleep to 10ms run.go:283: Retry Put of "potato2" to S3 bucket rclone-test-qexesid7zumeguc1wefuwul1: 1/10 (RequestError: send request failed caused by: Put "https://14aad7c9ed489151b51557e321b246cf.r2.cloudflarestorage.com/rclone-test-qexesid7zumeguc1wefuwul1/potato2": read tcp [2a01:4f9:c011:405e::1]:34116->[2606:4700::6812:85a]:443: i/o timeout) 2022/06/17 09:11:38 DEBUG : pacer: Reducing sleep to 0s 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:1424 Error: Should be true Test: TestServerSideMoveOverlap Messages: listing wrong, want potato2 (60) 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:1424 Error: Not equal: expected: 0 actual : 1 Test: TestServerSideMoveOverlap Messages: 1 objects not found --- FAIL: TestServerSideMoveOverlap (310.39s) === RUN TestSyncCompareDest run.go:181: Remote "S3 bucket rclone-test-qexesid7zumeguc1wefuwul1", Local "Local file system at /tmp/rclone2984337896", Modify Window "1ns" 2022/06/17 09:11:45 DEBUG : Creating backend with remote "TestS3R2:rclone-test-qexesid7zumeguc1wefuwul1/dst" 2022/06/17 09:11:46 DEBUG : Creating backend with remote "TestS3R2:rclone-test-qexesid7zumeguc1wefuwul1/CompareDest" 2022/06/17 09:11:46 DEBUG : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1 path dst: Waiting for checks to finish 2022/06/17 09:11:46 DEBUG : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1 path dst: Waiting for transfers to finish 2022/06/17 09:11:47 DEBUG : one: md5 = f97c5d29941bfb1b2fdab0874906ab82 OK 2022/06/17 09:11:47 INFO : one: Copied (new) 2022/06/17 09:11:47 DEBUG : Waiting for deletions to finish fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:299: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:299: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:306: Error Trace: fstest.go:306 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1542 Error: Should be true Test: TestSyncCompareDest Messages: listing wrong, want dst/one (3) got fstest.go:204: Not found "dst/one" fstest.go:207: Error Trace: fstest.go:207 fstest.go:311 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1542 Error: Not equal: expected: 0 actual : 1 Test: TestSyncCompareDest 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:1546 Error: Should be true Test: TestSyncCompareDest Messages: listing wrong, want dst/one (3) got fstest.go:204: Not found "dst/one" fstest.go:207: Error Trace: fstest.go:207 fstest.go:311 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1546 Error: Not equal: expected: 0 actual : 1 Test: TestSyncCompareDest Messages: 1 objects not found 2022/06/17 09:12:01 DEBUG : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1 path dst: Waiting for checks to finish 2022/06/17 09:12:01 DEBUG : one: Sizes differ (src 5 vs dst 3) 2022/06/17 09:12:01 DEBUG : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1 path dst: Waiting for transfers to finish 2022/06/17 09:12:02 DEBUG : one: md5 = 07912d142f5d63ee918b34796b5a2432 OK 2022/06/17 09:12:02 INFO : one: Copied (replaced existing) 2022/06/17 09:12:02 DEBUG : Waiting for deletions to finish fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:299: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:299: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:306: Error Trace: fstest.go:306 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1556 Error: Should be true Test: TestSyncCompareDest Messages: listing wrong, want dst/one (5) got fstest.go:204: Not found "dst/one" fstest.go:207: Error Trace: fstest.go:207 fstest.go:311 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1556 Error: Not equal: expected: 0 actual : 1 Test: TestSyncCompareDest 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:1562 Error: Should be true Test: TestSyncCompareDest Messages: listing wrong, want CompareDest/one (5), dst/one (3) got fstest.go:204: Not found "CompareDest/one" fstest.go:204: Not found "dst/one" fstest.go:207: Error Trace: fstest.go:207 fstest.go:311 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1562 Error: Not equal: expected: 0 actual : 2 Test: TestSyncCompareDest Messages: 2 objects not found 2022/06/17 09:12:18 DEBUG : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1 path dst: Waiting for checks to finish 2022/06/17 09:12:18 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/06/17 09:12:18 DEBUG : one: Destination found in --compare-dest, skipping 2022/06/17 09:12:18 DEBUG : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1 path dst: Waiting for transfers to finish 2022/06/17 09:12:18 DEBUG : Waiting for deletions to finish 2022/06/17 09:12:18 INFO : There was nothing to transfer fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:299: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:299: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:306: Error Trace: fstest.go:306 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1569 Error: Should be true Test: TestSyncCompareDest Messages: listing wrong, want CompareDest/one (5), dst/one (3) got fstest.go:204: Not found "dst/one" fstest.go:204: Not found "CompareDest/one" fstest.go:207: Error Trace: fstest.go:207 fstest.go:311 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1569 Error: Not equal: expected: 0 actual : 2 Test: TestSyncCompareDest Messages: 2 objects not found fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:299: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:299: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:306: Error Trace: fstest.go:306 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1574 Error: Should be true Test: TestSyncCompareDest Messages: listing wrong, want CompareDest/one (5), CompareDest/two (3), dst/one (3) got fstest.go:204: Not found "CompareDest/one" fstest.go:204: Not found "dst/one" fstest.go:204: Not found "CompareDest/two" fstest.go:207: Error Trace: fstest.go:207 fstest.go:311 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1574 Error: Not equal: expected: 0 actual : 3 Test: TestSyncCompareDest Messages: 3 objects not found 2022/06/17 09:12:32 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/06/17 09:12:32 DEBUG : two: Destination found in --compare-dest, skipping 2022/06/17 09:12:32 DEBUG : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1 path dst: Waiting for checks to finish 2022/06/17 09:12:33 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/06/17 09:12:33 DEBUG : one: Destination found in --compare-dest, skipping 2022/06/17 09:12:33 DEBUG : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1 path dst: Waiting for transfers to finish 2022/06/17 09:12:33 DEBUG : Waiting for deletions to finish 2022/06/17 09:12:33 INFO : There was nothing to transfer fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:299: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:299: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:306: Error Trace: fstest.go:306 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1581 Error: Should be true Test: TestSyncCompareDest Messages: listing wrong, want CompareDest/one (5), CompareDest/two (3), dst/one (3) got fstest.go:204: Not found "CompareDest/one" fstest.go:204: Not found "dst/one" fstest.go:204: Not found "CompareDest/two" fstest.go:207: Error Trace: fstest.go:207 fstest.go:311 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1581 Error: Not equal: expected: 0 actual : 3 Test: TestSyncCompareDest Messages: 3 objects not found 2022/06/17 09:12:40 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/06/17 09:12:40 DEBUG : two: Destination found in --compare-dest, skipping 2022/06/17 09:12:40 DEBUG : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1 path dst: Waiting for checks to finish 2022/06/17 09:12:40 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/06/17 09:12:40 DEBUG : one: Destination found in --compare-dest, skipping 2022/06/17 09:12:40 DEBUG : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1 path dst: Waiting for transfers to finish 2022/06/17 09:12:40 DEBUG : Waiting for deletions to finish 2022/06/17 09:12:40 INFO : There was nothing to transfer fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:299: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:299: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:306: Error Trace: fstest.go:306 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1588 Error: Should be true Test: TestSyncCompareDest Messages: listing wrong, want CompareDest/one (5), CompareDest/two (3), dst/one (3) got fstest.go:204: Not found "CompareDest/one" fstest.go:204: Not found "dst/one" fstest.go:204: Not found "CompareDest/two" fstest.go:207: Error Trace: fstest.go:207 fstest.go:311 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1588 Error: Not equal: expected: 0 actual : 3 Test: TestSyncCompareDest Messages: 3 objects not found 2022/06/17 09:12:48 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/06/17 09:12:48 DEBUG : two: md5 = b8a9f715dbb64fd5c56e7783c6820a61 OK 2022/06/17 09:12:48 DEBUG : two: Destination found in --compare-dest, skipping 2022/06/17 09:12:48 DEBUG : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1 path dst: Waiting for checks to finish 2022/06/17 09:12:48 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/06/17 09:12:48 DEBUG : one: Destination found in --compare-dest, skipping 2022/06/17 09:12:48 DEBUG : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1 path dst: Waiting for transfers to finish 2022/06/17 09:12:48 DEBUG : Waiting for deletions to finish 2022/06/17 09:12:48 INFO : There was nothing to transfer fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:299: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:299: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:306: Error Trace: fstest.go:306 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1615 Error: Should be true Test: TestSyncCompareDest Messages: listing wrong, want CompareDest/one (5), CompareDest/two (3), dst/one (3) got fstest.go:204: Not found "CompareDest/one" fstest.go:204: Not found "dst/one" fstest.go:204: Not found "CompareDest/two" fstest.go:207: Error Trace: fstest.go:207 fstest.go:311 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1615 Error: Not equal: expected: 0 actual : 3 Test: TestSyncCompareDest Messages: 3 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:1622 Error: Should be true Test: TestSyncCompareDest Messages: listing wrong, want CompareDest/one (5), CompareDest/two (3), dst/one (3) got fstest.go:204: Not found "dst/one" fstest.go:204: Not found "CompareDest/two" fstest.go:204: Not found "CompareDest/one" fstest.go:207: Error Trace: fstest.go:207 fstest.go:311 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1622 Error: Not equal: expected: 0 actual : 3 Test: TestSyncCompareDest Messages: 3 objects not found 2022/06/17 09:13:02 DEBUG : two: Sizes differ (src 5 vs dst 3) 2022/06/17 09:13:02 DEBUG : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1 path dst: Waiting for checks to finish 2022/06/17 09:13:02 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/06/17 09:13:02 DEBUG : one: Destination found in --compare-dest, skipping 2022/06/17 09:13:02 DEBUG : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1 path dst: Waiting for transfers to finish 2022/06/17 09:13:03 DEBUG : two: md5 = 2379e4ce8c3380e996ab0509f17069ad OK 2022/06/17 09:13:03 INFO : two: Copied (new) 2022/06/17 09:13:03 DEBUG : Waiting for deletions to finish fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:299: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:299: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:306: Error Trace: fstest.go:306 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1632 Error: Should be true Test: TestSyncCompareDest Messages: listing wrong, want CompareDest/one (5), CompareDest/two (3), dst/one (3), dst/two (5) got fstest.go:204: Not found "CompareDest/one" fstest.go:204: Not found "dst/one" fstest.go:204: Not found "CompareDest/two" fstest.go:204: Not found "dst/two" fstest.go:207: Error Trace: fstest.go:207 fstest.go:311 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1632 Error: Not equal: expected: 0 actual : 4 Test: TestSyncCompareDest Messages: 4 objects not found --- FAIL: TestSyncCompareDest (85.04s) === RUN TestSyncMultipleCompareDest run.go:181: Remote "S3 bucket rclone-test-qexesid7zumeguc1wefuwul1", Local "Local file system at /tmp/rclone2984337896", 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:1653 Error: Should be true Test: TestSyncMultipleCompareDest Messages: listing wrong, want pre-dest1/1 (1), pre-dest2/2 (1) got fstest.go:204: Not found "pre-dest1/1" fstest.go:204: Not found "pre-dest2/2" fstest.go:207: Error Trace: fstest.go:207 fstest.go:311 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1653 Error: Not equal: expected: 0 actual : 2 Test: TestSyncMultipleCompareDest Messages: 2 objects not found 2022/06/17 09:13:18 DEBUG : Creating backend with remote "TestS3R2:rclone-test-qexesid7zumeguc1wefuwul1/dest" 2022/06/17 09:13:18 DEBUG : Creating backend with remote "TestS3R2:rclone-test-qexesid7zumeguc1wefuwul1/pre-dest1" 2022/06/17 09:13:19 DEBUG : Creating backend with remote "TestS3R2:rclone-test-qexesid7zumeguc1wefuwul1/pre-dest2" 2022/06/17 09:13:19 DEBUG : 1: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/06/17 09:13:19 DEBUG : 1: Destination found in --compare-dest, skipping 2022/06/17 09:13:19 DEBUG : 2: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/06/17 09:13:19 DEBUG : 2: Destination found in --compare-dest, skipping 2022/06/17 09:13:19 DEBUG : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1 path dest: Waiting for checks to finish 2022/06/17 09:13:19 DEBUG : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1 path dest: Waiting for transfers to finish 2022/06/17 09:13:20 DEBUG : 3: md5 = eccbc87e4b5ce2fe28308fd9f2a7baf3 OK 2022/06/17 09:13:20 INFO : 3: Copied (new) 2022/06/17 09:13:20 DEBUG : Waiting for deletions to finish fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:299: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:299: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:306: Error Trace: fstest.go:306 fstest.go:339 fstest.go:351 sync_test.go:1663 Error: Should be true Test: TestSyncMultipleCompareDest Messages: listing wrong, want 3 (1) got fstest.go:204: Not found "3" fstest.go:207: Error Trace: fstest.go:207 fstest.go:311 fstest.go:339 fstest.go:351 sync_test.go:1663 Error: Not equal: expected: 0 actual : 1 Test: TestSyncMultipleCompareDest 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:1664 Error: Should be true Test: TestSyncMultipleCompareDest Messages: listing wrong, want dest/3 (1), pre-dest1/1 (1), pre-dest2/2 (1) got fstest.go:204: Not found "pre-dest1/1" fstest.go:204: Not found "pre-dest2/2" fstest.go:204: Not found "dest/3" fstest.go:207: Error Trace: fstest.go:207 fstest.go:311 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1664 Error: Not equal: expected: 0 actual : 3 Test: TestSyncMultipleCompareDest Messages: 3 objects not found --- FAIL: TestSyncMultipleCompareDest (23.68s) === RUN TestSyncCopyDest run.go:181: Remote "S3 bucket rclone-test-qexesid7zumeguc1wefuwul1", Local "Local file system at /tmp/rclone2984337896", Modify Window "1ns" 2022/06/17 09:13:34 DEBUG : Creating backend with remote "TestS3R2:rclone-test-qexesid7zumeguc1wefuwul1/dst" 2022/06/17 09:13:35 DEBUG : Creating backend with remote "TestS3R2:rclone-test-qexesid7zumeguc1wefuwul1/CopyDest" 2022/06/17 09:13:36 DEBUG : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1 path dst: Waiting for checks to finish 2022/06/17 09:13:36 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/06/17 09:13:36 DEBUG : one: Unchanged skipping 2022/06/17 09:13:36 DEBUG : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1 path dst: Waiting for transfers to finish 2022/06/17 09:13:36 DEBUG : Waiting for deletions to finish 2022/06/17 09:13:36 INFO : two: Deleted 2022/06/17 09:13:36 INFO : There was nothing to transfer fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:299: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:299: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:306: Error Trace: fstest.go:306 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1694 Error: Should be true Test: TestSyncCopyDest Messages: listing wrong, want dst/one (3) got fstest.go:204: Not found "dst/one" fstest.go:207: Error Trace: fstest.go:207 fstest.go:311 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1694 Error: Not equal: expected: 0 actual : 1 Test: TestSyncCopyDest 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:1698 Error: Should be true Test: TestSyncCopyDest Messages: listing wrong, want dst/one (3) got fstest.go:204: Not found "dst/one" fstest.go:207: Error Trace: fstest.go:207 fstest.go:311 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1698 Error: Not equal: expected: 0 actual : 1 Test: TestSyncCopyDest Messages: 1 objects not found 2022/06/17 09:13:50 DEBUG : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1 path dst: Waiting for checks to finish 2022/06/17 09:13:51 DEBUG : one: Sizes differ (src 5 vs dst 3) 2022/06/17 09:13:51 DEBUG : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1 path dst: Waiting for transfers to finish 2022/06/17 09:13:51 DEBUG : one: md5 = 07912d142f5d63ee918b34796b5a2432 OK 2022/06/17 09:13:51 INFO : one: Copied (replaced existing) 2022/06/17 09:13:51 DEBUG : Waiting for deletions to finish fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:299: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:299: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:306: Error Trace: fstest.go:306 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1708 Error: Should be true Test: TestSyncCopyDest Messages: listing wrong, want dst/one (5) got fstest.go:204: Not found "dst/one" fstest.go:207: Error Trace: fstest.go:207 fstest.go:311 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1708 Error: Not equal: expected: 0 actual : 1 Test: TestSyncCopyDest 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:1717 Error: Should be true Test: TestSyncCopyDest Messages: listing wrong, want CopyDest/one (5), dst/one (3) got fstest.go:204: Not found "CopyDest/one" fstest.go:204: Not found "dst/one" fstest.go:207: Error Trace: fstest.go:207 fstest.go:311 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1717 Error: Not equal: expected: 0 actual : 2 Test: TestSyncCopyDest Messages: 2 objects not found 2022/06/17 09:14:06 DEBUG : Creating backend with remote "TestS3R2:rclone-test-qexesid7zumeguc1wefuwul1/BackupDir" 2022/06/17 09:14:07 DEBUG : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1 path dst: Waiting for checks to finish 2022/06/17 09:14:08 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/06/17 09:14:08 DEBUG : one: Sizes differ (src 5 vs dst 3) 2022/06/17 09:14:09 DEBUG : one: md5 = f97c5d29941bfb1b2fdab0874906ab82 OK 2022/06/17 09:14:09 INFO : one: Copied (server-side copy) 2022/06/17 09:14:09 INFO : one: Deleted 2022/06/17 09:14:09 DEBUG : one: md5 = 07912d142f5d63ee918b34796b5a2432 OK 2022/06/17 09:14:09 INFO : one: Copied (server-side copy) 2022/06/17 09:14:09 DEBUG : one: Destination found in --copy-dest, using server-side copy 2022/06/17 09:14:09 DEBUG : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1 path dst: Waiting for transfers to finish 2022/06/17 09:14:09 DEBUG : Waiting for deletions to finish fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:299: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:299: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:306: Error Trace: fstest.go:306 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1728 Error: Should be true Test: TestSyncCopyDest Messages: listing wrong, want BackupDir/one (3), CopyDest/one (5), dst/one (5) got fstest.go:204: Not found "CopyDest/one" fstest.go:204: Not found "dst/one" fstest.go:204: Not found "BackupDir/one" fstest.go:207: Error Trace: fstest.go:207 fstest.go:311 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1728 Error: Not equal: expected: 0 actual : 3 Test: TestSyncCopyDest Messages: 3 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:1734 Error: Should be true Test: TestSyncCopyDest Messages: listing wrong, want BackupDir/one (3), CopyDest/one (5), CopyDest/two (3), dst/one (5) got fstest.go:204: Not found "CopyDest/two" fstest.go:204: Not found "CopyDest/one" fstest.go:204: Not found "dst/one" fstest.go:204: Not found "BackupDir/one" fstest.go:207: Error Trace: fstest.go:207 fstest.go:311 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1734 Error: Not equal: expected: 0 actual : 4 Test: TestSyncCopyDest Messages: 4 objects not found 2022/06/17 09:14:24 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/06/17 09:14:25 DEBUG : two: md5 = b8a9f715dbb64fd5c56e7783c6820a61 OK 2022/06/17 09:14:25 INFO : two: Copied (server-side copy) 2022/06/17 09:14:25 DEBUG : two: Destination found in --copy-dest, using server-side copy 2022/06/17 09:14:25 DEBUG : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1 path dst: Waiting for checks to finish 2022/06/17 09:14:25 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/06/17 09:14:25 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/06/17 09:14:25 DEBUG : one: Unchanged skipping 2022/06/17 09:14:25 DEBUG : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1 path dst: Waiting for transfers to finish 2022/06/17 09:14:25 DEBUG : Waiting for deletions to finish fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:299: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:299: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:306: Error Trace: fstest.go:306 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1744 Error: Should be true Test: TestSyncCopyDest Messages: listing wrong, want BackupDir/one (3), CopyDest/one (5), CopyDest/two (3), dst/one (5), dst/two (3) got fstest.go:204: Not found "CopyDest/one" fstest.go:204: Not found "dst/one" fstest.go:204: Not found "BackupDir/one" fstest.go:204: Not found "CopyDest/two" fstest.go:204: Not found "dst/two" fstest.go:207: Error Trace: fstest.go:207 fstest.go:311 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1744 Error: Not equal: expected: 0 actual : 5 Test: TestSyncCopyDest Messages: 5 objects not found 2022/06/17 09:14:32 DEBUG : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1 path dst: Waiting for checks to finish 2022/06/17 09:14:33 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/06/17 09:14:33 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/06/17 09:14:33 DEBUG : two: Unchanged skipping 2022/06/17 09:14:33 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/06/17 09:14:33 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/06/17 09:14:33 DEBUG : one: Unchanged skipping 2022/06/17 09:14:33 DEBUG : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1 path dst: Waiting for transfers to finish 2022/06/17 09:14:33 DEBUG : Waiting for deletions to finish 2022/06/17 09:14:33 INFO : There was nothing to transfer fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:299: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:299: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:306: Error Trace: fstest.go:306 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1751 Error: Should be true Test: TestSyncCopyDest Messages: listing wrong, want BackupDir/one (3), CopyDest/one (5), CopyDest/two (3), dst/one (5), dst/two (3) got fstest.go:204: Not found "CopyDest/one" fstest.go:204: Not found "dst/one" fstest.go:204: Not found "BackupDir/one" fstest.go:204: Not found "CopyDest/two" fstest.go:204: Not found "dst/two" fstest.go:207: Error Trace: fstest.go:207 fstest.go:311 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1751 Error: Not equal: expected: 0 actual : 5 Test: TestSyncCopyDest Messages: 5 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:1756 Error: Should be true Test: TestSyncCopyDest Messages: listing wrong, want BackupDir/one (3), CopyDest/one (5), CopyDest/three (5), CopyDest/two (3), dst/one (5), dst/two (3) got fstest.go:204: Not found "dst/one" fstest.go:204: Not found "BackupDir/one" fstest.go:204: Not found "CopyDest/two" fstest.go:204: Not found "dst/two" fstest.go:204: Not found "CopyDest/three" fstest.go:204: Not found "CopyDest/one" fstest.go:207: Error Trace: fstest.go:207 fstest.go:311 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1756 Error: Not equal: expected: 0 actual : 6 Test: TestSyncCopyDest Messages: 6 objects not found 2022/06/17 09:14:48 DEBUG : three: Sizes differ (src 7 vs dst 5) 2022/06/17 09:14:48 DEBUG : three: Destination not found in --copy-dest 2022/06/17 09:14:48 DEBUG : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1 path dst: Waiting for checks to finish 2022/06/17 09:14:48 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/06/17 09:14:48 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/06/17 09:14:48 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/06/17 09:14:48 DEBUG : two: Unchanged skipping 2022/06/17 09:14:48 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/06/17 09:14:48 DEBUG : one: Unchanged skipping 2022/06/17 09:14:48 DEBUG : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1 path dst: Waiting for transfers to finish 2022/06/17 09:14:48 DEBUG : three: md5 = 1bccb9dccb3e9f6a3f9d2a8bdb54b7f5 OK 2022/06/17 09:14:48 INFO : three: Copied (new) 2022/06/17 09:14:48 DEBUG : Waiting for deletions to finish fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:299: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:299: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:306: Error Trace: fstest.go:306 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1766 Error: Should be true Test: TestSyncCopyDest Messages: listing wrong, want BackupDir/one (3), CopyDest/one (5), CopyDest/three (5), CopyDest/two (3), dst/one (5), dst/three (7), dst/two (3) got fstest.go:204: Not found "CopyDest/one" fstest.go:204: Not found "dst/one" fstest.go:204: Not found "BackupDir/one" fstest.go:204: Not found "CopyDest/two" fstest.go:204: Not found "dst/two" fstest.go:204: Not found "CopyDest/three" fstest.go:204: Not found "dst/three" fstest.go:207: Error Trace: fstest.go:207 fstest.go:311 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1766 Error: Not equal: expected: 0 actual : 7 Test: TestSyncCopyDest Messages: 7 objects not found --- FAIL: TestSyncCopyDest (81.67s) === RUN TestSyncBackupDir run.go:181: Remote "S3 bucket rclone-test-qexesid7zumeguc1wefuwul1", Local "Local file system at /tmp/rclone2984337896", 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:1806 sync_test.go:1855 Error: Should be true Test: TestSyncBackupDir Messages: listing wrong, want dst/one (3), dst/three.txt (5), dst/two (3) got fstest.go:204: Not found "dst/three.txt" fstest.go:204: Not found "dst/one" fstest.go:204: Not found "dst/two" fstest.go:207: Error Trace: fstest.go:207 fstest.go:311 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1806 sync_test.go:1855 Error: Not equal: expected: 0 actual : 3 Test: TestSyncBackupDir Messages: 3 objects not found 2022/06/17 09:15:04 DEBUG : Creating backend with remote "TestS3R2:rclone-test-qexesid7zumeguc1wefuwul1/dst" 2022/06/17 09:15:04 DEBUG : Creating backend with remote "TestS3R2:rclone-test-qexesid7zumeguc1wefuwul1/backup" 2022/06/17 09:15:04 DEBUG : one: Sizes differ (src 4 vs dst 3) 2022/06/17 09:15:04 DEBUG : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1 path dst: Waiting for checks to finish 2022/06/17 09:15:04 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/06/17 09:15:04 DEBUG : two: Unchanged skipping 2022/06/17 09:15:05 DEBUG : one: md5 = f97c5d29941bfb1b2fdab0874906ab82 OK 2022/06/17 09:15:05 INFO : one: Copied (server-side copy) 2022/06/17 09:15:05 INFO : one: Deleted 2022/06/17 09:15:05 DEBUG : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1 path dst: Waiting for transfers to finish 2022/06/17 09:15:05 DEBUG : one: md5 = c7957179c41f69d44f217a108c7915d8 OK 2022/06/17 09:15:05 INFO : one: Copied (new) 2022/06/17 09:15:05 DEBUG : Waiting for deletions to finish 2022/06/17 09:15:06 DEBUG : three: md5 = 1bccb9dccb3e9f6a3f9d2a8bdb54b7f5 OK 2022/06/17 09:15:06 INFO : three: Copied (server-side copy) 2022/06/17 09:15:06 DEBUG : three.txt: md5 = 35d6d33467aae9a2e3dccb4b6b027878 OK 2022/06/17 09:15:06 INFO : three.txt: Copied (server-side copy) 2022/06/17 09:15:06 INFO : three: Deleted 2022/06/17 09:15:06 INFO : three: Moved into backup dir 2022/06/17 09:15:06 INFO : three.txt: Deleted 2022/06/17 09:15:06 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:1827 sync_test.go:1855 Error: Should be true Test: TestSyncBackupDir Messages: listing wrong, want backup/one (3), backup/three.txt (5), dst/one (4), dst/two (3) got fstest.go:204: Not found "backup/one" fstest.go:204: Not found "dst/two" fstest.go:204: Not found "backup/three.txt" fstest.go:204: Not found "dst/one" fstest.go:207: Error Trace: fstest.go:207 fstest.go:311 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1827 sync_test.go:1855 Error: Not equal: expected: 0 actual : 4 Test: TestSyncBackupDir Messages: 4 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:1833 sync_test.go:1855 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 fstest.go:204: Not found "backup/one" fstest.go:204: Not found "dst/two" fstest.go:204: Not found "backup/three.txt" fstest.go:204: Not found "dst/one" fstest.go:204: Not found "dst/three.txt" fstest.go:207: Error Trace: fstest.go:207 fstest.go:311 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1833 sync_test.go:1855 Error: Not equal: expected: 0 actual : 5 Test: TestSyncBackupDir Messages: 5 objects not found 2022/06/17 09:15:21 DEBUG : one: Sizes differ (src 5 vs dst 4) 2022/06/17 09:15:21 DEBUG : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1 path dst: Waiting for checks to finish 2022/06/17 09:15:21 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/06/17 09:15:21 DEBUG : two: Unchanged skipping 2022/06/17 09:15:22 DEBUG : one: md5 = c7957179c41f69d44f217a108c7915d8 OK 2022/06/17 09:15:22 INFO : one: Copied (server-side copy) 2022/06/17 09:15:22 INFO : one: Deleted 2022/06/17 09:15:22 DEBUG : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1 path dst: Waiting for transfers to finish 2022/06/17 09:15:22 DEBUG : one: md5 = 0f93e81041f0cab37c37a05ae998b219 OK 2022/06/17 09:15:22 INFO : one: Copied (new) 2022/06/17 09:15:22 DEBUG : Waiting for deletions to finish 2022/06/17 09:15:23 DEBUG : three.txt: md5 = 91341eed84691a83caea73aa785736d5 OK 2022/06/17 09:15:23 INFO : three.txt: Copied (server-side copy) 2022/06/17 09:15:23 INFO : three.txt: Deleted 2022/06/17 09:15:23 INFO : three.txt: Moved into backup dir fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:299: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:299: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:306: Error Trace: fstest.go:306 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1852 sync_test.go:1855 Error: Should be true Test: TestSyncBackupDir Messages: listing wrong, want backup/one (4), backup/three.txt (6), dst/one (5), dst/two (3) got fstest.go:204: Not found "dst/one" fstest.go:204: Not found "dst/two" fstest.go:204: Not found "backup/three.txt" fstest.go:204: Not found "backup/one" fstest.go:207: Error Trace: fstest.go:207 fstest.go:311 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1852 sync_test.go:1855 Error: Not equal: expected: 0 actual : 4 Test: TestSyncBackupDir Messages: 4 objects not found --- FAIL: TestSyncBackupDir (34.38s) === RUN TestSyncBackupDirWithSuffix run.go:181: Remote "S3 bucket rclone-test-qexesid7zumeguc1wefuwul1", Local "Local file system at /tmp/rclone2984337896", 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:1806 sync_test.go:1858 Error: Should be true Test: TestSyncBackupDirWithSuffix Messages: listing wrong, want dst/one (3), dst/three.txt (5), dst/two (3) got fstest.go:204: Not found "dst/one" fstest.go:204: Not found "dst/two" fstest.go:204: Not found "dst/three.txt" fstest.go:207: Error Trace: fstest.go:207 fstest.go:311 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1806 sync_test.go:1858 Error: Not equal: expected: 0 actual : 3 Test: TestSyncBackupDirWithSuffix Messages: 3 objects not found 2022/06/17 09:15:38 DEBUG : Creating backend with remote "TestS3R2:rclone-test-qexesid7zumeguc1wefuwul1/dst" 2022/06/17 09:15:39 DEBUG : Creating backend with remote "TestS3R2:rclone-test-qexesid7zumeguc1wefuwul1/backup" 2022/06/17 09:15:39 DEBUG : one: Sizes differ (src 4 vs dst 3) 2022/06/17 09:15:39 DEBUG : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1 path dst: Waiting for checks to finish 2022/06/17 09:15:39 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/06/17 09:15:39 DEBUG : two: Unchanged skipping 2022/06/17 09:15:40 DEBUG : one: md5 = f97c5d29941bfb1b2fdab0874906ab82 OK 2022/06/17 09:15:40 INFO : one: Copied (server-side copy) to: one.bak 2022/06/17 09:15:40 INFO : one: Deleted 2022/06/17 09:15:40 DEBUG : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1 path dst: Waiting for transfers to finish 2022/06/17 09:15:40 DEBUG : one: md5 = c7957179c41f69d44f217a108c7915d8 OK 2022/06/17 09:15:40 INFO : one: Copied (new) 2022/06/17 09:15:40 DEBUG : Waiting for deletions to finish 2022/06/17 09:15:41 DEBUG : three.txt: md5 = 35d6d33467aae9a2e3dccb4b6b027878 OK 2022/06/17 09:15:41 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2022/06/17 09:15:41 INFO : three.txt: Deleted 2022/06/17 09:15:41 INFO : three.txt: Moved into backup dir fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:299: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:299: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:306: Error Trace: fstest.go:306 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1827 sync_test.go:1858 Error: Should be true Test: TestSyncBackupDirWithSuffix Messages: listing wrong, want backup/one.bak (3), backup/three.txt.bak (5), dst/one (4), dst/two (3) got fstest.go:204: Not found "backup/one.bak" fstest.go:204: Not found "dst/two" fstest.go:204: Not found "backup/three.txt.bak" fstest.go:204: Not found "dst/one" fstest.go:207: Error Trace: fstest.go:207 fstest.go:311 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1827 sync_test.go:1858 Error: Not equal: expected: 0 actual : 4 Test: TestSyncBackupDirWithSuffix Messages: 4 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:1833 sync_test.go:1858 Error: Should be true Test: TestSyncBackupDirWithSuffix Messages: listing wrong, want backup/one.bak (3), backup/three.txt.bak (5), dst/one (4), dst/three.txt (6), dst/two (3) got fstest.go:204: Not found "backup/one.bak" fstest.go:204: Not found "dst/two" fstest.go:204: Not found "backup/three.txt.bak" fstest.go:204: Not found "dst/one" fstest.go:204: Not found "dst/three.txt" fstest.go:207: Error Trace: fstest.go:207 fstest.go:311 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1833 sync_test.go:1858 Error: Not equal: expected: 0 actual : 5 Test: TestSyncBackupDirWithSuffix Messages: 5 objects not found 2022/06/17 09:15:56 DEBUG : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1 path dst: Waiting for checks to finish 2022/06/17 09:15:56 DEBUG : one: Sizes differ (src 5 vs dst 4) 2022/06/17 09:15:56 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/06/17 09:15:56 DEBUG : two: Unchanged skipping 2022/06/17 09:15:56 DEBUG : one: md5 = c7957179c41f69d44f217a108c7915d8 OK 2022/06/17 09:15:56 INFO : one: Copied (server-side copy) to: one.bak 2022/06/17 09:15:57 INFO : one: Deleted 2022/06/17 09:15:57 DEBUG : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1 path dst: Waiting for transfers to finish 2022/06/17 09:15:57 DEBUG : one: md5 = 0f93e81041f0cab37c37a05ae998b219 OK 2022/06/17 09:15:57 INFO : one: Copied (new) 2022/06/17 09:15:57 DEBUG : Waiting for deletions to finish 2022/06/17 09:15:58 DEBUG : three.txt: md5 = 91341eed84691a83caea73aa785736d5 OK 2022/06/17 09:15:58 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2022/06/17 09:15:58 INFO : three.txt: Deleted 2022/06/17 09:15:58 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:1852 sync_test.go:1858 Error: Should be true Test: TestSyncBackupDirWithSuffix Messages: listing wrong, want backup/one.bak (4), backup/three.txt.bak (6), dst/one (5), dst/two (3) got fstest.go:204: Not found "dst/one" fstest.go:204: Not found "dst/two" fstest.go:204: Not found "backup/three.txt.bak" fstest.go:204: Not found "backup/one.bak" fstest.go:207: Error Trace: fstest.go:207 fstest.go:311 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1852 sync_test.go:1858 Error: Not equal: expected: 0 actual : 4 Test: TestSyncBackupDirWithSuffix Messages: 4 objects not found --- FAIL: TestSyncBackupDirWithSuffix (34.95s) === RUN TestSyncBackupDirWithSuffixKeepExtension run.go:181: Remote "S3 bucket rclone-test-qexesid7zumeguc1wefuwul1", Local "Local file system at /tmp/rclone2984337896", 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:1806 sync_test.go:1861 Error: Should be true Test: TestSyncBackupDirWithSuffixKeepExtension Messages: listing wrong, want dst/one (3), dst/three.txt (5), dst/two (3) got fstest.go:204: Not found "dst/two" fstest.go:204: Not found "dst/three.txt" fstest.go:204: Not found "dst/one" fstest.go:207: Error Trace: fstest.go:207 fstest.go:311 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1806 sync_test.go:1861 Error: Not equal: expected: 0 actual : 3 Test: TestSyncBackupDirWithSuffixKeepExtension Messages: 3 objects not found 2022/06/17 09:16:13 DEBUG : Creating backend with remote "TestS3R2:rclone-test-qexesid7zumeguc1wefuwul1/dst" 2022/06/17 09:16:13 DEBUG : Creating backend with remote "TestS3R2:rclone-test-qexesid7zumeguc1wefuwul1/backup" 2022/06/17 09:16:14 DEBUG : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1 path dst: Waiting for checks to finish 2022/06/17 09:16:14 DEBUG : one: Sizes differ (src 4 vs dst 3) 2022/06/17 09:16:14 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/06/17 09:16:14 DEBUG : two: Unchanged skipping 2022/06/17 09:16:16 DEBUG : one: md5 = f97c5d29941bfb1b2fdab0874906ab82 OK 2022/06/17 09:16:16 INFO : one: Copied (server-side copy) to: one-2019-01-01 2022/06/17 09:16:16 INFO : one: Deleted 2022/06/17 09:16:16 DEBUG : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1 path dst: Waiting for transfers to finish 2022/06/17 09:16:16 DEBUG : one: md5 = c7957179c41f69d44f217a108c7915d8 OK 2022/06/17 09:16:16 INFO : one: Copied (new) 2022/06/17 09:16:16 DEBUG : Waiting for deletions to finish 2022/06/17 09:16:17 DEBUG : three.txt: md5 = 35d6d33467aae9a2e3dccb4b6b027878 OK 2022/06/17 09:16:17 INFO : three.txt: Copied (server-side copy) to: three-2019-01-01.txt 2022/06/17 09:16:17 INFO : three.txt: Deleted 2022/06/17 09:16:17 INFO : three.txt: Moved into backup dir fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:299: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:299: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:306: Error Trace: fstest.go:306 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1827 sync_test.go:1861 Error: Should be true Test: TestSyncBackupDirWithSuffixKeepExtension Messages: listing wrong, want backup/one-2019-01-01 (3), backup/three-2019-01-01.txt (5), dst/one (4), dst/two (3) got fstest.go:204: Not found "backup/one-2019-01-01" fstest.go:204: Not found "dst/two" fstest.go:204: Not found "backup/three-2019-01-01.txt" fstest.go:204: Not found "dst/one" fstest.go:207: Error Trace: fstest.go:207 fstest.go:311 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1827 sync_test.go:1861 Error: Not equal: expected: 0 actual : 4 Test: TestSyncBackupDirWithSuffixKeepExtension Messages: 4 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:1833 sync_test.go:1861 Error: Should be true Test: TestSyncBackupDirWithSuffixKeepExtension Messages: listing wrong, want backup/one-2019-01-01 (3), backup/three-2019-01-01.txt (5), dst/one (4), dst/three.txt (6), dst/two (3) got fstest.go:204: Not found "dst/three.txt" fstest.go:204: Not found "backup/one-2019-01-01" fstest.go:204: Not found "dst/two" fstest.go:204: Not found "backup/three-2019-01-01.txt" fstest.go:204: Not found "dst/one" fstest.go:207: Error Trace: fstest.go:207 fstest.go:311 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1833 sync_test.go:1861 Error: Not equal: expected: 0 actual : 5 Test: TestSyncBackupDirWithSuffixKeepExtension Messages: 5 objects not found 2022/06/17 09:16:32 DEBUG : one: Sizes differ (src 5 vs dst 4) 2022/06/17 09:16:32 DEBUG : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1 path dst: Waiting for checks to finish 2022/06/17 09:16:32 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/06/17 09:16:32 DEBUG : two: Unchanged skipping 2022/06/17 09:16:32 DEBUG : one: md5 = c7957179c41f69d44f217a108c7915d8 OK 2022/06/17 09:16:32 INFO : one: Copied (server-side copy) to: one-2019-01-01 2022/06/17 09:16:32 INFO : one: Deleted 2022/06/17 09:16:32 DEBUG : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1 path dst: Waiting for transfers to finish 2022/06/17 09:16:33 DEBUG : one: md5 = 0f93e81041f0cab37c37a05ae998b219 OK 2022/06/17 09:16:33 INFO : one: Copied (new) 2022/06/17 09:16:33 DEBUG : Waiting for deletions to finish 2022/06/17 09:16:34 DEBUG : three.txt: md5 = 91341eed84691a83caea73aa785736d5 OK 2022/06/17 09:16:34 INFO : three.txt: Copied (server-side copy) to: three-2019-01-01.txt 2022/06/17 09:16:34 INFO : three.txt: Deleted 2022/06/17 09:16:34 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:1852 sync_test.go:1861 Error: Should be true Test: TestSyncBackupDirWithSuffixKeepExtension Messages: listing wrong, want backup/one-2019-01-01 (4), backup/three-2019-01-01.txt (6), dst/one (5), dst/two (3) got fstest.go:204: Not found "dst/one" fstest.go:204: Not found "dst/two" fstest.go:204: Not found "backup/three-2019-01-01.txt" fstest.go:204: Not found "backup/one-2019-01-01" fstest.go:207: Error Trace: fstest.go:207 fstest.go:311 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1852 sync_test.go:1861 Error: Not equal: expected: 0 actual : 4 Test: TestSyncBackupDirWithSuffixKeepExtension Messages: 4 objects not found --- FAIL: TestSyncBackupDirWithSuffixKeepExtension (35.98s) === RUN TestSyncBackupDirSuffixOnly run.go:181: Remote "S3 bucket rclone-test-qexesid7zumeguc1wefuwul1", Local "Local file system at /tmp/rclone2984337896", 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:1806 sync_test.go:1864 Error: Should be true Test: TestSyncBackupDirSuffixOnly Messages: listing wrong, want dst/one (3), dst/three.txt (5), dst/two (3) got fstest.go:204: Not found "dst/one" fstest.go:204: Not found "dst/two" fstest.go:204: Not found "dst/three.txt" fstest.go:207: Error Trace: fstest.go:207 fstest.go:311 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1806 sync_test.go:1864 Error: Not equal: expected: 0 actual : 3 Test: TestSyncBackupDirSuffixOnly Messages: 3 objects not found 2022/06/17 09:16:51 DEBUG : Creating backend with remote "TestS3R2:rclone-test-qexesid7zumeguc1wefuwul1/dst" 2022/06/17 09:16:52 DEBUG : one: Sizes differ (src 4 vs dst 3) 2022/06/17 09:16:52 DEBUG : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1 path dst: Waiting for checks to finish 2022/06/17 09:16:52 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/06/17 09:16:52 DEBUG : two: Unchanged skipping 2022/06/17 09:16:54 DEBUG : one: md5 = f97c5d29941bfb1b2fdab0874906ab82 OK 2022/06/17 09:16:54 INFO : one: Copied (server-side copy) to: one.bak 2022/06/17 09:16:55 INFO : one: Deleted 2022/06/17 09:16:55 DEBUG : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1 path dst: Waiting for transfers to finish 2022/06/17 09:16:56 DEBUG : one: md5 = c7957179c41f69d44f217a108c7915d8 OK 2022/06/17 09:16:56 INFO : one: Copied (new) 2022/06/17 09:16:56 DEBUG : Waiting for deletions to finish 2022/06/17 09:16:59 DEBUG : three.txt: md5 = 35d6d33467aae9a2e3dccb4b6b027878 OK 2022/06/17 09:16:59 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2022/06/17 09:16:59 INFO : three.txt: Deleted 2022/06/17 09:16:59 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:1827 sync_test.go:1864 Error: Should be true Test: TestSyncBackupDirSuffixOnly Messages: listing wrong, want dst/one (4), dst/one.bak (3), dst/three.txt.bak (5), dst/two (3) got fstest.go:204: Not found "dst/one.bak" fstest.go:204: Not found "dst/two" fstest.go:204: Not found "dst/three.txt.bak" fstest.go:204: Not found "dst/one" fstest.go:207: Error Trace: fstest.go:207 fstest.go:311 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1827 sync_test.go:1864 Error: Not equal: expected: 0 actual : 4 Test: TestSyncBackupDirSuffixOnly Messages: 4 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:1833 sync_test.go:1864 Error: Should be true Test: TestSyncBackupDirSuffixOnly Messages: listing wrong, want dst/one (4), dst/one.bak (3), dst/three.txt (6), dst/three.txt.bak (5), dst/two (3) got fstest.go:204: Not found "dst/three.txt.bak" fstest.go:204: Not found "dst/one" fstest.go:204: Not found "dst/three.txt" fstest.go:204: Not found "dst/one.bak" fstest.go:204: Not found "dst/two" fstest.go:207: Error Trace: fstest.go:207 fstest.go:311 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1833 sync_test.go:1864 Error: Not equal: expected: 0 actual : 5 Test: TestSyncBackupDirSuffixOnly Messages: 5 objects not found 2022/06/17 09:17:14 DEBUG : one.bak: Excluded 2022/06/17 09:17:14 DEBUG : three.txt.bak: Excluded 2022/06/17 09:17:14 DEBUG : one: Sizes differ (src 5 vs dst 4) 2022/06/17 09:17:14 DEBUG : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1 path dst: Waiting for checks to finish 2022/06/17 09:17:14 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/06/17 09:17:14 DEBUG : two: Unchanged skipping 2022/06/17 09:17:15 DEBUG : one: md5 = c7957179c41f69d44f217a108c7915d8 OK 2022/06/17 09:17:15 INFO : one: Copied (server-side copy) to: one.bak 2022/06/17 09:17:15 INFO : one: Deleted 2022/06/17 09:17:15 DEBUG : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1 path dst: Waiting for transfers to finish 2022/06/17 09:17:15 DEBUG : one: md5 = 0f93e81041f0cab37c37a05ae998b219 OK 2022/06/17 09:17:15 INFO : one: Copied (new) 2022/06/17 09:17:15 DEBUG : Waiting for deletions to finish 2022/06/17 09:17:16 DEBUG : three.txt: md5 = 91341eed84691a83caea73aa785736d5 OK 2022/06/17 09:17:16 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2022/06/17 09:17:16 INFO : three.txt: Deleted 2022/06/17 09:17:16 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:1852 sync_test.go:1864 Error: Should be true Test: TestSyncBackupDirSuffixOnly Messages: listing wrong, want dst/one (5), dst/one.bak (4), dst/three.txt.bak (6), dst/two (3) got fstest.go:204: Not found "dst/one" fstest.go:204: Not found "dst/two" fstest.go:204: Not found "dst/three.txt.bak" fstest.go:204: Not found "dst/one.bak" fstest.go:207: Error Trace: fstest.go:207 fstest.go:311 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1852 sync_test.go:1864 Error: Not equal: expected: 0 actual : 4 Test: TestSyncBackupDirSuffixOnly Messages: 4 objects not found --- FAIL: TestSyncBackupDirSuffixOnly (42.26s) === RUN TestSyncSuffix run.go:181: Remote "S3 bucket rclone-test-qexesid7zumeguc1wefuwul1", Local "Local file system at /tmp/rclone2984337896", 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:1891 sync_test.go:1949 Error: Should be true Test: TestSyncSuffix Messages: listing wrong, want dst/one (3), dst/three.txt (5), dst/two (3) got fstest.go:204: Not found "dst/three.txt" fstest.go:204: Not found "dst/one" fstest.go:204: Not found "dst/two" fstest.go:207: Error Trace: fstest.go:207 fstest.go:311 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1891 sync_test.go:1949 Error: Not equal: expected: 0 actual : 3 Test: TestSyncSuffix Messages: 3 objects not found 2022/06/17 09:17:32 DEBUG : Creating backend with remote "TestS3R2:rclone-test-qexesid7zumeguc1wefuwul1/dst" 2022/06/17 09:17:32 DEBUG : one: Sizes differ (src 4 vs dst 3) 2022/06/17 09:17:33 DEBUG : one: md5 = f97c5d29941bfb1b2fdab0874906ab82 OK 2022/06/17 09:17:33 INFO : one: Copied (server-side copy) to: one.bak 2022/06/17 09:17:33 INFO : one: Deleted 2022/06/17 09:17:33 DEBUG : one: md5 = c7957179c41f69d44f217a108c7915d8 OK 2022/06/17 09:17:33 INFO : one: Copied (new) 2022/06/17 09:17:33 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/06/17 09:17:33 DEBUG : two: Unchanged skipping 2022/06/17 09:17:33 DEBUG : three.txt: Sizes differ (src 6 vs dst 5) 2022/06/17 09:17:34 DEBUG : three.txt: md5 = 35d6d33467aae9a2e3dccb4b6b027878 OK 2022/06/17 09:17:34 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2022/06/17 09:17:34 INFO : three.txt: Deleted 2022/06/17 09:17:34 DEBUG : three.txt: md5 = 91341eed84691a83caea73aa785736d5 OK 2022/06/17 09:17:34 INFO : three.txt: Copied (new) fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:299: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:299: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:306: Error Trace: fstest.go:306 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1917 sync_test.go:1949 Error: Should be true Test: TestSyncSuffix Messages: listing wrong, want dst/one (4), dst/one.bak (3), dst/three.txt (6), dst/three.txt.bak (5), dst/two (3) got fstest.go:204: Not found "dst/three.txt.bak" fstest.go:204: Not found "dst/one" fstest.go:204: Not found "dst/three.txt" fstest.go:204: Not found "dst/one.bak" fstest.go:204: Not found "dst/two" fstest.go:207: Error Trace: fstest.go:207 fstest.go:311 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1917 sync_test.go:1949 Error: Not equal: expected: 0 actual : 5 Test: TestSyncSuffix Messages: 5 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:1923 sync_test.go:1949 Error: Should be true Test: TestSyncSuffix Messages: listing wrong, want dst/one (4), dst/one.bak (3), dst/three.txt (6), dst/three.txt.bak (5), dst/two (3) got fstest.go:204: Not found "dst/one.bak" fstest.go:204: Not found "dst/two" fstest.go:204: Not found "dst/three.txt.bak" fstest.go:204: Not found "dst/one" fstest.go:204: Not found "dst/three.txt" fstest.go:207: Error Trace: fstest.go:207 fstest.go:311 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1923 sync_test.go:1949 Error: Not equal: expected: 0 actual : 5 Test: TestSyncSuffix Messages: 5 objects not found 2022/06/17 09:17:49 DEBUG : one: Sizes differ (src 5 vs dst 4) 2022/06/17 09:17:50 DEBUG : one: md5 = c7957179c41f69d44f217a108c7915d8 OK 2022/06/17 09:17:50 INFO : one: Copied (server-side copy) to: one.bak 2022/06/17 09:17:50 INFO : one: Deleted 2022/06/17 09:17:50 DEBUG : one: md5 = 0f93e81041f0cab37c37a05ae998b219 OK 2022/06/17 09:17:50 INFO : one: Copied (new) 2022/06/17 09:17:50 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/06/17 09:17:50 DEBUG : two: Unchanged skipping 2022/06/17 09:17:50 DEBUG : three.txt: Sizes differ (src 19 vs dst 6) 2022/06/17 09:17:51 DEBUG : three.txt: md5 = 91341eed84691a83caea73aa785736d5 OK 2022/06/17 09:17:51 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2022/06/17 09:17:51 INFO : three.txt: Deleted 2022/06/17 09:17:51 DEBUG : three.txt: md5 = 423e01c5e84a30d129b97aedb6e219a9 OK 2022/06/17 09:17:51 INFO : three.txt: Copied (new) fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:299: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:299: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:306: Error Trace: fstest.go:306 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1947 sync_test.go:1949 Error: Should be true Test: TestSyncSuffix Messages: listing wrong, want dst/one (5), dst/one.bak (4), dst/three.txt (19), dst/three.txt.bak (6), dst/two (3) got fstest.go:204: Not found "dst/one" fstest.go:204: Not found "dst/three.txt" fstest.go:204: Not found "dst/two" fstest.go:204: Not found "dst/three.txt.bak" fstest.go:204: Not found "dst/one.bak" fstest.go:207: Error Trace: fstest.go:207 fstest.go:311 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1947 sync_test.go:1949 Error: Not equal: expected: 0 actual : 5 Test: TestSyncSuffix Messages: 5 objects not found --- FAIL: TestSyncSuffix (35.00s) === RUN TestSyncSuffixKeepExtension run.go:181: Remote "S3 bucket rclone-test-qexesid7zumeguc1wefuwul1", Local "Local file system at /tmp/rclone2984337896", 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:1891 sync_test.go:1950 Error: Should be true Test: TestSyncSuffixKeepExtension Messages: listing wrong, want dst/one (3), dst/three.txt (5), dst/two (3) got fstest.go:204: Not found "dst/one" fstest.go:204: Not found "dst/two" fstest.go:204: Not found "dst/three.txt" fstest.go:207: Error Trace: fstest.go:207 fstest.go:311 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1891 sync_test.go:1950 Error: Not equal: expected: 0 actual : 3 Test: TestSyncSuffixKeepExtension Messages: 3 objects not found 2022/06/17 09:18:06 DEBUG : Creating backend with remote "TestS3R2:rclone-test-qexesid7zumeguc1wefuwul1/dst" 2022/06/17 09:18:07 DEBUG : one: Sizes differ (src 4 vs dst 3) 2022/06/17 09:18:07 DEBUG : one: md5 = f97c5d29941bfb1b2fdab0874906ab82 OK 2022/06/17 09:18:07 INFO : one: Copied (server-side copy) to: one-2019-01-01 2022/06/17 09:18:07 INFO : one: Deleted 2022/06/17 09:18:08 DEBUG : one: md5 = c7957179c41f69d44f217a108c7915d8 OK 2022/06/17 09:18:08 INFO : one: Copied (new) 2022/06/17 09:18:08 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/06/17 09:18:08 DEBUG : two: Unchanged skipping 2022/06/17 09:18:08 DEBUG : three.txt: Sizes differ (src 6 vs dst 5) 2022/06/17 09:18:09 DEBUG : three.txt: md5 = 35d6d33467aae9a2e3dccb4b6b027878 OK 2022/06/17 09:18:09 INFO : three.txt: Copied (server-side copy) to: three-2019-01-01.txt 2022/06/17 09:18:09 INFO : three.txt: Deleted 2022/06/17 09:18:09 DEBUG : three.txt: md5 = 91341eed84691a83caea73aa785736d5 OK 2022/06/17 09:18:09 INFO : three.txt: Copied (new) fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:299: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:299: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:306: Error Trace: fstest.go:306 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1917 sync_test.go:1950 Error: Should be true Test: TestSyncSuffixKeepExtension Messages: listing wrong, want dst/one (4), dst/one-2019-01-01 (3), dst/three-2019-01-01.txt (5), dst/three.txt (6), dst/two (3) got fstest.go:204: Not found "dst/one-2019-01-01" fstest.go:204: Not found "dst/two" fstest.go:204: Not found "dst/three-2019-01-01.txt" fstest.go:204: Not found "dst/one" fstest.go:204: Not found "dst/three.txt" fstest.go:207: Error Trace: fstest.go:207 fstest.go:311 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1917 sync_test.go:1950 Error: Not equal: expected: 0 actual : 5 Test: TestSyncSuffixKeepExtension Messages: 5 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:1923 sync_test.go:1950 Error: Should be true Test: TestSyncSuffixKeepExtension Messages: listing wrong, want dst/one (4), dst/one-2019-01-01 (3), dst/three-2019-01-01.txt (5), dst/three.txt (6), dst/two (3) got fstest.go:204: Not found "dst/one-2019-01-01" fstest.go:204: Not found "dst/two" fstest.go:204: Not found "dst/three-2019-01-01.txt" fstest.go:204: Not found "dst/one" fstest.go:204: Not found "dst/three.txt" fstest.go:207: Error Trace: fstest.go:207 fstest.go:311 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1923 sync_test.go:1950 Error: Not equal: expected: 0 actual : 5 Test: TestSyncSuffixKeepExtension Messages: 5 objects not found 2022/06/17 09:18:24 DEBUG : one: Sizes differ (src 5 vs dst 4) 2022/06/17 09:18:25 DEBUG : one: md5 = c7957179c41f69d44f217a108c7915d8 OK 2022/06/17 09:18:25 INFO : one: Copied (server-side copy) to: one-2019-01-01 2022/06/17 09:18:25 INFO : one: Deleted 2022/06/17 09:18:25 DEBUG : one: md5 = 0f93e81041f0cab37c37a05ae998b219 OK 2022/06/17 09:18:25 INFO : one: Copied (new) 2022/06/17 09:18:25 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/06/17 09:18:25 DEBUG : two: Unchanged skipping 2022/06/17 09:18:25 DEBUG : three.txt: Sizes differ (src 19 vs dst 6) 2022/06/17 09:18:26 DEBUG : three.txt: md5 = 91341eed84691a83caea73aa785736d5 OK 2022/06/17 09:18:26 INFO : three.txt: Copied (server-side copy) to: three-2019-01-01.txt 2022/06/17 09:18:26 INFO : three.txt: Deleted 2022/06/17 09:18:26 DEBUG : three.txt: md5 = 423e01c5e84a30d129b97aedb6e219a9 OK 2022/06/17 09:18:26 INFO : three.txt: Copied (new) fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:299: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:299: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:306: Error Trace: fstest.go:306 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1947 sync_test.go:1950 Error: Should be true Test: TestSyncSuffixKeepExtension Messages: listing wrong, want dst/one (5), dst/one-2019-01-01 (4), dst/three-2019-01-01.txt (6), dst/three.txt (19), dst/two (3) got fstest.go:204: Not found "dst/one" fstest.go:204: Not found "dst/three.txt" fstest.go:204: Not found "dst/two" fstest.go:204: Not found "dst/three-2019-01-01.txt" fstest.go:204: Not found "dst/one-2019-01-01" fstest.go:207: Error Trace: fstest.go:207 fstest.go:311 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1947 sync_test.go:1950 Error: Not equal: expected: 0 actual : 5 Test: TestSyncSuffixKeepExtension Messages: 5 objects not found --- FAIL: TestSyncSuffixKeepExtension (35.12s) === RUN TestSyncUTFNorm run.go:181: Remote "S3 bucket rclone-test-qexesid7zumeguc1wefuwul1", Local "Local file system at /tmp/rclone2984337896", 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:1972 Error: Should be true Test: TestSyncUTFNorm Messages: listing wrong, want Testêé (18) got fstest.go:204: Not found "Testêé" fstest.go:207: Error Trace: fstest.go:207 fstest.go:311 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1972 Error: Not equal: expected: 0 actual : 1 Test: TestSyncUTFNorm Messages: 1 objects not found 2022/06/17 09:18:41 DEBUG : Testêé: Sizes differ (src 14 vs dst 18) 2022/06/17 09:18:41 DEBUG : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1: Waiting for checks to finish 2022/06/17 09:18:41 DEBUG : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1: Waiting for transfers to finish 2022/06/17 09:18:42 DEBUG : Testêé: md5 = ce114e4501d2f4e2dcea3e17b546f339 OK 2022/06/17 09:18:42 INFO : Testêé: Copied (replaced existing) to: Testêé 2022/06/17 09:18:42 DEBUG : Waiting for deletions to finish 2022/06/17 09:18:42 INFO : backup/three.txt: Deleted 2022/06/17 09:18:42 INFO : backup/one.bak: Deleted 2022/06/17 09:18:42 INFO : backup/one: Deleted 2022/06/17 09:18:42 INFO : dst/three.txt.bak: Deleted 2022/06/17 09:18:42 INFO : dest/3: Deleted 2022/06/17 09:18:42 INFO : pre-dest2/2: Deleted 2022/06/17 09:18:42 INFO : CopyDest/three: Deleted 2022/06/17 09:18:42 INFO : CopyDest/one: Deleted 2022/06/17 09:18:42 INFO : BackupDir/one: Deleted 2022/06/17 09:18:42 INFO : dst/one-2019-01-01: Deleted 2022/06/17 09:18:42 INFO : dst/one.bak: Deleted 2022/06/17 09:18:42 INFO : backup/three.txt.bak: Deleted 2022/06/17 09:18:42 INFO : CopyDest/two: Deleted 2022/06/17 09:18:42 INFO : dst/three.txt: Deleted 2022/06/17 09:18:42 INFO : potato2: Deleted 2022/06/17 09:18:42 INFO : backup/one-2019-01-01: Deleted 2022/06/17 09:18:42 INFO : backup/three: Deleted 2022/06/17 09:18:42 INFO : backup/three-2019-01-01.txt: Deleted 2022/06/17 09:18:42 INFO : CompareDest/two: Deleted 2022/06/17 09:18:42 INFO : dst/one: Deleted 2022/06/17 09:18:42 INFO : dst/three-2019-01-01.txt: Deleted 2022/06/17 09:18:42 INFO : dst/two: Deleted 2022/06/17 09:18:42 INFO : CompareDest/one: Deleted 2022/06/17 09:18:42 INFO : pre-dest1/1: Deleted fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:299: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:299: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:306: Error Trace: fstest.go:306 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1983 Error: Should be true Test: TestSyncUTFNorm Messages: listing wrong, want Testêé (14) got fstest.go:204: Not found "Testêé" fstest.go:207: Error Trace: fstest.go:207 fstest.go:311 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:1983 Error: Not equal: expected: 0 actual : 1 Test: TestSyncUTFNorm Messages: 1 objects not found --- FAIL: TestSyncUTFNorm (16.42s) === RUN TestSyncImmutable run.go:181: Remote "S3 bucket rclone-test-qexesid7zumeguc1wefuwul1", Local "Local file system at /tmp/rclone2984337896", Modify Window "1ns" 2022/06/17 09:18:50 DEBUG : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1: Waiting for checks to finish 2022/06/17 09:18:50 DEBUG : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1: Waiting for transfers to finish 2022/06/17 09:18:50 DEBUG : existing: md5 = 8ee2027983915ec78acc45027d874316 OK 2022/06/17 09:18:50 INFO : existing: Copied (new) 2022/06/17 09:18:50 DEBUG : Waiting for deletions to finish 2022/06/17 09:18:50 INFO : Testêé: Deleted fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:299: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:299: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:306: Error Trace: fstest.go:306 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:2005 Error: Should be true Test: TestSyncImmutable Messages: listing wrong, want existing (6) got fstest.go:204: Not found "existing" fstest.go:207: Error Trace: fstest.go:207 fstest.go:311 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:2005 Error: Not equal: expected: 0 actual : 1 Test: TestSyncImmutable 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:2010 Error: Should be true Test: TestSyncImmutable Messages: listing wrong, want existing (6) got fstest.go:204: Not found "existing" fstest.go:207: Error Trace: fstest.go:207 fstest.go:311 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:2010 Error: Not equal: expected: 0 actual : 1 Test: TestSyncImmutable Messages: 1 objects not found 2022/06/17 09:19:05 DEBUG : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1: Waiting for checks to finish 2022/06/17 09:19:05 DEBUG : existing: Sizes differ (src 8 vs dst 6) 2022/06/17 09:19:05 ERROR : existing: Source and destination exist but do not match: immutable file modified 2022/06/17 09:19:05 DEBUG : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1: Waiting for transfers to finish 2022/06/17 09:19:05 ERROR : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1: not deleting files as there were IO errors 2022/06/17 09:19:05 ERROR : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1: not deleting directories as there were IO errors 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:2017 Error: Should be true Test: TestSyncImmutable Messages: listing wrong, want existing (6) got fstest.go:204: Not found "existing" fstest.go:207: Error Trace: fstest.go:207 fstest.go:311 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:2017 Error: Not equal: expected: 0 actual : 1 Test: TestSyncImmutable Messages: 1 objects not found --- FAIL: TestSyncImmutable (22.48s) === RUN TestSyncIgnoreCase run.go:181: Remote "S3 bucket rclone-test-qexesid7zumeguc1wefuwul1", Local "Local file system at /tmp/rclone2984337896", 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:2038 Error: Should be true Test: TestSyncIgnoreCase Messages: listing wrong, want EXISTING (6) got fstest.go:204: Not found "EXISTING" fstest.go:207: Error Trace: fstest.go:207 fstest.go:311 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:2038 Error: Not equal: expected: 0 actual : 1 Test: TestSyncIgnoreCase Messages: 1 objects not found 2022/06/17 09:19:20 NOTICE: existing: Duplicate object found in destination - ignoring 2022/06/17 09:19:20 DEBUG : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1: Waiting for checks to finish 2022/06/17 09:19:20 DEBUG : existing: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/06/17 09:19:20 DEBUG : existing: Unchanged skipping 2022/06/17 09:19:20 DEBUG : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1: Waiting for transfers to finish 2022/06/17 09:19:20 DEBUG : Waiting for deletions to finish 2022/06/17 09:19:20 INFO : There was nothing to transfer fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:299: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:299: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:306: Error Trace: fstest.go:306 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:2045 Error: Should be true Test: TestSyncIgnoreCase Messages: listing wrong, want EXISTING (6) got fstest.go:204: Not found "EXISTING" fstest.go:207: Error Trace: fstest.go:207 fstest.go:311 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:2045 Error: Not equal: expected: 0 actual : 1 Test: TestSyncIgnoreCase Messages: 1 objects not found --- FAIL: TestSyncIgnoreCase (15.57s) === RUN TestSyncConcurrentDelete run.go:181: Remote "S3 bucket rclone-test-qexesid7zumeguc1wefuwul1", Local "Local file system at /tmp/rclone2984337896", 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:2124 sync_test.go:2136 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 fstest.go:204: Not found "both4" fstest.go:204: Not found "only6" fstest.go:204: Not found "both8" fstest.go:204: Not found "both12" fstest.go:204: Not found "only14" fstest.go:204: Not found "only17" fstest.go:204: Not found "only1" fstest.go:204: Not found "both16" fstest.go:204: Not found "only16" fstest.go:204: Not found "only19" fstest.go:204: Not found "only13" fstest.go:204: Not found "both14" fstest.go:204: Not found "both17" fstest.go:204: Not found "both18" fstest.go:204: Not found "both1" fstest.go:204: Not found "both2" fstest.go:204: Not found "only2" fstest.go:204: Not found "both13" fstest.go:204: Not found "only15" fstest.go:204: Not found "both3" fstest.go:204: Not found "only8" fstest.go:204: Not found "only9" fstest.go:204: Not found "only12" fstest.go:204: Not found "both0" fstest.go:204: Not found "both19" fstest.go:204: Not found "only3" fstest.go:204: Not found "only5" fstest.go:204: Not found "both6" fstest.go:204: Not found "only7" fstest.go:204: Not found "both9" fstest.go:204: Not found "both10" fstest.go:204: Not found "only11" fstest.go:204: Not found "only18" fstest.go:204: Not found "only0" fstest.go:204: Not found "only4" fstest.go:204: Not found "both5" fstest.go:204: Not found "both7" fstest.go:204: Not found "only10" fstest.go:204: Not found "both11" fstest.go:204: Not found "both15" fstest.go:207: Error Trace: fstest.go:207 fstest.go:311 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:2124 sync_test.go:2136 Error: Not equal: expected: 0 actual : 40 Test: TestSyncConcurrentDelete Messages: 40 objects not found 2022/06/17 09:19:48 DEBUG : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1: Waiting for checks to finish 2022/06/17 09:19:49 DEBUG : both1: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/06/17 09:19:49 DEBUG : both1: Unchanged skipping 2022/06/17 09:19:49 DEBUG : both10: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/06/17 09:19:49 DEBUG : both10: Unchanged skipping 2022/06/17 09:19:49 DEBUG : both0: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/06/17 09:19:49 DEBUG : both0: Unchanged skipping 2022/06/17 09:19:49 DEBUG : both13: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/06/17 09:19:49 DEBUG : both13: Unchanged skipping 2022/06/17 09:19:49 DEBUG : both11: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/06/17 09:19:49 DEBUG : both11: Unchanged skipping 2022/06/17 09:19:49 DEBUG : both14: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/06/17 09:19:49 DEBUG : both14: Unchanged skipping 2022/06/17 09:19:49 DEBUG : both15: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/06/17 09:19:49 DEBUG : both15: Unchanged skipping 2022/06/17 09:19:49 DEBUG : both12: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/06/17 09:19:49 DEBUG : both12: Unchanged skipping 2022/06/17 09:19:49 DEBUG : both16: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/06/17 09:19:49 DEBUG : both16: Unchanged skipping 2022/06/17 09:19:49 DEBUG : both17: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/06/17 09:19:49 DEBUG : both17: Unchanged skipping 2022/06/17 09:19:49 DEBUG : both18: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/06/17 09:19:49 DEBUG : both18: Unchanged skipping 2022/06/17 09:19:49 DEBUG : both19: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/06/17 09:19:49 DEBUG : both19: Unchanged skipping 2022/06/17 09:19:49 DEBUG : both2: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/06/17 09:19:49 DEBUG : both2: Unchanged skipping 2022/06/17 09:19:49 DEBUG : both3: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/06/17 09:19:49 DEBUG : both3: Unchanged skipping 2022/06/17 09:19:49 DEBUG : both4: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/06/17 09:19:49 DEBUG : both4: Unchanged skipping 2022/06/17 09:19:49 DEBUG : both5: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/06/17 09:19:49 DEBUG : both5: Unchanged skipping 2022/06/17 09:19:49 DEBUG : both6: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/06/17 09:19:49 DEBUG : both6: Unchanged skipping 2022/06/17 09:19:49 DEBUG : both8: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/06/17 09:19:49 DEBUG : both8: Unchanged skipping 2022/06/17 09:19:49 DEBUG : both9: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/06/17 09:19:49 DEBUG : both9: Unchanged skipping 2022/06/17 09:19:49 DEBUG : both7: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/06/17 09:19:49 DEBUG : both7: Unchanged skipping 2022/06/17 09:19:49 DEBUG : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1: Waiting for transfers to finish 2022/06/17 09:19:49 DEBUG : Waiting for deletions to finish 2022/06/17 09:19:49 INFO : only13: Deleted 2022/06/17 09:19:49 INFO : only16: Deleted 2022/06/17 09:19:49 INFO : only0: Deleted 2022/06/17 09:19:49 INFO : only2: Deleted 2022/06/17 09:19:49 INFO : only5: Deleted 2022/06/17 09:19:49 INFO : existing: Deleted 2022/06/17 09:19:49 INFO : EXISTING: Deleted 2022/06/17 09:19:49 INFO : only6: Deleted 2022/06/17 09:19:49 INFO : only9: Deleted 2022/06/17 09:19:49 INFO : only14: Deleted 2022/06/17 09:19:49 INFO : only8: Deleted 2022/06/17 09:19:49 INFO : only3: Deleted 2022/06/17 09:19:49 INFO : only12: Deleted 2022/06/17 09:19:49 INFO : only4: Deleted 2022/06/17 09:19:49 INFO : only10: Deleted 2022/06/17 09:19:50 INFO : only7: Deleted 2022/06/17 09:19:50 INFO : only18: Deleted 2022/06/17 09:19:50 INFO : only19: Deleted 2022/06/17 09:19:50 INFO : only11: Deleted 2022/06/17 09:19:50 INFO : only15: Deleted 2022/06/17 09:19:50 INFO : only17: Deleted 2022/06/17 09:19:50 INFO : only1: Deleted 2022/06/17 09:19:50 INFO : There was nothing to transfer fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:299: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:299: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:306: Error Trace: fstest.go:306 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:2132 sync_test.go:2136 Error: Should be true Test: TestSyncConcurrentDelete Messages: listing wrong, want both0 (6), both1 (6), both10 (6), both11 (6), both12 (6), both13 (6), both14 (6), both15 (6), both16 (6), both17 (6), both18 (6), both19 (6), both2 (6), both3 (6), both4 (6), both5 (6), both6 (6), both7 (6), both8 (6), both9 (6) got fstest.go:204: Not found "both5" fstest.go:204: Not found "both9" fstest.go:204: Not found "both16" fstest.go:204: Not found "both17" fstest.go:204: Not found "both18" fstest.go:204: Not found "both0" fstest.go:204: Not found "both2" fstest.go:204: Not found "both6" fstest.go:204: Not found "both10" fstest.go:204: Not found "both13" fstest.go:204: Not found "both7" fstest.go:204: Not found "both8" fstest.go:204: Not found "both15" fstest.go:204: Not found "both19" fstest.go:204: Not found "both1" fstest.go:204: Not found "both3" fstest.go:204: Not found "both4" fstest.go:204: Not found "both11" fstest.go:204: Not found "both12" fstest.go:204: Not found "both14" fstest.go:207: Error Trace: fstest.go:207 fstest.go:311 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:2132 sync_test.go:2136 Error: Not equal: expected: 0 actual : 20 Test: TestSyncConcurrentDelete Messages: 20 objects not found --- FAIL: TestSyncConcurrentDelete (29.24s) === RUN TestSyncConcurrentTruncate run.go:181: Remote "S3 bucket rclone-test-qexesid7zumeguc1wefuwul1", Local "Local file system at /tmp/rclone2984337896", 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:2124 sync_test.go:2140 Error: Should be true Test: TestSyncConcurrentTruncate Messages: listing wrong, want both0 (6), both1 (6), both10 (6), both11 (6), both12 (6), both13 (6), both14 (6), both15 (6), both16 (6), both17 (6), both18 (6), both19 (6), both2 (6), both3 (6), both4 (6), both5 (6), both6 (6), both7 (6), both8 (6), both9 (6), only0 (6), only1 (6), only10 (6), only11 (6), only12 (6), only13 (6), only14 (6), only15 (6), only16 (6), only17 (6), only18 (6), only19 (6), only2 (6), only3 (6), only4 (6), only5 (6), only6 (6), only7 (6), only8 (6), only9 (6) got fstest.go:204: Not found "both7" fstest.go:204: Not found "only10" fstest.go:204: Not found "only11" fstest.go:204: Not found "both13" fstest.go:204: Not found "only15" fstest.go:204: Not found "only18" fstest.go:204: Not found "only1" fstest.go:204: Not found "both3" fstest.go:204: Not found "only5" fstest.go:204: Not found "both10" fstest.go:204: Not found "both11" fstest.go:204: Not found "both19" fstest.go:204: Not found "only2" fstest.go:204: Not found "only9" fstest.go:204: Not found "only16" fstest.go:204: Not found "only19" fstest.go:204: Not found "both0" fstest.go:204: Not found "both4" fstest.go:204: Not found "both6" fstest.go:204: Not found "both12" fstest.go:204: Not found "only13" fstest.go:204: Not found "only0" fstest.go:204: Not found "both2" fstest.go:204: Not found "only6" fstest.go:204: Not found "only12" fstest.go:204: Not found "both14" fstest.go:204: Not found "both16" fstest.go:204: Not found "only8" fstest.go:204: Not found "only17" fstest.go:204: Not found "only3" fstest.go:204: Not found "only7" fstest.go:204: Not found "both18" fstest.go:204: Not found "both17" fstest.go:204: Not found "both1" fstest.go:204: Not found "only4" fstest.go:204: Not found "both5" fstest.go:204: Not found "both8" fstest.go:204: Not found "both9" fstest.go:204: Not found "only14" fstest.go:204: Not found "both15" fstest.go:207: Error Trace: fstest.go:207 fstest.go:311 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:2124 sync_test.go:2140 Error: Not equal: expected: 0 actual : 40 Test: TestSyncConcurrentTruncate Messages: 40 objects not found 2022/06/17 09:20:18 DEBUG : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1: Waiting for checks to finish 2022/06/17 09:20:18 DEBUG : both1: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/06/17 09:20:18 DEBUG : both1: Unchanged skipping 2022/06/17 09:20:18 DEBUG : both0: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/06/17 09:20:18 DEBUG : both0: Unchanged skipping 2022/06/17 09:20:18 DEBUG : both10: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/06/17 09:20:18 DEBUG : both10: Unchanged skipping 2022/06/17 09:20:18 DEBUG : both11: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/06/17 09:20:18 DEBUG : both11: Unchanged skipping 2022/06/17 09:20:18 DEBUG : both12: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/06/17 09:20:18 DEBUG : both12: Unchanged skipping 2022/06/17 09:20:18 DEBUG : both13: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/06/17 09:20:18 DEBUG : both13: Unchanged skipping 2022/06/17 09:20:18 DEBUG : both15: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/06/17 09:20:18 DEBUG : both15: Unchanged skipping 2022/06/17 09:20:18 DEBUG : both14: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/06/17 09:20:18 DEBUG : both14: Unchanged skipping 2022/06/17 09:20:18 DEBUG : both16: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/06/17 09:20:18 DEBUG : both16: Unchanged skipping 2022/06/17 09:20:18 DEBUG : both19: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/06/17 09:20:18 DEBUG : both19: Unchanged skipping 2022/06/17 09:20:18 DEBUG : both17: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/06/17 09:20:18 DEBUG : both17: Unchanged skipping 2022/06/17 09:20:18 DEBUG : both18: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/06/17 09:20:18 DEBUG : both18: Unchanged skipping 2022/06/17 09:20:18 DEBUG : both2: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/06/17 09:20:18 DEBUG : both2: Unchanged skipping 2022/06/17 09:20:18 DEBUG : both3: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/06/17 09:20:18 DEBUG : both3: Unchanged skipping 2022/06/17 09:20:18 DEBUG : both4: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/06/17 09:20:18 DEBUG : both4: Unchanged skipping 2022/06/17 09:20:18 DEBUG : both7: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/06/17 09:20:18 DEBUG : both7: Unchanged skipping 2022/06/17 09:20:18 DEBUG : both6: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/06/17 09:20:18 DEBUG : both6: Unchanged skipping 2022/06/17 09:20:18 DEBUG : only0: Sizes differ (src 0 vs dst 6) 2022/06/17 09:20:18 DEBUG : only1: Sizes differ (src 0 vs dst 6) 2022/06/17 09:20:18 DEBUG : only10: Sizes differ (src 0 vs dst 6) 2022/06/17 09:20:18 DEBUG : only11: Sizes differ (src 0 vs dst 6) 2022/06/17 09:20:18 DEBUG : only12: Sizes differ (src 0 vs dst 6) 2022/06/17 09:20:18 DEBUG : only13: Sizes differ (src 0 vs dst 6) 2022/06/17 09:20:18 DEBUG : only14: Sizes differ (src 0 vs dst 6) 2022/06/17 09:20:18 DEBUG : only15: Sizes differ (src 0 vs dst 6) 2022/06/17 09:20:18 DEBUG : both5: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/06/17 09:20:18 DEBUG : both5: Unchanged skipping 2022/06/17 09:20:18 DEBUG : only16: Sizes differ (src 0 vs dst 6) 2022/06/17 09:20:18 DEBUG : only18: Sizes differ (src 0 vs dst 6) 2022/06/17 09:20:18 DEBUG : only19: Sizes differ (src 0 vs dst 6) 2022/06/17 09:20:18 DEBUG : only2: Sizes differ (src 0 vs dst 6) 2022/06/17 09:20:18 DEBUG : only3: Sizes differ (src 0 vs dst 6) 2022/06/17 09:20:18 DEBUG : only4: Sizes differ (src 0 vs dst 6) 2022/06/17 09:20:18 DEBUG : only5: Sizes differ (src 0 vs dst 6) 2022/06/17 09:20:18 DEBUG : only17: Sizes differ (src 0 vs dst 6) 2022/06/17 09:20:18 DEBUG : only7: Sizes differ (src 0 vs dst 6) 2022/06/17 09:20:18 DEBUG : only8: Sizes differ (src 0 vs dst 6) 2022/06/17 09:20:18 DEBUG : only9: Sizes differ (src 0 vs dst 6) 2022/06/17 09:20:18 DEBUG : only6: Sizes differ (src 0 vs dst 6) 2022/06/17 09:20:18 DEBUG : both8: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/06/17 09:20:18 DEBUG : both8: Unchanged skipping 2022/06/17 09:20:19 DEBUG : both9: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/06/17 09:20:19 DEBUG : both9: Unchanged skipping 2022/06/17 09:20:19 DEBUG : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1: Waiting for transfers to finish 2022/06/17 09:20:19 DEBUG : only10: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2022/06/17 09:20:19 INFO : only10: Copied (replaced existing) 2022/06/17 09:20:19 DEBUG : only1: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2022/06/17 09:20:19 INFO : only1: Copied (replaced existing) 2022/06/17 09:20:19 DEBUG : only0: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2022/06/17 09:20:19 INFO : only0: Copied (replaced existing) 2022/06/17 09:20:19 DEBUG : only12: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2022/06/17 09:20:19 INFO : only12: Copied (replaced existing) 2022/06/17 09:20:19 DEBUG : only13: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2022/06/17 09:20:19 INFO : only13: Copied (replaced existing) 2022/06/17 09:20:19 DEBUG : only11: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2022/06/17 09:20:19 INFO : only11: Copied (replaced existing) 2022/06/17 09:20:19 DEBUG : only16: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2022/06/17 09:20:19 INFO : only16: Copied (replaced existing) 2022/06/17 09:20:19 DEBUG : only15: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2022/06/17 09:20:19 INFO : only15: Copied (replaced existing) 2022/06/17 09:20:19 DEBUG : only14: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2022/06/17 09:20:19 INFO : only14: Copied (replaced existing) 2022/06/17 09:20:19 DEBUG : only18: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2022/06/17 09:20:19 INFO : only18: Copied (replaced existing) 2022/06/17 09:20:19 DEBUG : only2: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2022/06/17 09:20:19 INFO : only2: Copied (replaced existing) 2022/06/17 09:20:19 DEBUG : only19: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2022/06/17 09:20:19 INFO : only19: Copied (replaced existing) 2022/06/17 09:20:19 DEBUG : only4: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2022/06/17 09:20:19 INFO : only4: Copied (replaced existing) 2022/06/17 09:20:19 DEBUG : only5: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2022/06/17 09:20:19 INFO : only5: Copied (replaced existing) 2022/06/17 09:20:19 DEBUG : only3: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2022/06/17 09:20:19 INFO : only3: Copied (replaced existing) 2022/06/17 09:20:19 DEBUG : only17: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2022/06/17 09:20:19 INFO : only17: Copied (replaced existing) 2022/06/17 09:20:19 DEBUG : only7: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2022/06/17 09:20:19 INFO : only7: Copied (replaced existing) 2022/06/17 09:20:19 DEBUG : only9: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2022/06/17 09:20:19 INFO : only9: Copied (replaced existing) 2022/06/17 09:20:20 DEBUG : only8: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2022/06/17 09:20:20 INFO : only8: Copied (replaced existing) 2022/06/17 09:20:20 DEBUG : only6: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2022/06/17 09:20:20 INFO : only6: Copied (replaced existing) 2022/06/17 09:20:20 DEBUG : Waiting for deletions to finish fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:299: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:299: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:306: Error Trace: fstest.go:306 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:2132 sync_test.go:2140 Error: Should be true Test: TestSyncConcurrentTruncate Messages: listing wrong, want both0 (6), both1 (6), both10 (6), both11 (6), both12 (6), both13 (6), both14 (6), both15 (6), both16 (6), both17 (6), both18 (6), both19 (6), both2 (6), both3 (6), both4 (6), both5 (6), both6 (6), both7 (6), both8 (6), both9 (6), only0 (0), only1 (0), only10 (0), only11 (0), only12 (0), only13 (0), only14 (0), only15 (0), only16 (0), only17 (0), only18 (0), only19 (0), only2 (0), only3 (0), only4 (0), only5 (0), only6 (0), only7 (0), only8 (0), only9 (0) got fstest.go:204: Not found "only0" fstest.go:204: Not found "both4" fstest.go:204: Not found "both7" fstest.go:204: Not found "only10" fstest.go:204: Not found "only17" fstest.go:204: Not found "both3" fstest.go:204: Not found "both5" fstest.go:204: Not found "only9" fstest.go:204: Not found "both10" fstest.go:204: Not found "only11" fstest.go:204: Not found "only15" fstest.go:204: Not found "both19" fstest.go:204: Not found "both2" fstest.go:204: Not found "both6" fstest.go:204: Not found "both8" fstest.go:204: Not found "only8" fstest.go:204: Not found "both9" fstest.go:204: Not found "only2" fstest.go:204: Not found "both17" fstest.go:204: Not found "both1" fstest.go:204: Not found "only3" fstest.go:204: Not found "both12" fstest.go:204: Not found "only13" fstest.go:204: Not found "both14" fstest.go:204: Not found "only14" fstest.go:204: Not found "both16" fstest.go:204: Not found "only18" fstest.go:204: Not found "only1" fstest.go:204: Not found "only4" fstest.go:204: Not found "only6" fstest.go:204: Not found "both11" fstest.go:204: Not found "both13" fstest.go:204: Not found "only7" fstest.go:204: Not found "both18" fstest.go:204: Not found "only19" fstest.go:204: Not found "both0" fstest.go:204: Not found "only5" fstest.go:204: Not found "only12" fstest.go:204: Not found "both15" fstest.go:204: Not found "only16" fstest.go:207: Error Trace: fstest.go:207 fstest.go:311 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:2132 sync_test.go:2140 Error: Not equal: expected: 0 actual : 40 Test: TestSyncConcurrentTruncate Messages: 40 objects not found --- FAIL: TestSyncConcurrentTruncate (29.74s) FAIL 2022/06/17 09:20:27 DEBUG : S3 bucket rclone-test-qexesid7zumeguc1wefuwul1: Purge dir "" 2022/06/17 09:20:27 purge failed to rmdir "": BucketNotEmpty: The bucket you tried to delete (rclone-test-qexesid7zumeguc1wefuwul1) is not empty (account 14aad7c9ed489151b51557e321b246cf). status code: 409, request id: , host id: "./sync.test -test.v -test.timeout 1h0m0s -remote TestS3R2: -verbose -test.run '^(TestCopy|TestCopyAfterDelete|TestCopyCheckFirst|TestCopyDeleteBefore|TestCopyEmptyDirectories|TestCopyNoTraverse|TestCopyRedownload|TestCopyWithDepth|TestCopyWithFilesFrom|TestCopyWithFilesFromAndNoTraverse|TestMoveEmptyDirectories|TestMoveWithDeleteEmptySrcDirs|TestMoveWithIgnoreExisting|TestMoveWithoutDeleteEmptySrcDirs|TestServerSideCopy|TestServerSideMove|TestServerSideMoveDeleteEmptySourceDirs|TestServerSideMoveOverlap|TestServerSideMoveWithFilter|TestSyncAfterAddingAFile|TestSyncAfterChangingContentsOnly|TestSyncAfterChangingFilesSizeOnly|TestSyncAfterChangingModtimeOnly|TestSyncAfterChangingModtimeOnlyWithNoUpdateModTime|TestSyncAfterRemovingAFileAndAddingAFile|TestSyncAfterRemovingAFileAndAddingAFileDryRun|TestSyncAfterRemovingAFileAndAddingAFileSubDir|TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors|TestSyncBackupDir|TestSyncBackupDirSuffixOnly|TestSyncBackupDirWithSuffix|TestSyncBackupDirWithSuffixKeepExtension|TestSyncBasedOnCheckSum|TestSyncCompareDest|TestSyncConcurrentDelete|TestSyncConcurrentTruncate|TestSyncCopyDest|TestSyncDeleteBefore|TestSyncDeleteDuring|TestSyncDoesntUpdateModtime|TestSyncEmptyDirectories|TestSyncIgnoreCase|TestSyncIgnoreErrors|TestSyncIgnoreExisting|TestSyncIgnoreSize|TestSyncIgnoreTimes|TestSyncImmutable|TestSyncMultipleCompareDest|TestSyncNoTraverse|TestSyncSizeOnly|TestSyncSuffix|TestSyncSuffixKeepExtension|TestSyncUTFNorm|TestSyncWithExclude|TestSyncWithExcludeAndDeleteExcluded|TestSyncWithTrackRenames|TestSyncWithTrackRenamesStrategyLeaf|TestSyncWithTrackRenamesStrategyModtime|TestSyncWithUpdateOlder)$'" - Finished ERROR in 25m13.141118653s (try 5/5): exit status 1: Failed [TestCopy TestCopyNoTraverse TestCopyCheckFirst TestSyncNoTraverse TestCopyWithDepth TestCopyWithFilesFrom TestCopyWithFilesFromAndNoTraverse TestCopyEmptyDirectories TestMoveEmptyDirectories TestSyncEmptyDirectories TestServerSideCopy TestCopyAfterDelete TestCopyRedownload TestSyncBasedOnCheckSum TestSyncSizeOnly TestSyncIgnoreSize TestSyncIgnoreTimes TestSyncIgnoreExisting TestSyncIgnoreErrors TestSyncAfterChangingModtimeOnly TestSyncAfterChangingModtimeOnlyWithNoUpdateModTime TestSyncDoesntUpdateModtime TestSyncAfterAddingAFile TestSyncAfterChangingFilesSizeOnly TestSyncAfterChangingContentsOnly TestSyncAfterRemovingAFileAndAddingAFileDryRun TestSyncAfterRemovingAFileAndAddingAFile TestSyncAfterRemovingAFileAndAddingAFileSubDir TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors TestSyncDeleteDuring TestSyncDeleteBefore TestCopyDeleteBefore TestSyncWithExclude TestSyncWithExcludeAndDeleteExcluded TestSyncWithUpdateOlder TestSyncWithTrackRenames TestSyncWithTrackRenamesStrategyModtime TestSyncWithTrackRenamesStrategyLeaf TestMoveWithDeleteEmptySrcDirs TestMoveWithoutDeleteEmptySrcDirs TestMoveWithIgnoreExisting TestServerSideMove TestServerSideMoveWithFilter TestServerSideMoveDeleteEmptySourceDirs TestServerSideMoveOverlap TestSyncCompareDest TestSyncMultipleCompareDest TestSyncCopyDest TestSyncBackupDir TestSyncBackupDirWithSuffix TestSyncBackupDirWithSuffixKeepExtension TestSyncBackupDirSuffixOnly TestSyncSuffix TestSyncSuffixKeepExtension TestSyncUTFNorm TestSyncImmutable TestSyncIgnoreCase TestSyncConcurrentDelete TestSyncConcurrentTruncate]