"./vfs.test -test.v -test.timeout 1h0m0s -remote TestPcloud: -verbose -test.run '^(TestFileOpenRead|TestFileOpenWrite)$|^TestFileSetModTime$/^cache=full,open=true,write=true$'" - Starting (try 2/5) 2025/12/16 04:04:44 DEBUG : Creating backend with remote "TestPcloud:rclone-test-tusozaz9habe" 2025/12/16 04:04:44 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/12/16 04:04:46 DEBUG : Creating backend with remote "/tmp/rclone4285361859" === RUN TestFileSetModTime === RUN TestFileSetModTime/cache=full,open=true,write=true run.go:185: Remote "pcloud root 'rclone-test-tusozaz9habe'", Local "Local file system at /tmp/rclone4285361859", Modify Window "1s" 2025/12/16 04:04:46 INFO : pcloud root 'rclone-test-tusozaz9habe': poll-interval is not supported by this remote 2025/12/16 04:04:46 DEBUG : pcloud root 'rclone-test-tusozaz9habe': vfs cache: root is "/home/rclone/.cache/rclone" 2025/12/16 04:04:46 DEBUG : pcloud root 'rclone-test-tusozaz9habe': vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestPcloud/rclone-test-tusozaz9habe" 2025/12/16 04:04:46 DEBUG : pcloud root 'rclone-test-tusozaz9habe': vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestPcloud/rclone-test-tusozaz9habe" 2025/12/16 04:04:46 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestPcloud/rclone-test-tusozaz9habe" 2025/12/16 04:04:46 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2025/12/16 04:04:46 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestPcloud/rclone-test-tusozaz9habe" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfs/TestPcloud/rclone-test-tusozaz9habe" 2025/12/16 04:04:46 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestPcloud/rclone-test-tusozaz9habe" 2025/12/16 04:04:46 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2025/12/16 04:04:46 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestPcloud/rclone-test-tusozaz9habe" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfsMeta/TestPcloud/rclone-test-tusozaz9habe" 2025/12/16 04:04:46 INFO : pcloud root 'rclone-test-tusozaz9habe': vfs cache: cleaned: objects 0 (was 0) in use 0, to upload 0, uploading 0, total size 0 (was 0) write_test.go:299: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/vfs/write_test.go:299 /usr/local/go/src/sync/once.go:78 /usr/local/go/src/sync/once.go:69 /home/rclone/go/src/github.com/rclone/rclone/vfs/write_test.go:295 /home/rclone/go/src/github.com/rclone/rclone/vfs/file_test.go:96 /home/rclone/go/src/github.com/rclone/rclone/vfs/file_test.go:159 Error: Received unexpected error: object not found Test: TestFileSetModTime/cache=full,open=true,write=true 2025/12/16 04:04:48 DEBUG : WaitForWriters: timeout=30s 2025/12/16 04:04:48 DEBUG : dir: Looking for writers 2025/12/16 04:04:48 DEBUG : file1: reading active writers 2025/12/16 04:04:48 DEBUG : Looking for writers 2025/12/16 04:04:48 DEBUG : dir: reading active writers 2025/12/16 04:04:48 DEBUG : >WaitForWriters: 2025/12/16 04:04:48 DEBUG : pcloud root 'rclone-test-tusozaz9habe': vfs cache: cleaner exiting fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178 /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:299 /usr/local/go/src/sync/once.go:78 /usr/local/go/src/sync/once.go:69 /home/rclone/go/src/github.com/rclone/rclone/vfs/write_test.go:295 /home/rclone/go/src/github.com/rclone/rclone/vfs/file_test.go:96 /home/rclone/go/src/github.com/rclone/rclone/vfs/file_test.go:159 /usr/local/go/src/runtime/asm_amd64.s:1700 Error: Should be true Test: TestFileSetModTime/cache=full,open=true,write=true Messages: listing wrong, want got time_test (5) fstest.go:191: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178 /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:299 /usr/local/go/src/sync/once.go:78 /usr/local/go/src/sync/once.go:69 /home/rclone/go/src/github.com/rclone/rclone/vfs/write_test.go:295 /home/rclone/go/src/github.com/rclone/rclone/vfs/file_test.go:96 /home/rclone/go/src/github.com/rclone/rclone/vfs/file_test.go:159 Error: Should be true Test: TestFileSetModTime/cache=full,open=true,write=true Messages: Unexpected file "time_test" --- FAIL: TestFileSetModTime (10.50s) --- FAIL: TestFileSetModTime/cache=full,open=true,write=true (10.50s) === RUN TestFileOpenRead run.go:185: Remote "pcloud root 'rclone-test-tusozaz9habe'", Local "Local file system at /tmp/rclone4285361859", Modify Window "1s" 2025/12/16 04:04:57 INFO : pcloud root 'rclone-test-tusozaz9habe': poll-interval is not supported by this remote 2025/12/16 04:04:57 NOTICE: pcloud root 'rclone-test-tusozaz9habe': --vfs-cache-mode writes or full is recommended for this remote as it can't stream fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/vfs/file_test.go:28 /home/rclone/go/src/github.com/rclone/rclone/vfs/file_test.go:178 /usr/local/go/src/runtime/asm_amd64.s:1700 Error: Should be true Test: TestFileOpenRead Messages: listing wrong, want dir/file1 (14) got dir/file1 (14), time_test (5) fstest.go:191: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/vfs/file_test.go:28 /home/rclone/go/src/github.com/rclone/rclone/vfs/file_test.go:178 Error: Should be true Test: TestFileOpenRead Messages: Unexpected file "time_test" 2025/12/16 04:05:07 DEBUG : dir/file1: Open: flags=O_RDONLY 2025/12/16 04:05:07 DEBUG : dir/file1: >Open: fd=dir/file1 (r), err= 2025/12/16 04:05:07 DEBUG : dir/file1: ChunkedReader.openRange at 0 length 134217728 2025/12/16 04:05:08 DEBUG : Couldn't parse Date: from server edef3.pcloud.com: "Tue, 16 Dec 2025 04:05:08 +0000": parsing time "Tue, 16 Dec 2025 04:05:08 +0000" as "Mon Jan _2 15:04:05 2006": cannot parse ", 16 Dec 2025 04:05:08 +0000" as " " 2025/12/16 04:05:08 DEBUG : dir/file1: ChunkedReader.Read at 0 length 512 chunkOffset 0 chunkSize 134217728 2025/12/16 04:05:08 DEBUG : WaitForWriters: timeout=30s 2025/12/16 04:05:08 DEBUG : dir: Looking for writers 2025/12/16 04:05:08 DEBUG : file1: reading active writers 2025/12/16 04:05:08 DEBUG : Looking for writers 2025/12/16 04:05:08 DEBUG : dir: reading active writers 2025/12/16 04:05:08 DEBUG : time_test: reading active writers 2025/12/16 04:05:08 DEBUG : >WaitForWriters: fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:292: Sleeping for 6s just to make sure --- FAIL: TestFileOpenRead (19.02s) === RUN TestFileOpenWrite run.go:185: Remote "pcloud root 'rclone-test-tusozaz9habe'", Local "Local file system at /tmp/rclone4285361859", Modify Window "1s" 2025/12/16 04:05:16 INFO : pcloud root 'rclone-test-tusozaz9habe': poll-interval is not supported by this remote 2025/12/16 04:05:16 NOTICE: pcloud root 'rclone-test-tusozaz9habe': --vfs-cache-mode writes or full is recommended for this remote as it can't stream fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:292: Sleeping for 6s just to make sure 2025/12/16 04:05:25 DEBUG : dir: Added virtual directory entry vAddFile: "file1" 2025/12/16 04:05:25 DEBUG : pcloud root 'rclone-test-tusozaz9habe': File to upload is small (25 bytes), uploading instead of streaming 2025/12/16 04:05:25 DEBUG : dir/file1: size = 25 OK 2025/12/16 04:05:25 DEBUG : dir/file1: sha1 = f1f3d7db56f36d9a5a912b01803c2176a972b9b0 OK 2025/12/16 04:05:25 DEBUG : dir/file1: Size and sha1 of src and dst objects identical 2025/12/16 04:05:25 DEBUG : dir: Added virtual directory entry vAddFile: "file1" 2025/12/16 04:05:25 DEBUG : WaitForWriters: timeout=30s 2025/12/16 04:05:25 DEBUG : dir: Looking for writers 2025/12/16 04:05:25 DEBUG : file1: reading active writers 2025/12/16 04:05:25 DEBUG : Looking for writers 2025/12/16 04:05:25 DEBUG : dir: reading active writers 2025/12/16 04:05:25 DEBUG : >WaitForWriters: fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:292: Sleeping for 6s just to make sure --- PASS: TestFileOpenWrite (18.18s) FAIL 2025/12/16 04:05:34 DEBUG : pcloud root 'rclone-test-tusozaz9habe': Purge remote "./vfs.test -test.v -test.timeout 1h0m0s -remote TestPcloud: -verbose -test.run '^(TestFileOpenRead|TestFileOpenWrite)$|^TestFileSetModTime$/^cache=full,open=true,write=true$'" - Finished ERROR in 50.498329931s (try 2/5): exit status 1: Failed [TestFileSetModTime/cache=full,open=true,write=true TestFileOpenRead]