"./vfs.test -test.v -test.timeout 1h0m0s -remote TestFileLu: -verbose -test.run '^(TestFileReadAtNonZeroLength|TestFileReadAtZeroLength|TestVFSIsMetadataFile|TestVFSMkdir|TestVFSMkdirAll|TestVFSOpenFile|TestVFSRename|TestVFSStatfs|TestWriteFileHandleFlush|TestWriteFileHandleMethods|TestWriteFileHandleRelease|TestWriteFileHandleWriteAt|TestWriteFileModTimeWithOpenWriters|TestZipDirsInRoot|TestZipLargeFiles|TestZipManyFiles|TestZipManySubDirs)$'" - Starting (try 3/5)
2025/12/01 04:07:18 DEBUG : Creating backend with remote "TestFileLu:rclone-test-muwihes8fuxo"
2025/12/01 04:07:18 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/12/01 04:07:18 DEBUG : Creating backend with remote "/tmp/rclone2121884145"
=== RUN   TestVFSOpenFile
    run.go:185: Remote "FileLu root 'rclone-test-muwihes8fuxo'", Local "Local file system at /tmp/rclone2121884145", Modify Window "876000h0m0s"
2025/12/01 04:07:18 INFO  : FileLu root 'rclone-test-muwihes8fuxo': poll-interval is not supported by this remote
2025/12/01 04:07:18 NOTICE: FileLu root 'rclone-test-muwihes8fuxo': --vfs-cache-mode writes or full is recommended for this remote as it can't stream
    run.go:242: Failed to mkdir "FileLu root 'rclone-test-muwihes8fuxo'": error decoding response: invalid character '<' looking for beginning of value
2025/12/01 04:07:18 DEBUG : WaitForWriters: timeout=30s
2025/12/01 04:07:18 DEBUG : Looking for writers
2025/12/01 04:07:18 DEBUG : >WaitForWriters: 
2025/12/01 04:07:19 ERROR : error listing: error decoding response: invalid character '<' looking for beginning of value
    run.go:169: 
        	Error Trace:	/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:169
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393
        	            				/usr/local/go/src/testing/testing.go:1211
        	            				/usr/local/go/src/testing/testing.go:1445
        	            				/usr/local/go/src/testing/testing.go:1786
        	            				/usr/local/go/src/runtime/panic.go:636
        	            				/usr/local/go/src/testing/testing.go:1041
        	            				/usr/local/go/src/testing/testing.go:1125
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:242
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:250
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:263
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:297
        	            				/home/rclone/go/src/github.com/rclone/rclone/vfs/vfs_test.go:255
        	Error:      	Received unexpected error:
        	            	error decoding response: invalid character '<' looking for beginning of value
        	Test:       	TestVFSOpenFile
--- FAIL: TestVFSOpenFile (1.27s)
=== RUN   TestVFSRename
    run.go:185: Remote "FileLu root 'rclone-test-muwihes8fuxo'", Local "Local file system at /tmp/rclone2121884145", Modify Window "876000h0m0s"
2025/12/01 04:07:19 INFO  : FileLu root 'rclone-test-muwihes8fuxo': poll-interval is not supported by this remote
2025/12/01 04:07:19 NOTICE: FileLu root 'rclone-test-muwihes8fuxo': --vfs-cache-mode writes or full is recommended for this remote as it can't stream
    run.go:242: Failed to mkdir "FileLu root 'rclone-test-muwihes8fuxo'": error decoding response: invalid character '<' looking for beginning of value
2025/12/01 04:07:19 DEBUG : WaitForWriters: timeout=30s
2025/12/01 04:07:19 DEBUG : Looking for writers
2025/12/01 04:07:19 DEBUG : >WaitForWriters: 
2025/12/01 04:07:20 ERROR : error listing: error decoding response: invalid character '<' looking for beginning of value
    run.go:169: 
        	Error Trace:	/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:169
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393
        	            				/usr/local/go/src/testing/testing.go:1211
        	            				/usr/local/go/src/testing/testing.go:1445
        	            				/usr/local/go/src/testing/testing.go:1786
        	            				/usr/local/go/src/runtime/panic.go:636
        	            				/usr/local/go/src/testing/testing.go:1041
        	            				/usr/local/go/src/testing/testing.go:1125
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:242
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:250
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:263
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:297
        	            				/home/rclone/go/src/github.com/rclone/rclone/vfs/vfs_test.go:294
        	Error:      	Received unexpected error:
        	            	error decoding response: invalid character '<' looking for beginning of value
        	Test:       	TestVFSRename
--- FAIL: TestVFSRename (1.19s)
=== RUN   TestVFSStatfs
    run.go:185: Remote "FileLu root 'rclone-test-muwihes8fuxo'", Local "Local file system at /tmp/rclone2121884145", Modify Window "876000h0m0s"
2025/12/01 04:07:20 INFO  : FileLu root 'rclone-test-muwihes8fuxo': poll-interval is not supported by this remote
2025/12/01 04:07:20 NOTICE: FileLu root 'rclone-test-muwihes8fuxo': --vfs-cache-mode writes or full is recommended for this remote as it can't stream
2025/12/01 04:07:21 ERROR : FileLu root 'rclone-test-muwihes8fuxo': Statfs failed: HTTP error 500 (500 Internal Server Error) returned body: "<h1>Software error:</h1>\n<pre>Can't connect to Mysql server. at Modules/DataBaseImpl.pm line 87.\n</pre>\n<p>\nFor help, please send mail to the webmaster (<a href=\"/cdn-cgi/l/email-protection#681a07071c2804070b090400071b1c\">root@localhost</a>), giving this error message \nand the time and date of the error.\n\n</p>\n<script defer src=\"https://static.cloudflareinsights.com/beacon.min.js/vcd15cbe7772f49c399c6a5babf22c1241717689176015\" integrity=\"sha512-ZpsOmlRQV6y907TI0dKBHq9Md29nnaEIPlkf84rnaERnq6zvWvPUqr2ft8M1aS28oN72PdrCzSjY4U6VaAw1EQ==\" data-cf-beacon='{\"version\":\"2024.11.0\",\"token\":\"6f6029fd984b45709fbb2fa7fa9773fb\",\"server_timing\":{\"name\":{\"cfCacheStatus\":true,\"cfEdge\":true,\"cfExtPri\":true,\"cfL4\":true,\"cfOrigin\":true,\"cfSpeedBrain\":true},\"location_startswith\":null}}' crossorigin=\"anonymous\"></script>\n<script>(function(){function c(){var b=a.contentDocument||a.contentWindow.document;if(b){var d=b.createElement('script');d.innerHTML=\"window.__CF$cv$params={r:'9a6fb79279afbba2',t:'MTc2NDU2MjA0MA=='};var a=document.createElement('script');a.src='/cdn-cgi/challenge-platform/scripts/jsd/main.js';document.getElementsByTagName('head')[0].appendChild(a);\";b.getElementsByTagName('head')[0].appendChild(d)}}if(document.body){var a=document.createElement('iframe');a.height=1;a.width=1;a.style.position='absolute';a.style.top=0;a.style.left=0;a.style.border='none';a.style.visibility='hidden';document.body.appendChild(a);if('loading'!==document.readyState)c();else if(window.addEventListener)document.addEventListener('DOMContentLoaded',c);else{var e=document.onreadystatechange||function(){};document.onreadystatechange=function(b){e(b);'loading'!==document.readyState&&(document.onreadystatechange=e,c())}}}})();</script><script data-cfasync=\"false\" src=\"/cdn-cgi/scripts/5c5dd728/cloudflare-static/email-decode.min.js\"></script>"
    vfs_test.go:331: 
        	Error Trace:	/home/rclone/go/src/github.com/rclone/rclone/vfs/vfs_test.go:331
        	Error:      	Expected value not to be nil.
        	Test:       	TestVFSStatfs
