"./operations.test -test.v -test.timeout 1h0m0s -remote TestChunkerChunk50bYandex: -verbose -size-limit 1024 -test.run '^(TestCheckDownload|TestCheckSum|TestCopyFileCopyDest)$|^TestRcat$/^withChecksum=true,ignoreChecksum=false$'" - Starting (try 2/5) 2025/04/26 03:43:51 DEBUG : Creating backend with remote "TestChunkerChunk50bYandex:rclone-test-boxadot6bopu" 2025/04/26 03:43:51 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/04/26 03:43:51 DEBUG : Creating backend with remote "TestYandex:rclone-test-boxadot6bopu" 2025/04/26 03:43:52 DEBUG : Creating backend with remote "/tmp/rclone1667571187" === RUN TestCheckDownload run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-boxadot6bopu'", Local "Local file system at /tmp/rclone1667571187", Modify Window "1ns" 2025/04/26 03:44:05 INFO : rutabaga.rclone_chunk.001_b9bcas: Moved (server-side) to: rutabaga === RUN TestCheckDownload/1 === RUN TestCheckDownload/2 2025/04/26 03:44:11 INFO : empty space.rclone_chunk.001_b9bk47: Moved (server-side) to: empty space === RUN TestCheckDownload/3 2025/04/26 03:44:20 INFO : potato2.rclone_chunk.001_b9bqoy: Moved (server-side) to: potato2.rclone_chunk.001 2025/04/26 03:44:22 INFO : potato2.rclone_chunk.002_b9bqoy: Moved (server-side) to: potato2.rclone_chunk.002 === RUN TestCheckDownload/4 === RUN TestCheckDownload/5 2025/04/26 03:44:37 INFO : remotepotato.rclone_chunk.001_b9c7e5: Moved (server-side) to: remotepotato.rclone_chunk.001 2025/04/26 03:44:38 INFO : remotepotato.rclone_chunk.002_b9c7e5: Moved (server-side) to: remotepotato.rclone_chunk.002 === RUN TestCheckDownload/6 === RUN TestCheckDownload/7 --- PASS: TestCheckDownload (98.55s) --- PASS: TestCheckDownload/1 (0.92s) --- PASS: TestCheckDownload/2 (0.65s) --- PASS: TestCheckDownload/3 (1.04s) --- PASS: TestCheckDownload/4 (2.76s) --- PASS: TestCheckDownload/5 (1.34s) --- PASS: TestCheckDownload/6 (1.93s) --- PASS: TestCheckDownload/7 (2.72s) === RUN TestCheckSum run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-boxadot6bopu'", Local "Local file system at /tmp/rclone1667571187", Modify Window "1ns" 2025/04/26 03:45:30 DEBUG : Creating backend with remote "TestChunkerChunk50bYandex:rclone-test-boxadot6bopu/data" 2025/04/26 03:45:30 DEBUG : Creating backend with remote "TestYandex:rclone-test-boxadot6bopu/data" 2025/04/26 03:45:36 INFO : data/banana.rclone_chunk.001_b9dwsd: Moved (server-side) to: data/banana 2025/04/26 03:45:42 INFO : test.sum.rclone_chunk.001_b9e23o: Moved (server-side) to: test.sum === RUN TestCheckSum/subtest1 2025/04/26 03:45:50 INFO : data/potato.rclone_chunk.001_b9e9is: Moved (server-side) to: data/potato 2025/04/26 03:46:01 INFO : test.sum: Deleted 2025/04/26 03:46:03 INFO : test.sum.rclone_chunk.001_b9eg06: Moved (server-side) to: test.sum === RUN TestCheckSum/subtest2 2025/04/26 03:46:13 INFO : test.sum.rclone_chunk.001_b9ev3i: Moved (server-side) to: test.sum.rclone_chunk.001 2025/04/26 03:46:14 INFO : test.sum.rclone_chunk.002_b9ev3i: Moved (server-side) to: test.sum.rclone_chunk.002 === RUN TestCheckSum/subtest3 2025/04/26 03:46:41 INFO : test.sum.rclone_chunk.001_b9fc8i: Moved (server-side) to: test.sum.rclone_chunk.001 2025/04/26 03:46:49 INFO : test.sum.rclone_chunk.002_b9fc8i: Moved (server-side) to: test.sum.rclone_chunk.002 === RUN TestCheckSum/subtest4 2025/04/26 03:47:16 INFO : test.sum.rclone_chunk.001_b9ga5x: Moved (server-side) to: test.sum.rclone_chunk.001 2025/04/26 03:47:18 INFO : test.sum.rclone_chunk.002_b9ga5x: Moved (server-side) to: test.sum.rclone_chunk.002 2025/04/26 03:47:20 INFO : test.sum.rclone_chunk.003_b9ga5x: Moved (server-side) to: test.sum.rclone_chunk.003 === 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:497 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:541 Error: Should be true Test: TestCheckSum Messages: test.sum: Modification time difference too big |-212351h42m14.500000001s| > 1ns (want 2001-02-03 04:05:06.499999999 +0000 UTC vs got 2025-04-26 03:47:21 +0000 UTC) (precision 1ns) === RUN TestCheckSum/subtest5 2025/04/26 03:47:33 DEBUG : pacer: low level retry 1/10 (error [500 - InternalServerError] Internal Server Error (Ошибка сервера.)) 2025/04/26 03:47:33 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2025/04/26 03:47:33 DEBUG : pacer: Reducing sleep to 15ms 2025/04/26 03:47:34 DEBUG : pacer: Reducing sleep to 11.25ms 2025/04/26 03:47:34 DEBUG : pacer: Reducing sleep to 10ms 2025/04/26 03:47:52 INFO : test.sum.rclone_chunk.001_b9h5c4: Moved (server-side) to: test.sum.rclone_chunk.001 2025/04/26 03:47:54 INFO : test.sum.rclone_chunk.002_b9h5c4: Moved (server-side) to: test.sum.rclone_chunk.002 2025/04/26 03:47:56 INFO : test.sum.rclone_chunk.003_b9h5c4: Moved (server-side) to: test.sum.rclone_chunk.003 === RUN TestCheckSum/subtest6 2025/04/26 03:48:13 INFO : data/banana: Deleted 2025/04/26 03:48:17 INFO : data/banana.rclone_chunk.001_b9i5dm: Moved (server-side) to: data/banana 2025/04/26 03:48:31 INFO : data/potato: Deleted 2025/04/26 03:48:33 INFO : data/potato.rclone_chunk.001_b9ij2d: Moved (server-side) to: data/potato 2025/04/26 03:48:54 INFO : test.sum.rclone_chunk.001_b9izg1: Moved (server-side) to: test.sum.rclone_chunk.001 2025/04/26 03:48:56 INFO : test.sum.rclone_chunk.002_b9izg1: Moved (server-side) to: test.sum.rclone_chunk.002 === RUN TestCheckSum/subtest7 --- FAIL: TestCheckSum (245.85s) --- PASS: TestCheckSum/subtest1 (1.39s) --- PASS: TestCheckSum/subtest2 (1.51s) --- PASS: TestCheckSum/subtest3 (3.15s) --- PASS: TestCheckSum/subtest4 (2.31s) --- PASS: TestCheckSum/subtest5 (3.38s) --- PASS: TestCheckSum/subtest6 (3.41s) --- PASS: TestCheckSum/subtest7 (3.55s) === RUN TestCopyFileCopyDest run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-boxadot6bopu'", Local "Local file system at /tmp/rclone1667571187", Modify Window "1ns" 2025/04/26 03:49:36 DEBUG : Creating backend with remote "TestChunkerChunk50bYandex:rclone-test-boxadot6bopu/dst" 2025/04/26 03:49:36 DEBUG : Config file has changed externally - reloading 2025/04/26 03:49:36 DEBUG : Creating backend with remote "TestYandex:rclone-test-boxadot6bopu/dst" 2025/04/26 03:49:37 DEBUG : Creating backend with remote "TestChunkerChunk50bYandex:rclone-test-boxadot6bopu/CopyDest" 2025/04/26 03:49:37 DEBUG : Creating backend with remote "TestYandex:rclone-test-boxadot6bopu/CopyDest" 2025/04/26 03:49:37 DEBUG : one: Need to transfer - File not found at Destination 2025/04/26 03:49:38 DEBUG : one: skip slow MD5 on source file, hashing in-transit 2025/04/26 03:49:43 INFO : one.rclone_chunk.001_b9krm4: Moved (server-side) to: one 2025/04/26 03:49:43 DEBUG : one: md5 = f97c5d29941bfb1b2fdab0874906ab82 OK 2025/04/26 03:49:43 INFO : one: Copied (new) 2025/04/26 03:49:46 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/04/26 03:49:46 DEBUG : one: skip slow MD5 on source file, hashing in-transit 2025/04/26 03:49:54 INFO : one: Deleted 2025/04/26 03:49:57 INFO : one.rclone_chunk.001_b9kz5p: Moved (server-side) to: one 2025/04/26 03:49:57 DEBUG : one: md5 = 07912d142f5d63ee918b34796b5a2432 OK 2025/04/26 03:49:57 INFO : one: Copied (replaced existing) 2025/04/26 03:50:09 INFO : dst/one: Deleted 2025/04/26 03:50:10 INFO : dst/one.rclone_chunk.001_b9lb9a: Moved (server-side) to: dst/one 2025/04/26 03:50:16 INFO : CopyDest/one.rclone_chunk.001_b9lo0c: Moved (server-side) to: CopyDest/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:316 Error: Should be true Test: TestCopyFileCopyDest Messages: CopyDest/one: Modification time difference too big |-116894h50m15.876543211s| > 1ns (want 2011-12-25 12:59:59.123456789 +0000 UTC vs got 2025-04-26 03:50:15 +0000 UTC) (precision 1ns) 2025/04/26 03:50:17 DEBUG : Creating backend with remote "TestChunkerChunk50bYandex:rclone-test-boxadot6bopu/BackupDir" 2025/04/26 03:50:17 DEBUG : Creating backend with remote "TestYandex:rclone-test-boxadot6bopu/BackupDir" 2025/04/26 03:50:18 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/04/26 03:50:18 DEBUG : one: Modification times differ by 116894h50m15.876543211s: 2011-12-25 12:59:59.123456789 +0000 UTC, 2025-04-26 03:50:15 +0000 UTC 2025/04/26 03:50:18 DEBUG : one: md5 = 07912d142f5d63ee918b34796b5a2432 OK 2025/04/26 03:50:18 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/04/26 03:50:19 DEBUG : one: move non-chunked object... 2025/04/26 03:50:20 INFO : one: Moved (server-side) 2025/04/26 03:50:20 INFO : one: Moved (server-side) 2025/04/26 03:50:20 DEBUG : one: copy non-chunked object... 2025/04/26 03:50:22 DEBUG : one: md5 = 07912d142f5d63ee918b34796b5a2432 OK 2025/04/26 03:50:22 INFO : one: Copied (server-side copy) 2025/04/26 03:50:22 DEBUG : one: Destination found in --copy-dest, using server-side copy 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:326 Error: Should be true Test: TestCopyFileCopyDest Messages: dst/one: Modification time difference too big |-116894h50m21.876543211s| > 1ns (want 2011-12-25 12:59:59.123456789 +0000 UTC vs got 2025-04-26 03:50:21 +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:326 Error: Should be true Test: TestCopyFileCopyDest Messages: CopyDest/one: Modification time difference too big |-116894h50m15.876543211s| > 1ns (want 2011-12-25 12:59:59.123456789 +0000 UTC vs got 2025-04-26 03:50:15 +0000 UTC) (precision 1ns) 2025/04/26 03:50:31 INFO : CopyDest/two.rclone_chunk.001_b9m05n: Moved (server-side) to: CopyDest/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:332 Error: Should be true Test: TestCopyFileCopyDest Messages: CopyDest/one: Modification time difference too big |-116894h50m15.876543211s| > 1ns (want 2011-12-25 12:59:59.123456789 +0000 UTC vs got 2025-04-26 03:50:15 +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:332 Error: Should be true Test: TestCopyFileCopyDest Messages: dst/one: Modification time difference too big |-116894h50m21.876543211s| > 1ns (want 2011-12-25 12:59:59.123456789 +0000 UTC vs got 2025-04-26 03:50:21 +0000 UTC) (precision 1ns) 2025/04/26 03:50:32 DEBUG : two: Need to transfer - File not found at Destination 2025/04/26 03:50:33 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/26 03:50:33 DEBUG : two: copy non-chunked object... 2025/04/26 03:50:35 DEBUG : two: md5 = b8a9f715dbb64fd5c56e7783c6820a61 OK 2025/04/26 03:50:35 INFO : two: Copied (server-side copy) 2025/04/26 03:50:35 DEBUG : two: Destination found in --copy-dest, using server-side copy 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:341 Error: Should be true Test: TestCopyFileCopyDest Messages: CopyDest/one: Modification time difference too big |-116894h50m15.876543211s| > 1ns (want 2011-12-25 12:59:59.123456789 +0000 UTC vs got 2025-04-26 03:50:15 +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:341 Error: Should be true Test: TestCopyFileCopyDest Messages: dst/one: Modification time difference too big |-116894h50m21.876543211s| > 1ns (want 2011-12-25 12:59:59.123456789 +0000 UTC vs got 2025-04-26 03:50:21 +0000 UTC) (precision 1ns) 2025/04/26 03:50:37 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/26 03:50:37 DEBUG : two: Unchanged 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:347 Error: Should be true Test: TestCopyFileCopyDest Messages: dst/one: Modification time difference too big |-116894h50m21.876543211s| > 1ns (want 2011-12-25 12:59:59.123456789 +0000 UTC vs got 2025-04-26 03:50:21 +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:347 Error: Should be true Test: TestCopyFileCopyDest Messages: CopyDest/one: Modification time difference too big |-116894h50m15.876543211s| > 1ns (want 2011-12-25 12:59:59.123456789 +0000 UTC vs got 2025-04-26 03:50:15 +0000 UTC) (precision 1ns) 2025/04/26 03:50:43 INFO : CopyDest/three.rclone_chunk.001_b9mf8b: Moved (server-side) to: CopyDest/three 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:352 Error: Should be true Test: TestCopyFileCopyDest Messages: CopyDest/one: Modification time difference too big |-116894h50m15.876543211s| > 1ns (want 2011-12-25 12:59:59.123456789 +0000 UTC vs got 2025-04-26 03:50:15 +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:352 Error: Should be true Test: TestCopyFileCopyDest Messages: dst/one: Modification time difference too big |-116894h50m21.876543211s| > 1ns (want 2011-12-25 12:59:59.123456789 +0000 UTC vs got 2025-04-26 03:50:21 +0000 UTC) (precision 1ns) 2025/04/26 03:50:45 DEBUG : three: Need to transfer - File not found at Destination 2025/04/26 03:50:45 DEBUG : three: Sizes differ (src 7 vs dst 5) 2025/04/26 03:50:45 DEBUG : three: Destination not found in --copy-dest 2025/04/26 03:50:45 DEBUG : three: skip slow MD5 on source file, hashing in-transit 2025/04/26 03:50:50 INFO : three.rclone_chunk.001_b9mm6k: Moved (server-side) to: three 2025/04/26 03:50:50 DEBUG : three: md5 = 1bccb9dccb3e9f6a3f9d2a8bdb54b7f5 OK 2025/04/26 03:50:50 INFO : three: 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:361 Error: Should be true Test: TestCopyFileCopyDest Messages: CopyDest/one: Modification time difference too big |-116894h50m15.876543211s| > 1ns (want 2011-12-25 12:59:59.123456789 +0000 UTC vs got 2025-04-26 03:50:15 +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:361 Error: Should be true Test: TestCopyFileCopyDest Messages: dst/one: Modification time difference too big |-116894h50m21.876543211s| > 1ns (want 2011-12-25 12:59:59.123456789 +0000 UTC vs got 2025-04-26 03:50:21 +0000 UTC) (precision 1ns) --- FAIL: TestCopyFileCopyDest (127.34s) === RUN TestRcat === RUN TestRcat/withChecksum=true,ignoreChecksum=false run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-boxadot6bopu'", Local "Local file system at /tmp/rclone1667571187", Modify Window "1ns" operations_test.go:1495: Adjust StreamingUploadCutoff to size limit 1Ki (was 100Ki) 2025/04/26 03:51:44 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-boxadot6bopu': File to upload is small (34 bytes), uploading instead of streaming 2025/04/26 03:51:49 INFO : with_checksum_small_file_from_pipe.rclone_chunk.001_b9o9oo: Moved (server-side) to: with_checksum_small_file_from_pipe 2025/04/26 03:51:49 DEBUG : with_checksum_small_file_from_pipe: md5 = 4c762bc6ec18f21b23fcae426c7863b5 OK 2025/04/26 03:51:49 DEBUG : with_checksum_small_file_from_pipe: Size and md5 of src and dst objects identical 2025/04/26 03:52:32 DEBUG : pacer: low level retry 1/10 (error [500 - InternalServerError] Internal Server Error (Ошибка сервера.)) 2025/04/26 03:52:32 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2025/04/26 03:52:32 DEBUG : pacer: Reducing sleep to 15ms 2025/04/26 03:52:33 DEBUG : pacer: Reducing sleep to 11.25ms 2025/04/26 03:52:33 DEBUG : pacer: Reducing sleep to 10ms 2025/04/26 03:52:45 INFO : with_checksum_big_file_from_pipe.rclone_chunk.001_b9oetp: Moved (server-side) to: with_checksum_big_file_from_pipe.rclone_chunk.001 2025/04/26 03:52:47 INFO : with_checksum_big_file_from_pipe.rclone_chunk.002_b9oetp: Moved (server-side) to: with_checksum_big_file_from_pipe.rclone_chunk.002 2025/04/26 03:52:49 INFO : with_checksum_big_file_from_pipe.rclone_chunk.003_b9oetp: Moved (server-side) to: with_checksum_big_file_from_pipe.rclone_chunk.003 2025/04/26 03:52:51 INFO : with_checksum_big_file_from_pipe.rclone_chunk.004_b9oetp: Moved (server-side) to: with_checksum_big_file_from_pipe.rclone_chunk.004 2025/04/26 03:52:52 INFO : with_checksum_big_file_from_pipe.rclone_chunk.005_b9oetp: Moved (server-side) to: with_checksum_big_file_from_pipe.rclone_chunk.005 2025/04/26 03:52:54 INFO : with_checksum_big_file_from_pipe.rclone_chunk.006_b9oetp: Moved (server-side) to: with_checksum_big_file_from_pipe.rclone_chunk.006 2025/04/26 03:52:55 INFO : with_checksum_big_file_from_pipe.rclone_chunk.007_b9oetp: Moved (server-side) to: with_checksum_big_file_from_pipe.rclone_chunk.007 2025/04/26 03:52:57 INFO : with_checksum_big_file_from_pipe.rclone_chunk.008_b9oetp: Moved (server-side) to: with_checksum_big_file_from_pipe.rclone_chunk.008 2025/04/26 03:52:59 INFO : with_checksum_big_file_from_pipe.rclone_chunk.009_b9oetp: Moved (server-side) to: with_checksum_big_file_from_pipe.rclone_chunk.009 2025/04/26 03:53:01 INFO : with_checksum_big_file_from_pipe.rclone_chunk.010_b9oetp: Moved (server-side) to: with_checksum_big_file_from_pipe.rclone_chunk.010 2025/04/26 03:53:03 INFO : with_checksum_big_file_from_pipe.rclone_chunk.011_b9oetp: Moved (server-side) to: with_checksum_big_file_from_pipe.rclone_chunk.011 2025/04/26 03:53:06 INFO : with_checksum_big_file_from_pipe.rclone_chunk.012_b9oetp: Moved (server-side) to: with_checksum_big_file_from_pipe.rclone_chunk.012 2025/04/26 03:53:08 INFO : with_checksum_big_file_from_pipe.rclone_chunk.013_b9oetp: Moved (server-side) to: with_checksum_big_file_from_pipe.rclone_chunk.013 2025/04/26 03:53:10 INFO : with_checksum_big_file_from_pipe.rclone_chunk.014_b9oetp: Moved (server-side) to: with_checksum_big_file_from_pipe.rclone_chunk.014 2025/04/26 03:53:11 INFO : with_checksum_big_file_from_pipe.rclone_chunk.015_b9oetp: Moved (server-side) to: with_checksum_big_file_from_pipe.rclone_chunk.015 2025/04/26 03:53:13 INFO : with_checksum_big_file_from_pipe.rclone_chunk.016_b9oetp: Moved (server-side) to: with_checksum_big_file_from_pipe.rclone_chunk.016 2025/04/26 03:53:15 INFO : with_checksum_big_file_from_pipe.rclone_chunk.017_b9oetp: Moved (server-side) to: with_checksum_big_file_from_pipe.rclone_chunk.017 2025/04/26 03:53:17 INFO : with_checksum_big_file_from_pipe.rclone_chunk.018_b9oetp: Moved (server-side) to: with_checksum_big_file_from_pipe.rclone_chunk.018 2025/04/26 03:53:19 INFO : with_checksum_big_file_from_pipe.rclone_chunk.019_b9oetp: Moved (server-side) to: with_checksum_big_file_from_pipe.rclone_chunk.019 2025/04/26 03:53:21 INFO : with_checksum_big_file_from_pipe.rclone_chunk.020_b9oetp: Moved (server-side) to: with_checksum_big_file_from_pipe.rclone_chunk.020 2025/04/26 03:53:23 INFO : with_checksum_big_file_from_pipe.rclone_chunk.021_b9oetp: Moved (server-side) to: with_checksum_big_file_from_pipe.rclone_chunk.021 2025/04/26 03:53:27 DEBUG : with_checksum_big_file_from_pipe: md5 = 0ad4cf7b35f62b8ff9c73f481594fbdd OK 2025/04/26 03:53:27 DEBUG : with_checksum_big_file_from_pipe: Size and md5 of src and dst objects identical --- PASS: TestRcat (210.69s) --- PASS: TestRcat/withChecksum=true,ignoreChecksum=false (210.69s) FAIL 2025/04/26 03:55:14 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-boxadot6bopu': Purge remote "./operations.test -test.v -test.timeout 1h0m0s -remote TestChunkerChunk50bYandex: -verbose -size-limit 1024 -test.run '^(TestCheckDownload|TestCheckSum|TestCopyFileCopyDest)$|^TestRcat$/^withChecksum=true,ignoreChecksum=false$'" - Finished ERROR in 11m27.84990368s (try 2/5): exit status 1: Failed [TestCheckSum TestCopyFileCopyDest]