"./vfs.test -test.v -test.timeout 1h0m0s -remote TestDropbox: -verbose -test.run '^(TestDirMkdirSub|TestFileOpenWrite)$'" - Starting (try 2/5)
2024/12/25 01:55:37 DEBUG : Creating backend with remote "TestDropbox:rclone-test-vuyuxep8wiqo"
2024/12/25 01:55:37 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2024/12/25 01:55:37 DEBUG : Creating backend with remote "/tmp/rclone3416972062"
=== RUN   TestDirMkdirSub
    run.go:180: Remote "Dropbox root 'rclone-test-vuyuxep8wiqo'", Local "Local file system at /tmp/rclone3416972062", Modify Window "1s"
2024/12/25 01:55:37 INFO  : Dropbox root 'rclone-test-vuyuxep8wiqo': Failed to get StartCursor: path/not_found/..
2024/12/25 01:55:38 DEBUG : dir/file1: Uploading chunk 1/1
2024/12/25 01:55:39 DEBUG : dir/file1: Uploading chunk 2/1
2024/12/25 01:55:40 DEBUG : Dropbox root 'rclone-test-vuyuxep8wiqo': Adding "dir/file1" to batch
2024/12/25 01:55:40 DEBUG : Dropbox root 'rclone-test-vuyuxep8wiqo': Batch idle for 500ms so committing
2024/12/25 01:55:40 DEBUG : Dropbox root 'rclone-test-vuyuxep8wiqo': Committing sync batch length 1 starting with: dir/file1
2024/12/25 01:55:41 DEBUG : Dropbox root 'rclone-test-vuyuxep8wiqo': Committed sync batch length 1 starting with: dir/file1
2024/12/25 01:55:43 DEBUG : dir: Added virtual directory entry vAddDir: "sub"
2024/12/25 01:55:43 DEBUG : dir/sub: Added virtual directory entry vAddDir: "subsub"
2024/12/25 01:55:45 DEBUG : WaitForWriters: timeout=30s
2024/12/25 01:55:45 DEBUG : dir/sub/subsub: Looking for writers
2024/12/25 01:55:45 DEBUG : dir/sub: Looking for writers
2024/12/25 01:55:45 DEBUG : subsub: reading active writers
2024/12/25 01:55:45 DEBUG : dir: Looking for writers
2024/12/25 01:55:45 DEBUG : file1: reading active writers
2024/12/25 01:55:45 DEBUG : sub: reading active writers
2024/12/25 01:55:45 DEBUG : : Looking for writers
2024/12/25 01:55:45 DEBUG : dir: reading active writers
2024/12/25 01:55:45 DEBUG : >WaitForWriters: 
--- PASS: TestDirMkdirSub (13.83s)
=== RUN   TestFileOpenWrite
    run.go:180: Remote "Dropbox root 'rclone-test-vuyuxep8wiqo'", Local "Local file system at /tmp/rclone3416972062", Modify Window "1s"
2024/12/25 01:55:52 DEBUG : dir/file1: Uploading chunk 1/1
2024/12/25 01:55:52 DEBUG : dir/file1: Uploading chunk 2/1
2024/12/25 01:55:53 DEBUG : Dropbox root 'rclone-test-vuyuxep8wiqo': Adding "dir/file1" to batch
2024/12/25 01:55:53 DEBUG : Dropbox root 'rclone-test-vuyuxep8wiqo': Batch idle for 500ms so committing
2024/12/25 01:55:53 DEBUG : Dropbox root 'rclone-test-vuyuxep8wiqo': Committing sync batch length 1 starting with: dir/file1
2024/12/25 01:55:54 DEBUG : Dropbox root 'rclone-test-vuyuxep8wiqo': Committed sync batch length 1 starting with: dir/file1
2024/12/25 01:55:55 DEBUG : dir: Added virtual directory entry vAddFile: "file1"
2024/12/25 01:55:55 DEBUG : Dropbox root 'rclone-test-vuyuxep8wiqo': File to upload is small (25 bytes), uploading instead of streaming
2024/12/25 01:55:55 DEBUG : dir/file1: Uploading chunk 1/1
2024/12/25 01:55:56 DEBUG : dir/file1: Uploading chunk 2/1
2024/12/25 01:55:56 DEBUG : Dropbox root 'rclone-test-vuyuxep8wiqo': Adding "dir/file1" to batch
2024/12/25 01:55:57 DEBUG : Dropbox root 'rclone-test-vuyuxep8wiqo': Batch idle for 500ms so committing
2024/12/25 01:55:57 DEBUG : Dropbox root 'rclone-test-vuyuxep8wiqo': Committing sync batch length 1 starting with: dir/file1
2024/12/25 01:55:58 DEBUG : Dropbox root 'rclone-test-vuyuxep8wiqo': Committed sync batch length 1 starting with: dir/file1
2024/12/25 01:55:58 DEBUG : dir/file1: dropbox = c28ee946ceebc5dd7464b525fdfc3a480f47b2b22832edbfc4741c21a9d4c664 OK
2024/12/25 01:55:58 DEBUG : dir/file1: Size and dropbox of src and dst objects identical
2024/12/25 01:55:58 DEBUG : dir: Added virtual directory entry vAddFile: "file1"
2024/12/25 01:55:58 DEBUG : WaitForWriters: timeout=30s
2024/12/25 01:55:58 DEBUG : dir: Looking for writers
2024/12/25 01:55:58 DEBUG : file1: reading active writers
2024/12/25 01:55:58 DEBUG : : Looking for writers
2024/12/25 01:55:58 DEBUG : dir: reading active writers
2024/12/25 01:55:58 DEBUG : >WaitForWriters: 
--- PASS: TestFileOpenWrite (9.99s)
PASS
2024/12/25 01:56:01 DEBUG : Dropbox root 'rclone-test-vuyuxep8wiqo': Purge remote
"./vfs.test -test.v -test.timeout 1h0m0s -remote TestDropbox: -verbose -test.run '^(TestDirMkdirSub|TestFileOpenWrite)$'" - Finished OK in 24.886055783s (try 2/5)