"./vfs.test -test.v -test.timeout 1h0m0s -remote TestDropbox: -verbose -test.run '^(TestDirWalk|TestFileOpenWrite)$'" - Starting (try 2/5)
2025/10/12 03:02:32 DEBUG : Creating backend with remote "TestDropbox:rclone-test-cinihah5hawa"
2025/10/12 03:02:32 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/10/12 03:02:32 DEBUG : Creating backend with remote "/tmp/rclone1562953836"
=== RUN   TestDirWalk
    run.go:180: Remote "Dropbox root 'rclone-test-cinihah5hawa'", Local "Local file system at /tmp/rclone1562953836", Modify Window "1s"
2025/10/12 03:02:32 INFO  : Dropbox root 'rclone-test-cinihah5hawa': Failed to get StartCursor: path/not_found/..
2025/10/12 03:02:33 DEBUG : dir/file1: Uploading chunk 1/1
2025/10/12 03:02:33 DEBUG : dir/file1: Uploading chunk 2/1
2025/10/12 03:02:34 DEBUG : Dropbox root 'rclone-test-cinihah5hawa': Adding "dir/file1" to batch
2025/10/12 03:02:34 DEBUG : Dropbox root 'rclone-test-cinihah5hawa': Batch idle for 500ms so committing
2025/10/12 03:02:34 DEBUG : Dropbox root 'rclone-test-cinihah5hawa': Committing sync batch length 1 starting with: dir/file1
2025/10/12 03:02:35 DEBUG : Dropbox root 'rclone-test-cinihah5hawa': Committed sync batch length 1 starting with: dir/file1
2025/10/12 03:02:36 DEBUG : fil/a/b/c: Uploading chunk 1/1
2025/10/12 03:02:36 DEBUG : fil/a/b/c: Uploading chunk 2/1
2025/10/12 03:02:36 DEBUG : Dropbox root 'rclone-test-cinihah5hawa': Adding "fil/a/b/c" to batch
2025/10/12 03:02:37 DEBUG : Dropbox root 'rclone-test-cinihah5hawa': Batch idle for 500ms so committing
2025/10/12 03:02:37 DEBUG : Dropbox root 'rclone-test-cinihah5hawa': Committing sync batch length 1 starting with: fil/a/b/c
2025/10/12 03:02:38 DEBUG : Dropbox root 'rclone-test-cinihah5hawa': Committed sync batch length 1 starting with: fil/a/b/c
2025/10/12 03:02:38 DEBUG : forgetting directory cache
2025/10/12 03:02:38 DEBUG : dir: forgetting directory cache
2025/10/12 03:02:39 DEBUG : WaitForWriters: timeout=30s
2025/10/12 03:02:39 DEBUG : dir: Looking for writers
2025/10/12 03:02:39 DEBUG : fil/a/b: Looking for writers
2025/10/12 03:02:39 DEBUG : fil/a: Looking for writers
2025/10/12 03:02:39 DEBUG : b: reading active writers
2025/10/12 03:02:39 DEBUG : fil: Looking for writers
2025/10/12 03:02:39 DEBUG : a: reading active writers
2025/10/12 03:02:39 DEBUG : Looking for writers
2025/10/12 03:02:39 DEBUG : dir: reading active writers
2025/10/12 03:02:39 DEBUG : fil: reading active writers
2025/10/12 03:02:39 DEBUG : >WaitForWriters: 
--- PASS: TestDirWalk (13.95s)
=== RUN   TestFileOpenWrite
    run.go:180: Remote "Dropbox root 'rclone-test-cinihah5hawa'", Local "Local file system at /tmp/rclone1562953836", Modify Window "1s"
2025/10/12 03:02:46 DEBUG : dir/file1: Uploading chunk 1/1
2025/10/12 03:02:47 DEBUG : dir/file1: Uploading chunk 2/1
2025/10/12 03:02:47 DEBUG : Dropbox root 'rclone-test-cinihah5hawa': Adding "dir/file1" to batch
2025/10/12 03:02:48 DEBUG : Dropbox root 'rclone-test-cinihah5hawa': Batch idle for 500ms so committing
2025/10/12 03:02:48 DEBUG : Dropbox root 'rclone-test-cinihah5hawa': Committing sync batch length 1 starting with: dir/file1
2025/10/12 03:02:48 DEBUG : Dropbox root 'rclone-test-cinihah5hawa': Committed sync batch length 1 starting with: dir/file1
2025/10/12 03:02:49 DEBUG : dir: Added virtual directory entry vAddFile: "file1"
2025/10/12 03:02:49 DEBUG : Dropbox root 'rclone-test-cinihah5hawa': File to upload is small (25 bytes), uploading instead of streaming
2025/10/12 03:02:50 DEBUG : dir/file1: Uploading chunk 1/1
2025/10/12 03:02:50 DEBUG : dir/file1: Uploading chunk 2/1
2025/10/12 03:02:50 DEBUG : Dropbox root 'rclone-test-cinihah5hawa': Adding "dir/file1" to batch
2025/10/12 03:02:51 DEBUG : Dropbox root 'rclone-test-cinihah5hawa': Batch idle for 500ms so committing
2025/10/12 03:02:51 DEBUG : Dropbox root 'rclone-test-cinihah5hawa': Committing sync batch length 1 starting with: dir/file1
2025/10/12 03:02:52 DEBUG : Dropbox root 'rclone-test-cinihah5hawa': Committed sync batch length 1 starting with: dir/file1
2025/10/12 03:02:52 DEBUG : dir/file1: dropbox = c28ee946ceebc5dd7464b525fdfc3a480f47b2b22832edbfc4741c21a9d4c664 OK
2025/10/12 03:02:52 DEBUG : dir/file1: Size and dropbox of src and dst objects identical
2025/10/12 03:02:52 DEBUG : dir: Added virtual directory entry vAddFile: "file1"
2025/10/12 03:02:52 DEBUG : WaitForWriters: timeout=30s
2025/10/12 03:02:52 DEBUG : dir: Looking for writers
2025/10/12 03:02:52 DEBUG : file1: reading active writers
2025/10/12 03:02:52 DEBUG : Looking for writers
2025/10/12 03:02:52 DEBUG : dir: reading active writers
2025/10/12 03:02:52 DEBUG : >WaitForWriters: 
--- PASS: TestFileOpenWrite (8.08s)
PASS
2025/10/12 03:02:54 DEBUG : Dropbox root 'rclone-test-cinihah5hawa': Purge remote
"./vfs.test -test.v -test.timeout 1h0m0s -remote TestDropbox: -verbose -test.run '^(TestDirWalk|TestFileOpenWrite)$'" - Finished OK in 22.983033668s (try 2/5)