"./vfs.test -test.v -test.timeout 1h0m0s -remote TestDropbox: -verbose -test.run '^(TestDirForgetAll|TestDirForgetPath|TestDirHandleReaddir|TestDirWalk|TestUnicodeNormalization|TestVFSOpenFile)$'" - Starting (try 2/5)
2025/07/08 04:17:31 DEBUG : Creating backend with remote "TestDropbox:rclone-test-taqisuv1suba"
2025/07/08 04:17:31 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/07/08 04:17:31 DEBUG : Creating backend with remote "/tmp/rclone4279316862"
=== RUN   TestDirHandleReaddir
    run.go:180: Remote "Dropbox root 'rclone-test-taqisuv1suba'", Local "Local file system at /tmp/rclone4279316862", Modify Window "1s"
2025/07/08 04:17:31 INFO  : Dropbox root 'rclone-test-taqisuv1suba': Failed to get StartCursor: path/not_found/..
2025/07/08 04:17:32 DEBUG : dir/file1: Uploading chunk 1/1
2025/07/08 04:17:33 DEBUG : dir/file1: Uploading chunk 2/1
2025/07/08 04:17:33 DEBUG : Dropbox root 'rclone-test-taqisuv1suba': Adding "dir/file1" to batch
2025/07/08 04:17:33 DEBUG : Dropbox root 'rclone-test-taqisuv1suba': Batch idle for 500ms so committing
2025/07/08 04:17:33 DEBUG : Dropbox root 'rclone-test-taqisuv1suba': Committing sync batch length 1 starting with: dir/file1
2025/07/08 04:17:35 ERROR : Dropbox root 'rclone-test-taqisuv1suba': sync batch commit: failed to commit batch length 1: batch had 1 errors: last error: upload failed: too_many_write_operations
    run.go:285: Failed to put "dir/file1" to "Dropbox root 'rclone-test-taqisuv1suba'": upload failed: batch upload failed: upload failed: too_many_write_operations
2025/07/08 04:17:35 DEBUG : WaitForWriters: timeout=30s
2025/07/08 04:17:35 DEBUG : Looking for writers
2025/07/08 04:17:35 DEBUG : >WaitForWriters: 
--- FAIL: TestDirHandleReaddir (4.62s)
=== RUN   TestDirForgetAll
    run.go:180: Remote "Dropbox root 'rclone-test-taqisuv1suba'", Local "Local file system at /tmp/rclone4279316862", Modify Window "1s"
2025/07/08 04:17:36 DEBUG : dir/file1: Uploading chunk 1/1
2025/07/08 04:17:37 DEBUG : dir/file1: Uploading chunk 2/1
2025/07/08 04:17:37 DEBUG : Dropbox root 'rclone-test-taqisuv1suba': Adding "dir/file1" to batch
2025/07/08 04:17:37 DEBUG : Dropbox root 'rclone-test-taqisuv1suba': Batch idle for 500ms so committing
2025/07/08 04:17:37 DEBUG : Dropbox root 'rclone-test-taqisuv1suba': Committing sync batch length 1 starting with: dir/file1
2025/07/08 04:17:39 DEBUG : Dropbox root 'rclone-test-taqisuv1suba': Committed sync batch length 1 starting with: dir/file1
2025/07/08 04:17:40 DEBUG : dir: forgetting directory cache
2025/07/08 04:17:40 DEBUG : forgetting directory cache
2025/07/08 04:17:40 DEBUG : dir: forgetting directory cache
2025/07/08 04:17:40 DEBUG : WaitForWriters: timeout=30s
2025/07/08 04:17:40 DEBUG : Looking for writers
2025/07/08 04:17:40 DEBUG : >WaitForWriters: 
--- PASS: TestDirForgetAll (7.36s)
=== RUN   TestDirForgetPath
    run.go:180: Remote "Dropbox root 'rclone-test-taqisuv1suba'", Local "Local file system at /tmp/rclone4279316862", Modify Window "1s"
