"./sync.test -test.v -test.timeout 1h0m0s -remote TestChunkerChunk50bYandex: -verbose -size-limit 1024 -test.run '^(TestServerSideCopyOverSelf|TestSyncBackupDirWithSuffixKeepExtension|TestSyncConcurrentDelete|TestSyncIgnoreTimes|TestSyncWithExcludeAndDeleteExcluded|TestSyncWithTrackRenamesStrategyModtime)$'" - Starting (try 3/5) 2024/10/03 02:45:30 DEBUG : Creating backend with remote "TestChunkerChunk50bYandex:rclone-test-bosumec9fala" 2024/10/03 02:45:30 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2024/10/03 02:45:30 DEBUG : Creating backend with remote "TestYandex:rclone-test-bosumec9fala" 2024/10/03 02:45:31 DEBUG : Reset feature "ListR" 2024/10/03 02:45:31 DEBUG : Creating backend with remote "/tmp/rclone1163530752" === RUN TestServerSideCopyOverSelf run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-bosumec9fala'", Local "Local file system at /tmp/rclone1163530752", Modify Window "1ns" 2024/10/03 02:45:36 INFO : sub dir/hello world.rclone_chunk.001_rjvrlc: Moved (server-side) to: sub dir/hello world 2024/10/03 02:45:37 DEBUG : Creating backend with remote "TestChunkerChunk50bYandex:rclone-test-necutef1hewi" 2024/10/03 02:45:37 DEBUG : Creating backend with remote "TestYandex:rclone-test-necutef1hewi" 2024/10/03 02:45:37 DEBUG : Reset feature "ListR" sync_test.go:626: Server side copy (if possible) Chunked 'TestChunkerChunk50bYandex:rclone-test-bosumec9fala' -> Chunked 'TestChunkerChunk50bYandex:rclone-test-necutef1hewi' 2024/10/03 02:45:37 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2024/10/03 02:45:37 DEBUG : sub dir/hello world: copy non-chunked object... 2024/10/03 02:45:37 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-necutef1hewi': Waiting for checks to finish 2024/10/03 02:45:37 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-necutef1hewi': Waiting for transfers to finish 2024/10/03 02:45:40 DEBUG : sub dir/hello world: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2024/10/03 02:45:40 INFO : sub dir/hello world: Copied (server-side copy) run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-bosumec9fala'", Local "Local file system at /tmp/rclone1163530752", Modify Window "1ns" 2024/10/03 02:45:46 INFO : sub dir/hello world: Deleted 2024/10/03 02:45:50 INFO : sub dir/hello world.rclone_chunk.001_rjw1ob: Moved (server-side) to: sub dir/hello world 2024/10/03 02:45:51 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-necutef1hewi': Waiting for checks to finish 2024/10/03 02:45:51 DEBUG : sub dir/hello world: Sizes differ (src 17 vs dst 11) 2024/10/03 02:45:51 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-necutef1hewi': Waiting for transfers to finish 2024/10/03 02:45:51 DEBUG : sub dir/hello world: copy non-chunked object... 2024/10/03 02:45:52 ERROR : sub dir/hello world: Failed to copy: couldn't copy file: [409 - DiskResourceAlreadyExistsError] Resource "disk:/rclone-test-necutef1hewi/sub dir/hello world" already exists. (Ресурс "disk:/rclone-test-necutef1hewi/sub dir/hello world" уже существует.) sync_test.go:639: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:639 Error: Received unexpected error: couldn't copy file: [409 - DiskResourceAlreadyExistsError] Resource "disk:/rclone-test-necutef1hewi/sub dir/hello world" already exists. (Ресурс "disk:/rclone-test-necutef1hewi/sub dir/hello world" уже существует.) Test: TestServerSideCopyOverSelf 2024/10/03 02:45:52 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-necutef1hewi': Purge remote --- FAIL: TestServerSideCopyOverSelf (31.04s) === RUN TestSyncIgnoreTimes run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-bosumec9fala'", Local "Local file system at /tmp/rclone1163530752", Modify Window "1ns" 2024/10/03 02:46:07 INFO : existing.rclone_chunk.001_rjwm0n: Moved (server-side) to: existing 2024/10/03 02:46:08 DEBUG : existing: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/10/03 02:46:08 DEBUG : existing: Unchanged skipping 2024/10/03 02:46:08 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-bosumec9fala': Waiting for checks to finish 2024/10/03 02:46:08 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-bosumec9fala': Waiting for transfers to finish 2024/10/03 02:46:08 DEBUG : Waiting for deletions to finish 2024/10/03 02:46:08 INFO : There was nothing to transfer run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-bosumec9fala'", Local "Local file system at /tmp/rclone1163530752", Modify Window "1ns" 2024/10/03 02:46:09 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-bosumec9fala': Waiting for checks to finish 2024/10/03 02:46:09 DEBUG : existing: Transferring unconditionally as --ignore-times is in use 2024/10/03 02:46:09 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-bosumec9fala': Waiting for transfers to finish 2024/10/03 02:46:09 DEBUG : existing: skip slow MD5 on source file, hashing in-transit 2024/10/03 02:46:14 INFO : existing: Deleted 2024/10/03 02:46:17 INFO : existing.rclone_chunk.001_rjwt0b: Moved (server-side) to: existing 2024/10/03 02:46:17 DEBUG : existing: md5 = 8ee2027983915ec78acc45027d874316 OK 2024/10/03 02:46:17 INFO : existing: Copied (replaced existing) 2024/10/03 02:46:17 DEBUG : Waiting for deletions to finish run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-bosumec9fala'", Local "Local file system at /tmp/rclone1163530752", Modify Window "1ns" --- PASS: TestSyncIgnoreTimes (20.01s) === RUN TestSyncWithExcludeAndDeleteExcluded run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-bosumec9fala'", Local "Local file system at /tmp/rclone1163530752", Modify Window "1ns" 2024/10/03 02:46:29 INFO : potato2.rclone_chunk.001_rjx6xl: Moved (server-side) to: potato2.rclone_chunk.001 2024/10/03 02:46:31 INFO : potato2.rclone_chunk.002_rjx6xl: Moved (server-side) to: potato2.rclone_chunk.002 2024/10/03 02:46:48 INFO : empty space.rclone_chunk.001_rjxqyt: Moved (server-side) to: empty space 2024/10/03 02:46:54 INFO : enormous.rclone_chunk.001_rjxwix: Moved (server-side) to: enormous.rclone_chunk.001 2024/10/03 02:46:56 INFO : enormous.rclone_chunk.002_rjxwix: Moved (server-side) to: enormous.rclone_chunk.002 2024/10/03 02:47:00 DEBUG : enormous: Excluded (Size Filter) 2024/10/03 02:47:00 DEBUG : enormous: Excluded 2024/10/03 02:47:00 DEBUG : potato2: Excluded (Size Filter) 2024/10/03 02:47:00 DEBUG : potato2: Excluded 2024/10/03 02:47:00 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/10/03 02:47:00 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-bosumec9fala': Waiting for checks to finish 2024/10/03 02:47:00 DEBUG : empty space: Unchanged skipping 2024/10/03 02:47:00 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-bosumec9fala': Waiting for transfers to finish 2024/10/03 02:47:00 DEBUG : Waiting for deletions to finish 2024/10/03 02:47:06 INFO : potato2: Deleted 2024/10/03 02:47:06 INFO : enormous: Deleted 2024/10/03 02:47:06 INFO : There was nothing to transfer run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-bosumec9fala'", Local "Local file system at /tmp/rclone1163530752", Modify Window "1ns" 2024/10/03 02:47:07 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/10/03 02:47:07 DEBUG : empty space: Unchanged skipping 2024/10/03 02:47:07 DEBUG : Local file system at /tmp/rclone1163530752: Waiting for checks to finish 2024/10/03 02:47:07 DEBUG : Local file system at /tmp/rclone1163530752: Waiting for transfers to finish 2024/10/03 02:47:07 DEBUG : Waiting for deletions to finish 2024/10/03 02:47:07 INFO : potato2: Deleted 2024/10/03 02:47:07 INFO : enormous: Deleted 2024/10/03 02:47:07 INFO : There was nothing to transfer run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-bosumec9fala'", Local "Local file system at /tmp/rclone1163530752", Modify Window "1ns" --- PASS: TestSyncWithExcludeAndDeleteExcluded (49.16s) === RUN TestSyncWithTrackRenamesStrategyModtime run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-bosumec9fala'", Local "Local file system at /tmp/rclone1163530752", Modify Window "1ns" sync_test.go:1610: Can track renames: true 2024/10/03 02:47:11 INFO : Chunked 'TestChunkerChunk50bYandex:rclone-test-bosumec9fala': Making map for --track-renames 2024/10/03 02:47:11 INFO : Chunked 'TestChunkerChunk50bYandex:rclone-test-bosumec9fala': Finished making map for --track-renames 2024/10/03 02:47:11 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-bosumec9fala': Waiting for checks to finish 2024/10/03 02:47:11 DEBUG : potato: Need to transfer - No matching file found at Destination 2024/10/03 02:47:11 DEBUG : yam: Need to transfer - No matching file found at Destination 2024/10/03 02:47:11 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-bosumec9fala': Waiting for renames to finish 2024/10/03 02:47:11 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-bosumec9fala': Waiting for transfers to finish 2024/10/03 02:47:11 DEBUG : potato: skip slow MD5 on source file, hashing in-transit 2024/10/03 02:47:11 DEBUG : yam: skip slow MD5 on source file, hashing in-transit 2024/10/03 02:47:17 INFO : potato.rclone_chunk.001_rjyj3y: Moved (server-side) to: potato 2024/10/03 02:47:17 DEBUG : potato: md5 = 7f6fa9ddec9bfebda9510af0323fd696 OK 2024/10/03 02:47:17 INFO : potato: Copied (new) 2024/10/03 02:47:24 INFO : yam.rclone_chunk.001_rjyjbj: Moved (server-side) to: yam 2024/10/03 02:47:24 DEBUG : yam: md5 = 48dc443644c4b89556dc06834b46451d OK 2024/10/03 02:47:24 INFO : yam: Copied (new) 2024/10/03 02:47:24 DEBUG : Waiting for deletions to finish run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-bosumec9fala'", Local "Local file system at /tmp/rclone1163530752", Modify Window "1ns" 2024/10/03 02:47:25 DEBUG : potato: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/10/03 02:47:25 INFO : Chunked 'TestChunkerChunk50bYandex:rclone-test-bosumec9fala': Making map for --track-renames 2024/10/03 02:47:25 INFO : Chunked 'TestChunkerChunk50bYandex:rclone-test-bosumec9fala': Finished making map for --track-renames 2024/10/03 02:47:25 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-bosumec9fala': Waiting for checks to finish 2024/10/03 02:47:25 DEBUG : potato: Unchanged skipping 2024/10/03 02:47:25 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-bosumec9fala': Waiting for renames to finish 2024/10/03 02:47:25 DEBUG : yam: move non-chunked object... 2024/10/03 02:47:29 INFO : yam: Moved (server-side) to: yaml 2024/10/03 02:47:29 INFO : yam: Moved (server-side) to: yaml 2024/10/03 02:47:29 INFO : yaml: Renamed from "yam" 2024/10/03 02:47:29 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-bosumec9fala': Waiting for transfers to finish 2024/10/03 02:47:29 DEBUG : Waiting for deletions to finish 2024/10/03 02:47:29 INFO : There was nothing to transfer run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-bosumec9fala'", Local "Local file system at /tmp/rclone1163530752", Modify Window "1ns" --- PASS: TestSyncWithTrackRenamesStrategyModtime (24.22s) === RUN TestSyncBackupDirWithSuffixKeepExtension run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-bosumec9fala'", Local "Local file system at /tmp/rclone1163530752", Modify Window "1ns" 2024/10/03 02:47:44 INFO : dst/one.rclone_chunk.001_rjz7w3: Moved (server-side) to: dst/one 2024/10/03 02:47:53 INFO : dst/two.rclone_chunk.001_rjzh9w: Moved (server-side) to: dst/two 2024/10/03 02:48:00 INFO : dst/three.txt.rclone_chunk.001_rjzpzk: Moved (server-side) to: dst/three.txt 2024/10/03 02:48:01 DEBUG : Creating backend with remote "TestChunkerChunk50bYandex:rclone-test-bosumec9fala/dst" 2024/10/03 02:48:01 DEBUG : Config file has changed externally - reloading 2024/10/03 02:48:01 DEBUG : Creating backend with remote "TestYandex:rclone-test-bosumec9fala/dst" 2024/10/03 02:48:01 DEBUG : Reset feature "ListR" 2024/10/03 02:48:01 DEBUG : Creating backend with remote "TestChunkerChunk50bYandex:rclone-test-bosumec9fala/backup" 2024/10/03 02:48:01 DEBUG : Creating backend with remote "TestYandex:rclone-test-bosumec9fala/backup" 2024/10/03 02:48:01 DEBUG : Reset feature "ListR" 2024/10/03 02:48:02 DEBUG : one: Sizes differ (src 4 vs dst 3) 2024/10/03 02:48:02 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/10/03 02:48:02 DEBUG : two: Unchanged skipping 2024/10/03 02:48:02 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-bosumec9fala/dst': Waiting for checks to finish 2024/10/03 02:48:02 DEBUG : one: move non-chunked object... 2024/10/03 02:48:04 INFO : one: Moved (server-side) to: one-2019-01-01 2024/10/03 02:48:04 INFO : one: Moved (server-side) to: one-2019-01-01 2024/10/03 02:48:04 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-bosumec9fala/dst': Waiting for transfers to finish 2024/10/03 02:48:04 DEBUG : one: skip slow MD5 on source file, hashing in-transit 2024/10/03 02:48:06 DEBUG : pacer: low level retry 1/10 (error [500 - InternalServerError] Internal Server Error (Ошибка сервера.)) 2024/10/03 02:48:06 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2024/10/03 02:48:07 DEBUG : pacer: Reducing sleep to 15ms 2024/10/03 02:48:07 DEBUG : pacer: Reducing sleep to 11.25ms 2024/10/03 02:48:07 DEBUG : pacer: Reducing sleep to 10ms 2024/10/03 02:48:09 INFO : one.rclone_chunk.001_rk00dy: Moved (server-side) to: one 2024/10/03 02:48:09 DEBUG : one: md5 = c7957179c41f69d44f217a108c7915d8 OK 2024/10/03 02:48:09 INFO : one: Copied (new) 2024/10/03 02:48:09 DEBUG : Waiting for deletions to finish 2024/10/03 02:48:10 DEBUG : three.txt: move non-chunked object... 2024/10/03 02:48:11 INFO : three.txt: Moved (server-side) to: three-2019-01-01.txt 2024/10/03 02:48:11 INFO : three.txt: Moved (server-side) to: three-2019-01-01.txt 2024/10/03 02:48:11 INFO : three.txt: Moved into backup dir 2024/10/03 02:48:18 INFO : dst/three.txt.rclone_chunk.001_rk08kb: Moved (server-side) to: dst/three.txt 2024/10/03 02:48:19 DEBUG : one: Sizes differ (src 5 vs dst 4) 2024/10/03 02:48:19 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/10/03 02:48:19 DEBUG : two: Unchanged skipping 2024/10/03 02:48:19 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-bosumec9fala/dst': Waiting for checks to finish 2024/10/03 02:48:21 INFO : one-2019-01-01: Deleted 2024/10/03 02:48:21 DEBUG : one: move non-chunked object... 2024/10/03 02:48:23 INFO : one: Moved (server-side) to: one-2019-01-01 2024/10/03 02:48:23 INFO : one: Moved (server-side) to: one-2019-01-01 2024/10/03 02:48:23 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-bosumec9fala/dst': Waiting for transfers to finish 2024/10/03 02:48:23 DEBUG : one: skip slow MD5 on source file, hashing in-transit 2024/10/03 02:48:28 INFO : one.rclone_chunk.001_rk0jdz: Moved (server-side) to: one 2024/10/03 02:48:28 DEBUG : one: md5 = 0f93e81041f0cab37c37a05ae998b219 OK 2024/10/03 02:48:28 INFO : one: Copied (new) 2024/10/03 02:48:28 DEBUG : Waiting for deletions to finish 2024/10/03 02:48:30 INFO : three-2019-01-01.txt: Deleted 2024/10/03 02:48:30 DEBUG : three.txt: move non-chunked object... 2024/10/03 02:48:32 INFO : three.txt: Moved (server-side) to: three-2019-01-01.txt 2024/10/03 02:48:32 INFO : three.txt: Moved (server-side) to: three-2019-01-01.txt 2024/10/03 02:48:32 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirWithSuffixKeepExtension (69.79s) === RUN TestSyncConcurrentDelete run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-bosumec9fala'", Local "Local file system at /tmp/rclone1163530752", Modify Window "1ns" 2024/10/03 02:48:49 INFO : both0.rclone_chunk.001_rk15ei: Moved (server-side) to: both0 2024/10/03 02:48:55 INFO : only0.rclone_chunk.001_rk196f: Moved (server-side) to: only0 2024/10/03 02:48:59 INFO : both1.rclone_chunk.001_rk1frn: Moved (server-side) to: both1 2024/10/03 02:49:05 INFO : only1.rclone_chunk.001_rk1kyd: Moved (server-side) to: only1 2024/10/03 02:49:11 INFO : both2.rclone_chunk.001_rk1p9y: Moved (server-side) to: both2 2024/10/03 02:49:16 INFO : only2.rclone_chunk.001_rk1vbm: Moved (server-side) to: only2 2024/10/03 02:49:21 INFO : both3.rclone_chunk.001_rk2050: Moved (server-side) to: both3 2024/10/03 02:49:26 INFO : only3.rclone_chunk.001_rk25f5: Moved (server-side) to: only3 2024/10/03 02:49:36 INFO : both4.rclone_chunk.001_rk2be1: Moved (server-side) to: both4 2024/10/03 02:49:42 INFO : only4.rclone_chunk.001_rk2kkc: Moved (server-side) to: only4 2024/10/03 02:49:47 INFO : both5.rclone_chunk.001_rk2qf6: Moved (server-side) to: both5 2024/10/03 02:49:55 INFO : only5.rclone_chunk.001_rk2v2f: Moved (server-side) to: only5 2024/10/03 02:49:59 INFO : both6.rclone_chunk.001_rk330m: Moved (server-side) to: both6 2024/10/03 02:50:04 INFO : only6.rclone_chunk.001_rk37cw: Moved (server-side) to: only6 2024/10/03 02:50:09 INFO : both7.rclone_chunk.001_rk3cur: Moved (server-side) to: both7 2024/10/03 02:50:15 INFO : only7.rclone_chunk.001_rk3i3r: Moved (server-side) to: only7 2024/10/03 02:50:22 INFO : both8.rclone_chunk.001_rk3nlu: Moved (server-side) to: both8 2024/10/03 02:50:33 INFO : only8.rclone_chunk.001_rk3vvv: Moved (server-side) to: only8 2024/10/03 02:50:37 INFO : both9.rclone_chunk.001_rk45pt: Moved (server-side) to: both9 2024/10/03 02:50:43 INFO : only9.rclone_chunk.001_rk4at7: Moved (server-side) to: only9 2024/10/03 02:50:52 INFO : both10.rclone_chunk.001_rk4ga8: Moved (server-side) to: both10 2024/10/03 02:50:57 INFO : only10.rclone_chunk.001_rk4pv4: Moved (server-side) to: only10 2024/10/03 02:51:02 INFO : both11.rclone_chunk.001_rk4t67: Moved (server-side) to: both11 2024/10/03 02:51:11 INFO : only11.rclone_chunk.001_rk4z16: Moved (server-side) to: only11 2024/10/03 02:51:16 ERROR : both12.rclone_chunk.001_rk57kw: Couldn't move: object not found 2024/10/03 02:51:17 ERROR : both12.rclone_chunk.001_rk57kw: Failed to remove temporary chunk: [404 - DiskNotFoundError] Resource not found. (Не удалось найти запрошенный ресурс.) run.go:285: Failed to put "both12" to "Chunked 'TestChunkerChunk50bYandex:rclone-test-bosumec9fala'": object not found --- FAIL: TestSyncConcurrentDelete (188.87s) FAIL 2024/10/03 02:51:54 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-bosumec9fala': Purge remote "./sync.test -test.v -test.timeout 1h0m0s -remote TestChunkerChunk50bYandex: -verbose -size-limit 1024 -test.run '^(TestServerSideCopyOverSelf|TestSyncBackupDirWithSuffixKeepExtension|TestSyncConcurrentDelete|TestSyncIgnoreTimes|TestSyncWithExcludeAndDeleteExcluded|TestSyncWithTrackRenamesStrategyModtime)$'" - Finished ERROR in 6m25.045519851s (try 3/5): exit status 1: Failed [TestServerSideCopyOverSelf TestSyncConcurrentDelete]