"./sync.test -test.v -test.timeout 1h0m0s -remote TestOneDrive: -verbose -test.run '^(TestCopyEmptyDirectories|TestCopyMetadata|TestMoveEmptyDirectories|TestNothingToTransferWithEmptyDirs|TestSyncEmptyDirectories)$'" - Starting (try 4/5) 2026/01/27 02:18:22 DEBUG : Creating backend with remote "TestOneDrive:rclone-test-soqupis5xeco" 2026/01/27 02:18:22 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/01/27 02:18:22 DEBUG : TestOneDrive: Set overridden config ["user_agent"] for backend startup 2026/01/27 02:18:52 ERROR : UnknownError: : upload chunks may be taking too long - try reducing --onedrive-chunk-size or decreasing --transfers 2026/01/27 02:18:52 DEBUG : pacer: low level retry 1/10 (error UnknownError: ) 2026/01/27 02:18:52 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2026/01/27 02:18:53 DEBUG : pacer: Reducing sleep to 15ms 2026/01/27 02:18:54 DEBUG : pacer: Reducing sleep to 11.25ms 2026/01/27 02:18:54 DEBUG : pacer: Reducing sleep to 10ms 2026/01/27 02:18:54 DEBUG : Creating backend with remote "/tmp/rclone436782441" === RUN TestCopyMetadata run.go:185: Remote "OneDrive root 'rclone-test-soqupis5xeco'", Local "Local file system at /tmp/rclone436782441", Modify Window "1s" 2026/01/27 02:18:54 DEBUG : metadata sub dir: Making directory with metadata 2026/01/27 02:18:54 INFO : metadata sub dir: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2026/01/27 02:18:54 DEBUG : empty metadata sub dir: Making directory with metadata 2026/01/27 02:18:54 INFO : empty metadata sub dir: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2026/01/27 02:18:54 DEBUG : Local file system at /tmp/rclone436782441: File to upload is small (21 bytes), uploading instead of streaming 2026/01/27 02:18:54 DEBUG : metadata sub dir/hello metadata world: size = 21 OK 2026/01/27 02:18:54 DEBUG : metadata sub dir/hello metadata world: md5 = baa7a013449dbba2b1ed02b5cfb4e1a2 OK 2026/01/27 02:18:54 DEBUG : metadata sub dir/hello metadata world: Size and md5 of src and dst objects identical 2026/01/27 02:18:54 INFO : metadata sub dir: Set directory modification time (using DirSetModTime) 2026/01/27 02:18:58 DEBUG : empty metadata sub dir: skipping unsupported metadata item: potato: king edward 2026/01/27 02:18:58 DEBUG : empty metadata sub dir: skipping unsupported metadata item: mode: 40775 2026/01/27 02:18:58 DEBUG : empty metadata sub dir: skipping unsupported metadata item: uid: 1000 2026/01/27 02:18:58 DEBUG : empty metadata sub dir: skipping unsupported metadata item: gid: 1000 2026/01/27 02:18:58 DEBUG : empty metadata sub dir: skipping unsupported metadata item: atime: 2011-12-25T12:59:59.123456789Z 2026/01/27 02:18:59 INFO : empty metadata sub dir: Updated directory metadata 2026/01/27 02:19:00 DEBUG : metadata sub dir: skipping unsupported metadata item: gid: 1000 2026/01/27 02:19:00 DEBUG : metadata sub dir: skipping unsupported metadata item: atime: 2011-12-25T12:59:59.123456789Z 2026/01/27 02:19:00 DEBUG : metadata sub dir: skipping unsupported metadata item: potato: king edward 2026/01/27 02:19:00 DEBUG : metadata sub dir: skipping unsupported metadata item: mode: 40775 2026/01/27 02:19:00 DEBUG : metadata sub dir: skipping unsupported metadata item: uid: 1000 2026/01/27 02:19:01 INFO : metadata sub dir: Updated directory metadata 2026/01/27 02:19:01 DEBUG : metadata sub dir/hello metadata world: Need to transfer - File not found at Destination 2026/01/27 02:19:01 DEBUG : metadata sub dir/hello metadata world: Starting multipart upload 2026/01/27 02:19:01 DEBUG : metadata sub dir/hello metadata world: skipping unsupported metadata item: potato: jersey 2026/01/27 02:19:01 DEBUG : metadata sub dir/hello metadata world: skipping unsupported metadata item: mode: 100664 2026/01/27 02:19:01 DEBUG : metadata sub dir/hello metadata world: skipping unsupported metadata item: uid: 1000 2026/01/27 02:19:01 DEBUG : metadata sub dir/hello metadata world: skipping unsupported metadata item: gid: 1000 2026/01/27 02:19:01 DEBUG : metadata sub dir/hello metadata world: skipping unsupported metadata item: atime: 2026-01-27T02:19:01.174296727Z 2026/01/27 02:19:01 DEBUG : OneDrive root 'rclone-test-soqupis5xeco': Waiting for checks to finish 2026/01/27 02:19:01 DEBUG : OneDrive root 'rclone-test-soqupis5xeco': Waiting for transfers to finish 2026/01/27 02:19:02 DEBUG : metadata sub dir/hello metadata world: Uploading segment 0/21 size 21 2026/01/27 02:19:02 DEBUG : metadata sub dir/hello metadata world: size = 21 OK 2026/01/27 02:19:02 DEBUG : metadata sub dir/hello metadata world: quickxor = 88266c8bdbebce00b6a10c741d0319c240873080 OK 2026/01/27 02:19:02 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 |-123517h19m1.876543211s| > 1s (want 2011-12-25 12:59:59.123456789 +0000 UTC vs got 2026-01-27 02:19:01 +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 |-123517h19m1.876543211s| > 1s (want 2011-12-25 12:59:59.123456789 +0000 UTC vs got 2026-01-27 02:19:01 +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 |-123517h18m59.876543211s| > 1s (want 2011-12-25 12:59:59.123456789 +0000 UTC vs got 2026-01-27 02:18:59 +0000 UTC) (precision 1s) --- FAIL: TestCopyMetadata (17.30s) === RUN TestCopyEmptyDirectories run.go:185: Remote "OneDrive root 'rclone-test-soqupis5xeco'", Local "Local file system at /tmp/rclone436782441", Modify Window "1s" 2026/01/27 02:19:12 DEBUG : sub dir2/sub sub dir2: Making directory with metadata 2026/01/27 02:19:12 INFO : sub dir2/sub sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2026/01/27 02:19:12 INFO : sub dir2: Set directory modification time (using DirSetModTime) 2026/01/27 02:19:12 INFO : sub dir: Set directory modification time (using DirSetModTime) 2026/01/27 02:19:12 DEBUG : sub dir2: Making directory with metadata 2026/01/27 02:19:42 DEBUG : pacer: low level retry 1/10 (error UnknownError: ) 2026/01/27 02:19:42 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2026/01/27 02:19:43 DEBUG : pacer: Reducing sleep to 15ms 2026/01/27 02:19:43 DEBUG : pacer: Reducing sleep to 11.25ms 2026/01/27 02:19:43 INFO : sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2026/01/27 02:19:43 DEBUG : sub dir: Making directory with metadata 2026/01/27 02:19:44 DEBUG : pacer: Reducing sleep to 10ms 2026/01/27 02:19:45 INFO : sub dir: Made directory with metadata (mtime=2001-02-03T04:05:06.499999999Z) 2026/01/27 02:19:45 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2026/01/27 02:19:45 DEBUG : sub dir/hello world: Starting multipart upload 2026/01/27 02:19:45 DEBUG : sub dir2/sub sub dir2: Making directory with metadata 2026/01/27 02:19:46 DEBUG : sub dir/hello world: Uploading segment 0/11 size 11 2026/01/27 02:19:46 DEBUG : sub dir/hello world: size = 11 OK 2026/01/27 02:19:46 DEBUG : sub dir/hello world: quickxor = 6828031bd8f00610dce10d726b03190000000000 OK 2026/01/27 02:19:46 INFO : sub dir/hello world: Copied (new) 2026/01/27 02:19:47 INFO : sub dir2/sub sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2026/01/27 02:19:47 DEBUG : OneDrive root 'rclone-test-soqupis5xeco': Waiting for checks to finish 2026/01/27 02:19:47 DEBUG : OneDrive root 'rclone-test-soqupis5xeco': 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: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 |-218974h14m39.500000001s| > 1s (want 2001-02-03 04:05:06.499999999 +0000 UTC vs got 2026-01-27 02:19:46 +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 |-123517h19m43.876543211s| > 1s (want 2011-12-25 12:59:59.123456789 +0000 UTC vs got 2026-01-27 02:19: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:371 Error: Should be true Test: TestCopyEmptyDirectories Messages: sub dir2/sub sub dir2: Modification time difference too big |-123517h19m47.876543211s| > 1s (want 2011-12-25 12:59:59.123456789 +0000 UTC vs got 2026-01-27 02:19:47 +0000 UTC) (precision 1s) --- FAIL: TestCopyEmptyDirectories (55.78s) === RUN TestMoveEmptyDirectories run.go:185: Remote "OneDrive root 'rclone-test-soqupis5xeco'", Local "Local file system at /tmp/rclone436782441", Modify Window "1s" 2026/01/27 02:20:07 DEBUG : sub dir2: Making directory with metadata 2026/01/27 02:20:07 INFO : sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2026/01/27 02:20:08 DEBUG : sub dir2: Making directory with metadata 2026/01/27 02:20:09 INFO : sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2026/01/27 02:20:09 DEBUG : sub dir: Making directory with metadata 2026/01/27 02:20:11 INFO : sub dir: Made directory with metadata (mtime=2026-01-27T02:20:07.99193763Z) 2026/01/27 02:20:11 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2026/01/27 02:20:11 DEBUG : sub dir/hello world: Starting multipart upload 2026/01/27 02:20:11 DEBUG : OneDrive root 'rclone-test-soqupis5xeco': Waiting for checks to finish 2026/01/27 02:20:11 DEBUG : OneDrive root 'rclone-test-soqupis5xeco': Waiting for transfers to finish 2026/01/27 02:20:12 DEBUG : sub dir/hello world: Uploading segment 0/11 size 11 2026/01/27 02:20:12 DEBUG : sub dir/hello world: size = 11 OK 2026/01/27 02:20:12 DEBUG : sub dir/hello world: quickxor = 6828031bd8f00610dce10d726b03190000000000 OK 2026/01/27 02:20:12 INFO : sub dir/hello world: Copied (new) 2026/01/27 02:20:12 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 |-123517h20m9.876543211s| > 1s (want 2011-12-25 12:59:59.123456789 +0000 UTC vs got 2026-01-27 02:20:09 +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 |-3.00806237s| > 1s (want 2026-01-27 02:20:07.99193763 +0000 UTC vs got 2026-01-27 02:20:11 +0000 UTC) (precision 1s) --- FAIL: TestMoveEmptyDirectories (11.22s) === RUN TestSyncEmptyDirectories run.go:185: Remote "OneDrive root 'rclone-test-soqupis5xeco'", Local "Local file system at /tmp/rclone436782441", Modify Window "1s" 2026/01/27 02:20:19 DEBUG : sub dir2: Making directory with metadata 2026/01/27 02:20:19 INFO : sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2026/01/27 02:20:19 INFO : sub dir: Set directory modification time (using DirSetModTime) 2026/01/27 02:20:19 DEBUG : sub dir2: Making directory with metadata 2026/01/27 02:20:20 INFO : sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2026/01/27 02:20:20 DEBUG : sub dir: Making directory with metadata 2026/01/27 02:20:22 INFO : sub dir: Made directory with metadata (mtime=2001-02-03T04:05:06.499999999Z) 2026/01/27 02:20:22 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2026/01/27 02:20:22 DEBUG : sub dir/hello world: Starting multipart upload 2026/01/27 02:20:22 DEBUG : OneDrive root 'rclone-test-soqupis5xeco': Waiting for checks to finish 2026/01/27 02:20:22 DEBUG : OneDrive root 'rclone-test-soqupis5xeco': Waiting for transfers to finish 2026/01/27 02:20:22 DEBUG : sub dir/hello world: Uploading segment 0/11 size 11 2026/01/27 02:20:23 DEBUG : sub dir/hello world: size = 11 OK 2026/01/27 02:20:23 DEBUG : sub dir/hello world: quickxor = 6828031bd8f00610dce10d726b03190000000000 OK 2026/01/27 02:20:23 INFO : sub dir/hello world: Copied (new) 2026/01/27 02:20:23 DEBUG : Waiting for deletions to finish 2026/01/27 02:20:54 DEBUG : pacer: low level retry 1/10 (error UnknownError: ) 2026/01/27 02:20:54 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2026/01/27 02:20:54 DEBUG : pacer: Reducing sleep to 15ms 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 |-218974h15m15.500000001s| > 1s (want 2001-02-03 04:05:06.499999999 +0000 UTC vs got 2026-01-27 02:20:22 +0000 UTC) (precision 1s) 2026/01/27 02:20:55 DEBUG : pacer: Reducing sleep to 11.25ms 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 |-123517h20m20.876543211s| > 1s (want 2011-12-25 12:59:59.123456789 +0000 UTC vs got 2026-01-27 02:20:20 +0000 UTC) (precision 1s) 2026/01/27 02:20:56 DEBUG : pacer: Reducing sleep to 10ms --- FAIL: TestSyncEmptyDirectories (42.05s) === RUN TestNothingToTransferWithEmptyDirs run.go:185: Remote "OneDrive root 'rclone-test-soqupis5xeco'", Local "Local file system at /tmp/rclone436782441", Modify Window "1s" 2026/01/27 02:21:01 INFO : sub dir: Set directory modification time (using DirSetModTime) 2026/01/27 02:21:01 DEBUG : sub dir: Making directory with metadata 2026/01/27 02:21:02 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 |-3.731552429s| > 1s (want 2026-01-27 02:21:01.268447571 +0000 UTC vs got 2026-01-27 02:21:05 +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 |-5.731552429s| > 1s (want 2026-01-27 02:21:01.268447571 +0000 UTC vs got 2026-01-27 02:21:07 +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 |-5.731552429s| > 1s (want 2026-01-27 02:21:01.268447571 +0000 UTC vs got 2026-01-27 02:21:07 +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 |-11.731552429s| > 1s (want 2026-01-27 02:21:01.268447571 +0000 UTC vs got 2026-01-27 02:21:13 +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/27 02:21:22 INFO : sub dir2: Set directory modification time (using SetModTime) 2026/01/27 02:21:24 INFO : sub dir2/very: Set directory modification time (using SetModTime) 2026/01/27 02:21:25 INFO : sub dir2/very/very: Set directory modification time (using SetModTime) 2026/01/27 02:21:26 INFO : sub dir2/very/very/very: Set directory modification time (using SetModTime) 2026/01/27 02:21:27 INFO : sub dir2/very/very/very/very: Set directory modification time (using SetModTime) 2026/01/27 02:21:28 INFO : sub dir2/very/very/very/very/very: Set directory modification time (using SetModTime) 2026/01/27 02:21:30 INFO : sub dir2/very/very/very/very/very/nested: Set directory modification time (using SetModTime) 2026/01/27 02:21:31 INFO : sub dir2/very/very/very/very/very/nested/subdir: Set directory modification time (using SetModTime) 2026/01/27 02:21:31 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/27 02:21:22 INFO : sub dir2: Set directory modification time (using SetModTime) 2026/01/27 02:21:24 INFO : sub dir2/very: Set directory modification time (using SetModTime) 2026/01/27 02:21:25 INFO : sub dir2/very/very: Set directory modification time (using SetModTime) 2026/01/27 02:21:26 INFO : sub dir2/very/very/very: Set directory modification time (using SetModTime) 2026/01/27 02:21:27 INFO : sub dir2/very/very/very/very: Set directory modification time (using SetModTime) 2026/01/27 02:21:28 INFO : sub dir2/very/very/very/very/very: Set directory modification time (using SetModTime) 2026/01/27 02:21:30 INFO : sub dir2/very/very/very/very/very/nested: Set directory modification time (using SetModTime) 2026/01/27 02:21:31 INFO : sub dir2/very/very/very/very/very/nested/subdir: Set directory modification time (using SetModTime) 2026/01/27 02:21:31 INFO : There was nothing to transfer 2026/01/27 02:21:38 INFO : sub dir2: Set directory modification time (using DirSetModTime) 2026/01/27 02:21:38 INFO : sub dir2: Set directory modification time (using DirSetModTime) 2026/01/27 02:21:38 INFO : sub dirEmpty/sub dirEmpty2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2026/01/27 02:21:38 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 |-4.739198851s| > 1s (want 2026-01-27 02:21:38.260801149 +0000 UTC vs got 2026-01-27 02:21: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: Modification time difference too big |-123517h21m40.876543211s| > 1s (want 2011-12-25 12:59:59.123456789 +0000 UTC vs got 2026-01-27 02:21:40 +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 |-123517h21m41.876543211s| > 1s (want 2011-12-25 12:59:59.123456789 +0000 UTC vs got 2026-01-27 02:21:41 +0000 UTC) (precision 1s) 2026/01/27 02:22:09 DEBUG : TestOneDrive: Token expired 2026/01/27 02:22:09 DEBUG : Config file has changed externally - reloading 2026/01/27 02:22:09 DEBUG : TestOneDrive: No updated token found in the config file 2026/01/27 02:22:09 DEBUG : TestOneDrive: Token refresh successful 2026/01/27 02:22:09 DEBUG : Saving config "token" in section "TestOneDrive" of the config file 2026/01/27 02:22:09 DEBUG : TestOneDrive: Saved new token in config file --- FAIL: TestNothingToTransferWithEmptyDirs (74.18s) FAIL 2026/01/27 02:22:15 DEBUG : OneDrive root 'rclone-test-soqupis5xeco': Purge remote "./sync.test -test.v -test.timeout 1h0m0s -remote TestOneDrive: -verbose -test.run '^(TestCopyEmptyDirectories|TestCopyMetadata|TestMoveEmptyDirectories|TestNothingToTransferWithEmptyDirs|TestSyncEmptyDirectories)$'" - Finished ERROR in 3m53.32214569s (try 4/5): exit status 1: Failed [TestCopyMetadata TestCopyEmptyDirectories TestMoveEmptyDirectories TestSyncEmptyDirectories TestNothingToTransferWithEmptyDirs]