"./vfs.test -test.v -test.timeout 1h0m0s -remote TestPcloud: -verbose -test.run '^(TestWriteFileHandleRelease|TestWriteFileModTimeWithOpenWriters)$|^TestFileSetModTime$/^(cache=off,open=true,write=false|cache=off,open=true,write=true)$'" - Starting (try 2/5)
2024/09/15 02:40:55 DEBUG : Creating backend with remote "TestPcloud:rclone-test-fawarip3siho"
2024/09/15 02:40:55 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2024/09/15 02:40:55 DEBUG : Creating backend with remote "/tmp/rclone3536134616"
=== RUN   TestFileSetModTime
=== RUN   TestFileSetModTime/cache=off,open=true,write=false
    run.go:180: Remote "pcloud root 'rclone-test-fawarip3siho'", Local "Local file system at /tmp/rclone3536134616", Modify Window "1s"
2024/09/15 02:40:55 INFO  : pcloud root 'rclone-test-fawarip3siho': poll-interval is not supported by this remote
2024/09/15 02:40:55 NOTICE: pcloud root 'rclone-test-fawarip3siho': --vfs-cache-mode writes or full is recommended for this remote as it can't stream
2024/09/15 02:40:56 DEBUG : Can set mod time: true
2024/09/15 02:40:56 DEBUG : dir/file1: Open: flags=O_WRONLY|O_TRUNC
2024/09/15 02:40:56 DEBUG : dir/file1: >Open: fd=dir/file1 (w), err=<nil>
2024/09/15 02:40:56 DEBUG : dir: Added virtual directory entry vAddFile: "file1"
2024/09/15 02:40:56 DEBUG : pcloud root 'rclone-test-fawarip3siho': File to upload is small (0 bytes), uploading instead of streaming
2024/09/15 02:40:57 DEBUG : dir/file1: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2024/09/15 02:40:57 DEBUG : dir/file1: Size and sha1 of src and dst objects identical
2024/09/15 02:40:57 DEBUG : dir/file1: Applied pending mod time 2011-12-25 12:59:59.123456789 +0000 UTC OK
2024/09/15 02:40:57 DEBUG : dir: Added virtual directory entry vAddFile: "file1"
2024/09/15 02:40:57 DEBUG : WaitForWriters: timeout=30s
2024/09/15 02:40:57 DEBUG : dir: Looking for writers
2024/09/15 02:40:57 DEBUG : file1: reading active writers
2024/09/15 02:40:57 DEBUG : : Looking for writers
2024/09/15 02:40:57 DEBUG : dir: reading active writers
2024/09/15 02:40:57 DEBUG : >WaitForWriters: 
2024/09/15 02:40:57 DEBUG : WaitForWriters: timeout=30s
2024/09/15 02:40:57 DEBUG : dir: Looking for writers
2024/09/15 02:40:57 DEBUG : file1: reading active writers
2024/09/15 02:40:57 DEBUG : : Looking for writers
2024/09/15 02:40:57 DEBUG : dir: reading active writers
2024/09/15 02:40:57 DEBUG : >WaitForWriters: 
    fstest.go:292: Sleeping for 1s for list eventual consistency: 1/3
    fstest.go:295: Flushing the directory cache
    fstest.go:286: Sleeping for 6s just to make sure
=== RUN   TestFileSetModTime/cache=off,open=true,write=true
    run.go:180: Remote "pcloud root 'rclone-test-fawarip3siho'", Local "Local file system at /tmp/rclone3536134616", Modify Window "1s"