2025/07/08 04:17:43 DEBUG : dir/file1: Uploading chunk 1/1
2025/07/08 04:17:44 DEBUG : dir/file1: Uploading chunk 2/1
2025/07/08 04:17:44 DEBUG : Dropbox root 'rclone-test-taqisuv1suba': Adding "dir/file1" to batch
2025/07/08 04:17:45 DEBUG : Dropbox root 'rclone-test-taqisuv1suba': Batch idle for 500ms so committing
2025/07/08 04:17:45 DEBUG : Dropbox root 'rclone-test-taqisuv1suba': Committing sync batch length 1 starting with: dir/file1
2025/07/08 04:17:46 DEBUG : Dropbox root 'rclone-test-taqisuv1suba': Committed sync batch length 1 starting with: dir/file1
2025/07/08 04:17:47 DEBUG : ForgetPath: relativePath="dir/notfound", type=1
2025/07/08 04:17:47 DEBUG : dir: invalidating directory cache
2025/07/08 04:17:47 DEBUG : >ForgetPath: 
2025/07/08 04:17:47 DEBUG : ForgetPath: relativePath="dir", type=0
2025/07/08 04:17:47 DEBUG : invalidating directory cache
2025/07/08 04:17:47 DEBUG : dir: forgetting directory cache
2025/07/08 04:17:47 DEBUG : >ForgetPath: 
2025/07/08 04:17:47 DEBUG : ForgetPath: relativePath="not/in/cache", type=0
2025/07/08 04:17:47 DEBUG : >ForgetPath: 
2025/07/08 04:17:47 DEBUG : WaitForWriters: timeout=30s
2025/07/08 04:17:47 DEBUG : dir: Looking for writers
2025/07/08 04:17:47 DEBUG : Looking for writers
2025/07/08 04:17:47 DEBUG : dir: reading active writers
2025/07/08 04:17:47 DEBUG : >WaitForWriters: 
--- PASS: TestDirForgetPath (6.00s)
=== RUN   TestDirWalk
    run.go:180: Remote "Dropbox root 'rclone-test-taqisuv1suba'", Local "Local file system at /tmp/rclone4279316862", Modify Window "1s"
2025/07/08 04:17:49 DEBUG : dir/file1: Uploading chunk 1/1
2025/07/08 04:17:50 DEBUG : dir/file1: Uploading chunk 2/1
2025/07/08 04:17:50 DEBUG : Dropbox root 'rclone-test-taqisuv1suba': Adding "dir/file1" to batch
2025/07/08 04:17:51 DEBUG : Dropbox root 'rclone-test-taqisuv1suba': Batch idle for 500ms so committing
2025/07/08 04:17:51 DEBUG : Dropbox root 'rclone-test-taqisuv1suba': Committing sync batch length 1 starting with: dir/file1
2025/07/08 04:17:51 DEBUG : Dropbox root 'rclone-test-taqisuv1suba': Committed sync batch length 1 starting with: dir/file1
2025/07/08 04:17:52 DEBUG : fil/a/b/c: Uploading chunk 1/1
2025/07/08 04:17:52 DEBUG : fil/a/b/c: Uploading chunk 2/1
2025/07/08 04:17:53 DEBUG : Dropbox root 'rclone-test-taqisuv1suba': Adding "fil/a/b/c" to batch
2025/07/08 04:17:53 DEBUG : Dropbox root 'rclone-test-taqisuv1suba': Batch idle for 500ms so committing
2025/07/08 04:17:53 DEBUG : Dropbox root 'rclone-test-taqisuv1suba': Committing sync batch length 1 starting with: fil/a/b/c
2025/07/08 04:17:57 ERROR : Dropbox root 'rclone-test-taqisuv1suba': sync batch commit: failed to commit batch length 1: batch had 1 errors: last error: upload failed: too_many_write_operations
    run.go:285: Failed to put "fil/a/b/c" to "Dropbox root 'rclone-test-taqisuv1suba'": upload failed: batch upload failed: upload failed: too_many_write_operations
