"./vfs.test -test.v -test.timeout 1h0m0s -remote TestDropbox: -verbose -test.run '^(TestUnicodeNormalization|TestZipManySubDirs)$'" - Starting (try 2/5)
2026/02/07 02:56:50 DEBUG : Creating backend with remote "TestDropbox:rclone-test-lehased7ceta"
2026/02/07 02:56:50 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/02/07 02:56:50 DEBUG : Creating backend with remote "/tmp/rclone3715054873"
=== RUN   TestUnicodeNormalization
    run.go:185: Remote "Dropbox root 'rclone-test-lehased7ceta'", Local "Local file system at /tmp/rclone3715054873", Modify Window "1s"
2026/02/07 02:56:51 DEBUG : normal name with no special characters.txt: Uploading chunk 1/1
2026/02/07 02:56:51 DEBUG : normal name with no special characters.txt: Uploading chunk 2/1
2026/02/07 02:56:52 DEBUG : Dropbox root 'rclone-test-lehased7ceta': Adding "normal name with no special characters.txt" to batch
2026/02/07 02:56:53 DEBUG : Dropbox root 'rclone-test-lehased7ceta': Batch idle for 500ms so committing
2026/02/07 02:56:53 DEBUG : Dropbox root 'rclone-test-lehased7ceta': Committing sync batch length 1 starting with: normal name with no special characters.txt
2026/02/07 02:56:53 DEBUG : Dropbox root 'rclone-test-lehased7ceta': Committed sync batch length 1 starting with: normal name with no special characters.txt
2026/02/07 02:56:54 DEBUG : 測試_Русский___ě_áñ: Uploading chunk 1/1
2026/02/07 02:56:54 DEBUG : 測試_Русский___ě_áñ: Uploading chunk 2/1
2026/02/07 02:56:55 DEBUG : Dropbox root 'rclone-test-lehased7ceta': Adding "測試_Русский___ě_áñ" to batch
2026/02/07 02:56:55 DEBUG : Dropbox root 'rclone-test-lehased7ceta': Batch idle for 500ms so committing
2026/02/07 02:56:55 DEBUG : Dropbox root 'rclone-test-lehased7ceta': Committing sync batch length 1 starting with: 測試_Русский___ě_áñ
2026/02/07 02:56:56 DEBUG : Dropbox root 'rclone-test-lehased7ceta': Committed sync batch length 1 starting with: 測試_Русский___ě_áñ
2026/02/07 02:56:56 DEBUG : normal name with no special characters.txt: OpenFile: flags=O_RDONLY, perm=-rwxrwxrwx
2026/02/07 02:56:56 DEBUG : normal name with no special characters.txt: Open: flags=O_RDONLY
2026/02/07 02:56:56 DEBUG : normal name with no special characters.txt: >Open: fd=normal name with no special characters.txt (r), err=<nil>
2026/02/07 02:56:56 DEBUG : normal name with no special characters.txt: >OpenFile: fd=normal name with no special characters.txt (r), err=<nil>
2026/02/07 02:56:56 DEBUG : normal name with no special characters.txt: ChunkedReader.openRange at 0 length 134217728
2026/02/07 02:56:57 DEBUG : normal name with no special characters.txt: ChunkedReader.Read at 0 length 5 chunkOffset 0 chunkSize 134217728
2026/02/07 02:56:57 DEBUG : 測試_Русский___ě_áñ: OpenFile: flags=O_RDONLY, perm=-rwxrwxrwx
2026/02/07 02:56:57 DEBUG : 測試_Русский___ě_áñ: Open: flags=O_RDONLY
2026/02/07 02:56:57 DEBUG : 測試_Русский___ě_áñ: >Open: fd=測試_Русский___ě_áñ (r), err=<nil>
2026/02/07 02:56:57 DEBUG : 測試_Русский___ě_áñ: >OpenFile: fd=測試_Русский___ě_áñ (r), err=<nil>
2026/02/07 02:56:57 DEBUG : 測試_Русский___ě_áñ: ChunkedReader.openRange at 0 length 134217728
2026/02/07 02:56:58 DEBUG : 測試_Русский___ě_áñ: ChunkedReader.Read at 0 length 5 chunkOffset 0 chunkSize 134217728
2026/02/07 02:56:58 DEBUG : normal name with no special characters.txt: OpenFile: flags=O_RDONLY, perm=-rwxrwxrwx
2026/02/07 02:56:58 DEBUG : normal name with no special characters.txt: Open: flags=O_RDONLY
2026/02/07 02:56:58 DEBUG : normal name with no special characters.txt: >Open: fd=normal name with no special characters.txt (r), err=<nil>
2026/02/07 02:56:58 DEBUG : normal name with no special characters.txt: >OpenFile: fd=normal name with no special characters.txt (r), err=<nil>
2026/02/07 02:56:58 DEBUG : normal name with no special characters.txt: ChunkedReader.openRange at 0 length 134217728
2026/02/07 02:56:58 DEBUG : normal name with no special characters.txt: ChunkedReader.Read at 0 length 5 chunkOffset 0 chunkSize 134217728
2026/02/07 02:56:58 DEBUG : 測試_Русский___ě_áñ: OpenFile: flags=O_RDONLY, perm=-rwxrwxrwx
2026/02/07 02:56:58 DEBUG : 測試_Русский___ě_áñ: >OpenFile: fd=<nil>, err=file does not exist
2026/02/07 02:56:58 DEBUG : WaitForWriters: timeout=30s
2026/02/07 02:56:58 DEBUG : Looking for writers
2026/02/07 02:56:58 DEBUG : normal name with no special characters.txt: reading active writers
2026/02/07 02:56:58 DEBUG : 測試_Русский___ě_áñ: reading active writers
2026/02/07 02:56:58 DEBUG : >WaitForWriters: 
--- PASS: TestUnicodeNormalization (9.87s)
=== RUN   TestZipManySubDirs
    run.go:185: Remote "Dropbox root 'rclone-test-lehased7ceta'", Local "Local file system at /tmp/rclone3715054873", Modify Window "1s"
