"./operations.test -test.v -test.timeout 1h0m0s -remote TestChunkerChunk50bYandex: -verbose -fast-list -size-limit 1024 -test.run '^(TestCheckSizeOnly|TestCopyFileCopyDest|TestDeduplicateNewestByHash|TestDirMove|TestRemoveExisting)$'" - Starting (try 2/5) 2025/01/23 04:47:51 DEBUG : Creating backend with remote "TestChunkerChunk50bYandex:rclone-test-risinok3xose" 2025/01/23 04:47:51 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/01/23 04:47:51 DEBUG : Creating backend with remote "TestYandex:rclone-test-risinok3xose" 2025/01/23 04:47:52 DEBUG : Reset feature "ListR" 2025/01/23 04:47:52 DEBUG : Creating backend with remote "/tmp/rclone1640015448" === RUN TestCheckSizeOnly run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-risinok3xose'", Local "Local file system at /tmp/rclone1640015448", Modify Window "1ns" 2025/01/23 04:47:57 INFO : rutabaga.rclone_chunk.001_j48u4b: Moved (server-side) to: rutabaga === RUN TestCheckSizeOnly/1 === RUN TestCheckSizeOnly/2 2025/01/23 04:48:02 INFO : empty space.rclone_chunk.001_j4901v: Moved (server-side) to: empty space === RUN TestCheckSizeOnly/3 2025/01/23 04:48:10 INFO : potato2.rclone_chunk.001_j496xk: Moved (server-side) to: potato2.rclone_chunk.001 2025/01/23 04:48:11 INFO : potato2.rclone_chunk.002_j496xk: Moved (server-side) to: potato2.rclone_chunk.002 === RUN TestCheckSizeOnly/4 === RUN TestCheckSizeOnly/5 2025/01/23 04:48:22 INFO : remotepotato.rclone_chunk.001_j49jvz: Moved (server-side) to: remotepotato.rclone_chunk.001 2025/01/23 04:48:24 INFO : remotepotato.rclone_chunk.002_j49jvz: Moved (server-side) to: remotepotato.rclone_chunk.002 === RUN TestCheckSizeOnly/6 === RUN TestCheckSizeOnly/7 --- PASS: TestCheckSizeOnly (67.45s) --- PASS: TestCheckSizeOnly/1 (0.41s) --- PASS: TestCheckSizeOnly/2 (0.36s) --- PASS: TestCheckSizeOnly/3 (0.73s) --- PASS: TestCheckSizeOnly/4 (0.21s) --- PASS: TestCheckSizeOnly/5 (0.54s) --- PASS: TestCheckSizeOnly/6 (0.52s) --- PASS: TestCheckSizeOnly/7 (0.31s) === RUN TestCopyFileCopyDest run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-risinok3xose'", Local "Local file system at /tmp/rclone1640015448", Modify Window "1ns" 2025/01/23 04:48:59 DEBUG : Creating backend with remote "TestChunkerChunk50bYandex:rclone-test-risinok3xose/dst" 2025/01/23 04:48:59 DEBUG : Creating backend with remote "TestYandex:rclone-test-risinok3xose/dst" 2025/01/23 04:48:59 DEBUG : Reset feature "ListR" 2025/01/23 04:49:00 DEBUG : Creating backend with remote "TestChunkerChunk50bYandex:rclone-test-risinok3xose/CopyDest" 2025/01/23 04:49:00 DEBUG : Creating backend with remote "TestYandex:rclone-test-risinok3xose/CopyDest" 2025/01/23 04:49:01 DEBUG : Reset feature "ListR" 2025/01/23 04:49:01 DEBUG : one: Need to transfer - File not found at Destination 2025/01/23 04:49:01 DEBUG : one: skip slow MD5 on source file, hashing in-transit 2025/01/23 04:49:06 INFO : one.rclone_chunk.001_j4arlq: Moved (server-side) to: one 2025/01/23 04:49:06 DEBUG : one: md5 = f97c5d29941bfb1b2fdab0874906ab82 OK 2025/01/23 04:49:06 INFO : one: Copied (new) 2025/01/23 04:49:08 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/01/23 04:49:08 DEBUG : one: skip slow MD5 on source file, hashing in-transit 2025/01/23 04:49:15 INFO : one: Deleted 2025/01/23 04:49:16 INFO : one.rclone_chunk.001_j4ay1u: Moved (server-side) to: one 2025/01/23 04:49:16 DEBUG : one: md5 = 07912d142f5d63ee918b34796b5a2432 OK 2025/01/23 04:49:16 INFO : one: Copied (replaced existing) 2025/01/23 04:49:25 INFO : dst/one: Deleted 2025/01/23 04:49:26 INFO : dst/one.rclone_chunk.001_j4b7px: Moved (server-side) to: dst/one 2025/01/23 04:49:34 INFO : CopyDest/one.rclone_chunk.001_j4bg3y: Moved (server-side) to: CopyDest/one 2025/01/23 04:49:36 DEBUG : Creating backend with remote "TestChunkerChunk50bYandex:rclone-test-risinok3xose/BackupDir" 2025/01/23 04:49:36 DEBUG : Creating backend with remote "TestYandex:rclone-test-risinok3xose/BackupDir" 2025/01/23 04:49:37 DEBUG : Reset feature "ListR" 2025/01/23 04:49:37 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/01/23 04:49:38 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/01/23 04:49:38 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/01/23 04:49:38 DEBUG : one: move non-chunked object... 2025/01/23 04:49:40 INFO : one: Moved (server-side) 2025/01/23 04:49:40 INFO : one: Moved (server-side) 2025/01/23 04:49:40 DEBUG : one: copy non-chunked object... 2025/01/23 04:49:42 DEBUG : one: md5 = 07912d142f5d63ee918b34796b5a2432 OK 2025/01/23 04:49:42 INFO : one: Copied (server-side copy) 2025/01/23 04:49:42 DEBUG : one: Destination found in --copy-dest, using server-side copy 2025/01/23 04:49:48 INFO : CopyDest/two.rclone_chunk.001_j4bx5v: Moved (server-side) to: CopyDest/two 2025/01/23 04:49:49 DEBUG : two: Need to transfer - File not found at Destination 2025/01/23 04:49:50 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/01/23 04:49:50 DEBUG : two: copy non-chunked object... 2025/01/23 04:49:52 DEBUG : two: md5 = b8a9f715dbb64fd5c56e7783c6820a61 OK 2025/01/23 04:49:52 INFO : two: Copied (server-side copy) 2025/01/23 04:49:52 DEBUG : two: Destination found in --copy-dest, using server-side copy 2025/01/23 04:49:53 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/01/23 04:49:53 DEBUG : two: Unchanged skipping 2025/01/23 04:49:58 INFO : CopyDest/three.rclone_chunk.001_j4c8zp: Moved (server-side) to: CopyDest/three 2025/01/23 04:49:59 DEBUG : three: Need to transfer - File not found at Destination 2025/01/23 04:50:00 DEBUG : three: Sizes differ (src 7 vs dst 5) 2025/01/23 04:50:00 DEBUG : three: Destination not found in --copy-dest 2025/01/23 04:50:01 DEBUG : three: skip slow MD5 on source file, hashing in-transit 2025/01/23 04:50:05 INFO : three.rclone_chunk.001_j4cfy3: Moved (server-side) to: three 2025/01/23 04:50:05 DEBUG : three: md5 = 1bccb9dccb3e9f6a3f9d2a8bdb54b7f5 OK 2025/01/23 04:50:05 INFO : three: Copied (new) --- PASS: TestCopyFileCopyDest (101.83s) === RUN TestDeduplicateNewestByHash run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-risinok3xose'", Local "Local file system at /tmp/rclone1640015448", Modify Window "1ns" 2025/01/23 04:50:43 DEBUG : pacer: low level retry 1/10 (error [500 - InternalServerError] Internal Server Error (Ошибка сервера.)) 2025/01/23 04:50:43 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2025/01/23 04:50:43 DEBUG : pacer: Reducing sleep to 15ms 2025/01/23 04:50:44 DEBUG : pacer: Reducing sleep to 11.25ms 2025/01/23 04:50:44 DEBUG : pacer: Reducing sleep to 10ms 2025/01/23 04:50:48 INFO : one.rclone_chunk.001_j4djpy: Moved (server-side) to: one.rclone_chunk.001 2025/01/23 04:50:49 INFO : one.rclone_chunk.002_j4djpy: Moved (server-side) to: one.rclone_chunk.002 2025/01/23 04:50:58 INFO : also/one.rclone_chunk.001_j4dut0: Moved (server-side) to: also/one.rclone_chunk.001 2025/01/23 04:51:00 INFO : also/one.rclone_chunk.002_j4dut0: Moved (server-side) to: also/one.rclone_chunk.002 2025/01/23 04:51:09 INFO : another.rclone_chunk.001_j4e4ps: Moved (server-side) to: another.rclone_chunk.001 2025/01/23 04:51:10 INFO : another.rclone_chunk.002_j4e4ps: Moved (server-side) to: another.rclone_chunk.002 2025/01/23 04:51:17 INFO : not-one.rclone_chunk.001_j4eeyt: Moved (server-side) to: not-one 2025/01/23 04:51:19 INFO : Chunked 'TestChunkerChunk50bYandex:rclone-test-risinok3xose': Looking for duplicate md5 hashes using newest mode. 2025/01/23 04:51:21 NOTICE: 07ca2a61a5d4769bb5bcbdb2634da2cd: Found 3 files with duplicate md5 hashes 2025/01/23 04:51:30 INFO : one: Deleted 2025/01/23 04:51:39 INFO : also/one: Deleted 2025/01/23 04:51:39 NOTICE: 07ca2a61a5d4769bb5bcbdb2634da2cd: Deleted 2 extra copies --- PASS: TestDeduplicateNewestByHash (77.32s) === RUN TestDirMove run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-risinok3xose'", Local "Local file system at /tmp/rclone1640015448", Modify Window "1ns" 2025/01/23 04:52:04 INFO : A1/one.rclone_chunk.001_j4fp7y: Moved (server-side) to: A1/one 2025/01/23 04:52:08 INFO : A1/two.rclone_chunk.001_j4fu5e: Moved (server-side) to: A1/two 2025/01/23 04:52:13 INFO : A1/B1/three.rclone_chunk.001_j4fyyi: Moved (server-side) to: A1/B1/three 2025/01/23 04:52:18 INFO : A1/B1/C1/four.rclone_chunk.001_j4g3wy: Moved (server-side) to: A1/B1/C1/four 2025/01/23 04:52:23 INFO : A1/B1/C2/five.rclone_chunk.001_j4g8ru: Moved (server-side) to: A1/B1/C2/five 2025/01/23 04:52:23 INFO : A1/B2: Making directory 2025/01/23 04:52:23 INFO : A1/B1/C3: Making directory 2025/01/23 04:52:39 DEBUG : A2/B1/C2/five: move non-chunked object... 2025/01/23 04:52:39 DEBUG : A2/B1/three: move non-chunked object... 2025/01/23 04:52:39 DEBUG : A2/one: move non-chunked object... 2025/01/23 04:52:39 DEBUG : A2/two: move non-chunked object... 2025/01/23 04:52:39 DEBUG : A2/B1/C1/four: move non-chunked object... 2025/01/23 04:52:40 INFO : A2/one: Moved (server-side) to: A3/one 2025/01/23 04:52:40 INFO : A2/one: Moved (server-side) to: A3/one 2025/01/23 04:52:40 INFO : A2/B1/three: Moved (server-side) to: A3/B1/three 2025/01/23 04:52:40 INFO : A2/B1/three: Moved (server-side) to: A3/B1/three 2025/01/23 04:52:40 INFO : A2/two: Moved (server-side) to: A3/two 2025/01/23 04:52:40 INFO : A2/two: Moved (server-side) to: A3/two 2025/01/23 04:52:40 INFO : A2/B1/C2/five: Moved (server-side) to: A3/B1/C2/five 2025/01/23 04:52:40 INFO : A2/B1/C2/five: Moved (server-side) to: A3/B1/C2/five 2025/01/23 04:52:41 INFO : A2/B1/C1/four: Moved (server-side) to: A3/B1/C1/four 2025/01/23 04:52:41 INFO : A2/B1/C1/four: Moved (server-side) to: A3/B1/C1/four 2025/01/23 04:53:06 INFO : Chunked 'TestChunkerChunk50bYandex:rclone-test-risinok3xose': Can't DirMove - falling back to file moves: can't move directory - incompatible remotes 2025/01/23 04:53:11 DEBUG : A3/B1/C1/four: move non-chunked object... 2025/01/23 04:53:11 DEBUG : A3/one: move non-chunked object... 2025/01/23 04:53:11 DEBUG : A3/two: move non-chunked object... 2025/01/23 04:53:11 DEBUG : A3/B1/C2/five: move non-chunked object... 2025/01/23 04:53:11 DEBUG : A3/B1/three: move non-chunked object... 2025/01/23 04:53:12 INFO : A3/B1/C1/four: Moved (server-side) to: A4/B1/C1/four 2025/01/23 04:53:12 INFO : A3/B1/C1/four: Moved (server-side) to: A4/B1/C1/four 2025/01/23 04:53:12 INFO : A3/two: Moved (server-side) to: A4/two 2025/01/23 04:53:12 INFO : A3/two: Moved (server-side) to: A4/two 2025/01/23 04:53:12 INFO : A3/B1/C2/five: Moved (server-side) to: A4/B1/C2/five 2025/01/23 04:53:12 INFO : A3/B1/C2/five: Moved (server-side) to: A4/B1/C2/five 2025/01/23 04:53:13 INFO : A3/one: Moved (server-side) to: A4/one 2025/01/23 04:53:13 INFO : A3/one: Moved (server-side) to: A4/one 2025/01/23 04:53:15 INFO : A3/B1/three: Moved (server-side) to: A4/B1/three 2025/01/23 04:53:15 INFO : A3/B1/three: Moved (server-side) to: A4/B1/three --- PASS: TestDirMove (143.24s) === RUN TestRemoveExisting run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-risinok3xose'", Local "Local file system at /tmp/rclone1640015448", Modify Window "1ns" 2025/01/23 04:54:26 INFO : sub dir/test remove existing.rclone_chunk.001_j4joym: Moved (server-side) to: sub dir/test remove existing 2025/01/23 04:54:30 INFO : sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890123456789.rclone_chunk.001_j4jsks: Moved (server-side) to: sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890123456789 2025/01/23 04:54:33 DEBUG : sub dir/test remove existing: TEST: renaming existing object to "sub dir/test remove existing.nupetox8" before starting 2025/01/23 04:54:33 DEBUG : sub dir/test remove existing: move non-chunked object... 2025/01/23 04:54:35 INFO : sub dir/test remove existing: Moved (server-side) to: sub dir/test remove existing.nupetox8 2025/01/23 04:54:37 DEBUG : sub dir/test remove existing.nupetox8: TEST: removing renamed existing file after operation 2025/01/23 04:54:41 DEBUG : sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890123456789: TEST: renaming existing object to "sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890.mugitel9" before starting 2025/01/23 04:54:41 DEBUG : sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890123456789: move non-chunked object... 2025/01/23 04:54:43 INFO : sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890123456789: Moved (server-side) to: sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890.mugitel9 2025/01/23 04:54:44 DEBUG : sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890.mugitel9: TEST: renaming existing back after failed operation 2025/01/23 04:54:44 DEBUG : sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890.mugitel9: move non-chunked object... 2025/01/23 04:54:47 INFO : sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890.mugitel9: Moved (server-side) to: sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890123456789 2025/01/23 04:54:49 DEBUG : sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890123456789: TEST: renaming existing object to "sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890.domusus1" before starting 2025/01/23 04:54:49 DEBUG : sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890123456789: move non-chunked object... 2025/01/23 04:54:50 INFO : sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890123456789: Moved (server-side) to: sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890.domusus1 2025/01/23 04:54:51 DEBUG : sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890.domusus1: TEST: removing renamed existing file after operation --- PASS: TestRemoveExisting (38.41s) PASS 2025/01/23 04:55:00 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-risinok3xose': Purge remote "./operations.test -test.v -test.timeout 1h0m0s -remote TestChunkerChunk50bYandex: -verbose -fast-list -size-limit 1024 -test.run '^(TestCheckSizeOnly|TestCopyFileCopyDest|TestDeduplicateNewestByHash|TestDirMove|TestRemoveExisting)$'" - Finished OK in 7m14.161586689s (try 2/5)