"./sync.test -test.v -test.timeout 1h0m0s -remote TestChunkerChunk50bYandex: -verbose -size-limit 1024 -test.run '^(TestCopyCheckFirst|TestCopyRedownload|TestServerSideMoveWithFilter|TestSyncBackupDirWithSuffix|TestSyncConcurrentDelete|TestSyncCopyDest)$'" - Starting (try 2/5) 2025/04/11 02:24:13 DEBUG : Creating backend with remote "TestChunkerChunk50bYandex:rclone-test-cejamuq2meca" 2025/04/11 02:24:13 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/04/11 02:24:13 DEBUG : Creating backend with remote "TestYandex:rclone-test-cejamuq2meca" 2025/04/11 02:24:13 DEBUG : Creating backend with remote "/tmp/rclone535445051" === RUN TestCopyCheckFirst run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-cejamuq2meca'", Local "Local file system at /tmp/rclone535445051", Modify Window "1ns" 2025/04/11 02:24:13 INFO : Chunked 'TestChunkerChunk50bYandex:rclone-test-cejamuq2meca': Running all checks before starting transfers 2025/04/11 02:24:14 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/04/11 02:24:14 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-cejamuq2meca': Waiting for checks to finish 2025/04/11 02:24:14 INFO : Chunked 'TestChunkerChunk50bYandex:rclone-test-cejamuq2meca': Checks finished, now starting transfers 2025/04/11 02:24:14 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-cejamuq2meca': Waiting for transfers to finish 2025/04/11 02:24:14 DEBUG : sub dir/hello world: skip slow MD5 on source file, hashing in-transit 2025/04/11 02:24:20 INFO : sub dir/hello world.rclone_chunk.001_jdm8iq: Moved (server-side) to: sub dir/hello world 2025/04/11 02:24:20 DEBUG : sub dir/hello world: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2025/04/11 02:24:20 INFO : sub dir/hello world: Copied (new) run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-cejamuq2meca'", Local "Local file system at /tmp/rclone535445051", Modify Window "1ns" --- PASS: TestCopyCheckFirst (18.84s) === RUN TestCopyRedownload run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-cejamuq2meca'", Local "Local file system at /tmp/rclone535445051", Modify Window "1ns" 2025/04/11 02:24:40 INFO : sub dir/hello world.rclone_chunk.001_jdmrb8: Moved (server-side) to: sub dir/hello world 2025/04/11 02:24:41 DEBUG : Added delayed dir = "sub dir", newDst= 2025/04/11 02:24:41 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/04/11 02:24:41 DEBUG : Local file system at /tmp/rclone535445051: Waiting for checks to finish 2025/04/11 02:24:41 DEBUG : Local file system at /tmp/rclone535445051: Waiting for transfers to finish 2025/04/11 02:24:43 DEBUG : sub dir/hello world: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2025/04/11 02:24:43 DEBUG : sub dir/hello world.7ddbfcba.partial: renamed to: sub dir/hello world 2025/04/11 02:24:43 INFO : sub dir/hello world: Copied (new) 2025/04/11 02:24:43 INFO : sub dir: Set directory modification time (using DirSetModTime) run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-cejamuq2meca'", Local "Local file system at /tmp/rclone535445051", Modify Window "1ns" --- PASS: TestCopyRedownload (20.84s) === RUN TestServerSideMoveWithFilter run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-cejamuq2meca'", Local "Local file system at /tmp/rclone535445051", Modify Window "1ns" 2025/04/11 02:24:53 DEBUG : Creating backend with remote "TestChunkerChunk50bYandex:rclone-test-pisadiz5fugi" 2025/04/11 02:24:53 DEBUG : Config file has changed externally - reloading 2025/04/11 02:24:53 DEBUG : Creating backend with remote "TestYandex:rclone-test-pisadiz5fugi" 2025/04/11 02:25:01 INFO : potato2.rclone_chunk.001_jdncv8: Moved (server-side) to: potato2.rclone_chunk.001 2025/04/11 02:25:03 INFO : potato2.rclone_chunk.002_jdncv8: Moved (server-side) to: potato2.rclone_chunk.002 2025/04/11 02:25:11 INFO : empty space.rclone_chunk.001_jdnoqg: Moved (server-side) to: empty space 2025/04/11 02:25:19 INFO : potato3.rclone_chunk.001_jdntv0: Moved (server-side) to: potato3.rclone_chunk.001 2025/04/11 02:25:21 INFO : potato3.rclone_chunk.002_jdntv0: Moved (server-side) to: potato3.rclone_chunk.002 sync_test.go:1705: Server side move (if possible) Chunked 'TestChunkerChunk50bYandex:rclone-test-cejamuq2meca' -> Chunked 'TestChunkerChunk50bYandex:rclone-test-pisadiz5fugi' 2025/04/11 02:25:31 INFO : empty space.rclone_chunk.001_jdo8d8: Moved (server-side) to: empty space 2025/04/11 02:25:39 INFO : potato3.rclone_chunk.001_jdod3z: Moved (server-side) to: potato3.rclone_chunk.001 2025/04/11 02:25:40 INFO : potato3.rclone_chunk.002_jdod3z: Moved (server-side) to: potato3.rclone_chunk.002 2025/04/11 02:25:44 DEBUG : empty space: Excluded (Size Filter) 2025/04/11 02:25:44 DEBUG : empty space: Excluded 2025/04/11 02:25:44 DEBUG : empty space: Excluded (Size Filter) 2025/04/11 02:25:44 DEBUG : empty space: Excluded 2025/04/11 02:25:44 DEBUG : potato2: Need to transfer - File not found at Destination 2025/04/11 02:25:44 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2025/04/11 02:25:44 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-pisadiz5fugi': Waiting for checks to finish 2025/04/11 02:25:44 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-pisadiz5fugi': Waiting for transfers to finish 2025/04/11 02:25:45 DEBUG : potato2: move 2 data chunks... 2025/04/11 02:25:47 INFO : potato2.rclone_chunk.001: Moved (server-side) 2025/04/11 02:25:48 INFO : potato2.rclone_chunk.002: Moved (server-side) 2025/04/11 02:25:50 INFO : potato2: Moved (server-side) 2025/04/11 02:25:53 INFO : potato2: Moved (server-side) 2025/04/11 02:25:56 INFO : potato3: Deleted 2025/04/11 02:25:56 DEBUG : potato3: move 2 data chunks... 2025/04/11 02:25:58 INFO : potato3.rclone_chunk.001: Moved (server-side) 2025/04/11 02:25:59 INFO : potato3.rclone_chunk.002: Moved (server-side) 2025/04/11 02:26:03 INFO : potato3: Moved (server-side) 2025/04/11 02:26:05 INFO : potato3: Moved (server-side) 2025/04/11 02:26:06 DEBUG : Creating backend with remote "TestChunkerChunk50bYandex:rclone-test-dusuzol9vuho" 2025/04/11 02:26:06 DEBUG : Config file has changed externally - reloading 2025/04/11 02:26:06 DEBUG : Creating backend with remote "TestYandex:rclone-test-dusuzol9vuho" 2025/04/11 02:26:07 DEBUG : empty space: Excluded (Size Filter) 2025/04/11 02:26:07 DEBUG : empty space: Excluded 2025/04/11 02:26:07 DEBUG : potato2: Need to transfer - File not found at Destination 2025/04/11 02:26:07 DEBUG : potato3: Need to transfer - File not found at Destination 2025/04/11 02:26:07 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-dusuzol9vuho': Waiting for checks to finish 2025/04/11 02:26:07 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-dusuzol9vuho': Waiting for transfers to finish 2025/04/11 02:26:08 DEBUG : potato3: move 2 data chunks... 2025/04/11 02:26:08 DEBUG : potato2: move 2 data chunks... 2025/04/11 02:26:09 INFO : potato3.rclone_chunk.001: Moved (server-side) 2025/04/11 02:26:10 INFO : potato2.rclone_chunk.001: Moved (server-side) 2025/04/11 02:26:11 INFO : potato3.rclone_chunk.002: Moved (server-side) 2025/04/11 02:26:11 INFO : potato2.rclone_chunk.002: Moved (server-side) 2025/04/11 02:26:13 INFO : potato2: Moved (server-side) 2025/04/11 02:26:13 INFO : potato3: Moved (server-side) 2025/04/11 02:26:15 INFO : potato2: Moved (server-side) 2025/04/11 02:26:15 INFO : potato3: Moved (server-side) 2025/04/11 02:26:18 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-dusuzol9vuho': Purge remote 2025/04/11 02:26:27 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-pisadiz5fugi': Purge remote --- PASS: TestServerSideMoveWithFilter (104.04s) === RUN TestSyncCopyDest run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-cejamuq2meca'", Local "Local file system at /tmp/rclone535445051", Modify Window "1ns" 2025/04/11 02:26:37 DEBUG : Creating backend with remote "TestChunkerChunk50bYandex:rclone-test-cejamuq2meca/dst" 2025/04/11 02:26:37 DEBUG : Config file has changed externally - reloading 2025/04/11 02:26:37 DEBUG : Creating backend with remote "TestYandex:rclone-test-cejamuq2meca/dst" 2025/04/11 02:26:37 DEBUG : Creating backend with remote "TestChunkerChunk50bYandex:rclone-test-cejamuq2meca/CopyDest" 2025/04/11 02:26:37 DEBUG : Creating backend with remote "TestYandex:rclone-test-cejamuq2meca/CopyDest" 2025/04/11 02:26:38 DEBUG : one: Need to transfer - File not found at Destination 2025/04/11 02:26:38 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-cejamuq2meca/dst': Waiting for checks to finish 2025/04/11 02:26:38 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-cejamuq2meca/dst': Waiting for transfers to finish 2025/04/11 02:26:39 DEBUG : one: skip slow MD5 on source file, hashing in-transit 2025/04/11 02:26:44 INFO : one.rclone_chunk.001_jdq9c1: Moved (server-side) to: one 2025/04/11 02:26:44 DEBUG : one: md5 = f97c5d29941bfb1b2fdab0874906ab82 OK 2025/04/11 02:26:44 INFO : one: Copied (new) 2025/04/11 02:26:44 DEBUG : Waiting for deletions to finish 2025/04/11 02:26:45 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/04/11 02:26:45 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-cejamuq2meca/dst': Waiting for checks to finish 2025/04/11 02:26:45 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-cejamuq2meca/dst': Waiting for transfers to finish 2025/04/11 02:26:45 DEBUG : one: skip slow MD5 on source file, hashing in-transit 2025/04/11 02:26:53 INFO : one: Deleted 2025/04/11 02:26:54 INFO : one.rclone_chunk.001_jdqfsv: Moved (server-side) to: one 2025/04/11 02:26:54 DEBUG : one: md5 = 07912d142f5d63ee918b34796b5a2432 OK 2025/04/11 02:26:54 INFO : one: Copied (replaced existing) 2025/04/11 02:26:54 DEBUG : Waiting for deletions to finish 2025/04/11 02:27:03 INFO : dst/one: Deleted 2025/04/11 02:27:05 INFO : dst/one.rclone_chunk.001_jdqpox: Moved (server-side) to: dst/one 2025/04/11 02:27:11 INFO : CopyDest/one.rclone_chunk.001_jdr0bb: Moved (server-side) to: CopyDest/one 2025/04/11 02:27:11 DEBUG : Creating backend with remote "TestChunkerChunk50bYandex:rclone-test-cejamuq2meca/BackupDir" 2025/04/11 02:27:11 DEBUG : Creating backend with remote "TestYandex:rclone-test-cejamuq2meca/BackupDir" 2025/04/11 02:27:12 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/04/11 02:27:12 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-cejamuq2meca/dst': Waiting for checks to finish 2025/04/11 02:27:13 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/11 02:27:13 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/04/11 02:27:13 DEBUG : one: move non-chunked object... 2025/04/11 02:27:14 INFO : one: Moved (server-side) 2025/04/11 02:27:14 INFO : one: Moved (server-side) 2025/04/11 02:27:14 DEBUG : one: copy non-chunked object... 2025/04/11 02:27:17 DEBUG : one: md5 = 07912d142f5d63ee918b34796b5a2432 OK 2025/04/11 02:27:17 INFO : one: Copied (server-side copy) 2025/04/11 02:27:17 DEBUG : one: Destination found in --copy-dest, using server-side copy 2025/04/11 02:27:17 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-cejamuq2meca/dst': Waiting for transfers to finish 2025/04/11 02:27:17 DEBUG : Waiting for deletions to finish 2025/04/11 02:27:25 INFO : CopyDest/two.rclone_chunk.001_jdrcbj: 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/sync/sync_test.go:2300 Error: Should be true Test: TestSyncCopyDest Messages: CopyDest/two: Modification time difference too big |-116533h27m22.876543211s| > 1ns (want 2011-12-25 12:59:59.123456789 +0000 UTC vs got 2025-04-11 02:27:22 +0000 UTC) (precision 1ns) 2025/04/11 02:27:27 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/11 02:27:27 DEBUG : one: Unchanged skipping 2025/04/11 02:27:27 DEBUG : two: Modification times differ by 116533h27m22.876543211s: 2011-12-25 12:59:59.123456789 +0000 UTC, 2025-04-11 02:27:22 +0000 UTC 2025/04/11 02:27:27 DEBUG : two: md5 = b8a9f715dbb64fd5c56e7783c6820a61 OK 2025/04/11 02:27:27 DEBUG : two: copy non-chunked object... 2025/04/11 02:27:30 DEBUG : two: md5 = b8a9f715dbb64fd5c56e7783c6820a61 OK 2025/04/11 02:27:30 INFO : two: Copied (server-side copy) 2025/04/11 02:27:30 DEBUG : two: Destination found in --copy-dest, using server-side copy 2025/04/11 02:27:30 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-cejamuq2meca/dst': Waiting for checks to finish 2025/04/11 02:27:30 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-cejamuq2meca/dst': Waiting for transfers to finish 2025/04/11 02:27:30 DEBUG : Waiting for deletions to finish 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/sync/sync_test.go:2312 Error: Should be true Test: TestSyncCopyDest Messages: dst/two: Modification time difference too big |-116533h27m29.876543211s| > 1ns (want 2011-12-25 12:59:59.123456789 +0000 UTC vs got 2025-04-11 02:27:29 +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/sync/sync_test.go:2312 Error: Should be true Test: TestSyncCopyDest Messages: CopyDest/two: Modification time difference too big |-116533h27m22.876543211s| > 1ns (want 2011-12-25 12:59:59.123456789 +0000 UTC vs got 2025-04-11 02:27:22 +0000 UTC) (precision 1ns) 2025/04/11 02:27:31 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/11 02:27:31 DEBUG : one: Unchanged skipping 2025/04/11 02:27:31 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-cejamuq2meca/dst': Waiting for checks to finish 2025/04/11 02:27:31 DEBUG : two: Modification times differ by 116533h27m29.876543211s: 2011-12-25 12:59:59.123456789 +0000 UTC, 2025-04-11 02:27:29 +0000 UTC 2025/04/11 02:27:31 DEBUG : two: md5 = b8a9f715dbb64fd5c56e7783c6820a61 OK 2025/04/11 02:27:32 INFO : two: Updated modification time in destination 2025/04/11 02:27:32 DEBUG : two: Unchanged skipping 2025/04/11 02:27:32 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-cejamuq2meca/dst': Waiting for transfers to finish 2025/04/11 02:27:32 DEBUG : Waiting for deletions to finish 2025/04/11 02:27:32 INFO : There was nothing to transfer 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/sync/sync_test.go:2321 Error: Should be true Test: TestSyncCopyDest Messages: CopyDest/two: Modification time difference too big |-116533h27m22.876543211s| > 1ns (want 2011-12-25 12:59:59.123456789 +0000 UTC vs got 2025-04-11 02:27:22 +0000 UTC) (precision 1ns) 2025/04/11 02:27:40 INFO : CopyDest/three.rclone_chunk.001_jdrrsi: 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/sync/sync_test.go:2326 Error: Should be true Test: TestSyncCopyDest Messages: CopyDest/two: Modification time difference too big |-116533h27m22.876543211s| > 1ns (want 2011-12-25 12:59:59.123456789 +0000 UTC vs got 2025-04-11 02:27:22 +0000 UTC) (precision 1ns) 2025/04/11 02:27:41 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/11 02:27:41 DEBUG : one: Unchanged skipping 2025/04/11 02:27:41 DEBUG : three: Sizes differ (src 7 vs dst 5) 2025/04/11 02:27:41 DEBUG : three: Destination not found in --copy-dest 2025/04/11 02:27:41 DEBUG : three: Need to transfer - File not found at Destination 2025/04/11 02:27:41 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-cejamuq2meca/dst': Waiting for checks to finish 2025/04/11 02:27:41 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/11 02:27:41 DEBUG : two: Unchanged skipping 2025/04/11 02:27:41 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-cejamuq2meca/dst': Waiting for transfers to finish 2025/04/11 02:27:42 DEBUG : three: skip slow MD5 on source file, hashing in-transit 2025/04/11 02:27:48 INFO : three.rclone_chunk.001_jds062: Moved (server-side) to: three 2025/04/11 02:27:48 DEBUG : three: md5 = 1bccb9dccb3e9f6a3f9d2a8bdb54b7f5 OK 2025/04/11 02:27:48 INFO : three: Copied (new) 2025/04/11 02:27:48 DEBUG : Waiting for deletions to finish 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/sync/sync_test.go:2338 Error: Should be true Test: TestSyncCopyDest Messages: CopyDest/two: Modification time difference too big |-116533h27m22.876543211s| > 1ns (want 2011-12-25 12:59:59.123456789 +0000 UTC vs got 2025-04-11 02:27:22 +0000 UTC) (precision 1ns) --- FAIL: TestSyncCopyDest (110.60s) === RUN TestSyncBackupDirWithSuffix run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-cejamuq2meca'", Local "Local file system at /tmp/rclone535445051", Modify Window "1ns" 2025/04/11 02:28:34 INFO : dst/one.rclone_chunk.001_jdtaou: Moved (server-side) to: dst/one 2025/04/11 02:28:40 INFO : dst/two.rclone_chunk.001_jdtho0: Moved (server-side) to: dst/two 2025/04/11 02:28:46 INFO : dst/three.txt.rclone_chunk.001_jdtm2j: Moved (server-side) to: dst/three.txt 2025/04/11 02:28:46 DEBUG : Creating backend with remote "TestChunkerChunk50bYandex:rclone-test-cejamuq2meca/dst" 2025/04/11 02:28:46 DEBUG : Config file has changed externally - reloading 2025/04/11 02:28:46 DEBUG : Creating backend with remote "TestYandex:rclone-test-cejamuq2meca/dst" 2025/04/11 02:28:47 DEBUG : Creating backend with remote "TestChunkerChunk50bYandex:rclone-test-cejamuq2meca/backup" 2025/04/11 02:28:47 DEBUG : Creating backend with remote "TestYandex:rclone-test-cejamuq2meca/backup" 2025/04/11 02:28:47 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/04/11 02:28:47 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/11 02:28:47 DEBUG : two: Unchanged skipping 2025/04/11 02:28:47 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-cejamuq2meca/dst': Waiting for checks to finish 2025/04/11 02:28:48 DEBUG : one: move non-chunked object... 2025/04/11 02:28:49 INFO : one: Moved (server-side) to: one.bak 2025/04/11 02:28:49 INFO : one: Moved (server-side) to: one.bak 2025/04/11 02:28:49 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-cejamuq2meca/dst': Waiting for transfers to finish 2025/04/11 02:28:50 DEBUG : one: skip slow MD5 on source file, hashing in-transit 2025/04/11 02:28:55 INFO : one.rclone_chunk.001_jdtw8q: Moved (server-side) to: one 2025/04/11 02:28:55 DEBUG : one: md5 = c7957179c41f69d44f217a108c7915d8 OK 2025/04/11 02:28:55 INFO : one: Copied (new) 2025/04/11 02:28:55 DEBUG : Waiting for deletions to finish 2025/04/11 02:28:55 DEBUG : three.txt: move non-chunked object... 2025/04/11 02:28:56 INFO : three.txt: Moved (server-side) to: three.txt.bak 2025/04/11 02:28:56 INFO : three.txt: Moved (server-side) to: three.txt.bak 2025/04/11 02:28:56 INFO : three.txt: Moved into backup dir 2025/04/11 02:29:03 INFO : dst/three.txt.rclone_chunk.001_jdu49l: Moved (server-side) to: dst/three.txt 2025/04/11 02:29:04 DEBUG : one: Sizes differ (src 5 vs dst 4) 2025/04/11 02:29:04 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-cejamuq2meca/dst': Waiting for checks to finish 2025/04/11 02:29:04 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/11 02:29:04 DEBUG : two: Unchanged skipping 2025/04/11 02:29:08 INFO : one.bak: Deleted 2025/04/11 02:29:08 DEBUG : one: move non-chunked object... 2025/04/11 02:29:10 INFO : one: Moved (server-side) to: one.bak 2025/04/11 02:29:10 INFO : one: Moved (server-side) to: one.bak 2025/04/11 02:29:10 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-cejamuq2meca/dst': Waiting for transfers to finish 2025/04/11 02:29:10 DEBUG : one: skip slow MD5 on source file, hashing in-transit 2025/04/11 02:29:16 INFO : one.rclone_chunk.001_jdugay: Moved (server-side) to: one 2025/04/11 02:29:16 DEBUG : one: md5 = 0f93e81041f0cab37c37a05ae998b219 OK 2025/04/11 02:29:16 INFO : one: Copied (new) 2025/04/11 02:29:16 DEBUG : Waiting for deletions to finish 2025/04/11 02:29:20 INFO : three.txt.bak: Deleted 2025/04/11 02:29:20 DEBUG : three.txt: move non-chunked object... 2025/04/11 02:29:22 INFO : three.txt: Moved (server-side) to: three.txt.bak 2025/04/11 02:29:22 INFO : three.txt: Moved (server-side) to: three.txt.bak 2025/04/11 02:29:22 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirWithSuffix (79.88s) === RUN TestSyncConcurrentDelete run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-cejamuq2meca'", Local "Local file system at /tmp/rclone535445051", Modify Window "1ns" 2025/04/11 02:29:52 INFO : both0.rclone_chunk.001_jdvi3m: Moved (server-side) to: both0 2025/04/11 02:29:57 INFO : only0.rclone_chunk.001_jdvm0b: Moved (server-side) to: only0 2025/04/11 02:30:03 INFO : both1.rclone_chunk.001_jdvrcg: Moved (server-side) to: both1 2025/04/11 02:30:10 INFO : only1.rclone_chunk.001_jdvxbo: Moved (server-side) to: only1 2025/04/11 02:30:16 INFO : both2.rclone_chunk.001_jdw4pd: Moved (server-side) to: both2 2025/04/11 02:30:20 INFO : only2.rclone_chunk.001_jdwat3: Moved (server-side) to: only2 2025/04/11 02:30:25 INFO : both3.rclone_chunk.001_jdwf9o: Moved (server-side) to: both3 2025/04/11 02:30:29 INFO : only3.rclone_chunk.001_jdwj1q: Moved (server-side) to: only3 2025/04/11 02:30:35 INFO : both4.rclone_chunk.001_jdwog9: Moved (server-side) to: both4 2025/04/11 02:30:41 INFO : only4.rclone_chunk.001_jdwuwg: Moved (server-side) to: only4 2025/04/11 02:30:47 INFO : both5.rclone_chunk.001_jdx0hs: Moved (server-side) to: both5 2025/04/11 02:30:53 INFO : only5.rclone_chunk.001_jdx539: Moved (server-side) to: only5 2025/04/11 02:30:59 INFO : both6.rclone_chunk.001_jdxc46: Moved (server-side) to: both6 2025/04/11 02:31:07 INFO : only6.rclone_chunk.001_jdxikh: Moved (server-side) to: only6 2025/04/11 02:31:11 INFO : both7.rclone_chunk.001_jdxpq0: Moved (server-side) to: both7 2025/04/11 02:31:17 INFO : only7.rclone_chunk.001_jdxul3: Moved (server-side) to: only7 2025/04/11 02:31:23 INFO : both8.rclone_chunk.001_jdxzs4: Moved (server-side) to: both8 2025/04/11 02:31:28 INFO : only8.rclone_chunk.001_jdy58x: Moved (server-side) to: only8 2025/04/11 02:31:34 INFO : both9.rclone_chunk.001_jdyaeo: Moved (server-side) to: both9 2025/04/11 02:31:39 INFO : only9.rclone_chunk.001_jdyhn7: Moved (server-side) to: only9 2025/04/11 02:31:45 INFO : both10.rclone_chunk.001_jdymj1: Moved (server-side) to: both10 2025/04/11 02:31:50 INFO : only10.rclone_chunk.001_jdyro6: Moved (server-side) to: only10 2025/04/11 02:31:55 INFO : both11.rclone_chunk.001_jdywjy: Moved (server-side) to: both11 2025/04/11 02:32:00 INFO : only11.rclone_chunk.001_jdz1ou: Moved (server-side) to: only11 2025/04/11 02:32:05 INFO : both12.rclone_chunk.001_jdz6kn: Moved (server-side) to: both12 2025/04/11 02:32:12 INFO : only12.rclone_chunk.001_jdzd1l: Moved (server-side) to: only12 2025/04/11 02:32:17 INFO : both13.rclone_chunk.001_jdzimn: Moved (server-side) to: both13 2025/04/11 02:32:23 INFO : only13.rclone_chunk.001_jdznjm: Moved (server-side) to: only13 2025/04/11 02:32:36 INFO : both14.rclone_chunk.001_jdztvr: Moved (server-side) to: both14 2025/04/11 02:32:41 INFO : only14.rclone_chunk.001_je06rd: Moved (server-side) to: only14 2025/04/11 02:32:46 INFO : both15.rclone_chunk.001_je0b5p: Moved (server-side) to: both15 2025/04/11 02:32:51 INFO : only15.rclone_chunk.001_je0gnj: Moved (server-side) to: only15 2025/04/11 02:32:56 INFO : both16.rclone_chunk.001_je0m5x: Moved (server-side) to: both16 2025/04/11 02:33:03 INFO : only16.rclone_chunk.001_je0rn9: Moved (server-side) to: only16 2025/04/11 02:33:08 INFO : both17.rclone_chunk.001_je0x8o: Moved (server-side) to: both17 2025/04/11 02:33:14 INFO : only17.rclone_chunk.001_je1229: Moved (server-side) to: only17 2025/04/11 02:33:19 INFO : both18.rclone_chunk.001_je18zm: Moved (server-side) to: both18 2025/04/11 02:33:24 INFO : only18.rclone_chunk.001_je1dsi: Moved (server-side) to: only18 2025/04/11 02:33:30 INFO : both19.rclone_chunk.001_je1jo2: Moved (server-side) to: both19 2025/04/11 02:33:35 INFO : only19.rclone_chunk.001_je1pkh: Moved (server-side) to: only19 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/sync/sync_test.go:2744 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2758 Error: Should be true Test: TestSyncConcurrentDelete Messages: only18: Modification time difference too big |-211990h28m17.500000001s| > 1ns (want 2001-02-03 04:05:06.499999999 +0000 UTC vs got 2025-04-11 02:33:24 +0000 UTC) (precision 1ns) 2025/04/11 02:33:36 DEBUG : both0: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/11 02:33:36 DEBUG : both1: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/11 02:33:36 DEBUG : both10: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/11 02:33:36 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-cejamuq2meca': Waiting for checks to finish 2025/04/11 02:33:36 DEBUG : both11: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/11 02:33:36 DEBUG : both0: Unchanged skipping 2025/04/11 02:33:36 DEBUG : both12: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/11 02:33:36 DEBUG : both12: Unchanged skipping 2025/04/11 02:33:36 DEBUG : both13: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/11 02:33:36 DEBUG : both13: Unchanged skipping 2025/04/11 02:33:36 DEBUG : both14: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/11 02:33:36 DEBUG : both14: Unchanged skipping 2025/04/11 02:33:36 DEBUG : both15: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/11 02:33:36 DEBUG : both15: Unchanged skipping 2025/04/11 02:33:36 DEBUG : both16: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/11 02:33:36 DEBUG : both16: Unchanged skipping 2025/04/11 02:33:36 DEBUG : both17: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/11 02:33:36 DEBUG : both17: Unchanged skipping 2025/04/11 02:33:36 DEBUG : both18: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/11 02:33:36 DEBUG : both18: Unchanged skipping 2025/04/11 02:33:36 DEBUG : both19: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/11 02:33:36 DEBUG : both19: Unchanged skipping 2025/04/11 02:33:36 DEBUG : both2: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/11 02:33:36 DEBUG : both2: Unchanged skipping 2025/04/11 02:33:36 DEBUG : both3: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/11 02:33:36 DEBUG : both1: Unchanged skipping 2025/04/11 02:33:36 DEBUG : both4: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/11 02:33:36 DEBUG : both3: Unchanged skipping 2025/04/11 02:33:36 DEBUG : both5: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/11 02:33:36 DEBUG : both5: Unchanged skipping 2025/04/11 02:33:36 DEBUG : both6: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/11 02:33:36 DEBUG : both6: Unchanged skipping 2025/04/11 02:33:36 DEBUG : both7: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/11 02:33:36 DEBUG : both7: Unchanged skipping 2025/04/11 02:33:36 DEBUG : both8: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/11 02:33:36 DEBUG : both8: Unchanged skipping 2025/04/11 02:33:36 DEBUG : both9: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/11 02:33:36 DEBUG : both10: Unchanged skipping 2025/04/11 02:33:36 DEBUG : both4: Unchanged skipping 2025/04/11 02:33:36 DEBUG : both11: Unchanged skipping 2025/04/11 02:33:36 DEBUG : both9: Unchanged skipping 2025/04/11 02:33:36 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-cejamuq2meca': Waiting for transfers to finish 2025/04/11 02:33:36 DEBUG : Waiting for deletions to finish 2025/04/11 02:33:40 INFO : only11: Deleted 2025/04/11 02:33:40 INFO : only18: Deleted 2025/04/11 02:33:40 INFO : only16: Deleted 2025/04/11 02:33:40 INFO : only14: Deleted 2025/04/11 02:33:44 INFO : only5: Deleted 2025/04/11 02:33:44 INFO : only15: Deleted 2025/04/11 02:33:44 INFO : only0: Deleted 2025/04/11 02:33:44 INFO : only10: Deleted 2025/04/11 02:33:48 INFO : only6: Deleted 2025/04/11 02:33:48 INFO : only3: Deleted 2025/04/11 02:33:48 INFO : only4: Deleted 2025/04/11 02:33:51 INFO : only13: Deleted 2025/04/11 02:33:51 INFO : only17: Deleted 2025/04/11 02:33:51 INFO : only1: Deleted 2025/04/11 02:33:52 INFO : only12: Deleted 2025/04/11 02:33:55 INFO : only19: Deleted 2025/04/11 02:33:55 INFO : only2: Deleted 2025/04/11 02:33:55 INFO : only7: Deleted 2025/04/11 02:33:56 INFO : only9: Deleted 2025/04/11 02:33:58 INFO : only8: Deleted run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-cejamuq2meca'", Local "Local file system at /tmp/rclone535445051", Modify Window "1ns" --- FAIL: TestSyncConcurrentDelete (332.66s) FAIL 2025/04/11 02:35:20 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-cejamuq2meca': Purge remote "./sync.test -test.v -test.timeout 1h0m0s -remote TestChunkerChunk50bYandex: -verbose -size-limit 1024 -test.run '^(TestCopyCheckFirst|TestCopyRedownload|TestServerSideMoveWithFilter|TestSyncBackupDirWithSuffix|TestSyncConcurrentDelete|TestSyncCopyDest)$'" - Finished ERROR in 11m11.993601422s (try 2/5): exit status 1: Failed [TestSyncCopyDest TestSyncConcurrentDelete]