"./sync.test -test.v -test.timeout 1h0m0s -remote TestOneDrive: -verbose -test.run '^(TestCopyEmptyDirectories|TestCopyMetadata|TestMoveEmptyDirectories|TestNothingToTransferWithEmptyDirs|TestSyncEmptyDirectories|TestSyncMultipleCompareDest)$'" - Starting (try 2/5) 2026/01/28 03:23:15 DEBUG : Creating backend with remote "TestOneDrive:rclone-test-gavuxus0tadu" 2026/01/28 03:23:15 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/01/28 03:23:15 DEBUG : TestOneDrive: Set overridden config ["user_agent"] for backend startup 2026/01/28 03:23:16 DEBUG : Creating backend with remote "/tmp/rclone1550567162" === RUN TestCopyMetadata run.go:185: Remote "OneDrive root 'rclone-test-gavuxus0tadu'", Local "Local file system at /tmp/rclone1550567162", Modify Window "1s" 2026/01/28 03:23:16 DEBUG : metadata sub dir: Making directory with metadata 2026/01/28 03:23:16 INFO : metadata sub dir: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2026/01/28 03:23:16 DEBUG : empty metadata sub dir: Making directory with metadata 2026/01/28 03:23:16 INFO : empty metadata sub dir: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2026/01/28 03:23:16 DEBUG : Local file system at /tmp/rclone1550567162: File to upload is small (21 bytes), uploading instead of streaming 2026/01/28 03:23:16 DEBUG : metadata sub dir/hello metadata world: size = 21 OK 2026/01/28 03:23:16 DEBUG : metadata sub dir/hello metadata world: md5 = baa7a013449dbba2b1ed02b5cfb4e1a2 OK 2026/01/28 03:23:16 DEBUG : metadata sub dir/hello metadata world: Size and md5 of src and dst objects identical 2026/01/28 03:23:16 INFO : metadata sub dir: Set directory modification time (using DirSetModTime) 2026/01/28 03:23:18 DEBUG : empty metadata sub dir: skipping unsupported metadata item: mode: 40775 2026/01/28 03:23:18 DEBUG : empty metadata sub dir: skipping unsupported metadata item: uid: 1000 2026/01/28 03:23:18 DEBUG : empty metadata sub dir: skipping unsupported metadata item: gid: 1000 2026/01/28 03:23:18 DEBUG : empty metadata sub dir: skipping unsupported metadata item: atime: 2011-12-25T12:59:59.123456789Z 2026/01/28 03:23:18 DEBUG : empty metadata sub dir: skipping unsupported metadata item: potato: king edward 2026/01/28 03:23:18 INFO : empty metadata sub dir: Updated directory metadata 2026/01/28 03:23:18 DEBUG : metadata sub dir: skipping unsupported metadata item: potato: king edward 2026/01/28 03:23:18 DEBUG : metadata sub dir: skipping unsupported metadata item: mode: 40775 2026/01/28 03:23:18 DEBUG : metadata sub dir: skipping unsupported metadata item: uid: 1000 2026/01/28 03:23:18 DEBUG : metadata sub dir: skipping unsupported metadata item: gid: 1000 2026/01/28 03:23:18 DEBUG : metadata sub dir: skipping unsupported metadata item: atime: 2011-12-25T12:59:59.123456789Z 2026/01/28 03:23:19 INFO : metadata sub dir: Updated directory metadata 2026/01/28 03:23:19 DEBUG : metadata sub dir/hello metadata world: Need to transfer - File not found at Destination 2026/01/28 03:23:19 DEBUG : metadata sub dir/hello metadata world: Starting multipart upload 2026/01/28 03:23:19 DEBUG : metadata sub dir/hello metadata world: skipping unsupported metadata item: gid: 1000 2026/01/28 03:23:19 DEBUG : metadata sub dir/hello metadata world: skipping unsupported metadata item: atime: 2026-01-28T03:23:19.333650314Z 2026/01/28 03:23:19 DEBUG : metadata sub dir/hello metadata world: skipping unsupported metadata item: potato: jersey 2026/01/28 03:23:19 DEBUG : metadata sub dir/hello metadata world: skipping unsupported metadata item: mode: 100664 2026/01/28 03:23:19 DEBUG : metadata sub dir/hello metadata world: skipping unsupported metadata item: uid: 1000 2026/01/28 03:23:19 DEBUG : OneDrive root 'rclone-test-gavuxus0tadu': Waiting for checks to finish 2026/01/28 03:23:19 DEBUG : OneDrive root 'rclone-test-gavuxus0tadu': Waiting for transfers to finish 2026/01/28 03:23:19 DEBUG : metadata sub dir/hello metadata world: Uploading segment 0/21 size 21 2026/01/28 03:23:21 DEBUG : metadata sub dir/hello metadata world: size = 21 OK 2026/01/28 03:23:21 DEBUG : metadata sub dir/hello metadata world: quickxor = 88266c8bdbebce00b6a10c741d0319c240873080 OK 2026/01/28 03:23:21 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:378 /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 |-123542h23m20.876543211s| > 1s (want 2011-12-25 12:59:59.123456789 +0000 UTC vs got 2026-01-28 03:23:20 +0000 UTC) (precision 1s) 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:613 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:160 /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 |-123542h23m20.876543211s| > 1s (want 2011-12-25 12:59:59.123456789 +0000 UTC vs got 2026-01-28 03:23:20 +0000 UTC) (precision 1s) 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:613 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:171 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:177 Error: Should be true Test: TestCopyMetadata Messages: empty metadata sub dir: Modification time difference too big |-123542h23m18.876543211s| > 1s (want 2011-12-25 12:59:59.123456789 +0000 UTC vs got 2026-01-28 03:23:18 +0000 UTC) (precision 1s) --- FAIL: TestCopyMetadata (8.06s) === RUN TestCopyEmptyDirectories run.go:185: Remote "OneDrive root 'rclone-test-gavuxus0tadu'", Local "Local file system at /tmp/rclone1550567162", Modify Window "1s" 2026/01/28 03:23:24 DEBUG : sub dir2/sub sub dir2: Making directory with metadata 2026/01/28 03:23:24 INFO : sub dir2/sub sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2026/01/28 03:23:24 INFO : sub dir2: Set directory modification time (using DirSetModTime) 2026/01/28 03:23:24 INFO : sub dir: Set directory modification time (using DirSetModTime) 2026/01/28 03:23:25 DEBUG : sub dir2: Making directory with metadata 2026/01/28 03:23:25 INFO : sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2026/01/28 03:23:25 DEBUG : sub dir: Making directory with metadata 2026/01/28 03:23:26 INFO : sub dir: Made directory with metadata (mtime=2001-02-03T04:05:06.499999999Z) 2026/01/28 03:23:26 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2026/01/28 03:23:26 DEBUG : sub dir/hello world: Starting multipart upload 2026/01/28 03:23:26 DEBUG : sub dir2/sub sub dir2: Making directory with metadata 2026/01/28 03:23:27 DEBUG : sub dir/hello world: Uploading segment 0/11 size 11 2026/01/28 03:23:27 INFO : sub dir2/sub sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2026/01/28 03:23:27 DEBUG : OneDrive root 'rclone-test-gavuxus0tadu': Waiting for checks to finish 2026/01/28 03:23:27 DEBUG : OneDrive root 'rclone-test-gavuxus0tadu': Waiting for transfers to finish 2026/01/28 03:23:27 DEBUG : sub dir/hello world: size = 11 OK 2026/01/28 03:23:27 DEBUG : sub dir/hello world: quickxor = 6828031bd8f00610dce10d726b03190000000000 OK 2026/01/28 03:23:27 INFO : sub dir/hello 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:378 /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 |-218999h18m19.500000001s| > 1s (want 2001-02-03 04:05:06.499999999 +0000 UTC vs got 2026-01-28 03:23:26 +0000 UTC) (precision 1s) 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:378 /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 |-123542h23m25.876543211s| > 1s (want 2011-12-25 12:59:59.123456789 +0000 UTC vs got 2026-01-28 03:23:25 +0000 UTC) (precision 1s) 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:378 /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 |-123542h23m28.876543211s| > 1s (want 2011-12-25 12:59:59.123456789 +0000 UTC vs got 2026-01-28 03:23:28 +0000 UTC) (precision 1s) --- FAIL: TestCopyEmptyDirectories (6.69s) === RUN TestMoveEmptyDirectories run.go:185: Remote "OneDrive root 'rclone-test-gavuxus0tadu'", Local "Local file system at /tmp/rclone1550567162", Modify Window "1s" 2026/01/28 03:23:31 DEBUG : sub dir2: Making directory with metadata 2026/01/28 03:23:31 INFO : sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2026/01/28 03:23:31 DEBUG : sub dir2: Making directory with metadata 2026/01/28 03:23:32 INFO : sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2026/01/28 03:23:32 DEBUG : sub dir: Making directory with metadata 2026/01/28 03:23:33 INFO : sub dir: Made directory with metadata (mtime=2026-01-28T03:23:31.657758273Z) 2026/01/28 03:23:33 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2026/01/28 03:23:33 DEBUG : sub dir/hello world: Starting multipart upload 2026/01/28 03:23:33 DEBUG : OneDrive root 'rclone-test-gavuxus0tadu': Waiting for checks to finish 2026/01/28 03:23:33 DEBUG : OneDrive root 'rclone-test-gavuxus0tadu': Waiting for transfers to finish 2026/01/28 03:23:33 DEBUG : sub dir/hello world: Uploading segment 0/11 size 11 2026/01/28 03:23:34 DEBUG : sub dir/hello world: size = 11 OK 2026/01/28 03:23:34 DEBUG : sub dir/hello world: quickxor = 6828031bd8f00610dce10d726b03190000000000 OK 2026/01/28 03:23:34 INFO : sub dir/hello world: Copied (new) 2026/01/28 03:23:34 INFO : sub dir/hello world: Deleted 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:378 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:427 Error: Should be true Test: TestMoveEmptyDirectories Messages: sub dir2: Modification time difference too big |-123542h23m32.876543211s| > 1s (want 2011-12-25 12:59:59.123456789 +0000 UTC vs got 2026-01-28 03:23:32 +0000 UTC) (precision 1s) 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/fs/sync/sync_test.go:431 Error: Should be true Test: TestMoveEmptyDirectories Messages: sub dir: Modification time difference too big |-2.342241727s| > 1s (want 2026-01-28 03:23:31.657758273 +0000 UTC vs got 2026-01-28 03:23:34 +0000 UTC) (precision 1s) --- FAIL: TestMoveEmptyDirectories (5.78s) === RUN TestSyncEmptyDirectories run.go:185: Remote "OneDrive root 'rclone-test-gavuxus0tadu'", Local "Local file system at /tmp/rclone1550567162", Modify Window "1s" 2026/01/28 03:23:37 DEBUG : sub dir2: Making directory with metadata 2026/01/28 03:23:37 INFO : sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2026/01/28 03:23:37 INFO : sub dir: Set directory modification time (using DirSetModTime) 2026/01/28 03:23:37 DEBUG : sub dir2: Making directory with metadata 2026/01/28 03:23:38 INFO : sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2026/01/28 03:23:38 DEBUG : sub dir: Making directory with metadata 2026/01/28 03:23:39 INFO : sub dir: Made directory with metadata (mtime=2001-02-03T04:05:06.499999999Z) 2026/01/28 03:23:39 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2026/01/28 03:23:39 DEBUG : sub dir/hello world: Starting multipart upload 2026/01/28 03:23:39 DEBUG : OneDrive root 'rclone-test-gavuxus0tadu': Waiting for checks to finish 2026/01/28 03:23:39 DEBUG : OneDrive root 'rclone-test-gavuxus0tadu': Waiting for transfers to finish 2026/01/28 03:23:39 DEBUG : sub dir/hello world: Uploading segment 0/11 size 11 2026/01/28 03:23:40 DEBUG : sub dir/hello world: size = 11 OK 2026/01/28 03:23:40 DEBUG : sub dir/hello world: quickxor = 6828031bd8f00610dce10d726b03190000000000 OK 2026/01/28 03:23:40 INFO : sub dir/hello world: Copied (new) 2026/01/28 03:23:40 DEBUG : Waiting for deletions 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:378 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:528 Error: Should be true Test: TestSyncEmptyDirectories Messages: sub dir: Modification time difference too big |-218999h18m32.500000001s| > 1s (want 2001-02-03 04:05:06.499999999 +0000 UTC vs got 2026-01-28 03:23:39 +0000 UTC) (precision 1s) 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:378 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:528 Error: Should be true Test: TestSyncEmptyDirectories Messages: sub dir2: Modification time difference too big |-123542h23m38.876543211s| > 1s (want 2011-12-25 12:59:59.123456789 +0000 UTC vs got 2026-01-28 03:23:38 +0000 UTC) (precision 1s) --- FAIL: TestSyncEmptyDirectories (5.76s) === RUN TestSyncMultipleCompareDest run.go:185: Remote "OneDrive root 'rclone-test-gavuxus0tadu'", Local "Local file system at /tmp/rclone1550567162", Modify Window "1s" 2026/01/28 03:23:43 DEBUG : pre-dest1/1: Starting multipart upload 2026/01/28 03:23:44 DEBUG : pre-dest1/1: Uploading segment 0/1 size 1 2026/01/28 03:23:45 DEBUG : pre-dest2/2: Starting multipart upload 2026/01/28 03:23:46 DEBUG : pre-dest2/2: Uploading segment 0/1 size 1 2026/01/28 03:23:48 DEBUG : Creating backend with remote "TestOneDrive:rclone-test-gavuxus0tadu/dest" 2026/01/28 03:23:48 DEBUG : TestOneDrive: Set overridden config ["user_agent"] for backend startup 2026/01/28 03:23:49 DEBUG : Creating backend with remote "TestOneDrive:rclone-test-gavuxus0tadu/pre-dest1" 2026/01/28 03:23:49 DEBUG : TestOneDrive: Set overridden config ["user_agent"] for backend startup 2026/01/28 03:23:50 DEBUG : Creating backend with remote "TestOneDrive:rclone-test-gavuxus0tadu/pre-dest2" 2026/01/28 03:23:50 DEBUG : TestOneDrive: Set overridden config ["user_agent"] for backend startup 2026/01/28 03:23:52 DEBUG : 1: size = 1 OK 2026/01/28 03:23:52 DEBUG : 1: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2026/01/28 03:23:52 DEBUG : 1: Destination found in --compare-dest, skipping 2026/01/28 03:23:52 DEBUG : 2: size = 1 OK 2026/01/28 03:23:52 DEBUG : 2: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2026/01/28 03:23:52 DEBUG : 2: Destination found in --compare-dest, skipping 2026/01/28 03:23:53 DEBUG : 3: Need to transfer - File not found at Destination 2026/01/28 03:23:53 DEBUG : OneDrive root 'rclone-test-gavuxus0tadu/dest': Waiting for checks to finish 2026/01/28 03:23:53 DEBUG : OneDrive root 'rclone-test-gavuxus0tadu/dest': Waiting for transfers to finish 2026/01/28 03:23:53 DEBUG : 3: Starting multipart upload 2026/01/28 03:23:54 DEBUG : 3: Uploading segment 0/1 size 1 2026/01/28 03:23:55 DEBUG : 3: size = 1 OK 2026/01/28 03:23:55 DEBUG : 3: quickxor = 3300000000000000000000000100000000000000 OK 2026/01/28 03:23:55 INFO : 3: Copied (new) 2026/01/28 03:23:55 DEBUG : Waiting for deletions to finish --- PASS: TestSyncMultipleCompareDest (20.68s) === RUN TestNothingToTransferWithEmptyDirs run.go:185: Remote "OneDrive root 'rclone-test-gavuxus0tadu'", Local "Local file system at /tmp/rclone1550567162", Modify Window "1s" 2026/01/28 03:24:03 INFO : sub dir: Set directory modification time (using DirSetModTime) 2026/01/28 03:24:03 DEBUG : sub dir: Making directory with metadata 2026/01/28 03:24:05 INFO : sub dir: Made directory with metadata (mtime=2011-12-30T12:59:59Z) 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:378 /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 |-2.128959519s| > 1s (want 2026-01-28 03:24:03.871040481 +0000 UTC vs got 2026-01-28 03:24:06 +0000 UTC) (precision 1s) 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:378 /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 |-4.128959519s| > 1s (want 2026-01-28 03:24:03.871040481 +0000 UTC vs got 2026-01-28 03:24:08 +0000 UTC) (precision 1s) 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:378 /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 |-7.128959519s| > 1s (want 2026-01-28 03:24:03.871040481 +0000 UTC vs got 2026-01-28 03:24:11 +0000 UTC) (precision 1s) 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:378 /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 |-12.128959519s| > 1s (want 2026-01-28 03:24:03.871040481 +0000 UTC vs got 2026-01-28 03:24:16 +0000 UTC) (precision 1s) 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/01/28 03:24:25 INFO : sub dir2: Set directory modification time (using SetModTime) 2026/01/28 03:24:26 INFO : sub dir2/very: Set directory modification time (using SetModTime) 2026/01/28 03:24:27 INFO : sub dir2/very/very: Set directory modification time (using SetModTime) 2026/01/28 03:24:28 INFO : sub dir2/very/very/very: Set directory modification time (using SetModTime) 2026/01/28 03:24:30 INFO : sub dir2/very/very/very/very: Set directory modification time (using SetModTime) 2026/01/28 03:24:31 INFO : sub dir2/very/very/very/very/very: Set directory modification time (using SetModTime) 2026/01/28 03:24:32 INFO : sub dir2/very/very/very/very/very/nested: Set directory modification time (using SetModTime) 2026/01/28 03:24:33 INFO : sub dir2/very/very/very/very/very/nested/subdir: Set directory modification time (using SetModTime) 2026/01/28 03:24:33 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/01/28 03:24:25 INFO : sub dir2: Set directory modification time (using SetModTime) 2026/01/28 03:24:26 INFO : sub dir2/very: Set directory modification time (using SetModTime) 2026/01/28 03:24:27 INFO : sub dir2/very/very: Set directory modification time (using SetModTime) 2026/01/28 03:24:28 INFO : sub dir2/very/very/very: Set directory modification time (using SetModTime) 2026/01/28 03:24:30 INFO : sub dir2/very/very/very/very: Set directory modification time (using SetModTime) 2026/01/28 03:24:31 INFO : sub dir2/very/very/very/very/very: Set directory modification time (using SetModTime) 2026/01/28 03:24:32 INFO : sub dir2/very/very/very/very/very/nested: Set directory modification time (using SetModTime) 2026/01/28 03:24:33 INFO : sub dir2/very/very/very/very/very/nested/subdir: Set directory modification time (using SetModTime) 2026/01/28 03:24:33 INFO : There was nothing to transfer 2026/01/28 03:24:41 INFO : sub dir2: Set directory modification time (using DirSetModTime) 2026/01/28 03:24:41 INFO : sub dir2: Set directory modification time (using DirSetModTime) 2026/01/28 03:24:41 INFO : sub dirEmpty/sub dirEmpty2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2026/01/28 03:24:41 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:378 /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 |-3.635631025s| > 1s (want 2026-01-28 03:24:41.364368975 +0000 UTC vs got 2026-01-28 03:24:45 +0000 UTC) (precision 1s) 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:378 /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 |-123542h24m43.876543211s| > 1s (want 2011-12-25 12:59:59.123456789 +0000 UTC vs got 2026-01-28 03:24:43 +0000 UTC) (precision 1s) 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:378 /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 |-123542h24m44.876543211s| > 1s (want 2011-12-25 12:59:59.123456789 +0000 UTC vs got 2026-01-28 03:24:44 +0000 UTC) (precision 1s) --- FAIL: TestNothingToTransferWithEmptyDirs (72.10s) FAIL 2026/01/28 03:25:15 DEBUG : OneDrive root 'rclone-test-gavuxus0tadu': Purge remote "./sync.test -test.v -test.timeout 1h0m0s -remote TestOneDrive: -verbose -test.run '^(TestCopyEmptyDirectories|TestCopyMetadata|TestMoveEmptyDirectories|TestNothingToTransferWithEmptyDirs|TestSyncEmptyDirectories|TestSyncMultipleCompareDest)$'" - Finished ERROR in 2m0.59747113s (try 2/5): exit status 1: Failed [TestCopyMetadata TestCopyEmptyDirectories TestMoveEmptyDirectories TestSyncEmptyDirectories TestNothingToTransferWithEmptyDirs]