2025/12/01 04:07:21 DEBUG : WaitForWriters: timeout=30s
2025/12/01 04:07:21 DEBUG : Looking for writers
2025/12/01 04:07:21 DEBUG : >WaitForWriters: 
2025/12/01 04:07:22 ERROR : error listing: error decoding response: invalid character '<' looking for beginning of value
    run.go:169: 
        	Error Trace:	/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:169
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393
        	            				/usr/local/go/src/testing/testing.go:1211
        	            				/usr/local/go/src/testing/testing.go:1445
        	            				/usr/local/go/src/testing/testing.go:1786
        	            				/usr/local/go/src/runtime/panic.go:636
        	            				/usr/local/go/src/testing/testing.go:1041
        	            				/home/rclone/go/src/github.com/rclone/rclone/vfs/vfs_test.go:331
        	Error:      	Received unexpected error:
        	            	error decoding response: invalid character '<' looking for beginning of value
        	Test:       	TestVFSStatfs
--- FAIL: TestVFSStatfs (1.85s)
=== RUN   TestVFSMkdir
    run.go:185: Remote "FileLu root 'rclone-test-muwihes8fuxo'", Local "Local file system at /tmp/rclone2121884145", Modify Window "876000h0m0s"
2025/12/01 04:07:22 INFO  : FileLu root 'rclone-test-muwihes8fuxo': poll-interval is not supported by this remote
2025/12/01 04:07:22 NOTICE: FileLu root 'rclone-test-muwihes8fuxo': --vfs-cache-mode writes or full is recommended for this remote as it can't stream
2025/12/01 04:07:28 ERROR : error listing: error decoding response: invalid character '<' looking for beginning of value
    fstest.go:282: Error listing: error decoding response: invalid character '<' looking for beginning of value
2025/12/01 04:07:28 DEBUG : WaitForWriters: timeout=30s
2025/12/01 04:07:28 DEBUG : Looking for writers
2025/12/01 04:07:28 DEBUG : >WaitForWriters: 
2025/12/01 04:07:34 ERROR : error listing: error decoding response: invalid character '<' looking for beginning of value
    run.go:169: 
        	Error Trace:	/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:169
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393
        	            				/usr/local/go/src/testing/testing.go:1211
        	            				/usr/local/go/src/testing/testing.go:1445
        	            				/usr/local/go/src/testing/testing.go:1786
        	            				/usr/local/go/src/runtime/panic.go:636
        	            				/usr/local/go/src/testing/testing.go:1041
        	            				/usr/local/go/src/testing/testing.go:1125
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:282
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:365
        	            				/home/rclone/go/src/github.com/rclone/rclone/vfs/vfs_test.go:371
        	Error:      	Received unexpected error:
        	            	error decoding response: invalid character '<' looking for beginning of value
        	Test:       	TestVFSMkdir
--- FAIL: TestVFSMkdir (11.45s)
=== RUN   TestVFSMkdirAll
    run.go:185: Remote "FileLu root 'rclone-test-muwihes8fuxo'", Local "Local file system at /tmp/rclone2121884145", Modify Window "876000h0m0s"
2025/12/01 04:07:34 INFO  : FileLu root 'rclone-test-muwihes8fuxo': poll-interval is not supported by this remote
2025/12/01 04:07:34 NOTICE: FileLu root 'rclone-test-muwihes8fuxo': --vfs-cache-mode writes or full is recommended for this remote as it can't stream
2025/12/01 04:07:40 ERROR : error listing: error decoding response: invalid character '<' looking for beginning of value
    fstest.go:282: Error listing: error decoding response: invalid character '<' looking for beginning of value
2025/12/01 04:07:40 DEBUG : WaitForWriters: timeout=30s
2025/12/01 04:07:40 DEBUG : Looking for writers
2025/12/01 04:07:40 DEBUG : >WaitForWriters: 
2025/12/01 04:07:46 ERROR : error listing: error decoding response: invalid character '<' looking for beginning of value
    run.go:169: 
        	Error Trace:	/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:169
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393
        	            				/usr/local/go/src/testing/testing.go:1211
        	            				/usr/local/go/src/testing/testing.go:1445
        	            				/usr/local/go/src/testing/testing.go:1786
        	            				/usr/local/go/src/runtime/panic.go:636
        	            				/usr/local/go/src/testing/testing.go:1041
        	            				/usr/local/go/src/testing/testing.go:1125
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:282
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:365
        	            				/home/rclone/go/src/github.com/rclone/rclone/vfs/vfs_test.go:416
        	Error:      	Received unexpected error:
        	            	error decoding response: invalid character '<' looking for beginning of value
        	Test:       	TestVFSMkdirAll
--- FAIL: TestVFSMkdirAll (12.02s)
=== RUN   TestVFSIsMetadataFile
    run.go:185: Remote "FileLu root 'rclone-test-muwihes8fuxo'", Local "Local file system at /tmp/rclone2121884145", Modify Window "876000h0m0s"
2025/12/01 04:07:46 INFO  : FileLu root 'rclone-test-muwihes8fuxo': poll-interval is not supported by this remote
2025/12/01 04:07:46 NOTICE: FileLu root 'rclone-test-muwihes8fuxo': --vfs-cache-mode writes or full is recommended for this remote as it can't stream
2025/12/01 04:07:46 DEBUG : WaitForWriters: timeout=30s
2025/12/01 04:07:46 DEBUG : Looking for writers
2025/12/01 04:07:46 DEBUG : >WaitForWriters: 
2025/12/01 04:07:52 ERROR : error listing: error decoding response: invalid character '<' looking for beginning of value
    run.go:169: 
        	Error Trace:	/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:169
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393
        	            				/usr/local/go/src/testing/testing.go:1211
        	            				/usr/local/go/src/testing/testing.go:1445
        	            				/usr/local/go/src/testing/testing.go:1786
        	            				/usr/local/go/src/runtime/asm_amd64.s:1700
        	Error:      	Received unexpected error:
        	            	error decoding response: invalid character '<' looking for beginning of value
        	Test:       	TestVFSIsMetadataFile
--- FAIL: TestVFSIsMetadataFile (5.99s)
=== RUN   TestWriteFileHandleMethods
    run.go:185: Remote "FileLu root 'rclone-test-muwihes8fuxo'", Local "Local file system at /tmp/rclone2121884145", Modify Window "876000h0m0s"
2025/12/01 04:07:52 INFO  : FileLu root 'rclone-test-muwihes8fuxo': poll-interval is not supported by this remote
2025/12/01 04:07:52 NOTICE: FileLu root 'rclone-test-muwihes8fuxo': --vfs-cache-mode writes or full is recommended for this remote as it can't stream
2025/12/01 04:07:52 DEBUG : file1: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx
2025/12/01 04:07:58 ERROR : /: Dir.Stat error: error decoding response: invalid character '<' looking for beginning of value
2025/12/01 04:07:58 DEBUG : file1: >OpenFile: fd=<nil>, err=error decoding response: invalid character '<' looking for beginning of value
    write_test.go:25: 
        	Error Trace:	/home/rclone/go/src/github.com/rclone/rclone/vfs/write_test.go:25
        	            				/home/rclone/go/src/github.com/rclone/rclone/vfs/write_test.go:93
        	Error:      	Received unexpected error:
        	            	error decoding response: invalid character '<' looking for beginning of value
        	Test:       	TestWriteFileHandleMethods
2025/12/01 04:07:58 DEBUG : WaitForWriters: timeout=30s
2025/12/01 04:07:58 DEBUG : Looking for writers
2025/12/01 04:07:58 DEBUG : >WaitForWriters: 
2025/12/01 04:08:04 ERROR : error listing: error decoding response: invalid character '<' looking for beginning of value
    run.go:169: 
        	Error Trace:	/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:169
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393
        	            				/usr/local/go/src/testing/testing.go:1211
        	            				/usr/local/go/src/testing/testing.go:1445
        	            				/usr/local/go/src/testing/testing.go:1786
        	            				/usr/local/go/src/runtime/panic.go:636
        	            				/usr/local/go/src/testing/testing.go:1041
        	            				/home/rclone/go/src/github.com/rclone/rclone/vfs/write_test.go:25
        	            				/home/rclone/go/src/github.com/rclone/rclone/vfs/write_test.go:93
        	Error:      	Received unexpected error:
        	            	error decoding response: invalid character '<' looking for beginning of value
        	Test:       	TestWriteFileHandleMethods