2024/09/15 02:41:04 INFO  : pcloud root 'rclone-test-fawarip3siho': poll-interval is not supported by this remote
2024/09/15 02:41:04 NOTICE: pcloud root 'rclone-test-fawarip3siho': --vfs-cache-mode writes or full is recommended for this remote as it can't stream
2024/09/15 02:41:05 DEBUG : dir/file1: Open: flags=O_WRONLY|O_TRUNC
2024/09/15 02:41:05 DEBUG : dir/file1: >Open: fd=dir/file1 (w), err=<nil>
2024/09/15 02:41:05 DEBUG : dir: Added virtual directory entry vAddFile: "file1"
2024/09/15 02:41:05 DEBUG : pcloud root 'rclone-test-fawarip3siho': File to upload is small (5 bytes), uploading instead of streaming
2024/09/15 02:41:05 DEBUG : dir/file1: sha1 = aaf4c61ddcc5e8a2dabede0f3b482cd9aea9434d OK
2024/09/15 02:41:05 DEBUG : dir/file1: Size and sha1 of src and dst objects identical
2024/09/15 02:41:05 DEBUG : dir/file1: Applied pending mod time 2011-12-25 12:59:59.123456789 +0000 UTC OK
2024/09/15 02:41:05 DEBUG : dir: Added virtual directory entry vAddFile: "file1"
2024/09/15 02:41:05 DEBUG : WaitForWriters: timeout=30s
2024/09/15 02:41:05 DEBUG : dir: Looking for writers
2024/09/15 02:41:05 DEBUG : file1: reading active writers
2024/09/15 02:41:05 DEBUG : : Looking for writers
2024/09/15 02:41:05 DEBUG : dir: reading active writers
2024/09/15 02:41:05 DEBUG : >WaitForWriters: 
    fstest.go:292: Sleeping for 1s for list eventual consistency: 1/3
    fstest.go:295: Flushing the directory cache
    fstest.go:292: Sleeping for 2s for list eventual consistency: 2/3
    fstest.go:295: Flushing the directory cache
    fstest.go:292: Sleeping for 4s for list eventual consistency: 3/3
    fstest.go:295: Flushing the directory cache
    fstest.go:299: 
        	Error Trace:	/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:299
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:344
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:340
        	            				/home/rclone/go/src/github.com/rclone/rclone/vfs/file_test.go:139
        	            				/home/rclone/go/src/github.com/rclone/rclone/vfs/file_test.go:159
        	Error:      	Should be true
        	Test:       	TestFileSetModTime/cache=off,open=true,write=true
        	Messages:   	listing wrong, want
        	            	  dir/file1 (5) got
        	            	  dir/file1 (14)
    fstest.go:137: 
        	Error Trace:	/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:137
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:143
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:189
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:302
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:344
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:340
        	            				/home/rclone/go/src/github.com/rclone/rclone/vfs/file_test.go:139
        	            				/home/rclone/go/src/github.com/rclone/rclone/vfs/file_test.go:159
        	Error:      	Should be true
        	Test:       	TestFileSetModTime/cache=off,open=true,write=true
        	Messages:   	pcloud root 'rclone-test-fawarip3siho'/dir/file1: sha1 hash incorrect - expecting "aaf4c61ddcc5e8a2dabede0f3b482cd9aea9434d" got "a379624177abc4679cafafa8eae1d73e1478aaa6"
    fstest.go:137: 
        	Error Trace:	/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:137
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:143
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:189
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:302
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:344
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:340
        	            				/home/rclone/go/src/github.com/rclone/rclone/vfs/file_test.go:139
        	            				/home/rclone/go/src/github.com/rclone/rclone/vfs/file_test.go:159
        	Error:      	Should be true
        	Test:       	TestFileSetModTime/cache=off,open=true,write=true
        	Messages:   	pcloud root 'rclone-test-fawarip3siho'/dir/file1: sha256 hash incorrect - expecting "2cf24dba5fb0a30e26e83b2ac5b9e29e1b161e5c1fa7425e73043362938b9824" got "226e7cfa701fb8ba542d42e0f8bd3090cbbcc9f54d834f361c0ab8c3f4846b72"
    fstest.go:144: 
        	Error Trace:	/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:144
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:189
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:302
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:344
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:340
        	            				/home/rclone/go/src/github.com/rclone/rclone/vfs/file_test.go:139
        	            				/home/rclone/go/src/github.com/rclone/rclone/vfs/file_test.go:159
        	Error:      	Not equal: 
        	            	expected: 5
        	            	actual  : 14
        	Test:       	TestFileSetModTime/cache=off,open=true,write=true
        	Messages:   	dir/file1: size incorrect file=5 vs obj=14
2024/09/15 02:41:13 DEBUG : WaitForWriters: timeout=30s
2024/09/15 02:41:13 DEBUG : dir: Looking for writers
2024/09/15 02:41:13 DEBUG : file1: reading active writers
2024/09/15 02:41:13 DEBUG : : Looking for writers
2024/09/15 02:41:13 DEBUG : dir: reading active writers
2024/09/15 02:41:13 DEBUG : >WaitForWriters: 
--- FAIL: TestFileSetModTime (18.15s)
    --- PASS: TestFileSetModTime/cache=off,open=true,write=false (9.15s)
    --- FAIL: TestFileSetModTime/cache=off,open=true,write=true (9.00s)
=== RUN   TestWriteFileHandleRelease
    run.go:180: Remote "pcloud root 'rclone-test-fawarip3siho'", Local "Local file system at /tmp/rclone3536134616", Modify Window "1s"
