"./operations.test -test.v -test.timeout 1h0m0s -remote TestChunkerChunk50bYandex: -verbose -size-limit 1024 -test.run '^(TestCheckSumDownload|TestDirMove)$|^TestRcat$/^withChecksum=false,ignoreChecksum=true$'" - Starting (try 2/5) 2025/04/24 07:09:59 DEBUG : Creating backend with remote "TestChunkerChunk50bYandex:rclone-test-woxulaw9hutu" 2025/04/24 07:09:59 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/04/24 07:09:59 DEBUG : Creating backend with remote "TestYandex:rclone-test-woxulaw9hutu" 2025/04/24 07:09:59 DEBUG : Creating backend with remote "/tmp/rclone3353889061" === RUN TestCheckSumDownload run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-woxulaw9hutu'", Local "Local file system at /tmp/rclone3353889061", Modify Window "1ns" 2025/04/24 07:09:59 DEBUG : Creating backend with remote "TestChunkerChunk50bYandex:rclone-test-woxulaw9hutu/data" 2025/04/24 07:09:59 DEBUG : Creating backend with remote "TestYandex:rclone-test-woxulaw9hutu/data" 2025/04/24 07:10:07 INFO : data/banana.rclone_chunk.001_7tiphw: Moved (server-side) to: data/banana 2025/04/24 07:10:14 INFO : test.sum.rclone_chunk.001_7tiwv9: Moved (server-side) to: test.sum === RUN TestCheckSumDownload/subtest1 2025/04/24 07:10:24 INFO : data/potato.rclone_chunk.001_7tj7og: Moved (server-side) to: data/potato 2025/04/24 07:10:32 INFO : test.sum: Deleted 2025/04/24 07:10:34 INFO : test.sum.rclone_chunk.001_7tjeiz: Moved (server-side) to: test.sum === RUN TestCheckSumDownload/subtest2 2025/04/24 07:10:45 INFO : test.sum.rclone_chunk.001_7tjr17: Moved (server-side) to: test.sum.rclone_chunk.001 2025/04/24 07:10:46 INFO : test.sum.rclone_chunk.002_7tjr17: Moved (server-side) to: test.sum.rclone_chunk.002 === RUN TestCheckSumDownload/subtest3 2025/04/24 07:11:16 INFO : test.sum.rclone_chunk.001_7tkea3: Moved (server-side) to: test.sum.rclone_chunk.001 2025/04/24 07:11:18 INFO : test.sum.rclone_chunk.002_7tkea3: Moved (server-side) to: test.sum.rclone_chunk.002 === RUN TestCheckSumDownload/subtest4 2025/04/24 07:11:49 INFO : test.sum.rclone_chunk.001_7tl6rr: Moved (server-side) to: test.sum.rclone_chunk.001 2025/04/24 07:11:50 INFO : test.sum.rclone_chunk.002_7tl6rr: Moved (server-side) to: test.sum.rclone_chunk.002 2025/04/24 07:11:52 INFO : test.sum.rclone_chunk.003_7tl6rr: Moved (server-side) to: test.sum.rclone_chunk.003 === RUN TestCheckSumDownload/subtest5 2025/04/24 07:12:25 INFO : test.sum.rclone_chunk.001_7tm2po: Moved (server-side) to: test.sum.rclone_chunk.001 2025/04/24 07:12:27 INFO : test.sum.rclone_chunk.002_7tm2po: Moved (server-side) to: test.sum.rclone_chunk.002 2025/04/24 07:12:29 INFO : test.sum.rclone_chunk.003_7tm2po: Moved (server-side) to: test.sum.rclone_chunk.003 === RUN TestCheckSumDownload/subtest6 2025/04/24 07:12:48 INFO : data/banana: Deleted 2025/04/24 07:12:50 INFO : data/banana.rclone_chunk.001_7tn4ws: Moved (server-side) to: data/banana 2025/04/24 07:13:03 INFO : data/potato: Deleted 2025/04/24 07:13:06 INFO : data/potato.rclone_chunk.001_7tnir1: Moved (server-side) to: data/potato 2025/04/24 07:13:29 INFO : test.sum.rclone_chunk.001_7tnw9j: Moved (server-side) to: test.sum.rclone_chunk.001 2025/04/24 07:13:31 INFO : test.sum.rclone_chunk.002_7tnw9j: Moved (server-side) to: test.sum.rclone_chunk.002 2025/04/24 07:13:33 DEBUG : pacer: low level retry 1/10 (error [500 - InternalServerError] Internal Server Error (Ошибка сервера.)) 2025/04/24 07:13:33 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2025/04/24 07:13:34 DEBUG : pacer: Reducing sleep to 15ms 2025/04/24 07:13:34 DEBUG : pacer: Reducing sleep to 11.25ms 2025/04/24 07:13:34 DEBUG : pacer: Reducing sleep to 10ms === RUN TestCheckSumDownload/subtest7 --- PASS: TestCheckSumDownload (249.28s) --- PASS: TestCheckSumDownload/subtest1 (2.72s) --- PASS: TestCheckSumDownload/subtest2 (2.18s) --- PASS: TestCheckSumDownload/subtest3 (8.26s) --- PASS: TestCheckSumDownload/subtest4 (4.47s) --- PASS: TestCheckSumDownload/subtest5 (4.15s) --- PASS: TestCheckSumDownload/subtest6 (3.96s) --- PASS: TestCheckSumDownload/subtest7 (4.32s) === RUN TestDirMove run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-woxulaw9hutu'", Local "Local file system at /tmp/rclone3353889061", Modify Window "1ns" 2025/04/24 07:14:15 INFO : A1/one.rclone_chunk.001_7tpmez: Moved (server-side) to: A1/one 2025/04/24 07:14:21 INFO : A1/two.rclone_chunk.001_7tpsy5: Moved (server-side) to: A1/two 2025/04/24 07:14:27 INFO : A1/B1/three.rclone_chunk.001_7tpyxf: Moved (server-side) to: A1/B1/three 2025/04/24 07:14:33 INFO : A1/B1/C1/four.rclone_chunk.001_7tq57x: Moved (server-side) to: A1/B1/C1/four 2025/04/24 07:14:40 INFO : A1/B1/C2/five.rclone_chunk.001_7tqa26: Moved (server-side) to: A1/B1/C2/five 2025/04/24 07:14:40 INFO : A1/B2: Making directory 2025/04/24 07:14:40 INFO : A1/B1/C3: Making directory 2025/04/24 07:14:54 DEBUG : A2/B1/three: move non-chunked object... 2025/04/24 07:14:54 DEBUG : A2/two: move non-chunked object... 2025/04/24 07:14:54 DEBUG : A2/B1/C1/four: move non-chunked object... 2025/04/24 07:14:54 DEBUG : A2/one: move non-chunked object... 2025/04/24 07:14:54 DEBUG : A2/B1/C2/five: move non-chunked object... 2025/04/24 07:14:56 INFO : A2/one: Moved (server-side) to: A3/one 2025/04/24 07:14:56 INFO : A2/one: Moved (server-side) to: A3/one 2025/04/24 07:14:56 INFO : A2/B1/C2/five: Moved (server-side) to: A3/B1/C2/five 2025/04/24 07:14:56 INFO : A2/B1/C2/five: Moved (server-side) to: A3/B1/C2/five 2025/04/24 07:14:56 INFO : A2/two: Moved (server-side) to: A3/two 2025/04/24 07:14:56 INFO : A2/two: Moved (server-side) to: A3/two 2025/04/24 07:14:58 INFO : A2/B1/C1/four: Moved (server-side) to: A3/B1/C1/four 2025/04/24 07:14:58 INFO : A2/B1/C1/four: Moved (server-side) to: A3/B1/C1/four 2025/04/24 07:14:58 INFO : A2/B1/three: Moved (server-side) to: A3/B1/three 2025/04/24 07:14:58 INFO : A2/B1/three: Moved (server-side) to: A3/B1/three 2025/04/24 07:15:34 INFO : Chunked 'TestChunkerChunk50bYandex:rclone-test-woxulaw9hutu': Can't DirMove - falling back to file moves: can't move directory - incompatible remotes 2025/04/24 07:15:38 DEBUG : A3/one: move non-chunked object... 2025/04/24 07:15:38 DEBUG : A3/B1/three: move non-chunked object... 2025/04/24 07:15:38 DEBUG : A3/B1/C2/five: move non-chunked object... 2025/04/24 07:15:38 DEBUG : A3/two: move non-chunked object... 2025/04/24 07:15:38 DEBUG : A3/B1/C1/four: move non-chunked object... 2025/04/24 07:15:39 INFO : A3/one: Moved (server-side) to: A4/one 2025/04/24 07:15:39 INFO : A3/one: Moved (server-side) to: A4/one 2025/04/24 07:15:40 INFO : A3/B1/C1/four: Moved (server-side) to: A4/B1/C1/four 2025/04/24 07:15:40 INFO : A3/B1/C1/four: Moved (server-side) to: A4/B1/C1/four 2025/04/24 07:15:40 INFO : A3/two: Moved (server-side) to: A4/two 2025/04/24 07:15:40 INFO : A3/two: Moved (server-side) to: A4/two 2025/04/24 07:15:40 INFO : A3/B1/C2/five: Moved (server-side) to: A4/B1/C2/five 2025/04/24 07:15:40 INFO : A3/B1/C2/five: Moved (server-side) to: A4/B1/C2/five 2025/04/24 07:15:41 INFO : A3/B1/three: Moved (server-side) to: A4/B1/three 2025/04/24 07:15:41 INFO : A3/B1/three: Moved (server-side) to: A4/B1/three --- PASS: TestDirMove (188.43s) === RUN TestRcat === RUN TestRcat/withChecksum=false,ignoreChecksum=true run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-woxulaw9hutu'", Local "Local file system at /tmp/rclone3353889061", Modify Window "1ns" operations_test.go:1495: Adjust StreamingUploadCutoff to size limit 1Ki (was 100Ki) 2025/04/24 07:17:17 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-woxulaw9hutu': File to upload is small (34 bytes), uploading instead of streaming 2025/04/24 07:17:22 INFO : ignore_checksum_small_file_from_pipe.rclone_chunk.001_7tuup5: Moved (server-side) to: ignore_checksum_small_file_from_pipe 2025/04/24 07:17:22 DEBUG : ignore_checksum_small_file_from_pipe: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/24 07:18:33 INFO : ignore_checksum_big_file_from_pipe.rclone_chunk.001_7tv026: Moved (server-side) to: ignore_checksum_big_file_from_pipe.rclone_chunk.001 2025/04/24 07:18:35 INFO : ignore_checksum_big_file_from_pipe.rclone_chunk.002_7tv026: Moved (server-side) to: ignore_checksum_big_file_from_pipe.rclone_chunk.002 2025/04/24 07:18:36 INFO : ignore_checksum_big_file_from_pipe.rclone_chunk.003_7tv026: Moved (server-side) to: ignore_checksum_big_file_from_pipe.rclone_chunk.003 2025/04/24 07:18:38 INFO : ignore_checksum_big_file_from_pipe.rclone_chunk.004_7tv026: Moved (server-side) to: ignore_checksum_big_file_from_pipe.rclone_chunk.004 2025/04/24 07:18:40 INFO : ignore_checksum_big_file_from_pipe.rclone_chunk.005_7tv026: Moved (server-side) to: ignore_checksum_big_file_from_pipe.rclone_chunk.005 2025/04/24 07:18:41 INFO : ignore_checksum_big_file_from_pipe.rclone_chunk.006_7tv026: Moved (server-side) to: ignore_checksum_big_file_from_pipe.rclone_chunk.006 2025/04/24 07:18:43 INFO : ignore_checksum_big_file_from_pipe.rclone_chunk.007_7tv026: Moved (server-side) to: ignore_checksum_big_file_from_pipe.rclone_chunk.007 2025/04/24 07:18:46 INFO : ignore_checksum_big_file_from_pipe.rclone_chunk.008_7tv026: Moved (server-side) to: ignore_checksum_big_file_from_pipe.rclone_chunk.008 2025/04/24 07:18:48 INFO : ignore_checksum_big_file_from_pipe.rclone_chunk.009_7tv026: Moved (server-side) to: ignore_checksum_big_file_from_pipe.rclone_chunk.009 2025/04/24 07:18:49 INFO : ignore_checksum_big_file_from_pipe.rclone_chunk.010_7tv026: Moved (server-side) to: ignore_checksum_big_file_from_pipe.rclone_chunk.010 2025/04/24 07:18:51 INFO : ignore_checksum_big_file_from_pipe.rclone_chunk.011_7tv026: Moved (server-side) to: ignore_checksum_big_file_from_pipe.rclone_chunk.011 2025/04/24 07:18:59 DEBUG : Couldn't decode error response: invalid character 'S' looking for beginning of value 2025/04/24 07:18:59 ERROR : ignore_checksum_big_file_from_pipe.rclone_chunk.012_7tv026: Couldn't move: couldn't move file: [504 - ] (504 Gateway time out) 2025/04/24 07:19:03 INFO : ignore_checksum_big_file_from_pipe.rclone_chunk.012_7tv026: Moved (server-side) to: ignore_checksum_big_file_from_pipe.rclone_chunk.012 2025/04/24 07:19:04 INFO : ignore_checksum_big_file_from_pipe.rclone_chunk.013_7tv026: Moved (server-side) to: ignore_checksum_big_file_from_pipe.rclone_chunk.013 2025/04/24 07:19:06 INFO : ignore_checksum_big_file_from_pipe.rclone_chunk.014_7tv026: Moved (server-side) to: ignore_checksum_big_file_from_pipe.rclone_chunk.014 2025/04/24 07:19:08 INFO : ignore_checksum_big_file_from_pipe.rclone_chunk.015_7tv026: Moved (server-side) to: ignore_checksum_big_file_from_pipe.rclone_chunk.015 2025/04/24 07:19:09 INFO : ignore_checksum_big_file_from_pipe.rclone_chunk.016_7tv026: Moved (server-side) to: ignore_checksum_big_file_from_pipe.rclone_chunk.016 2025/04/24 07:19:11 INFO : ignore_checksum_big_file_from_pipe.rclone_chunk.017_7tv026: Moved (server-side) to: ignore_checksum_big_file_from_pipe.rclone_chunk.017 2025/04/24 07:19:13 INFO : ignore_checksum_big_file_from_pipe.rclone_chunk.018_7tv026: Moved (server-side) to: ignore_checksum_big_file_from_pipe.rclone_chunk.018 2025/04/24 07:19:15 INFO : ignore_checksum_big_file_from_pipe.rclone_chunk.019_7tv026: Moved (server-side) to: ignore_checksum_big_file_from_pipe.rclone_chunk.019 2025/04/24 07:19:17 INFO : ignore_checksum_big_file_from_pipe.rclone_chunk.020_7tv026: Moved (server-side) to: ignore_checksum_big_file_from_pipe.rclone_chunk.020 2025/04/24 07:19:19 INFO : ignore_checksum_big_file_from_pipe.rclone_chunk.021_7tv026: Moved (server-side) to: ignore_checksum_big_file_from_pipe.rclone_chunk.021 2025/04/24 07:19:21 DEBUG : ignore_checksum_big_file_from_pipe: Size and modification time the same (differ by 0s, within tolerance 1ns) --- PASS: TestRcat (230.04s) --- PASS: TestRcat/withChecksum=false,ignoreChecksum=true (230.04s) PASS 2025/04/24 07:21:07 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-woxulaw9hutu': Purge remote "./operations.test -test.v -test.timeout 1h0m0s -remote TestChunkerChunk50bYandex: -verbose -size-limit 1024 -test.run '^(TestCheckSumDownload|TestDirMove)$|^TestRcat$/^withChecksum=false,ignoreChecksum=true$'" - Finished OK in 11m13.242063827s (try 2/5)