"./sync.test -test.v -test.timeout 1h0m0s -remote TestChunkerChunk50bYandex: -verbose -size-limit 1024 -test.run '^(TestServerSideMoveOverSelf|TestSyncBackupDirSuffixOnly|TestSyncDeleteDuring)$'" - Starting (try 2/5)
2025/04/25 03:02:06 DEBUG : Creating backend with remote "TestChunkerChunk50bYandex:rclone-test-kihiwiv5demo"
2025/04/25 03:02:06 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/04/25 03:02:06 DEBUG : Creating backend with remote "TestYandex:rclone-test-kihiwiv5demo"
2025/04/25 03:02:06 DEBUG : Creating backend with remote "/tmp/rclone3241923307"
=== RUN   TestServerSideMoveOverSelf
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-kihiwiv5demo'", Local "Local file system at /tmp/rclone3241923307", Modify Window "1ns"
2025/04/25 03:02:14 INFO  : sub dir/hello world.rclone_chunk.001_9cplo8: Moved (server-side) to: sub dir/hello world
2025/04/25 03:02:15 DEBUG : Creating backend with remote "TestChunkerChunk50bYandex:rclone-test-noxipuf2liye"
2025/04/25 03:02:15 DEBUG : Creating backend with remote "TestYandex:rclone-test-noxipuf2liye"
    sync_test.go:671: Server side copy (if possible) Chunked 'TestChunkerChunk50bYandex:rclone-test-kihiwiv5demo' -> Chunked 'TestChunkerChunk50bYandex:rclone-test-noxipuf2liye'
