"./sync.test -test.v -test.timeout 1h0m0s -remote TestChunkerChunk50bYandex: -verbose -fast-list -size-limit 1024 -test.run '^(TestCopyNoEmptyDirectories|TestServerSideMoveDeleteEmptySourceDirs|TestSyncBackupDirWithSuffix|TestSyncNoEmptyDirectories)$'" - Starting (try 2/5) 2025/06/25 06:59:44 DEBUG : Creating backend with remote "TestChunkerChunk50bYandex:rclone-test-xegagah5naxa" 2025/06/25 06:59:44 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/06/25 06:59:44 DEBUG : Creating backend with remote "TestYandex:rclone-test-xegagah5naxa" 2025/06/25 06:59:44 DEBUG : Creating backend with remote "/tmp/rclone3595718155" === RUN TestCopyNoEmptyDirectories run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-xegagah5naxa'", Local "Local file system at /tmp/rclone3595718155", Modify Window "1ns" 2025/06/25 06:59:44 INFO : sub dir2: Making directory 2025/06/25 06:59:44 DEBUG : sub dir2/sub sub dir2: Making directory with metadata 2025/06/25 06:59:44 INFO : sub dir2/sub sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/06/25 06:59:45 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/06/25 06:59:45 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-xegagah5naxa': Waiting for checks to finish 2025/06/25 06:59:45 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-xegagah5naxa': Waiting for transfers to finish 2025/06/25 06:59:45 DEBUG : sub dir/hello world: skip slow MD5 on source file, hashing in-transit 2025/06/25 06:59:51 INFO : sub dir/hello world.rclone_chunk.001_eme70h: Moved (server-side) to: sub dir/hello world 2025/06/25 06:59:51 DEBUG : sub dir/hello world: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2025/06/25 06:59:51 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopyNoEmptyDirectories (19.44s) === RUN TestSyncNoEmptyDirectories run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-xegagah5naxa'", Local "Local file system at /tmp/rclone3595718155", Modify Window "1ns" 2025/06/25 07:00:03 INFO : sub dir2: Making directory 2025/06/25 07:00:04 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/06/25 07:00:04 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-xegagah5naxa': Waiting for checks to finish 2025/06/25 07:00:04 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-xegagah5naxa': Waiting for transfers to finish 2025/06/25 07:00:04 DEBUG : sub dir/hello world: skip slow MD5 on source file, hashing in-transit 2025/06/25 07:00:13 INFO : sub dir/hello world.rclone_chunk.001_emeqvi: Moved (server-side) to: sub dir/hello world 2025/06/25 07:00:13 DEBUG : sub dir/hello world: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2025/06/25 07:00:13 INFO : sub dir/hello world: Copied (new) 2025/06/25 07:00:13 DEBUG : Waiting for deletions to finish --- PASS: TestSyncNoEmptyDirectories (24.31s) === RUN TestServerSideMoveDeleteEmptySourceDirs run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-xegagah5naxa'", Local "Local file system at /tmp/rclone3595718155", Modify Window "1ns" 2025/06/25 07:00:28 DEBUG : Creating backend with remote "TestChunkerChunk50bYandex:rclone-test-teduniv6daxi" 2025/06/25 07:00:28 DEBUG : Creating backend with remote "TestYandex:rclone-test-teduniv6daxi" 2025/06/25 07:00:35 INFO : potato2.rclone_chunk.001_emfe4u: Moved (server-side) to: potato2.rclone_chunk.001 2025/06/25 07:00:37 INFO : potato2.rclone_chunk.002_emfe4u: Moved (server-side) to: potato2.rclone_chunk.002 2025/06/25 07:00:45 INFO : empty space.rclone_chunk.001_emfp0j: Moved (server-side) to: empty space 2025/06/25 07:00:56 INFO : potato3.rclone_chunk.001_emfw1n: Moved (server-side) to: potato3.rclone_chunk.001 2025/06/25 07:00:58 INFO : potato3.rclone_chunk.002_emfw1n: Moved (server-side) to: potato3.rclone_chunk.002 2025/06/25 07:01:02 INFO : tomatoDir: Making directory sync_test.go:1706: Server side move (if possible) Chunked 'TestChunkerChunk50bYandex:rclone-test-xegagah5naxa' -> Chunked 'TestChunkerChunk50bYandex:rclone-test-teduniv6daxi' 2025/06/25 07:01:10 INFO : empty space.rclone_chunk.001_emgfzp: Moved (server-side) to: empty space 2025/06/25 07:01:13 DEBUG : pacer: low level retry 1/10 (error [500 - InternalServerError] Internal Server Error (Ошибка сервера.)) 2025/06/25 07:01:13 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2025/06/25 07:01:14 DEBUG : pacer: Reducing sleep to 15ms 2025/06/25 07:01:14 DEBUG : pacer: Reducing sleep to 11.25ms 2025/06/25 07:01:15 DEBUG : pacer: Reducing sleep to 10ms 2025/06/25 07:01:19 INFO : potato3.rclone_chunk.001_emgk0j: Moved (server-side) to: potato3.rclone_chunk.001 2025/06/25 07:01:21 INFO : potato3.rclone_chunk.002_emgk0j: Moved (server-side) to: potato3.rclone_chunk.002 2025/06/25 07:01:24 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-teduniv6daxi': Using server-side directory move 2025/06/25 07:01:24 INFO : Chunked 'TestChunkerChunk50bYandex:rclone-test-teduniv6daxi': Server side directory move failed - fallback to file moves: can't copy directory - destination already exists 2025/06/25 07:01:25 DEBUG : potato2: Need to transfer - File not found at Destination 2025/06/25 07:01:25 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/25 07:01:25 DEBUG : empty space: Unchanged skipping 2025/06/25 07:01:25 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2025/06/25 07:01:25 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-teduniv6daxi': Waiting for checks to finish 2025/06/25 07:01:25 DEBUG : potato2: move 2 data chunks... 2025/06/25 07:01:27 INFO : potato2.rclone_chunk.001: Moved (server-side) 2025/06/25 07:01:29 INFO : potato2.rclone_chunk.002: Moved (server-side) 2025/06/25 07:01:30 INFO : empty space: Deleted 2025/06/25 07:01:30 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-teduniv6daxi': Waiting for transfers to finish 2025/06/25 07:01:31 INFO : potato2: Moved (server-side) 2025/06/25 07:01:34 INFO : potato2: Moved (server-side) 2025/06/25 07:01:41 INFO : potato3: Deleted 2025/06/25 07:01:41 DEBUG : potato3: move 2 data chunks... 2025/06/25 07:01:43 INFO : potato3.rclone_chunk.001: Moved (server-side) 2025/06/25 07:01:45 INFO : potato3.rclone_chunk.002: Moved (server-side) 2025/06/25 07:01:47 INFO : potato3: Moved (server-side) 2025/06/25 07:01:49 INFO : potato3: Moved (server-side) 2025/06/25 07:01:49 INFO : tomatoDir: Removing directory 2025/06/25 07:01:56 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-xegagah5naxa': deleted 1 directories 2025/06/25 07:02:00 DEBUG : Creating backend with remote "TestChunkerChunk50bYandex:rclone-test-fiqusos3bixu" 2025/06/25 07:02:00 DEBUG : Config file has changed externally - reloading 2025/06/25 07:02:00 DEBUG : Creating backend with remote "TestYandex:rclone-test-fiqusos3bixu" 2025/06/25 07:02:01 INFO : tomatoDir: Making directory 2025/06/25 07:02:01 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-fiqusos3bixu': Using server-side directory move 2025/06/25 07:02:06 INFO : Chunked 'TestChunkerChunk50bYandex:rclone-test-fiqusos3bixu': Server side directory move succeeded 2025/06/25 07:02:08 ERROR : error listing: directory not found 2025/06/25 07:02:09 ERROR : error listing: directory not found 2025/06/25 07:02:09 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-fiqusos3bixu': Purge remote 2025/06/25 07:02:28 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-teduniv6daxi': Purge remote 2025/06/25 07:02:28 NOTICE: purge failed: [404 - DiskNotFoundError] Resource not found. (Не удалось найти запрошенный ресурс.) --- PASS: TestServerSideMoveDeleteEmptySourceDirs (120.83s) === RUN TestSyncBackupDirWithSuffix run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-xegagah5naxa'", Local "Local file system at /tmp/rclone3595718155", Modify Window "1ns" 2025/06/25 07:02:34 INFO : dst/one.rclone_chunk.001_emirz4: Moved (server-side) to: dst/one 2025/06/25 07:02:42 INFO : dst/two.rclone_chunk.001_emix1z: Moved (server-side) to: dst/two 2025/06/25 07:02:48 INFO : dst/three.txt.rclone_chunk.001_emj57r: Moved (server-side) to: dst/three.txt 2025/06/25 07:02:48 DEBUG : Creating backend with remote "TestChunkerChunk50bYandex:rclone-test-xegagah5naxa/dst" 2025/06/25 07:02:48 DEBUG : Creating backend with remote "TestYandex:rclone-test-xegagah5naxa/dst" 2025/06/25 07:02:49 DEBUG : Creating backend with remote "TestChunkerChunk50bYandex:rclone-test-xegagah5naxa/backup" 2025/06/25 07:02:49 DEBUG : Creating backend with remote "TestYandex:rclone-test-xegagah5naxa/backup" 2025/06/25 07:02:50 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/06/25 07:02:50 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/25 07:02:50 DEBUG : two: Unchanged skipping 2025/06/25 07:02:50 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-xegagah5naxa/dst': Waiting for checks to finish 2025/06/25 07:02:50 DEBUG : one: move non-chunked object... 2025/06/25 07:02:51 INFO : one: Moved (server-side) to: one.bak 2025/06/25 07:02:51 INFO : one: Moved (server-side) to: one.bak 2025/06/25 07:02:51 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-xegagah5naxa/dst': Waiting for transfers to finish 2025/06/25 07:02:52 DEBUG : one: skip slow MD5 on source file, hashing in-transit 2025/06/25 07:02:58 INFO : one.rclone_chunk.001_emjei4: Moved (server-side) to: one 2025/06/25 07:02:58 DEBUG : one: md5 = c7957179c41f69d44f217a108c7915d8 OK 2025/06/25 07:02:58 INFO : one: Copied (new) 2025/06/25 07:02:58 DEBUG : Waiting for deletions to finish 2025/06/25 07:02:59 DEBUG : three.txt: move non-chunked object... 2025/06/25 07:03:00 INFO : three.txt: Moved (server-side) to: three.txt.bak 2025/06/25 07:03:00 INFO : three.txt: Moved (server-side) to: three.txt.bak 2025/06/25 07:03:00 INFO : three.txt: Moved into backup dir 2025/06/25 07:03:07 INFO : dst/three.txt.rclone_chunk.001_emjoas: Moved (server-side) to: dst/three.txt 2025/06/25 07:03:08 DEBUG : one: Sizes differ (src 5 vs dst 4) 2025/06/25 07:03:08 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/25 07:03:08 DEBUG : two: Unchanged skipping 2025/06/25 07:03:08 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-xegagah5naxa/dst': Waiting for checks to finish 2025/06/25 07:03:17 INFO : one.bak: Deleted 2025/06/25 07:03:17 DEBUG : one: move non-chunked object... 2025/06/25 07:03:18 INFO : one: Moved (server-side) to: one.bak 2025/06/25 07:03:18 INFO : one: Moved (server-side) to: one.bak 2025/06/25 07:03:18 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-xegagah5naxa/dst': Waiting for transfers to finish 2025/06/25 07:03:19 DEBUG : one: skip slow MD5 on source file, hashing in-transit 2025/06/25 07:03:26 INFO : one.rclone_chunk.001_emk52x: Moved (server-side) to: one 2025/06/25 07:03:26 DEBUG : one: md5 = 0f93e81041f0cab37c37a05ae998b219 OK 2025/06/25 07:03:26 INFO : one: Copied (new) 2025/06/25 07:03:26 DEBUG : Waiting for deletions to finish 2025/06/25 07:03:33 INFO : three.txt.bak: Deleted 2025/06/25 07:03:33 DEBUG : three.txt: move non-chunked object... 2025/06/25 07:03:34 INFO : three.txt: Moved (server-side) to: three.txt.bak 2025/06/25 07:03:34 INFO : three.txt: Moved (server-side) to: three.txt.bak 2025/06/25 07:03:34 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirWithSuffix (102.94s) PASS 2025/06/25 07:04:12 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-xegagah5naxa': Purge remote "./sync.test -test.v -test.timeout 1h0m0s -remote TestChunkerChunk50bYandex: -verbose -fast-list -size-limit 1024 -test.run '^(TestCopyNoEmptyDirectories|TestServerSideMoveDeleteEmptySourceDirs|TestSyncBackupDirWithSuffix|TestSyncNoEmptyDirectories)$'" - Finished OK in 4m34.087422583s (try 2/5)