"./vfs.test -test.v -test.timeout 1h0m0s -remote TestOpenDrive: -verbose -test.run '^(TestDirCreate|TestDirHandleMethods|TestDirMkdir|TestDirRemoveName)$|^TestFileSetModTime$/^(cache=off,open=false,write=false|cache=off,open=true,write=true)$'" - Starting (try 2/5) 2025/06/20 03:38:21 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-bukituw5nagi" 2025/06/20 03:38:21 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/06/20 03:38:22 DEBUG : Starting OpenDrive session with ID: 70f96b612ed5040e75f8d4995c2924f8e0b9cd26fde10b3bb394d7bc4b9210fe 2025/06/20 03:38:22 DEBUG : Creating backend with remote "/tmp/rclone1700775237" === RUN TestDirHandleMethods run.go:180: Remote "OpenDrive root 'rclone-test-bukituw5nagi'", Local "Local file system at /tmp/rclone1700775237", Modify Window "1s" 2025/06/20 03:38:22 INFO : OpenDrive root 'rclone-test-bukituw5nagi': poll-interval is not supported by this remote 2025/06/20 03:38:22 NOTICE: OpenDrive root 'rclone-test-bukituw5nagi': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2025/06/20 03:38:24 DEBUG : dir/file1: Uploading chunk 0, size=14, remain=0 2025/06/20 03:38:25 DEBUG : WaitForWriters: timeout=30s 2025/06/20 03:38:25 DEBUG : dir: Looking for writers 2025/06/20 03:38:25 DEBUG : Looking for writers 2025/06/20 03:38:25 DEBUG : dir: reading active writers 2025/06/20 03:38:25 DEBUG : >WaitForWriters: run.go:125: removing dir "dir" failed - try 1/3: folder not empty --- PASS: TestDirHandleMethods (5.22s) === RUN TestDirCreate run.go:180: Remote "OpenDrive root 'rclone-test-bukituw5nagi'", Local "Local file system at /tmp/rclone1700775237", Modify Window "1s" 2025/06/20 03:38:27 INFO : OpenDrive root 'rclone-test-bukituw5nagi': poll-interval is not supported by this remote 2025/06/20 03:38:27 NOTICE: OpenDrive root 'rclone-test-bukituw5nagi': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2025/06/20 03:38:29 DEBUG : dir/file1: Uploading chunk 0, size=14, remain=0 dir_test.go:354: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/vfs/dir_test.go:354 Error: Should be true Test: TestDirCreate 2025/06/20 03:38:30 DEBUG : dir/potato: Open: flags=O_WRONLY|O_CREATE 2025/06/20 03:38:30 DEBUG : dir: Added virtual directory entry vAddFile: "potato" 2025/06/20 03:38:30 DEBUG : dir/potato: >Open: fd=dir/potato (w), err= 2025/06/20 03:38:30 DEBUG : dir: Added virtual directory entry vAddFile: "potato" 2025/06/20 03:38:30 DEBUG : OpenDrive root 'rclone-test-bukituw5nagi': File to upload is small (5 bytes), uploading instead of streaming 2025/06/20 03:38:31 DEBUG : dir/potato: Uploading chunk 0, size=5, remain=0 2025/06/20 03:38:32 DEBUG : dir/potato: md5 = 5d41402abc4b2a76b9719d911017c592 OK 2025/06/20 03:38:32 DEBUG : dir/potato: Size and md5 of src and dst objects identical 2025/06/20 03:38:32 DEBUG : dir: Added virtual directory entry vAddFile: "potato" 2025/06/20 03:38:32 DEBUG : WaitForWriters: timeout=30s 2025/06/20 03:38:32 DEBUG : dir: Looking for writers 2025/06/20 03:38:32 DEBUG : potato: reading active writers 2025/06/20 03:38:32 DEBUG : file1: reading active writers 2025/06/20 03:38:32 DEBUG : Looking for writers 2025/06/20 03:38:32 DEBUG : dir: reading active writers 2025/06/20 03:38:32 DEBUG : >WaitForWriters: --- FAIL: TestDirCreate (5.87s) === RUN TestDirMkdir run.go:180: Remote "OpenDrive root 'rclone-test-bukituw5nagi'", Local "Local file system at /tmp/rclone1700775237", Modify Window "1s" 2025/06/20 03:38:33 INFO : OpenDrive root 'rclone-test-bukituw5nagi': poll-interval is not supported by this remote 2025/06/20 03:38:33 NOTICE: OpenDrive root 'rclone-test-bukituw5nagi': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2025/06/20 03:38:34 DEBUG : dir/file1: Uploading chunk 0, size=14, remain=0 2025/06/20 03:38:36 DEBUG : dir: Added virtual directory entry vAddDir: "sub" dir_test.go:398: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/vfs/dir_test.go:398 Error: Should be true Test: TestDirMkdir 2025/06/20 03:38:37 DEBUG : WaitForWriters: timeout=30s 2025/06/20 03:38:37 DEBUG : dir/sub: Looking for writers 2025/06/20 03:38:37 DEBUG : dir: Looking for writers 2025/06/20 03:38:37 DEBUG : file1: reading active writers 2025/06/20 03:38:37 DEBUG : sub: reading active writers 2025/06/20 03:38:37 DEBUG : Looking for writers 2025/06/20 03:38:37 DEBUG : dir: reading active writers 2025/06/20 03:38:37 DEBUG : >WaitForWriters: --- FAIL: TestDirMkdir (5.50s) === RUN TestDirRemoveName run.go:180: Remote "OpenDrive root 'rclone-test-bukituw5nagi'", Local "Local file system at /tmp/rclone1700775237", Modify Window "1s" 2025/06/20 03:38:39 INFO : OpenDrive root 'rclone-test-bukituw5nagi': poll-interval is not supported by this remote 2025/06/20 03:38:39 NOTICE: OpenDrive root 'rclone-test-bukituw5nagi': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2025/06/20 03:38:40 DEBUG : dir/file1: Uploading chunk 0, size=14, remain=0 2025/06/20 03:38:42 DEBUG : dir/file1: Remove: 2025/06/20 03:38:42 DEBUG : dir: Added virtual directory entry vDel: "file1" 2025/06/20 03:38:42 DEBUG : dir/file1: >Remove: err= 2025/06/20 03:38:42 DEBUG : WaitForWriters: timeout=30s 2025/06/20 03:38:42 DEBUG : dir: Looking for writers 2025/06/20 03:38:42 DEBUG : Looking for writers 2025/06/20 03:38:42 DEBUG : dir: reading active writers 2025/06/20 03:38:42 DEBUG : >WaitForWriters: --- PASS: TestDirRemoveName (4.38s) === RUN TestFileSetModTime === RUN TestFileSetModTime/cache=off,open=false,write=false run.go:180: Remote "OpenDrive root 'rclone-test-bukituw5nagi'", Local "Local file system at /tmp/rclone1700775237", Modify Window "1s" 2025/06/20 03:38:43 INFO : OpenDrive root 'rclone-test-bukituw5nagi': poll-interval is not supported by this remote 2025/06/20 03:38:43 NOTICE: OpenDrive root 'rclone-test-bukituw5nagi': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2025/06/20 03:38:44 DEBUG : dir/file1: Uploading chunk 0, size=14, remain=0 2025/06/20 03:38:47 DEBUG : time_test: Uploading chunk 0, size=5, remain=0 2025/06/20 03:38:48 DEBUG : Can set mod time: true 2025/06/20 03:38:49 DEBUG : dir/file1: Applied pending mod time 2011-12-25 12:59:59.123456789 +0000 UTC OK fstest.go:124: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:124 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:129 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:148 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:192 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:335 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:347 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:340 /home/rclone/go/src/github.com/rclone/rclone/vfs/file_test.go:139 /home/rclone/go/src/github.com/rclone/rclone/vfs/file_test.go:159 Error: Should be true Test: TestFileSetModTime/cache=off,open=false,write=false Messages: dir/file1: Modification time difference too big |95456h54m53.123456789s| > 1s (want 2011-12-25 12:59:59.123456789 +0000 UTC vs got 2001-02-03 04:05:06 +0000 UTC) (precision 1s) 2025/06/20 03:38:49 DEBUG : WaitForWriters: timeout=30s 2025/06/20 03:38:49 DEBUG : dir: Looking for writers 2025/06/20 03:38:49 DEBUG : file1: reading active writers 2025/06/20 03:38:49 DEBUG : Looking for writers 2025/06/20 03:38:49 DEBUG : dir: reading active writers 2025/06/20 03:38:49 DEBUG : >WaitForWriters: run.go:125: removing dir "dir" failed - try 1/3: folder not empty === RUN TestFileSetModTime/cache=off,open=true,write=true run.go:180: Remote "OpenDrive root 'rclone-test-bukituw5nagi'", Local "Local file system at /tmp/rclone1700775237", Modify Window "1s" 2025/06/20 03:38:51 INFO : OpenDrive root 'rclone-test-bukituw5nagi': poll-interval is not supported by this remote 2025/06/20 03:38:51 NOTICE: OpenDrive root 'rclone-test-bukituw5nagi': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2025/06/20 03:38:52 DEBUG : dir/file1: Uploading chunk 0, size=14, remain=0 2025/06/20 03:38:54 DEBUG : dir/file1: Open: flags=O_WRONLY|O_TRUNC 2025/06/20 03:38:54 DEBUG : dir/file1: >Open: fd=dir/file1 (w), err= 2025/06/20 03:38:54 DEBUG : dir: Added virtual directory entry vAddFile: "file1" 2025/06/20 03:38:54 DEBUG : OpenDrive root 'rclone-test-bukituw5nagi': File to upload is small (5 bytes), uploading instead of streaming 2025/06/20 03:38:54 DEBUG : dir/file1: Uploading chunk 0, size=5, remain=0 2025/06/20 03:38:55 DEBUG : dir/file1: md5 = 5d41402abc4b2a76b9719d911017c592 OK 2025/06/20 03:38:55 DEBUG : dir/file1: Size and md5 of src and dst objects identical 2025/06/20 03:38:55 DEBUG : dir/file1: Applied pending mod time 2011-12-25 12:59:59.123456789 +0000 UTC OK 2025/06/20 03:38:55 DEBUG : dir: Added virtual directory entry vAddFile: "file1" 2025/06/20 03:38:55 DEBUG : WaitForWriters: timeout=30s 2025/06/20 03:38:55 DEBUG : dir: Looking for writers 2025/06/20 03:38:55 DEBUG : file1: reading active writers 2025/06/20 03:38:55 DEBUG : Looking for writers 2025/06/20 03:38:55 DEBUG : dir: reading active writers 2025/06/20 03:38:55 DEBUG : >WaitForWriters: 2025/06/20 03:38:56 DEBUG : WaitForWriters: timeout=30s 2025/06/20 03:38:56 DEBUG : dir: Looking for writers 2025/06/20 03:38:56 DEBUG : file1: reading active writers 2025/06/20 03:38:56 DEBUG : Looking for writers 2025/06/20 03:38:56 DEBUG : dir: reading active writers 2025/06/20 03:38:56 DEBUG : >WaitForWriters: --- FAIL: TestFileSetModTime (13.59s) --- FAIL: TestFileSetModTime/cache=off,open=false,write=false (7.93s) --- PASS: TestFileSetModTime/cache=off,open=true,write=true (5.66s) FAIL 2025/06/20 03:38:57 DEBUG : OpenDrive root 'rclone-test-bukituw5nagi': Purge remote "./vfs.test -test.v -test.timeout 1h0m0s -remote TestOpenDrive: -verbose -test.run '^(TestDirCreate|TestDirHandleMethods|TestDirMkdir|TestDirRemoveName)$|^TestFileSetModTime$/^(cache=off,open=false,write=false|cache=off,open=true,write=true)$'" - Finished ERROR in 35.870866151s (try 2/5): exit status 1: Failed [TestDirCreate TestDirMkdir TestFileSetModTime/cache=off,open=false,write=false]