--- FAIL: TestWriteFileHandleMethods (12.00s)
=== RUN   TestWriteFileHandleWriteAt
    run.go:185: Remote "FileLu root 'rclone-test-muwihes8fuxo'", Local "Local file system at /tmp/rclone2121884145", Modify Window "876000h0m0s"
2025/12/01 04:08:04 INFO  : FileLu root 'rclone-test-muwihes8fuxo': poll-interval is not supported by this remote
2025/12/01 04:08:04 NOTICE: FileLu root 'rclone-test-muwihes8fuxo': --vfs-cache-mode writes or full is recommended for this remote as it can't stream
2025/12/01 04:08:04 DEBUG : file1: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx
2025/12/01 04:08:10 ERROR : /: Dir.Stat error: error decoding response: invalid character '<' looking for beginning of value
2025/12/01 04:08:10 DEBUG : file1: >OpenFile: fd=<nil>, err=error decoding response: invalid character '<' looking for beginning of value
    write_test.go:25: 
        	Error Trace:	/home/rclone/go/src/github.com/rclone/rclone/vfs/write_test.go:25
        	            				/home/rclone/go/src/github.com/rclone/rclone/vfs/write_test.go:195
        	Error:      	Received unexpected error:
        	            	error decoding response: invalid character '<' looking for beginning of value
        	Test:       	TestWriteFileHandleWriteAt
2025/12/01 04:08:10 DEBUG : WaitForWriters: timeout=30s
2025/12/01 04:08:10 DEBUG : Looking for writers
2025/12/01 04:08:10 DEBUG : >WaitForWriters: 
2025/12/01 04:08:16 ERROR : error listing: error decoding response: invalid character '<' looking for beginning of value
    run.go:169: 
        	Error Trace:	/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:169
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393
        	            				/usr/local/go/src/testing/testing.go:1211
        	            				/usr/local/go/src/testing/testing.go:1445
        	            				/usr/local/go/src/testing/testing.go:1786
        	            				/usr/local/go/src/runtime/panic.go:636
        	            				/usr/local/go/src/testing/testing.go:1041
        	            				/home/rclone/go/src/github.com/rclone/rclone/vfs/write_test.go:25
        	            				/home/rclone/go/src/github.com/rclone/rclone/vfs/write_test.go:195
        	Error:      	Received unexpected error:
        	            	error decoding response: invalid character '<' looking for beginning of value
        	Test:       	TestWriteFileHandleWriteAt
--- FAIL: TestWriteFileHandleWriteAt (12.02s)
=== RUN   TestWriteFileHandleFlush
    run.go:185: Remote "FileLu root 'rclone-test-muwihes8fuxo'", Local "Local file system at /tmp/rclone2121884145", Modify Window "876000h0m0s"
2025/12/01 04:08:16 INFO  : FileLu root 'rclone-test-muwihes8fuxo': poll-interval is not supported by this remote
2025/12/01 04:08:16 NOTICE: FileLu root 'rclone-test-muwihes8fuxo': --vfs-cache-mode writes or full is recommended for this remote as it can't stream
2025/12/01 04:08:16 DEBUG : file1: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx
2025/12/01 04:08:22 ERROR : /: Dir.Stat error: error decoding response: invalid character '<' looking for beginning of value
2025/12/01 04:08:22 DEBUG : file1: >OpenFile: fd=<nil>, err=error decoding response: invalid character '<' looking for beginning of value
    write_test.go:25: 
        	Error Trace:	/home/rclone/go/src/github.com/rclone/rclone/vfs/write_test.go:25
        	            				/home/rclone/go/src/github.com/rclone/rclone/vfs/write_test.go:239
        	Error:      	Received unexpected error:
        	            	error decoding response: invalid character '<' looking for beginning of value
        	Test:       	TestWriteFileHandleFlush
2025/12/01 04:08:22 DEBUG : WaitForWriters: timeout=30s
2025/12/01 04:08:22 DEBUG : Looking for writers
2025/12/01 04:08:22 DEBUG : >WaitForWriters: 
2025/12/01 04:08:28 ERROR : error listing: error decoding response: invalid character '<' looking for beginning of value
    run.go:169: 
        	Error Trace:	/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:169
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393
        	            				/usr/local/go/src/testing/testing.go:1211
        	            				/usr/local/go/src/testing/testing.go:1445
        	            				/usr/local/go/src/testing/testing.go:1786
        	            				/usr/local/go/src/runtime/panic.go:636
        	            				/usr/local/go/src/testing/testing.go:1041
        	            				/home/rclone/go/src/github.com/rclone/rclone/vfs/write_test.go:25
        	            				/home/rclone/go/src/github.com/rclone/rclone/vfs/write_test.go:239
        	Error:      	Received unexpected error:
        	            	error decoding response: invalid character '<' looking for beginning of value
        	Test:       	TestWriteFileHandleFlush
--- FAIL: TestWriteFileHandleFlush (12.00s)
=== RUN   TestWriteFileHandleRelease
    run.go:185: Remote "FileLu root 'rclone-test-muwihes8fuxo'", Local "Local file system at /tmp/rclone2121884145", Modify Window "876000h0m0s"
2025/12/01 04:08:28 INFO  : FileLu root 'rclone-test-muwihes8fuxo': poll-interval is not supported by this remote
2025/12/01 04:08:28 NOTICE: FileLu root 'rclone-test-muwihes8fuxo': --vfs-cache-mode writes or full is recommended for this remote as it can't stream
2025/12/01 04:08:28 DEBUG : file1: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx
2025/12/01 04:08:34 ERROR : /: Dir.Stat error: error decoding response: invalid character '<' looking for beginning of value
2025/12/01 04:08:34 DEBUG : file1: >OpenFile: fd=<nil>, err=error decoding response: invalid character '<' looking for beginning of value
    write_test.go:25: 
        	Error Trace:	/home/rclone/go/src/github.com/rclone/rclone/vfs/write_test.go:25
        	            				/home/rclone/go/src/github.com/rclone/rclone/vfs/write_test.go:271
        	Error:      	Received unexpected error:
        	            	error decoding response: invalid character '<' looking for beginning of value
        	Test:       	TestWriteFileHandleRelease
2025/12/01 04:08:34 DEBUG : WaitForWriters: timeout=30s
2025/12/01 04:08:34 DEBUG : Looking for writers
2025/12/01 04:08:34 DEBUG : >WaitForWriters: 
2025/12/01 04:08:40 ERROR : error listing: error decoding response: invalid character '<' looking for beginning of value
    run.go:169: 
        	Error Trace:	/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:169
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393
        	            				/usr/local/go/src/testing/testing.go:1211
        	            				/usr/local/go/src/testing/testing.go:1445
        	            				/usr/local/go/src/testing/testing.go:1786
        	            				/usr/local/go/src/runtime/panic.go:636
        	            				/usr/local/go/src/testing/testing.go:1041
        	            				/home/rclone/go/src/github.com/rclone/rclone/vfs/write_test.go:25
        	            				/home/rclone/go/src/github.com/rclone/rclone/vfs/write_test.go:271
        	Error:      	Received unexpected error:
        	            	error decoding response: invalid character '<' looking for beginning of value
        	Test:       	TestWriteFileHandleRelease
--- FAIL: TestWriteFileHandleRelease (12.00s)
=== RUN   TestWriteFileModTimeWithOpenWriters
    run.go:185: Remote "FileLu root 'rclone-test-muwihes8fuxo'", Local "Local file system at /tmp/rclone2121884145", Modify Window "876000h0m0s"
