"./sync.test -test.v -test.timeout 1h0m0s -remote TestYandex: -verbose -test.run '^(TestServerSideCopy|TestSyncAfterRemovingAFileAndAddingAFileSubDir|TestSyncConcurrentDelete)$'" - Starting (try 2/5) 2025/02/17 02:58:50 DEBUG : Creating backend with remote "TestYandex:rclone-test-hatobom3pexo" 2025/02/17 02:58:50 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/02/17 02:58:51 DEBUG : Creating backend with remote "/tmp/rclone3470950312" === RUN TestServerSideCopy run.go:180: Remote "Yandex rclone-test-hatobom3pexo", Local "Local file system at /tmp/rclone3470950312", Modify Window "1ns" 2025/02/17 02:58:54 DEBUG : Creating backend with remote "TestYandex:rclone-test-teralam1kake" sync_test.go:590: Server side copy (if possible) Yandex rclone-test-hatobom3pexo -> Yandex rclone-test-teralam1kake 2025/02/17 02:58:55 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/02/17 02:58:55 DEBUG : Yandex rclone-test-teralam1kake: Waiting for checks to finish 2025/02/17 02:58:55 DEBUG : Yandex rclone-test-teralam1kake: Waiting for transfers to finish 2025/02/17 02:58:58 DEBUG : sub dir/hello world: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2025/02/17 02:58:58 INFO : sub dir/hello world: Copied (server-side copy) run.go:180: Remote "Yandex rclone-test-hatobom3pexo", Local "Local file system at /tmp/rclone3470950312", Modify Window "1ns" 2025/02/17 02:58:59 DEBUG : Yandex rclone-test-teralam1kake: Purge remote 2025/02/17 02:59:15 DEBUG : pacer: low level retry 1/10 (error [500 - InternalServerError] Internal Server Error (Ошибка сервера.)) 2025/02/17 02:59:15 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2025/02/17 02:59:16 DEBUG : pacer: Reducing sleep to 15ms --- PASS: TestServerSideCopy (24.87s) === RUN TestSyncAfterRemovingAFileAndAddingAFileSubDir run.go:180: Remote "Yandex rclone-test-hatobom3pexo", Local "Local file system at /tmp/rclone3470950312", Modify Window "1ns" 2025/02/17 02:59:16 DEBUG : pacer: Reducing sleep to 11.25ms 2025/02/17 02:59:16 DEBUG : pacer: Reducing sleep to 10ms 2025/02/17 02:59:19 INFO : d: Making directory 2025/02/17 02:59:20 INFO : d/e: Making directory 2025/02/17 02:59:22 DEBUG : a/potato2: Need to transfer - File not found at Destination 2025/02/17 02:59:22 DEBUG : c/non empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/02/17 02:59:22 DEBUG : c/non empty space: Unchanged skipping 2025/02/17 02:59:22 DEBUG : Yandex rclone-test-hatobom3pexo: Waiting for checks to finish 2025/02/17 02:59:22 DEBUG : Yandex rclone-test-hatobom3pexo: Waiting for transfers to finish 2025/02/17 02:59:27 DEBUG : a/potato2: md5 = d6548b156ea68a4e003e786df99eee76 OK 2025/02/17 02:59:27 INFO : a/potato2: Copied (new) 2025/02/17 02:59:27 DEBUG : Waiting for deletions to finish 2025/02/17 02:59:31 INFO : b/potato: Deleted 2025/02/17 02:59:31 INFO : d/e: Removing directory 2025/02/17 02:59:35 INFO : d: Removing directory 2025/02/17 02:59:39 INFO : b: Removing directory 2025/02/17 02:59:44 DEBUG : Yandex rclone-test-hatobom3pexo: deleted 3 directories run.go:180: Remote "Yandex rclone-test-hatobom3pexo", Local "Local file system at /tmp/rclone3470950312", Modify Window "1ns" --- PASS: TestSyncAfterRemovingAFileAndAddingAFileSubDir (46.09s) === RUN TestSyncConcurrentDelete run.go:180: Remote "Yandex rclone-test-hatobom3pexo", Local "Local file system at /tmp/rclone3470950312", Modify Window "1ns" 2025/02/17 03:00:18 DEBUG : pacer: low level retry 1/10 (error [500 - InternalServerError] Internal Server Error (Ошибка сервера.)) 2025/02/17 03:00:18 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2025/02/17 03:00:18 DEBUG : pacer: Reducing sleep to 15ms 2025/02/17 03:00:19 DEBUG : pacer: Reducing sleep to 11.25ms 2025/02/17 03:00:19 DEBUG : pacer: Reducing sleep to 10ms fstest.go:121: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:121 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:126 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:145 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:189 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:302 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:344 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:340 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2744 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2758 Error: Should be true Test: TestSyncConcurrentDelete Messages: both3: Modification time difference too big |-210718h55m8.500000001s| > 1ns (want 2001-02-03 04:05:06.499999999 +0000 UTC vs got 2025-02-17 03:00:15 +0000 UTC) (precision 1ns) 2025/02/17 03:01:21 DEBUG : both0: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/02/17 03:01:21 DEBUG : both1: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/02/17 03:01:21 DEBUG : both10: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/02/17 03:01:21 DEBUG : both11: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/02/17 03:01:21 DEBUG : Yandex rclone-test-hatobom3pexo: Waiting for checks to finish 2025/02/17 03:01:21 DEBUG : both0: Unchanged skipping 2025/02/17 03:01:21 DEBUG : both12: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/02/17 03:01:21 DEBUG : both12: Unchanged skipping 2025/02/17 03:01:21 DEBUG : both13: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/02/17 03:01:21 DEBUG : both13: Unchanged skipping 2025/02/17 03:01:21 DEBUG : both14: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/02/17 03:01:21 DEBUG : both1: Unchanged skipping 2025/02/17 03:01:21 DEBUG : both15: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/02/17 03:01:21 DEBUG : both15: Unchanged skipping 2025/02/17 03:01:21 DEBUG : both16: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/02/17 03:01:21 DEBUG : both16: Unchanged skipping 2025/02/17 03:01:21 DEBUG : both17: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/02/17 03:01:21 DEBUG : both17: Unchanged skipping 2025/02/17 03:01:21 DEBUG : both18: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/02/17 03:01:21 DEBUG : both18: Unchanged skipping 2025/02/17 03:01:21 DEBUG : both19: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/02/17 03:01:21 DEBUG : both19: Unchanged skipping 2025/02/17 03:01:21 DEBUG : both2: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/02/17 03:01:21 DEBUG : both2: Unchanged skipping 2025/02/17 03:01:21 DEBUG : both3: Modification times differ by 210718h55m8.500000001s: 2001-02-03 04:05:06.499999999 +0000 UTC, 2025-02-17 03:00:15 +0000 UTC 2025/02/17 03:01:21 DEBUG : both10: Unchanged skipping 2025/02/17 03:01:21 DEBUG : both4: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/02/17 03:01:21 DEBUG : both4: Unchanged skipping 2025/02/17 03:01:21 DEBUG : both5: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/02/17 03:01:21 DEBUG : both5: Unchanged skipping 2025/02/17 03:01:21 DEBUG : both6: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/02/17 03:01:21 DEBUG : both6: Unchanged skipping 2025/02/17 03:01:21 DEBUG : both7: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/02/17 03:01:21 DEBUG : both7: Unchanged skipping 2025/02/17 03:01:21 DEBUG : both8: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/02/17 03:01:21 DEBUG : both8: Unchanged skipping 2025/02/17 03:01:21 DEBUG : both9: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/02/17 03:01:21 DEBUG : both9: Unchanged skipping 2025/02/17 03:01:21 DEBUG : both3: md5 = 8ee2027983915ec78acc45027d874316 OK 2025/02/17 03:01:21 DEBUG : both11: Unchanged skipping 2025/02/17 03:01:21 DEBUG : both14: Unchanged skipping 2025/02/17 03:01:22 INFO : both3: Updated modification time in destination 2025/02/17 03:01:22 DEBUG : both3: Unchanged skipping 2025/02/17 03:01:22 DEBUG : Yandex rclone-test-hatobom3pexo: Waiting for transfers to finish 2025/02/17 03:01:22 DEBUG : Waiting for deletions to finish 2025/02/17 03:01:25 INFO : only13: Deleted 2025/02/17 03:01:25 INFO : only17: Deleted 2025/02/17 03:01:25 INFO : only1: Deleted 2025/02/17 03:01:25 INFO : only10: Deleted 2025/02/17 03:01:28 INFO : only5: Deleted 2025/02/17 03:01:28 INFO : only9: Deleted 2025/02/17 03:01:28 INFO : only11: Deleted 2025/02/17 03:01:28 INFO : only7: Deleted 2025/02/17 03:01:32 INFO : only4: Deleted 2025/02/17 03:01:32 INFO : only19: Deleted 2025/02/17 03:01:32 INFO : only16: Deleted 2025/02/17 03:01:32 INFO : only2: Deleted 2025/02/17 03:01:35 INFO : only8: Deleted 2025/02/17 03:01:35 INFO : only0: Deleted 2025/02/17 03:01:36 INFO : only12: Deleted 2025/02/17 03:01:36 INFO : only14: Deleted 2025/02/17 03:01:38 INFO : only15: Deleted 2025/02/17 03:01:38 INFO : only18: Deleted 2025/02/17 03:01:39 INFO : only3: Deleted 2025/02/17 03:01:39 INFO : only6: Deleted run.go:180: Remote "Yandex rclone-test-hatobom3pexo", Local "Local file system at /tmp/rclone3470950312", Modify Window "1ns" --- FAIL: TestSyncConcurrentDelete (167.97s) FAIL 2025/02/17 03:02:50 DEBUG : Yandex rclone-test-hatobom3pexo: Purge remote "./sync.test -test.v -test.timeout 1h0m0s -remote TestYandex: -verbose -test.run '^(TestServerSideCopy|TestSyncAfterRemovingAFileAndAddingAFileSubDir|TestSyncConcurrentDelete)$'" - Finished ERROR in 4m3.375104265s (try 2/5): exit status 1: Failed [TestSyncConcurrentDelete]