"./sync.test -test.v -test.timeout 1h0m0s -remote TestPixeldrain: -verbose -test.run '^(TestSyncBackupDir|TestSyncBackupDirWithSuffixKeepExtension)$'" - Starting (try 2/5) 2026/03/25 01:41:36 DEBUG : Creating backend with remote "TestPixeldrain:rclone-test-xudarir9muxu" 2026/03/25 01:41:36 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/03/25 01:41:36 INFO : pixeldrain root 'rclone-test-xudarir9muxu': Logged in as 'ncw', subscription 'Prepaid', storage limit -1 2026/03/25 01:41:36 DEBUG : Creating backend with remote "/tmp/rclone582099518" === RUN TestSyncBackupDir run.go:185: Remote "pixeldrain root 'rclone-test-xudarir9muxu'", Local "Local file system at /tmp/rclone582099518", Modify Window "1ms" 2026/03/25 01:41:36 DEBUG : Creating backend with remote "TestPixeldrain:rclone-test-xudarir9muxu/dst" 2026/03/25 01:41:36 INFO : pixeldrain root 'rclone-test-xudarir9muxu/dst': Logged in as 'ncw', subscription 'Prepaid', storage limit -1 2026/03/25 01:41:36 DEBUG : Creating backend with remote "TestPixeldrain:rclone-test-xudarir9muxu/backup" 2026/03/25 01:41:36 INFO : pixeldrain root 'rclone-test-xudarir9muxu/backup': Logged in as 'ncw', subscription 'Prepaid', storage limit -1 2026/03/25 01:41:36 DEBUG : one: size = 4 (Local file system at /tmp/rclone582099518) 2026/03/25 01:41:36 DEBUG : one: size = 3 (pixeldrain root 'rclone-test-xudarir9muxu/dst') 2026/03/25 01:41:36 DEBUG : one: Sizes differ 2026/03/25 01:41:36 DEBUG : pixeldrain root 'rclone-test-xudarir9muxu/dst': Waiting for checks to finish 2026/03/25 01:41:36 DEBUG : two: size = 3 OK 2026/03/25 01:41:36 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2026/03/25 01:41:36 DEBUG : two: Unchanged skipping 2026/03/25 01:41:36 INFO : one: Moved (server-side) 2026/03/25 01:41:36 DEBUG : pixeldrain root 'rclone-test-xudarir9muxu/dst': Waiting for transfers to finish 2026/03/25 01:41:36 DEBUG : one: size = 4 OK 2026/03/25 01:41:36 DEBUG : one: sha256 = adefbbf0dff80800a3eb8cd37fa8160a530c6ab1fed4f44001b69139c5177a93 OK 2026/03/25 01:41:36 INFO : one: Copied (new) 2026/03/25 01:41:36 DEBUG : Waiting for deletions to finish 2026/03/25 01:41:36 INFO : three.txt: Moved (server-side) 2026/03/25 01:41:36 INFO : three.txt: Moved into backup dir fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2429 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2458 /usr/local/go/src/runtime/asm_amd64.s:1771 Error: Should be true Test: TestSyncBackupDir Messages: listing wrong, want backup/one (3), backup/three.txt (5), dst/one (4), dst/two (3) got backup/one (3), backup/three.txt (5), dst/two (3) fstest.go:203: Not found "dst/one" fstest.go:206: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:206 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:310 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2429 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2458 Error: Not equal: expected: 0 actual : 1 Test: TestSyncBackupDir Messages: 1 objects not found fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2435 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2458 /usr/local/go/src/runtime/asm_amd64.s:1771 Error: Should be true Test: TestSyncBackupDir Messages: listing wrong, want backup/one (3), backup/three.txt (5), dst/one (4), dst/three.txt (6), dst/two (3) got backup/one (3), backup/three.txt (5), dst/three.txt (6), dst/two (3) fstest.go:203: Not found "dst/one" fstest.go:206: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:206 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:310 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2435 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2458 Error: Not equal: expected: 0 actual : 1 Test: TestSyncBackupDir Messages: 1 objects not found 2026/03/25 01:41:51 DEBUG : one: Need to transfer - File not found at Destination 2026/03/25 01:41:51 DEBUG : two: size = 3 OK 2026/03/25 01:41:51 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2026/03/25 01:41:51 DEBUG : two: Unchanged skipping 2026/03/25 01:41:51 DEBUG : pixeldrain root 'rclone-test-xudarir9muxu/dst': Waiting for checks to finish 2026/03/25 01:41:51 DEBUG : pixeldrain root 'rclone-test-xudarir9muxu/dst': Waiting for transfers to finish 2026/03/25 01:41:51 DEBUG : one: size = 5 OK 2026/03/25 01:41:51 DEBUG : one: sha256 = 517b021836359b3420b6082b5b16f379079452b9e0ac97b88ae86c6c757ee7b8 OK 2026/03/25 01:41:51 INFO : one: Copied (new) 2026/03/25 01:41:51 DEBUG : Waiting for deletions to finish 2026/03/25 01:41:51 INFO : three.txt: Deleted 2026/03/25 01:41:51 INFO : three.txt: Moved (server-side) 2026/03/25 01:41:51 INFO : three.txt: Moved into backup dir fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2454 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2458 /usr/local/go/src/runtime/asm_amd64.s:1771 Error: Should be true Test: TestSyncBackupDir Messages: listing wrong, want backup/one (4), backup/three.txt (6), dst/one (5), dst/two (3) got backup/one (3), backup/three.txt (6), dst/one (5), dst/two (3) fstest.go:143: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:143 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:149 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:195 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2454 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2458 Error: Should be true Test: TestSyncBackupDir Messages: pixeldrain root 'rclone-test-xudarir9muxu'/backup/one: sha256 hash incorrect - expecting "adefbbf0dff80800a3eb8cd37fa8160a530c6ab1fed4f44001b69139c5177a93" got "7692c3ad3540bb803c020b3aee66cd8887123234ea0c6e7143c0add73ff431ed" fstest.go:150: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:150 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:195 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2454 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2458 Error: Not equal: expected: 4 actual : 3 Test: TestSyncBackupDir Messages: backup/one: size incorrect file=4 vs obj=3 fstest.go:127: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:127 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:132 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:151 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:195 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2454 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2458 Error: Should be true Test: TestSyncBackupDir Messages: backup/one: Modification time difference too big |95456h54m52.624456789s| > 1ms (want 2011-12-25 12:59:59.123456789 +0000 UTC vs got 2001-02-03 04:05:06.499 +0000 UTC) (precision 1ms) --- FAIL: TestSyncBackupDir (22.70s) === RUN TestSyncBackupDirWithSuffixKeepExtension run.go:185: Remote "pixeldrain root 'rclone-test-xudarir9muxu'", Local "Local file system at /tmp/rclone582099518", Modify Window "1ms" 2026/03/25 01:41:59 DEBUG : Creating backend with remote "TestPixeldrain:rclone-test-xudarir9muxu/dst" 2026/03/25 01:41:59 INFO : pixeldrain root 'rclone-test-xudarir9muxu/dst': Logged in as 'ncw', subscription 'Prepaid', storage limit -1 2026/03/25 01:41:59 DEBUG : Creating backend with remote "TestPixeldrain:rclone-test-xudarir9muxu/backup" 2026/03/25 01:41:59 INFO : pixeldrain root 'rclone-test-xudarir9muxu/backup': Logged in as 'ncw', subscription 'Prepaid', storage limit -1 2026/03/25 01:41:59 DEBUG : one: size = 4 (Local file system at /tmp/rclone582099518) 2026/03/25 01:41:59 DEBUG : one: size = 3 (pixeldrain root 'rclone-test-xudarir9muxu/dst') 2026/03/25 01:41:59 DEBUG : one: Sizes differ 2026/03/25 01:41:59 DEBUG : pixeldrain root 'rclone-test-xudarir9muxu/dst': Waiting for checks to finish 2026/03/25 01:41:59 DEBUG : two: size = 3 OK 2026/03/25 01:41:59 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2026/03/25 01:41:59 DEBUG : two: Unchanged skipping 2026/03/25 01:41:59 INFO : one: Moved (server-side) to: one-2019-01-01 2026/03/25 01:41:59 DEBUG : pixeldrain root 'rclone-test-xudarir9muxu/dst': Waiting for transfers to finish 2026/03/25 01:41:59 DEBUG : one: size = 4 OK 2026/03/25 01:41:59 DEBUG : one: sha256 = adefbbf0dff80800a3eb8cd37fa8160a530c6ab1fed4f44001b69139c5177a93 OK 2026/03/25 01:41:59 INFO : one: Copied (new) 2026/03/25 01:41:59 DEBUG : Waiting for deletions to finish 2026/03/25 01:41:59 INFO : three.txt: Moved (server-side) to: three-2019-01-01.txt 2026/03/25 01:41:59 INFO : three.txt: Moved into backup dir 2026/03/25 01:41:59 DEBUG : one: size = 5 (Local file system at /tmp/rclone582099518) 2026/03/25 01:41:59 DEBUG : one: size = 4 (pixeldrain root 'rclone-test-xudarir9muxu/dst') 2026/03/25 01:41:59 DEBUG : one: Sizes differ 2026/03/25 01:41:59 DEBUG : two: size = 3 OK 2026/03/25 01:41:59 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2026/03/25 01:41:59 DEBUG : two: Unchanged skipping 2026/03/25 01:41:59 DEBUG : pixeldrain root 'rclone-test-xudarir9muxu/dst': Waiting for checks to finish 2026/03/25 01:41:59 INFO : one-2019-01-01: Deleted 2026/03/25 01:41:59 INFO : one: Moved (server-side) to: one-2019-01-01 2026/03/25 01:41:59 DEBUG : pixeldrain root 'rclone-test-xudarir9muxu/dst': Waiting for transfers to finish 2026/03/25 01:41:59 DEBUG : one: size = 5 OK 2026/03/25 01:41:59 DEBUG : one: sha256 = 517b021836359b3420b6082b5b16f379079452b9e0ac97b88ae86c6c757ee7b8 OK 2026/03/25 01:41:59 INFO : one: Copied (new) 2026/03/25 01:41:59 DEBUG : Waiting for deletions to finish 2026/03/25 01:41:59 INFO : three-2019-01-01.txt: Deleted 2026/03/25 01:42:00 INFO : three.txt: Moved (server-side) to: three-2019-01-01.txt 2026/03/25 01:42:00 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirWithSuffixKeepExtension (1.31s) FAIL 2026/03/25 01:42:00 DEBUG : pixeldrain root 'rclone-test-xudarir9muxu': Purge remote "./sync.test -test.v -test.timeout 1h0m0s -remote TestPixeldrain: -verbose -test.run '^(TestSyncBackupDir|TestSyncBackupDirWithSuffixKeepExtension)$'" - Finished ERROR in 24.220692506s (try 2/5): exit status 1: Failed [TestSyncBackupDir]