2025/04/25 03:02:16 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination
2025/04/25 03:02:16 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-noxipuf2liye': Waiting for checks to finish
2025/04/25 03:02:16 DEBUG : sub dir/hello world: copy non-chunked object...
2025/04/25 03:02:16 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-noxipuf2liye': Waiting for transfers to finish
2025/04/25 03:02:19 DEBUG : sub dir/hello world: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK
2025/04/25 03:02:19 INFO  : sub dir/hello world: Copied (server-side copy)
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-kihiwiv5demo'", Local "Local file system at /tmp/rclone3241923307", Modify Window "1ns"
2025/04/25 03:02:28 INFO  : sub dir/hello world: Deleted
2025/04/25 03:02:30 INFO  : sub dir/hello world.rclone_chunk.001_9cpy7x: Moved (server-side) to: sub dir/hello world
2025/04/25 03:02:31 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-noxipuf2liye': Using server-side directory move
2025/04/25 03:02:31 INFO  : Chunked 'TestChunkerChunk50bYandex:rclone-test-noxipuf2liye': Server side directory move failed - fallback to file moves: can't copy directory - destination already exists
2025/04/25 03:02:32 DEBUG : sub dir/hello world: Sizes differ (src 17 vs dst 11)
2025/04/25 03:02:32 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-noxipuf2liye': Waiting for checks to finish
2025/04/25 03:02:32 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-noxipuf2liye': Waiting for transfers to finish
2025/04/25 03:02:36 INFO  : sub dir/hello world: Deleted
2025/04/25 03:02:36 DEBUG : sub dir/hello world: move non-chunked object...
2025/04/25 03:02:38 INFO  : sub dir/hello world: Moved (server-side)
2025/04/25 03:02:38 INFO  : sub dir/hello world: Moved (server-side)
2025/04/25 03:02:47 INFO  : sub dir/hello world.rclone_chunk.001_9cqh4z: Moved (server-side) to: sub dir/hello world
2025/04/25 03:02:48 DEBUG : testing file moves
2025/04/25 03:02:49 DEBUG : sub dir/hello world: Sizes differ (src 24 vs dst 17)
2025/04/25 03:02:49 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-noxipuf2liye': Waiting for checks to finish
2025/04/25 03:02:49 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-noxipuf2liye': Waiting for transfers to finish
2025/04/25 03:02:53 INFO  : sub dir/hello world: Deleted
2025/04/25 03:02:53 DEBUG : sub dir/hello world: move non-chunked object...
2025/04/25 03:02:54 INFO  : sub dir/hello world: Moved (server-side)
2025/04/25 03:02:54 INFO  : sub dir/hello world: Moved (server-side)
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-kihiwiv5demo'", Local "Local file system at /tmp/rclone3241923307", Modify Window "1ns"
2025/04/25 03:02:56 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-noxipuf2liye': Purge remote
--- PASS: TestServerSideMoveOverSelf (65.46s)
=== RUN   TestSyncDeleteDuring
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-kihiwiv5demo'", Local "Local file system at /tmp/rclone3241923307", Modify Window "1ns"
2025/04/25 03:03:16 DEBUG : pacer: low level retry 1/10 (error [500 - InternalServerError] Internal Server Error (Ошибка сервера.))
2025/04/25 03:03:16 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2025/04/25 03:03:16 DEBUG : pacer: Reducing sleep to 15ms
2025/04/25 03:03:17 DEBUG : pacer: Reducing sleep to 11.25ms
2025/04/25 03:03:17 DEBUG : pacer: Reducing sleep to 10ms
2025/04/25 03:03:19 INFO  : potato.rclone_chunk.001_9crdbh: Moved (server-side) to: potato
2025/04/25 03:03:23 INFO  : empty space.rclone_chunk.001_9crk70: Moved (server-side) to: empty space
2025/04/25 03:03:24 DEBUG : Waiting for deletions to finish
2025/04/25 03:03:24 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/04/25 03:03:24 DEBUG : potato2: Need to transfer - File not found at Destination
2025/04/25 03:03:24 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-kihiwiv5demo': Waiting for checks to finish
2025/04/25 03:03:24 DEBUG : empty space: Unchanged skipping
2025/04/25 03:03:24 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-kihiwiv5demo': Waiting for transfers to finish
2025/04/25 03:03:25 DEBUG : potato2: skip slow MD5 on source file, hashing in-transit
2025/04/25 03:03:29 INFO  : potato: Deleted
2025/04/25 03:03:31 INFO  : potato2.rclone_chunk.001_9crqpf: Moved (server-side) to: potato2.rclone_chunk.001
2025/04/25 03:03:34 INFO  : potato2.rclone_chunk.002_9crqpf: Moved (server-side) to: potato2.rclone_chunk.002
2025/04/25 03:03:38 DEBUG : potato2: md5 = d6548b156ea68a4e003e786df99eee76 OK
2025/04/25 03:03:38 INFO  : potato2: Copied (new)
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-kihiwiv5demo'", Local "Local file system at /tmp/rclone3241923307", Modify Window "1ns"
--- PASS: TestSyncDeleteDuring (46.27s)
=== RUN   TestSyncBackupDirSuffixOnly
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-kihiwiv5demo'", Local "Local file system at /tmp/rclone3241923307", Modify Window "1ns"
2025/04/25 03:04:06 INFO  : dst/one.rclone_chunk.001_9csnja: Moved (server-side) to: dst/one
2025/04/25 03:04:11 INFO  : dst/two.rclone_chunk.001_9csvix: Moved (server-side) to: dst/two
2025/04/25 03:04:16 INFO  : dst/three.txt.rclone_chunk.001_9ct13q: Moved (server-side) to: dst/three.txt
2025/04/25 03:04:17 DEBUG : Creating backend with remote "TestChunkerChunk50bYandex:rclone-test-kihiwiv5demo/dst"
2025/04/25 03:04:17 DEBUG : Creating backend with remote "TestYandex:rclone-test-kihiwiv5demo/dst"
2025/04/25 03:04:18 DEBUG : one: Sizes differ (src 4 vs dst 3)
2025/04/25 03:04:18 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/04/25 03:04:18 DEBUG : two: Unchanged skipping
2025/04/25 03:04:18 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-kihiwiv5demo/dst': Waiting for checks to finish
2025/04/25 03:04:18 DEBUG : one: move non-chunked object...
2025/04/25 03:04:20 INFO  : one: Moved (server-side) to: one.bak
2025/04/25 03:04:20 INFO  : one: Moved (server-side) to: one.bak
2025/04/25 03:04:20 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-kihiwiv5demo/dst': Waiting for transfers to finish
2025/04/25 03:04:20 DEBUG : one: skip slow MD5 on source file, hashing in-transit
2025/04/25 03:04:26 INFO  : one.rclone_chunk.001_9ct94x: Moved (server-side) to: one
2025/04/25 03:04:26 DEBUG : one: md5 = c7957179c41f69d44f217a108c7915d8 OK
2025/04/25 03:04:26 INFO  : one: Copied (new)
2025/04/25 03:04:26 DEBUG : Waiting for deletions to finish
2025/04/25 03:04:26 DEBUG : three.txt: move non-chunked object...
2025/04/25 03:04:27 INFO  : three.txt: Moved (server-side) to: three.txt.bak
2025/04/25 03:04:27 INFO  : three.txt: Moved (server-side) to: three.txt.bak
2025/04/25 03:04:27 INFO  : three.txt: Moved into backup dir
2025/04/25 03:04:34 INFO  : dst/three.txt.rclone_chunk.001_9cths2: Moved (server-side) to: dst/three.txt
2025/04/25 03:04:35 DEBUG : one.bak: Excluded (Path Filter)
2025/04/25 03:04:35 DEBUG : one.bak: Excluded
2025/04/25 03:04:35 DEBUG : three.txt.bak: Excluded (Path Filter)
2025/04/25 03:04:35 DEBUG : three.txt.bak: Excluded
2025/04/25 03:04:35 DEBUG : one: Sizes differ (src 5 vs dst 4)
2025/04/25 03:04:35 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/04/25 03:04:35 DEBUG : two: Unchanged skipping
2025/04/25 03:04:35 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-kihiwiv5demo/dst': Waiting for checks to finish
2025/04/25 03:04:39 INFO  : one.bak: Deleted
2025/04/25 03:04:39 DEBUG : one: move non-chunked object...
2025/04/25 03:04:41 INFO  : one: Moved (server-side) to: one.bak
2025/04/25 03:04:41 INFO  : one: Moved (server-side) to: one.bak
2025/04/25 03:04:41 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-kihiwiv5demo/dst': Waiting for transfers to finish
2025/04/25 03:04:41 DEBUG : one: skip slow MD5 on source file, hashing in-transit
2025/04/25 03:04:46 INFO  : one.rclone_chunk.001_9ctu2s: Moved (server-side) to: one
2025/04/25 03:04:46 DEBUG : one: md5 = 0f93e81041f0cab37c37a05ae998b219 OK
2025/04/25 03:04:46 INFO  : one: Copied (new)
2025/04/25 03:04:46 DEBUG : Waiting for deletions to finish
2025/04/25 03:04:51 INFO  : three.txt.bak: Deleted
2025/04/25 03:04:51 DEBUG : three.txt: move non-chunked object...
2025/04/25 03:04:53 INFO  : three.txt: Moved (server-side) to: three.txt.bak
2025/04/25 03:04:53 INFO  : three.txt: Moved (server-side) to: three.txt.bak
2025/04/25 03:04:53 INFO  : three.txt: Moved into backup dir
--- PASS: TestSyncBackupDirSuffixOnly (78.60s)
PASS
2025/04/25 03:05:17 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-kihiwiv5demo': Purge remote
"./sync.test -test.v -test.timeout 1h0m0s -remote TestChunkerChunk50bYandex: -verbose -size-limit 1024 -test.run '^(TestServerSideMoveOverSelf|TestSyncBackupDirSuffixOnly|TestSyncDeleteDuring)$'" - Finished OK in 3m15.387699151s (try 2/5)