2025/12/01 04:08:40 INFO  : FileLu root 'rclone-test-muwihes8fuxo': poll-interval is not supported by this remote
2025/12/01 04:08:40 NOTICE: FileLu root 'rclone-test-muwihes8fuxo': --vfs-cache-mode writes or full is recommended for this remote as it can't stream
2025/12/01 04:08:40 DEBUG : file1: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx
2025/12/01 04:08:44 DEBUG : file1: Open: flags=O_WRONLY|O_CREATE
2025/12/01 04:08:44 DEBUG : Added virtual directory entry vAddFile: "file1"
2025/12/01 04:08:44 DEBUG : file1: >Open: fd=file1 (w), err=<nil>
2025/12/01 04:08:44 DEBUG : file1: >OpenFile: fd=file1 (w), err=<nil>
2025/12/01 04:08:46 INFO  : FileLu root 'rclone-test-muwihes8fuxo': Successfully created folder "rclone-test-muwihes8fuxo" with ID 1562362
2025/12/01 04:09:23 INFO  : FileLu root 'rclone-test-muwihes8fuxo': Put: Successfully uploaded new file "time_test"
2025/12/01 04:09:24 INFO  : FileLu root 'rclone-test-muwihes8fuxo': Successfully deleted file: /rclone-test-muwihes8fuxo/time_test
2025/12/01 04:09:24 DEBUG : Can set mod time: false
    write_test.go:321: can't set mod time
2025/12/01 04:09:24 DEBUG : WaitForWriters: timeout=30s
2025/12/01 04:09:24 DEBUG : Looking for writers
2025/12/01 04:09:24 DEBUG : file1: reading active writers
2025/12/01 04:09:24 DEBUG : file1: active writers 1
2025/12/01 04:09:24 DEBUG : Still 1 writers active and 0 cache items in use, waiting 10ms
2025/12/01 04:09:24 DEBUG : Looking for writers
2025/12/01 04:09:24 DEBUG : file1: reading active writers
2025/12/01 04:09:24 DEBUG : file1: active writers 1
2025/12/01 04:09:24 DEBUG : Still 1 writers active and 0 cache items in use, waiting 20ms
2025/12/01 04:09:24 DEBUG : Looking for writers
2025/12/01 04:09:24 DEBUG : file1: reading active writers
2025/12/01 04:09:24 DEBUG : file1: active writers 1
2025/12/01 04:09:24 DEBUG : Still 1 writers active and 0 cache items in use, waiting 40ms
2025/12/01 04:09:24 DEBUG : Looking for writers
2025/12/01 04:09:24 DEBUG : file1: reading active writers
2025/12/01 04:09:24 DEBUG : file1: active writers 1
2025/12/01 04:09:24 DEBUG : Still 1 writers active and 0 cache items in use, waiting 80ms
2025/12/01 04:09:24 DEBUG : Looking for writers
2025/12/01 04:09:24 DEBUG : file1: reading active writers
2025/12/01 04:09:24 DEBUG : file1: active writers 1
2025/12/01 04:09:24 DEBUG : Still 1 writers active and 0 cache items in use, waiting 160ms
2025/12/01 04:09:24 DEBUG : Looking for writers
2025/12/01 04:09:24 DEBUG : file1: reading active writers
2025/12/01 04:09:24 DEBUG : file1: active writers 1
2025/12/01 04:09:24 DEBUG : Still 1 writers active and 0 cache items in use, waiting 320ms
2025/12/01 04:09:25 DEBUG : Looking for writers
2025/12/01 04:09:25 DEBUG : file1: reading active writers
2025/12/01 04:09:25 DEBUG : file1: active writers 1
2025/12/01 04:09:25 DEBUG : Still 1 writers active and 0 cache items in use, waiting 640ms
2025/12/01 04:09:25 DEBUG : Looking for writers
2025/12/01 04:09:25 DEBUG : file1: reading active writers
2025/12/01 04:09:25 DEBUG : file1: active writers 1
2025/12/01 04:09:25 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s
2025/12/01 04:09:26 DEBUG : Looking for writers
2025/12/01 04:09:26 DEBUG : file1: reading active writers
2025/12/01 04:09:26 DEBUG : file1: active writers 1
2025/12/01 04:09:26 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s
2025/12/01 04:09:27 DEBUG : Looking for writers
2025/12/01 04:09:27 DEBUG : file1: reading active writers
2025/12/01 04:09:27 DEBUG : file1: active writers 1
2025/12/01 04:09:27 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s
2025/12/01 04:09:28 DEBUG : Looking for writers
2025/12/01 04:09:28 DEBUG : file1: reading active writers
2025/12/01 04:09:28 DEBUG : file1: active writers 1
2025/12/01 04:09:28 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s
2025/12/01 04:09:29 DEBUG : Looking for writers
2025/12/01 04:09:29 DEBUG : file1: reading active writers
2025/12/01 04:09:29 DEBUG : file1: active writers 1
2025/12/01 04:09:29 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s
2025/12/01 04:09:30 DEBUG : Looking for writers
2025/12/01 04:09:30 DEBUG : file1: reading active writers
2025/12/01 04:09:30 DEBUG : file1: active writers 1
2025/12/01 04:09:30 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s
2025/12/01 04:09:31 DEBUG : Looking for writers
2025/12/01 04:09:31 DEBUG : file1: reading active writers
2025/12/01 04:09:31 DEBUG : file1: active writers 1
2025/12/01 04:09:31 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s
2025/12/01 04:09:32 DEBUG : Looking for writers
2025/12/01 04:09:32 DEBUG : file1: reading active writers
2025/12/01 04:09:32 DEBUG : file1: active writers 1
2025/12/01 04:09:32 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s
2025/12/01 04:09:33 DEBUG : Looking for writers
2025/12/01 04:09:33 DEBUG : file1: reading active writers
2025/12/01 04:09:33 DEBUG : file1: active writers 1
2025/12/01 04:09:33 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s
2025/12/01 04:09:34 DEBUG : Looking for writers
2025/12/01 04:09:34 DEBUG : file1: reading active writers
2025/12/01 04:09:34 DEBUG : file1: active writers 1
2025/12/01 04:09:34 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s
2025/12/01 04:09:35 DEBUG : Looking for writers
2025/12/01 04:09:35 DEBUG : file1: reading active writers
2025/12/01 04:09:35 DEBUG : file1: active writers 1
2025/12/01 04:09:35 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s
2025/12/01 04:09:36 DEBUG : Looking for writers
2025/12/01 04:09:36 DEBUG : file1: reading active writers
2025/12/01 04:09:36 DEBUG : file1: active writers 1
2025/12/01 04:09:36 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s
2025/12/01 04:09:37 DEBUG : Looking for writers
2025/12/01 04:09:37 DEBUG : file1: reading active writers
2025/12/01 04:09:37 DEBUG : file1: active writers 1
2025/12/01 04:09:37 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s
2025/12/01 04:09:38 DEBUG : Looking for writers
2025/12/01 04:09:38 DEBUG : file1: reading active writers
2025/12/01 04:09:38 DEBUG : file1: active writers 1
2025/12/01 04:09:38 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s
2025/12/01 04:09:39 DEBUG : Looking for writers
2025/12/01 04:09:39 DEBUG : file1: reading active writers
2025/12/01 04:09:39 DEBUG : file1: active writers 1
2025/12/01 04:09:39 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s
2025/12/01 04:09:40 DEBUG : Looking for writers
2025/12/01 04:09:40 DEBUG : file1: reading active writers
2025/12/01 04:09:40 DEBUG : file1: active writers 1
2025/12/01 04:09:40 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s
2025/12/01 04:09:41 DEBUG : Looking for writers
2025/12/01 04:09:41 DEBUG : file1: reading active writers
2025/12/01 04:09:41 DEBUG : file1: active writers 1
2025/12/01 04:09:41 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s
2025/12/01 04:09:42 DEBUG : Looking for writers
2025/12/01 04:09:42 DEBUG : file1: reading active writers
2025/12/01 04:09:42 DEBUG : file1: active writers 1
2025/12/01 04:09:42 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s
2025/12/01 04:09:43 DEBUG : Looking for writers
2025/12/01 04:09:43 DEBUG : file1: reading active writers
2025/12/01 04:09:43 DEBUG : file1: active writers 1
2025/12/01 04:09:43 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s
2025/12/01 04:09:44 DEBUG : Looking for writers
2025/12/01 04:09:44 DEBUG : file1: reading active writers
2025/12/01 04:09:44 DEBUG : file1: active writers 1
2025/12/01 04:09:44 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s
2025/12/01 04:09:45 DEBUG : Looking for writers
2025/12/01 04:09:45 DEBUG : file1: reading active writers
2025/12/01 04:09:45 DEBUG : file1: active writers 1
2025/12/01 04:09:45 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s
2025/12/01 04:09:46 DEBUG : Looking for writers
2025/12/01 04:09:46 DEBUG : file1: reading active writers
2025/12/01 04:09:46 DEBUG : file1: active writers 1
2025/12/01 04:09:46 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s
2025/12/01 04:09:47 DEBUG : Looking for writers
2025/12/01 04:09:47 DEBUG : file1: reading active writers
2025/12/01 04:09:47 DEBUG : file1: active writers 1
2025/12/01 04:09:47 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s
2025/12/01 04:09:48 DEBUG : Looking for writers
2025/12/01 04:09:48 DEBUG : file1: reading active writers
2025/12/01 04:09:48 DEBUG : file1: active writers 1
2025/12/01 04:09:48 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s
2025/12/01 04:09:49 DEBUG : Looking for writers
2025/12/01 04:09:49 DEBUG : file1: reading active writers
2025/12/01 04:09:49 DEBUG : file1: active writers 1
2025/12/01 04:09:49 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s
2025/12/01 04:09:50 DEBUG : Looking for writers
2025/12/01 04:09:50 DEBUG : file1: reading active writers
2025/12/01 04:09:50 DEBUG : file1: active writers 1
2025/12/01 04:09:50 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s
2025/12/01 04:09:51 DEBUG : Looking for writers
2025/12/01 04:09:51 DEBUG : file1: reading active writers
2025/12/01 04:09:51 DEBUG : file1: active writers 1
2025/12/01 04:09:51 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s
2025/12/01 04:09:52 DEBUG : Looking for writers
2025/12/01 04:09:52 DEBUG : file1: reading active writers
2025/12/01 04:09:52 DEBUG : file1: active writers 1
2025/12/01 04:09:52 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s
2025/12/01 04:09:53 DEBUG : Looking for writers
2025/12/01 04:09:53 DEBUG : file1: reading active writers
2025/12/01 04:09:53 DEBUG : file1: active writers 1
2025/12/01 04:09:53 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s
2025/12/01 04:09:54 ERROR : Exiting even though 1 writers active and 0 cache items in use after 30s
Cache: <nil>
2025/12/01 04:09:54 DEBUG : >WaitForWriters: 
--- SKIP: TestWriteFileModTimeWithOpenWriters (76.51s)
=== RUN   TestFileReadAtZeroLength
    run.go:185: Remote "FileLu root 'rclone-test-muwihes8fuxo'", Local "Local file system at /tmp/rclone2121884145", Modify Window "876000h0m0s"
