"./vfs.test -test.v -test.timeout 2h0m0s -remote TestInternxt: -list-retries 5 -verbose -test.run '^(TestDirRename|TestWriteFileHandleMethods|TestWriteFileHandleRelease|TestWriteFileModTimeWithOpenWriters)$|^TestFileSetModTime$/^(cache=off,open=false,write=false|cache=off,open=true,write=false)$'" - Starting (try 2/5) 2026/08/31 04:00:49 DEBUG : Creating backend with remote "TestInternxt:rclone-test-navasiq1tinu" 2026/08/31 04:00:49 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/08/31 04:00:49 DEBUG : User info: rootFolderId=2661cf64-e104-478b-b737-48c3ac8984d2, bucket=697358bf16ceabcf39af85f2 2026/08/31 04:00:49 DEBUG : Saving config "token" in section "TestInternxt" of the config file 2026/08/31 04:00:49 DEBUG : TestInternxt: Saved new token in config file 2026/08/31 04:00:49 DEBUG : Internxt root 'rclone-test-navasiq1tinu': Persisted rotated token from user info, expiry: 2026-09-07 04:00:49 +0000 UTC 2026/08/31 04:00:49 DEBUG : Creating backend with remote "/tmp/rclone1845167902" === RUN TestDirRename run.go:198: Remote "Internxt root 'rclone-test-navasiq1tinu'", Local "Local file system at /tmp/rclone1845167902", Modify Window "876000h0m0s" 2026/08/31 04:00:49 INFO : Internxt root 'rclone-test-navasiq1tinu': poll-interval is not supported by this remote 2026/08/31 04:00:49 NOTICE: Internxt root 'rclone-test-navasiq1tinu': --vfs-cache-mode writes or full is recommended for this remote as it can't stream FileLimitsResponse { "maxUploadFileSize": 107374182400, "versioning": { "enabled": true, "maxFileSize": 20971520, "retentionDays": 30, "maxVersions": 20 } } 2026/08/31 04:00:54 ERROR : dir/not found: Dir.Rename error: file does not exist 2026/08/31 04:00:54 DEBUG : dir: Updating dir with dir2 0x3c33394940c0 2026/08/31 04:00:54 DEBUG : dir: forgetting directory cache 2026/08/31 04:00:54 DEBUG : Added virtual directory entry vDel: "dir" 2026/08/31 04:00:54 DEBUG : Added virtual directory entry vAddDir: "dir2" 2026/08/31 04:00:56 INFO : dir2/file1: Moved (server-side) to: file2 2026/08/31 04:00:56 DEBUG : file2: Updating file with file2 0x3c33394ef110 2026/08/31 04:00:56 DEBUG : dir2: Added virtual directory entry vDel: "file1" 2026/08/31 04:00:56 DEBUG : Added virtual directory entry vAddFile: "file2" 2026/08/31 04:00:57 INFO : dir2/file3: Deleted 2026/08/31 04:00:58 INFO : file2: Moved (server-side) to: dir2/file3 2026/08/31 04:00:58 DEBUG : dir2/file3: Updating file with dir2/file3 0x3c33394ef110 2026/08/31 04:00:58 DEBUG : Added virtual directory entry vDel: "file2" 2026/08/31 04:00:58 DEBUG : dir2: Added virtual directory entry vAddFile: "file3" 2026/08/31 04:01:00 DEBUG : Added virtual directory entry vAddDir: "empty directory" 2026/08/31 04:01:00 DEBUG : empty directory: Updating dir with renamed empty directory 0x3c3339494240 2026/08/31 04:01:00 DEBUG : empty directory: forgetting directory cache 2026/08/31 04:01:00 DEBUG : Added virtual directory entry vDel: "empty directory" 2026/08/31 04:01:00 DEBUG : Added virtual directory entry vAddDir: "renamed empty directory" 2026/08/31 04:01:00 DEBUG : dir2: Renaming to "dir3" 2026/08/31 04:01:00 DEBUG : WaitForWriters: timeout=30s 2026/08/31 04:01:00 DEBUG : dir3: Looking for writers 2026/08/31 04:01:00 DEBUG : file3: reading active writers 2026/08/31 04:01:00 DEBUG : renamed empty directory: Looking for writers 2026/08/31 04:01:00 DEBUG : Looking for writers 2026/08/31 04:01:00 DEBUG : dir3: reading active writers 2026/08/31 04:01:00 DEBUG : renamed empty directory: reading active writers 2026/08/31 04:01:00 DEBUG : >WaitForWriters: 2026/08/31 04:01:01 ERROR : empty directory: error listing: directory not found --- PASS: TestDirRename (12.00s) === RUN TestFileSetModTime === RUN TestFileSetModTime/cache=off,open=false,write=false run.go:198: Remote "Internxt root 'rclone-test-navasiq1tinu'", Local "Local file system at /tmp/rclone1845167902", Modify Window "876000h0m0s" 2026/08/31 04:01:01 INFO : Internxt root 'rclone-test-navasiq1tinu': poll-interval is not supported by this remote 2026/08/31 04:01:01 NOTICE: Internxt root 'rclone-test-navasiq1tinu': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2026/08/31 04:01:08 DEBUG : Can set mod time: false file_test.go:97: can't set mod time 2026/08/31 04:01:08 DEBUG : WaitForWriters: timeout=30s 2026/08/31 04:01:08 DEBUG : renamed empty directory: Looking for writers 2026/08/31 04:01:08 DEBUG : dir: Looking for writers 2026/08/31 04:01:08 DEBUG : file1: reading active writers 2026/08/31 04:01:08 DEBUG : dir2: Looking for writers 2026/08/31 04:01:08 DEBUG : Looking for writers 2026/08/31 04:01:08 DEBUG : dir2: reading active writers 2026/08/31 04:01:08 DEBUG : renamed empty directory: reading active writers 2026/08/31 04:01:08 DEBUG : dir: reading active writers 2026/08/31 04:01:08 DEBUG : >WaitForWriters: 2026/08/31 04:01:11 ERROR : dir: error listing: directory not found fstest.go:299: Sleeping for 1s for list eventual consistency: 1/5 fstest.go:302: Flushing the directory cache fstest.go:293: Sleeping for 6s just to make sure === RUN TestFileSetModTime/cache=off,open=true,write=false file_test.go:93: can't set mod time --- PASS: TestFileSetModTime (17.27s) --- SKIP: TestFileSetModTime/cache=off,open=false,write=false (17.27s) --- SKIP: TestFileSetModTime/cache=off,open=true,write=false (0.00s) === RUN TestWriteFileHandleMethods run.go:198: Remote "Internxt root 'rclone-test-navasiq1tinu'", Local "Local file system at /tmp/rclone1845167902", Modify Window "876000h0m0s" 2026/08/31 04:01:19 INFO : Internxt root 'rclone-test-navasiq1tinu': poll-interval is not supported by this remote 2026/08/31 04:01:19 NOTICE: Internxt root 'rclone-test-navasiq1tinu': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2026/08/31 04:01:19 DEBUG : file1: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2026/08/31 04:01:21 DEBUG : file1: Open: flags=O_WRONLY|O_CREATE 2026/08/31 04:01:21 DEBUG : Added virtual directory entry vAddFile: "file1" 2026/08/31 04:01:21 DEBUG : file1: >Open: fd=file1 (w), err= 2026/08/31 04:01:21 DEBUG : file1: >OpenFile: fd=file1 (w), err= 2026/08/31 04:01:21 DEBUG : Added virtual directory entry vAddFile: "file1" 2026/08/31 04:01:21 ERROR : file1: WriteFileHandle: Read: Can't read and write to file without --vfs-cache-mode >= minimal 2026/08/31 04:01:21 ERROR : file1: WriteFileHandle: ReadAt: Can't read and write to file without --vfs-cache-mode >= minimal 2026/08/31 04:01:21 ERROR : file1: WriteFileHandle: Truncate: Can't change size without --vfs-cache-mode >= writes 2026/08/31 04:01:21 DEBUG : Internxt root 'rclone-test-navasiq1tinu': File to upload is small (5 bytes), uploading instead of streaming 2026/08/31 04:01:21 DEBUG : file1: size = 5 OK 2026/08/31 04:01:21 NOTICE: Internxt root 'rclone-test-navasiq1tinu': --checksum is in use but the source and destination have no hashes in common; falling back to --size-only 2026/08/31 04:01:21 DEBUG : file1: Size of src and dst objects identical 2026/08/31 04:01:21 DEBUG : Added virtual directory entry vAddFile: "file1" 2026/08/31 04:01:22 DEBUG : file1: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2026/08/31 04:01:22 DEBUG : file1: Open: flags=O_WRONLY|O_CREATE 2026/08/31 04:01:22 DEBUG : Added virtual directory entry vAddFile: "file1" 2026/08/31 04:01:22 DEBUG : file1: >Open: fd=file1 (w), err= 2026/08/31 04:01:22 DEBUG : file1: >OpenFile: fd=file1 (w), err= 2026/08/31 04:01:22 DEBUG : file1: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2026/08/31 04:01:22 DEBUG : file1: Open: flags=O_WRONLY|O_CREATE 2026/08/31 04:01:22 DEBUG : Added virtual directory entry vAddFile: "file1" 2026/08/31 04:01:22 DEBUG : file1: >Open: fd=file1 (w), err= 2026/08/31 04:01:22 DEBUG : file1: >OpenFile: fd=file1 (w), err= 2026/08/31 04:01:22 ERROR : file1: WriteFileHandle: Can't open for write without O_TRUNC on existing file without --vfs-cache-mode >= writes 2026/08/31 04:01:22 DEBUG : file1: OpenFile: flags=O_WRONLY|O_CREATE|O_TRUNC, perm=-rwxrwxrwx 2026/08/31 04:01:22 DEBUG : file1: Open: flags=O_WRONLY|O_CREATE|O_TRUNC 2026/08/31 04:01:22 DEBUG : Added virtual directory entry vAddFile: "file1" 2026/08/31 04:01:22 DEBUG : file1: >Open: fd=file1 (w), err= 2026/08/31 04:01:22 DEBUG : file1: >OpenFile: fd=file1 (w), err= 2026/08/31 04:01:22 DEBUG : Added virtual directory entry vAddFile: "file1" 2026/08/31 04:01:22 DEBUG : Internxt root 'rclone-test-navasiq1tinu': File to upload is small (0 bytes), uploading instead of streaming 2026/08/31 04:01:23 DEBUG : Internxt root 'rclone-test-navasiq1tinu': Renamed existing file file1 to backup file1.rclone-backup-romewol6. (UUID: f82c4f01-c7dd-454a-ae6e-27fec02cd7cc) 2026/08/31 04:01:24 ERROR : file1: WriteFileHandle.New Rcat failed: failed to create empty file metadata: create meta: File already exists (status 409) write_test.go:182: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/vfs/write_test.go:182 Error: Received unexpected error: failed to create empty file metadata: create meta: File already exists (status 409) Test: TestWriteFileHandleMethods dir_test.go:250: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/vfs/dir_test.go:250 /home/rclone/go/src/github.com/rclone/rclone/vfs/write_test.go:183 Error: Not equal: expected: []string{"file1,0,false"} actual : []string{"file1,5,false"} Diff: --- Expected +++ Actual @@ -1,3 +1,3 @@ ([]string) (len=1) { - (string) (len=13) "file1,0,false" + (string) (len=13) "file1,5,false" } Test: TestWriteFileHandleMethods 2026/08/31 04:01:24 DEBUG : file1: OpenFile: flags=O_WRONLY|O_CREATE|O_TRUNC, perm=-rwxrwxrwx 2026/08/31 04:01:24 DEBUG : file1: Open: flags=O_WRONLY|O_CREATE|O_TRUNC 2026/08/31 04:01:24 DEBUG : Added virtual directory entry vAddFile: "file1" 2026/08/31 04:01:24 DEBUG : file1: >Open: fd=file1 (w), err= 2026/08/31 04:01:24 DEBUG : file1: >OpenFile: fd=file1 (w), err= 2026/08/31 04:01:24 DEBUG : Added virtual directory entry vAddFile: "file1" 2026/08/31 04:01:24 DEBUG : Internxt root 'rclone-test-navasiq1tinu': File to upload is small (7 bytes), uploading instead of streaming 2026/08/31 04:01:25 DEBUG : file1: size = 7 OK 2026/08/31 04:01:25 DEBUG : file1: Size of src and dst objects identical 2026/08/31 04:01:25 DEBUG : Added virtual directory entry vAddFile: "file1" 2026/08/31 04:01:25 DEBUG : WaitForWriters: timeout=30s 2026/08/31 04:01:25 DEBUG : Looking for writers 2026/08/31 04:01:25 DEBUG : file1: reading active writers 2026/08/31 04:01:25 DEBUG : >WaitForWriters: --- FAIL: TestWriteFileHandleMethods (7.18s) === RUN TestWriteFileHandleRelease run.go:198: Remote "Internxt root 'rclone-test-navasiq1tinu'", Local "Local file system at /tmp/rclone1845167902", Modify Window "876000h0m0s" 2026/08/31 04:01:26 INFO : Internxt root 'rclone-test-navasiq1tinu': poll-interval is not supported by this remote 2026/08/31 04:01:26 NOTICE: Internxt root 'rclone-test-navasiq1tinu': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2026/08/31 04:01:26 DEBUG : file1: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2026/08/31 04:01:26 DEBUG : file1: Open: flags=O_WRONLY|O_CREATE 2026/08/31 04:01:26 DEBUG : Added virtual directory entry vAddFile: "file1" 2026/08/31 04:01:26 DEBUG : file1: >Open: fd=file1 (w), err= 2026/08/31 04:01:26 DEBUG : file1: >OpenFile: fd=file1 (w), err= 2026/08/31 04:01:26 DEBUG : file1: WriteFileHandle.Release closing 2026/08/31 04:01:26 DEBUG : Added virtual directory entry vAddFile: "file1" 2026/08/31 04:01:26 DEBUG : Internxt root 'rclone-test-navasiq1tinu': File to upload is small (0 bytes), uploading instead of streaming 2026/08/31 04:01:26 DEBUG : file1: size = 0 OK 2026/08/31 04:01:26 DEBUG : file1: Size of src and dst objects identical 2026/08/31 04:01:26 DEBUG : Added virtual directory entry vAddFile: "file1" 2026/08/31 04:01:26 DEBUG : file1: WriteFileHandle.Release nothing to do 2026/08/31 04:01:26 DEBUG : WaitForWriters: timeout=30s 2026/08/31 04:01:26 DEBUG : Looking for writers 2026/08/31 04:01:26 DEBUG : file1: reading active writers 2026/08/31 04:01:26 DEBUG : >WaitForWriters: --- PASS: TestWriteFileHandleRelease (1.22s) === RUN TestWriteFileModTimeWithOpenWriters run.go:198: Remote "Internxt root 'rclone-test-navasiq1tinu'", Local "Local file system at /tmp/rclone1845167902", Modify Window "876000h0m0s" 2026/08/31 04:01:27 INFO : Internxt root 'rclone-test-navasiq1tinu': poll-interval is not supported by this remote 2026/08/31 04:01:27 NOTICE: Internxt root 'rclone-test-navasiq1tinu': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2026/08/31 04:01:27 DEBUG : file1: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2026/08/31 04:01:27 DEBUG : file1: Open: flags=O_WRONLY|O_CREATE 2026/08/31 04:01:27 DEBUG : Added virtual directory entry vAddFile: "file1" 2026/08/31 04:01:27 DEBUG : file1: >Open: fd=file1 (w), err= 2026/08/31 04:01:27 DEBUG : file1: >OpenFile: fd=file1 (w), err= write_test.go:363: can't set mod time 2026/08/31 04:01:27 DEBUG : WaitForWriters: timeout=30s 2026/08/31 04:01:27 DEBUG : Looking for writers 2026/08/31 04:01:27 DEBUG : file1: reading active writers 2026/08/31 04:01:27 DEBUG : file1: active writers 1 2026/08/31 04:01:27 DEBUG : Still 1 writers active and 0 cache items in use, waiting 10ms 2026/08/31 04:01:27 DEBUG : Looking for writers 2026/08/31 04:01:27 DEBUG : file1: reading active writers 2026/08/31 04:01:27 DEBUG : file1: active writers 1 2026/08/31 04:01:27 DEBUG : Still 1 writers active and 0 cache items in use, waiting 20ms 2026/08/31 04:01:27 DEBUG : Looking for writers 2026/08/31 04:01:27 DEBUG : file1: reading active writers 2026/08/31 04:01:27 DEBUG : file1: active writers 1 2026/08/31 04:01:27 DEBUG : Still 1 writers active and 0 cache items in use, waiting 40ms 2026/08/31 04:01:27 DEBUG : Looking for writers 2026/08/31 04:01:27 DEBUG : file1: reading active writers 2026/08/31 04:01:27 DEBUG : file1: active writers 1 2026/08/31 04:01:27 DEBUG : Still 1 writers active and 0 cache items in use, waiting 80ms 2026/08/31 04:01:28 DEBUG : Looking for writers 2026/08/31 04:01:28 DEBUG : file1: reading active writers 2026/08/31 04:01:28 DEBUG : file1: active writers 1 2026/08/31 04:01:28 DEBUG : Still 1 writers active and 0 cache items in use, waiting 160ms 2026/08/31 04:01:28 DEBUG : Looking for writers 2026/08/31 04:01:28 DEBUG : file1: reading active writers 2026/08/31 04:01:28 DEBUG : file1: active writers 1 2026/08/31 04:01:28 DEBUG : Still 1 writers active and 0 cache items in use, waiting 320ms 2026/08/31 04:01:28 DEBUG : Looking for writers 2026/08/31 04:01:28 DEBUG : file1: reading active writers 2026/08/31 04:01:28 DEBUG : file1: active writers 1 2026/08/31 04:01:28 DEBUG : Still 1 writers active and 0 cache items in use, waiting 640ms 2026/08/31 04:01:29 DEBUG : Looking for writers 2026/08/31 04:01:29 DEBUG : file1: reading active writers 2026/08/31 04:01:29 DEBUG : file1: active writers 1 2026/08/31 04:01:29 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2026/08/31 04:01:30 DEBUG : Looking for writers 2026/08/31 04:01:30 DEBUG : file1: reading active writers 2026/08/31 04:01:30 DEBUG : file1: active writers 1 2026/08/31 04:01:30 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2026/08/31 04:01:31 DEBUG : Looking for writers 2026/08/31 04:01:31 DEBUG : file1: reading active writers 2026/08/31 04:01:31 DEBUG : file1: active writers 1 2026/08/31 04:01:31 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2026/08/31 04:01:32 DEBUG : Looking for writers 2026/08/31 04:01:32 DEBUG : file1: reading active writers 2026/08/31 04:01:32 DEBUG : file1: active writers 1 2026/08/31 04:01:32 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2026/08/31 04:01:33 DEBUG : Looking for writers 2026/08/31 04:01:33 DEBUG : file1: reading active writers 2026/08/31 04:01:33 DEBUG : file1: active writers 1 2026/08/31 04:01:33 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2026/08/31 04:01:34 DEBUG : Looking for writers 2026/08/31 04:01:34 DEBUG : file1: reading active writers 2026/08/31 04:01:34 DEBUG : file1: active writers 1 2026/08/31 04:01:34 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2026/08/31 04:01:35 DEBUG : Looking for writers 2026/08/31 04:01:35 DEBUG : file1: reading active writers 2026/08/31 04:01:35 DEBUG : file1: active writers 1 2026/08/31 04:01:35 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2026/08/31 04:01:36 DEBUG : Looking for writers 2026/08/31 04:01:36 DEBUG : file1: reading active writers 2026/08/31 04:01:36 DEBUG : file1: active writers 1 2026/08/31 04:01:36 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2026/08/31 04:01:37 DEBUG : Looking for writers 2026/08/31 04:01:37 DEBUG : file1: reading active writers 2026/08/31 04:01:37 DEBUG : file1: active writers 1 2026/08/31 04:01:37 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2026/08/31 04:01:38 DEBUG : Looking for writers 2026/08/31 04:01:38 DEBUG : file1: reading active writers 2026/08/31 04:01:38 DEBUG : file1: active writers 1 2026/08/31 04:01:38 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2026/08/31 04:01:39 DEBUG : Looking for writers 2026/08/31 04:01:39 DEBUG : file1: reading active writers 2026/08/31 04:01:39 DEBUG : file1: active writers 1 2026/08/31 04:01:39 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2026/08/31 04:01:40 DEBUG : Looking for writers 2026/08/31 04:01:40 DEBUG : file1: reading active writers 2026/08/31 04:01:40 DEBUG : file1: active writers 1 2026/08/31 04:01:40 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2026/08/31 04:01:41 DEBUG : Looking for writers 2026/08/31 04:01:41 DEBUG : file1: reading active writers 2026/08/31 04:01:41 DEBUG : file1: active writers 1 2026/08/31 04:01:41 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2026/08/31 04:01:42 DEBUG : Looking for writers 2026/08/31 04:01:42 DEBUG : file1: reading active writers 2026/08/31 04:01:42 DEBUG : file1: active writers 1 2026/08/31 04:01:42 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2026/08/31 04:01:43 DEBUG : Looking for writers 2026/08/31 04:01:43 DEBUG : file1: reading active writers 2026/08/31 04:01:43 DEBUG : file1: active writers 1 2026/08/31 04:01:43 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2026/08/31 04:01:44 DEBUG : Looking for writers 2026/08/31 04:01:44 DEBUG : file1: reading active writers 2026/08/31 04:01:44 DEBUG : file1: active writers 1 2026/08/31 04:01:44 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2026/08/31 04:01:45 DEBUG : Looking for writers 2026/08/31 04:01:45 DEBUG : file1: reading active writers 2026/08/31 04:01:45 DEBUG : file1: active writers 1 2026/08/31 04:01:45 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2026/08/31 04:01:46 DEBUG : Looking for writers 2026/08/31 04:01:46 DEBUG : file1: reading active writers 2026/08/31 04:01:46 DEBUG : file1: active writers 1 2026/08/31 04:01:46 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2026/08/31 04:01:47 DEBUG : Looking for writers 2026/08/31 04:01:47 DEBUG : file1: reading active writers 2026/08/31 04:01:47 DEBUG : file1: active writers 1 2026/08/31 04:01:47 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2026/08/31 04:01:48 DEBUG : Looking for writers 2026/08/31 04:01:48 DEBUG : file1: reading active writers 2026/08/31 04:01:48 DEBUG : file1: active writers 1 2026/08/31 04:01:48 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2026/08/31 04:01:49 DEBUG : Looking for writers 2026/08/31 04:01:49 DEBUG : file1: reading active writers 2026/08/31 04:01:49 DEBUG : file1: active writers 1 2026/08/31 04:01:49 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2026/08/31 04:01:50 DEBUG : Looking for writers 2026/08/31 04:01:50 DEBUG : file1: reading active writers 2026/08/31 04:01:50 DEBUG : file1: active writers 1 2026/08/31 04:01:50 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2026/08/31 04:01:51 DEBUG : Looking for writers 2026/08/31 04:01:51 DEBUG : file1: reading active writers 2026/08/31 04:01:51 DEBUG : file1: active writers 1 2026/08/31 04:01:51 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2026/08/31 04:01:52 DEBUG : Looking for writers 2026/08/31 04:01:52 DEBUG : file1: reading active writers 2026/08/31 04:01:52 DEBUG : file1: active writers 1 2026/08/31 04:01:52 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2026/08/31 04:01:53 DEBUG : Looking for writers 2026/08/31 04:01:53 DEBUG : file1: reading active writers 2026/08/31 04:01:53 DEBUG : file1: active writers 1 2026/08/31 04:01:53 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2026/08/31 04:01:54 DEBUG : Looking for writers 2026/08/31 04:01:54 DEBUG : file1: reading active writers 2026/08/31 04:01:54 DEBUG : file1: active writers 1 2026/08/31 04:01:54 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2026/08/31 04:01:55 DEBUG : Looking for writers 2026/08/31 04:01:55 DEBUG : file1: reading active writers 2026/08/31 04:01:55 DEBUG : file1: active writers 1 2026/08/31 04:01:55 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2026/08/31 04:01:56 DEBUG : Looking for writers 2026/08/31 04:01:56 DEBUG : file1: reading active writers 2026/08/31 04:01:56 DEBUG : file1: active writers 1 2026/08/31 04:01:56 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2026/08/31 04:01:57 DEBUG : Looking for writers 2026/08/31 04:01:57 DEBUG : file1: reading active writers 2026/08/31 04:01:57 DEBUG : file1: active writers 1 2026/08/31 04:01:57 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s 2026/08/31 04:01:57 ERROR : Exiting even though 1 writers active and 0 cache items in use after 30s Cache: 2026/08/31 04:01:57 DEBUG : >WaitForWriters: --- SKIP: TestWriteFileModTimeWithOpenWriters (30.60s) FAIL 2026/08/31 04:01:58 DEBUG : Internxt root 'rclone-test-navasiq1tinu': Purge dir "" "./vfs.test -test.v -test.timeout 2h0m0s -remote TestInternxt: -list-retries 5 -verbose -test.run '^(TestDirRename|TestWriteFileHandleMethods|TestWriteFileHandleRelease|TestWriteFileModTimeWithOpenWriters)$|^TestFileSetModTime$/^(cache=off,open=false,write=false|cache=off,open=true,write=false)$'" - Finished ERROR in 1m10.103612776s (try 2/5): exit status 1: Failed [TestWriteFileHandleMethods]