"./sync.test -test.v -test.timeout 1h0m0s -remote TestS3Wasabi: -verbose -fast-list -test.run '^(TestCopy|TestCopyDeleteBefore|TestServerSideMove|TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors|TestSyncConcurrentDelete|TestSyncConcurrentTruncate|TestSyncDeleteBefore|TestSyncDeleteDuring|TestSyncReplaceDirModTime)$|^TestTransform$/^NFC$'" - Starting (try 2/5) 2025/07/30 02:05:14 DEBUG : Creating backend with remote "TestS3Wasabi:rclone-test-koyowek0qeyo" 2025/07/30 02:05:14 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/07/30 02:05:14 DEBUG : Creating backend with remote "/tmp/rclone4044236551" === RUN TestCopy run.go:180: Remote "S3 bucket rclone-test-koyowek0qeyo", Local "Local file system at /tmp/rclone4044236551", Modify Window "1ns" 2025/07/30 02:05:14 INFO : sub dir: Set directory modification time (using DirSetModTime) 2025/07/30 02:05:15 INFO : S3 bucket rclone-test-koyowek0qeyo: Bucket "rclone-test-koyowek0qeyo" created with ACL "" 2025/07/30 02:05:15 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/07/30 02:05:15 DEBUG : S3 bucket rclone-test-koyowek0qeyo: Waiting for checks to finish 2025/07/30 02:05:15 DEBUG : S3 bucket rclone-test-koyowek0qeyo: Waiting for transfers to finish 2025/07/30 02:05:15 DEBUG : sub dir/hello world: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2025/07/30 02:05:15 INFO : sub dir/hello world: Copied (new) 2025/07/30 02:05:16 DEBUG : S3 bucket rclone-test-koyowek0qeyo: Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata --- PASS: TestCopy (2.09s) === RUN TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors run.go:180: Remote "S3 bucket rclone-test-koyowek0qeyo", Local "Local file system at /tmp/rclone4044236551", Modify Window "1ns" 2025/07/30 02:05:17 INFO : d: Making directory fstest.go:247: Filtering empty directory "d" 2025/07/30 02:05:17 DEBUG : a/potato2: Need to transfer - File not found at Destination 2025/07/30 02:05:17 DEBUG : S3 bucket rclone-test-koyowek0qeyo: Waiting for checks to finish 2025/07/30 02:05:18 DEBUG : c/non empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/30 02:05:18 DEBUG : c/non empty space: Unchanged skipping 2025/07/30 02:05:18 DEBUG : S3 bucket rclone-test-koyowek0qeyo: Waiting for transfers to finish 2025/07/30 02:05:18 DEBUG : a/potato2: md5 = d6548b156ea68a4e003e786df99eee76 OK 2025/07/30 02:05:18 INFO : a/potato2: Copied (new) 2025/07/30 02:05:18 ERROR : S3 bucket rclone-test-koyowek0qeyo: not deleting files as there were IO errors 2025/07/30 02:05:18 ERROR : S3 bucket rclone-test-koyowek0qeyo: not deleting directories as there were IO errors fstest.go:247: Filtering empty directory "d" --- PASS: TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors (3.32s) === RUN TestSyncDeleteDuring run.go:180: Remote "S3 bucket rclone-test-koyowek0qeyo", Local "Local file system at /tmp/rclone4044236551", Modify Window "1ns" 2025/07/30 02:05:21 DEBUG : Waiting for deletions to finish 2025/07/30 02:05:21 DEBUG : potato2: Need to transfer - File not found at Destination 2025/07/30 02:05:21 DEBUG : S3 bucket rclone-test-koyowek0qeyo: Waiting for checks to finish 2025/07/30 02:05:21 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/30 02:05:21 DEBUG : empty space: Unchanged skipping 2025/07/30 02:05:21 DEBUG : S3 bucket rclone-test-koyowek0qeyo: Waiting for transfers to finish 2025/07/30 02:05:21 INFO : potato: Deleted 2025/07/30 02:05:21 DEBUG : potato2: md5 = d6548b156ea68a4e003e786df99eee76 OK 2025/07/30 02:05:21 INFO : potato2: Copied (new) --- PASS: TestSyncDeleteDuring (2.56s) === RUN TestSyncDeleteBefore run.go:180: Remote "S3 bucket rclone-test-koyowek0qeyo", Local "Local file system at /tmp/rclone4044236551", Modify Window "1ns" 2025/07/30 02:05:23 DEBUG : Waiting for deletions to finish 2025/07/30 02:05:23 DEBUG : S3 bucket rclone-test-koyowek0qeyo: Waiting for checks to finish 2025/07/30 02:05:23 DEBUG : S3 bucket rclone-test-koyowek0qeyo: Waiting for transfers to finish 2025/07/30 02:05:23 INFO : potato: Deleted 2025/07/30 02:05:24 DEBUG : potato2: Need to transfer - File not found at Destination 2025/07/30 02:05:24 DEBUG : S3 bucket rclone-test-koyowek0qeyo: Waiting for checks to finish 2025/07/30 02:05:24 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/30 02:05:24 DEBUG : empty space: Unchanged skipping 2025/07/30 02:05:24 DEBUG : S3 bucket rclone-test-koyowek0qeyo: Waiting for transfers to finish 2025/07/30 02:05:24 DEBUG : potato2: md5 = d6548b156ea68a4e003e786df99eee76 OK 2025/07/30 02:05:24 INFO : potato2: Copied (new) --- PASS: TestSyncDeleteBefore (2.99s) === RUN TestCopyDeleteBefore run.go:180: Remote "S3 bucket rclone-test-koyowek0qeyo", Local "Local file system at /tmp/rclone4044236551", Modify Window "1ns" 2025/07/30 02:05:26 DEBUG : potato2: Need to transfer - File not found at Destination 2025/07/30 02:05:26 DEBUG : S3 bucket rclone-test-koyowek0qeyo: Waiting for checks to finish 2025/07/30 02:05:26 DEBUG : S3 bucket rclone-test-koyowek0qeyo: Waiting for transfers to finish 2025/07/30 02:05:26 DEBUG : potato2: md5 = 9519bc2e940e1696ea7365b4b81adedc OK 2025/07/30 02:05:26 INFO : potato2: Copied (new) --- PASS: TestCopyDeleteBefore (2.43s) === RUN TestServerSideMove run.go:180: Remote "S3 bucket rclone-test-koyowek0qeyo", Local "Local file system at /tmp/rclone4044236551", Modify Window "1ns" 2025/07/30 02:05:28 DEBUG : Creating backend with remote "TestS3Wasabi:rclone-test-xodequg1biru" sync_test.go:1735: Server side move (if possible) S3 bucket rclone-test-koyowek0qeyo -> S3 bucket rclone-test-xodequg1biru 2025/07/30 02:05:30 INFO : S3 bucket rclone-test-xodequg1biru: Bucket "rclone-test-xodequg1biru" created with ACL "" 2025/07/30 02:05:31 DEBUG : potato2: Need to transfer - File not found at Destination 2025/07/30 02:05:31 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2025/07/30 02:05:31 DEBUG : S3 bucket rclone-test-xodequg1biru: Waiting for checks to finish 2025/07/30 02:05:31 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/30 02:05:31 DEBUG : empty space: Unchanged skipping 2025/07/30 02:05:31 DEBUG : potato3: md5 = b1a53993579b830006f2e1610415c32c OK 2025/07/30 02:05:31 INFO : potato3: Copied (server-side copy) 2025/07/30 02:05:31 INFO : empty space: Deleted 2025/07/30 02:05:31 DEBUG : S3 bucket rclone-test-xodequg1biru: Waiting for transfers to finish 2025/07/30 02:05:31 DEBUG : potato2: md5 = d6548b156ea68a4e003e786df99eee76 OK 2025/07/30 02:05:31 INFO : potato2: Copied (server-side copy) 2025/07/30 02:05:31 INFO : potato3: Deleted 2025/07/30 02:05:31 INFO : potato2: Deleted 2025/07/30 02:05:32 DEBUG : Creating backend with remote "TestS3Wasabi:rclone-test-fuwujap9jafe" 2025/07/30 02:05:33 DEBUG : empty space: Need to transfer - File not found at Destination 2025/07/30 02:05:33 DEBUG : potato2: Need to transfer - File not found at Destination 2025/07/30 02:05:33 DEBUG : potato3: Need to transfer - File not found at Destination 2025/07/30 02:05:33 DEBUG : S3 bucket rclone-test-fuwujap9jafe: Waiting for checks to finish 2025/07/30 02:05:33 DEBUG : S3 bucket rclone-test-fuwujap9jafe: Waiting for transfers to finish 2025/07/30 02:05:33 INFO : S3 bucket rclone-test-fuwujap9jafe: Bucket "rclone-test-fuwujap9jafe" created with ACL "" 2025/07/30 02:05:33 DEBUG : empty space: md5 = 336d5ebc5436534e61d16e63ddfca327 OK 2025/07/30 02:05:33 INFO : empty space: Copied (server-side copy) 2025/07/30 02:05:34 INFO : empty space: Deleted 2025/07/30 02:05:34 DEBUG : potato3: md5 = b1a53993579b830006f2e1610415c32c OK 2025/07/30 02:05:34 INFO : potato3: Copied (server-side copy) 2025/07/30 02:05:34 DEBUG : potato2: md5 = d6548b156ea68a4e003e786df99eee76 OK 2025/07/30 02:05:34 INFO : potato2: Copied (server-side copy) 2025/07/30 02:05:34 INFO : potato3: Deleted 2025/07/30 02:05:34 INFO : potato2: Deleted 2025/07/30 02:05:34 DEBUG : S3 bucket rclone-test-fuwujap9jafe: Purge remote 2025/07/30 02:05:35 DEBUG : S3 bucket rclone-test-fuwujap9jafe: bucket is versioned: false 2025/07/30 02:05:35 DEBUG : Waiting for deletions to finish 2025/07/30 02:05:35 DEBUG : "empty space" version false 2025/07/30 02:05:35 DEBUG : empty space: Deleting (id "") 2025/07/30 02:05:35 DEBUG : "potato2" version false 2025/07/30 02:05:35 DEBUG : potato2: Deleting (id "") 2025/07/30 02:05:35 DEBUG : "potato3" version false 2025/07/30 02:05:35 DEBUG : potato3: Deleting (id "") 2025/07/30 02:05:35 INFO : potato3: Deleted 2025/07/30 02:05:35 INFO : potato2: Deleted 2025/07/30 02:05:35 INFO : empty space: Deleted 2025/07/30 02:05:39 INFO : S3 bucket rclone-test-fuwujap9jafe: Bucket "rclone-test-fuwujap9jafe" deleted 2025/07/30 02:05:39 DEBUG : S3 bucket rclone-test-xodequg1biru: Purge remote 2025/07/30 02:05:39 DEBUG : S3 bucket rclone-test-xodequg1biru: bucket is versioned: false 2025/07/30 02:05:39 DEBUG : Waiting for deletions to finish 2025/07/30 02:05:40 INFO : S3 bucket rclone-test-xodequg1biru: Bucket "rclone-test-xodequg1biru" deleted --- PASS: TestServerSideMove (12.79s) === RUN TestSyncConcurrentDelete run.go:180: Remote "S3 bucket rclone-test-koyowek0qeyo", Local "Local file system at /tmp/rclone4044236551", Modify Window "1ns" 2025/07/30 02:05:58 DEBUG : S3 bucket rclone-test-koyowek0qeyo: Waiting for checks to finish 2025/07/30 02:05:58 DEBUG : both0: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/30 02:05:58 DEBUG : both0: Unchanged skipping 2025/07/30 02:05:58 DEBUG : both10: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/30 02:05:58 DEBUG : both10: Unchanged skipping 2025/07/30 02:05:58 DEBUG : both12: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/30 02:05:58 DEBUG : both12: Unchanged skipping 2025/07/30 02:05:58 DEBUG : both13: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/30 02:05:58 DEBUG : both13: Unchanged skipping 2025/07/30 02:05:58 DEBUG : both14: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/30 02:05:58 DEBUG : both14: Unchanged skipping 2025/07/30 02:05:58 DEBUG : both15: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/30 02:05:58 DEBUG : both15: Unchanged skipping 2025/07/30 02:05:58 DEBUG : both16: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/30 02:05:58 DEBUG : both16: Unchanged skipping 2025/07/30 02:05:58 DEBUG : both17: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/30 02:05:58 DEBUG : both17: Unchanged skipping 2025/07/30 02:05:58 DEBUG : both11: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/30 02:05:58 DEBUG : both11: Unchanged skipping 2025/07/30 02:05:58 DEBUG : both19: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/30 02:05:58 DEBUG : both19: Unchanged skipping 2025/07/30 02:05:58 DEBUG : both1: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/30 02:05:58 DEBUG : both1: Unchanged skipping 2025/07/30 02:05:58 DEBUG : both18: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/30 02:05:58 DEBUG : both18: Unchanged skipping 2025/07/30 02:05:58 DEBUG : both2: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/30 02:05:58 DEBUG : both2: Unchanged skipping 2025/07/30 02:05:58 DEBUG : both3: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/30 02:05:58 DEBUG : both3: Unchanged skipping 2025/07/30 02:05:58 DEBUG : both4: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/30 02:05:58 DEBUG : both4: Unchanged skipping 2025/07/30 02:05:58 DEBUG : both5: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/30 02:05:58 DEBUG : both5: Unchanged skipping 2025/07/30 02:05:58 DEBUG : both6: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/30 02:05:58 DEBUG : both6: Unchanged skipping 2025/07/30 02:05:58 DEBUG : both7: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/30 02:05:58 DEBUG : both7: Unchanged skipping 2025/07/30 02:05:58 DEBUG : both8: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/30 02:05:58 DEBUG : both8: Unchanged skipping 2025/07/30 02:05:59 DEBUG : both9: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/30 02:05:59 DEBUG : both9: Unchanged skipping 2025/07/30 02:05:59 DEBUG : S3 bucket rclone-test-koyowek0qeyo: Waiting for transfers to finish 2025/07/30 02:05:59 DEBUG : Waiting for deletions to finish 2025/07/30 02:05:59 INFO : only3: Deleted 2025/07/30 02:05:59 INFO : only11: Deleted 2025/07/30 02:05:59 INFO : only18: Deleted 2025/07/30 02:05:59 INFO : only13: Deleted 2025/07/30 02:05:59 INFO : only9: Deleted 2025/07/30 02:05:59 INFO : only5: Deleted 2025/07/30 02:05:59 INFO : only6: Deleted 2025/07/30 02:05:59 INFO : only16: Deleted 2025/07/30 02:05:59 INFO : only0: Deleted 2025/07/30 02:05:59 INFO : only19: Deleted 2025/07/30 02:05:59 INFO : only7: Deleted 2025/07/30 02:05:59 INFO : only10: Deleted 2025/07/30 02:05:59 INFO : only15: Deleted 2025/07/30 02:05:59 INFO : only17: Deleted 2025/07/30 02:05:59 INFO : only2: Deleted 2025/07/30 02:05:59 INFO : only4: Deleted 2025/07/30 02:05:59 INFO : only8: Deleted 2025/07/30 02:05:59 INFO : only12: Deleted 2025/07/30 02:05:59 INFO : only14: Deleted 2025/07/30 02:05:59 INFO : only1: Deleted --- PASS: TestSyncConcurrentDelete (26.73s) === RUN TestSyncConcurrentTruncate run.go:180: Remote "S3 bucket rclone-test-koyowek0qeyo", Local "Local file system at /tmp/rclone4044236551", Modify Window "1ns" 2025/07/30 02:06:25 DEBUG : S3 bucket rclone-test-koyowek0qeyo: Waiting for checks to finish 2025/07/30 02:06:25 DEBUG : both0: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/30 02:06:25 DEBUG : both0: Unchanged skipping 2025/07/30 02:06:25 DEBUG : both12: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/30 02:06:25 DEBUG : both12: Unchanged skipping 2025/07/30 02:06:25 DEBUG : both11: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/30 02:06:25 DEBUG : both11: Unchanged skipping 2025/07/30 02:06:25 DEBUG : both10: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/30 02:06:25 DEBUG : both10: Unchanged skipping 2025/07/30 02:06:25 DEBUG : both13: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/30 02:06:25 DEBUG : both13: Unchanged skipping 2025/07/30 02:06:25 DEBUG : both14: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/30 02:06:25 DEBUG : both14: Unchanged skipping 2025/07/30 02:06:25 DEBUG : both16: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/30 02:06:25 DEBUG : both16: Unchanged skipping 2025/07/30 02:06:25 DEBUG : both15: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/30 02:06:25 DEBUG : both15: Unchanged skipping 2025/07/30 02:06:25 DEBUG : both17: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/30 02:06:25 DEBUG : both17: Unchanged skipping 2025/07/30 02:06:25 DEBUG : both18: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/30 02:06:25 DEBUG : both18: Unchanged skipping 2025/07/30 02:06:25 DEBUG : both1: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/30 02:06:25 DEBUG : both1: Unchanged skipping 2025/07/30 02:06:25 DEBUG : both19: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/30 02:06:25 DEBUG : both19: Unchanged skipping 2025/07/30 02:06:25 DEBUG : both2: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/30 02:06:25 DEBUG : both2: Unchanged skipping 2025/07/30 02:06:25 DEBUG : both3: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/30 02:06:25 DEBUG : both3: Unchanged skipping 2025/07/30 02:06:25 DEBUG : both4: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/30 02:06:25 DEBUG : both4: Unchanged skipping 2025/07/30 02:06:25 DEBUG : both5: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/30 02:06:25 DEBUG : both5: Unchanged skipping 2025/07/30 02:06:25 DEBUG : both6: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/30 02:06:25 DEBUG : both6: Unchanged skipping 2025/07/30 02:06:25 DEBUG : only0: Sizes differ (src 0 vs dst 6) 2025/07/30 02:06:25 DEBUG : only10: Sizes differ (src 0 vs dst 6) 2025/07/30 02:06:25 DEBUG : only11: Sizes differ (src 0 vs dst 6) 2025/07/30 02:06:25 DEBUG : only12: Sizes differ (src 0 vs dst 6) 2025/07/30 02:06:25 DEBUG : only13: Sizes differ (src 0 vs dst 6) 2025/07/30 02:06:25 DEBUG : only14: Sizes differ (src 0 vs dst 6) 2025/07/30 02:06:25 DEBUG : only15: Sizes differ (src 0 vs dst 6) 2025/07/30 02:06:25 DEBUG : only16: Sizes differ (src 0 vs dst 6) 2025/07/30 02:06:25 DEBUG : only17: Sizes differ (src 0 vs dst 6) 2025/07/30 02:06:25 DEBUG : only18: Sizes differ (src 0 vs dst 6) 2025/07/30 02:06:25 DEBUG : only19: Sizes differ (src 0 vs dst 6) 2025/07/30 02:06:25 DEBUG : only1: Sizes differ (src 0 vs dst 6) 2025/07/30 02:06:25 DEBUG : only2: Sizes differ (src 0 vs dst 6) 2025/07/30 02:06:25 DEBUG : only3: Sizes differ (src 0 vs dst 6) 2025/07/30 02:06:25 DEBUG : only4: Sizes differ (src 0 vs dst 6) 2025/07/30 02:06:25 DEBUG : only5: Sizes differ (src 0 vs dst 6) 2025/07/30 02:06:25 DEBUG : only6: Sizes differ (src 0 vs dst 6) 2025/07/30 02:06:25 DEBUG : only7: Sizes differ (src 0 vs dst 6) 2025/07/30 02:06:25 DEBUG : only8: Sizes differ (src 0 vs dst 6) 2025/07/30 02:06:25 DEBUG : only9: Sizes differ (src 0 vs dst 6) 2025/07/30 02:06:25 DEBUG : both7: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/30 02:06:25 DEBUG : both7: Unchanged skipping 2025/07/30 02:06:25 DEBUG : both8: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/30 02:06:25 DEBUG : both8: Unchanged skipping 2025/07/30 02:06:25 DEBUG : both9: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/30 02:06:25 DEBUG : both9: Unchanged skipping 2025/07/30 02:06:25 DEBUG : S3 bucket rclone-test-koyowek0qeyo: Waiting for transfers to finish 2025/07/30 02:06:26 DEBUG : only0: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/07/30 02:06:26 INFO : only0: Copied (replaced existing) 2025/07/30 02:06:26 DEBUG : only11: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/07/30 02:06:26 INFO : only11: Copied (replaced existing) 2025/07/30 02:06:26 DEBUG : only12: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/07/30 02:06:26 INFO : only12: Copied (replaced existing) 2025/07/30 02:06:26 DEBUG : only14: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/07/30 02:06:26 INFO : only14: Copied (replaced existing) 2025/07/30 02:06:26 DEBUG : only15: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/07/30 02:06:26 INFO : only15: Copied (replaced existing) 2025/07/30 02:06:26 DEBUG : only13: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/07/30 02:06:26 INFO : only13: Copied (replaced existing) 2025/07/30 02:06:26 DEBUG : only10: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/07/30 02:06:26 INFO : only10: Copied (replaced existing) 2025/07/30 02:06:26 DEBUG : only19: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/07/30 02:06:26 INFO : only19: Copied (replaced existing) 2025/07/30 02:06:26 DEBUG : only18: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/07/30 02:06:26 INFO : only18: Copied (replaced existing) 2025/07/30 02:06:26 DEBUG : only17: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/07/30 02:06:26 INFO : only17: Copied (replaced existing) 2025/07/30 02:06:26 DEBUG : only1: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/07/30 02:06:26 INFO : only1: Copied (replaced existing) 2025/07/30 02:06:26 DEBUG : only2: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/07/30 02:06:26 INFO : only2: Copied (replaced existing) 2025/07/30 02:06:27 DEBUG : only16: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/07/30 02:06:27 INFO : only16: Copied (replaced existing) 2025/07/30 02:06:27 DEBUG : only3: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/07/30 02:06:27 INFO : only3: Copied (replaced existing) 2025/07/30 02:06:27 DEBUG : only4: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/07/30 02:06:27 INFO : only4: Copied (replaced existing) 2025/07/30 02:06:27 DEBUG : only5: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/07/30 02:06:27 INFO : only5: Copied (replaced existing) 2025/07/30 02:06:27 DEBUG : only7: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/07/30 02:06:27 INFO : only7: Copied (replaced existing) 2025/07/30 02:06:27 DEBUG : only6: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/07/30 02:06:27 INFO : only6: Copied (replaced existing) 2025/07/30 02:06:27 DEBUG : only8: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/07/30 02:06:27 INFO : only8: Copied (replaced existing) 2025/07/30 02:06:27 DEBUG : only9: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/07/30 02:06:27 INFO : only9: Copied (replaced existing) 2025/07/30 02:06:27 DEBUG : Waiting for deletions to finish --- PASS: TestSyncConcurrentTruncate (37.89s) === RUN TestSyncReplaceDirModTime run.go:180: Remote "S3 bucket rclone-test-koyowek0qeyo", Local "Local file system at /tmp/rclone4044236551", Modify Window "1ns" 2025/07/30 02:06:45 DEBUG : empty_dir: Making directory with metadata 2025/07/30 02:06:45 INFO : empty_dir: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/07/30 02:06:45 DEBUG : empty_on_remote: Making directory with metadata 2025/07/30 02:06:45 INFO : empty_on_remote: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/07/30 02:06:45 INFO : empty_on_remote: Making directory 2025/07/30 02:06:45 INFO : empty_on_remote: Made directory with modification time 2011-12-25 12:59:59.123456789 +0000 UTC 2025/07/30 02:06:46 INFO : test_dir1: Set directory modification time (using DirSetModTime) 2025/07/30 02:06:46 INFO : test_dir2: Set directory modification time (using DirSetModTime) 2025/07/30 02:06:46 INFO : test_dir2/sub_dir: Set directory modification time (using DirSetModTime) 2025/07/30 02:06:46 INFO : empty_on_remote: Set directory modification time (using DirSetModTime) --- PASS: TestSyncReplaceDirModTime (3.16s) === RUN TestTransform === RUN TestTransform/NFC run.go:180: Remote "S3 bucket rclone-test-koyowek0qeyo", Local "Local file system at /tmp/rclone4044236551", Modify Window "1ns" 2025/07/30 02:06:54 DEBUG : Waiting for deletions to finish 2025/07/30 02:06:54 DEBUG : apple: Excluded (Path Filter) 2025/07/30 02:06:54 DEBUG : appleappleapplebanana: Excluded (Path Filter) 2025/07/30 02:06:54 DEBUG : banana: Excluded (Path Filter) 2025/07/30 02:06:54 DEBUG : dir1/0000-abcdefg.txt: Excluded (Path Filter) 2025/07/30 02:06:54 DEBUG : dir1/0001-bcdefgh.txt: Excluded (Path Filter) 2025/07/30 02:06:54 DEBUG : dir1/0002-cdefghi.txt: Excluded (Path Filter) 2025/07/30 02:06:54 DEBUG : dir1/0003-defghij.txt: Excluded (Path Filter) 2025/07/30 02:06:54 DEBUG : dir1/0004-efghijk.txt: Excluded (Path Filter) 2025/07/30 02:06:54 DEBUG : dir1/0005-fghijkl.txt: Excluded (Path Filter) 2025/07/30 02:06:54 DEBUG : dir1/0006-ghijklm.txt: Excluded (Path Filter) 2025/07/30 02:06:54 DEBUG : dir1/0007-1234567.txt: Excluded (Path Filter) 2025/07/30 02:06:54 DEBUG : dir1/0008-2345678.txt: Excluded (Path Filter) 2025/07/30 02:06:54 DEBUG : dir1/0009-3456789.txt: Excluded (Path Filter) 2025/07/30 02:06:54 DEBUG : dir1/0010-456789.txt: Excluded (Path Filter) 2025/07/30 02:06:54 DEBUG : dir1/0011-56789;.txt: Excluded (Path Filter) 2025/07/30 02:06:54 DEBUG : dir1/0012-6789;.txt: Excluded (Path Filter) 2025/07/30 02:06:54 DEBUG : dir1/0013-789;=.txt: Excluded (Path Filter) 2025/07/30 02:06:54 DEBUG : dir1/0014-89;=.txt: Excluded (Path Filter) 2025/07/30 02:06:54 DEBUG : dir1/0015-9;=.txt: Excluded (Path Filter) 2025/07/30 02:06:54 DEBUG : splitbananasplit: Excluded (Path Filter) 2025/07/30 02:06:57 DEBUG : S3 bucket rclone-test-koyowek0qeyo: Waiting for checks to finish 2025/07/30 02:06:57 DEBUG : dir1/0001-bcdefgh.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/30 02:06:57 DEBUG : dir1/0001-bcdefgh.txt: Unchanged skipping 2025/07/30 02:06:57 DEBUG : splitbananasplit: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/30 02:06:57 DEBUG : splitbananasplit: Unchanged skipping 2025/07/30 02:06:57 DEBUG : dir1/0000-abcdefg.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/30 02:06:57 DEBUG : dir1/0000-abcdefg.txt: Unchanged skipping 2025/07/30 02:06:57 DEBUG : dir1/0002-cdefghi.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/30 02:06:57 DEBUG : dir1/0002-cdefghi.txt: Unchanged skipping 2025/07/30 02:06:57 DEBUG : appleappleapplebanana: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/30 02:06:57 DEBUG : appleappleapplebanana: Unchanged skipping 2025/07/30 02:06:57 DEBUG : apple: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/30 02:06:57 DEBUG : apple: Unchanged skipping 2025/07/30 02:06:57 DEBUG : banana: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/30 02:06:57 DEBUG : banana: Unchanged skipping 2025/07/30 02:06:57 DEBUG : dir1/0007-1234567.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/30 02:06:57 DEBUG : dir1/0007-1234567.txt: Unchanged skipping 2025/07/30 02:06:57 DEBUG : dir1/0006-ghijklm.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/30 02:06:57 DEBUG : dir1/0006-ghijklm.txt: Unchanged skipping 2025/07/30 02:06:57 DEBUG : dir1/0005-fghijkl.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/30 02:06:57 DEBUG : dir1/0005-fghijkl.txt: Unchanged skipping 2025/07/30 02:06:57 DEBUG : dir1/0004-efghijk.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/30 02:06:57 DEBUG : dir1/0004-efghijk.txt: Unchanged skipping 2025/07/30 02:06:57 DEBUG : dir1/0003-defghij.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/30 02:06:57 DEBUG : dir1/0003-defghij.txt: Unchanged skipping 2025/07/30 02:06:57 DEBUG : dir1/0008-2345678.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/30 02:06:57 DEBUG : dir1/0008-2345678.txt: Unchanged skipping 2025/07/30 02:06:57 DEBUG : dir1/0009-3456789.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/30 02:06:57 DEBUG : dir1/0009-3456789.txt: Unchanged skipping 2025/07/30 02:06:58 DEBUG : dir1/0011-56789;.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/30 02:06:58 DEBUG : dir1/0012-6789;.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/30 02:06:58 DEBUG : dir1/0011-56789;.txt: Unchanged skipping 2025/07/30 02:06:58 DEBUG : dir1/0012-6789;.txt: Unchanged skipping 2025/07/30 02:06:58 DEBUG : dir1/0013-789;=.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/30 02:06:58 DEBUG : dir1/0013-789;=.txt: Unchanged skipping 2025/07/30 02:06:58 DEBUG : dir1/0014-89;=.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/30 02:06:58 DEBUG : dir1/0014-89;=.txt: Unchanged skipping 2025/07/30 02:06:58 DEBUG : dir1/0010-456789.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/30 02:06:58 DEBUG : dir1/0010-456789.txt: Unchanged skipping 2025/07/30 02:06:58 DEBUG : dir1/0015-9;=.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/30 02:06:58 DEBUG : dir1/0015-9;=.txt: Unchanged skipping 2025/07/30 02:06:58 DEBUG : S3 bucket rclone-test-koyowek0qeyo: Waiting for transfers to finish 2025/07/30 02:06:58 DEBUG : Waiting for deletions to finish 2025/07/30 02:06:58 INFO : There was nothing to transfer 2025/07/30 02:06:58 DEBUG : Waiting for deletions to finish 2025/07/30 02:06:58 DEBUG : apple: Excluded (Path Filter) 2025/07/30 02:06:58 DEBUG : appleappleapplebanana: Excluded (Path Filter) 2025/07/30 02:06:58 DEBUG : banana: Excluded (Path Filter) 2025/07/30 02:06:58 DEBUG : dir1/0000-abcdefg.txt: Excluded (Path Filter) 2025/07/30 02:06:58 DEBUG : dir1/0001-bcdefgh.txt: Excluded (Path Filter) 2025/07/30 02:06:58 DEBUG : dir1/0002-cdefghi.txt: Excluded (Path Filter) 2025/07/30 02:06:58 DEBUG : dir1/0003-defghij.txt: Excluded (Path Filter) 2025/07/30 02:06:58 DEBUG : dir1/0004-efghijk.txt: Excluded (Path Filter) 2025/07/30 02:06:58 DEBUG : dir1/0005-fghijkl.txt: Excluded (Path Filter) 2025/07/30 02:06:58 DEBUG : dir1/0006-ghijklm.txt: Excluded (Path Filter) 2025/07/30 02:06:58 DEBUG : dir1/0007-1234567.txt: Excluded (Path Filter) 2025/07/30 02:06:58 DEBUG : dir1/0008-2345678.txt: Excluded (Path Filter) 2025/07/30 02:06:58 DEBUG : dir1/0009-3456789.txt: Excluded (Path Filter) 2025/07/30 02:06:58 DEBUG : dir1/0010-456789.txt: Excluded (Path Filter) 2025/07/30 02:06:58 DEBUG : dir1/0011-56789;.txt: Excluded (Path Filter) 2025/07/30 02:06:58 DEBUG : dir1/0012-6789;.txt: Excluded (Path Filter) 2025/07/30 02:06:58 DEBUG : dir1/0013-789;=.txt: Excluded (Path Filter) 2025/07/30 02:06:58 DEBUG : dir1/0014-89;=.txt: Excluded (Path Filter) 2025/07/30 02:06:58 DEBUG : dir1/0015-9;=.txt: Excluded (Path Filter) 2025/07/30 02:06:58 DEBUG : splitbananasplit: Excluded (Path Filter) 2025/07/30 02:06:58 DEBUG : S3 bucket rclone-test-koyowek0qeyo: Waiting for checks to finish 2025/07/30 02:06:58 DEBUG : apple: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/30 02:06:58 DEBUG : apple: Unchanged skipping 2025/07/30 02:06:58 DEBUG : dir1/0001-bcdefgh.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/30 02:06:58 DEBUG : dir1/0001-bcdefgh.txt: Unchanged skipping 2025/07/30 02:06:58 DEBUG : dir1/0002-cdefghi.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/30 02:06:58 DEBUG : dir1/0002-cdefghi.txt: Unchanged skipping 2025/07/30 02:06:58 DEBUG : dir1/0000-abcdefg.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/30 02:06:58 DEBUG : dir1/0000-abcdefg.txt: Unchanged skipping 2025/07/30 02:06:58 DEBUG : dir1/0003-defghij.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/30 02:06:58 DEBUG : dir1/0003-defghij.txt: Unchanged skipping 2025/07/30 02:06:58 DEBUG : splitbananasplit: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/30 02:06:58 DEBUG : splitbananasplit: Unchanged skipping 2025/07/30 02:06:58 DEBUG : banana: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/30 02:06:58 DEBUG : banana: Unchanged skipping 2025/07/30 02:06:58 DEBUG : dir1/0004-efghijk.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/30 02:06:58 DEBUG : dir1/0004-efghijk.txt: Unchanged skipping 2025/07/30 02:06:58 DEBUG : dir1/0005-fghijkl.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/30 02:06:58 DEBUG : dir1/0005-fghijkl.txt: Unchanged skipping 2025/07/30 02:06:58 DEBUG : dir1/0006-ghijklm.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/30 02:06:58 DEBUG : dir1/0006-ghijklm.txt: Unchanged skipping 2025/07/30 02:06:58 DEBUG : dir1/0007-1234567.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/30 02:06:58 DEBUG : dir1/0007-1234567.txt: Unchanged skipping 2025/07/30 02:06:58 DEBUG : dir1/0010-456789.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/30 02:06:58 DEBUG : dir1/0010-456789.txt: Unchanged skipping 2025/07/30 02:06:58 DEBUG : dir1/0009-3456789.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/30 02:06:58 DEBUG : dir1/0009-3456789.txt: Unchanged skipping 2025/07/30 02:06:58 DEBUG : dir1/0011-56789;.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/30 02:06:58 DEBUG : dir1/0011-56789;.txt: Unchanged skipping 2025/07/30 02:06:58 DEBUG : dir1/0012-6789;.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/30 02:06:58 DEBUG : dir1/0012-6789;.txt: Unchanged skipping 2025/07/30 02:06:58 DEBUG : dir1/0013-789;=.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/30 02:06:58 DEBUG : dir1/0013-789;=.txt: Unchanged skipping 2025/07/30 02:06:58 DEBUG : dir1/0015-9;=.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/30 02:06:58 DEBUG : dir1/0015-9;=.txt: Unchanged skipping 2025/07/30 02:06:58 DEBUG : appleappleapplebanana: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/30 02:06:58 DEBUG : appleappleapplebanana: Unchanged skipping 2025/07/30 02:06:58 DEBUG : dir1/0014-89;=.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/30 02:06:58 DEBUG : dir1/0014-89;=.txt: Unchanged skipping 2025/07/30 02:06:58 DEBUG : dir1/0008-2345678.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/30 02:06:58 DEBUG : dir1/0008-2345678.txt: Unchanged skipping 2025/07/30 02:06:58 DEBUG : S3 bucket rclone-test-koyowek0qeyo: Waiting for transfers to finish 2025/07/30 02:06:58 DEBUG : Waiting for deletions to finish 2025/07/30 02:06:58 INFO : There was nothing to transfer 2025/07/30 02:06:58 DEBUG : Waiting for deletions to finish 2025/07/30 02:06:59 DEBUG : apple: Excluded (Path Filter) 2025/07/30 02:06:59 DEBUG : appleappleapplebanana: Excluded (Path Filter) 2025/07/30 02:06:59 DEBUG : banana: Excluded (Path Filter) 2025/07/30 02:06:59 DEBUG : dir1/0000-abcdefg.txt: Excluded (Path Filter) 2025/07/30 02:06:59 DEBUG : dir1/0001-bcdefgh.txt: Excluded (Path Filter) 2025/07/30 02:06:59 DEBUG : dir1/0002-cdefghi.txt: Excluded (Path Filter) 2025/07/30 02:06:59 DEBUG : dir1/0003-defghij.txt: Excluded (Path Filter) 2025/07/30 02:06:59 DEBUG : dir1/0004-efghijk.txt: Excluded (Path Filter) 2025/07/30 02:06:59 DEBUG : dir1/0005-fghijkl.txt: Excluded (Path Filter) 2025/07/30 02:06:59 DEBUG : dir1/0006-ghijklm.txt: Excluded (Path Filter) 2025/07/30 02:06:59 DEBUG : dir1/0007-1234567.txt: Excluded (Path Filter) 2025/07/30 02:06:59 DEBUG : dir1/0008-2345678.txt: Excluded (Path Filter) 2025/07/30 02:06:59 DEBUG : dir1/0009-3456789.txt: Excluded (Path Filter) 2025/07/30 02:06:59 DEBUG : dir1/0010-456789.txt: Excluded (Path Filter) 2025/07/30 02:06:59 DEBUG : dir1/0011-56789;.txt: Excluded (Path Filter) 2025/07/30 02:06:59 DEBUG : dir1/0012-6789;.txt: Excluded (Path Filter) 2025/07/30 02:06:59 DEBUG : dir1/0013-789;=.txt: Excluded (Path Filter) 2025/07/30 02:06:59 DEBUG : dir1/0014-89;=.txt: Excluded (Path Filter) 2025/07/30 02:06:59 DEBUG : dir1/0015-9;=.txt: Excluded (Path Filter) 2025/07/30 02:06:59 DEBUG : splitbananasplit: Excluded (Path Filter) --- PASS: TestTransform (17.00s) --- PASS: TestTransform/NFC (17.00s) PASS 2025/07/30 02:07:05 DEBUG : S3 bucket rclone-test-koyowek0qeyo: Purge remote 2025/07/30 02:07:05 DEBUG : S3 bucket rclone-test-koyowek0qeyo: bucket is versioned: false 2025/07/30 02:07:05 DEBUG : Waiting for deletions to finish 2025/07/30 02:07:08 INFO : S3 bucket rclone-test-koyowek0qeyo: Bucket "rclone-test-koyowek0qeyo" deleted "./sync.test -test.v -test.timeout 1h0m0s -remote TestS3Wasabi: -verbose -fast-list -test.run '^(TestCopy|TestCopyDeleteBefore|TestServerSideMove|TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors|TestSyncConcurrentDelete|TestSyncConcurrentTruncate|TestSyncDeleteBefore|TestSyncDeleteDuring|TestSyncReplaceDirModTime)$|^TestTransform$/^NFC$'" - Finished OK in 1m53.626817226s (try 2/5)