2025/12/01 04:09:56 INFO  : FileLu root 'rclone-test-muwihes8fuxo': poll-interval is not supported by this remote
2025/12/01 04:09:56 NOTICE: FileLu root 'rclone-test-muwihes8fuxo': --vfs-cache-mode writes or full is recommended for this remote as it can't stream
2025/12/01 04:09:56 DEBUG : file1: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx
2025/12/01 04:09:57 DEBUG : file1: Open: flags=O_WRONLY|O_CREATE
2025/12/01 04:09:57 DEBUG : Added virtual directory entry vAddFile: "file1"
2025/12/01 04:09:57 DEBUG : file1: >Open: fd=file1 (w), err=<nil>
2025/12/01 04:09:57 DEBUG : file1: >OpenFile: fd=file1 (w), err=<nil>
2025/12/01 04:09:57 DEBUG : Added virtual directory entry vAddFile: "file1"
2025/12/01 04:09:57 DEBUG : FileLu root 'rclone-test-muwihes8fuxo': File to upload is small (0 bytes), uploading instead of streaming
2025/12/01 04:09:57 ERROR : file1: WriteFileHandle.New Rcat failed: can't upload empty files to this remote
2025/12/01 04:09:57 DEBUG : file1: Remove: 
2025/12/01 04:09:57 DEBUG : Added virtual directory entry vDel: "file1"
2025/12/01 04:09:57 DEBUG : file1: >Remove: err=<nil>
    write_test.go:357: skipping test: can't upload empty files to this remote
2025/12/01 04:09:57 DEBUG : WaitForWriters: timeout=30s
2025/12/01 04:09:57 DEBUG : Looking for writers
2025/12/01 04:09:57 DEBUG : >WaitForWriters: 
--- PASS: TestFileReadAtZeroLength (1.98s)
=== RUN   TestFileReadAtNonZeroLength
    run.go:185: Remote "FileLu root 'rclone-test-muwihes8fuxo'", Local "Local file system at /tmp/rclone2121884145", Modify Window "876000h0m0s"
2025/12/01 04:09:58 INFO  : FileLu root 'rclone-test-muwihes8fuxo': poll-interval is not supported by this remote
2025/12/01 04:09:58 NOTICE: FileLu root 'rclone-test-muwihes8fuxo': --vfs-cache-mode writes or full is recommended for this remote as it can't stream
2025/12/01 04:09:58 DEBUG : file1: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx
2025/12/01 04:09:59 DEBUG : file1: Open: flags=O_WRONLY|O_CREATE
2025/12/01 04:09:59 DEBUG : Added virtual directory entry vAddFile: "file1"
2025/12/01 04:09:59 DEBUG : file1: >Open: fd=file1 (w), err=<nil>
2025/12/01 04:09:59 DEBUG : file1: >OpenFile: fd=file1 (w), err=<nil>
2025/12/01 04:09:59 DEBUG : Added virtual directory entry vAddFile: "file1"
2025/12/01 04:09:59 DEBUG : FileLu root 'rclone-test-muwihes8fuxo': File to upload is small (100 bytes), uploading instead of streaming
2025/12/01 04:10:04 INFO  : FileLu root 'rclone-test-muwihes8fuxo': Put: Successfully uploaded new file "file1"
2025/12/01 04:10:04 DEBUG : file1: size = 100 OK
2025/12/01 04:10:04 NOTICE: FileLu root 'rclone-test-muwihes8fuxo': --checksum is in use but the source and destination have no hashes in common; falling back to --size-only
2025/12/01 04:10:04 DEBUG : file1: Size of src and dst objects identical
2025/12/01 04:10:04 DEBUG : Added virtual directory entry vAddFile: "file1"
2025/12/01 04:10:04 DEBUG : file1: OpenFile: flags=O_RDONLY, perm=----------
2025/12/01 04:10:04 DEBUG : file1: Open: flags=O_RDONLY
2025/12/01 04:10:04 DEBUG : file1: >Open: fd=file1 (r), err=<nil>
2025/12/01 04:10:04 DEBUG : file1: >OpenFile: fd=file1 (r), err=<nil>
2025/12/01 04:10:04 DEBUG : file1: ChunkedReader.openRange at 0 length 134217728
2025/12/01 04:10:08 DEBUG : file1: ChunkedReader.Read at 0 length 1024 chunkOffset 0 chunkSize 134217728
2025/12/01 04:10:08 DEBUG : WaitForWriters: timeout=30s
2025/12/01 04:10:08 DEBUG : Looking for writers
2025/12/01 04:10:08 DEBUG : file1: reading active writers
2025/12/01 04:10:08 DEBUG : >WaitForWriters: 
2025/12/01 04:10:09 INFO  : FileLu root 'rclone-test-muwihes8fuxo': Successfully deleted file: /rclone-test-muwihes8fuxo/file1
--- PASS: TestFileReadAtNonZeroLength (11.55s)
=== RUN   TestZipManyFiles
    run.go:185: Remote "FileLu root 'rclone-test-muwihes8fuxo'", Local "Local file system at /tmp/rclone2121884145", Modify Window "876000h0m0s"