2024/09/15 02:41:13 INFO  : pcloud root 'rclone-test-fawarip3siho': poll-interval is not supported by this remote
2024/09/15 02:41:13 NOTICE: pcloud root 'rclone-test-fawarip3siho': --vfs-cache-mode writes or full is recommended for this remote as it can't stream
2024/09/15 02:41:13 DEBUG : file1: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx
2024/09/15 02:41:14 DEBUG : file1: Open: flags=O_WRONLY|O_CREATE
2024/09/15 02:41:14 DEBUG : : Added virtual directory entry vAddFile: "file1"
2024/09/15 02:41:14 DEBUG : file1: >Open: fd=file1 (w), err=<nil>
2024/09/15 02:41:14 DEBUG : file1: >OpenFile: fd=file1 (w), err=<nil>
2024/09/15 02:41:14 DEBUG : file1: WriteFileHandle.Release closing
2024/09/15 02:41:14 DEBUG : : Added virtual directory entry vAddFile: "file1"
2024/09/15 02:41:14 DEBUG : pcloud root 'rclone-test-fawarip3siho': File to upload is small (0 bytes), uploading instead of streaming
2024/09/15 02:41:14 DEBUG : file1: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2024/09/15 02:41:14 DEBUG : file1: Size and sha1 of src and dst objects identical
2024/09/15 02:41:14 DEBUG : : Added virtual directory entry vAddFile: "file1"
2024/09/15 02:41:14 DEBUG : file1: WriteFileHandle.Release nothing to do
2024/09/15 02:41:14 DEBUG : WaitForWriters: timeout=30s
2024/09/15 02:41:14 DEBUG : : Looking for writers
2024/09/15 02:41:14 DEBUG : file1: reading active writers
2024/09/15 02:41:14 DEBUG : >WaitForWriters: 
--- PASS: TestWriteFileHandleRelease (0.63s)
=== RUN   TestWriteFileModTimeWithOpenWriters
    run.go:180: Remote "pcloud root 'rclone-test-fawarip3siho'", Local "Local file system at /tmp/rclone3536134616", Modify Window "1s"
2024/09/15 02:41:14 INFO  : pcloud root 'rclone-test-fawarip3siho': poll-interval is not supported by this remote
2024/09/15 02:41:14 NOTICE: pcloud root 'rclone-test-fawarip3siho': --vfs-cache-mode writes or full is recommended for this remote as it can't stream
2024/09/15 02:41:14 DEBUG : file1: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx
2024/09/15 02:41:14 DEBUG : file1: Open: flags=O_WRONLY|O_CREATE
2024/09/15 02:41:14 DEBUG : : Added virtual directory entry vAddFile: "file1"
2024/09/15 02:41:14 DEBUG : file1: >Open: fd=file1 (w), err=<nil>
2024/09/15 02:41:14 DEBUG : file1: >OpenFile: fd=file1 (w), err=<nil>
2024/09/15 02:41:14 DEBUG : : Added virtual directory entry vAddFile: "file1"
2024/09/15 02:41:14 DEBUG : pcloud root 'rclone-test-fawarip3siho': File to upload is small (2 bytes), uploading instead of streaming
2024/09/15 02:41:14 DEBUG : file1: sha1 = c22b5f9178342609428d6f51b2c5af4c0bde6a42 OK
2024/09/15 02:41:14 DEBUG : file1: Size and sha1 of src and dst objects identical
2024/09/15 02:41:14 DEBUG : file1: Applied pending mod time 2012-11-18 17:32:31 +0000 UTC OK
2024/09/15 02:41:14 DEBUG : : Added virtual directory entry vAddFile: "file1"
2024/09/15 02:41:14 DEBUG : WaitForWriters: timeout=30s
2024/09/15 02:41:14 DEBUG : : Looking for writers
2024/09/15 02:41:14 DEBUG : file1: reading active writers
2024/09/15 02:41:14 DEBUG : >WaitForWriters: 
--- PASS: TestWriteFileModTimeWithOpenWriters (0.48s)
FAIL
2024/09/15 02:41:15 DEBUG : pcloud root 'rclone-test-fawarip3siho': Purge remote
"./vfs.test -test.v -test.timeout 1h0m0s -remote TestPcloud: -verbose -test.run '^(TestWriteFileHandleRelease|TestWriteFileModTimeWithOpenWriters)$|^TestFileSetModTime$/^(cache=off,open=true,write=false|cache=off,open=true,write=true)$'" - Finished ERROR in 19.737870753s (try 2/5): exit status 1: Failed [TestFileSetModTime/cache=off,open=true,write=true]