"./vfs.test -test.v -test.timeout 2h0m0s -remote TestInternxt: -list-retries 5 -verbose -test.run '^(TestCaseSensitivity|TestDirRename|TestWriteFileHandleRelease)$'" - Starting (try 3/5) 2026/09/30 06:36:03 DEBUG : Creating backend with remote "TestInternxt:rclone-test-wezeqiw6xato" 2026/09/30 06:36:03 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/09/30 06:36:03 DEBUG : User info: rootFolderId=2661cf64-e104-478b-b737-48c3ac8984d2, bucket=697358bf16ceabcf39af85f2 2026/09/30 06:36:03 DEBUG : Saving config "token" in section "TestInternxt" of the config file 2026/09/30 06:36:03 DEBUG : TestInternxt: Saved new token in config file 2026/09/30 06:36:03 DEBUG : Internxt root 'rclone-test-wezeqiw6xato': Persisted rotated token from user info, expiry: 2026-10-07 06:36:03 +0000 UTC 2026/09/30 06:36:03 DEBUG : Creating backend with remote "/tmp/rclone2339268185" === RUN TestDirRename run.go:198: Remote "Internxt root 'rclone-test-wezeqiw6xato'", Local "Local file system at /tmp/rclone2339268185", Modify Window "876000h0m0s" 2026/09/30 06:36:03 INFO : Internxt root 'rclone-test-wezeqiw6xato': poll-interval is not supported by this remote 2026/09/30 06:36:03 NOTICE: Internxt root 'rclone-test-wezeqiw6xato': --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/09/30 06:36:09 ERROR : dir/not found: Dir.Rename error: file does not exist 2026/09/30 06:36:09 DEBUG : dir: Updating dir with dir2 0x38d7cc540300 2026/09/30 06:36:09 DEBUG : dir: forgetting directory cache 2026/09/30 06:36:09 DEBUG : Added virtual directory entry vDel: "dir" 2026/09/30 06:36:09 DEBUG : Added virtual directory entry vAddDir: "dir2" 2026/09/30 06:36:10 INFO : dir2/file1: Moved (server-side) to: file2 2026/09/30 06:36:10 DEBUG : file2: Updating file with file2 0x38d7cc18b5f0 2026/09/30 06:36:10 DEBUG : dir2: Added virtual directory entry vDel: "file1" 2026/09/30 06:36:10 DEBUG : Added virtual directory entry vAddFile: "file2" 2026/09/30 06:36:11 INFO : dir2/file3: Deleted 2026/09/30 06:36:11 DEBUG : pacer: low level retry 1/10 (error move file: A file with the same name already exists in destination folder (status 409)) 2026/09/30 06:36:11 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2026/09/30 06:36:11 DEBUG : pacer: Reducing sleep to 15ms 2026/09/30 06:36:11 INFO : file2: Moved (server-side) to: dir2/file3 2026/09/30 06:36:11 DEBUG : dir2/file3: Updating file with dir2/file3 0x38d7cc18b5f0 2026/09/30 06:36:11 DEBUG : Added virtual directory entry vDel: "file2" 2026/09/30 06:36:11 DEBUG : dir2: Added virtual directory entry vAddFile: "file3" 2026/09/30 06:36:11 DEBUG : pacer: Reducing sleep to 11.25ms 2026/09/30 06:36:12 DEBUG : pacer: Reducing sleep to 10ms 2026/09/30 06:36:14 DEBUG : Added virtual directory entry vAddDir: "empty directory" 2026/09/30 06:36:14 DEBUG : empty directory: Updating dir with renamed empty directory 0x38d7cc19c900 2026/09/30 06:36:14 DEBUG : empty directory: forgetting directory cache 2026/09/30 06:36:14 DEBUG : Added virtual directory entry vDel: "empty directory" 2026/09/30 06:36:14 DEBUG : Added virtual directory entry vAddDir: "renamed empty directory" 2026/09/30 06:36:14 DEBUG : dir2: Renaming to "dir3" 2026/09/30 06:36:14 DEBUG : WaitForWriters: timeout=30s 2026/09/30 06:36:14 DEBUG : dir3: Looking for writers 2026/09/30 06:36:14 DEBUG : file3: reading active writers 2026/09/30 06:36:14 DEBUG : renamed empty directory: Looking for writers 2026/09/30 06:36:14 DEBUG : Looking for writers 2026/09/30 06:36:14 DEBUG : dir3: reading active writers 2026/09/30 06:36:14 DEBUG : renamed empty directory: reading active writers 2026/09/30 06:36:14 DEBUG : >WaitForWriters: fstest.go:299: Sleeping for 1s for list eventual consistency: 1/5 fstest.go:302: Flushing the directory cache fstest.go:299: Sleeping for 2s for list eventual consistency: 2/5 fstest.go:302: Flushing the directory cache fstest.go:299: Sleeping for 4s for list eventual consistency: 3/5 fstest.go:302: Flushing the directory cache fstest.go:299: Sleeping for 8s for list eventual consistency: 4/5 fstest.go:302: Flushing the directory cache fstest.go:299: Sleeping for 16s for list eventual consistency: 5/5 fstest.go:302: Flushing the directory cache fstest.go:328: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:328 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:339 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:406 /usr/local/go/src/testing/testing.go:1460 /usr/local/go/src/testing/testing.go:1820 /usr/local/go/src/testing/testing.go:2187 Error: Not equal: expected: []string{} actual : []string{"renamed empty directory"} Diff: --- Expected +++ Actual @@ -1,2 +1,3 @@ -([]string) { +([]string) (len=1) { + (string) (len=23) "renamed empty directory" } Test: TestDirRename Messages: directories --- FAIL: TestDirRename (47.99s) === RUN TestCaseSensitivity run.go:198: Remote "Internxt root 'rclone-test-wezeqiw6xato'", Local "Local file system at /tmp/rclone2339268185", Modify Window "876000h0m0s" 2026/09/30 06:36:55 INFO : Internxt root 'rclone-test-wezeqiw6xato': poll-interval is not supported by this remote 2026/09/30 06:36:55 NOTICE: Internxt root 'rclone-test-wezeqiw6xato': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2026/09/30 06:36:55 INFO : Internxt root 'rclone-test-wezeqiw6xato': poll-interval is not supported by this remote 2026/09/30 06:36:55 NOTICE: Internxt root 'rclone-test-wezeqiw6xato': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2026/09/30 06:36:55 DEBUG : FiLeA: OpenFile: flags=O_RDONLY, perm=-rwxrwxrwx 2026/09/30 06:36:56 DEBUG : FiLeA: Open: flags=O_RDONLY 2026/09/30 06:36:56 DEBUG : FiLeA: >Open: fd=FiLeA (r), err= 2026/09/30 06:36:56 DEBUG : FiLeA: >OpenFile: fd=FiLeA (r), err= 2026/09/30 06:36:56 DEBUG : FiLeA: ChunkedReader.openRange at 0 length 134217728 2026/09/30 06:36:57 DEBUG : FiLeA: ChunkedReader.Read at 0 length 5 chunkOffset 0 chunkSize 134217728 2026/09/30 06:36:57 DEBUG : FiLeA: OpenFile: flags=O_RDONLY, perm=-rwxrwxrwx 2026/09/30 06:36:57 DEBUG : FiLeA: Open: flags=O_RDONLY 2026/09/30 06:36:57 DEBUG : FiLeA: >Open: fd=FiLeA (r), err= 2026/09/30 06:36:57 DEBUG : FiLeA: >OpenFile: fd=FiLeA (r), err= 2026/09/30 06:36:57 DEBUG : FiLeA: ChunkedReader.openRange at 0 length 134217728 2026/09/30 06:36:58 DEBUG : FiLeA: ChunkedReader.Read at 0 length 5 chunkOffset 0 chunkSize 134217728 2026/09/30 06:36:58 DEBUG : FiLeA: OpenFile: flags=O_RDONLY, perm=-rwxrwxrwx 2026/09/30 06:36:58 DEBUG : FiLeA: Open: flags=O_RDONLY 2026/09/30 06:36:58 DEBUG : FiLeA: >Open: fd=FiLeA (r), err= 2026/09/30 06:36:58 DEBUG : FiLeA: >OpenFile: fd=FiLeA (r), err= 2026/09/30 06:36:58 DEBUG : FiLeA: ChunkedReader.openRange at 0 length 134217728 2026/09/30 06:36:58 DEBUG : FiLeA: ChunkedReader.Read at 0 length 5 chunkOffset 0 chunkSize 134217728 2026/09/30 06:36:58 DEBUG : FiLeB: OpenFile: flags=O_RDONLY, perm=-rwxrwxrwx 2026/09/30 06:36:58 DEBUG : FiLeB: Open: flags=O_RDONLY 2026/09/30 06:36:58 DEBUG : FiLeB: >Open: fd=FiLeB (r), err= 2026/09/30 06:36:58 DEBUG : FiLeB: >OpenFile: fd=FiLeB (r), err= 2026/09/30 06:36:58 DEBUG : FiLeB: ChunkedReader.openRange at 0 length 134217728 2026/09/30 06:36:58 DEBUG : FiLeB: ChunkedReader.Read at 0 length 5 chunkOffset 0 chunkSize 134217728 2026/09/30 06:36:58 DEBUG : FilEb: OpenFile: flags=O_RDONLY, perm=-rwxrwxrwx 2026/09/30 06:36:58 DEBUG : FilEb: Open: flags=O_RDONLY 2026/09/30 06:36:58 DEBUG : FilEb: >Open: fd=FilEb (r), err= 2026/09/30 06:36:58 DEBUG : FilEb: >OpenFile: fd=FilEb (r), err= 2026/09/30 06:36:58 DEBUG : FilEb: ChunkedReader.openRange at 0 length 134217728 2026/09/30 06:36:59 DEBUG : FilEb: ChunkedReader.Read at 0 length 5 chunkOffset 0 chunkSize 134217728 2026/09/30 06:36:59 DEBUG : FiLeA: OpenFile: flags=O_RDONLY, perm=-rwxrwxrwx 2026/09/30 06:36:59 DEBUG : FiLeA: Open: flags=O_RDONLY 2026/09/30 06:36:59 DEBUG : FiLeA: >Open: fd=FiLeA (r), err= 2026/09/30 06:36:59 DEBUG : FiLeA: >OpenFile: fd=FiLeA (r), err= 2026/09/30 06:36:59 DEBUG : FiLeA: ChunkedReader.openRange at 0 length 134217728 2026/09/30 06:37:00 DEBUG : FiLeA: ChunkedReader.Read at 0 length 5 chunkOffset 0 chunkSize 134217728 2026/09/30 06:37:00 DEBUG : fileA: OpenFile: flags=O_RDONLY, perm=-rwxrwxrwx 2026/09/30 06:37:00 DEBUG : FiLeA: Open: flags=O_RDONLY 2026/09/30 06:37:00 DEBUG : FiLeA: >Open: fd=FiLeA (r), err= 2026/09/30 06:37:00 DEBUG : fileA: >OpenFile: fd=FiLeA (r), err= 2026/09/30 06:37:00 DEBUG : FiLeA: ChunkedReader.openRange at 0 length 134217728 2026/09/30 06:37:00 DEBUG : FiLeA: ChunkedReader.Read at 0 length 5 chunkOffset 0 chunkSize 134217728 2026/09/30 06:37:00 DEBUG : filea: OpenFile: flags=O_RDONLY, perm=-rwxrwxrwx 2026/09/30 06:37:00 DEBUG : FiLeA: Open: flags=O_RDONLY 2026/09/30 06:37:00 DEBUG : FiLeA: >Open: fd=FiLeA (r), err= 2026/09/30 06:37:00 DEBUG : filea: >OpenFile: fd=FiLeA (r), err= 2026/09/30 06:37:00 DEBUG : FiLeA: ChunkedReader.openRange at 0 length 134217728 2026/09/30 06:37:00 DEBUG : FiLeA: ChunkedReader.Read at 0 length 5 chunkOffset 0 chunkSize 134217728 2026/09/30 06:37:00 DEBUG : FILEA: OpenFile: flags=O_RDONLY, perm=-rwxrwxrwx 2026/09/30 06:37:00 DEBUG : FiLeA: Open: flags=O_RDONLY 2026/09/30 06:37:00 DEBUG : FiLeA: >Open: fd=FiLeA (r), err= 2026/09/30 06:37:00 DEBUG : FILEA: >OpenFile: fd=FiLeA (r), err= 2026/09/30 06:37:00 DEBUG : FiLeA: ChunkedReader.openRange at 0 length 134217728 2026/09/30 06:37:01 DEBUG : FiLeA: ChunkedReader.Read at 0 length 5 chunkOffset 0 chunkSize 134217728 2026/09/30 06:37:01 DEBUG : FiLeB: OpenFile: flags=O_RDONLY, perm=-rwxrwxrwx 2026/09/30 06:37:01 DEBUG : FiLeB: Open: flags=O_RDONLY 2026/09/30 06:37:01 DEBUG : FiLeB: >Open: fd=FiLeB (r), err= 2026/09/30 06:37:01 DEBUG : FiLeB: >OpenFile: fd=FiLeB (r), err= 2026/09/30 06:37:01 DEBUG : FiLeB: ChunkedReader.openRange at 0 length 134217728 2026/09/30 06:37:01 DEBUG : FiLeB: ChunkedReader.Read at 0 length 5 chunkOffset 0 chunkSize 134217728 2026/09/30 06:37:01 DEBUG : FilEb: OpenFile: flags=O_RDONLY, perm=-rwxrwxrwx 2026/09/30 06:37:01 DEBUG : FilEb: Open: flags=O_RDONLY 2026/09/30 06:37:01 DEBUG : FilEb: >Open: fd=FilEb (r), err= 2026/09/30 06:37:01 DEBUG : FilEb: >OpenFile: fd=FilEb (r), err= 2026/09/30 06:37:01 DEBUG : FilEb: ChunkedReader.openRange at 0 length 134217728 2026/09/30 06:37:01 DEBUG : FilEb: ChunkedReader.Read at 0 length 5 chunkOffset 0 chunkSize 134217728 2026/09/30 06:37:01 DEBUG : fileb: OpenFile: flags=O_RDONLY, perm=-rwxrwxrwx 2026/09/30 06:37:01 ERROR : /: Dir.Stat error: duplicate filename "fileb" detected with case/unicode normalization settings 2026/09/30 06:37:01 DEBUG : fileb: >OpenFile: fd=, err=duplicate filename "fileb" detected with case/unicode normalization settings 2026/09/30 06:37:01 DEBUG : FILEB: OpenFile: flags=O_RDONLY, perm=-rwxrwxrwx 2026/09/30 06:37:01 ERROR : /: Dir.Stat error: duplicate filename "FILEB" detected with case/unicode normalization settings 2026/09/30 06:37:01 DEBUG : FILEB: >OpenFile: fd=, err=duplicate filename "FILEB" detected with case/unicode normalization settings 2026/09/30 06:37:01 DEBUG : FiLeA: OpenFile: flags=O_RDONLY, perm=-rwxrwxrwx 2026/09/30 06:37:01 DEBUG : FiLeA: Open: flags=O_RDONLY 2026/09/30 06:37:01 DEBUG : FiLeA: >Open: fd=FiLeA (r), err= 2026/09/30 06:37:01 DEBUG : FiLeA: >OpenFile: fd=FiLeA (r), err= 2026/09/30 06:37:01 DEBUG : FiLeA: ChunkedReader.openRange at 0 length 134217728 2026/09/30 06:37:02 DEBUG : FiLeA: ChunkedReader.Read at 0 length 5 chunkOffset 0 chunkSize 134217728 2026/09/30 06:37:02 DEBUG : fileA: OpenFile: flags=O_RDONLY, perm=-rwxrwxrwx 2026/09/30 06:37:02 DEBUG : fileA: >OpenFile: fd=, err=file does not exist 2026/09/30 06:37:02 DEBUG : filea: OpenFile: flags=O_RDONLY, perm=-rwxrwxrwx 2026/09/30 06:37:02 DEBUG : filea: >OpenFile: fd=, err=file does not exist 2026/09/30 06:37:02 DEBUG : FILEA: OpenFile: flags=O_RDONLY, perm=-rwxrwxrwx 2026/09/30 06:37:02 DEBUG : FILEA: >OpenFile: fd=, err=file does not exist 2026/09/30 06:37:02 DEBUG : FiLeB: OpenFile: flags=O_RDONLY, perm=-rwxrwxrwx 2026/09/30 06:37:02 DEBUG : FiLeB: Open: flags=O_RDONLY 2026/09/30 06:37:02 DEBUG : FiLeB: >Open: fd=FiLeB (r), err= 2026/09/30 06:37:02 DEBUG : FiLeB: >OpenFile: fd=FiLeB (r), err= 2026/09/30 06:37:02 DEBUG : FiLeB: ChunkedReader.openRange at 0 length 134217728 2026/09/30 06:37:02 DEBUG : FiLeB: ChunkedReader.Read at 0 length 5 chunkOffset 0 chunkSize 134217728 2026/09/30 06:37:02 DEBUG : FilEb: OpenFile: flags=O_RDONLY, perm=-rwxrwxrwx 2026/09/30 06:37:02 DEBUG : FilEb: Open: flags=O_RDONLY 2026/09/30 06:37:02 DEBUG : FilEb: >Open: fd=FilEb (r), err= 2026/09/30 06:37:02 DEBUG : FilEb: >OpenFile: fd=FilEb (r), err= 2026/09/30 06:37:02 DEBUG : FilEb: ChunkedReader.openRange at 0 length 134217728 2026/09/30 06:37:03 DEBUG : FilEb: ChunkedReader.Read at 0 length 5 chunkOffset 0 chunkSize 134217728 2026/09/30 06:37:03 DEBUG : fileb: OpenFile: flags=O_RDONLY, perm=-rwxrwxrwx 2026/09/30 06:37:03 DEBUG : fileb: >OpenFile: fd=, err=file does not exist 2026/09/30 06:37:03 DEBUG : FILEB: OpenFile: flags=O_RDONLY, perm=-rwxrwxrwx 2026/09/30 06:37:03 DEBUG : FILEB: >OpenFile: fd=, err=file does not exist 2026/09/30 06:37:03 DEBUG : WaitForWriters: timeout=30s 2026/09/30 06:37:03 DEBUG : renamed empty directory: Looking for writers 2026/09/30 06:37:03 DEBUG : Looking for writers 2026/09/30 06:37:03 DEBUG : renamed empty directory: reading active writers 2026/09/30 06:37:03 DEBUG : FiLeA: reading active writers 2026/09/30 06:37:03 DEBUG : FiLeB: reading active writers 2026/09/30 06:37:03 DEBUG : FilEb: reading active writers 2026/09/30 06:37:03 DEBUG : >WaitForWriters: 2026/09/30 06:37:03 DEBUG : WaitForWriters: timeout=30s 2026/09/30 06:37:03 DEBUG : renamed empty directory: Looking for writers 2026/09/30 06:37:03 DEBUG : Looking for writers 2026/09/30 06:37:03 DEBUG : renamed empty directory: reading active writers 2026/09/30 06:37:03 DEBUG : FiLeA: reading active writers 2026/09/30 06:37:03 DEBUG : FiLeB: reading active writers 2026/09/30 06:37:03 DEBUG : FilEb: reading active writers 2026/09/30 06:37:03 DEBUG : >WaitForWriters: --- PASS: TestCaseSensitivity (14.89s) === RUN TestWriteFileHandleRelease run.go:198: Remote "Internxt root 'rclone-test-wezeqiw6xato'", Local "Local file system at /tmp/rclone2339268185", Modify Window "876000h0m0s" 2026/09/30 06:37:06 INFO : Internxt root 'rclone-test-wezeqiw6xato': poll-interval is not supported by this remote 2026/09/30 06:37:06 NOTICE: Internxt root 'rclone-test-wezeqiw6xato': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2026/09/30 06:37:06 DEBUG : file1: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2026/09/30 06:37:07 DEBUG : file1: Open: flags=O_WRONLY|O_CREATE 2026/09/30 06:37:07 DEBUG : Added virtual directory entry vAddFile: "file1" 2026/09/30 06:37:07 DEBUG : file1: >Open: fd=file1 (w), err= 2026/09/30 06:37:07 DEBUG : file1: >OpenFile: fd=file1 (w), err= 2026/09/30 06:37:07 DEBUG : file1: WriteFileHandle.Release closing 2026/09/30 06:37:07 DEBUG : Added virtual directory entry vAddFile: "file1" 2026/09/30 06:37:07 DEBUG : Internxt root 'rclone-test-wezeqiw6xato': File to upload is small (0 bytes), uploading instead of streaming 2026/09/30 06:37:09 DEBUG : file1: size = 0 OK 2026/09/30 06:37:09 NOTICE: Internxt root 'rclone-test-wezeqiw6xato': --checksum is in use but the source and destination have no hashes in common; falling back to --size-only 2026/09/30 06:37:09 DEBUG : file1: Size of src and dst objects identical 2026/09/30 06:37:09 DEBUG : Added virtual directory entry vAddFile: "file1" 2026/09/30 06:37:09 DEBUG : file1: WriteFileHandle.Release nothing to do 2026/09/30 06:37:09 DEBUG : WaitForWriters: timeout=30s 2026/09/30 06:37:09 DEBUG : Looking for writers 2026/09/30 06:37:09 DEBUG : file1: reading active writers 2026/09/30 06:37:09 DEBUG : >WaitForWriters: --- PASS: TestWriteFileHandleRelease (3.33s) FAIL 2026/09/30 06:37:10 DEBUG : Internxt root 'rclone-test-wezeqiw6xato': Purge dir "" "./vfs.test -test.v -test.timeout 2h0m0s -remote TestInternxt: -list-retries 5 -verbose -test.run '^(TestCaseSensitivity|TestDirRename|TestWriteFileHandleRelease)$'" - Finished ERROR in 1m7.534230692s (try 3/5): exit status 1: Failed [TestDirRename]