2025/12/01 04:10:10 INFO  : FileLu root 'rclone-test-muwihes8fuxo': poll-interval is not supported by this remote
2025/12/01 04:10:10 NOTICE: FileLu root 'rclone-test-muwihes8fuxo': --vfs-cache-mode writes or full is recommended for this remote as it can't stream
2025/12/01 04:10:10 INFO  : FileLu root 'rclone-test-muwihes8fuxo': Successfully created folder "rclone-test-muwihes8fuxo/flat" with ID 1562403
2025/12/01 04:10:13 INFO  : FileLu root 'rclone-test-muwihes8fuxo': Put: Successfully uploaded new file "flat/f000.txt"
2025/12/01 04:10:17 INFO  : FileLu root 'rclone-test-muwihes8fuxo': Put: Successfully uploaded new file "flat/f001.txt"
2025/12/01 04:10:19 INFO  : FileLu root 'rclone-test-muwihes8fuxo': Put: Successfully uploaded new file "flat/f002.txt"
2025/12/01 04:10:22 INFO  : FileLu root 'rclone-test-muwihes8fuxo': Put: Successfully uploaded new file "flat/f003.txt"
2025/12/01 04:10:25 INFO  : FileLu root 'rclone-test-muwihes8fuxo': Put: Successfully uploaded new file "flat/f004.txt"
2025/12/01 04:10:27 DEBUG : flat/f000.txt: Open: flags=O_RDONLY
2025/12/01 04:10:27 DEBUG : flat/f000.txt: >Open: fd=flat/f000.txt (r), err=<nil>
2025/12/01 04:10:27 DEBUG : flat/f000.txt: Set virtual modtime to 2025-12-01 04:10:27.433315728 +0000 UTC m=+189.227959771
2025/12/01 04:10:27 DEBUG : flat/f000.txt: ChunkedReader.openRange at 0 length 134217728
2025/12/01 04:10:30 DEBUG : flat/f000.txt: ChunkedReader.Read at 0 length 32768 chunkOffset 0 chunkSize 134217728
2025/12/01 04:10:30 DEBUG : flat/f001.txt: Open: flags=O_RDONLY
2025/12/01 04:10:30 DEBUG : flat/f001.txt: >Open: fd=flat/f001.txt (r), err=<nil>
2025/12/01 04:10:30 DEBUG : flat/f001.txt: Set virtual modtime to 2025-12-01 04:10:27.433315517 +0000 UTC m=+189.227959560
2025/12/01 04:10:30 DEBUG : flat/f001.txt: ChunkedReader.openRange at 0 length 134217728
2025/12/01 04:10:34 DEBUG : flat/f001.txt: ChunkedReader.Read at 0 length 32768 chunkOffset 0 chunkSize 134217728
2025/12/01 04:10:34 DEBUG : flat/f002.txt: Open: flags=O_RDONLY
2025/12/01 04:10:34 DEBUG : flat/f002.txt: >Open: fd=flat/f002.txt (r), err=<nil>
2025/12/01 04:10:34 DEBUG : flat/f002.txt: Set virtual modtime to 2025-12-01 04:10:27.433315147 +0000 UTC m=+189.227959190
2025/12/01 04:10:34 DEBUG : flat/f002.txt: ChunkedReader.openRange at 0 length 134217728
2025/12/01 04:10:35 DEBUG : flat/f002.txt: ChunkedReader.Read at 0 length 32768 chunkOffset 0 chunkSize 134217728
2025/12/01 04:10:35 DEBUG : flat/f003.txt: Open: flags=O_RDONLY
2025/12/01 04:10:35 DEBUG : flat/f003.txt: >Open: fd=flat/f003.txt (r), err=<nil>
2025/12/01 04:10:35 DEBUG : flat/f003.txt: Set virtual modtime to 2025-12-01 04:10:27.433314826 +0000 UTC m=+189.227958869
2025/12/01 04:10:35 DEBUG : flat/f003.txt: ChunkedReader.openRange at 0 length 134217728
2025/12/01 04:10:36 DEBUG : flat/f003.txt: ChunkedReader.Read at 0 length 32768 chunkOffset 0 chunkSize 134217728
2025/12/01 04:10:36 DEBUG : flat/f004.txt: Open: flags=O_RDONLY
2025/12/01 04:10:36 DEBUG : flat/f004.txt: >Open: fd=flat/f004.txt (r), err=<nil>
2025/12/01 04:10:36 DEBUG : flat/f004.txt: Set virtual modtime to 2025-12-01 04:10:27.433313865 +0000 UTC m=+189.227957908
2025/12/01 04:10:36 DEBUG : flat/f004.txt: ChunkedReader.openRange at 0 length 134217728
2025/12/01 04:10:38 DEBUG : flat/f004.txt: ChunkedReader.Read at 0 length 32768 chunkOffset 0 chunkSize 134217728
2025/12/01 04:10:38 DEBUG : WaitForWriters: timeout=30s
2025/12/01 04:10:38 DEBUG : flat: Looking for writers
2025/12/01 04:10:38 DEBUG : f004.txt: reading active writers
2025/12/01 04:10:38 DEBUG : f000.txt: reading active writers
2025/12/01 04:10:38 DEBUG : f001.txt: reading active writers
2025/12/01 04:10:38 DEBUG : f002.txt: reading active writers
2025/12/01 04:10:38 DEBUG : f003.txt: reading active writers
2025/12/01 04:10:38 DEBUG : Looking for writers
2025/12/01 04:10:38 DEBUG : flat: reading active writers
2025/12/01 04:10:38 DEBUG : >WaitForWriters: 
2025/12/01 04:10:39 INFO  : FileLu root 'rclone-test-muwihes8fuxo': Successfully deleted file: /rclone-test-muwihes8fuxo/flat/f000.txt
2025/12/01 04:10:39 INFO  : FileLu root 'rclone-test-muwihes8fuxo': Successfully deleted file: /rclone-test-muwihes8fuxo/flat/f001.txt
2025/12/01 04:10:40 INFO  : FileLu root 'rclone-test-muwihes8fuxo': Successfully deleted file: /rclone-test-muwihes8fuxo/flat/f002.txt
2025/12/01 04:10:41 INFO  : FileLu root 'rclone-test-muwihes8fuxo': Successfully deleted file: /rclone-test-muwihes8fuxo/flat/f003.txt
2025/12/01 04:10:41 INFO  : FileLu root 'rclone-test-muwihes8fuxo': Successfully deleted file: /rclone-test-muwihes8fuxo/flat/f004.txt
2025/12/01 04:10:42 INFO  : FileLu root 'rclone-test-muwihes8fuxo': Rmdir: successfully deleted "/rclone-test-muwihes8fuxo/flat"
--- PASS: TestZipManyFiles (32.98s)
=== RUN   TestZipManySubDirs
    run.go:185: Remote "FileLu root 'rclone-test-muwihes8fuxo'", Local "Local file system at /tmp/rclone2121884145", Modify Window "876000h0m0s"
