"./sync.test -test.v -test.timeout 1h0m0s -remote TestChunkerChunk50bYandex: -verbose -size-limit 1024 -test.run '^(TestCopyCheckFirst|TestServerSideMoveDeleteEmptySourceDirs|TestSyncBackupDir|TestSyncBackupDirWithSuffix|TestSyncDeleteDuring)$'" - Starting (try 2/5) 2025/04/27 03:12:56 DEBUG : Creating backend with remote "TestChunkerChunk50bYandex:rclone-test-higomac7xebi" 2025/04/27 03:12:56 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/04/27 03:12:56 DEBUG : Creating backend with remote "TestYandex:rclone-test-higomac7xebi" 2025/04/27 03:12:57 DEBUG : Creating backend with remote "/tmp/rclone904180109" === RUN TestCopyCheckFirst run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-higomac7xebi'", Local "Local file system at /tmp/rclone904180109", Modify Window "1ns" 2025/04/27 03:12:57 INFO : Chunked 'TestChunkerChunk50bYandex:rclone-test-higomac7xebi': Running all checks before starting transfers 2025/04/27 03:12:57 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/04/27 03:12:57 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-higomac7xebi': Waiting for checks to finish 2025/04/27 03:12:57 INFO : Chunked 'TestChunkerChunk50bYandex:rclone-test-higomac7xebi': Checks finished, now starting transfers 2025/04/27 03:12:57 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-higomac7xebi': Waiting for transfers to finish 2025/04/27 03:12:57 DEBUG : sub dir/hello world: skip slow MD5 on source file, hashing in-transit 2025/04/27 03:13:03 INFO : sub dir/hello world.rclone_chunk.001_d2jmj4: Moved (server-side) to: sub dir/hello world 2025/04/27 03:13:03 DEBUG : sub dir/hello world: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2025/04/27 03:13:03 INFO : sub dir/hello world: Copied (new) run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-higomac7xebi'", Local "Local file system at /tmp/rclone904180109", Modify Window "1ns" --- PASS: TestCopyCheckFirst (21.34s) === RUN TestSyncDeleteDuring run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-higomac7xebi'", Local "Local file system at /tmp/rclone904180109", Modify Window "1ns" 2025/04/27 03:13:25 INFO : potato.rclone_chunk.001_d2k8tg: Moved (server-side) to: potato 2025/04/27 03:13:31 INFO : empty space.rclone_chunk.001_d2kefc: Moved (server-side) to: empty space 2025/04/27 03:13:31 DEBUG : Waiting for deletions to finish 2025/04/27 03:13:31 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/27 03:13:31 DEBUG : potato2: Need to transfer - File not found at Destination 2025/04/27 03:13:31 DEBUG : empty space: Unchanged skipping 2025/04/27 03:13:31 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-higomac7xebi': Waiting for checks to finish 2025/04/27 03:13:31 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-higomac7xebi': Waiting for transfers to finish 2025/04/27 03:13:32 DEBUG : potato2: skip slow MD5 on source file, hashing in-transit 2025/04/27 03:13:36 INFO : potato: Deleted 2025/04/27 03:13:39 INFO : potato2.rclone_chunk.001_d2klta: Moved (server-side) to: potato2.rclone_chunk.001 2025/04/27 03:13:40 INFO : potato2.rclone_chunk.002_d2klta: Moved (server-side) to: potato2.rclone_chunk.002 2025/04/27 03:13:43 DEBUG : potato2: md5 = d6548b156ea68a4e003e786df99eee76 OK 2025/04/27 03:13:43 INFO : potato2: Copied (new) run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-higomac7xebi'", Local "Local file system at /tmp/rclone904180109", Modify Window "1ns" --- PASS: TestSyncDeleteDuring (45.46s) === RUN TestServerSideMoveDeleteEmptySourceDirs run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-higomac7xebi'", Local "Local file system at /tmp/rclone904180109", Modify Window "1ns" 2025/04/27 03:14:04 DEBUG : Creating backend with remote "TestChunkerChunk50bYandex:rclone-test-dufecul3kaba" 2025/04/27 03:14:04 DEBUG : Config file has changed externally - reloading 2025/04/27 03:14:04 DEBUG : Creating backend with remote "TestYandex:rclone-test-dufecul3kaba" 2025/04/27 03:14:11 INFO : potato2.rclone_chunk.001_d2lh8e: Moved (server-side) to: potato2.rclone_chunk.001 2025/04/27 03:14:12 INFO : potato2.rclone_chunk.002_d2lh8e: Moved (server-side) to: potato2.rclone_chunk.002 2025/04/27 03:14:22 INFO : empty space.rclone_chunk.001_d2lsmd: Moved (server-side) to: empty space 2025/04/27 03:14:29 INFO : potato3.rclone_chunk.001_d2lzry: Moved (server-side) to: potato3.rclone_chunk.001 2025/04/27 03:14:30 INFO : potato3.rclone_chunk.002_d2lzry: Moved (server-side) to: potato3.rclone_chunk.002 2025/04/27 03:14:33 INFO : tomatoDir: Making directory sync_test.go:1705: Server side move (if possible) Chunked 'TestChunkerChunk50bYandex:rclone-test-higomac7xebi' -> Chunked 'TestChunkerChunk50bYandex:rclone-test-dufecul3kaba' 2025/04/27 03:14:40 INFO : empty space.rclone_chunk.001_d2md5z: Moved (server-side) to: empty space 2025/04/27 03:14:48 INFO : potato3.rclone_chunk.001_d2mh2s: Moved (server-side) to: potato3.rclone_chunk.001 2025/04/27 03:14:49 INFO : potato3.rclone_chunk.002_d2mh2s: Moved (server-side) to: potato3.rclone_chunk.002 2025/04/27 03:14:53 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-dufecul3kaba': Using server-side directory move 2025/04/27 03:14:53 INFO : Chunked 'TestChunkerChunk50bYandex:rclone-test-dufecul3kaba': Server side directory move failed - fallback to file moves: can't copy directory - destination already exists 2025/04/27 03:14:54 DEBUG : potato2: Need to transfer - File not found at Destination 2025/04/27 03:14:54 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/27 03:14:54 DEBUG : empty space: Unchanged skipping 2025/04/27 03:14:54 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2025/04/27 03:14:54 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-dufecul3kaba': Waiting for checks to finish 2025/04/27 03:14:54 DEBUG : potato2: move 2 data chunks... 2025/04/27 03:14:57 INFO : potato2.rclone_chunk.001: Moved (server-side) 2025/04/27 03:14:58 INFO : empty space: Deleted 2025/04/27 03:14:58 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-dufecul3kaba': Waiting for transfers to finish 2025/04/27 03:14:59 INFO : potato2.rclone_chunk.002: Moved (server-side) 2025/04/27 03:15:01 INFO : potato2: Moved (server-side) 2025/04/27 03:15:04 INFO : potato2: Moved (server-side) 2025/04/27 03:15:08 INFO : potato3: Deleted 2025/04/27 03:15:08 DEBUG : potato3: move 2 data chunks... 2025/04/27 03:15:10 INFO : potato3.rclone_chunk.001: Moved (server-side) 2025/04/27 03:15:11 INFO : potato3.rclone_chunk.002: Moved (server-side) 2025/04/27 03:15:13 INFO : potato3: Moved (server-side) 2025/04/27 03:15:14 INFO : potato3: Moved (server-side) 2025/04/27 03:15:14 INFO : tomatoDir: Removing directory 2025/04/27 03:15:20 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-higomac7xebi': deleted 1 directories 2025/04/27 03:15:22 DEBUG : Creating backend with remote "TestChunkerChunk50bYandex:rclone-test-febojon7lehu" 2025/04/27 03:15:22 DEBUG : Config file has changed externally - reloading 2025/04/27 03:15:22 DEBUG : Creating backend with remote "TestYandex:rclone-test-febojon7lehu" 2025/04/27 03:15:23 INFO : tomatoDir: Making directory 2025/04/27 03:15:23 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-febojon7lehu': Using server-side directory move 2025/04/27 03:15:27 INFO : Chunked 'TestChunkerChunk50bYandex:rclone-test-febojon7lehu': Server side directory move succeeded 2025/04/27 03:15:29 ERROR : : error listing: directory not found 2025/04/27 03:15:30 ERROR : : error listing: directory not found 2025/04/27 03:15:30 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-febojon7lehu': Purge remote 2025/04/27 03:15:44 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-dufecul3kaba': Purge remote 2025/04/27 03:15:44 NOTICE: purge failed: [404 - DiskNotFoundError] Resource not found. (Не удалось найти запрошенный ресурс.) --- PASS: TestServerSideMoveDeleteEmptySourceDirs (101.13s) === RUN TestSyncBackupDir run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-higomac7xebi'", Local "Local file system at /tmp/rclone904180109", Modify Window "1ns" 2025/04/27 03:15:51 INFO : dst/one.rclone_chunk.001_d2oaw0: Moved (server-side) to: dst/one 2025/04/27 03:15:56 INFO : dst/two.rclone_chunk.001_d2ogyw: Moved (server-side) to: dst/two 2025/04/27 03:16:01 INFO : dst/three.txt.rclone_chunk.001_d2ol4n: Moved (server-side) to: dst/three.txt 2025/04/27 03:16:02 DEBUG : Creating backend with remote "TestChunkerChunk50bYandex:rclone-test-higomac7xebi/dst" 2025/04/27 03:16:02 DEBUG : Config file has changed externally - reloading 2025/04/27 03:16:02 DEBUG : Creating backend with remote "TestYandex:rclone-test-higomac7xebi/dst" 2025/04/27 03:16:02 DEBUG : Creating backend with remote "TestChunkerChunk50bYandex:rclone-test-higomac7xebi/backup" 2025/04/27 03:16:02 DEBUG : Creating backend with remote "TestYandex:rclone-test-higomac7xebi/backup" 2025/04/27 03:16:03 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/04/27 03:16:03 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/27 03:16:03 DEBUG : two: Unchanged skipping 2025/04/27 03:16:03 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-higomac7xebi/dst': Waiting for checks to finish 2025/04/27 03:16:03 DEBUG : one: move non-chunked object... 2025/04/27 03:16:05 INFO : one: Moved (server-side) 2025/04/27 03:16:05 INFO : one: Moved (server-side) 2025/04/27 03:16:05 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-higomac7xebi/dst': Waiting for transfers to finish 2025/04/27 03:16:06 DEBUG : one: skip slow MD5 on source file, hashing in-transit 2025/04/27 03:16:12 INFO : one.rclone_chunk.001_d2ovxj: Moved (server-side) to: one 2025/04/27 03:16:12 DEBUG : one: md5 = c7957179c41f69d44f217a108c7915d8 OK 2025/04/27 03:16:12 INFO : one: Copied (new) 2025/04/27 03:16:12 DEBUG : Waiting for deletions to finish 2025/04/27 03:16:12 DEBUG : three.txt: move non-chunked object... 2025/04/27 03:16:13 INFO : three.txt: Moved (server-side) 2025/04/27 03:16:13 INFO : three.txt: Moved (server-side) 2025/04/27 03:16:13 INFO : three.txt: Moved into backup dir 2025/04/27 03:16:19 INFO : dst/three.txt.rclone_chunk.001_d2p3r6: Moved (server-side) to: dst/three.txt 2025/04/27 03:16:21 DEBUG : one: Sizes differ (src 5 vs dst 4) 2025/04/27 03:16:21 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/27 03:16:21 DEBUG : two: Unchanged skipping 2025/04/27 03:16:21 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-higomac7xebi/dst': Waiting for checks to finish 2025/04/27 03:16:26 INFO : one: Deleted 2025/04/27 03:16:26 DEBUG : one: move non-chunked object... 2025/04/27 03:16:27 INFO : one: Moved (server-side) 2025/04/27 03:16:27 INFO : one: Moved (server-side) 2025/04/27 03:16:27 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-higomac7xebi/dst': Waiting for transfers to finish 2025/04/27 03:16:28 DEBUG : one: skip slow MD5 on source file, hashing in-transit 2025/04/27 03:16:34 INFO : one.rclone_chunk.001_d2phbt: Moved (server-side) to: one 2025/04/27 03:16:34 DEBUG : one: md5 = 0f93e81041f0cab37c37a05ae998b219 OK 2025/04/27 03:16:34 INFO : one: Copied (new) 2025/04/27 03:16:34 DEBUG : Waiting for deletions to finish 2025/04/27 03:16:39 INFO : three.txt: Deleted 2025/04/27 03:16:39 DEBUG : three.txt: move non-chunked object... 2025/04/27 03:16:40 INFO : three.txt: Moved (server-side) 2025/04/27 03:16:40 INFO : three.txt: Moved (server-side) 2025/04/27 03:16:40 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDir (85.48s) === RUN TestSyncBackupDirWithSuffix run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-higomac7xebi'", Local "Local file system at /tmp/rclone904180109", Modify Window "1ns" 2025/04/27 03:17:19 INFO : dst/one.rclone_chunk.001_d2qnmx: Moved (server-side) to: dst/one 2025/04/27 03:17:24 INFO : dst/two.rclone_chunk.001_d2qwx4: Moved (server-side) to: dst/two 2025/04/27 03:17:30 INFO : dst/three.txt.rclone_chunk.001_d2r1pd: Moved (server-side) to: dst/three.txt 2025/04/27 03:17:31 DEBUG : Creating backend with remote "TestChunkerChunk50bYandex:rclone-test-higomac7xebi/dst" 2025/04/27 03:17:31 DEBUG : Config file has changed externally - reloading 2025/04/27 03:17:31 DEBUG : Creating backend with remote "TestYandex:rclone-test-higomac7xebi/dst" 2025/04/27 03:17:31 DEBUG : Creating backend with remote "TestChunkerChunk50bYandex:rclone-test-higomac7xebi/backup" 2025/04/27 03:17:31 DEBUG : Config file has changed externally - reloading 2025/04/27 03:17:31 DEBUG : Creating backend with remote "TestYandex:rclone-test-higomac7xebi/backup" 2025/04/27 03:17:32 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/04/27 03:17:32 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/27 03:17:32 DEBUG : two: Unchanged skipping 2025/04/27 03:17:32 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-higomac7xebi/dst': Waiting for checks to finish 2025/04/27 03:17:32 DEBUG : one: move non-chunked object... 2025/04/27 03:17:37 INFO : one: Moved (server-side) to: one.bak 2025/04/27 03:17:37 INFO : one: Moved (server-side) to: one.bak 2025/04/27 03:17:37 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-higomac7xebi/dst': Waiting for transfers to finish 2025/04/27 03:17:37 DEBUG : one: skip slow MD5 on source file, hashing in-transit 2025/04/27 03:17:42 INFO : one.rclone_chunk.001_d2retf: Moved (server-side) to: one 2025/04/27 03:17:42 DEBUG : one: md5 = c7957179c41f69d44f217a108c7915d8 OK 2025/04/27 03:17:42 INFO : one: Copied (new) 2025/04/27 03:17:42 DEBUG : Waiting for deletions to finish 2025/04/27 03:17:42 DEBUG : three.txt: move non-chunked object... 2025/04/27 03:17:44 INFO : three.txt: Moved (server-side) to: three.txt.bak 2025/04/27 03:17:44 INFO : three.txt: Moved (server-side) to: three.txt.bak 2025/04/27 03:17:44 INFO : three.txt: Moved into backup dir 2025/04/27 03:17:50 INFO : dst/three.txt.rclone_chunk.001_d2rmhl: Moved (server-side) to: dst/three.txt 2025/04/27 03:17:52 DEBUG : one: Sizes differ (src 5 vs dst 4) 2025/04/27 03:17:52 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/27 03:17:52 DEBUG : two: Unchanged skipping 2025/04/27 03:17:52 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-higomac7xebi/dst': Waiting for checks to finish 2025/04/27 03:17:57 INFO : one.bak: Deleted 2025/04/27 03:17:57 DEBUG : one: move non-chunked object... 2025/04/27 03:17:59 INFO : one: Moved (server-side) to: one.bak 2025/04/27 03:17:59 INFO : one: Moved (server-side) to: one.bak 2025/04/27 03:17:59 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-higomac7xebi/dst': Waiting for transfers to finish 2025/04/27 03:17:59 DEBUG : one: skip slow MD5 on source file, hashing in-transit 2025/04/27 03:18:04 INFO : one.rclone_chunk.001_d2s0zv: Moved (server-side) to: one 2025/04/27 03:18:04 DEBUG : one: md5 = 0f93e81041f0cab37c37a05ae998b219 OK 2025/04/27 03:18:04 INFO : one: Copied (new) 2025/04/27 03:18:04 DEBUG : Waiting for deletions to finish 2025/04/27 03:18:09 INFO : three.txt.bak: Deleted 2025/04/27 03:18:09 DEBUG : three.txt: move non-chunked object... 2025/04/27 03:18:11 INFO : three.txt: Moved (server-side) to: three.txt.bak 2025/04/27 03:18:11 INFO : three.txt: Moved (server-side) to: three.txt.bak 2025/04/27 03:18:11 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirWithSuffix (90.66s) PASS 2025/04/27 03:18:41 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-higomac7xebi': Purge remote "./sync.test -test.v -test.timeout 1h0m0s -remote TestChunkerChunk50bYandex: -verbose -size-limit 1024 -test.run '^(TestCopyCheckFirst|TestServerSideMoveDeleteEmptySourceDirs|TestSyncBackupDir|TestSyncBackupDirWithSuffix|TestSyncDeleteDuring)$'" - Finished OK in 5m50.03217864s (try 2/5)