2025/07/08 04:17:57 DEBUG : WaitForWriters: timeout=30s
2025/07/08 04:17:57 DEBUG : dir: Looking for writers
2025/07/08 04:17:57 DEBUG : Looking for writers
2025/07/08 04:17:57 DEBUG : dir: reading active writers
2025/07/08 04:17:57 DEBUG : >WaitForWriters: 
--- FAIL: TestDirWalk (10.42s)
=== RUN   TestUnicodeNormalization
    run.go:180: Remote "Dropbox root 'rclone-test-taqisuv1suba'", Local "Local file system at /tmp/rclone4279316862", Modify Window "1s"
2025/07/08 04:18:00 DEBUG : normal name with no special characters.txt: Uploading chunk 1/1
2025/07/08 04:18:00 DEBUG : normal name with no special characters.txt: Uploading chunk 2/1
2025/07/08 04:18:01 DEBUG : Dropbox root 'rclone-test-taqisuv1suba': Adding "normal name with no special characters.txt" to batch
2025/07/08 04:18:01 DEBUG : Dropbox root 'rclone-test-taqisuv1suba': Batch idle for 500ms so committing
2025/07/08 04:18:01 DEBUG : Dropbox root 'rclone-test-taqisuv1suba': Committing sync batch length 1 starting with: normal name with no special characters.txt
2025/07/08 04:18:02 DEBUG : Dropbox root 'rclone-test-taqisuv1suba': Committed sync batch length 1 starting with: normal name with no special characters.txt
2025/07/08 04:18:03 DEBUG : 測試_Русский___ě_áñ: Uploading chunk 1/1
2025/07/08 04:18:03 DEBUG : 測試_Русский___ě_áñ: Uploading chunk 2/1
2025/07/08 04:18:03 DEBUG : Dropbox root 'rclone-test-taqisuv1suba': Adding "測試_Русский___ě_áñ" to batch
2025/07/08 04:18:04 DEBUG : Dropbox root 'rclone-test-taqisuv1suba': Batch idle for 500ms so committing
2025/07/08 04:18:04 DEBUG : Dropbox root 'rclone-test-taqisuv1suba': Committing sync batch length 1 starting with: 測試_Русский___ě_áñ
2025/07/08 04:18:05 DEBUG : Dropbox root 'rclone-test-taqisuv1suba': Committed sync batch length 1 starting with: 測試_Русский___ě_áñ
2025/07/08 04:18:05 DEBUG : normal name with no special characters.txt: OpenFile: flags=O_RDONLY, perm=-rwxrwxrwx
2025/07/08 04:18:06 DEBUG : normal name with no special characters.txt: Open: flags=O_RDONLY
2025/07/08 04:18:06 DEBUG : normal name with no special characters.txt: >Open: fd=normal name with no special characters.txt (r), err=<nil>
2025/07/08 04:18:06 DEBUG : normal name with no special characters.txt: >OpenFile: fd=normal name with no special characters.txt (r), err=<nil>
2025/07/08 04:18:06 DEBUG : normal name with no special characters.txt: ChunkedReader.openRange at 0 length 134217728
2025/07/08 04:18:06 DEBUG : normal name with no special characters.txt: ChunkedReader.Read at 0 length 5 chunkOffset 0 chunkSize 134217728
2025/07/08 04:18:06 DEBUG : 測試_Русский___ě_áñ: OpenFile: flags=O_RDONLY, perm=-rwxrwxrwx
2025/07/08 04:18:06 DEBUG : 測試_Русский___ě_áñ: Open: flags=O_RDONLY
2025/07/08 04:18:06 DEBUG : 測試_Русский___ě_áñ: >Open: fd=測試_Русский___ě_áñ (r), err=<nil>
2025/07/08 04:18:06 DEBUG : 測試_Русский___ě_áñ: >OpenFile: fd=測試_Русский___ě_áñ (r), err=<nil>
2025/07/08 04:18:06 DEBUG : 測試_Русский___ě_áñ: ChunkedReader.openRange at 0 length 134217728
2025/07/08 04:18:07 DEBUG : 測試_Русский___ě_áñ: ChunkedReader.Read at 0 length 5 chunkOffset 0 chunkSize 134217728
2025/07/08 04:18:07 DEBUG : normal name with no special characters.txt: OpenFile: flags=O_RDONLY, perm=-rwxrwxrwx
2025/07/08 04:18:07 DEBUG : normal name with no special characters.txt: Open: flags=O_RDONLY
2025/07/08 04:18:07 DEBUG : normal name with no special characters.txt: >Open: fd=normal name with no special characters.txt (r), err=<nil>
2025/07/08 04:18:07 DEBUG : normal name with no special characters.txt: >OpenFile: fd=normal name with no special characters.txt (r), err=<nil>
2025/07/08 04:18:07 DEBUG : normal name with no special characters.txt: ChunkedReader.openRange at 0 length 134217728
2025/07/08 04:18:08 DEBUG : normal name with no special characters.txt: ChunkedReader.Read at 0 length 5 chunkOffset 0 chunkSize 134217728
2025/07/08 04:18:08 DEBUG : 測試_Русский___ě_áñ: OpenFile: flags=O_RDONLY, perm=-rwxrwxrwx
2025/07/08 04:18:08 DEBUG : 測試_Русский___ě_áñ: >OpenFile: fd=<nil>, err=file does not exist
2025/07/08 04:18:08 DEBUG : WaitForWriters: timeout=30s
2025/07/08 04:18:08 DEBUG : Looking for writers
2025/07/08 04:18:08 DEBUG : normal name with no special characters.txt: reading active writers
2025/07/08 04:18:08 DEBUG : 測試_Русский___ě_áñ: reading active writers
2025/07/08 04:18:08 DEBUG : >WaitForWriters: 
--- PASS: TestUnicodeNormalization (9.94s)
=== RUN   TestVFSOpenFile
    run.go:180: Remote "Dropbox root 'rclone-test-taqisuv1suba'", Local "Local file system at /tmp/rclone4279316862", Modify Window "1s"