2025/12/01 04:10:43 INFO  : FileLu root 'rclone-test-muwihes8fuxo': poll-interval is not supported by this remote
2025/12/01 04:10:43 NOTICE: FileLu root 'rclone-test-muwihes8fuxo': --vfs-cache-mode writes or full is recommended for this remote as it can't stream
2025/12/01 04:10:44 INFO  : FileLu root 'rclone-test-muwihes8fuxo': Successfully created folder "rclone-test-muwihes8fuxo/a" with ID 1562449
2025/12/01 04:10:45 INFO  : FileLu root 'rclone-test-muwihes8fuxo': Put: Successfully uploaded new file "a/top.txt"
2025/12/01 04:10:46 INFO  : FileLu root 'rclone-test-muwihes8fuxo': Successfully created folder "rclone-test-muwihes8fuxo/a/b" with ID 1562456
2025/12/01 04:10:48 INFO  : FileLu root 'rclone-test-muwihes8fuxo': Put: Successfully uploaded new file "a/b/mid.txt"
2025/12/01 04:10:49 INFO  : FileLu root 'rclone-test-muwihes8fuxo': Successfully created folder "rclone-test-muwihes8fuxo/a/b/c" with ID 1562459
2025/12/01 04:10:52 INFO  : FileLu root 'rclone-test-muwihes8fuxo': Put: Successfully uploaded new file "a/b/c/deep.txt"
2025/12/01 04:10:54 DEBUG : a/b/c/deep.txt: Open: flags=O_RDONLY
2025/12/01 04:10:54 DEBUG : a/b/c/deep.txt: >Open: fd=a/b/c/deep.txt (r), err=<nil>
2025/12/01 04:10:54 DEBUG : a/b/c/deep.txt: Set virtual modtime to 2025-12-01 04:10:54.882457713 +0000 UTC m=+216.677101786
2025/12/01 04:10:54 DEBUG : a/b/c/deep.txt: ChunkedReader.openRange at 0 length 134217728
2025/12/01 04:10:57 DEBUG : a/b/c/deep.txt: ChunkedReader.Read at 0 length 32768 chunkOffset 0 chunkSize 134217728
2025/12/01 04:10:57 DEBUG : a/b/mid.txt: Open: flags=O_RDONLY
2025/12/01 04:10:57 DEBUG : a/b/mid.txt: >Open: fd=a/b/mid.txt (r), err=<nil>
2025/12/01 04:10:57 DEBUG : a/b/mid.txt: Set virtual modtime to 2025-12-01 04:10:54.059089774 +0000 UTC m=+215.853733807
2025/12/01 04:10:57 DEBUG : a/b/mid.txt: ChunkedReader.openRange at 0 length 134217728
2025/12/01 04:10:58 DEBUG : a/b/mid.txt: ChunkedReader.Read at 0 length 32768 chunkOffset 0 chunkSize 134217728
2025/12/01 04:10:58 DEBUG : a/top.txt: Open: flags=O_RDONLY
2025/12/01 04:10:58 DEBUG : a/top.txt: >Open: fd=a/top.txt (r), err=<nil>
2025/12/01 04:10:58 DEBUG : a/top.txt: Set virtual modtime to 2025-12-01 04:10:53.692595548 +0000 UTC m=+215.487239591
2025/12/01 04:10:58 DEBUG : a/top.txt: ChunkedReader.openRange at 0 length 134217728
2025/12/01 04:10:59 DEBUG : a/top.txt: ChunkedReader.Read at 0 length 32768 chunkOffset 0 chunkSize 134217728
2025/12/01 04:10:59 DEBUG : WaitForWriters: timeout=30s
2025/12/01 04:10:59 DEBUG : a/b/c: Looking for writers
2025/12/01 04:10:59 DEBUG : deep.txt: reading active writers
2025/12/01 04:10:59 DEBUG : a/b: Looking for writers
2025/12/01 04:10:59 DEBUG : c: reading active writers
2025/12/01 04:10:59 DEBUG : mid.txt: reading active writers
2025/12/01 04:10:59 DEBUG : a: Looking for writers
2025/12/01 04:10:59 DEBUG : b: reading active writers
2025/12/01 04:10:59 DEBUG : top.txt: reading active writers
2025/12/01 04:10:59 DEBUG : Looking for writers
2025/12/01 04:10:59 DEBUG : a: reading active writers
2025/12/01 04:10:59 DEBUG : >WaitForWriters: 
2025/12/01 04:11:00 INFO  : FileLu root 'rclone-test-muwihes8fuxo': Successfully deleted file: /rclone-test-muwihes8fuxo/a/top.txt
2025/12/01 04:11:08 INFO  : FileLu root 'rclone-test-muwihes8fuxo': Successfully deleted file: /rclone-test-muwihes8fuxo/a/b/mid.txt
2025/12/01 04:11:09 INFO  : FileLu root 'rclone-test-muwihes8fuxo': Successfully deleted file: /rclone-test-muwihes8fuxo/a/b/c/deep.txt
2025/12/01 04:11:11 INFO  : FileLu root 'rclone-test-muwihes8fuxo': Rmdir: successfully deleted "/rclone-test-muwihes8fuxo/a/b/c"
2025/12/01 04:11:13 INFO  : FileLu root 'rclone-test-muwihes8fuxo': Rmdir: successfully deleted "/rclone-test-muwihes8fuxo/a/b"
2025/12/01 04:11:14 INFO  : FileLu root 'rclone-test-muwihes8fuxo': Rmdir: successfully deleted "/rclone-test-muwihes8fuxo/a"
--- PASS: TestZipManySubDirs (32.22s)
=== RUN   TestZipLargeFiles
    run.go:185: Remote "FileLu root 'rclone-test-muwihes8fuxo'", Local "Local file system at /tmp/rclone2121884145", Modify Window "876000h0m0s"
2025/12/01 04:11:15 INFO  : FileLu root 'rclone-test-muwihes8fuxo': poll-interval is not supported by this remote
2025/12/01 04:11:15 NOTICE: FileLu root 'rclone-test-muwihes8fuxo': --vfs-cache-mode writes or full is recommended for this remote as it can't stream
2025/12/01 04:11:16 INFO  : FileLu root 'rclone-test-muwihes8fuxo': Successfully created folder "rclone-test-muwihes8fuxo/bigdir" with ID 1562496
2025/12/01 04:11:19 INFO  : FileLu root 'rclone-test-muwihes8fuxo': Put: Successfully uploaded new file "bigdir/big.bin"
2025/12/01 04:11:21 DEBUG : bigdir/big.bin: Open: flags=O_RDONLY
2025/12/01 04:11:21 DEBUG : bigdir/big.bin: >Open: fd=bigdir/big.bin (r), err=<nil>
2025/12/01 04:11:21 DEBUG : bigdir/big.bin: Set virtual modtime to 2025-12-01 04:11:21.45788683 +0000 UTC m=+243.252530903
2025/12/01 04:11:21 DEBUG : bigdir/big.bin: ChunkedReader.openRange at 0 length 134217728
2025/12/01 04:11:23 DEBUG : bigdir/big.bin: ChunkedReader.Read at 0 length 4096 chunkOffset 0 chunkSize 134217728
2025/12/01 04:11:23 DEBUG : bigdir/big.bin: ChunkedReader.Read at 4096 length 8192 chunkOffset 0 chunkSize 134217728
2025/12/01 04:11:23 DEBUG : bigdir/big.bin: ChunkedReader.Read at 12288 length 16384 chunkOffset 0 chunkSize 134217728
2025/12/01 04:11:23 DEBUG : bigdir/big.bin: ChunkedReader.Read at 28672 length 32768 chunkOffset 0 chunkSize 134217728
2025/12/01 04:11:23 DEBUG : bigdir/big.bin: ChunkedReader.Read at 61440 length 65536 chunkOffset 0 chunkSize 134217728
2025/12/01 04:11:23 DEBUG : bigdir/big.bin: ChunkedReader.Read at 126976 length 131072 chunkOffset 0 chunkSize 134217728
2025/12/01 04:11:23 DEBUG : bigdir/big.bin: ChunkedReader.Read at 258048 length 262144 chunkOffset 0 chunkSize 134217728
2025/12/01 04:11:23 DEBUG : bigdir/big.bin: ChunkedReader.Read at 520192 length 524288 chunkOffset 0 chunkSize 134217728
2025/12/01 04:11:23 DEBUG : bigdir/big.bin: ChunkedReader.Read at 1044480 length 1048576 chunkOffset 0 chunkSize 134217728
2025/12/01 04:11:23 DEBUG : bigdir/big.bin: ChunkedReader.Read at 2093056 length 1048576 chunkOffset 0 chunkSize 134217728
2025/12/01 04:11:23 DEBUG : bigdir/big.bin: ChunkedReader.Read at 3141632 length 1048576 chunkOffset 0 chunkSize 134217728
2025/12/01 04:11:23 DEBUG : bigdir/big.bin: ChunkedReader.Read at 4190208 length 1048576 chunkOffset 0 chunkSize 134217728
2025/12/01 04:11:23 DEBUG : bigdir/big.bin: ChunkedReader.Read at 5238784 length 1048576 chunkOffset 0 chunkSize 134217728
2025/12/01 04:11:24 DEBUG : WaitForWriters: timeout=30s
2025/12/01 04:11:24 DEBUG : bigdir: Looking for writers
2025/12/01 04:11:24 DEBUG : big.bin: reading active writers
2025/12/01 04:11:24 DEBUG : Looking for writers
2025/12/01 04:11:24 DEBUG : bigdir: reading active writers
2025/12/01 04:11:24 DEBUG : >WaitForWriters: 
2025/12/01 04:11:26 INFO  : FileLu root 'rclone-test-muwihes8fuxo': Successfully deleted file: /rclone-test-muwihes8fuxo/bigdir/big.bin
2025/12/01 04:11:29 INFO  : FileLu root 'rclone-test-muwihes8fuxo': Rmdir: successfully deleted "/rclone-test-muwihes8fuxo/bigdir"
--- PASS: TestZipLargeFiles (14.65s)
=== RUN   TestZipDirsInRoot
    run.go:185: Remote "FileLu root 'rclone-test-muwihes8fuxo'", Local "Local file system at /tmp/rclone2121884145", Modify Window "876000h0m0s"
