"./vfs.test -test.v -test.timeout 1h0m0s -remote TestHubic: -verbose -test.run '^(TestDirCreate|TestDirForgetPath|TestDirMkdir|TestDirMkdirSub|TestDirOpen|TestDirReadDirAll|TestDirRemove|TestDirRemoveAll|TestDirRemoveName|TestDirRename|TestDirSetModTime|TestDirStat|TestDirWalk|TestFileReadAtNonZeroLength|TestFileReadAtZeroLength|TestRcGetVFS|TestReadFileHandleFlush|TestReadFileHandleMethods|TestReadFileHandleReadAt|TestReadFileHandleRelease|TestReadFileHandleSeek|TestVFSNew|TestVFSOpenFile|TestVFSRename|TestVFSStat|TestVFSStatParent|TestWriteFileHandleFlush|TestWriteFileHandleMethods|TestWriteFileHandleWriteAt|TestWriteFileModTimeWithOpenWriters)$'" - Starting (try 2/5) 2022/03/02 06:19:55 DEBUG : Creating backend with remote "TestHubic:rclone-test-kufaman1vajunuk6nuxoyep0" 2022/03/02 06:19:55 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2022/03/02 06:19:56 DEBUG : Hubic: Got swift credentials (expiry 2022-03-03 06:22:44 +0100 +0100 in 23h2m47.905828789s) 2022/03/02 06:19:56 DEBUG : Creating backend with remote "/tmp/rclone3515240406" === RUN TestDirForgetPath run.go:181: Remote "Hubic Swift container rclone-test-kufaman1vajunuk6nuxoyep0", Local "Local file system at /tmp/rclone3515240406", Modify Window "1ns" 2022/03/02 06:19:56 INFO : Hubic Swift container rclone-test-kufaman1vajunuk6nuxoyep0: poll-interval is not supported by this remote 2022/03/02 06:19:56 INFO : Swift container rclone-test-kufaman1vajunuk6nuxoyep0: Container "rclone-test-kufaman1vajunuk6nuxoyep0" created 2022/03/02 06:19:58 DEBUG : : ForgetPath: relativePath="dir/notfound", type=1 2022/03/02 06:19:58 DEBUG : dir: invalidating directory cache 2022/03/02 06:19:58 DEBUG : : >ForgetPath: 2022/03/02 06:19:58 DEBUG : : ForgetPath: relativePath="dir", type=0 2022/03/02 06:19:58 DEBUG : : invalidating directory cache 2022/03/02 06:19:58 DEBUG : dir: forgetting directory cache 2022/03/02 06:19:58 DEBUG : : >ForgetPath: 2022/03/02 06:19:58 DEBUG : : ForgetPath: relativePath="not/in/cache", type=0 2022/03/02 06:19:58 DEBUG : : >ForgetPath: 2022/03/02 06:19:58 DEBUG : WaitForWriters: timeout=30s 2022/03/02 06:19:58 DEBUG : dir: Looking for writers 2022/03/02 06:19:58 DEBUG : : Looking for writers 2022/03/02 06:19:58 DEBUG : dir: reading active writers 2022/03/02 06:19:58 DEBUG : >WaitForWriters: --- PASS: TestDirForgetPath (3.72s) === RUN TestDirWalk run.go:181: Remote "Hubic Swift container rclone-test-kufaman1vajunuk6nuxoyep0", Local "Local file system at /tmp/rclone3515240406", Modify Window "1ns" 2022/03/02 06:19:59 INFO : Hubic Swift container rclone-test-kufaman1vajunuk6nuxoyep0: poll-interval is not supported by this remote 2022/03/02 06:20:03 DEBUG : : forgetting directory cache 2022/03/02 06:20:03 DEBUG : dir: forgetting directory cache 2022/03/02 06:20:04 DEBUG : WaitForWriters: timeout=30s 2022/03/02 06:20:04 DEBUG : dir: Looking for writers 2022/03/02 06:20:04 DEBUG : fil/a/b: Looking for writers 2022/03/02 06:20:04 DEBUG : fil/a: Looking for writers 2022/03/02 06:20:04 DEBUG : b: reading active writers 2022/03/02 06:20:04 DEBUG : fil: Looking for writers 2022/03/02 06:20:04 DEBUG : a: reading active writers 2022/03/02 06:20:04 DEBUG : : Looking for writers 2022/03/02 06:20:04 DEBUG : fil: reading active writers 2022/03/02 06:20:04 DEBUG : dir: reading active writers 2022/03/02 06:20:04 DEBUG : >WaitForWriters: --- PASS: TestDirWalk (6.26s) === RUN TestDirSetModTime run.go:181: Remote "Hubic Swift container rclone-test-kufaman1vajunuk6nuxoyep0", Local "Local file system at /tmp/rclone3515240406", Modify Window "1ns" 2022/03/02 06:20:06 INFO : Hubic Swift container rclone-test-kufaman1vajunuk6nuxoyep0: poll-interval is not supported by this remote 2022/03/02 06:20:08 DEBUG : WaitForWriters: timeout=30s 2022/03/02 06:20:08 DEBUG : dir: Looking for writers 2022/03/02 06:20:08 DEBUG : : Looking for writers 2022/03/02 06:20:08 DEBUG : dir: reading active writers 2022/03/02 06:20:08 DEBUG : >WaitForWriters: --- PASS: TestDirSetModTime (3.20s) === RUN TestDirStat run.go:181: Remote "Hubic Swift container rclone-test-kufaman1vajunuk6nuxoyep0", Local "Local file system at /tmp/rclone3515240406", Modify Window "1ns" 2022/03/02 06:20:09 INFO : Hubic Swift container rclone-test-kufaman1vajunuk6nuxoyep0: poll-interval is not supported by this remote 2022/03/02 06:20:10 DEBUG : WaitForWriters: timeout=30s 2022/03/02 06:20:10 DEBUG : dir: Looking for writers 2022/03/02 06:20:10 DEBUG : file1: reading active writers 2022/03/02 06:20:10 DEBUG : : Looking for writers 2022/03/02 06:20:10 DEBUG : dir: reading active writers 2022/03/02 06:20:10 DEBUG : >WaitForWriters: --- PASS: TestDirStat (2.56s) === RUN TestDirReadDirAll run.go:181: Remote "Hubic Swift container rclone-test-kufaman1vajunuk6nuxoyep0", Local "Local file system at /tmp/rclone3515240406", Modify Window "1ns" 2022/03/02 06:20:11 INFO : Hubic Swift container rclone-test-kufaman1vajunuk6nuxoyep0: poll-interval is not supported by this remote === RUN TestDirReadDirAll/Virtual 2022/03/02 06:20:16 DEBUG : dir: Added virtual directory entry vAddFile: "virtualFile" 2022/03/02 06:20:16 DEBUG : dir: Added virtual directory entry vAddDir: "virtualDir" 2022/03/02 06:20:16 DEBUG : dir: Added virtual directory entry vDel: "file2" 2022/03/02 06:20:16 DEBUG : dir: Added virtual directory entry vDel: "subdir" 2022/03/02 06:20:21 DEBUG : Waiting for deletions to finish 2022/03/02 06:20:22 INFO : dir/subdir/file3: Deleted 2022/03/02 06:20:22 DEBUG : dir: invalidating directory cache 2022/03/02 06:20:24 DEBUG : dir: Removed virtual directory entry vDel: "file2" 2022/03/02 06:20:24 DEBUG : dir: Removed virtual directory entry vDel: "subdir" 2022/03/02 06:20:24 DEBUG : dir: Removed virtual directory entry vAddDir: "virtualDir" 2022/03/02 06:20:24 DEBUG : dir: Removed virtual directory entry vAddFile: "virtualFile" 2022/03/02 06:20:24 DEBUG : dir: Added virtual directory entry vAddFile: "virtualFile2" 2022/03/02 06:20:24 DEBUG : dir: Added virtual directory entry vAddDir: "virtualDir2" 2022/03/02 06:20:24 DEBUG : dir: Added virtual directory entry vDel: "file1" 2022/03/02 06:20:24 DEBUG : dir: invalidating directory cache 2022/03/02 06:20:24 DEBUG : dir: Removed virtual directory entry vDel: "file1" 2022/03/02 06:20:24 DEBUG : : forgetting directory cache 2022/03/02 06:20:24 DEBUG : dir: forgetting directory cache 2022/03/02 06:20:24 DEBUG : dir/virtualDir2: forgetting directory cache 2022/03/02 06:20:24 DEBUG : dir/virtualDir: forgetting directory cache 2022/03/02 06:20:24 DEBUG : WaitForWriters: timeout=30s 2022/03/02 06:20:24 DEBUG : dir/virtualDir2: Looking for writers 2022/03/02 06:20:24 DEBUG : dir/virtualDir: Looking for writers 2022/03/02 06:20:24 DEBUG : dir: Looking for writers 2022/03/02 06:20:24 DEBUG : virtualFile2: reading active writers 2022/03/02 06:20:24 DEBUG : virtualDir2: reading active writers 2022/03/02 06:20:24 DEBUG : virtualFile: reading active writers 2022/03/02 06:20:24 DEBUG : virtualDir: reading active writers 2022/03/02 06:20:24 DEBUG : file1: reading active writers 2022/03/02 06:20:24 DEBUG : : Looking for writers 2022/03/02 06:20:24 DEBUG : dir: reading active writers 2022/03/02 06:20:24 DEBUG : >WaitForWriters: --- PASS: TestDirReadDirAll (15.79s) --- PASS: TestDirReadDirAll/Virtual (7.94s) === RUN TestDirOpen run.go:181: Remote "Hubic Swift container rclone-test-kufaman1vajunuk6nuxoyep0", Local "Local file system at /tmp/rclone3515240406", Modify Window "1ns" 2022/03/02 06:20:27 INFO : Hubic Swift container rclone-test-kufaman1vajunuk6nuxoyep0: poll-interval is not supported by this remote 2022/03/02 06:20:29 ERROR : dir/: Can only open directories read only 2022/03/02 06:20:29 DEBUG : WaitForWriters: timeout=30s 2022/03/02 06:20:29 DEBUG : dir: Looking for writers 2022/03/02 06:20:29 DEBUG : : Looking for writers 2022/03/02 06:20:29 DEBUG : dir: reading active writers 2022/03/02 06:20:29 DEBUG : >WaitForWriters: --- PASS: TestDirOpen (2.75s) === RUN TestDirCreate run.go:181: Remote "Hubic Swift container rclone-test-kufaman1vajunuk6nuxoyep0", Local "Local file system at /tmp/rclone3515240406", Modify Window "1ns" 2022/03/02 06:20:30 INFO : Hubic Swift container rclone-test-kufaman1vajunuk6nuxoyep0: poll-interval is not supported by this remote 2022/03/02 06:20:32 DEBUG : dir/potato: Open: flags=O_WRONLY|O_CREATE 2022/03/02 06:20:32 DEBUG : dir: Added virtual directory entry vAddFile: "potato" 2022/03/02 06:20:32 DEBUG : dir/potato: >Open: fd=dir/potato (w), err= 2022/03/02 06:20:32 DEBUG : dir: Added virtual directory entry vAddFile: "potato" 2022/03/02 06:20:32 DEBUG : Hubic Swift container rclone-test-kufaman1vajunuk6nuxoyep0: File to upload is small (5 bytes), uploading instead of streaming 2022/03/02 06:20:34 DEBUG : dir/potato: md5 = 5d41402abc4b2a76b9719d911017c592 OK 2022/03/02 06:20:34 INFO : dir/potato: Copied (new) 2022/03/02 06:20:34 DEBUG : dir: Added virtual directory entry vAddFile: "potato" 2022/03/02 06:20:34 DEBUG : WaitForWriters: timeout=30s 2022/03/02 06:20:34 DEBUG : dir: Looking for writers 2022/03/02 06:20:34 DEBUG : file1: reading active writers 2022/03/02 06:20:34 DEBUG : potato: reading active writers 2022/03/02 06:20:34 DEBUG : : Looking for writers 2022/03/02 06:20:34 DEBUG : dir: reading active writers 2022/03/02 06:20:34 DEBUG : >WaitForWriters: --- PASS: TestDirCreate (6.22s) === RUN TestDirMkdir run.go:181: Remote "Hubic Swift container rclone-test-kufaman1vajunuk6nuxoyep0", Local "Local file system at /tmp/rclone3515240406", Modify Window "1ns" 2022/03/02 06:20:36 INFO : Hubic Swift container rclone-test-kufaman1vajunuk6nuxoyep0: poll-interval is not supported by this remote 2022/03/02 06:20:38 DEBUG : dir: Added virtual directory entry vAddDir: "sub" fstest.go:251: Filtering empty directory "dir/sub" 2022/03/02 06:20:39 DEBUG : WaitForWriters: timeout=30s 2022/03/02 06:20:39 DEBUG : dir/sub: Looking for writers 2022/03/02 06:20:39 DEBUG : dir: Looking for writers 2022/03/02 06:20:39 DEBUG : file1: reading active writers 2022/03/02 06:20:39 DEBUG : sub: reading active writers 2022/03/02 06:20:39 DEBUG : : Looking for writers 2022/03/02 06:20:39 DEBUG : dir: reading active writers 2022/03/02 06:20:39 DEBUG : >WaitForWriters: --- PASS: TestDirMkdir (4.02s) === RUN TestDirMkdirSub run.go:181: Remote "Hubic Swift container rclone-test-kufaman1vajunuk6nuxoyep0", Local "Local file system at /tmp/rclone3515240406", Modify Window "1ns" 2022/03/02 06:20:40 INFO : Hubic Swift container rclone-test-kufaman1vajunuk6nuxoyep0: poll-interval is not supported by this remote 2022/03/02 06:20:42 DEBUG : dir: Added virtual directory entry vAddDir: "sub" 2022/03/02 06:20:42 DEBUG : dir/sub: Added virtual directory entry vAddDir: "subsub" fstest.go:251: Filtering empty directory "dir/sub" fstest.go:251: Filtering empty directory "dir/sub/subsub" 2022/03/02 06:20:43 DEBUG : WaitForWriters: timeout=30s 2022/03/02 06:20:43 DEBUG : dir/sub/subsub: Looking for writers 2022/03/02 06:20:43 DEBUG : dir/sub: Looking for writers 2022/03/02 06:20:43 DEBUG : subsub: reading active writers 2022/03/02 06:20:43 DEBUG : dir: Looking for writers 2022/03/02 06:20:43 DEBUG : file1: reading active writers 2022/03/02 06:20:43 DEBUG : sub: reading active writers 2022/03/02 06:20:43 DEBUG : : Looking for writers 2022/03/02 06:20:43 DEBUG : dir: reading active writers 2022/03/02 06:20:43 DEBUG : >WaitForWriters: --- PASS: TestDirMkdirSub (3.77s) === RUN TestDirRemove run.go:181: Remote "Hubic Swift container rclone-test-kufaman1vajunuk6nuxoyep0", Local "Local file system at /tmp/rclone3515240406", Modify Window "1ns" 2022/03/02 06:20:44 INFO : Hubic Swift container rclone-test-kufaman1vajunuk6nuxoyep0: poll-interval is not supported by this remote 2022/03/02 06:20:45 ERROR : dir/: Dir.Remove not empty 2022/03/02 06:20:45 DEBUG : dir/file1: Remove: 2022/03/02 06:20:45 DEBUG : dir: Added virtual directory entry vDel: "file1" 2022/03/02 06:20:46 DEBUG : dir/file1: >Remove: err= 2022/03/02 06:20:46 DEBUG : : Added virtual directory entry vDel: "dir" 2022/03/02 06:20:46 DEBUG : WaitForWriters: timeout=30s 2022/03/02 06:20:46 DEBUG : : Looking for writers 2022/03/02 06:20:46 DEBUG : >WaitForWriters: --- PASS: TestDirRemove (2.74s) === RUN TestDirRemoveAll run.go:181: Remote "Hubic Swift container rclone-test-kufaman1vajunuk6nuxoyep0", Local "Local file system at /tmp/rclone3515240406", Modify Window "1ns" 2022/03/02 06:20:47 INFO : Hubic Swift container rclone-test-kufaman1vajunuk6nuxoyep0: poll-interval is not supported by this remote 2022/03/02 06:20:48 DEBUG : dir/file1: Remove: 2022/03/02 06:20:48 DEBUG : dir: Added virtual directory entry vDel: "file1" 2022/03/02 06:20:49 DEBUG : dir/file1: >Remove: err= 2022/03/02 06:20:49 DEBUG : : Added virtual directory entry vDel: "dir" 2022/03/02 06:20:49 DEBUG : WaitForWriters: timeout=30s 2022/03/02 06:20:49 DEBUG : : Looking for writers 2022/03/02 06:20:49 DEBUG : >WaitForWriters: --- PASS: TestDirRemoveAll (2.75s) === RUN TestDirRemoveName run.go:181: Remote "Hubic Swift container rclone-test-kufaman1vajunuk6nuxoyep0", Local "Local file system at /tmp/rclone3515240406", Modify Window "1ns" 2022/03/02 06:20:49 INFO : Hubic Swift container rclone-test-kufaman1vajunuk6nuxoyep0: poll-interval is not supported by this remote 2022/03/02 06:20:52 DEBUG : dir/file1: Remove: 2022/03/02 06:20:52 DEBUG : dir: Added virtual directory entry vDel: "file1" 2022/03/02 06:20:52 DEBUG : dir/file1: >Remove: err= fstest.go:251: Filtering empty directory "dir" fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:299: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:299: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:306: Error Trace: fstest.go:306 fstest.go:339 dir_test.go:512 Error: Should be true Test: TestDirRemoveName Messages: listing wrong, want got dir/file1 (14) fstest.go:192: Error Trace: fstest.go:192 fstest.go:309 fstest.go:339 dir_test.go:512 Error: Should be true Test: TestDirRemoveName Messages: Unexpected file "dir/file1" 2022/03/02 06:21:00 DEBUG : WaitForWriters: timeout=30s 2022/03/02 06:21:00 DEBUG : dir: Looking for writers 2022/03/02 06:21:00 DEBUG : : Looking for writers 2022/03/02 06:21:00 DEBUG : dir: reading active writers 2022/03/02 06:21:00 DEBUG : >WaitForWriters: run.go:126: removing file "dir/file1" failed - try 1/3: Object Not Found run.go:126: removing file "dir/file1" failed - try 2/3: Object Not Found run.go:126: removing file "dir/file1" failed - try 3/3: Object Not Found run.go:129: removing file "dir/file1" failed: Object Not Found --- FAIL: TestDirRemoveName (18.44s) === RUN TestDirRename run.go:181: Remote "Hubic Swift container rclone-test-kufaman1vajunuk6nuxoyep0", Local "Local file system at /tmp/rclone3515240406", Modify Window "1ns" 2022/03/02 06:21:08 INFO : Hubic Swift container rclone-test-kufaman1vajunuk6nuxoyep0: poll-interval is not supported by this remote 2022/03/02 06:21:12 ERROR : dir/not found: Dir.Rename error: file does not exist 2022/03/02 06:21:14 DEBUG : dir/file1: md5 = 0ef726ce9b1a7692357ff70dd321d595 OK 2022/03/02 06:21:14 INFO : dir/file1: Copied (server-side copy) to: dir2/file1 2022/03/02 06:21:15 DEBUG : dir/file3: md5 = ea32138586c9d4e9533ef6cf95e9ec4b OK 2022/03/02 06:21:15 INFO : dir/file3: Copied (server-side copy) to: dir2/file3 2022/03/02 06:21:15 INFO : dir/file1: Deleted 2022/03/02 06:21:16 INFO : dir/file3: Deleted 2022/03/02 06:21:16 DEBUG : dir: Updating dir with dir2 0xc00066b080 2022/03/02 06:21:16 DEBUG : dir: forgetting directory cache 2022/03/02 06:21:16 DEBUG : : Added virtual directory entry vDel: "dir" 2022/03/02 06:21:16 DEBUG : : Added virtual directory entry vAddDir: "dir2" 2022/03/02 06:21:19 DEBUG : dir2/file1: md5 = 0ef726ce9b1a7692357ff70dd321d595 OK 2022/03/02 06:21:19 INFO : dir2/file1: Copied (server-side copy) to: file2 2022/03/02 06:21:20 INFO : dir2/file1: Deleted 2022/03/02 06:21:20 DEBUG : file2: Updating file with file2 0xc00097ee40 2022/03/02 06:21:20 DEBUG : dir2: Added virtual directory entry vDel: "file1" 2022/03/02 06:21:20 DEBUG : : Added virtual directory entry vAddFile: "file2" 2022/03/02 06:21:22 DEBUG : file2: md5 = 0ef726ce9b1a7692357ff70dd321d595 OK 2022/03/02 06:21:22 INFO : file2: Copied (server-side copy) to: dir2/file3 2022/03/02 06:21:23 INFO : file2: Deleted 2022/03/02 06:21:23 DEBUG : dir2/file3: Updating file with dir2/file3 0xc00097ee40 2022/03/02 06:21:23 DEBUG : : Added virtual directory entry vDel: "file2" 2022/03/02 06:21:23 DEBUG : dir2: Added virtual directory entry vAddFile: "file3" fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:293: Sleeping for 6s just to make sure 2022/03/02 06:21:30 DEBUG : : Added virtual directory entry vAddDir: "empty directory" 2022/03/02 06:21:30 DEBUG : empty directory: Updating dir with renamed empty directory 0xc00097f2c0 2022/03/02 06:21:30 DEBUG : empty directory: forgetting directory cache 2022/03/02 06:21:30 DEBUG : : Added virtual directory entry vDel: "empty directory" 2022/03/02 06:21:30 DEBUG : : Added virtual directory entry vAddDir: "renamed empty directory" 2022/03/02 06:21:30 DEBUG : WaitForWriters: timeout=30s 2022/03/02 06:21:30 DEBUG : renamed empty directory: Looking for writers 2022/03/02 06:21:30 DEBUG : dir2: Looking for writers 2022/03/02 06:21:30 DEBUG : file3: reading active writers 2022/03/02 06:21:30 DEBUG : : Looking for writers 2022/03/02 06:21:30 DEBUG : dir2: reading active writers 2022/03/02 06:21:30 DEBUG : renamed empty directory: reading active writers 2022/03/02 06:21:30 DEBUG : >WaitForWriters: --- PASS: TestDirRename (23.97s) === RUN TestRcGetVFS run.go:181: Remote "Hubic Swift container rclone-test-kufaman1vajunuk6nuxoyep0", Local "Local file system at /tmp/rclone3515240406", Modify Window "1ns" 2022/03/02 06:21:32 INFO : Hubic Swift container rclone-test-kufaman1vajunuk6nuxoyep0: poll-interval is not supported by this remote 2022/03/02 06:21:32 INFO : Hubic Swift container rclone-test-kufaman1vajunuk6nuxoyep0: poll-interval is not supported by this remote 2022/03/02 06:21:32 DEBUG : WaitForWriters: timeout=30s 2022/03/02 06:21:32 DEBUG : : Looking for writers 2022/03/02 06:21:32 DEBUG : >WaitForWriters: --- PASS: TestRcGetVFS (0.14s) === RUN TestReadFileHandleMethods run.go:181: Remote "Hubic Swift container rclone-test-kufaman1vajunuk6nuxoyep0", Local "Local file system at /tmp/rclone3515240406", Modify Window "1ns" 2022/03/02 06:21:32 INFO : Hubic Swift container rclone-test-kufaman1vajunuk6nuxoyep0: poll-interval is not supported by this remote 2022/03/02 06:21:33 DEBUG : dir/file1: OpenFile: flags=O_RDONLY, perm=-rwxrwxrwx 2022/03/02 06:21:33 DEBUG : dir/file1: Open: flags=O_RDONLY 2022/03/02 06:21:33 DEBUG : dir/file1: >Open: fd=dir/file1 (r), err= 2022/03/02 06:21:33 DEBUG : dir/file1: >OpenFile: fd=dir/file1 (r), err= 2022/03/02 06:21:33 DEBUG : dir/file1: ChunkedReader.openRange at 0 length 134217728 2022/03/02 06:21:34 DEBUG : dir/file1: ChunkedReader.Read at 0 length 1 chunkOffset 0 chunkSize 134217728 2022/03/02 06:21:34 DEBUG : dir/file1: ChunkedReader.Read at 1 length 256 chunkOffset 0 chunkSize 134217728 2022/03/02 06:21:34 DEBUG : WaitForWriters: timeout=30s 2022/03/02 06:21:34 DEBUG : dir: Looking for writers 2022/03/02 06:21:34 DEBUG : file1: reading active writers 2022/03/02 06:21:34 DEBUG : : Looking for writers 2022/03/02 06:21:34 DEBUG : dir: reading active writers 2022/03/02 06:21:34 DEBUG : >WaitForWriters: --- PASS: TestReadFileHandleMethods (3.04s) === RUN TestReadFileHandleSeek run.go:181: Remote "Hubic Swift container rclone-test-kufaman1vajunuk6nuxoyep0", Local "Local file system at /tmp/rclone3515240406", Modify Window "1ns" 2022/03/02 06:21:35 INFO : Hubic Swift container rclone-test-kufaman1vajunuk6nuxoyep0: poll-interval is not supported by this remote 2022/03/02 06:21:36 DEBUG : dir/file1: OpenFile: flags=O_RDONLY, perm=-rwxrwxrwx 2022/03/02 06:21:37 DEBUG : dir/file1: Open: flags=O_RDONLY 2022/03/02 06:21:37 DEBUG : dir/file1: >Open: fd=dir/file1 (r), err= 2022/03/02 06:21:37 DEBUG : dir/file1: >OpenFile: fd=dir/file1 (r), err= 2022/03/02 06:21:37 DEBUG : dir/file1: ChunkedReader.openRange at 0 length 134217728 2022/03/02 06:21:37 DEBUG : dir/file1: ChunkedReader.Read at 0 length 1 chunkOffset 0 chunkSize 134217728 2022/03/02 06:21:37 DEBUG : dir/file1: waiting for in-sequence read to 5 for 20ms 2022/03/02 06:21:37 DEBUG : dir/file1: aborting in-sequence read wait, off=5 2022/03/02 06:21:37 DEBUG : dir/file1: failed to wait for in-sequence read to 5 2022/03/02 06:21:37 DEBUG : dir/file1: ReadFileHandle.seek from 1 to 5 (fs.RangeSeeker) 2022/03/02 06:21:37 DEBUG : dir/file1: ChunkedReader.RangeSeek from 1 to 5 length -1 2022/03/02 06:21:37 DEBUG : dir/file1: ChunkedReader.Read at -1 length 1 chunkOffset 5 chunkSize 134217728 2022/03/02 06:21:37 DEBUG : dir/file1: ChunkedReader.openRange at 5 length 134217728 2022/03/02 06:21:37 DEBUG : dir/file1: ReadFileHandle.seek from 6 to 3 (fs.RangeSeeker) 2022/03/02 06:21:37 DEBUG : dir/file1: ChunkedReader.RangeSeek from 6 to 3 length -1 2022/03/02 06:21:37 DEBUG : dir/file1: ChunkedReader.Read at -1 length 1 chunkOffset 3 chunkSize 134217728 2022/03/02 06:21:37 DEBUG : dir/file1: ChunkedReader.openRange at 3 length 134217728 2022/03/02 06:21:38 DEBUG : dir/file1: ReadFileHandle.seek from 4 to 13 (fs.RangeSeeker) 2022/03/02 06:21:38 DEBUG : dir/file1: ChunkedReader.RangeSeek from 4 to 13 length -1 2022/03/02 06:21:38 DEBUG : dir/file1: ChunkedReader.Read at -1 length 1 chunkOffset 13 chunkSize 134217728 2022/03/02 06:21:38 DEBUG : dir/file1: ChunkedReader.openRange at 13 length 134217728 2022/03/02 06:21:38 DEBUG : WaitForWriters: timeout=30s 2022/03/02 06:21:38 DEBUG : dir: Looking for writers 2022/03/02 06:21:38 DEBUG : file1: reading active writers 2022/03/02 06:21:38 DEBUG : : Looking for writers 2022/03/02 06:21:38 DEBUG : dir: reading active writers 2022/03/02 06:21:38 DEBUG : >WaitForWriters: fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:293: Sleeping for 6s just to make sure --- PASS: TestReadFileHandleSeek (11.46s) === RUN TestReadFileHandleReadAt run.go:181: Remote "Hubic Swift container rclone-test-kufaman1vajunuk6nuxoyep0", Local "Local file system at /tmp/rclone3515240406", Modify Window "1ns" 2022/03/02 06:21:46 INFO : Hubic Swift container rclone-test-kufaman1vajunuk6nuxoyep0: poll-interval is not supported by this remote 2022/03/02 06:21:48 DEBUG : dir/file1: OpenFile: flags=O_RDONLY, perm=-rwxrwxrwx 2022/03/02 06:21:48 DEBUG : dir/file1: Open: flags=O_RDONLY 2022/03/02 06:21:48 DEBUG : dir/file1: >Open: fd=dir/file1 (r), err= 2022/03/02 06:21:48 DEBUG : dir/file1: >OpenFile: fd=dir/file1 (r), err= 2022/03/02 06:21:48 DEBUG : dir/file1: ChunkedReader.openRange at 0 length 134217728 2022/03/02 06:21:49 DEBUG : dir/file1: ChunkedReader.Read at 0 length 1 chunkOffset 0 chunkSize 134217728 2022/03/02 06:21:49 DEBUG : dir/file1: waiting for in-sequence read to 5 for 20ms 2022/03/02 06:21:49 DEBUG : dir/file1: aborting in-sequence read wait, off=5 2022/03/02 06:21:49 DEBUG : dir/file1: failed to wait for in-sequence read to 5 2022/03/02 06:21:49 DEBUG : dir/file1: ReadFileHandle.seek from 1 to 5 (fs.RangeSeeker) 2022/03/02 06:21:49 DEBUG : dir/file1: ChunkedReader.RangeSeek from 1 to 5 length -1 2022/03/02 06:21:49 DEBUG : dir/file1: ChunkedReader.Read at -1 length 1 chunkOffset 5 chunkSize 134217728 2022/03/02 06:21:49 DEBUG : dir/file1: ChunkedReader.openRange at 5 length 134217728 2022/03/02 06:21:49 DEBUG : dir/file1: ReadFileHandle.seek from 6 to 1 (fs.RangeSeeker) 2022/03/02 06:21:49 DEBUG : dir/file1: ChunkedReader.RangeSeek from 6 to 1 length -1 2022/03/02 06:21:49 DEBUG : dir/file1: ChunkedReader.Read at -1 length 1 chunkOffset 1 chunkSize 134217728 2022/03/02 06:21:49 DEBUG : dir/file1: ChunkedReader.openRange at 1 length 134217728 2022/03/02 06:21:50 DEBUG : dir/file1: waiting for in-sequence read to 10 for 20ms 2022/03/02 06:21:50 DEBUG : dir/file1: aborting in-sequence read wait, off=10 2022/03/02 06:21:50 DEBUG : dir/file1: failed to wait for in-sequence read to 10 2022/03/02 06:21:50 DEBUG : dir/file1: ReadFileHandle.seek from 2 to 10 (fs.RangeSeeker) 2022/03/02 06:21:50 DEBUG : dir/file1: ChunkedReader.RangeSeek from 2 to 10 length -1 2022/03/02 06:21:50 DEBUG : dir/file1: ChunkedReader.Read at -1 length 6 chunkOffset 10 chunkSize 134217728 2022/03/02 06:21:50 DEBUG : dir/file1: ChunkedReader.openRange at 10 length 134217728 2022/03/02 06:21:50 DEBUG : dir/file1: ReadFileHandle.seek from 16 to 10 (fs.RangeSeeker) 2022/03/02 06:21:50 DEBUG : dir/file1: ChunkedReader.RangeSeek from 16 to 10 length -1 2022/03/02 06:21:50 DEBUG : dir/file1: ChunkedReader.Read at -1 length 256 chunkOffset 10 chunkSize 134217728 2022/03/02 06:21:50 DEBUG : dir/file1: ChunkedReader.openRange at 10 length 134217728 2022/03/02 06:21:50 DEBUG : dir/file1: waiting for in-sequence read to 100 for 20ms 2022/03/02 06:21:50 DEBUG : dir/file1: aborting in-sequence read wait, off=100 2022/03/02 06:21:50 DEBUG : dir/file1: failed to wait for in-sequence read to 100 2022/03/02 06:21:50 DEBUG : dir/file1: ReadFileHandle.Read attempt to read beyond end of file: 100 > 16 2022/03/02 06:21:50 DEBUG : dir/file1: waiting for in-sequence read to 100 for 20ms 2022/03/02 06:21:50 DEBUG : dir/file1: aborting in-sequence read wait, off=100 2022/03/02 06:21:50 DEBUG : dir/file1: failed to wait for in-sequence read to 100 2022/03/02 06:21:50 ERROR : dir/file1: ReadFileHandle.Read error: Bad file descriptor 2022/03/02 06:21:50 DEBUG : WaitForWriters: timeout=30s 2022/03/02 06:21:50 DEBUG : dir: Looking for writers 2022/03/02 06:21:50 DEBUG : file1: reading active writers 2022/03/02 06:21:50 DEBUG : : Looking for writers 2022/03/02 06:21:50 DEBUG : dir: reading active writers 2022/03/02 06:21:50 DEBUG : >WaitForWriters: --- PASS: TestReadFileHandleReadAt (5.68s) === RUN TestReadFileHandleFlush run.go:181: Remote "Hubic Swift container rclone-test-kufaman1vajunuk6nuxoyep0", Local "Local file system at /tmp/rclone3515240406", Modify Window "1ns" 2022/03/02 06:21:52 INFO : Hubic Swift container rclone-test-kufaman1vajunuk6nuxoyep0: poll-interval is not supported by this remote 2022/03/02 06:21:54 DEBUG : dir/file1: OpenFile: flags=O_RDONLY, perm=-rwxrwxrwx 2022/03/02 06:21:54 DEBUG : dir/file1: Open: flags=O_RDONLY 2022/03/02 06:21:54 DEBUG : dir/file1: >Open: fd=dir/file1 (r), err= 2022/03/02 06:21:54 DEBUG : dir/file1: >OpenFile: fd=dir/file1 (r), err= 2022/03/02 06:21:54 DEBUG : dir/file1: ChunkedReader.openRange at 0 length 134217728 2022/03/02 06:21:54 DEBUG : dir/file1: ChunkedReader.Read at 0 length 256 chunkOffset 0 chunkSize 134217728 2022/03/02 06:21:54 DEBUG : WaitForWriters: timeout=30s 2022/03/02 06:21:54 DEBUG : dir: Looking for writers 2022/03/02 06:21:54 DEBUG : file1: reading active writers 2022/03/02 06:21:54 DEBUG : : Looking for writers 2022/03/02 06:21:54 DEBUG : dir: reading active writers 2022/03/02 06:21:54 DEBUG : >WaitForWriters: --- PASS: TestReadFileHandleFlush (3.91s) === RUN TestReadFileHandleRelease run.go:181: Remote "Hubic Swift container rclone-test-kufaman1vajunuk6nuxoyep0", Local "Local file system at /tmp/rclone3515240406", Modify Window "1ns" 2022/03/02 06:21:56 INFO : Hubic Swift container rclone-test-kufaman1vajunuk6nuxoyep0: poll-interval is not supported by this remote 2022/03/02 06:21:57 DEBUG : dir/file1: OpenFile: flags=O_RDONLY, perm=-rwxrwxrwx 2022/03/02 06:21:58 DEBUG : dir/file1: Open: flags=O_RDONLY 2022/03/02 06:21:58 DEBUG : dir/file1: >Open: fd=dir/file1 (r), err= 2022/03/02 06:21:58 DEBUG : dir/file1: >OpenFile: fd=dir/file1 (r), err= 2022/03/02 06:21:58 DEBUG : dir/file1: ChunkedReader.openRange at 0 length 134217728 2022/03/02 06:21:58 DEBUG : dir/file1: ChunkedReader.Read at 0 length 256 chunkOffset 0 chunkSize 134217728 2022/03/02 06:21:58 DEBUG : dir/file1: ReadFileHandle.Release closing 2022/03/02 06:21:58 DEBUG : dir/file1: ReadFileHandle.Release nothing to do 2022/03/02 06:21:58 DEBUG : WaitForWriters: timeout=30s 2022/03/02 06:21:58 DEBUG : dir: Looking for writers 2022/03/02 06:21:58 DEBUG : file1: reading active writers 2022/03/02 06:21:58 DEBUG : : Looking for writers 2022/03/02 06:21:58 DEBUG : dir: reading active writers 2022/03/02 06:21:58 DEBUG : >WaitForWriters: --- PASS: TestReadFileHandleRelease (3.07s) === RUN TestVFSNew run.go:181: Remote "Hubic Swift container rclone-test-kufaman1vajunuk6nuxoyep0", Local "Local file system at /tmp/rclone3515240406", Modify Window "1ns" 2022/03/02 06:21:59 INFO : Hubic Swift container rclone-test-kufaman1vajunuk6nuxoyep0: poll-interval is not supported by this remote 2022/03/02 06:21:59 DEBUG : Hubic Swift container rclone-test-kufaman1vajunuk6nuxoyep0: Re-using VFS from active cache 2022/03/02 06:21:59 DEBUG : WaitForWriters: timeout=30s 2022/03/02 06:21:59 DEBUG : : Looking for writers 2022/03/02 06:21:59 DEBUG : >WaitForWriters: --- PASS: TestVFSNew (0.23s) === RUN TestVFSStat run.go:181: Remote "Hubic Swift container rclone-test-kufaman1vajunuk6nuxoyep0", Local "Local file system at /tmp/rclone3515240406", Modify Window "1ns" 2022/03/02 06:21:59 INFO : Hubic Swift container rclone-test-kufaman1vajunuk6nuxoyep0: poll-interval is not supported by this remote 2022/03/02 06:22:03 DEBUG : WaitForWriters: timeout=30s 2022/03/02 06:22:03 DEBUG : dir: Looking for writers 2022/03/02 06:22:03 DEBUG : file2: reading active writers 2022/03/02 06:22:03 DEBUG : : Looking for writers 2022/03/02 06:22:03 DEBUG : dir: reading active writers 2022/03/02 06:22:03 DEBUG : file1: reading active writers 2022/03/02 06:22:03 DEBUG : >WaitForWriters: --- PASS: TestVFSStat (5.59s) === RUN TestVFSStatParent run.go:181: Remote "Hubic Swift container rclone-test-kufaman1vajunuk6nuxoyep0", Local "Local file system at /tmp/rclone3515240406", Modify Window "1ns" 2022/03/02 06:22:05 INFO : Hubic Swift container rclone-test-kufaman1vajunuk6nuxoyep0: poll-interval is not supported by this remote 2022/03/02 06:22:11 DEBUG : WaitForWriters: timeout=30s 2022/03/02 06:22:11 DEBUG : dir: Looking for writers 2022/03/02 06:22:11 DEBUG : : Looking for writers 2022/03/02 06:22:11 DEBUG : dir: reading active writers 2022/03/02 06:22:11 DEBUG : file1: reading active writers 2022/03/02 06:22:11 DEBUG : >WaitForWriters: --- PASS: TestVFSStatParent (7.93s) === RUN TestVFSOpenFile run.go:181: Remote "Hubic Swift container rclone-test-kufaman1vajunuk6nuxoyep0", Local "Local file system at /tmp/rclone3515240406", Modify Window "1ns" 2022/03/02 06:22:13 INFO : Hubic Swift container rclone-test-kufaman1vajunuk6nuxoyep0: poll-interval is not supported by this remote 2022/03/02 06:22:16 DEBUG : file1: OpenFile: flags=O_RDONLY, perm=-rwxrwxrwx 2022/03/02 06:22:16 DEBUG : file1: Open: flags=O_RDONLY 2022/03/02 06:22:16 DEBUG : file1: >Open: fd=file1 (r), err= 2022/03/02 06:22:16 DEBUG : file1: >OpenFile: fd=file1 (r), err= 2022/03/02 06:22:16 DEBUG : dir: OpenFile: flags=O_RDONLY, perm=-rwxrwxrwx 2022/03/02 06:22:16 DEBUG : dir: >OpenFile: fd=dir/ (r), err= 2022/03/02 06:22:16 DEBUG : dir/new_file.txt: OpenFile: flags=O_RDONLY, perm=-rwxrwxrwx 2022/03/02 06:22:16 DEBUG : dir/new_file.txt: >OpenFile: fd=, err=file does not exist 2022/03/02 06:22:16 DEBUG : dir/new_file.txt: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2022/03/02 06:22:16 DEBUG : dir/new_file.txt: Open: flags=O_WRONLY|O_CREATE 2022/03/02 06:22:16 DEBUG : dir: Added virtual directory entry vAddFile: "new_file.txt" 2022/03/02 06:22:16 DEBUG : dir/new_file.txt: >Open: fd=dir/new_file.txt (w), err= 2022/03/02 06:22:16 DEBUG : dir/new_file.txt: >OpenFile: fd=dir/new_file.txt (w), err= 2022/03/02 06:22:16 DEBUG : dir: Added virtual directory entry vAddFile: "new_file.txt" 2022/03/02 06:22:16 DEBUG : Hubic Swift container rclone-test-kufaman1vajunuk6nuxoyep0: File to upload is small (0 bytes), uploading instead of streaming 2022/03/02 06:22:18 DEBUG : dir/new_file.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2022/03/02 06:22:18 INFO : dir/new_file.txt: Copied (new) 2022/03/02 06:22:18 DEBUG : dir: Added virtual directory entry vAddFile: "new_file.txt" 2022/03/02 06:22:18 DEBUG : not found/new_file.txt: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2022/03/02 06:22:18 DEBUG : not found/new_file.txt: >OpenFile: fd=, err=file does not exist 2022/03/02 06:22:18 DEBUG : WaitForWriters: timeout=30s 2022/03/02 06:22:18 DEBUG : dir: Looking for writers 2022/03/02 06:22:18 DEBUG : file2: reading active writers 2022/03/02 06:22:18 DEBUG : new_file.txt: reading active writers 2022/03/02 06:22:18 DEBUG : : Looking for writers 2022/03/02 06:22:18 DEBUG : dir: reading active writers 2022/03/02 06:22:18 DEBUG : file1: reading active writers 2022/03/02 06:22:18 DEBUG : >WaitForWriters: --- PASS: TestVFSOpenFile (8.60s) === RUN TestVFSRename run.go:181: Remote "Hubic Swift container rclone-test-kufaman1vajunuk6nuxoyep0", Local "Local file system at /tmp/rclone3515240406", Modify Window "1ns" 2022/03/02 06:22:21 INFO : Hubic Swift container rclone-test-kufaman1vajunuk6nuxoyep0: poll-interval is not supported by this remote 2022/03/02 06:22:25 DEBUG : dir/file2: md5 = 25ba584dea3b091afba78acbe36da91a OK 2022/03/02 06:22:25 INFO : dir/file2: Copied (server-side copy) to: dir/file1 2022/03/02 06:22:26 INFO : dir/file2: Deleted 2022/03/02 06:22:26 DEBUG : dir/file1: Updating file with dir/file1 0xc000649980 2022/03/02 06:22:26 DEBUG : dir: Added virtual directory entry vDel: "file2" 2022/03/02 06:22:26 DEBUG : dir: Added virtual directory entry vAddFile: "file1" 2022/03/02 06:22:28 DEBUG : dir/file1: md5 = 25ba584dea3b091afba78acbe36da91a OK 2022/03/02 06:22:28 INFO : dir/file1: Copied (server-side copy) to: file0 2022/03/02 06:22:29 INFO : dir/file1: Deleted 2022/03/02 06:22:29 DEBUG : file0: Updating file with file0 0xc000649980 2022/03/02 06:22:29 DEBUG : dir: Added virtual directory entry vDel: "file1" 2022/03/02 06:22:29 DEBUG : : Added virtual directory entry vAddFile: "file0" 2022/03/02 06:22:30 DEBUG : WaitForWriters: timeout=30s 2022/03/02 06:22:30 DEBUG : dir: Looking for writers 2022/03/02 06:22:30 DEBUG : : Looking for writers 2022/03/02 06:22:30 DEBUG : dir: reading active writers 2022/03/02 06:22:30 DEBUG : file0: reading active writers 2022/03/02 06:22:30 DEBUG : >WaitForWriters: --- PASS: TestVFSRename (9.24s) === RUN TestWriteFileHandleMethods run.go:181: Remote "Hubic Swift container rclone-test-kufaman1vajunuk6nuxoyep0", Local "Local file system at /tmp/rclone3515240406", Modify Window "1ns" 2022/03/02 06:22:31 INFO : Hubic Swift container rclone-test-kufaman1vajunuk6nuxoyep0: poll-interval is not supported by this remote 2022/03/02 06:22:31 DEBUG : file1: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2022/03/02 06:22:31 DEBUG : file1: Open: flags=O_WRONLY|O_CREATE 2022/03/02 06:22:31 DEBUG : : Added virtual directory entry vAddFile: "file1" 2022/03/02 06:22:31 DEBUG : file1: >Open: fd=file1 (w), err= 2022/03/02 06:22:31 DEBUG : file1: >OpenFile: fd=file1 (w), err= 2022/03/02 06:22:31 DEBUG : : Added virtual directory entry vAddFile: "file1" 2022/03/02 06:22:31 ERROR : file1: WriteFileHandle: Read: Can't read and write to file without --vfs-cache-mode >= minimal 2022/03/02 06:22:31 ERROR : file1: WriteFileHandle: ReadAt: Can't read and write to file without --vfs-cache-mode >= minimal 2022/03/02 06:22:31 ERROR : file1: WriteFileHandle: Truncate: Can't change size without --vfs-cache-mode >= writes 2022/03/02 06:22:31 DEBUG : Hubic Swift container rclone-test-kufaman1vajunuk6nuxoyep0: File to upload is small (5 bytes), uploading instead of streaming 2022/03/02 06:22:33 DEBUG : file1: md5 = 5d41402abc4b2a76b9719d911017c592 OK 2022/03/02 06:22:33 INFO : file1: Copied (new) 2022/03/02 06:22:33 DEBUG : : Added virtual directory entry vAddFile: "file1" 2022/03/02 06:22:33 DEBUG : file1: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2022/03/02 06:22:33 DEBUG : file1: Open: flags=O_WRONLY|O_CREATE 2022/03/02 06:22:33 DEBUG : : Added virtual directory entry vAddFile: "file1" 2022/03/02 06:22:33 DEBUG : file1: >Open: fd=file1 (w), err= 2022/03/02 06:22:33 DEBUG : file1: >OpenFile: fd=file1 (w), err= 2022/03/02 06:22:33 DEBUG : file1: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2022/03/02 06:22:33 DEBUG : file1: Open: flags=O_WRONLY|O_CREATE 2022/03/02 06:22:33 DEBUG : : Added virtual directory entry vAddFile: "file1" 2022/03/02 06:22:33 DEBUG : file1: >Open: fd=file1 (w), err= 2022/03/02 06:22:33 DEBUG : file1: >OpenFile: fd=file1 (w), err= 2022/03/02 06:22:33 ERROR : file1: WriteFileHandle: Can't open for write without O_TRUNC on existing file without --vfs-cache-mode >= writes 2022/03/02 06:22:33 DEBUG : file1: OpenFile: flags=O_WRONLY|O_CREATE|O_TRUNC, perm=-rwxrwxrwx 2022/03/02 06:22:33 DEBUG : file1: Open: flags=O_WRONLY|O_CREATE|O_TRUNC 2022/03/02 06:22:33 DEBUG : : Added virtual directory entry vAddFile: "file1" 2022/03/02 06:22:33 DEBUG : file1: >Open: fd=file1 (w), err= 2022/03/02 06:22:33 DEBUG : file1: >OpenFile: fd=file1 (w), err= 2022/03/02 06:22:33 DEBUG : : Added virtual directory entry vAddFile: "file1" 2022/03/02 06:22:33 DEBUG : Hubic Swift container rclone-test-kufaman1vajunuk6nuxoyep0: File to upload is small (0 bytes), uploading instead of streaming 2022/03/02 06:22:34 DEBUG : file1: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2022/03/02 06:22:34 INFO : file1: Copied (new) 2022/03/02 06:22:34 DEBUG : : Added virtual directory entry vAddFile: "file1" 2022/03/02 06:22:34 DEBUG : file1: OpenFile: flags=O_WRONLY|O_CREATE|O_TRUNC, perm=-rwxrwxrwx 2022/03/02 06:22:34 DEBUG : file1: Open: flags=O_WRONLY|O_CREATE|O_TRUNC 2022/03/02 06:22:34 DEBUG : : Added virtual directory entry vAddFile: "file1" 2022/03/02 06:22:34 DEBUG : file1: >Open: fd=file1 (w), err= 2022/03/02 06:22:34 DEBUG : file1: >OpenFile: fd=file1 (w), err= 2022/03/02 06:22:34 DEBUG : : Added virtual directory entry vAddFile: "file1" 2022/03/02 06:22:34 DEBUG : Hubic Swift container rclone-test-kufaman1vajunuk6nuxoyep0: File to upload is small (7 bytes), uploading instead of streaming 2022/03/02 06:22:36 DEBUG : file1: md5 = 9141de76717e095d4dd05f1e686ad6a8 OK 2022/03/02 06:22:36 INFO : file1: Copied (new) 2022/03/02 06:22:36 DEBUG : : Added virtual directory entry vAddFile: "file1" 2022/03/02 06:22:36 DEBUG : WaitForWriters: timeout=30s 2022/03/02 06:22:36 DEBUG : : Looking for writers 2022/03/02 06:22:36 DEBUG : file1: reading active writers 2022/03/02 06:22:36 DEBUG : >WaitForWriters: --- PASS: TestWriteFileHandleMethods (6.92s) === RUN TestWriteFileHandleWriteAt run.go:181: Remote "Hubic Swift container rclone-test-kufaman1vajunuk6nuxoyep0", Local "Local file system at /tmp/rclone3515240406", Modify Window "1ns" 2022/03/02 06:22:38 INFO : Hubic Swift container rclone-test-kufaman1vajunuk6nuxoyep0: poll-interval is not supported by this remote 2022/03/02 06:22:38 DEBUG : file1: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2022/03/02 06:22:38 DEBUG : file1: Open: flags=O_WRONLY|O_CREATE 2022/03/02 06:22:38 DEBUG : : Added virtual directory entry vAddFile: "file1" 2022/03/02 06:22:38 DEBUG : file1: >Open: fd=file1 (w), err= 2022/03/02 06:22:38 DEBUG : file1: >OpenFile: fd=file1 (w), err= 2022/03/02 06:22:38 DEBUG : : Added virtual directory entry vAddFile: "file1" 2022/03/02 06:22:38 DEBUG : file1: waiting for in-sequence write to 100 for 1s 2022/03/02 06:22:39 DEBUG : file1: aborting in-sequence write wait, off=100 2022/03/02 06:22:39 DEBUG : file1: failed to wait for in-sequence write to 100 2022/03/02 06:22:39 ERROR : file1: WriteFileHandle.Write: can't seek in file without --vfs-cache-mode >= writes 2022/03/02 06:22:39 DEBUG : Hubic Swift container rclone-test-kufaman1vajunuk6nuxoyep0: File to upload is small (11 bytes), uploading instead of streaming 2022/03/02 06:22:40 DEBUG : file1: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2022/03/02 06:22:40 INFO : file1: Copied (new) 2022/03/02 06:22:40 DEBUG : : Added virtual directory entry vAddFile: "file1" 2022/03/02 06:22:40 ERROR : file1: WriteFileHandle.Write: error: Bad file descriptor 2022/03/02 06:22:40 DEBUG : WaitForWriters: timeout=30s 2022/03/02 06:22:40 DEBUG : : Looking for writers 2022/03/02 06:22:40 DEBUG : file1: reading active writers 2022/03/02 06:22:40 DEBUG : >WaitForWriters: --- PASS: TestWriteFileHandleWriteAt (3.91s) === RUN TestWriteFileHandleFlush run.go:181: Remote "Hubic Swift container rclone-test-kufaman1vajunuk6nuxoyep0", Local "Local file system at /tmp/rclone3515240406", Modify Window "1ns" 2022/03/02 06:22:42 INFO : Hubic Swift container rclone-test-kufaman1vajunuk6nuxoyep0: poll-interval is not supported by this remote 2022/03/02 06:22:42 DEBUG : file1: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2022/03/02 06:22:42 DEBUG : file1: Open: flags=O_WRONLY|O_CREATE 2022/03/02 06:22:42 DEBUG : : Added virtual directory entry vAddFile: "file1" 2022/03/02 06:22:42 DEBUG : file1: >Open: fd=file1 (w), err= 2022/03/02 06:22:42 DEBUG : file1: >OpenFile: fd=file1 (w), err= 2022/03/02 06:22:42 DEBUG : file1: WriteFileHandle.Flush unwritten handle, writing 0 bytes to avoid race conditions 2022/03/02 06:22:42 DEBUG : : Added virtual directory entry vAddFile: "file1" 2022/03/02 06:22:42 DEBUG : Hubic Swift container rclone-test-kufaman1vajunuk6nuxoyep0: File to upload is small (5 bytes), uploading instead of streaming 2022/03/02 06:22:43 DEBUG : file1: md5 = 5d41402abc4b2a76b9719d911017c592 OK 2022/03/02 06:22:43 INFO : file1: Copied (new) 2022/03/02 06:22:43 DEBUG : : Added virtual directory entry vAddFile: "file1" 2022/03/02 06:22:43 DEBUG : file1: WriteFileHandle.Flush nothing to do 2022/03/02 06:22:43 DEBUG : WaitForWriters: timeout=30s 2022/03/02 06:22:43 DEBUG : : Looking for writers 2022/03/02 06:22:43 DEBUG : file1: reading active writers 2022/03/02 06:22:43 DEBUG : >WaitForWriters: --- PASS: TestWriteFileHandleFlush (2.97s) === RUN TestWriteFileModTimeWithOpenWriters run.go:181: Remote "Hubic Swift container rclone-test-kufaman1vajunuk6nuxoyep0", Local "Local file system at /tmp/rclone3515240406", Modify Window "1ns" 2022/03/02 06:22:44 INFO : Hubic Swift container rclone-test-kufaman1vajunuk6nuxoyep0: poll-interval is not supported by this remote 2022/03/02 06:22:44 DEBUG : file1: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2022/03/02 06:22:45 DEBUG : file1: Open: flags=O_WRONLY|O_CREATE 2022/03/02 06:22:45 DEBUG : : Added virtual directory entry vAddFile: "file1" 2022/03/02 06:22:45 DEBUG : file1: >Open: fd=file1 (w), err= 2022/03/02 06:22:45 DEBUG : file1: >OpenFile: fd=file1 (w), err= 2022/03/02 06:22:48 DEBUG : Can set mod time: true 2022/03/02 06:22:48 DEBUG : : Added virtual directory entry vAddFile: "file1" 2022/03/02 06:22:48 DEBUG : Hubic Swift container rclone-test-kufaman1vajunuk6nuxoyep0: File to upload is small (2 bytes), uploading instead of streaming 2022/03/02 06:22:50 DEBUG : file1: md5 = 49f68a5c8493ec2c0bf489821c21fc3b OK 2022/03/02 06:22:50 INFO : file1: Copied (new) 2022/03/02 06:22:51 DEBUG : file1: Applied pending mod time 2012-11-18 17:32:31 +0000 UTC OK 2022/03/02 06:22:51 DEBUG : : Added virtual directory entry vAddFile: "file1" 2022/03/02 06:22:51 DEBUG : WaitForWriters: timeout=30s 2022/03/02 06:22:51 DEBUG : : Looking for writers 2022/03/02 06:22:51 DEBUG : file1: reading active writers 2022/03/02 06:22:51 DEBUG : >WaitForWriters: --- PASS: TestWriteFileModTimeWithOpenWriters (8.01s) === RUN TestFileReadAtZeroLength run.go:181: Remote "Hubic Swift container rclone-test-kufaman1vajunuk6nuxoyep0", Local "Local file system at /tmp/rclone3515240406", Modify Window "1ns" 2022/03/02 06:22:52 INFO : Hubic Swift container rclone-test-kufaman1vajunuk6nuxoyep0: poll-interval is not supported by this remote 2022/03/02 06:22:52 DEBUG : file1: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2022/03/02 06:22:53 DEBUG : file1: Open: flags=O_WRONLY|O_CREATE 2022/03/02 06:22:53 DEBUG : : Added virtual directory entry vAddFile: "file1" 2022/03/02 06:22:53 DEBUG : file1: >Open: fd=file1 (w), err= 2022/03/02 06:22:53 DEBUG : file1: >OpenFile: fd=file1 (w), err= 2022/03/02 06:22:53 DEBUG : : Added virtual directory entry vAddFile: "file1" 2022/03/02 06:22:53 DEBUG : Hubic Swift container rclone-test-kufaman1vajunuk6nuxoyep0: File to upload is small (0 bytes), uploading instead of streaming 2022/03/02 06:22:54 DEBUG : file1: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2022/03/02 06:22:54 INFO : file1: Copied (new) 2022/03/02 06:22:54 DEBUG : : Added virtual directory entry vAddFile: "file1" 2022/03/02 06:22:54 DEBUG : file1: OpenFile: flags=O_RDONLY, perm=---------- 2022/03/02 06:22:54 DEBUG : file1: Open: flags=O_RDONLY 2022/03/02 06:22:54 DEBUG : file1: >Open: fd=file1 (r), err= 2022/03/02 06:22:54 DEBUG : file1: >OpenFile: fd=file1 (r), err= 2022/03/02 06:22:54 DEBUG : file1: ChunkedReader.openRange at 0 length 134217728 2022/03/02 06:22:55 DEBUG : file1: ChunkedReader.Read at 0 length 1024 chunkOffset 0 chunkSize 134217728 2022/03/02 06:22:55 DEBUG : WaitForWriters: timeout=30s 2022/03/02 06:22:55 DEBUG : : Looking for writers 2022/03/02 06:22:55 DEBUG : file1: reading active writers 2022/03/02 06:22:55 DEBUG : >WaitForWriters: --- PASS: TestFileReadAtZeroLength (3.46s) === RUN TestFileReadAtNonZeroLength run.go:181: Remote "Hubic Swift container rclone-test-kufaman1vajunuk6nuxoyep0", Local "Local file system at /tmp/rclone3515240406", Modify Window "1ns" 2022/03/02 06:22:56 INFO : Hubic Swift container rclone-test-kufaman1vajunuk6nuxoyep0: poll-interval is not supported by this remote 2022/03/02 06:22:56 DEBUG : file1: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2022/03/02 06:22:56 DEBUG : file1: Open: flags=O_WRONLY|O_CREATE 2022/03/02 06:22:56 DEBUG : : Added virtual directory entry vAddFile: "file1" 2022/03/02 06:22:56 DEBUG : file1: >Open: fd=file1 (w), err= 2022/03/02 06:22:56 DEBUG : file1: >OpenFile: fd=file1 (w), err= 2022/03/02 06:22:56 DEBUG : : Added virtual directory entry vAddFile: "file1" 2022/03/02 06:22:56 DEBUG : Hubic Swift container rclone-test-kufaman1vajunuk6nuxoyep0: File to upload is small (100 bytes), uploading instead of streaming 2022/03/02 06:22:58 DEBUG : file1: md5 = a534fd909a1bd0b884538ec67e091426 OK 2022/03/02 06:22:58 INFO : file1: Copied (new) 2022/03/02 06:22:58 DEBUG : : Added virtual directory entry vAddFile: "file1" 2022/03/02 06:22:58 DEBUG : file1: OpenFile: flags=O_RDONLY, perm=---------- 2022/03/02 06:22:58 DEBUG : file1: Open: flags=O_RDONLY 2022/03/02 06:22:58 DEBUG : file1: >Open: fd=file1 (r), err= 2022/03/02 06:22:58 DEBUG : file1: >OpenFile: fd=file1 (r), err= 2022/03/02 06:22:58 DEBUG : file1: ChunkedReader.openRange at 0 length 134217728 2022/03/02 06:22:58 DEBUG : file1: ChunkedReader.Read at 0 length 1024 chunkOffset 0 chunkSize 134217728 2022/03/02 06:22:58 DEBUG : WaitForWriters: timeout=30s 2022/03/02 06:22:58 DEBUG : : Looking for writers 2022/03/02 06:22:58 DEBUG : file1: reading active writers 2022/03/02 06:22:58 DEBUG : >WaitForWriters: --- PASS: TestFileReadAtNonZeroLength (3.35s) FAIL 2022/03/02 06:22:59 DEBUG : Hubic Swift container rclone-test-kufaman1vajunuk6nuxoyep0: Purge remote 2022/03/02 06:22:59 DEBUG : Waiting for deletions to finish 2022/03/02 06:23:00 INFO : Swift container rclone-test-kufaman1vajunuk6nuxoyep0: Container "rclone-test-kufaman1vajunuk6nuxoyep0" removed "./vfs.test -test.v -test.timeout 1h0m0s -remote TestHubic: -verbose -test.run '^(TestDirCreate|TestDirForgetPath|TestDirMkdir|TestDirMkdirSub|TestDirOpen|TestDirReadDirAll|TestDirRemove|TestDirRemoveAll|TestDirRemoveName|TestDirRename|TestDirSetModTime|TestDirStat|TestDirWalk|TestFileReadAtNonZeroLength|TestFileReadAtZeroLength|TestRcGetVFS|TestReadFileHandleFlush|TestReadFileHandleMethods|TestReadFileHandleReadAt|TestReadFileHandleRelease|TestReadFileHandleSeek|TestVFSNew|TestVFSOpenFile|TestVFSRename|TestVFSStat|TestVFSStatParent|TestWriteFileHandleFlush|TestWriteFileHandleMethods|TestWriteFileHandleWriteAt|TestWriteFileModTimeWithOpenWriters)$'" - Finished ERROR in 3m4.241138624s (try 2/5): exit status 1: Failed [TestDirRemoveName]