"./sync.test -test.v -test.timeout 1h0m0s -remote TestChunkerChunk50bYandex: -verbose -fast-list -size-limit 1024 -test.run '^(TestServerSideMoveDeleteEmptySourceDirs|TestSyncAfterChangingFilesSizeOnly|TestSyncCopyDest)$'" - Starting (try 2/5) 2025/03/29 05:27:27 DEBUG : Creating backend with remote "TestChunkerChunk50bYandex:rclone-test-jevoteg8civi" 2025/03/29 05:27:27 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/03/29 05:27:27 DEBUG : Creating backend with remote "TestYandex:rclone-test-jevoteg8civi" 2025/03/29 05:27:27 DEBUG : Reset feature "ListR" 2025/03/29 05:27:27 DEBUG : Creating backend with remote "/tmp/rclone3702169179" === RUN TestSyncAfterChangingFilesSizeOnly run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-jevoteg8civi'", Local "Local file system at /tmp/rclone3702169179", Modify Window "1ns" 2025/03/29 05:27:35 INFO : potato.rclone_chunk.001_vjffe0: Moved (server-side) to: potato.rclone_chunk.001 2025/03/29 05:27:36 INFO : potato.rclone_chunk.002_vjffe0: Moved (server-side) to: potato.rclone_chunk.002 2025/03/29 05:27:39 DEBUG : potato: Sizes differ (src 21 vs dst 60) 2025/03/29 05:27:39 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-jevoteg8civi': Waiting for checks to finish 2025/03/29 05:27:39 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-jevoteg8civi': Waiting for transfers to finish 2025/03/29 05:27:40 DEBUG : potato: skip slow MD5 on source file, hashing in-transit 2025/03/29 05:27:57 INFO : potato: Deleted 2025/03/29 05:27:58 INFO : potato.rclone_chunk.001_vjfr2v: Moved (server-side) to: potato 2025/03/29 05:27:58 DEBUG : potato: md5 = 100defcf18c42a1e0dc42a789b107cd2 OK 2025/03/29 05:27:58 INFO : potato: Copied (replaced existing) 2025/03/29 05:27:58 DEBUG : Waiting for deletions to finish run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-jevoteg8civi'", Local "Local file system at /tmp/rclone3702169179", Modify Window "1ns" --- PASS: TestSyncAfterChangingFilesSizeOnly (36.44s) === RUN TestServerSideMoveDeleteEmptySourceDirs run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-jevoteg8civi'", Local "Local file system at /tmp/rclone3702169179", Modify Window "1ns" 2025/03/29 05:28:04 DEBUG : Creating backend with remote "TestChunkerChunk50bYandex:rclone-test-wakobid2kime" 2025/03/29 05:28:04 DEBUG : Creating backend with remote "TestYandex:rclone-test-wakobid2kime" 2025/03/29 05:28:04 DEBUG : Reset feature "ListR" 2025/03/29 05:28:11 INFO : potato2.rclone_chunk.001_vjgf1r: Moved (server-side) to: potato2.rclone_chunk.001 2025/03/29 05:28:13 INFO : potato2.rclone_chunk.002_vjgf1r: Moved (server-side) to: potato2.rclone_chunk.002 2025/03/29 05:28:20 INFO : empty space.rclone_chunk.001_vjgrym: Moved (server-side) to: empty space 2025/03/29 05:28:28 INFO : potato3.rclone_chunk.001_vjgwqh: Moved (server-side) to: potato3.rclone_chunk.001 2025/03/29 05:28:29 INFO : potato3.rclone_chunk.002_vjgwqh: Moved (server-side) to: potato3.rclone_chunk.002 2025/03/29 05:28:31 INFO : tomatoDir: Making directory sync_test.go:1705: Server side move (if possible) Chunked 'TestChunkerChunk50bYandex:rclone-test-jevoteg8civi' -> Chunked 'TestChunkerChunk50bYandex:rclone-test-wakobid2kime' 2025/03/29 05:28:39 INFO : empty space.rclone_chunk.001_vjh8hs: Moved (server-side) to: empty space 2025/03/29 05:28:44 INFO : potato3.rclone_chunk.001_vjhed5: Moved (server-side) to: potato3.rclone_chunk.001 2025/03/29 05:28:46 INFO : potato3.rclone_chunk.002_vjhed5: Moved (server-side) to: potato3.rclone_chunk.002 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:350 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1710 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1901 Error: Should be true Test: TestServerSideMoveDeleteEmptySourceDirs Messages: potato3: Modification time difference too big |-211681h23m41.500000001s| > 1ns (want 2001-02-03 04:05:06.499999999 +0000 UTC vs got 2025-03-29 05:28:48 +0000 UTC) (precision 1ns) 2025/03/29 05:28:50 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-wakobid2kime': Using server-side directory move 2025/03/29 05:28:50 INFO : Chunked 'TestChunkerChunk50bYandex:rclone-test-wakobid2kime': Server side directory move failed - fallback to file moves: can't copy directory - destination already exists 2025/03/29 05:28:51 DEBUG : potato2: Need to transfer - File not found at Destination 2025/03/29 05:28:51 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/03/29 05:28:51 DEBUG : empty space: Unchanged skipping 2025/03/29 05:28:51 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2025/03/29 05:28:51 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-wakobid2kime': Waiting for checks to finish 2025/03/29 05:28:51 DEBUG : potato2: move 2 data chunks... 2025/03/29 05:28:53 INFO : potato2.rclone_chunk.001: Moved (server-side) 2025/03/29 05:28:55 INFO : empty space: Deleted 2025/03/29 05:28:55 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-wakobid2kime': Waiting for transfers to finish 2025/03/29 05:28:55 INFO : potato2.rclone_chunk.002: Moved (server-side) 2025/03/29 05:28:57 INFO : potato2: Moved (server-side) 2025/03/29 05:28:59 INFO : potato2: Moved (server-side) 2025/03/29 05:29:03 INFO : potato3: Deleted 2025/03/29 05:29:04 DEBUG : potato3: move 2 data chunks... 2025/03/29 05:29:06 INFO : potato3.rclone_chunk.001: Moved (server-side) 2025/03/29 05:29:07 INFO : potato3.rclone_chunk.002: Moved (server-side) 2025/03/29 05:29:08 INFO : potato3: Moved (server-side) 2025/03/29 05:29:11 INFO : potato3: Moved (server-side) 2025/03/29 05:29:11 INFO : tomatoDir: Removing directory 2025/03/29 05:29:15 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-jevoteg8civi': deleted 1 directories 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:350 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1729 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1901 Error: Should be true Test: TestServerSideMoveDeleteEmptySourceDirs Messages: potato3: Modification time difference too big |-116224h29m10.876543211s| > 1ns (want 2011-12-25 12:59:59.123456789 +0000 UTC vs got 2025-03-29 05:29:10 +0000 UTC) (precision 1ns) 2025/03/29 05:29:17 DEBUG : Creating backend with remote "TestChunkerChunk50bYandex:rclone-test-lecamit2kene" 2025/03/29 05:29:17 DEBUG : Creating backend with remote "TestYandex:rclone-test-lecamit2kene" 2025/03/29 05:29:17 DEBUG : Reset feature "ListR" 2025/03/29 05:29:17 INFO : tomatoDir: Making directory 2025/03/29 05:29:18 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-lecamit2kene': Using server-side directory move 2025/03/29 05:29:22 INFO : Chunked 'TestChunkerChunk50bYandex:rclone-test-lecamit2kene': Server side directory move succeeded 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:350 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1752 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1901 Error: Should be true Test: TestServerSideMoveDeleteEmptySourceDirs Messages: potato3: Modification time difference too big |-116224h29m10.876543211s| > 1ns (want 2011-12-25 12:59:59.123456789 +0000 UTC vs got 2025-03-29 05:29:10 +0000 UTC) (precision 1ns) 2025/03/29 05:29:24 ERROR : : error listing: directory not found 2025/03/29 05:29:24 ERROR : : error listing: directory not found 2025/03/29 05:29:24 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-lecamit2kene': Purge remote 2025/03/29 05:29:35 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-wakobid2kime': Purge remote 2025/03/29 05:29:35 NOTICE: purge failed: [404 - DiskNotFoundError] Resource not found. (Не удалось найти запрошенный ресурс.) --- FAIL: TestServerSideMoveDeleteEmptySourceDirs (92.20s) === RUN TestSyncCopyDest run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-jevoteg8civi'", Local "Local file system at /tmp/rclone3702169179", Modify Window "1ns" 2025/03/29 05:29:36 DEBUG : Creating backend with remote "TestChunkerChunk50bYandex:rclone-test-jevoteg8civi/dst" 2025/03/29 05:29:36 DEBUG : Creating backend with remote "TestYandex:rclone-test-jevoteg8civi/dst" 2025/03/29 05:29:37 DEBUG : Reset feature "ListR" 2025/03/29 05:29:37 DEBUG : Creating backend with remote "TestChunkerChunk50bYandex:rclone-test-jevoteg8civi/CopyDest" 2025/03/29 05:29:37 DEBUG : Creating backend with remote "TestYandex:rclone-test-jevoteg8civi/CopyDest" 2025/03/29 05:29:37 DEBUG : Reset feature "ListR" 2025/03/29 05:29:37 DEBUG : one: Need to transfer - File not found at Destination 2025/03/29 05:29:37 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-jevoteg8civi/dst': Waiting for checks to finish 2025/03/29 05:29:37 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-jevoteg8civi/dst': Waiting for transfers to finish 2025/03/29 05:29:38 DEBUG : one: skip slow MD5 on source file, hashing in-transit 2025/03/29 05:29:44 INFO : one.rclone_chunk.001_vjj12o: Moved (server-side) to: one 2025/03/29 05:29:44 DEBUG : one: md5 = f97c5d29941bfb1b2fdab0874906ab82 OK 2025/03/29 05:29:44 INFO : one: Copied (new) 2025/03/29 05:29:44 DEBUG : Waiting for deletions to finish 2025/03/29 05:29:45 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/03/29 05:29:45 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-jevoteg8civi/dst': Waiting for checks to finish 2025/03/29 05:29:45 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-jevoteg8civi/dst': Waiting for transfers to finish 2025/03/29 05:29:45 DEBUG : one: skip slow MD5 on source file, hashing in-transit 2025/03/29 05:29:53 INFO : one: Deleted 2025/03/29 05:29:54 INFO : one.rclone_chunk.001_vjj8j8: Moved (server-side) to: one 2025/03/29 05:29:54 DEBUG : one: md5 = 07912d142f5d63ee918b34796b5a2432 OK 2025/03/29 05:29:54 INFO : one: Copied (replaced existing) 2025/03/29 05:29:54 DEBUG : Waiting for deletions to finish 2025/03/29 05:30:04 INFO : dst/one: Deleted 2025/03/29 05:30:06 INFO : dst/one.rclone_chunk.001_vjjjmw: Moved (server-side) to: dst/one 2025/03/29 05:30:12 INFO : CopyDest/one.rclone_chunk.001_vjjt0i: Moved (server-side) to: CopyDest/one 2025/03/29 05:30:13 DEBUG : Creating backend with remote "TestChunkerChunk50bYandex:rclone-test-jevoteg8civi/BackupDir" 2025/03/29 05:30:13 DEBUG : Creating backend with remote "TestYandex:rclone-test-jevoteg8civi/BackupDir" 2025/03/29 05:30:14 DEBUG : Reset feature "ListR" 2025/03/29 05:30:14 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/03/29 05:30:14 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-jevoteg8civi/dst': Waiting for checks to finish 2025/03/29 05:30:15 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/03/29 05:30:15 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/03/29 05:30:15 DEBUG : one: move non-chunked object... 2025/03/29 05:30:17 INFO : one: Moved (server-side) 2025/03/29 05:30:17 INFO : one: Moved (server-side) 2025/03/29 05:30:17 DEBUG : one: copy non-chunked object... 2025/03/29 05:30:18 DEBUG : one: md5 = 07912d142f5d63ee918b34796b5a2432 OK 2025/03/29 05:30:18 INFO : one: Copied (server-side copy) 2025/03/29 05:30:18 DEBUG : one: Destination found in --copy-dest, using server-side copy 2025/03/29 05:30:18 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-jevoteg8civi/dst': Waiting for transfers to finish 2025/03/29 05:30:18 DEBUG : Waiting for deletions to finish 2025/03/29 05:30:25 INFO : CopyDest/two.rclone_chunk.001_vjk8ac: Moved (server-side) to: CopyDest/two 2025/03/29 05:30:27 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/03/29 05:30:27 DEBUG : two: copy non-chunked object... 2025/03/29 05:30:28 DEBUG : two: md5 = b8a9f715dbb64fd5c56e7783c6820a61 OK 2025/03/29 05:30:28 INFO : two: Copied (server-side copy) 2025/03/29 05:30:28 DEBUG : two: Destination found in --copy-dest, using server-side copy 2025/03/29 05:30:28 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/03/29 05:30:28 DEBUG : one: Unchanged skipping 2025/03/29 05:30:28 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-jevoteg8civi/dst': Waiting for checks to finish 2025/03/29 05:30:28 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-jevoteg8civi/dst': Waiting for transfers to finish 2025/03/29 05:30:28 DEBUG : Waiting for deletions to finish 2025/03/29 05:30:34 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/03/29 05:30:34 DEBUG : one: Unchanged skipping 2025/03/29 05:30:34 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/03/29 05:30:34 DEBUG : two: Unchanged skipping 2025/03/29 05:30:34 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-jevoteg8civi/dst': Waiting for checks to finish 2025/03/29 05:30:34 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-jevoteg8civi/dst': Waiting for transfers to finish 2025/03/29 05:30:34 DEBUG : Waiting for deletions to finish 2025/03/29 05:30:34 INFO : There was nothing to transfer 2025/03/29 05:30:39 INFO : CopyDest/three.rclone_chunk.001_vjkmky: Moved (server-side) to: CopyDest/three 2025/03/29 05:30:41 DEBUG : three: Sizes differ (src 7 vs dst 5) 2025/03/29 05:30:41 DEBUG : three: Destination not found in --copy-dest 2025/03/29 05:30:41 DEBUG : three: Need to transfer - File not found at Destination 2025/03/29 05:30:41 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/03/29 05:30:41 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/03/29 05:30:41 DEBUG : one: Unchanged skipping 2025/03/29 05:30:41 DEBUG : two: Unchanged skipping 2025/03/29 05:30:41 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-jevoteg8civi/dst': Waiting for checks to finish 2025/03/29 05:30:41 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-jevoteg8civi/dst': Waiting for transfers to finish 2025/03/29 05:30:41 DEBUG : three: skip slow MD5 on source file, hashing in-transit 2025/03/29 05:30:46 INFO : three.rclone_chunk.001_vjksft: Moved (server-side) to: three 2025/03/29 05:30:46 DEBUG : three: md5 = 1bccb9dccb3e9f6a3f9d2a8bdb54b7f5 OK 2025/03/29 05:30:46 INFO : three: Copied (new) 2025/03/29 05:30:46 DEBUG : Waiting for deletions to finish --- PASS: TestSyncCopyDest (112.88s) FAIL 2025/03/29 05:31:29 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-jevoteg8civi': Purge remote "./sync.test -test.v -test.timeout 1h0m0s -remote TestChunkerChunk50bYandex: -verbose -fast-list -size-limit 1024 -test.run '^(TestServerSideMoveDeleteEmptySourceDirs|TestSyncAfterChangingFilesSizeOnly|TestSyncCopyDest)$'" - Finished ERROR in 4m5.155566237s (try 2/5): exit status 1: Failed [TestServerSideMoveDeleteEmptySourceDirs]