2025/12/01 04:11:29 INFO  : FileLu root 'rclone-test-muwihes8fuxo': poll-interval is not supported by this remote
2025/12/01 04:11:29 NOTICE: FileLu root 'rclone-test-muwihes8fuxo': --vfs-cache-mode writes or full is recommended for this remote as it can't stream
2025/12/01 04:11:30 INFO  : FileLu root 'rclone-test-muwihes8fuxo': Successfully created folder "rclone-test-muwihes8fuxo/dir1" with ID 1562505
2025/12/01 04:11:33 INFO  : FileLu root 'rclone-test-muwihes8fuxo': Put: Successfully uploaded new file "dir1/a.txt"
2025/12/01 04:11:35 INFO  : FileLu root 'rclone-test-muwihes8fuxo': Successfully created folder "rclone-test-muwihes8fuxo/dir2" with ID 1562508
2025/12/01 04:11:37 INFO  : FileLu root 'rclone-test-muwihes8fuxo': Put: Successfully uploaded new file "dir2/b.txt"
2025/12/01 04:11:38 INFO  : FileLu root 'rclone-test-muwihes8fuxo': Successfully created folder "rclone-test-muwihes8fuxo/dir3" with ID 1562513
2025/12/01 04:11:40 INFO  : FileLu root 'rclone-test-muwihes8fuxo': Put: Successfully uploaded new file "dir3/c.txt"
2025/12/01 04:11:41 DEBUG : dir1/a.txt: Open: flags=O_RDONLY
2025/12/01 04:11:41 DEBUG : dir1/a.txt: >Open: fd=dir1/a.txt (r), err=<nil>
2025/12/01 04:11:41 DEBUG : dir1/a.txt: Set virtual modtime to 2025-12-01 04:11:41.980174746 +0000 UTC m=+263.774818779
2025/12/01 04:11:41 DEBUG : dir1/a.txt: ChunkedReader.openRange at 0 length 134217728
2025/12/01 04:11:43 DEBUG : dir1/a.txt: ChunkedReader.Read at 0 length 32768 chunkOffset 0 chunkSize 134217728
2025/12/01 04:11:43 DEBUG : dir2/b.txt: Open: flags=O_RDONLY
2025/12/01 04:11:43 DEBUG : dir2/b.txt: >Open: fd=dir2/b.txt (r), err=<nil>
2025/12/01 04:11:43 DEBUG : dir2/b.txt: Set virtual modtime to 2025-12-01 04:11:43.534879895 +0000 UTC m=+265.329523958
2025/12/01 04:11:43 DEBUG : dir2/b.txt: ChunkedReader.openRange at 0 length 134217728
2025/12/01 04:11:44 DEBUG : dir2/b.txt: ChunkedReader.Read at 0 length 32768 chunkOffset 0 chunkSize 134217728
2025/12/01 04:11:45 DEBUG : dir3/c.txt: Open: flags=O_RDONLY
2025/12/01 04:11:45 DEBUG : dir3/c.txt: >Open: fd=dir3/c.txt (r), err=<nil>
2025/12/01 04:11:45 DEBUG : dir3/c.txt: Set virtual modtime to 2025-12-01 04:11:45.851174183 +0000 UTC m=+267.645818256
2025/12/01 04:11:45 DEBUG : dir3/c.txt: ChunkedReader.openRange at 0 length 134217728
2025/12/01 04:11:47 DEBUG : dir3/c.txt: ChunkedReader.Read at 0 length 32768 chunkOffset 0 chunkSize 134217728
2025/12/01 04:11:47 DEBUG : WaitForWriters: timeout=30s
2025/12/01 04:11:47 DEBUG : dir1: Looking for writers
2025/12/01 04:11:47 DEBUG : a.txt: reading active writers
2025/12/01 04:11:47 DEBUG : dir2: Looking for writers
2025/12/01 04:11:47 DEBUG : b.txt: reading active writers
2025/12/01 04:11:47 DEBUG : dir3: Looking for writers
2025/12/01 04:11:47 DEBUG : c.txt: reading active writers
2025/12/01 04:11:47 DEBUG : Looking for writers
2025/12/01 04:11:47 DEBUG : dir1: reading active writers
2025/12/01 04:11:47 DEBUG : dir2: reading active writers
2025/12/01 04:11:47 DEBUG : dir3: reading active writers
2025/12/01 04:11:47 DEBUG : >WaitForWriters: 
2025/12/01 04:11:50 INFO  : FileLu root 'rclone-test-muwihes8fuxo': Successfully deleted file: /rclone-test-muwihes8fuxo/dir2/b.txt
2025/12/01 04:11:51 INFO  : FileLu root 'rclone-test-muwihes8fuxo': Successfully deleted file: /rclone-test-muwihes8fuxo/dir3/c.txt
2025/12/01 04:11:51 INFO  : FileLu root 'rclone-test-muwihes8fuxo': Successfully deleted file: /rclone-test-muwihes8fuxo/dir1/a.txt
2025/12/01 04:11:53 INFO  : FileLu root 'rclone-test-muwihes8fuxo': Rmdir: successfully deleted "/rclone-test-muwihes8fuxo/dir3"
2025/12/01 04:11:54 INFO  : FileLu root 'rclone-test-muwihes8fuxo': Rmdir: successfully deleted "/rclone-test-muwihes8fuxo/dir2"
2025/12/01 04:11:56 INFO  : FileLu root 'rclone-test-muwihes8fuxo': Rmdir: successfully deleted "/rclone-test-muwihes8fuxo/dir1"
--- PASS: TestZipDirsInRoot (26.95s)
FAIL
2025/12/01 04:11:56 DEBUG : FileLu root 'rclone-test-muwihes8fuxo': Purge remote
2025/12/01 04:11:58 INFO  : FileLu root 'rclone-test-muwihes8fuxo': Rmdir: successfully deleted "/rclone-test-muwihes8fuxo"
"./vfs.test -test.v -test.timeout 1h0m0s -remote TestFileLu: -verbose -test.run '^(TestFileReadAtNonZeroLength|TestFileReadAtZeroLength|TestVFSIsMetadataFile|TestVFSMkdir|TestVFSMkdirAll|TestVFSOpenFile|TestVFSRename|TestVFSStatfs|TestWriteFileHandleFlush|TestWriteFileHandleMethods|TestWriteFileHandleRelease|TestWriteFileHandleWriteAt|TestWriteFileModTimeWithOpenWriters|TestZipDirsInRoot|TestZipLargeFiles|TestZipManyFiles|TestZipManySubDirs)$'" - Finished ERROR in 4m39.879752049s (try 3/5): exit status 1: Failed [TestVFSOpenFile TestVFSRename TestVFSStatfs TestVFSMkdir TestVFSMkdirAll TestVFSIsMetadataFile TestWriteFileHandleMethods TestWriteFileHandleWriteAt TestWriteFileHandleFlush TestWriteFileHandleRelease]