"./operations.test -test.v -test.timeout 1h0m0s -remote TestChunkerChunk50bYandex: -verbose -size-limit 1024 -test.run '^(TestCheckSum|TestDeduplicateNewestByHash|TestLs|TestRmdirsNoLeaveRoot)$'" - Starting (try 2/5) 2025/01/14 03:01:31 DEBUG : Creating backend with remote "TestChunkerChunk50bYandex:rclone-test-zohuraw6supe" 2025/01/14 03:01:31 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/01/14 03:01:31 DEBUG : Creating backend with remote "TestYandex:rclone-test-zohuraw6supe" 2025/01/14 03:01:32 DEBUG : Reset feature "ListR" 2025/01/14 03:01:32 DEBUG : Creating backend with remote "/tmp/rclone1192422503" === RUN TestCheckSum run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-zohuraw6supe'", Local "Local file system at /tmp/rclone1192422503", Modify Window "1ns" 2025/01/14 03:01:32 DEBUG : Creating backend with remote "TestChunkerChunk50bYandex:rclone-test-zohuraw6supe/data" 2025/01/14 03:01:32 DEBUG : Creating backend with remote "TestYandex:rclone-test-zohuraw6supe/data" 2025/01/14 03:01:32 DEBUG : Reset feature "ListR" 2025/01/14 03:01:39 INFO : data/banana.rclone_chunk.001_2bbn05: Moved (server-side) to: data/banana 2025/01/14 03:01:44 INFO : test.sum.rclone_chunk.001_2bbtxk: Moved (server-side) to: test.sum === RUN TestCheckSum/subtest1 2025/01/14 03:01:55 INFO : data/potato.rclone_chunk.001_2bc2xf: Moved (server-side) to: data/potato 2025/01/14 03:02:04 INFO : test.sum: Deleted 2025/01/14 03:02:06 INFO : test.sum.rclone_chunk.001_2bca4g: Moved (server-side) to: test.sum === RUN TestCheckSum/subtest2 2025/01/14 03:02:19 INFO : test.sum.rclone_chunk.001_2bcppe: Moved (server-side) to: test.sum.rclone_chunk.001 2025/01/14 03:02:21 INFO : test.sum.rclone_chunk.002_2bcppe: Moved (server-side) to: test.sum.rclone_chunk.002 === RUN TestCheckSum/subtest3 2025/01/14 03:02:50 INFO : test.sum.rclone_chunk.001_2bdcrg: Moved (server-side) to: test.sum.rclone_chunk.001 2025/01/14 03:02:52 INFO : test.sum.rclone_chunk.002_2bdcrg: Moved (server-side) to: test.sum.rclone_chunk.002 === RUN TestCheckSum/subtest4 2025/01/14 03:03:19 INFO : test.sum.rclone_chunk.001_2be36w: Moved (server-side) to: test.sum.rclone_chunk.001 2025/01/14 03:03:21 INFO : test.sum.rclone_chunk.002_2be36w: Moved (server-side) to: test.sum.rclone_chunk.002 2025/01/14 03:03:23 INFO : test.sum.rclone_chunk.003_2be36w: Moved (server-side) to: test.sum.rclone_chunk.003 === RUN TestCheckSum/subtest5 2025/01/14 03:03:54 INFO : test.sum.rclone_chunk.001_2bezz2: Moved (server-side) to: test.sum.rclone_chunk.001 2025/01/14 03:03:56 INFO : test.sum.rclone_chunk.002_2bezz2: Moved (server-side) to: test.sum.rclone_chunk.002 2025/01/14 03:03:58 INFO : test.sum.rclone_chunk.003_2bezz2: Moved (server-side) to: test.sum.rclone_chunk.003 === RUN TestCheckSum/subtest6 2025/01/14 03:04:19 INFO : data/banana: Deleted 2025/01/14 03:04:21 INFO : data/banana.rclone_chunk.001_2bg1a3: Moved (server-side) to: data/banana 2025/01/14 03:04:29 INFO : data/potato: Deleted 2025/01/14 03:04:32 INFO : data/potato.rclone_chunk.001_2bgczt: Moved (server-side) to: data/potato 2025/01/14 03:04:55 INFO : test.sum.rclone_chunk.001_2bgnou: Moved (server-side) to: test.sum.rclone_chunk.001 2025/01/14 03:04:57 INFO : test.sum.rclone_chunk.002_2bgnou: Moved (server-side) to: test.sum.rclone_chunk.002 === RUN TestCheckSum/subtest7 --- PASS: TestCheckSum (236.30s) --- PASS: TestCheckSum/subtest1 (2.59s) --- PASS: TestCheckSum/subtest2 (2.09s) --- PASS: TestCheckSum/subtest3 (2.94s) --- PASS: TestCheckSum/subtest4 (2.75s) --- PASS: TestCheckSum/subtest5 (3.60s) --- PASS: TestCheckSum/subtest6 (3.54s) --- PASS: TestCheckSum/subtest7 (2.64s) === RUN TestDeduplicateNewestByHash run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-zohuraw6supe'", Local "Local file system at /tmp/rclone1192422503", Modify Window "1ns" 2025/01/14 03:05:34 DEBUG : pacer: low level retry 1/10 (error [500 - InternalServerError] Internal Server Error (Ошибка сервера.)) 2025/01/14 03:05:34 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2025/01/14 03:05:35 DEBUG : pacer: Reducing sleep to 15ms 2025/01/14 03:05:35 DEBUG : pacer: Reducing sleep to 11.25ms 2025/01/14 03:05:35 DEBUG : pacer: Reducing sleep to 10ms 2025/01/14 03:05:38 INFO : one.rclone_chunk.001_2bi6tb: Moved (server-side) to: one.rclone_chunk.001 2025/01/14 03:05:40 INFO : one.rclone_chunk.002_2bi6tb: Moved (server-side) to: one.rclone_chunk.002 2025/01/14 03:05:52 INFO : also/one.rclone_chunk.001_2bilhi: Moved (server-side) to: also/one.rclone_chunk.001 2025/01/14 03:05:54 INFO : also/one.rclone_chunk.002_2bilhi: Moved (server-side) to: also/one.rclone_chunk.002 2025/01/14 03:06:05 INFO : another.rclone_chunk.001_2biz36: Moved (server-side) to: another.rclone_chunk.001 2025/01/14 03:06:07 INFO : another.rclone_chunk.002_2biz36: Moved (server-side) to: another.rclone_chunk.002 2025/01/14 03:06:16 INFO : not-one.rclone_chunk.001_2bjb0y: Moved (server-side) to: not-one 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/dedupe_test.go:159 Error: Should be true Test: TestDeduplicateNewestByHash Messages: another: Modification time difference too big |-114326h6m9s| > 1ns (want 2011-12-30 12:59:59 +0000 UTC vs got 2025-01-14 03:06:08 +0000 UTC) (precision 1ns) 2025/01/14 03:06:18 INFO : Chunked 'TestChunkerChunk50bYandex:rclone-test-zohuraw6supe': Looking for duplicate md5 hashes using newest mode. 2025/01/14 03:06:20 NOTICE: f2c6210278ce1526d000d98d6041a1de: Found 3 files with duplicate md5 hashes 2025/01/14 03:06:33 INFO : one: Deleted 2025/01/14 03:06:47 INFO : also/one: Deleted 2025/01/14 03:06:47 NOTICE: f2c6210278ce1526d000d98d6041a1de: Deleted 2 extra copies 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/dedupe_test.go:164 Error: Should be true Test: TestDeduplicateNewestByHash Messages: another: Modification time difference too big |-114326h6m9s| > 1ns (want 2011-12-30 12:59:59 +0000 UTC vs got 2025-01-14 03:06:08 +0000 UTC) (precision 1ns) --- FAIL: TestDeduplicateNewestByHash (102.59s) === RUN TestLs run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-zohuraw6supe'", Local "Local file system at /tmp/rclone1192422503", Modify Window "1ns" 2025/01/14 03:07:19 INFO : potato2.rclone_chunk.001_2bl1j6: Moved (server-side) to: potato2.rclone_chunk.001 2025/01/14 03:07:21 INFO : potato2.rclone_chunk.002_2bl1j6: Moved (server-side) to: potato2.rclone_chunk.002 2025/01/14 03:07:31 INFO : empty space.rclone_chunk.001_2blfjf: Moved (server-side) to: empty space --- PASS: TestLs (40.02s) === RUN TestRmdirsNoLeaveRoot run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-zohuraw6supe'", Local "Local file system at /tmp/rclone1192422503", Modify Window "1ns" 2025/01/14 03:08:02 INFO : A1/B1/C1/one.rclone_chunk.001_2bm5kf: Moved (server-side) to: A1/B1/C1/one 2025/01/14 03:08:02 INFO : A2: Making directory 2025/01/14 03:08:03 INFO : A1/B2: Making directory 2025/01/14 03:08:03 INFO : A1/B2/C2: Making directory 2025/01/14 03:08:04 INFO : A1/B1/C3: Making directory 2025/01/14 03:08:04 INFO : A3: Making directory 2025/01/14 03:08:05 INFO : A3/B3: Making directory 2025/01/14 03:08:05 INFO : A3/B3/C4: Making directory 2025/01/14 03:08:12 INFO : A1/two.rclone_chunk.001_2bmks9: Moved (server-side) to: A1/two 2025/01/14 03:08:14 DEBUG : removing 1 level 3 directories 2025/01/14 03:08:14 INFO : A3/B3/C4: Removing directory 2025/01/14 03:08:22 DEBUG : removing 2 level 3 directories 2025/01/14 03:08:22 INFO : A1/B2/C2: Removing directory 2025/01/14 03:08:22 INFO : A1/B1/C3: Removing directory 2025/01/14 03:08:26 DEBUG : removing 2 level 2 directories 2025/01/14 03:08:26 INFO : A3/B3: Removing directory 2025/01/14 03:08:26 INFO : A1/B2: Removing directory 2025/01/14 03:08:31 DEBUG : removing 2 level 1 directories 2025/01/14 03:08:31 INFO : A3: Removing directory 2025/01/14 03:08:31 INFO : A2: Removing directory 2025/01/14 03:08:49 DEBUG : removing 1 level 3 directories 2025/01/14 03:08:49 INFO : A1/B1/C1: Removing directory 2025/01/14 03:08:54 DEBUG : removing 1 level 2 directories 2025/01/14 03:08:54 INFO : A1/B1: Removing directory 2025/01/14 03:08:58 DEBUG : removing 1 level 1 directories 2025/01/14 03:08:58 INFO : A1: Removing directory 2025/01/14 03:09:03 DEBUG : removing 1 level 0 directories 2025/01/14 03:09:03 INFO : Chunked 'TestChunkerChunk50bYandex:rclone-test-zohuraw6supe': Removing directory 2025/01/14 03:09:08 ERROR : : error listing: directory not found 2025/01/14 03:09:08 ERROR : : error listing: directory not found --- PASS: TestRmdirsNoLeaveRoot (77.93s) FAIL 2025/01/14 03:09:08 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-zohuraw6supe': Purge remote 2025/01/14 03:09:09 NOTICE: purge failed: [404 - DiskNotFoundError] Resource not found. (Не удалось найти запрошенный ресурс.) "./operations.test -test.v -test.timeout 1h0m0s -remote TestChunkerChunk50bYandex: -verbose -size-limit 1024 -test.run '^(TestCheckSum|TestDeduplicateNewestByHash|TestLs|TestRmdirsNoLeaveRoot)$'" - Finished ERROR in 7m37.545381848s (try 2/5): exit status 1: Failed [TestDeduplicateNewestByHash]