"./sync.test -test.v -test.timeout 1h0m0s -remote TestPixeldrain: -verbose -test.run '^(TestCopyEmptyDirectories|TestNothingToTransferWithEmptyDirs|TestSyncBackupDirWithSuffix|TestSyncBackupDirWithSuffixKeepExtension)$'" - Starting (try 2/5) 2026/07/11 04:02:49 DEBUG : Creating backend with remote "TestPixeldrain:rclone-test-nucugas0jisu" 2026/07/11 04:02:49 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/07/11 04:02:50 INFO : pixeldrain root 'rclone-test-nucugas0jisu': Logged in as 'ncw', subscription 'Prepaid', storage limit -1 2026/07/11 04:02:50 DEBUG : Creating backend with remote "/tmp/rclone1073143843" === RUN TestCopyEmptyDirectories run.go:198: Remote "pixeldrain root 'rclone-test-nucugas0jisu'", Local "Local file system at /tmp/rclone1073143843", Modify Window "1ms" 2026/07/11 04:02:50 DEBUG : sub dir2/sub sub dir2: Making directory with metadata 2026/07/11 04:02:50 INFO : sub dir2/sub sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2026/07/11 04:02:50 INFO : sub dir2: Set directory modification time (using DirSetModTime) 2026/07/11 04:02:50 INFO : sub dir: Set directory modification time (using DirSetModTime) 2026/07/11 04:02:50 INFO : sub dir2: Making directory 2026/07/11 04:02:50 INFO : sub dir2: Made directory with modification time 2011-12-25 12:59:59.123456789 +0000 UTC 2026/07/11 04:02:50 INFO : sub dir: Making directory 2026/07/11 04:02:50 INFO : sub dir: Made directory with modification time 2001-02-03 04:05:06.499999999 +0000 UTC 2026/07/11 04:02:50 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2026/07/11 04:02:50 INFO : sub dir2/sub sub dir2: Making directory 2026/07/11 04:02:50 DEBUG : sub dir/hello world: size = 11 OK 2026/07/11 04:02:50 DEBUG : sub dir/hello world: sha256 = b94d27b9934d3e08a52e52d7da7dabfac484efe37a5380ee9088f7ace2efcde9 OK 2026/07/11 04:02:50 INFO : sub dir/hello world: Copied (new) 2026/07/11 04:02:50 INFO : sub dir2/sub sub dir2: Made directory with modification time 2011-12-25 12:59:59.123456789 +0000 UTC 2026/07/11 04:02:50 DEBUG : pixeldrain root 'rclone-test-nucugas0jisu': Waiting for checks to finish 2026/07/11 04:02:50 DEBUG : pixeldrain root 'rclone-test-nucugas0jisu': Waiting for transfers to finish --- PASS: TestCopyEmptyDirectories (0.66s) === RUN TestSyncBackupDirWithSuffix run.go:198: Remote "pixeldrain root 'rclone-test-nucugas0jisu'", Local "Local file system at /tmp/rclone1073143843", Modify Window "1ms" 2026/07/11 04:02:50 DEBUG : Creating backend with remote "TestPixeldrain:rclone-test-nucugas0jisu/dst" 2026/07/11 04:02:51 INFO : pixeldrain root 'rclone-test-nucugas0jisu/dst': Logged in as 'ncw', subscription 'Prepaid', storage limit -1 2026/07/11 04:02:51 DEBUG : Creating backend with remote "TestPixeldrain:rclone-test-nucugas0jisu/backup" 2026/07/11 04:02:51 INFO : pixeldrain root 'rclone-test-nucugas0jisu/backup': Logged in as 'ncw', subscription 'Prepaid', storage limit -1 2026/07/11 04:02:51 DEBUG : one: size = 4 (Local file system at /tmp/rclone1073143843) 2026/07/11 04:02:51 DEBUG : one: size = 3 (pixeldrain root 'rclone-test-nucugas0jisu/dst') 2026/07/11 04:02:51 DEBUG : one: Sizes differ 2026/07/11 04:02:51 DEBUG : pixeldrain root 'rclone-test-nucugas0jisu/dst': Waiting for checks to finish 2026/07/11 04:02:51 DEBUG : two: size = 3 OK 2026/07/11 04:02:51 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2026/07/11 04:02:51 DEBUG : two: Unchanged skipping 2026/07/11 04:02:51 INFO : one: Moved (server-side) to: one.bak 2026/07/11 04:02:51 DEBUG : pixeldrain root 'rclone-test-nucugas0jisu/dst': Waiting for transfers to finish 2026/07/11 04:02:51 DEBUG : one: size = 4 OK 2026/07/11 04:02:51 DEBUG : one: sha256 = adefbbf0dff80800a3eb8cd37fa8160a530c6ab1fed4f44001b69139c5177a93 OK 2026/07/11 04:02:51 INFO : one: Copied (new) 2026/07/11 04:02:51 DEBUG : Waiting for deletions to finish 2026/07/11 04:02:51 INFO : three.txt: Moved (server-side) to: three.txt.bak 2026/07/11 04:02:51 INFO : three.txt: Moved into backup dir 2026/07/11 04:02:51 DEBUG : pixeldrain root 'rclone-test-nucugas0jisu/dst': Waiting for checks to finish 2026/07/11 04:02:51 DEBUG : one: size = 5 (Local file system at /tmp/rclone1073143843) 2026/07/11 04:02:51 DEBUG : one: size = 4 (pixeldrain root 'rclone-test-nucugas0jisu/dst') 2026/07/11 04:02:51 DEBUG : one: Sizes differ 2026/07/11 04:02:51 DEBUG : two: size = 3 OK 2026/07/11 04:02:51 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2026/07/11 04:02:51 DEBUG : two: Unchanged skipping 2026/07/11 04:02:51 INFO : one.bak: Deleted 2026/07/11 04:02:51 INFO : one: Moved (server-side) to: one.bak 2026/07/11 04:02:51 DEBUG : pixeldrain root 'rclone-test-nucugas0jisu/dst': Waiting for transfers to finish 2026/07/11 04:02:51 DEBUG : one: size = 5 OK 2026/07/11 04:02:51 DEBUG : one: sha256 = 517b021836359b3420b6082b5b16f379079452b9e0ac97b88ae86c6c757ee7b8 OK 2026/07/11 04:02:51 INFO : one: Copied (new) 2026/07/11 04:02:51 DEBUG : Waiting for deletions to finish 2026/07/11 04:02:51 INFO : three.txt.bak: Deleted 2026/07/11 04:02:51 INFO : three.txt: Moved (server-side) to: three.txt.bak 2026/07/11 04:02:51 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirWithSuffix (1.32s) === RUN TestSyncBackupDirWithSuffixKeepExtension run.go:198: Remote "pixeldrain root 'rclone-test-nucugas0jisu'", Local "Local file system at /tmp/rclone1073143843", Modify Window "1ms" 2026/07/11 04:02:52 DEBUG : Creating backend with remote "TestPixeldrain:rclone-test-nucugas0jisu/dst" 2026/07/11 04:02:52 INFO : pixeldrain root 'rclone-test-nucugas0jisu/dst': Logged in as 'ncw', subscription 'Prepaid', storage limit -1 2026/07/11 04:02:52 DEBUG : Creating backend with remote "TestPixeldrain:rclone-test-nucugas0jisu/backup" 2026/07/11 04:02:52 INFO : pixeldrain root 'rclone-test-nucugas0jisu/backup': Logged in as 'ncw', subscription 'Prepaid', storage limit -1 2026/07/11 04:02:52 DEBUG : one: size = 4 (Local file system at /tmp/rclone1073143843) 2026/07/11 04:02:52 DEBUG : one: size = 3 (pixeldrain root 'rclone-test-nucugas0jisu/dst') 2026/07/11 04:02:52 DEBUG : one: Sizes differ 2026/07/11 04:02:52 DEBUG : two: size = 3 OK 2026/07/11 04:02:52 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2026/07/11 04:02:52 DEBUG : two: Unchanged skipping 2026/07/11 04:02:52 DEBUG : pixeldrain root 'rclone-test-nucugas0jisu/dst': Waiting for checks to finish 2026/07/11 04:02:52 INFO : one: Moved (server-side) to: one-2019-01-01 2026/07/11 04:02:52 DEBUG : pixeldrain root 'rclone-test-nucugas0jisu/dst': Waiting for transfers to finish 2026/07/11 04:02:52 DEBUG : one: size = 4 OK 2026/07/11 04:02:52 DEBUG : one: sha256 = adefbbf0dff80800a3eb8cd37fa8160a530c6ab1fed4f44001b69139c5177a93 OK 2026/07/11 04:02:52 INFO : one: Copied (new) 2026/07/11 04:02:52 DEBUG : Waiting for deletions to finish 2026/07/11 04:02:52 INFO : three.txt: Moved (server-side) to: three-2019-01-01.txt 2026/07/11 04:02:52 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:358 /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:2466 /usr/local/go/src/runtime/asm_amd64.s:1771 Error: Should be true Test: TestSyncBackupDirWithSuffixKeepExtension Messages: listing wrong, want backup/one-2019-01-01 (3), backup/three-2019-01-01.txt (5), dst/one (4), dst/two (3) got backup/one-2019-01-01 (3), backup/three-2019-01-01.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:358 /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:2466 Error: Not equal: expected: 0 actual : 1 Test: TestSyncBackupDirWithSuffixKeepExtension 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:358 /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:2466 /usr/local/go/src/runtime/asm_amd64.s:1771 Error: Should be true Test: TestSyncBackupDirWithSuffixKeepExtension Messages: listing wrong, want backup/one-2019-01-01 (3), backup/three-2019-01-01.txt (5), dst/one (4), dst/three.txt (6), dst/two (3) got backup/one-2019-01-01 (3), backup/three-2019-01-01.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:358 /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:2466 Error: Not equal: expected: 0 actual : 1 Test: TestSyncBackupDirWithSuffixKeepExtension Messages: 1 objects not found 2026/07/11 04:03:07 DEBUG : one: Need to transfer - File not found at Destination 2026/07/11 04:03:07 DEBUG : two: size = 3 OK 2026/07/11 04:03:07 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2026/07/11 04:03:07 DEBUG : two: Unchanged skipping 2026/07/11 04:03:07 DEBUG : pixeldrain root 'rclone-test-nucugas0jisu/dst': Waiting for checks to finish 2026/07/11 04:03:07 DEBUG : pixeldrain root 'rclone-test-nucugas0jisu/dst': Waiting for transfers to finish 2026/07/11 04:03:07 DEBUG : one: size = 5 OK 2026/07/11 04:03:07 DEBUG : one: sha256 = 517b021836359b3420b6082b5b16f379079452b9e0ac97b88ae86c6c757ee7b8 OK 2026/07/11 04:03:07 INFO : one: Copied (new) 2026/07/11 04:03:07 DEBUG : Waiting for deletions to finish 2026/07/11 04:03:07 INFO : three-2019-01-01.txt: Deleted 2026/07/11 04:03:07 INFO : three.txt: Moved (server-side) to: three-2019-01-01.txt 2026/07/11 04:03:07 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:358 /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:2466 /usr/local/go/src/runtime/asm_amd64.s:1771 Error: Should be true Test: TestSyncBackupDirWithSuffixKeepExtension Messages: listing wrong, want backup/one-2019-01-01 (4), backup/three-2019-01-01.txt (6), dst/one (5), dst/two (3) got backup/one-2019-01-01 (3), backup/three-2019-01-01.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:358 /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:2466 Error: Should be true Test: TestSyncBackupDirWithSuffixKeepExtension Messages: pixeldrain root 'rclone-test-nucugas0jisu'/backup/one-2019-01-01: 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:358 /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:2466 Error: Not equal: expected: 4 actual : 3 Test: TestSyncBackupDirWithSuffixKeepExtension Messages: backup/one-2019-01-01: 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:358 /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:2466 Error: Should be true Test: TestSyncBackupDirWithSuffixKeepExtension Messages: backup/one-2019-01-01: 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: TestSyncBackupDirWithSuffixKeepExtension (22.64s) === RUN TestNothingToTransferWithEmptyDirs run.go:198: Remote "pixeldrain root 'rclone-test-nucugas0jisu'", Local "Local file system at /tmp/rclone1073143843", Modify Window "1ms" 2026/07/11 04:03:14 INFO : sub dir: Set directory modification time (using DirSetModTime) 2026/07/11 04:03:14 INFO : sub dir: Making directory 2026/07/11 04:03:14 INFO : sub dir: Made directory with modification time 2011-12-30 12:59:59 +0000 UTC 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:639 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:391 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2906 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2977 Error: Should be true Test: TestNothingToTransferWithEmptyDirs Messages: sub dir2: Modification time difference too big |-70.035486ms| > 1ms (want 2026-07-11 04:03:14.753964514 +0000 UTC vs got 2026-07-11 04:03:14.824 +0000 UTC) (precision 1ms) sync_test.go:2933: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2933 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2977 Error: Should be false Test: TestNothingToTransferWithEmptyDirs Messages: expected to find no "Set directory modification time" logs, but found one: 2026/07/11 04:03:15 INFO : sub dir2: Set directory modification time (using DirSetModTime) 2026/07/11 04:03:16 INFO : There was nothing to transfer sync_test.go:2935: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2935 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2977 Error: Should be false Test: TestNothingToTransferWithEmptyDirs Messages: expected to find no "directory"-related logs, but found one: 2026/07/11 04:03:15 INFO : sub dir2: Set directory modification time (using DirSetModTime) 2026/07/11 04:03:16 INFO : There was nothing to transfer 2026/07/11 04:03:16 INFO : sub dir2: Set directory modification time (using DirSetModTime) 2026/07/11 04:03:16 INFO : sub dir2: Set directory modification time (using DirSetModTime) 2026/07/11 04:03:16 INFO : sub dirEmpty/sub dirEmpty2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2026/07/11 04:03:16 INFO : sub dirEmpty: Set directory modification time (using DirSetModTime) 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:639 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:391 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2967 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2977 Error: Should be true Test: TestNothingToTransferWithEmptyDirs Messages: sub dirEmpty: Modification time difference too big |-127479h3m17.463543211s| > 1ms (want 2011-12-25 12:59:59.123456789 +0000 UTC vs got 2026-07-11 04:03:16.587 +0000 UTC) (precision 1ms) --- FAIL: TestNothingToTransferWithEmptyDirs (3.48s) FAIL 2026/07/11 04:03:18 DEBUG : pixeldrain root 'rclone-test-nucugas0jisu': Purge remote "./sync.test -test.v -test.timeout 1h0m0s -remote TestPixeldrain: -verbose -test.run '^(TestCopyEmptyDirectories|TestNothingToTransferWithEmptyDirs|TestSyncBackupDirWithSuffix|TestSyncBackupDirWithSuffixKeepExtension)$'" - Finished ERROR in 28.323650103s (try 2/5): exit status 1: Failed [TestSyncBackupDirWithSuffixKeepExtension TestNothingToTransferWithEmptyDirs]