"./operations.test -test.v -test.timeout 1h0m0s -remote TestChunkerChunk50bYandex: -verbose -fast-list -size-limit 1024 -test.run '^(TestCheckSum|TestCopyFileCompareDest)$'" - Starting (try 3/5) 2024/10/03 06:17:57 DEBUG : Creating backend with remote "TestChunkerChunk50bYandex:rclone-test-jalamem8kifi" 2024/10/03 06:17:57 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2024/10/03 06:17:57 DEBUG : Creating backend with remote "TestYandex:rclone-test-jalamem8kifi" 2024/10/03 06:17:58 DEBUG : Reset feature "ListR" 2024/10/03 06:17:58 DEBUG : Creating backend with remote "/tmp/rclone3709526667" === RUN TestCheckSum run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-jalamem8kifi'", Local "Local file system at /tmp/rclone3709526667", Modify Window "1ns" 2024/10/03 06:17:58 DEBUG : Creating backend with remote "TestChunkerChunk50bYandex:rclone-test-jalamem8kifi/data" 2024/10/03 06:17:58 DEBUG : Creating backend with remote "TestYandex:rclone-test-jalamem8kifi/data" 2024/10/03 06:17:58 DEBUG : Reset feature "ListR" 2024/10/03 06:18:05 INFO : data/banana.rclone_chunk.001_rtpvwu: Moved (server-side) to: data/banana 2024/10/03 06:18:10 INFO : test.sum.rclone_chunk.001_rtq1h8: Moved (server-side) to: test.sum === RUN TestCheckSum/subtest1 2024/10/03 06:18:17 INFO : data/potato.rclone_chunk.001_rtq96o: Moved (server-side) to: data/potato 2024/10/03 06:18:25 INFO : test.sum: Deleted 2024/10/03 06:18:27 INFO : test.sum.rclone_chunk.001_rtqesm: Moved (server-side) to: test.sum === RUN TestCheckSum/subtest2 2024/10/03 06:18:37 INFO : test.sum.rclone_chunk.001_rtqqh0: Moved (server-side) to: test.sum.rclone_chunk.001 2024/10/03 06:18:39 INFO : test.sum.rclone_chunk.002_rtqqh0: Moved (server-side) to: test.sum.rclone_chunk.002 === NAME TestCheckSum 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:469 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:542 Error: Should be true Test: TestCheckSum Messages: test.sum: Modification time difference too big |-207434h13m34.500000001s| > 1ns (want 2001-02-03 04:05:06.499999999 +0000 UTC vs got 2024-10-03 06:18:41 +0000 UTC) (precision 1ns) === RUN TestCheckSum/subtest3 2024/10/03 06:18:56 INFO : test.sum.rclone_chunk.001_rtr6p0: Moved (server-side) to: test.sum.rclone_chunk.001 2024/10/03 06:18:59 INFO : test.sum.rclone_chunk.002_rtr6p0: Moved (server-side) to: test.sum.rclone_chunk.002 === RUN TestCheckSum/subtest4 2024/10/03 06:19:20 INFO : test.sum.rclone_chunk.001_rtrq4t: Moved (server-side) to: test.sum.rclone_chunk.001 2024/10/03 06:19:21 INFO : test.sum.rclone_chunk.002_rtrq4t: Moved (server-side) to: test.sum.rclone_chunk.002 2024/10/03 06:19:24 INFO : test.sum.rclone_chunk.003_rtrq4t: Moved (server-side) to: test.sum.rclone_chunk.003 === RUN TestCheckSum/subtest5 2024/10/03 06:19:46 INFO : test.sum.rclone_chunk.001_rtsfdg: Moved (server-side) to: test.sum.rclone_chunk.001 2024/10/03 06:19:48 INFO : test.sum.rclone_chunk.002_rtsfdg: Moved (server-side) to: test.sum.rclone_chunk.002 2024/10/03 06:19:49 INFO : test.sum.rclone_chunk.003_rtsfdg: Moved (server-side) to: test.sum.rclone_chunk.003 === RUN TestCheckSum/subtest6 2024/10/03 06:20:08 INFO : data/banana: Deleted 2024/10/03 06:20:09 INFO : data/banana.rclone_chunk.001_rtt6n0: Moved (server-side) to: data/banana 2024/10/03 06:20:15 INFO : data/potato: Deleted 2024/10/03 06:20:16 INFO : data/potato.rclone_chunk.001_rttibz: Moved (server-side) to: data/potato 2024/10/03 06:20:29 INFO : test.sum.rclone_chunk.001_rttqok: Moved (server-side) to: test.sum.rclone_chunk.001 2024/10/03 06:20:34 INFO : test.sum.rclone_chunk.002_rttqok: Moved (server-side) to: test.sum.rclone_chunk.002 === RUN TestCheckSum/subtest7 --- FAIL: TestCheckSum (175.88s) --- PASS: TestCheckSum/subtest1 (1.39s) --- PASS: TestCheckSum/subtest2 (1.39s) --- PASS: TestCheckSum/subtest3 (2.35s) --- PASS: TestCheckSum/subtest4 (2.67s) --- PASS: TestCheckSum/subtest5 (2.46s) --- PASS: TestCheckSum/subtest6 (2.95s) --- PASS: TestCheckSum/subtest7 (2.08s) === RUN TestCopyFileCompareDest run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-jalamem8kifi'", Local "Local file system at /tmp/rclone3709526667", Modify Window "1ns" 2024/10/03 06:20:54 DEBUG : Creating backend with remote "TestChunkerChunk50bYandex:rclone-test-jalamem8kifi/dst" 2024/10/03 06:20:54 DEBUG : Creating backend with remote "TestYandex:rclone-test-jalamem8kifi/dst" 2024/10/03 06:20:54 DEBUG : Reset feature "ListR" 2024/10/03 06:20:54 DEBUG : Creating backend with remote "TestChunkerChunk50bYandex:rclone-test-jalamem8kifi/CompareDest" 2024/10/03 06:20:54 DEBUG : Creating backend with remote "TestYandex:rclone-test-jalamem8kifi/CompareDest" 2024/10/03 06:20:55 DEBUG : Reset feature "ListR" 2024/10/03 06:20:55 DEBUG : one: Need to transfer - File not found at Destination 2024/10/03 06:20:55 DEBUG : one: skip slow MD5 on source file, hashing in-transit 2024/10/03 06:21:02 INFO : one.rclone_chunk.001_rtur5x: Moved (server-side) to: one 2024/10/03 06:21:02 DEBUG : one: md5 = f97c5d29941bfb1b2fdab0874906ab82 OK 2024/10/03 06:21:02 INFO : one: Copied (new) 2024/10/03 06:21:07 DEBUG : one: Sizes differ (src 5 vs dst 3) 2024/10/03 06:21:08 DEBUG : one: skip slow MD5 on source file, hashing in-transit 2024/10/03 06:21:13 INFO : one: Deleted 2024/10/03 06:21:15 INFO : one.rclone_chunk.001_rtv43w: Moved (server-side) to: one 2024/10/03 06:21:15 DEBUG : one: md5 = 07912d142f5d63ee918b34796b5a2432 OK 2024/10/03 06:21:15 INFO : one: Copied (replaced existing) 2024/10/03 06:21:21 INFO : dst/one: Deleted 2024/10/03 06:21:23 INFO : dst/one.rclone_chunk.001_rtvc3u: Moved (server-side) to: dst/one 2024/10/03 06:21:28 INFO : CompareDest/one.rclone_chunk.001_rtvju1: Moved (server-side) to: CompareDest/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/copy_test.go:230 Error: Should be true Test: TestCopyFileCompareDest Messages: dst/one: Modification time difference too big |-207434h16m15.500000001s| > 1ns (want 2001-02-03 04:05:06.499999999 +0000 UTC vs got 2024-10-03 06:21:22 +0000 UTC) (precision 1ns) 2024/10/03 06:21:29 DEBUG : one: Sizes differ (src 5 vs dst 3) 2024/10/03 06:21:29 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/10/03 06:21:29 DEBUG : one: Destination found in --compare-dest, skipping 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/copy_test.go:236 Error: Should be true Test: TestCopyFileCompareDest Messages: dst/one: Modification time difference too big |-207434h16m15.500000001s| > 1ns (want 2001-02-03 04:05:06.499999999 +0000 UTC vs got 2024-10-03 06:21:22 +0000 UTC) (precision 1ns) 2024/10/03 06:21:35 INFO : CompareDest/two.rclone_chunk.001_rtvqvk: Moved (server-side) to: CompareDest/two 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/copy_test.go:241 Error: Should be true Test: TestCopyFileCompareDest Messages: dst/one: Modification time difference too big |-207434h16m15.500000001s| > 1ns (want 2001-02-03 04:05:06.499999999 +0000 UTC vs got 2024-10-03 06:21:22 +0000 UTC) (precision 1ns) 2024/10/03 06:21:36 DEBUG : two: Need to transfer - File not found at Destination 2024/10/03 06:21:37 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/10/03 06:21:37 DEBUG : two: Destination found in --compare-dest, skipping 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/copy_test.go:247 Error: Should be true Test: TestCopyFileCompareDest Messages: dst/one: Modification time difference too big |-207434h16m15.500000001s| > 1ns (want 2001-02-03 04:05:06.499999999 +0000 UTC vs got 2024-10-03 06:21:22 +0000 UTC) (precision 1ns) 2024/10/03 06:21:38 DEBUG : two: Need to transfer - File not found at Destination 2024/10/03 06:21:39 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/10/03 06:21:39 DEBUG : two: Destination found in --compare-dest, skipping 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/copy_test.go:253 Error: Should be true Test: TestCopyFileCompareDest Messages: dst/one: Modification time difference too big |-207434h16m15.500000001s| > 1ns (want 2001-02-03 04:05:06.499999999 +0000 UTC vs got 2024-10-03 06:21:22 +0000 UTC) (precision 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/copy_test.go:257 Error: Should be true Test: TestCopyFileCompareDest Messages: dst/one: Modification time difference too big |-207434h16m15.500000001s| > 1ns (want 2001-02-03 04:05:06.499999999 +0000 UTC vs got 2024-10-03 06:21:22 +0000 UTC) (precision 1ns) 2024/10/03 06:21:41 DEBUG : two: Need to transfer - File not found at Destination 2024/10/03 06:21:41 DEBUG : two: Sizes differ (src 5 vs dst 3) 2024/10/03 06:21:46 DEBUG : two: skip slow MD5 on source file, hashing in-transit 2024/10/03 06:21:55 INFO : two.rclone_chunk.001_rtw6cc: Moved (server-side) to: two 2024/10/03 06:21:55 DEBUG : two: md5 = 2379e4ce8c3380e996ab0509f17069ad OK 2024/10/03 06:21:55 INFO : two: Copied (new) 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/copy_test.go:266 Error: Should be true Test: TestCopyFileCompareDest Messages: dst/one: Modification time difference too big |-207434h16m15.500000001s| > 1ns (want 2001-02-03 04:05:06.499999999 +0000 UTC vs got 2024-10-03 06:21:22 +0000 UTC) (precision 1ns) --- FAIL: TestCopyFileCompareDest (72.48s) FAIL 2024/10/03 06:22:06 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-jalamem8kifi': Purge remote "./operations.test -test.v -test.timeout 1h0m0s -remote TestChunkerChunk50bYandex: -verbose -fast-list -size-limit 1024 -test.run '^(TestCheckSum|TestCopyFileCompareDest)$'" - Finished ERROR in 4m10.158587472s (try 3/5): exit status 1: Failed [TestCheckSum TestCopyFileCompareDest]