2026/02/07 02:57:00 DEBUG : a/top.txt: Uploading chunk 1/1
2026/02/07 02:57:01 DEBUG : a/top.txt: Uploading chunk 2/1
2026/02/07 02:57:01 DEBUG : Dropbox root 'rclone-test-lehased7ceta': Adding "a/top.txt" to batch
2026/02/07 02:57:02 DEBUG : Dropbox root 'rclone-test-lehased7ceta': Batch idle for 500ms so committing
2026/02/07 02:57:02 DEBUG : Dropbox root 'rclone-test-lehased7ceta': Committing sync batch length 1 starting with: a/top.txt
2026/02/07 02:57:03 DEBUG : Dropbox root 'rclone-test-lehased7ceta': Committed sync batch length 1 starting with: a/top.txt
2026/02/07 02:57:03 DEBUG : a/b/mid.txt: Uploading chunk 1/1
2026/02/07 02:57:04 DEBUG : a/b/mid.txt: Uploading chunk 2/1
2026/02/07 02:57:04 DEBUG : Dropbox root 'rclone-test-lehased7ceta': Adding "a/b/mid.txt" to batch
2026/02/07 02:57:05 DEBUG : Dropbox root 'rclone-test-lehased7ceta': Batch idle for 500ms so committing
2026/02/07 02:57:05 DEBUG : Dropbox root 'rclone-test-lehased7ceta': Committing sync batch length 1 starting with: a/b/mid.txt
2026/02/07 02:57:06 DEBUG : Dropbox root 'rclone-test-lehased7ceta': Committed sync batch length 1 starting with: a/b/mid.txt
2026/02/07 02:57:06 DEBUG : a/b/c/deep.txt: Uploading chunk 1/1
2026/02/07 02:57:06 DEBUG : a/b/c/deep.txt: Uploading chunk 2/1
2026/02/07 02:57:07 DEBUG : Dropbox root 'rclone-test-lehased7ceta': Adding "a/b/c/deep.txt" to batch
2026/02/07 02:57:07 DEBUG : Dropbox root 'rclone-test-lehased7ceta': Batch idle for 500ms so committing
2026/02/07 02:57:07 DEBUG : Dropbox root 'rclone-test-lehased7ceta': Committing sync batch length 1 starting with: a/b/c/deep.txt
2026/02/07 02:57:08 DEBUG : Dropbox root 'rclone-test-lehased7ceta': Committed sync batch length 1 starting with: a/b/c/deep.txt
2026/02/07 02:57:09 DEBUG : a/b/c/deep.txt: Open: flags=O_RDONLY
2026/02/07 02:57:09 DEBUG : a/b/c/deep.txt: >Open: fd=a/b/c/deep.txt (r), err=<nil>
2026/02/07 02:57:09 DEBUG : a/b/c/deep.txt: ChunkedReader.openRange at 0 length 134217728
2026/02/07 02:57:10 DEBUG : a/b/c/deep.txt: ChunkedReader.Read at 0 length 32768 chunkOffset 0 chunkSize 134217728
2026/02/07 02:57:10 DEBUG : a/b/mid.txt: Open: flags=O_RDONLY
2026/02/07 02:57:10 DEBUG : a/b/mid.txt: >Open: fd=a/b/mid.txt (r), err=<nil>
2026/02/07 02:57:10 DEBUG : a/b/mid.txt: ChunkedReader.openRange at 0 length 134217728
2026/02/07 02:57:10 DEBUG : a/b/mid.txt: ChunkedReader.Read at 0 length 32768 chunkOffset 0 chunkSize 134217728
2026/02/07 02:57:10 DEBUG : a/top.txt: Open: flags=O_RDONLY
2026/02/07 02:57:10 DEBUG : a/top.txt: >Open: fd=a/top.txt (r), err=<nil>
2026/02/07 02:57:10 DEBUG : a/top.txt: ChunkedReader.openRange at 0 length 134217728
2026/02/07 02:57:11 DEBUG : a/top.txt: ChunkedReader.Read at 0 length 32768 chunkOffset 0 chunkSize 134217728
2026/02/07 02:57:11 DEBUG : WaitForWriters: timeout=30s
2026/02/07 02:57:11 DEBUG : a/b/c: Looking for writers
2026/02/07 02:57:11 DEBUG : deep.txt: reading active writers
2026/02/07 02:57:11 DEBUG : a/b: Looking for writers
2026/02/07 02:57:11 DEBUG : c: reading active writers
2026/02/07 02:57:11 DEBUG : mid.txt: reading active writers
2026/02/07 02:57:11 DEBUG : a: Looking for writers
2026/02/07 02:57:11 DEBUG : top.txt: reading active writers
2026/02/07 02:57:11 DEBUG : b: reading active writers
2026/02/07 02:57:11 DEBUG : Looking for writers
2026/02/07 02:57:11 DEBUG : a: reading active writers
2026/02/07 02:57:11 DEBUG : >WaitForWriters: 
--- PASS: TestZipManySubDirs (17.83s)
PASS
2026/02/07 02:57:18 DEBUG : Dropbox root 'rclone-test-lehased7ceta': Purge remote
"./vfs.test -test.v -test.timeout 1h0m0s -remote TestDropbox: -verbose -test.run '^(TestUnicodeNormalization|TestZipManySubDirs)$'" - Finished OK in 28.630816111s (try 2/5)