"./operations.test -test.v -test.timeout 1h0m0s -remote TestChunkerChunk50bYandex: -verbose -fast-list -size-limit 1024 -test.run '^(TestCheckDownload|TestCopyFileCopyDest)$'" - Starting (try 2/5) 2025/05/21 04:03:03 DEBUG : Creating backend with remote "TestChunkerChunk50bYandex:rclone-test-bipojil6quxa" 2025/05/21 04:03:03 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/05/21 04:03:03 DEBUG : Creating backend with remote "TestYandex:rclone-test-bipojil6quxa" 2025/05/21 04:03:03 DEBUG : Creating backend with remote "/tmp/rclone1773653854" === RUN TestCheckDownload run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-bipojil6quxa'", Local "Local file system at /tmp/rclone1773653854", Modify Window "1ns" 2025/05/21 04:03:09 INFO : rutabaga.rclone_chunk.001_lkvcmb: Moved (server-side) to: rutabaga === RUN TestCheckDownload/1 === RUN TestCheckDownload/2 2025/05/21 04:03:16 INFO : empty space.rclone_chunk.001_lkvjo2: Moved (server-side) to: empty space === RUN TestCheckDownload/3 2025/05/21 04:03:25 INFO : potato2.rclone_chunk.001_lkvqoy: Moved (server-side) to: potato2.rclone_chunk.001 2025/05/21 04:03:27 INFO : potato2.rclone_chunk.002_lkvqoy: Moved (server-side) to: potato2.rclone_chunk.002 === RUN TestCheckDownload/4 === RUN TestCheckDownload/5 2025/05/21 04:03:43 INFO : remotepotato.rclone_chunk.001_lkw9hg: Moved (server-side) to: remotepotato.rclone_chunk.001 2025/05/21 04:03:45 INFO : remotepotato.rclone_chunk.002_lkw9hg: Moved (server-side) to: remotepotato.rclone_chunk.002 === RUN TestCheckDownload/6 === RUN TestCheckDownload/7 --- PASS: TestCheckDownload (83.86s) --- PASS: TestCheckDownload/1 (0.88s) --- PASS: TestCheckDownload/2 (0.93s) --- PASS: TestCheckDownload/3 (0.78s) --- PASS: TestCheckDownload/4 (2.54s) --- PASS: TestCheckDownload/5 (1.71s) --- PASS: TestCheckDownload/6 (1.35s) --- PASS: TestCheckDownload/7 (1.28s) === RUN TestCopyFileCopyDest run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-bipojil6quxa'", Local "Local file system at /tmp/rclone1773653854", Modify Window "1ns" 2025/05/21 04:04:27 DEBUG : Creating backend with remote "TestChunkerChunk50bYandex:rclone-test-bipojil6quxa/dst" 2025/05/21 04:04:27 DEBUG : Creating backend with remote "TestYandex:rclone-test-bipojil6quxa/dst" 2025/05/21 04:04:28 DEBUG : Creating backend with remote "TestChunkerChunk50bYandex:rclone-test-bipojil6quxa/CopyDest" 2025/05/21 04:04:28 DEBUG : Creating backend with remote "TestYandex:rclone-test-bipojil6quxa/CopyDest" 2025/05/21 04:04:28 DEBUG : one: Need to transfer - File not found at Destination 2025/05/21 04:04:29 DEBUG : one: skip slow MD5 on source file, hashing in-transit 2025/05/21 04:04:34 INFO : one.rclone_chunk.001_lkxp3a: Moved (server-side) to: one 2025/05/21 04:04:34 DEBUG : one: md5 = f97c5d29941bfb1b2fdab0874906ab82 OK 2025/05/21 04:04:34 INFO : one: Copied (new) 2025/05/21 04:04:37 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/05/21 04:04:38 DEBUG : one: skip slow MD5 on source file, hashing in-transit 2025/05/21 04:04:47 INFO : one: Deleted 2025/05/21 04:04:48 INFO : one.rclone_chunk.001_lkxy5r: Moved (server-side) to: one 2025/05/21 04:04:48 DEBUG : one: md5 = 07912d142f5d63ee918b34796b5a2432 OK 2025/05/21 04:04:48 INFO : one: Copied (replaced existing) 2025/05/21 04:04:58 INFO : dst/one: Deleted 2025/05/21 04:04:59 INFO : dst/one.rclone_chunk.001_lky9lc: Moved (server-side) to: dst/one 2025/05/21 04:05:03 INFO : CopyDest/one.rclone_chunk.001_lkyjfx: 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: dst/one: Modification time difference too big |-212951h59m51.500000001s| > 1ns (want 2001-02-03 04:05:06.499999999 +0000 UTC vs got 2025-05-21 04:04:58 +0000 UTC) (precision 1ns) 2025/05/21 04:05:05 DEBUG : Creating backend with remote "TestChunkerChunk50bYandex:rclone-test-bipojil6quxa/BackupDir" 2025/05/21 04:05:05 DEBUG : Creating backend with remote "TestYandex:rclone-test-bipojil6quxa/BackupDir" 2025/05/21 04:05:05 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/05/21 04:05:06 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/21 04:05:06 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/05/21 04:05:06 DEBUG : one: move non-chunked object... 2025/05/21 04:05:08 INFO : one: Moved (server-side) 2025/05/21 04:05:08 INFO : one: Moved (server-side) 2025/05/21 04:05:08 DEBUG : one: copy non-chunked object... 2025/05/21 04:05:10 DEBUG : one: md5 = 07912d142f5d63ee918b34796b5a2432 OK 2025/05/21 04:05:10 INFO : one: Copied (server-side copy) 2025/05/21 04:05:10 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: BackupDir/one: Modification time difference too big |-212952h0m1.500000001s| > 1ns (want 2001-02-03 04:05:06.499999999 +0000 UTC vs got 2025-05-21 04:05:08 +0000 UTC) (precision 1ns) 2025/05/21 04:05:17 INFO : CopyDest/two.rclone_chunk.001_lkyvgd: 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: BackupDir/one: Modification time difference too big |-212952h0m1.500000001s| > 1ns (want 2001-02-03 04:05:06.499999999 +0000 UTC vs got 2025-05-21 04:05:08 +0000 UTC) (precision 1ns) 2025/05/21 04:05:17 DEBUG : two: Need to transfer - File not found at Destination 2025/05/21 04:05:18 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/21 04:05:18 DEBUG : two: copy non-chunked object... 2025/05/21 04:05:20 DEBUG : two: md5 = b8a9f715dbb64fd5c56e7783c6820a61 OK 2025/05/21 04:05:20 INFO : two: Copied (server-side copy) 2025/05/21 04:05:20 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: BackupDir/one: Modification time difference too big |-212952h0m1.500000001s| > 1ns (want 2001-02-03 04:05:06.499999999 +0000 UTC vs got 2025-05-21 04:05:08 +0000 UTC) (precision 1ns) 2025/05/21 04:05:21 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/21 04:05:21 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: BackupDir/one: Modification time difference too big |-212952h0m1.500000001s| > 1ns (want 2001-02-03 04:05:06.499999999 +0000 UTC vs got 2025-05-21 04:05:08 +0000 UTC) (precision 1ns) 2025/05/21 04:05:27 INFO : CopyDest/three.rclone_chunk.001_lkz60f: 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: BackupDir/one: Modification time difference too big |-212952h0m1.500000001s| > 1ns (want 2001-02-03 04:05:06.499999999 +0000 UTC vs got 2025-05-21 04:05:08 +0000 UTC) (precision 1ns) 2025/05/21 04:05:28 DEBUG : three: Need to transfer - File not found at Destination 2025/05/21 04:05:29 DEBUG : three: Sizes differ (src 7 vs dst 5) 2025/05/21 04:05:29 DEBUG : three: Destination not found in --copy-dest 2025/05/21 04:05:29 DEBUG : three: skip slow MD5 on source file, hashing in-transit 2025/05/21 04:05:37 INFO : three.rclone_chunk.001_lkzdrc: Moved (server-side) to: three 2025/05/21 04:05:37 DEBUG : three: md5 = 1bccb9dccb3e9f6a3f9d2a8bdb54b7f5 OK 2025/05/21 04:05:37 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: BackupDir/one: Modification time difference too big |-212952h0m1.500000001s| > 1ns (want 2001-02-03 04:05:06.499999999 +0000 UTC vs got 2025-05-21 04:05:08 +0000 UTC) (precision 1ns) --- FAIL: TestCopyFileCopyDest (117.45s) FAIL 2025/05/21 04:06:25 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-bipojil6quxa': Purge remote "./operations.test -test.v -test.timeout 1h0m0s -remote TestChunkerChunk50bYandex: -verbose -fast-list -size-limit 1024 -test.run '^(TestCheckDownload|TestCopyFileCopyDest)$'" - Finished ERROR in 3m26.563859509s (try 2/5): exit status 1: Failed [TestCopyFileCopyDest]