"./operations.test -test.v -test.timeout 1h0m0s -remote TestChunkerChunk50bYandex: -verbose -size-limit 1024 -test.run '^(TestCheckSum|TestCopyFile)$|^TestListJSON$/^(Recurse|SubDir)$'" - Starting (try 2/5) 2024/11/12 02:39:53 DEBUG : Creating backend with remote "TestChunkerChunk50bYandex:rclone-test-juzibeb3qoqo" 2024/11/12 02:39:53 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2024/11/12 02:39:53 DEBUG : Creating backend with remote "TestYandex:rclone-test-juzibeb3qoqo" 2024/11/12 02:39:53 DEBUG : Reset feature "ListR" 2024/11/12 02:39:53 DEBUG : Creating backend with remote "/tmp/rclone3628123509" === RUN TestCheckSum run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-juzibeb3qoqo'", Local "Local file system at /tmp/rclone3628123509", Modify Window "1ns" 2024/11/12 02:39:53 DEBUG : Creating backend with remote "TestChunkerChunk50bYandex:rclone-test-juzibeb3qoqo/data" 2024/11/12 02:39:53 DEBUG : Creating backend with remote "TestYandex:rclone-test-juzibeb3qoqo/data" 2024/11/12 02:39:54 DEBUG : Reset feature "ListR" 2024/11/12 02:39:59 INFO : data/banana.rclone_chunk.001_tmatk2: Moved (server-side) to: data/banana 2024/11/12 02:40:06 INFO : test.sum.rclone_chunk.001_tmaxb8: Moved (server-side) to: test.sum === RUN TestCheckSum/subtest1 2024/11/12 02:40:13 INFO : data/potato.rclone_chunk.001_tmb6s1: Moved (server-side) to: data/potato 2024/11/12 02:40:19 INFO : test.sum: Deleted 2024/11/12 02:40:20 INFO : test.sum.rclone_chunk.001_tmbch6: Moved (server-side) to: test.sum === RUN TestCheckSum/subtest2 2024/11/12 02:40:34 INFO : test.sum.rclone_chunk.001_tmbofa: Moved (server-side) to: test.sum.rclone_chunk.001 2024/11/12 02:40:35 INFO : test.sum.rclone_chunk.002_tmbofa: Moved (server-side) to: test.sum.rclone_chunk.002 === RUN TestCheckSum/subtest3 2024/11/12 02:40:53 INFO : test.sum.rclone_chunk.001_tmc57k: Moved (server-side) to: test.sum.rclone_chunk.001 2024/11/12 02:40:55 INFO : test.sum.rclone_chunk.002_tmc57k: Moved (server-side) to: test.sum.rclone_chunk.002 === RUN TestCheckSum/subtest4 2024/11/12 02:41:18 INFO : test.sum.rclone_chunk.001_tmcoz2: Moved (server-side) to: test.sum.rclone_chunk.001 2024/11/12 02:41:19 INFO : test.sum.rclone_chunk.002_tmcoz2: Moved (server-side) to: test.sum.rclone_chunk.002 2024/11/12 02:41:21 INFO : test.sum.rclone_chunk.003_tmcoz2: Moved (server-side) to: test.sum.rclone_chunk.003 === RUN TestCheckSum/subtest5 2024/11/12 02:41:44 INFO : test.sum.rclone_chunk.001_tmde5p: Moved (server-side) to: test.sum.rclone_chunk.001 2024/11/12 02:41:46 INFO : test.sum.rclone_chunk.002_tmde5p: Moved (server-side) to: test.sum.rclone_chunk.002 2024/11/12 02:41:47 INFO : test.sum.rclone_chunk.003_tmde5p: Moved (server-side) to: test.sum.rclone_chunk.003 === RUN TestCheckSum/subtest6 2024/11/12 02:42:02 INFO : data/banana: Deleted 2024/11/12 02:42:04 INFO : data/banana.rclone_chunk.001_tme76q: Moved (server-side) to: data/banana 2024/11/12 02:42:09 INFO : data/potato: Deleted 2024/11/12 02:42:11 INFO : data/potato.rclone_chunk.001_tmefst: Moved (server-side) to: data/potato 2024/11/12 02:42:24 INFO : test.sum.rclone_chunk.001_tmemhq: Moved (server-side) to: test.sum.rclone_chunk.001 2024/11/12 02:42:26 INFO : test.sum.rclone_chunk.002_tmemhq: Moved (server-side) to: test.sum.rclone_chunk.002 === RUN TestCheckSum/subtest7 --- PASS: TestCheckSum (169.87s) --- PASS: TestCheckSum/subtest1 (1.25s) --- PASS: TestCheckSum/subtest2 (2.00s) --- PASS: TestCheckSum/subtest3 (2.62s) --- PASS: TestCheckSum/subtest4 (2.20s) --- PASS: TestCheckSum/subtest5 (3.02s) --- PASS: TestCheckSum/subtest6 (3.54s) --- PASS: TestCheckSum/subtest7 (2.57s) === RUN TestCopyFile run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-juzibeb3qoqo'", Local "Local file system at /tmp/rclone3628123509", Modify Window "1ns" 2024/11/12 02:42:44 DEBUG : file1: Need to transfer - File not found at Destination 2024/11/12 02:42:49 INFO : sub/file2.rclone_chunk.001_tmfid4: Moved (server-side) to: sub/file2 2024/11/12 02:42:49 DEBUG : file1: md5 = 0ef726ce9b1a7692357ff70dd321d595 OK 2024/11/12 02:42:49 INFO : file1: Copied (new) to: sub/file2 2024/11/12 02:42:50 DEBUG : file1: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/11/12 02:42:50 DEBUG : file1: Unchanged skipping 2024/11/12 02:42:51 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-juzibeb3qoqo': don't need to copy/move sub/file2, it is already at target location --- PASS: TestCopyFile (12.71s) === RUN TestListJSON run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-juzibeb3qoqo'", Local "Local file system at /tmp/rclone3628123509", Modify Window "1ns" 2024/11/12 02:43:02 INFO : file1.rclone_chunk.001_tmfuti: Moved (server-side) to: file1 2024/11/12 02:43:08 INFO : sub/file2.rclone_chunk.001_tmg068: Moved (server-side) to: sub/file2 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/lsjson_test.go:43 Error: Should be true Test: TestListJSON Messages: sub/file2: Modification time difference too big |-112933h43m6.876543211s| > 1ns (want 2011-12-25 12:59:59.123456789 +0000 UTC vs got 2024-11-12 02:43:06 +0000 UTC) (precision 1ns) === RUN TestListJSON/Recurse 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/fs/operations/lsjson_test.go:27 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/lsjson_test.go:204 Error: Should be true Test: TestListJSON/Recurse Messages: ListJSON: Modification time difference too big |-112933h43m6.876543211s| > 1ns (want 2011-12-25 12:59:59.123456789 +0000 UTC vs got 2024-11-12 02:43:06 +0000 UTC) (precision 1ns) === RUN TestListJSON/SubDir 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/fs/operations/lsjson_test.go:27 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/lsjson_test.go:204 Error: Should be true Test: TestListJSON/SubDir Messages: ListJSON: Modification time difference too big |-112933h43m6.876543211s| > 1ns (want 2011-12-25 12:59:59.123456789 +0000 UTC vs got 2024-11-12 02:43:06 +0000 UTC) (precision 1ns) --- FAIL: TestListJSON (21.96s) --- FAIL: TestListJSON/Recurse (0.62s) --- FAIL: TestListJSON/SubDir (0.30s) FAIL 2024/11/12 02:43:18 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-juzibeb3qoqo': Purge remote "./operations.test -test.v -test.timeout 1h0m0s -remote TestChunkerChunk50bYandex: -verbose -size-limit 1024 -test.run '^(TestCheckSum|TestCopyFile)$|^TestListJSON$/^(Recurse|SubDir)$'" - Finished ERROR in 3m26.573262315s (try 2/5): exit status 1: Failed [TestListJSON/Recurse TestListJSON/SubDir]