2025/07/08 04:18:10 DEBUG : file1: Uploading chunk 1/1
2025/07/08 04:18:10 DEBUG : file1: Uploading chunk 2/1
2025/07/08 04:18:11 DEBUG : Dropbox root 'rclone-test-taqisuv1suba': Adding "file1" to batch
2025/07/08 04:18:11 DEBUG : Dropbox root 'rclone-test-taqisuv1suba': Batch idle for 500ms so committing
2025/07/08 04:18:11 DEBUG : Dropbox root 'rclone-test-taqisuv1suba': Committing sync batch length 1 starting with: file1
2025/07/08 04:18:12 DEBUG : Dropbox root 'rclone-test-taqisuv1suba': Committed sync batch length 1 starting with: file1
2025/07/08 04:18:12 DEBUG : dir/file2: Uploading chunk 1/1
2025/07/08 04:18:12 DEBUG : dir/file2: Uploading chunk 2/1
2025/07/08 04:18:13 DEBUG : Dropbox root 'rclone-test-taqisuv1suba': Adding "dir/file2" to batch
2025/07/08 04:18:14 DEBUG : Dropbox root 'rclone-test-taqisuv1suba': Batch idle for 500ms so committing
2025/07/08 04:18:14 DEBUG : Dropbox root 'rclone-test-taqisuv1suba': Committing sync batch length 1 starting with: dir/file2
2025/07/08 04:18:15 DEBUG : Dropbox root 'rclone-test-taqisuv1suba': Committed sync batch length 1 starting with: dir/file2
2025/07/08 04:18:16 DEBUG : file1: OpenFile: flags=O_RDONLY, perm=-rwxrwxrwx
2025/07/08 04:18:16 DEBUG : file1: Open: flags=O_RDONLY
2025/07/08 04:18:16 DEBUG : file1: >Open: fd=file1 (r), err=<nil>
2025/07/08 04:18:16 DEBUG : file1: >OpenFile: fd=file1 (r), err=<nil>
2025/07/08 04:18:16 DEBUG : dir: OpenFile: flags=O_RDONLY, perm=-rwxrwxrwx
2025/07/08 04:18:16 DEBUG : dir: >OpenFile: fd=dir/ (r), err=<nil>
2025/07/08 04:18:16 DEBUG : dir/new_file.txt: OpenFile: flags=O_RDONLY, perm=-rwxrwxrwx
2025/07/08 04:18:16 DEBUG : dir/new_file.txt: >OpenFile: fd=<nil>, err=file does not exist
2025/07/08 04:18:16 DEBUG : dir/new_file.txt: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx
2025/07/08 04:18:16 DEBUG : dir/new_file.txt: Open: flags=O_WRONLY|O_CREATE
2025/07/08 04:18:16 DEBUG : dir: Added virtual directory entry vAddFile: "new_file.txt"
2025/07/08 04:18:16 DEBUG : dir/new_file.txt: >Open: fd=dir/new_file.txt (w), err=<nil>
2025/07/08 04:18:16 DEBUG : dir/new_file.txt: >OpenFile: fd=dir/new_file.txt (w), err=<nil>
2025/07/08 04:18:16 DEBUG : dir: Added virtual directory entry vAddFile: "new_file.txt"
2025/07/08 04:18:16 DEBUG : Dropbox root 'rclone-test-taqisuv1suba': File to upload is small (0 bytes), uploading instead of streaming
2025/07/08 04:18:16 DEBUG : dir/new_file.txt: Uploading chunk 1/0
2025/07/08 04:18:17 DEBUG : dir/new_file.txt: Uploading chunk 2/0
2025/07/08 04:18:17 DEBUG : Dropbox root 'rclone-test-taqisuv1suba': Adding "dir/new_file.txt" to batch
2025/07/08 04:18:17 DEBUG : Dropbox root 'rclone-test-taqisuv1suba': Batch idle for 500ms so committing
2025/07/08 04:18:17 DEBUG : Dropbox root 'rclone-test-taqisuv1suba': Committing sync batch length 1 starting with: dir/new_file.txt
2025/07/08 04:18:18 DEBUG : Dropbox root 'rclone-test-taqisuv1suba': Committed sync batch length 1 starting with: dir/new_file.txt
2025/07/08 04:18:18 DEBUG : dir/new_file.txt: dropbox = e3b0c44298fc1c149afbf4c8996fb92427ae41e4649b934ca495991b7852b855 OK
2025/07/08 04:18:18 DEBUG : dir/new_file.txt: Size and dropbox of src and dst objects identical
2025/07/08 04:18:18 DEBUG : dir: Added virtual directory entry vAddFile: "new_file.txt"
2025/07/08 04:18:18 DEBUG : not found/new_file.txt: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx
2025/07/08 04:18:18 DEBUG : not found/new_file.txt: >OpenFile: fd=<nil>, err=file does not exist
2025/07/08 04:18:18 DEBUG : WaitForWriters: timeout=30s
2025/07/08 04:18:18 DEBUG : dir: Looking for writers
2025/07/08 04:18:18 DEBUG : file2: reading active writers
2025/07/08 04:18:18 DEBUG : new_file.txt: reading active writers
2025/07/08 04:18:18 DEBUG : Looking for writers
2025/07/08 04:18:18 DEBUG : dir: reading active writers
2025/07/08 04:18:18 DEBUG : file1: reading active writers
2025/07/08 04:18:18 DEBUG : >WaitForWriters: 
--- PASS: TestVFSOpenFile (12.82s)
FAIL
2025/07/08 04:18:22 DEBUG : Dropbox root 'rclone-test-taqisuv1suba': Purge remote
"./vfs.test -test.v -test.timeout 1h0m0s -remote TestDropbox: -verbose -test.run '^(TestDirForgetAll|TestDirForgetPath|TestDirHandleReaddir|TestDirWalk|TestUnicodeNormalization|TestVFSOpenFile)$'" - Finished ERROR in 52.115555302s (try 2/5): exit status 1: Failed [TestDirHandleReaddir TestDirWalk]