"./operations.test -test.v -test.timeout 1h0m0s -remote TestChunkerChunk50bYandex: -verbose -size-limit 1024 -test.run '^(TestCheckSum|TestCheckSumDownload)$'" - Starting (try 3/5) 2025/01/12 02:54:27 DEBUG : Creating backend with remote "TestChunkerChunk50bYandex:rclone-test-lecufav0mitu" 2025/01/12 02:54:27 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/01/12 02:54:27 DEBUG : Creating backend with remote "TestYandex:rclone-test-lecufav0mitu" 2025/01/12 02:54:28 DEBUG : Reset feature "ListR" 2025/01/12 02:54:28 DEBUG : Creating backend with remote "/tmp/rclone2626089972" === RUN TestCheckSum run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-lecufav0mitu'", Local "Local file system at /tmp/rclone2626089972", Modify Window "1ns" 2025/01/12 02:54:28 DEBUG : Creating backend with remote "TestChunkerChunk50bYandex:rclone-test-lecufav0mitu/data" 2025/01/12 02:54:28 DEBUG : Creating backend with remote "TestYandex:rclone-test-lecufav0mitu/data" 2025/01/12 02:54:28 DEBUG : Reset feature "ListR" 2025/01/12 02:54:36 INFO : data/banana.rclone_chunk.001_ylnoz6: Moved (server-side) to: data/banana 2025/01/12 02:54:43 INFO : test.sum.rclone_chunk.001_ylnvzb: Moved (server-side) to: test.sum === RUN TestCheckSum/subtest1 2025/01/12 02:54:52 INFO : data/potato.rclone_chunk.001_ylo65j: Moved (server-side) to: data/potato 2025/01/12 02:55:02 INFO : test.sum: Deleted 2025/01/12 02:55:04 INFO : test.sum.rclone_chunk.001_yloch8: Moved (server-side) to: test.sum === RUN TestCheckSum/subtest2 2025/01/12 02:55:20 INFO : test.sum.rclone_chunk.001_ylostw: Moved (server-side) to: test.sum.rclone_chunk.001 2025/01/12 02:55:22 INFO : test.sum.rclone_chunk.002_ylostw: Moved (server-side) to: test.sum.rclone_chunk.002 === RUN TestCheckSum/subtest3 2025/01/12 02:55:35 DEBUG : pacer: low level retry 1/10 (error [500 - InternalServerError] Internal Server Error (Ошибка сервера.)) 2025/01/12 02:55:35 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2025/01/12 02:55:35 DEBUG : pacer: Reducing sleep to 15ms 2025/01/12 02:55:36 DEBUG : pacer: Reducing sleep to 11.25ms 2025/01/12 02:55:36 DEBUG : pacer: Reducing sleep to 10ms 2025/01/12 02:55:50 INFO : test.sum.rclone_chunk.001_ylpen1: Moved (server-side) to: test.sum.rclone_chunk.001 2025/01/12 02:55:52 INFO : test.sum.rclone_chunk.002_ylpen1: Moved (server-side) to: test.sum.rclone_chunk.002 === RUN TestCheckSum/subtest4 2025/01/12 02:56:19 INFO : test.sum.rclone_chunk.001_ylq79c: Moved (server-side) to: test.sum.rclone_chunk.001 2025/01/12 02:56:21 INFO : test.sum.rclone_chunk.002_ylq79c: Moved (server-side) to: test.sum.rclone_chunk.002 2025/01/12 02:56:23 INFO : test.sum.rclone_chunk.003_ylq79c: Moved (server-side) to: test.sum.rclone_chunk.003 === RUN TestCheckSum/subtest5 2025/01/12 02:56:36 DEBUG : pacer: low level retry 1/10 (error [500 - InternalServerError] Internal Server Error (Ошибка сервера.)) 2025/01/12 02:56:36 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2025/01/12 02:56:37 DEBUG : pacer: Reducing sleep to 15ms 2025/01/12 02:56:38 DEBUG : pacer: Reducing sleep to 11.25ms 2025/01/12 02:56:38 DEBUG : pacer: Reducing sleep to 10ms 2025/01/12 02:56:40 DEBUG : pacer: low level retry 1/10 (error [500 - InternalServerError] Internal Server Error (Ошибка сервера.)) 2025/01/12 02:56:40 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2025/01/12 02:56:40 DEBUG : pacer: Reducing sleep to 15ms 2025/01/12 02:56:41 DEBUG : pacer: Reducing sleep to 11.25ms 2025/01/12 02:56:41 DEBUG : pacer: Reducing sleep to 10ms 2025/01/12 02:56:58 INFO : test.sum.rclone_chunk.001_ylr4mr: Moved (server-side) to: test.sum.rclone_chunk.001 2025/01/12 02:57:01 INFO : test.sum.rclone_chunk.002_ylr4mr: Moved (server-side) to: test.sum.rclone_chunk.002 2025/01/12 02:57:02 INFO : test.sum.rclone_chunk.003_ylr4mr: Moved (server-side) to: test.sum.rclone_chunk.003 === RUN TestCheckSum/subtest6 2025/01/12 02:57:21 INFO : data/banana: Deleted 2025/01/12 02:57:24 INFO : data/banana.rclone_chunk.001_yls8gr: Moved (server-side) to: data/banana 2025/01/12 02:57:34 INFO : data/potato: Deleted 2025/01/12 02:57:36 INFO : data/potato.rclone_chunk.001_ylslke: Moved (server-side) to: data/potato 2025/01/12 02:57:57 INFO : test.sum.rclone_chunk.001_ylswwe: Moved (server-side) to: test.sum.rclone_chunk.001 2025/01/12 02:57:59 INFO : test.sum.rclone_chunk.002_ylswwe: Moved (server-side) to: test.sum.rclone_chunk.002 === RUN TestCheckSum/subtest7 --- PASS: TestCheckSum (246.92s) --- PASS: TestCheckSum/subtest1 (2.74s) --- PASS: TestCheckSum/subtest2 (2.40s) --- PASS: TestCheckSum/subtest3 (3.35s) --- PASS: TestCheckSum/subtest4 (2.76s) --- PASS: TestCheckSum/subtest5 (4.25s) --- PASS: TestCheckSum/subtest6 (3.72s) --- PASS: TestCheckSum/subtest7 (3.17s) === RUN TestCheckSumDownload run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-lecufav0mitu'", Local "Local file system at /tmp/rclone2626089972", Modify Window "1ns" 2025/01/12 02:58:35 DEBUG : Creating backend with remote "TestChunkerChunk50bYandex:rclone-test-lecufav0mitu/data" 2025/01/12 02:58:35 DEBUG : Config file has changed externally - reloading 2025/01/12 02:58:35 DEBUG : Creating backend with remote "TestYandex:rclone-test-lecufav0mitu/data" 2025/01/12 02:58:35 DEBUG : Reset feature "ListR" 2025/01/12 02:58:42 INFO : data/banana.rclone_chunk.001_yluinl: Moved (server-side) to: data/banana 2025/01/12 02:58:48 INFO : test.sum.rclone_chunk.001_yluqnt: Moved (server-side) to: test.sum fstest.go:121: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:121 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:126 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:145 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:189 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:302 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:344 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:340 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:440 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:545 Error: Should be true Test: TestCheckSumDownload Messages: data/banana: Modification time difference too big |-209854h53m34.500000001s| > 1ns (want 2001-02-03 04:05:06.499999999 +0000 UTC vs got 2025-01-12 02:58:41 +0000 UTC) (precision 1ns) === RUN TestCheckSumDownload/subtest1 2025/01/12 02:58:59 INFO : data/potato.rclone_chunk.001_ylv0wn: Moved (server-side) to: data/potato 2025/01/12 02:59:09 INFO : test.sum: Deleted 2025/01/12 02:59:11 INFO : test.sum.rclone_chunk.001_ylv7p7: Moved (server-side) to: test.sum === NAME TestCheckSumDownload fstest.go:121: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:121 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:126 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:145 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:189 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:302 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:344 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:340 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:454 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:545 Error: Should be true Test: TestCheckSumDownload Messages: data/banana: Modification time difference too big |-209854h53m34.500000001s| > 1ns (want 2001-02-03 04:05:06.499999999 +0000 UTC vs got 2025-01-12 02:58:41 +0000 UTC) (precision 1ns) === RUN TestCheckSumDownload/subtest2 2025/01/12 02:59:25 INFO : test.sum.rclone_chunk.001_ylvnuq: Moved (server-side) to: test.sum.rclone_chunk.001 2025/01/12 02:59:27 INFO : test.sum.rclone_chunk.002_ylvnuq: Moved (server-side) to: test.sum.rclone_chunk.002 === NAME TestCheckSumDownload fstest.go:121: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:121 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:126 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:145 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:189 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:302 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:344 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:340 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:468 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:545 Error: Should be true Test: TestCheckSumDownload Messages: data/banana: Modification time difference too big |-209854h53m34.500000001s| > 1ns (want 2001-02-03 04:05:06.499999999 +0000 UTC vs got 2025-01-12 02:58:41 +0000 UTC) (precision 1ns) === RUN TestCheckSumDownload/subtest3 2025/01/12 02:59:52 INFO : test.sum.rclone_chunk.001_ylw8qs: Moved (server-side) to: test.sum.rclone_chunk.001 2025/01/12 02:59:54 INFO : test.sum.rclone_chunk.002_ylw8qs: Moved (server-side) to: test.sum.rclone_chunk.002 === NAME TestCheckSumDownload fstest.go:121: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:121 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:126 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:145 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:189 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:302 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:344 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:340 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:482 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:545 Error: Should be true Test: TestCheckSumDownload Messages: data/banana: Modification time difference too big |-209854h53m34.500000001s| > 1ns (want 2001-02-03 04:05:06.499999999 +0000 UTC vs got 2025-01-12 02:58:41 +0000 UTC) (precision 1ns) === RUN TestCheckSumDownload/subtest4 2025/01/12 03:00:30 INFO : test.sum.rclone_chunk.001_ylx11y: Moved (server-side) to: test.sum.rclone_chunk.001 2025/01/12 03:00:33 INFO : test.sum.rclone_chunk.002_ylx11y: Moved (server-side) to: test.sum.rclone_chunk.002 2025/01/12 03:00:38 INFO : test.sum.rclone_chunk.003_ylx11y: Moved (server-side) to: test.sum.rclone_chunk.003 === NAME TestCheckSumDownload fstest.go:121: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:121 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:126 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:145 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:189 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:302 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:344 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:340 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:497 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:545 Error: Should be true Test: TestCheckSumDownload Messages: data/banana: Modification time difference too big |-209854h53m34.500000001s| > 1ns (want 2001-02-03 04:05:06.499999999 +0000 UTC vs got 2025-01-12 02:58:41 +0000 UTC) (precision 1ns) === RUN TestCheckSumDownload/subtest5 2025/01/12 03:01:15 INFO : test.sum.rclone_chunk.001_ylybmv: Moved (server-side) to: test.sum.rclone_chunk.001 2025/01/12 03:01:17 INFO : test.sum.rclone_chunk.002_ylybmv: Moved (server-side) to: test.sum.rclone_chunk.002 2025/01/12 03:01:19 INFO : test.sum.rclone_chunk.003_ylybmv: Moved (server-side) to: test.sum.rclone_chunk.003 === NAME TestCheckSumDownload fstest.go:121: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:121 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:126 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:145 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:189 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:302 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:344 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:340 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:512 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:545 Error: Should be true Test: TestCheckSumDownload Messages: data/banana: Modification time difference too big |-209854h53m34.500000001s| > 1ns (want 2001-02-03 04:05:06.499999999 +0000 UTC vs got 2025-01-12 02:58:41 +0000 UTC) (precision 1ns) === RUN TestCheckSumDownload/subtest6 2025/01/12 03:01:40 INFO : data/banana: Deleted 2025/01/12 03:01:42 INFO : data/banana.rclone_chunk.001_ylzc81: Moved (server-side) to: data/banana 2025/01/12 03:01:53 INFO : data/potato: Deleted 2025/01/12 03:01:55 INFO : data/potato.rclone_chunk.001_ylzqeh: Moved (server-side) to: data/potato 2025/01/12 03:02:19 INFO : test.sum.rclone_chunk.001_ym04fe: Moved (server-side) to: test.sum.rclone_chunk.001 2025/01/12 03:02:23 INFO : test.sum.rclone_chunk.002_ym04fe: Moved (server-side) to: test.sum.rclone_chunk.002 === RUN TestCheckSumDownload/subtest7 --- FAIL: TestCheckSumDownload (267.36s) --- PASS: TestCheckSumDownload/subtest1 (2.84s) --- PASS: TestCheckSumDownload/subtest2 (2.89s) --- PASS: TestCheckSumDownload/subtest3 (3.76s) --- PASS: TestCheckSumDownload/subtest4 (3.88s) --- PASS: TestCheckSumDownload/subtest5 (6.11s) --- PASS: TestCheckSumDownload/subtest6 (4.62s) --- PASS: TestCheckSumDownload/subtest7 (3.98s) FAIL 2025/01/12 03:03:02 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-lecufav0mitu': Purge remote "./operations.test -test.v -test.timeout 1h0m0s -remote TestChunkerChunk50bYandex: -verbose -size-limit 1024 -test.run '^(TestCheckSum|TestCheckSumDownload)$'" - Finished ERROR in 8m38.92609044s (try 3/5): exit status 1: Failed [TestCheckSumDownload]