"./operations.test -test.v -test.timeout 1h0m0s -remote TestYandex: -verbose -test.run '^(TestCheckSizeOnly|TestCheckSum|TestDeduplicateNewestByHash|TestListDirSorted|TestMoveFile|TestTouchDir)$'" - Starting (try 2/5) 2025/02/13 19:06:52 DEBUG : Creating backend with remote "TestYandex:rclone-test-corimor9kife" 2025/02/13 19:06:52 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/02/13 19:06:52 DEBUG : Creating backend with remote "/tmp/rclone2113741779" === RUN TestCheckSizeOnly run.go:180: Remote "Yandex rclone-test-corimor9kife", Local "Local file system at /tmp/rclone2113741779", Modify Window "1ns" === RUN TestCheckSizeOnly/1 === RUN TestCheckSizeOnly/2 === RUN TestCheckSizeOnly/3 === RUN TestCheckSizeOnly/4 === RUN TestCheckSizeOnly/5 === RUN TestCheckSizeOnly/6 === RUN TestCheckSizeOnly/7 --- PASS: TestCheckSizeOnly (29.15s) --- PASS: TestCheckSizeOnly/1 (0.36s) --- PASS: TestCheckSizeOnly/2 (0.30s) --- PASS: TestCheckSizeOnly/3 (0.45s) --- PASS: TestCheckSizeOnly/4 (0.53s) --- PASS: TestCheckSizeOnly/5 (0.29s) --- PASS: TestCheckSizeOnly/6 (0.46s) --- PASS: TestCheckSizeOnly/7 (0.56s) === RUN TestCheckSum run.go:180: Remote "Yandex rclone-test-corimor9kife", Local "Local file system at /tmp/rclone2113741779", Modify Window "1ns" 2025/02/13 19:07:21 DEBUG : Creating backend with remote "TestYandex:rclone-test-corimor9kife/data" === RUN TestCheckSum/subtest1 === RUN TestCheckSum/subtest2 === RUN TestCheckSum/subtest3 === RUN TestCheckSum/subtest4 === RUN TestCheckSum/subtest5 === RUN TestCheckSum/subtest6 === RUN TestCheckSum/subtest7 --- PASS: TestCheckSum (67.55s) --- PASS: TestCheckSum/subtest1 (1.47s) --- PASS: TestCheckSum/subtest2 (1.20s) --- PASS: TestCheckSum/subtest3 (1.33s) --- PASS: TestCheckSum/subtest4 (1.29s) --- PASS: TestCheckSum/subtest5 (1.17s) --- PASS: TestCheckSum/subtest6 (1.42s) --- PASS: TestCheckSum/subtest7 (1.42s) === RUN TestDeduplicateNewestByHash run.go:180: Remote "Yandex rclone-test-corimor9kife", Local "Local file system at /tmp/rclone2113741779", Modify Window "1ns" 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: also/one: Modification time difference too big |-115182h8m32.876543211s| > 1ns (want 2011-12-25 12:59:59.123456789 +0000 UTC vs got 2025-02-13 19:08:32 +0000 UTC) (precision 1ns) 2025/02/13 19:08:39 INFO : Yandex rclone-test-corimor9kife: Looking for duplicate md5 hashes using newest mode. 2025/02/13 19:08:40 NOTICE: 319016ebe77ee97debf44de90535378a: Found 3 files with duplicate md5 hashes 2025/02/13 19:08:43 INFO : one: Deleted 2025/02/13 19:08:47 INFO : another: Deleted 2025/02/13 19:08:47 NOTICE: 319016ebe77ee97debf44de90535378a: Deleted 2 extra copies fstest.go:292: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:292: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:292: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:299: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:299 /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: listing wrong, want another (100), not-one (5) got also/one (100), not-one (5) fstest.go:185: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:185 /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: Unexpected file "also/one" fstest.go:197: Not found "another" fstest.go:200: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:200 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:304 /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: Not equal: expected: 0 actual : 1 Test: TestDeduplicateNewestByHash Messages: 1 objects not found --- FAIL: TestDeduplicateNewestByHash (40.56s) === RUN TestListDirSorted run.go:180: Remote "Yandex rclone-test-corimor9kife", Local "Local file system at /tmp/rclone2113741779", Modify Window "1ns" 2025/02/13 19:09:27 DEBUG : a.txt: Excluded (Size Filter) 2025/02/13 19:09:27 DEBUG : a.txt: Excluded 2025/02/13 19:09:27 DEBUG : sub dir/hello world: Excluded (Size Filter) 2025/02/13 19:09:27 DEBUG : sub dir/hello world: Excluded 2025/02/13 19:09:27 DEBUG : sub dir/hello world2: Excluded (Size Filter) 2025/02/13 19:09:27 DEBUG : sub dir/hello world2: Excluded 2025/02/13 19:09:28 DEBUG : sub dir/ignore dir: Excluded 2025/02/13 19:09:29 DEBUG : sub dir/hello world: Excluded (Size Filter) 2025/02/13 19:09:29 DEBUG : sub dir/hello world: Excluded 2025/02/13 19:09:29 DEBUG : sub dir/hello world2: Excluded (Size Filter) 2025/02/13 19:09:29 DEBUG : sub dir/hello world2: Excluded 2025/02/13 19:09:29 DEBUG : sub dir/ignore dir: Excluded --- PASS: TestListDirSorted (66.02s) === RUN TestMoveFile run.go:180: Remote "Yandex rclone-test-corimor9kife", Local "Local file system at /tmp/rclone2113741779", Modify Window "1ns" 2025/02/13 19:10:16 DEBUG : file1: Need to transfer - File not found at Destination 2025/02/13 19:10:21 DEBUG : file1: md5 = 0ef726ce9b1a7692357ff70dd321d595 OK 2025/02/13 19:10:21 INFO : file1: Copied (new) to: sub/file2 2025/02/13 19:10:21 INFO : file1: Deleted 2025/02/13 19:10:22 DEBUG : file1: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/02/13 19:10:22 DEBUG : file1: Unchanged skipping 2025/02/13 19:10:22 INFO : file1: Deleted 2025/02/13 19:10:22 DEBUG : Yandex rclone-test-corimor9kife: don't need to copy/move sub/file2, it is already at target location --- PASS: TestMoveFile (16.78s) === RUN TestTouchDir run.go:180: Remote "Yandex rclone-test-corimor9kife", Local "Local file system at /tmp/rclone2113741779", Modify Window "1ns" 2025/02/13 19:10:42 DEBUG : Yandex rclone-test-corimor9kife: Touching "empty space" 2025/02/13 19:10:42 DEBUG : Yandex rclone-test-corimor9kife: Touching "potato2" 2025/02/13 19:10:43 DEBUG : Yandex rclone-test-corimor9kife: Touching "sub dir/potato3" --- PASS: TestTouchDir (28.72s) FAIL 2025/02/13 19:11:01 DEBUG : Yandex rclone-test-corimor9kife: Purge remote "./operations.test -test.v -test.timeout 1h0m0s -remote TestYandex: -verbose -test.run '^(TestCheckSizeOnly|TestCheckSum|TestDeduplicateNewestByHash|TestListDirSorted|TestMoveFile|TestTouchDir)$'" - Finished ERROR in 4m12.868723304s (try 2/5): exit status 1: Failed [TestDeduplicateNewestByHash]