"./sync.test -test.v -test.timeout 1h0m0s -remote TestChunkerChunk50bYandex: -verbose -size-limit 1024 -test.run '^(TestServerSideMoveDeleteEmptySourceDirs|TestSyncAfterRemovingAFileAndAddingAFile|TestSyncDeleteBefore)$'" - Starting (try 3/5) 2025/06/22 04:34:09 DEBUG : Creating backend with remote "TestChunkerChunk50bYandex:rclone-test-woqejep4gizu" 2025/06/22 04:34:09 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/06/22 04:34:09 DEBUG : Creating backend with remote "TestYandex:rclone-test-woqejep4gizu" 2025/06/22 04:34:10 DEBUG : Creating backend with remote "/tmp/rclone3721151863" === RUN TestSyncAfterRemovingAFileAndAddingAFile run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-woqejep4gizu'", Local "Local file system at /tmp/rclone3721151863", Modify Window "1ns" 2025/06/22 04:34:15 INFO : potato.rclone_chunk.001_8vnlcv: Moved (server-side) to: potato 2025/06/22 04:34:19 INFO : empty space.rclone_chunk.001_8vnp3e: Moved (server-side) to: empty space 2025/06/22 04:34:20 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/22 04:34:20 DEBUG : potato2: Need to transfer - File not found at Destination 2025/06/22 04:34:20 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-woqejep4gizu': Waiting for checks to finish 2025/06/22 04:34:20 DEBUG : empty space: Unchanged skipping 2025/06/22 04:34:20 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-woqejep4gizu': Waiting for transfers to finish 2025/06/22 04:34:20 DEBUG : potato2: skip slow MD5 on source file, hashing in-transit 2025/06/22 04:34:26 INFO : potato2.rclone_chunk.001_8vnun6: Moved (server-side) to: potato2.rclone_chunk.001 2025/06/22 04:34:28 INFO : potato2.rclone_chunk.002_8vnun6: Moved (server-side) to: potato2.rclone_chunk.002 2025/06/22 04:34:31 DEBUG : potato2: md5 = d6548b156ea68a4e003e786df99eee76 OK 2025/06/22 04:34:31 INFO : potato2: Copied (new) 2025/06/22 04:34:31 DEBUG : Waiting for deletions to finish 2025/06/22 04:34:35 INFO : potato: Deleted --- PASS: TestSyncAfterRemovingAFileAndAddingAFile (46.97s) === RUN TestSyncDeleteBefore run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-woqejep4gizu'", Local "Local file system at /tmp/rclone3721151863", Modify Window "1ns" 2025/06/22 04:35:02 INFO : potato.rclone_chunk.001_8vovo1: Moved (server-side) to: potato 2025/06/22 04:35:07 INFO : empty space.rclone_chunk.001_8vp1ja: Moved (server-side) to: empty space 2025/06/22 04:35:07 DEBUG : Waiting for deletions to finish 2025/06/22 04:35:08 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-woqejep4gizu': Waiting for checks to finish 2025/06/22 04:35:08 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-woqejep4gizu': Waiting for transfers to finish 2025/06/22 04:35:13 INFO : potato: Deleted 2025/06/22 04:35:13 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/22 04:35:13 DEBUG : potato2: Need to transfer - File not found at Destination 2025/06/22 04:35:13 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-woqejep4gizu': Waiting for checks to finish 2025/06/22 04:35:13 DEBUG : empty space: Unchanged skipping 2025/06/22 04:35:13 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-woqejep4gizu': Waiting for transfers to finish 2025/06/22 04:35:14 DEBUG : potato2: skip slow MD5 on source file, hashing in-transit 2025/06/22 04:35:21 INFO : potato2.rclone_chunk.001_8vpcoa: Moved (server-side) to: potato2.rclone_chunk.001 2025/06/22 04:35:22 INFO : potato2.rclone_chunk.002_8vpcoa: Moved (server-side) to: potato2.rclone_chunk.002 2025/06/22 04:35:24 DEBUG : potato2: md5 = d6548b156ea68a4e003e786df99eee76 OK 2025/06/22 04:35:24 INFO : potato2: Copied (new) --- PASS: TestSyncDeleteBefore (49.21s) === RUN TestServerSideMoveDeleteEmptySourceDirs run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-woqejep4gizu'", Local "Local file system at /tmp/rclone3721151863", Modify Window "1ns" 2025/06/22 04:35:46 DEBUG : Creating backend with remote "TestChunkerChunk50bYandex:rclone-test-cunevos6wuju" 2025/06/22 04:35:46 DEBUG : Config file has changed externally - reloading 2025/06/22 04:35:46 DEBUG : Creating backend with remote "TestYandex:rclone-test-cunevos6wuju" 2025/06/22 04:35:56 INFO : potato2.rclone_chunk.001_8vqcw8: Moved (server-side) to: potato2.rclone_chunk.001 2025/06/22 04:35:57 INFO : potato2.rclone_chunk.002_8vqcw8: Moved (server-side) to: potato2.rclone_chunk.002 2025/06/22 04:36:04 INFO : empty space.rclone_chunk.001_8vqm92: Moved (server-side) to: empty space 2025/06/22 04:36:11 INFO : potato3.rclone_chunk.001_8vqrf0: Moved (server-side) to: potato3.rclone_chunk.001 2025/06/22 04:36:12 INFO : potato3.rclone_chunk.002_8vqrf0: Moved (server-side) to: potato3.rclone_chunk.002 2025/06/22 04:36:14 INFO : tomatoDir: Making directory sync_test.go:1706: Server side move (if possible) Chunked 'TestChunkerChunk50bYandex:rclone-test-woqejep4gizu' -> Chunked 'TestChunkerChunk50bYandex:rclone-test-cunevos6wuju' 2025/06/22 04:36:21 INFO : empty space.rclone_chunk.001_8vr3do: Moved (server-side) to: empty space 2025/06/22 04:36:28 INFO : potato3.rclone_chunk.001_8vr7os: Moved (server-side) to: potato3.rclone_chunk.001 2025/06/22 04:36:29 INFO : potato3.rclone_chunk.002_8vr7os: Moved (server-side) to: potato3.rclone_chunk.002 2025/06/22 04:36:32 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-cunevos6wuju': Using server-side directory move 2025/06/22 04:36:33 INFO : Chunked 'TestChunkerChunk50bYandex:rclone-test-cunevos6wuju': Server side directory move failed - fallback to file moves: can't copy directory - destination already exists 2025/06/22 04:36:33 DEBUG : potato2: Need to transfer - File not found at Destination 2025/06/22 04:36:33 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/22 04:36:33 DEBUG : empty space: Unchanged skipping 2025/06/22 04:36:33 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2025/06/22 04:36:34 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-cunevos6wuju': Waiting for checks to finish 2025/06/22 04:36:34 DEBUG : potato2: move 2 data chunks... 2025/06/22 04:36:35 INFO : potato2.rclone_chunk.001: Moved (server-side) 2025/06/22 04:36:37 INFO : potato2.rclone_chunk.002: Moved (server-side) 2025/06/22 04:36:38 INFO : empty space: Deleted 2025/06/22 04:36:38 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-cunevos6wuju': Waiting for transfers to finish 2025/06/22 04:36:39 INFO : potato2: Moved (server-side) 2025/06/22 04:36:40 INFO : potato2: Moved (server-side) 2025/06/22 04:36:49 INFO : potato3: Deleted 2025/06/22 04:36:49 DEBUG : potato3: move 2 data chunks... 2025/06/22 04:36:50 INFO : potato3.rclone_chunk.001: Moved (server-side) 2025/06/22 04:36:52 INFO : potato3.rclone_chunk.002: Moved (server-side) 2025/06/22 04:36:53 INFO : potato3: Moved (server-side) 2025/06/22 04:36:55 INFO : potato3: Moved (server-side) 2025/06/22 04:36:55 INFO : tomatoDir: Removing directory 2025/06/22 04:37:01 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-woqejep4gizu': deleted 1 directories 2025/06/22 04:37:03 DEBUG : Creating backend with remote "TestChunkerChunk50bYandex:rclone-test-wiwepob1tufe" 2025/06/22 04:37:03 DEBUG : Creating backend with remote "TestYandex:rclone-test-wiwepob1tufe" 2025/06/22 04:37:03 INFO : tomatoDir: Making directory 2025/06/22 04:37:03 DEBUG : pacer: low level retry 1/10 (error Put "https://cloud-api.yandex.com/v1/disk/resources?path=%2Frclone-test-cunevos6wuju%2FtomatoDir": read tcp [2a01:4f9:c011:405e::1]:43420->[2a02:6b8::1:127]:443: read: connection reset by peer) 2025/06/22 04:37:03 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2025/06/22 04:37:03 DEBUG : pacer: low level retry 2/10 (error Put "https://cloud-api.yandex.com/v1/disk/resources?path=%2Frclone-test-cunevos6wuju%2FtomatoDir": read tcp [2a01:4f9:c011:405e::1]:36658->[2a02:6b8::1:127]:443: read: connection reset by peer) 2025/06/22 04:37:03 DEBUG : pacer: Rate limited, increasing sleep to 40ms 2025/06/22 04:37:04 DEBUG : pacer: Reducing sleep to 30ms 2025/06/22 04:37:04 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-wiwepob1tufe': Using server-side directory move 2025/06/22 04:37:04 DEBUG : pacer: low level retry 1/10 (error Post "https://cloud-api.yandex.com/v1/disk/resources/move?from=disk%3A%2Frclone-test-cunevos6wuju&overwrite=false&path=disk%3A%2Frclone-test-wiwepob1tufe%2F": read tcp [2a01:4f9:c011:405e::1]:35280->[2a02:6b8::1:127]:443: read: connection reset by peer) 2025/06/22 04:37:04 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2025/06/22 04:37:05 DEBUG : pacer: Reducing sleep to 15ms 2025/06/22 04:37:05 DEBUG : pacer: Reducing sleep to 11.25ms 2025/06/22 04:37:06 DEBUG : pacer: Reducing sleep to 10ms 2025/06/22 04:37:07 INFO : Chunked 'TestChunkerChunk50bYandex:rclone-test-wiwepob1tufe': Server side directory move succeeded 2025/06/22 04:37:09 DEBUG : pacer: Reducing sleep to 22.5ms 2025/06/22 04:37:09 ERROR : error listing: directory not found 2025/06/22 04:37:10 DEBUG : pacer: Reducing sleep to 16.875ms 2025/06/22 04:37:10 ERROR : error listing: directory not found 2025/06/22 04:37:10 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-wiwepob1tufe': Purge remote 2025/06/22 04:37:23 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-cunevos6wuju': Purge remote 2025/06/22 04:37:23 DEBUG : pacer: Reducing sleep to 12.65625ms 2025/06/22 04:37:23 NOTICE: purge failed: [404 - DiskNotFoundError] Resource not found. (Не удалось найти запрошенный ресурс.) --- PASS: TestServerSideMoveDeleteEmptySourceDirs (97.85s) PASS 2025/06/22 04:37:24 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-woqejep4gizu': Purge remote "./sync.test -test.v -test.timeout 1h0m0s -remote TestChunkerChunk50bYandex: -verbose -size-limit 1024 -test.run '^(TestServerSideMoveDeleteEmptySourceDirs|TestSyncAfterRemovingAFileAndAddingAFile|TestSyncDeleteBefore)$'" - Finished OK in 3m19.944723041s (try 3/5)