"./vfs.test -test.v -test.timeout 1h0m0s -remote TestOpenDrive: -verbose -test.run '^(TestDirCreate|TestDirMkdir|TestFileReadAtNonZeroLength|TestVFSMkdirAll)$|^TestFileSetModTime$/^cache=full,open=true,write=false$'" - Starting (try 2/5) 2025/12/26 01:43:45 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-pecexis3xowa" 2025/12/26 01:43:45 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/12/26 01:43:45 DEBUG : Starting OpenDrive session with ID: 155a19b42b2069045cabededf73f7876374e3d0bbb125353f1c1ea03ad27b4a7 2025/12/26 01:43:45 DEBUG : Creating backend with remote "/tmp/rclone3964098998" === RUN TestDirCreate run.go:185: Remote "OpenDrive root 'rclone-test-pecexis3xowa'", Local "Local file system at /tmp/rclone3964098998", Modify Window "1s" 2025/12/26 01:43:45 INFO : OpenDrive root 'rclone-test-pecexis3xowa': poll-interval is not supported by this remote 2025/12/26 01:43:45 NOTICE: OpenDrive root 'rclone-test-pecexis3xowa': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2025/12/26 01:43:47 DEBUG : dir/file1: Uploading chunk 0, size=14, remain=0 2025/12/26 01:43:49 DEBUG : dir/potato: Open: flags=O_WRONLY|O_CREATE 2025/12/26 01:43:49 DEBUG : dir: Added virtual directory entry vAddFile: "potato" 2025/12/26 01:43:49 DEBUG : dir/potato: >Open: fd=dir/potato (w), err= 2025/12/26 01:43:49 DEBUG : dir: Added virtual directory entry vAddFile: "potato" 2025/12/26 01:43:49 DEBUG : OpenDrive root 'rclone-test-pecexis3xowa': File to upload is small (5 bytes), uploading instead of streaming 2025/12/26 01:43:50 DEBUG : dir/potato: Uploading chunk 0, size=5, remain=0 2025/12/26 01:43:50 DEBUG : dir/potato: size = 5 OK 2025/12/26 01:43:50 DEBUG : dir/potato: md5 = 5d41402abc4b2a76b9719d911017c592 OK 2025/12/26 01:43:50 DEBUG : dir/potato: Size and md5 of src and dst objects identical 2025/12/26 01:43:50 DEBUG : dir: Added virtual directory entry vAddFile: "potato" 2025/12/26 01:43:50 DEBUG : WaitForWriters: timeout=30s 2025/12/26 01:43:50 DEBUG : dir: Looking for writers 2025/12/26 01:43:50 DEBUG : file1: reading active writers 2025/12/26 01:43:50 DEBUG : potato: reading active writers 2025/12/26 01:43:50 DEBUG : Looking for writers 2025/12/26 01:43:50 DEBUG : dir: reading active writers 2025/12/26 01:43:50 DEBUG : >WaitForWriters: --- PASS: TestDirCreate (6.29s) === RUN TestDirMkdir run.go:185: Remote "OpenDrive root 'rclone-test-pecexis3xowa'", Local "Local file system at /tmp/rclone3964098998", Modify Window "1s" 2025/12/26 01:43:52 INFO : OpenDrive root 'rclone-test-pecexis3xowa': poll-interval is not supported by this remote 2025/12/26 01:43:52 NOTICE: OpenDrive root 'rclone-test-pecexis3xowa': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2025/12/26 01:43:53 DEBUG : dir/file1: Uploading chunk 0, size=14, remain=0 2025/12/26 01:43:55 DEBUG : dir: Added virtual directory entry vAddDir: "sub" 2025/12/26 01:43:55 DEBUG : dir/sub: Dir.ReadDirAll error: failed to get folder list: Directory doesn't exist (Error 404) dir_test.go:246: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/vfs/dir_test.go:246 /home/rclone/go/src/github.com/rclone/rclone/vfs/dir_test.go:402 Error: Received unexpected error: failed to get folder list: Directory doesn't exist (Error 404) Test: TestDirMkdir 2025/12/26 01:43:55 DEBUG : WaitForWriters: timeout=30s 2025/12/26 01:43:55 DEBUG : dir/sub: Looking for writers 2025/12/26 01:43:55 DEBUG : dir: Looking for writers 2025/12/26 01:43:55 DEBUG : file1: reading active writers 2025/12/26 01:43:55 DEBUG : sub: reading active writers 2025/12/26 01:43:55 DEBUG : Looking for writers 2025/12/26 01:43:55 DEBUG : dir: reading active writers 2025/12/26 01:43:55 DEBUG : >WaitForWriters: --- FAIL: TestDirMkdir (4.67s) === RUN TestFileSetModTime === RUN TestFileSetModTime/cache=full,open=true,write=false run.go:185: Remote "OpenDrive root 'rclone-test-pecexis3xowa'", Local "Local file system at /tmp/rclone3964098998", Modify Window "1s" 2025/12/26 01:43:56 INFO : OpenDrive root 'rclone-test-pecexis3xowa': poll-interval is not supported by this remote 2025/12/26 01:43:56 DEBUG : OpenDrive root 'rclone-test-pecexis3xowa': vfs cache: root is "/home/rclone/.cache/rclone" 2025/12/26 01:43:56 DEBUG : OpenDrive root 'rclone-test-pecexis3xowa': vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestOpenDrive/rclone-test-pecexis3xowa" 2025/12/26 01:43:56 DEBUG : OpenDrive root 'rclone-test-pecexis3xowa': vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestOpenDrive/rclone-test-pecexis3xowa" 2025/12/26 01:43:56 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestOpenDrive/rclone-test-pecexis3xowa" 2025/12/26 01:43:56 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2025/12/26 01:43:56 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestOpenDrive/rclone-test-pecexis3xowa" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfs/TestOpenDrive/rclone-test-pecexis3xowa" 2025/12/26 01:43:56 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestOpenDrive/rclone-test-pecexis3xowa" 2025/12/26 01:43:56 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2025/12/26 01:43:56 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestOpenDrive/rclone-test-pecexis3xowa" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfsMeta/TestOpenDrive/rclone-test-pecexis3xowa" 2025/12/26 01:43:56 INFO : OpenDrive root 'rclone-test-pecexis3xowa': vfs cache: cleaned: objects 0 (was 0) in use 0, to upload 0, uploading 0, total size 0 (was 0) 2025/12/26 01:43:58 DEBUG : dir/file1: Uploading chunk 0, size=14, remain=0 2025/12/26 01:44:00 DEBUG : time_test: Uploading chunk 0, size=5, remain=0 2025/12/26 01:44:02 DEBUG : Can set mod time: true 2025/12/26 01:44:02 DEBUG : dir/file1: Open: flags=O_WRONLY 2025/12/26 01:44:02 DEBUG : dir/file1: newRWFileHandle: 2025/12/26 01:44:02 DEBUG : dir/file1: >newRWFileHandle: err= 2025/12/26 01:44:02 DEBUG : dir/file1: >Open: fd=dir/file1 (rw), err= 2025/12/26 01:44:02 DEBUG : dir/file1(0xc000812c00): close: 2025/12/26 01:44:02 DEBUG : dir/file1: Applied pending mod time 2011-12-25 12:59:59.123456789 +0000 UTC OK 2025/12/26 01:44:02 DEBUG : dir/file1(0xc000812c00): >close: err= 2025/12/26 01:44:02 DEBUG : WaitForWriters: timeout=30s 2025/12/26 01:44:02 DEBUG : dir: Looking for writers 2025/12/26 01:44:02 DEBUG : file1: reading active writers 2025/12/26 01:44:02 DEBUG : Looking for writers 2025/12/26 01:44:02 DEBUG : dir: reading active writers 2025/12/26 01:44:02 DEBUG : >WaitForWriters: 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:345 /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=full,open=true,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/12/26 01:44:02 DEBUG : WaitForWriters: timeout=30s 2025/12/26 01:44:02 DEBUG : dir: Looking for writers 2025/12/26 01:44:02 DEBUG : file1: reading active writers 2025/12/26 01:44:02 DEBUG : Looking for writers 2025/12/26 01:44:02 DEBUG : dir: reading active writers 2025/12/26 01:44:02 DEBUG : >WaitForWriters: 2025/12/26 01:44:02 DEBUG : OpenDrive root 'rclone-test-pecexis3xowa': vfs cache: cleaner exiting --- FAIL: TestFileSetModTime (6.92s) --- FAIL: TestFileSetModTime/cache=full,open=true,write=false (6.92s) === RUN TestVFSMkdirAll run.go:185: Remote "OpenDrive root 'rclone-test-pecexis3xowa'", Local "Local file system at /tmp/rclone3964098998", Modify Window "1s" 2025/12/26 01:44:03 INFO : OpenDrive root 'rclone-test-pecexis3xowa': poll-interval is not supported by this remote 2025/12/26 01:44:03 NOTICE: OpenDrive root 'rclone-test-pecexis3xowa': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2025/12/26 01:44:04 DEBUG : Added virtual directory entry vAddDir: "a" 2025/12/26 01:44:05 DEBUG : a: Added virtual directory entry vAddDir: "b" 2025/12/26 01:44:05 ERROR : a/b/: Dir.Mkdir failed to read directory: failed to get folder list: Directory doesn't exist (Error 404) vfs_test.go:425: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/vfs/vfs_test.go:425 Error: Received unexpected error: failed to get folder list: Directory doesn't exist (Error 404) Test: TestVFSMkdirAll 2025/12/26 01:44:05 DEBUG : WaitForWriters: timeout=30s 2025/12/26 01:44:05 DEBUG : a/b: Looking for writers 2025/12/26 01:44:05 DEBUG : a: Looking for writers 2025/12/26 01:44:05 DEBUG : b: reading active writers 2025/12/26 01:44:05 DEBUG : Looking for writers 2025/12/26 01:44:05 DEBUG : a: reading active writers 2025/12/26 01:44:05 DEBUG : >WaitForWriters: fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:292: Sleeping for 6s just to make sure --- FAIL: TestVFSMkdirAll (10.00s) === RUN TestFileReadAtNonZeroLength run.go:185: Remote "OpenDrive root 'rclone-test-pecexis3xowa'", Local "Local file system at /tmp/rclone3964098998", Modify Window "1s" 2025/12/26 01:44:13 INFO : OpenDrive root 'rclone-test-pecexis3xowa': poll-interval is not supported by this remote 2025/12/26 01:44:13 NOTICE: OpenDrive root 'rclone-test-pecexis3xowa': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2025/12/26 01:44:13 DEBUG : file1: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2025/12/26 01:44:13 DEBUG : file1: Open: flags=O_WRONLY|O_CREATE 2025/12/26 01:44:13 DEBUG : Added virtual directory entry vAddFile: "file1" 2025/12/26 01:44:13 DEBUG : file1: >Open: fd=file1 (w), err= 2025/12/26 01:44:13 DEBUG : file1: >OpenFile: fd=file1 (w), err= 2025/12/26 01:44:13 DEBUG : Added virtual directory entry vAddFile: "file1" 2025/12/26 01:44:13 DEBUG : OpenDrive root 'rclone-test-pecexis3xowa': File to upload is small (100 bytes), uploading instead of streaming 2025/12/26 01:44:14 DEBUG : file1: Uploading chunk 0, size=100, remain=0 2025/12/26 01:44:15 DEBUG : file1: size = 100 OK 2025/12/26 01:44:15 DEBUG : file1: md5 = 3d00774471e86ca96fcad2f04cfa10a5 OK 2025/12/26 01:44:15 DEBUG : file1: Size and md5 of src and dst objects identical 2025/12/26 01:44:15 DEBUG : Added virtual directory entry vAddFile: "file1" 2025/12/26 01:44:15 DEBUG : file1: OpenFile: flags=O_RDONLY, perm=---------- 2025/12/26 01:44:15 DEBUG : file1: Open: flags=O_RDONLY 2025/12/26 01:44:15 DEBUG : file1: >Open: fd=file1 (r), err= 2025/12/26 01:44:15 DEBUG : file1: >OpenFile: fd=file1 (r), err= 2025/12/26 01:44:15 DEBUG : file1: ChunkedReader.openRange at 0 length 134217728 write_test.go:370: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/vfs/write_test.go:370 /home/rclone/go/src/github.com/rclone/rclone/vfs/write_test.go:384 Error: Received unexpected error: failed to open file): File does not exist (Error 404) Test: TestFileReadAtNonZeroLength write_test.go:372: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/vfs/write_test.go:372 /home/rclone/go/src/github.com/rclone/rclone/vfs/write_test.go:384 Error: Not equal: expected: 0 actual : 100 Test: TestFileReadAtNonZeroLength write_test.go:373: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/vfs/write_test.go:373 /home/rclone/go/src/github.com/rclone/rclone/vfs/write_test.go:384 Error: Not equal: expected: []byte{0x74, 0x6f, 0x6d, 0x69, 0x63, 0x69, 0x72, 0x30, 0x6c, 0x61, 0x67, 0x65, 0x77, 0x69, 0x6d, 0x34, 0x78, 0x65, 0x78, 0x61, 0x6a, 0x6f, 0x66, 0x38, 0x74, 0x6f, 0x6a, 0x65, 0x76, 0x6f, 0x67, 0x36, 0x74, 0x75, 0x77, 0x61, 0x6c, 0x65, 0x7a, 0x34, 0x6b, 0x75, 0x6d, 0x6f, 0x79, 0x65, 0x6a, 0x32, 0x78, 0x61, 0x66, 0x61, 0x72, 0x75, 0x64, 0x39, 0x74, 0x6f, 0x67, 0x69, 0x64, 0x61, 0x67, 0x30, 0x73, 0x65, 0x6c, 0x69, 0x62, 0x69, 0x64, 0x31, 0x68, 0x6f, 0x62, 0x75, 0x76, 0x6f, 0x76, 0x34, 0x6a, 0x75, 0x64, 0x61, 0x63, 0x61, 0x6a, 0x31, 0x62, 0x75, 0x6d, 0x61, 0x6a, 0x61, 0x73, 0x36, 0x71, 0x65, 0x78, 0x75} actual : []byte{} Diff: --- Expected +++ Actual @@ -1,9 +1,2 @@ -([]uint8) (len=100) { - 00000000 74 6f 6d 69 63 69 72 30 6c 61 67 65 77 69 6d 34 |tomicir0lagewim4| - 00000010 78 65 78 61 6a 6f 66 38 74 6f 6a 65 76 6f 67 36 |xexajof8tojevog6| - 00000020 74 75 77 61 6c 65 7a 34 6b 75 6d 6f 79 65 6a 32 |tuwalez4kumoyej2| - 00000030 78 61 66 61 72 75 64 39 74 6f 67 69 64 61 67 30 |xafarud9togidag0| - 00000040 73 65 6c 69 62 69 64 31 68 6f 62 75 76 6f 76 34 |selibid1hobuvov4| - 00000050 6a 75 64 61 63 61 6a 31 62 75 6d 61 6a 61 73 36 |judacaj1bumajas6| - 00000060 71 65 78 75 |qexu| +([]uint8) { } Test: TestFileReadAtNonZeroLength 2025/12/26 01:44:16 DEBUG : WaitForWriters: timeout=30s 2025/12/26 01:44:16 DEBUG : Looking for writers 2025/12/26 01:44:16 DEBUG : file1: reading active writers 2025/12/26 01:44:16 DEBUG : >WaitForWriters: --- FAIL: TestFileReadAtNonZeroLength (2.75s) FAIL 2025/12/26 01:44:16 DEBUG : OpenDrive root 'rclone-test-pecexis3xowa': Purge remote "./vfs.test -test.v -test.timeout 1h0m0s -remote TestOpenDrive: -verbose -test.run '^(TestDirCreate|TestDirMkdir|TestFileReadAtNonZeroLength|TestVFSMkdirAll)$|^TestFileSetModTime$/^cache=full,open=true,write=false$'" - Finished ERROR in 31.921019644s (try 2/5): exit status 1: Failed [TestDirMkdir TestFileSetModTime/cache=full,open=true,write=false TestVFSMkdirAll TestFileReadAtNonZeroLength]