"./sync.test -test.v -test.timeout 1h0m0s -remote TestPixeldrain: -verbose -test.run '^(TestCopy|TestCopyEmptyDirectories|TestCopyMetadata|TestCopyMetadataNoEmptyDirs|TestNothingToTransferWithEmptyDirs|TestNothingToTransferWithoutEmptyDirs|TestSyncBackupDir|TestSyncBackupDirWithSuffix)$'" - Starting (try 3/5) 2026/07/10 03:01:28 DEBUG : Creating backend with remote "TestPixeldrain:rclone-test-nikiyic6webo" 2026/07/10 03:01:28 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/07/10 03:01:28 INFO : pixeldrain root 'rclone-test-nikiyic6webo': Logged in as 'ncw', subscription 'Prepaid', storage limit -1 2026/07/10 03:01:28 DEBUG : Creating backend with remote "/tmp/rclone35214661" === RUN TestCopy run.go:198: Remote "pixeldrain root 'rclone-test-nikiyic6webo'", Local "Local file system at /tmp/rclone35214661", Modify Window "1ms" 2026/07/10 03:01:28 INFO : sub dir: Set directory modification time (using DirSetModTime) 2026/07/10 03:01:28 DEBUG : Added delayed dir = "sub dir", newDst= 2026/07/10 03:01:28 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2026/07/10 03:01:28 DEBUG : pixeldrain root 'rclone-test-nikiyic6webo': Waiting for checks to finish 2026/07/10 03:01:28 DEBUG : pixeldrain root 'rclone-test-nikiyic6webo': Waiting for transfers to finish 2026/07/10 03:01:28 DEBUG : sub dir/hello world: size = 11 OK 2026/07/10 03:01:28 DEBUG : sub dir/hello world: sha256 = b94d27b9934d3e08a52e52d7da7dabfac484efe37a5380ee9088f7ace2efcde9 OK 2026/07/10 03:01:28 INFO : sub dir/hello world: Copied (new) 2026/07/10 03:01:28 INFO : sub dir: 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:86 Error: Should be true Test: TestCopy Messages: sub dir: Modification time difference too big |-127454h1m29.712543211s| > 1ms (want 2011-12-25 12:59:59.123456789 +0000 UTC vs got 2026-07-10 03:01:28.836 +0000 UTC) (precision 1ms) --- FAIL: TestCopy (0.36s) === RUN TestCopyMetadata run.go:198: Remote "pixeldrain root 'rclone-test-nikiyic6webo'", Local "Local file system at /tmp/rclone35214661", Modify Window "1ms" 2026/07/10 03:01:29 DEBUG : metadata sub dir: Making directory with metadata 2026/07/10 03:01:29 INFO : metadata sub dir: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2026/07/10 03:01:29 DEBUG : empty metadata sub dir: Making directory with metadata 2026/07/10 03:01:29 INFO : empty metadata sub dir: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2026/07/10 03:01:29 DEBUG : Local file system at /tmp/rclone35214661: File to upload is small (21 bytes), uploading instead of streaming 2026/07/10 03:01:29 DEBUG : metadata sub dir/hello metadata world: size = 21 OK 2026/07/10 03:01:29 DEBUG : metadata sub dir/hello metadata world: md5 = baa7a013449dbba2b1ed02b5cfb4e1a2 OK 2026/07/10 03:01:29 DEBUG : metadata sub dir/hello metadata world: Size and md5 of src and dst objects identical 2026/07/10 03:01:29 INFO : metadata sub dir: Set directory modification time (using DirSetModTime) 2026/07/10 03:01:29 INFO : empty metadata sub dir: Making directory 2026/07/10 03:01:29 INFO : empty metadata sub dir: Made directory with modification time 2011-12-25 12:59:59.123456789 +0000 UTC 2026/07/10 03:01:29 INFO : metadata sub dir: Making directory 2026/07/10 03:01:29 INFO : metadata sub dir: Made directory with modification time 2011-12-25 12:59:59.123456789 +0000 UTC 2026/07/10 03:01:29 DEBUG : metadata sub dir/hello metadata world: Need to transfer - File not found at Destination 2026/07/10 03:01:29 DEBUG : pixeldrain root 'rclone-test-nikiyic6webo': Waiting for checks to finish 2026/07/10 03:01:29 DEBUG : pixeldrain root 'rclone-test-nikiyic6webo': Waiting for transfers to finish 2026/07/10 03:01:29 DEBUG : metadata sub dir/hello metadata world: size = 21 OK 2026/07/10 03:01:29 DEBUG : metadata sub dir/hello metadata world: sha256 = 64d5652158c99e4dd08097075e4e76b287f3b63667c0968c7867720a543c6916 OK 2026/07/10 03:01:29 INFO : metadata sub dir/hello metadata world: Copied (new) 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:153 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:177 Error: Should be true Test: TestCopyMetadata Messages: metadata sub dir: Modification time difference too big |-127454h1m30.091543211s| > 1ms (want 2011-12-25 12:59:59.123456789 +0000 UTC vs got 2026-07-10 03:01:29.215 +0000 UTC) (precision 1ms) --- FAIL: TestCopyMetadata (0.53s) === RUN TestCopyMetadataNoEmptyDirs run.go:198: Remote "pixeldrain root 'rclone-test-nikiyic6webo'", Local "Local file system at /tmp/rclone35214661", Modify Window "1ms" 2026/07/10 03:01:29 DEBUG : metadata sub dir: Making directory with metadata 2026/07/10 03:01:29 INFO : metadata sub dir: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2026/07/10 03:01:29 DEBUG : empty metadata sub dir: Making directory with metadata 2026/07/10 03:01:29 INFO : empty metadata sub dir: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2026/07/10 03:01:29 DEBUG : Local file system at /tmp/rclone35214661: File to upload is small (21 bytes), uploading instead of streaming 2026/07/10 03:01:29 DEBUG : metadata sub dir/hello metadata world: size = 21 OK 2026/07/10 03:01:29 DEBUG : metadata sub dir/hello metadata world: md5 = baa7a013449dbba2b1ed02b5cfb4e1a2 OK 2026/07/10 03:01:29 DEBUG : metadata sub dir/hello metadata world: Size and md5 of src and dst objects identical 2026/07/10 03:01:29 INFO : metadata sub dir: Set directory modification time (using DirSetModTime) 2026/07/10 03:01:29 DEBUG : Added delayed dir = "empty metadata sub dir", newDst= 2026/07/10 03:01:29 DEBUG : Added delayed dir = "metadata sub dir", newDst= 2026/07/10 03:01:29 DEBUG : metadata sub dir/hello metadata world: Need to transfer - File not found at Destination 2026/07/10 03:01:29 DEBUG : pixeldrain root 'rclone-test-nikiyic6webo': Waiting for checks to finish 2026/07/10 03:01:29 DEBUG : pixeldrain root 'rclone-test-nikiyic6webo': Waiting for transfers to finish 2026/07/10 03:01:29 DEBUG : metadata sub dir/hello metadata world: size = 21 OK 2026/07/10 03:01:29 DEBUG : metadata sub dir/hello metadata world: sha256 = 64d5652158c99e4dd08097075e4e76b287f3b63667c0968c7867720a543c6916 OK 2026/07/10 03:01:29 INFO : metadata sub dir/hello metadata world: Copied (new) 2026/07/10 03:01:29 INFO : metadata sub dir: 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:153 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:181 Error: Should be true Test: TestCopyMetadataNoEmptyDirs Messages: metadata sub dir: Modification time difference too big |-127454h1m30.559543211s| > 1ms (want 2011-12-25 12:59:59.123456789 +0000 UTC vs got 2026-07-10 03:01:29.683 +0000 UTC) (precision 1ms) --- FAIL: TestCopyMetadataNoEmptyDirs (0.38s) === RUN TestCopyEmptyDirectories run.go:198: Remote "pixeldrain root 'rclone-test-nikiyic6webo'", Local "Local file system at /tmp/rclone35214661", Modify Window "1ms" 2026/07/10 03:01:30 DEBUG : sub dir2/sub sub dir2: Making directory with metadata 2026/07/10 03:01:30 INFO : sub dir2/sub sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2026/07/10 03:01:30 INFO : sub dir2: Set directory modification time (using DirSetModTime) 2026/07/10 03:01:30 INFO : sub dir: Set directory modification time (using DirSetModTime) 2026/07/10 03:01:30 INFO : sub dir2: Making directory 2026/07/10 03:01:30 INFO : sub dir2: Made directory with modification time 2011-12-25 12:59:59.123456789 +0000 UTC 2026/07/10 03:01:30 INFO : sub dir: Making directory 2026/07/10 03:01:30 INFO : sub dir: Made directory with modification time 2001-02-03 04:05:06.499999999 +0000 UTC 2026/07/10 03:01:30 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2026/07/10 03:01:30 INFO : sub dir2/sub sub dir2: Making directory 2026/07/10 03:01:30 DEBUG : sub dir/hello world: size = 11 OK 2026/07/10 03:01:30 DEBUG : sub dir/hello world: sha256 = b94d27b9934d3e08a52e52d7da7dabfac484efe37a5380ee9088f7ace2efcde9 OK 2026/07/10 03:01:30 INFO : sub dir/hello world: Copied (new) 2026/07/10 03:01:30 INFO : sub dir2/sub sub dir2: Made directory with modification time 2011-12-25 12:59:59.123456789 +0000 UTC 2026/07/10 03:01:30 DEBUG : pixeldrain root 'rclone-test-nikiyic6webo': Waiting for checks to finish 2026/07/10 03:01:30 DEBUG : pixeldrain root 'rclone-test-nikiyic6webo': Waiting for transfers to finish 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:371 Error: Should be true Test: TestCopyEmptyDirectories Messages: sub dir: Modification time difference too big |-222910h56m23.619000001s| > 1ms (want 2001-02-03 04:05:06.499999999 +0000 UTC vs got 2026-07-10 03:01:30.119 +0000 UTC) (precision 1ms) 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:371 Error: Should be true Test: TestCopyEmptyDirectories Messages: sub dir2: Modification time difference too big |-127454h1m30.936543211s| > 1ms (want 2011-12-25 12:59:59.123456789 +0000 UTC vs got 2026-07-10 03:01:30.06 +0000 UTC) (precision 1ms) 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:371 Error: Should be true Test: TestCopyEmptyDirectories Messages: sub dir2/sub sub dir2: Modification time difference too big |-127454h1m31.064543211s| > 1ms (want 2011-12-25 12:59:59.123456789 +0000 UTC vs got 2026-07-10 03:01:30.188 +0000 UTC) (precision 1ms) --- FAIL: TestCopyEmptyDirectories (0.60s) === RUN TestSyncBackupDir run.go:198: Remote "pixeldrain root 'rclone-test-nikiyic6webo'", Local "Local file system at /tmp/rclone35214661", Modify Window "1ms" 2026/07/10 03:01:30 DEBUG : Creating backend with remote "TestPixeldrain:rclone-test-nikiyic6webo/dst" 2026/07/10 03:01:30 INFO : pixeldrain root 'rclone-test-nikiyic6webo/dst': Logged in as 'ncw', subscription 'Prepaid', storage limit -1 2026/07/10 03:01:30 DEBUG : Creating backend with remote "TestPixeldrain:rclone-test-nikiyic6webo/backup" 2026/07/10 03:01:31 INFO : pixeldrain root 'rclone-test-nikiyic6webo/backup': Logged in as 'ncw', subscription 'Prepaid', storage limit -1 2026/07/10 03:01:31 DEBUG : two: size = 3 OK 2026/07/10 03:01:31 DEBUG : pixeldrain root 'rclone-test-nikiyic6webo/dst': Waiting for checks to finish 2026/07/10 03:01:31 DEBUG : one: size = 4 (Local file system at /tmp/rclone35214661) 2026/07/10 03:01:31 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2026/07/10 03:01:31 DEBUG : one: size = 3 (pixeldrain root 'rclone-test-nikiyic6webo/dst') 2026/07/10 03:01:31 DEBUG : two: Unchanged skipping 2026/07/10 03:01:31 DEBUG : one: Sizes differ 2026/07/10 03:01:31 INFO : one: Moved (server-side) 2026/07/10 03:01:31 DEBUG : pixeldrain root 'rclone-test-nikiyic6webo/dst': Waiting for transfers to finish 2026/07/10 03:01:31 DEBUG : one: size = 4 OK 2026/07/10 03:01:31 DEBUG : one: sha256 = adefbbf0dff80800a3eb8cd37fa8160a530c6ab1fed4f44001b69139c5177a93 OK 2026/07/10 03:01:31 INFO : one: Copied (new) 2026/07/10 03:01:31 DEBUG : Waiting for deletions to finish 2026/07/10 03:01:31 INFO : three.txt: Moved (server-side) 2026/07/10 03:01:31 INFO : three.txt: Moved into backup dir 2026/07/10 03:01:31 DEBUG : one: size = 5 (Local file system at /tmp/rclone35214661) 2026/07/10 03:01:31 DEBUG : one: size = 4 (pixeldrain root 'rclone-test-nikiyic6webo/dst') 2026/07/10 03:01:31 DEBUG : one: Sizes differ 2026/07/10 03:01:31 DEBUG : two: size = 3 OK 2026/07/10 03:01:31 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2026/07/10 03:01:31 DEBUG : two: Unchanged skipping 2026/07/10 03:01:31 DEBUG : pixeldrain root 'rclone-test-nikiyic6webo/dst': Waiting for checks to finish 2026/07/10 03:01:31 INFO : one: Deleted 2026/07/10 03:01:31 INFO : one: Moved (server-side) 2026/07/10 03:01:31 DEBUG : pixeldrain root 'rclone-test-nikiyic6webo/dst': Waiting for transfers to finish 2026/07/10 03:01:31 DEBUG : one: size = 5 OK 2026/07/10 03:01:31 DEBUG : one: sha256 = 517b021836359b3420b6082b5b16f379079452b9e0ac97b88ae86c6c757ee7b8 OK 2026/07/10 03:01:31 INFO : one: Copied (new) 2026/07/10 03:01:31 DEBUG : Waiting for deletions to finish 2026/07/10 03:01:31 INFO : three.txt: Deleted 2026/07/10 03:01:31 INFO : three.txt: Moved (server-side) 2026/07/10 03:01:31 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDir (1.37s) === RUN TestSyncBackupDirWithSuffix run.go:198: Remote "pixeldrain root 'rclone-test-nikiyic6webo'", Local "Local file system at /tmp/rclone35214661", Modify Window "1ms" 2026/07/10 03:01:32 DEBUG : Creating backend with remote "TestPixeldrain:rclone-test-nikiyic6webo/dst" 2026/07/10 03:01:32 INFO : pixeldrain root 'rclone-test-nikiyic6webo/dst': Logged in as 'ncw', subscription 'Prepaid', storage limit -1 2026/07/10 03:01:32 DEBUG : Creating backend with remote "TestPixeldrain:rclone-test-nikiyic6webo/backup" 2026/07/10 03:01:32 INFO : pixeldrain root 'rclone-test-nikiyic6webo/backup': Logged in as 'ncw', subscription 'Prepaid', storage limit -1 2026/07/10 03:01:32 DEBUG : one: size = 4 (Local file system at /tmp/rclone35214661) 2026/07/10 03:01:32 DEBUG : one: size = 3 (pixeldrain root 'rclone-test-nikiyic6webo/dst') 2026/07/10 03:01:32 DEBUG : one: Sizes differ 2026/07/10 03:01:32 DEBUG : two: size = 3 OK 2026/07/10 03:01:32 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2026/07/10 03:01:32 DEBUG : two: Unchanged skipping 2026/07/10 03:01:32 DEBUG : pixeldrain root 'rclone-test-nikiyic6webo/dst': Waiting for checks to finish 2026/07/10 03:01:32 INFO : one: Moved (server-side) to: one.bak 2026/07/10 03:01:32 DEBUG : pixeldrain root 'rclone-test-nikiyic6webo/dst': Waiting for transfers to finish 2026/07/10 03:01:32 DEBUG : one: size = 4 OK 2026/07/10 03:01:32 DEBUG : one: sha256 = adefbbf0dff80800a3eb8cd37fa8160a530c6ab1fed4f44001b69139c5177a93 OK 2026/07/10 03:01:32 INFO : one: Copied (new) 2026/07/10 03:01:32 DEBUG : Waiting for deletions to finish 2026/07/10 03:01:32 INFO : three.txt: Moved (server-side) to: three.txt.bak 2026/07/10 03:01:32 INFO : three.txt: Moved into backup dir 2026/07/10 03:01:32 DEBUG : one: size = 5 (Local file system at /tmp/rclone35214661) 2026/07/10 03:01:32 DEBUG : one: size = 4 (pixeldrain root 'rclone-test-nikiyic6webo/dst') 2026/07/10 03:01:32 DEBUG : one: Sizes differ 2026/07/10 03:01:32 DEBUG : pixeldrain root 'rclone-test-nikiyic6webo/dst': Waiting for checks to finish 2026/07/10 03:01:32 DEBUG : two: size = 3 OK 2026/07/10 03:01:32 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2026/07/10 03:01:32 DEBUG : two: Unchanged skipping 2026/07/10 03:01:32 INFO : one.bak: Deleted 2026/07/10 03:01:32 INFO : one: Moved (server-side) to: one.bak 2026/07/10 03:01:32 DEBUG : pixeldrain root 'rclone-test-nikiyic6webo/dst': Waiting for transfers to finish 2026/07/10 03:01:32 DEBUG : one: size = 5 OK 2026/07/10 03:01:32 DEBUG : one: sha256 = 517b021836359b3420b6082b5b16f379079452b9e0ac97b88ae86c6c757ee7b8 OK 2026/07/10 03:01:32 INFO : one: Copied (new) 2026/07/10 03:01:32 DEBUG : Waiting for deletions to finish 2026/07/10 03:01:32 INFO : three.txt.bak: Deleted 2026/07/10 03:01:33 INFO : three.txt: Moved (server-side) to: three.txt.bak 2026/07/10 03:01:33 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirWithSuffix (1.31s) === RUN TestNothingToTransferWithEmptyDirs run.go:198: Remote "pixeldrain root 'rclone-test-nikiyic6webo'", Local "Local file system at /tmp/rclone35214661", Modify Window "1ms" 2026/07/10 03:01:33 INFO : sub dir: Set directory modification time (using DirSetModTime) 2026/07/10 03:01:33 INFO : sub dir: Making directory 2026/07/10 03:01:33 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 |-68.382276ms| > 1ms (want 2026-07-10 03:01:33.332617724 +0000 UTC vs got 2026-07-10 03:01:33.401 +0000 UTC) (precision 1ms) 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/very: Modification time difference too big |-167.382276ms| > 1ms (want 2026-07-10 03:01:33.332617724 +0000 UTC vs got 2026-07-10 03:01:33.5 +0000 UTC) (precision 1ms) 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/very/very: Modification time difference too big |-227.382276ms| > 1ms (want 2026-07-10 03:01:33.332617724 +0000 UTC vs got 2026-07-10 03:01:33.56 +0000 UTC) (precision 1ms) 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/very/very/very/very/very/nested/subdir: Modification time difference too big |-550.382276ms| > 1ms (want 2026-07-10 03:01:33.332617724 +0000 UTC vs got 2026-07-10 03:01:33.883 +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/10 03:01:34 INFO : sub dir2: Set directory modification time (using DirSetModTime) 2026/07/10 03:01:34 INFO : sub dir2/very: Set directory modification time (using DirSetModTime) 2026/07/10 03:01:34 INFO : sub dir2/very/very: Set directory modification time (using DirSetModTime) 2026/07/10 03:01:34 INFO : sub dir2/very/very/very: Set directory modification time (using DirSetModTime) 2026/07/10 03:01:34 INFO : sub dir2/very/very/very/very: Set directory modification time (using DirSetModTime) 2026/07/10 03:01:34 INFO : sub dir2/very/very/very/very/very: Set directory modification time (using DirSetModTime) 2026/07/10 03:01:34 INFO : sub dir2/very/very/very/very/very/nested: Set directory modification time (using DirSetModTime) 2026/07/10 03:01:34 INFO : sub dir2/very/very/very/very/very/nested/subdir: Set directory modification time (using DirSetModTime) 2026/07/10 03:01:34 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/10 03:01:34 INFO : sub dir2: Set directory modification time (using DirSetModTime) 2026/07/10 03:01:34 INFO : sub dir2/very: Set directory modification time (using DirSetModTime) 2026/07/10 03:01:34 INFO : sub dir2/very/very: Set directory modification time (using DirSetModTime) 2026/07/10 03:01:34 INFO : sub dir2/very/very/very: Set directory modification time (using DirSetModTime) 2026/07/10 03:01:34 INFO : sub dir2/very/very/very/very: Set directory modification time (using DirSetModTime) 2026/07/10 03:01:34 INFO : sub dir2/very/very/very/very/very: Set directory modification time (using DirSetModTime) 2026/07/10 03:01:34 INFO : sub dir2/very/very/very/very/very/nested: Set directory modification time (using DirSetModTime) 2026/07/10 03:01:34 INFO : sub dir2/very/very/very/very/very/nested/subdir: Set directory modification time (using DirSetModTime) 2026/07/10 03:01:34 INFO : There was nothing to transfer 2026/07/10 03:01:35 INFO : sub dir2: Set directory modification time (using DirSetModTime) 2026/07/10 03:01:35 INFO : sub dir2: Set directory modification time (using DirSetModTime) 2026/07/10 03:01:35 INFO : sub dirEmpty/sub dirEmpty2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2026/07/10 03:01:35 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:2965 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2977 Error: Should be true Test: TestNothingToTransferWithEmptyDirs Messages: sub dir2/sub dir3: Modification time difference too big |-134.37863ms| > 1ms (want 2026-07-10 03:01:35.35262137 +0000 UTC vs got 2026-07-10 03:01:35.487 +0000 UTC) (precision 1ms) 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 |-127454h1m36.265543211s| > 1ms (want 2011-12-25 12:59:59.123456789 +0000 UTC vs got 2026-07-10 03:01:35.389 +0000 UTC) (precision 1ms) 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/sub dirEmpty2: Modification time difference too big |-127454h1m36.353543211s| > 1ms (want 2011-12-25 12:59:59.123456789 +0000 UTC vs got 2026-07-10 03:01:35.477 +0000 UTC) (precision 1ms) --- FAIL: TestNothingToTransferWithEmptyDirs (3.69s) === RUN TestNothingToTransferWithoutEmptyDirs run.go:198: Remote "pixeldrain root 'rclone-test-nikiyic6webo'", Local "Local file system at /tmp/rclone35214661", Modify Window "1ms" 2026/07/10 03:01:37 INFO : sub dir: Set directory modification time (using DirSetModTime) 2026/07/10 03:01:37 INFO : sub dir: Making directory 2026/07/10 03:01:37 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:2981 Error: Should be true Test: TestNothingToTransferWithoutEmptyDirs Messages: sub dir2/very/very/very/very/very/nested/subdir: Modification time difference too big |-126.375618ms| > 1ms (want 2026-07-10 03:01:37.023624382 +0000 UTC vs got 2026-07-10 03:01:37.15 +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:2981 Error: Should be false Test: TestNothingToTransferWithoutEmptyDirs Messages: expected to find no "Set directory modification time" logs, but found one: 2026/07/10 03:01:38 INFO : sub dir2/very/very/very/very/very/nested/subdir: Set directory modification time (using DirSetModTime) 2026/07/10 03:01:38 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:2981 Error: Should be false Test: TestNothingToTransferWithoutEmptyDirs Messages: expected to find no "directory"-related logs, but found one: 2026/07/10 03:01:38 INFO : sub dir2/very/very/very/very/very/nested/subdir: Set directory modification time (using DirSetModTime) 2026/07/10 03:01:38 INFO : There was nothing to transfer 2026/07/10 03:01:38 INFO : sub dir2: Set directory modification time (using DirSetModTime) 2026/07/10 03:01:38 INFO : sub dir2: Set directory modification time (using DirSetModTime) 2026/07/10 03:01:38 INFO : sub dirEmpty/sub dirEmpty2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2026/07/10 03:01:38 INFO : sub dirEmpty: Set directory modification time (using DirSetModTime) --- FAIL: TestNothingToTransferWithoutEmptyDirs (3.02s) FAIL 2026/07/10 03:01:40 DEBUG : pixeldrain root 'rclone-test-nikiyic6webo': Purge remote "./sync.test -test.v -test.timeout 1h0m0s -remote TestPixeldrain: -verbose -test.run '^(TestCopy|TestCopyEmptyDirectories|TestCopyMetadata|TestCopyMetadataNoEmptyDirs|TestNothingToTransferWithEmptyDirs|TestNothingToTransferWithoutEmptyDirs|TestSyncBackupDir|TestSyncBackupDirWithSuffix)$'" - Finished ERROR in 11.457262408s (try 3/5): exit status 1: Failed [TestCopy TestCopyMetadata TestCopyMetadataNoEmptyDirs TestCopyEmptyDirectories TestNothingToTransferWithEmptyDirs TestNothingToTransferWithoutEmptyDirs]