"./vfs.test -test.v -test.timeout 2h0m0s -remote TestDrime: -verbose" - Starting (try 1/5) 2026/03/05 05:37:48 DEBUG : Creating backend with remote "TestDrime:rclone-test-wigucoc0wuka" 2026/03/05 05:37:48 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/03/05 05:37:49 DEBUG : Creating backend with remote "/tmp/rclone3614224526" === RUN TestDirHandleMethods run.go:185: Remote "drime root 'rclone-test-wigucoc0wuka'", Local "Local file system at /tmp/rclone3614224526", Modify Window "876000h0m0s" 2026/03/05 05:37:49 INFO : drime root 'rclone-test-wigucoc0wuka': poll-interval is not supported by this remote 2026/03/05 05:37:51 DEBUG : WaitForWriters: timeout=30s 2026/03/05 05:37:51 DEBUG : dir: Looking for writers 2026/03/05 05:37:51 DEBUG : Looking for writers 2026/03/05 05:37:51 DEBUG : dir: reading active writers 2026/03/05 05:37:51 DEBUG : >WaitForWriters: --- PASS: TestDirHandleMethods (3.48s) === RUN TestDirHandleReaddir run.go:185: Remote "drime root 'rclone-test-wigucoc0wuka'", Local "Local file system at /tmp/rclone3614224526", Modify Window "876000h0m0s" 2026/03/05 05:37:52 INFO : drime root 'rclone-test-wigucoc0wuka': poll-interval is not supported by this remote 2026/03/05 05:37:57 DEBUG : WaitForWriters: timeout=30s 2026/03/05 05:37:57 DEBUG : dir/subdir: Looking for writers 2026/03/05 05:37:57 DEBUG : dir: Looking for writers 2026/03/05 05:37:57 DEBUG : file1: reading active writers 2026/03/05 05:37:57 DEBUG : file2: reading active writers 2026/03/05 05:37:57 DEBUG : subdir: reading active writers 2026/03/05 05:37:57 DEBUG : Looking for writers 2026/03/05 05:37:57 DEBUG : dir: reading active writers 2026/03/05 05:37:57 DEBUG : >WaitForWriters: run.go:130: removing dir "dir/subdir" failed - try 1/3: directory not empty --- PASS: TestDirHandleReaddir (7.81s) === RUN TestDirHandleReaddirnames run.go:185: Remote "drime root 'rclone-test-wigucoc0wuka'", Local "Local file system at /tmp/rclone3614224526", Modify Window "876000h0m0s" 2026/03/05 05:38:00 INFO : drime root 'rclone-test-wigucoc0wuka': poll-interval is not supported by this remote 2026/03/05 05:38:02 DEBUG : WaitForWriters: timeout=30s 2026/03/05 05:38:02 DEBUG : dir: Looking for writers 2026/03/05 05:38:02 DEBUG : file1: reading active writers 2026/03/05 05:38:02 DEBUG : Looking for writers 2026/03/05 05:38:02 DEBUG : dir: reading active writers 2026/03/05 05:38:02 DEBUG : >WaitForWriters: --- PASS: TestDirHandleReaddirnames (3.15s) === RUN TestDirMethods run.go:185: Remote "drime root 'rclone-test-wigucoc0wuka'", Local "Local file system at /tmp/rclone3614224526", Modify Window "876000h0m0s" 2026/03/05 05:38:03 INFO : drime root 'rclone-test-wigucoc0wuka': poll-interval is not supported by this remote 2026/03/05 05:38:05 DEBUG : WaitForWriters: timeout=30s 2026/03/05 05:38:05 DEBUG : dir: Looking for writers 2026/03/05 05:38:05 DEBUG : Looking for writers 2026/03/05 05:38:05 DEBUG : dir: reading active writers 2026/03/05 05:38:05 DEBUG : >WaitForWriters: --- PASS: TestDirMethods (3.21s) === RUN TestDirForgetAll run.go:185: Remote "drime root 'rclone-test-wigucoc0wuka'", Local "Local file system at /tmp/rclone3614224526", Modify Window "876000h0m0s" 2026/03/05 05:38:07 INFO : drime root 'rclone-test-wigucoc0wuka': poll-interval is not supported by this remote 2026/03/05 05:38:09 DEBUG : dir: forgetting directory cache 2026/03/05 05:38:09 DEBUG : forgetting directory cache 2026/03/05 05:38:09 DEBUG : dir: forgetting directory cache 2026/03/05 05:38:09 DEBUG : WaitForWriters: timeout=30s 2026/03/05 05:38:09 DEBUG : Looking for writers 2026/03/05 05:38:09 DEBUG : >WaitForWriters: --- PASS: TestDirForgetAll (3.24s) === RUN TestDirForgetPath run.go:185: Remote "drime root 'rclone-test-wigucoc0wuka'", Local "Local file system at /tmp/rclone3614224526", Modify Window "876000h0m0s" 2026/03/05 05:38:10 INFO : drime root 'rclone-test-wigucoc0wuka': poll-interval is not supported by this remote 2026/03/05 05:38:12 DEBUG : ForgetPath: relativePath="dir/notfound", type=1 2026/03/05 05:38:12 DEBUG : dir: invalidating directory cache 2026/03/05 05:38:12 DEBUG : >ForgetPath: 2026/03/05 05:38:12 DEBUG : ForgetPath: relativePath="dir", type=0 2026/03/05 05:38:12 DEBUG : invalidating directory cache 2026/03/05 05:38:12 DEBUG : dir: forgetting directory cache 2026/03/05 05:38:12 DEBUG : >ForgetPath: 2026/03/05 05:38:12 DEBUG : ForgetPath: relativePath="not/in/cache", type=0 2026/03/05 05:38:12 DEBUG : >ForgetPath: 2026/03/05 05:38:12 DEBUG : WaitForWriters: timeout=30s 2026/03/05 05:38:12 DEBUG : dir: Looking for writers 2026/03/05 05:38:12 DEBUG : Looking for writers 2026/03/05 05:38:12 DEBUG : dir: reading active writers 2026/03/05 05:38:12 DEBUG : >WaitForWriters: --- PASS: TestDirForgetPath (3.27s) === RUN TestDirWalk run.go:185: Remote "drime root 'rclone-test-wigucoc0wuka'", Local "Local file system at /tmp/rclone3614224526", Modify Window "876000h0m0s" 2026/03/05 05:38:13 INFO : drime root 'rclone-test-wigucoc0wuka': poll-interval is not supported by this remote 2026/03/05 05:38:18 DEBUG : forgetting directory cache 2026/03/05 05:38:18 DEBUG : dir: forgetting directory cache 2026/03/05 05:38:18 DEBUG : WaitForWriters: timeout=30s 2026/03/05 05:38:18 DEBUG : dir: Looking for writers 2026/03/05 05:38:18 DEBUG : fil/a/b: Looking for writers 2026/03/05 05:38:18 DEBUG : fil/a: Looking for writers 2026/03/05 05:38:18 DEBUG : b: reading active writers 2026/03/05 05:38:18 DEBUG : fil: Looking for writers 2026/03/05 05:38:18 DEBUG : a: reading active writers 2026/03/05 05:38:18 DEBUG : Looking for writers 2026/03/05 05:38:18 DEBUG : dir: reading active writers 2026/03/05 05:38:18 DEBUG : fil: reading active writers 2026/03/05 05:38:18 DEBUG : >WaitForWriters: run.go:130: removing dir "fil/a/b" failed - try 1/3: directory not empty --- PASS: TestDirWalk (9.49s) === RUN TestDirSetModTime run.go:185: Remote "drime root 'rclone-test-wigucoc0wuka'", Local "Local file system at /tmp/rclone3614224526", Modify Window "876000h0m0s" 2026/03/05 05:38:23 INFO : drime root 'rclone-test-wigucoc0wuka': poll-interval is not supported by this remote 2026/03/05 05:38:24 DEBUG : WaitForWriters: timeout=30s 2026/03/05 05:38:24 DEBUG : dir: Looking for writers 2026/03/05 05:38:24 DEBUG : Looking for writers 2026/03/05 05:38:24 DEBUG : dir: reading active writers 2026/03/05 05:38:24 DEBUG : >WaitForWriters: --- PASS: TestDirSetModTime (3.04s) === RUN TestDirStat run.go:185: Remote "drime root 'rclone-test-wigucoc0wuka'", Local "Local file system at /tmp/rclone3614224526", Modify Window "876000h0m0s" 2026/03/05 05:38:26 INFO : drime root 'rclone-test-wigucoc0wuka': poll-interval is not supported by this remote 2026/03/05 05:38:28 DEBUG : WaitForWriters: timeout=30s 2026/03/05 05:38:28 DEBUG : dir: Looking for writers 2026/03/05 05:38:28 DEBUG : file1: reading active writers 2026/03/05 05:38:28 DEBUG : Looking for writers 2026/03/05 05:38:28 DEBUG : dir: reading active writers 2026/03/05 05:38:28 DEBUG : >WaitForWriters: --- PASS: TestDirStat (3.27s) === RUN TestDirReadDirAll run.go:185: Remote "drime root 'rclone-test-wigucoc0wuka'", Local "Local file system at /tmp/rclone3614224526", Modify Window "876000h0m0s" 2026/03/05 05:38:29 INFO : drime root 'rclone-test-wigucoc0wuka': poll-interval is not supported by this remote === RUN TestDirReadDirAll/Virtual 2026/03/05 05:38:33 DEBUG : dir: Added virtual directory entry vAddFile: "virtualFile" 2026/03/05 05:38:33 DEBUG : dir: Added virtual directory entry vAddDir: "virtualDir" 2026/03/05 05:38:33 DEBUG : dir: Added virtual directory entry vDel: "file2" 2026/03/05 05:38:33 DEBUG : dir: Added virtual directory entry vDel: "subdir" 2026/03/05 05:38:35 DEBUG : dir: invalidating directory cache 2026/03/05 05:38:36 DEBUG : dir: Removed virtual directory entry vAddDir: "virtualDir" 2026/03/05 05:38:36 DEBUG : dir: Removed virtual directory entry vDel: "file2" 2026/03/05 05:38:36 DEBUG : dir: Removed virtual directory entry vDel: "subdir" 2026/03/05 05:38:36 DEBUG : dir/file1: Reset virtual modtime 2026/03/05 05:38:36 DEBUG : dir: Removed virtual directory entry vAddFile: "virtualFile" 2026/03/05 05:38:36 DEBUG : dir/virtualFile: Reset virtual modtime 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/dir_test.go:300 Error: Not equal: expected: []string{"file1,14,false", "virtualDir,0,true", "virtualFile,20,false"} actual : []string{"file1,14,false", "file2,15,false", "subdir,0,true", "virtualDir,0,true", "virtualFile,20,false"} Diff: --- Expected +++ Actual @@ -1,3 +1,5 @@ -([]string) (len=3) { +([]string) (len=5) { (string) (len=14) "file1,14,false", + (string) (len=14) "file2,15,false", + (string) (len=13) "subdir,0,true", (string) (len=17) "virtualDir,0,true", Test: TestDirReadDirAll/Virtual 2026/03/05 05:38:36 DEBUG : dir: Added virtual directory entry vAddFile: "virtualFile2" 2026/03/05 05:38:36 DEBUG : dir: Added virtual directory entry vAddDir: "virtualDir2" 2026/03/05 05:38:36 DEBUG : dir: Added virtual directory entry vDel: "file1" 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/dir_test.go:313 Error: Not equal: expected: []string{"virtualDir,0,true", "virtualDir2,0,true", "virtualFile,20,false", "virtualFile2,100,false"} actual : []string{"file2,15,false", "subdir,0,true", "virtualDir,0,true", "virtualDir2,0,true", "virtualFile,20,false", "virtualFile2,100,false"} Diff: --- Expected +++ Actual @@ -1,2 +1,4 @@ -([]string) (len=4) { +([]string) (len=6) { + (string) (len=14) "file2,15,false", + (string) (len=13) "subdir,0,true", (string) (len=17) "virtualDir,0,true", Test: TestDirReadDirAll/Virtual 2026/03/05 05:38:36 DEBUG : dir: invalidating directory cache 2026/03/05 05:38:36 DEBUG : dir: Removed virtual directory entry vAddDir: "virtualDir2" 2026/03/05 05:38:36 DEBUG : dir: Removed virtual directory entry vDel: "file1" 2026/03/05 05:38:36 DEBUG : dir/file2: Reset virtual modtime 2026/03/05 05:38:36 DEBUG : dir/virtualFile: Reset virtual modtime 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/dir_test.go:324 Error: Not equal: expected: []string{"file1,14,false", "virtualDir,0,true", "virtualFile,20,false", "virtualFile2,100,false"} actual : []string{"file1,14,false", "file2,15,false", "subdir,0,true", "virtualDir,0,true", "virtualFile,20,false", "virtualFile2,100,false"} Diff: --- Expected +++ Actual @@ -1,3 +1,5 @@ -([]string) (len=4) { +([]string) (len=6) { (string) (len=14) "file1,14,false", + (string) (len=14) "file2,15,false", + (string) (len=13) "subdir,0,true", (string) (len=17) "virtualDir,0,true", Test: TestDirReadDirAll/Virtual 2026/03/05 05:38:36 DEBUG : forgetting directory cache 2026/03/05 05:38:36 DEBUG : dir: forgetting directory cache 2026/03/05 05:38:36 DEBUG : dir/virtualDir: forgetting directory cache 2026/03/05 05:38:36 DEBUG : dir/subdir: forgetting directory cache 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/dir_test.go:329 Error: Not equal: expected: []string{"file1,14,false", "virtualDir,0,true", "virtualFile,20,false", "virtualFile2,100,false"} actual : []string{"file1,14,false", "file2,15,false", "subdir,0,true", "virtualDir,0,true", "virtualFile,20,false", "virtualFile2,100,false"} Diff: --- Expected +++ Actual @@ -1,3 +1,5 @@ -([]string) (len=4) { +([]string) (len=6) { (string) (len=14) "file1,14,false", + (string) (len=14) "file2,15,false", + (string) (len=13) "subdir,0,true", (string) (len=17) "virtualDir,0,true", Test: TestDirReadDirAll/Virtual 2026/03/05 05:38:36 DEBUG : WaitForWriters: timeout=30s 2026/03/05 05:38:36 DEBUG : dir/subdir: Looking for writers 2026/03/05 05:38:36 DEBUG : dir/virtualDir: Looking for writers 2026/03/05 05:38:36 DEBUG : dir: Looking for writers 2026/03/05 05:38:36 DEBUG : file1: reading active writers 2026/03/05 05:38:36 DEBUG : file2: reading active writers 2026/03/05 05:38:36 DEBUG : subdir: reading active writers 2026/03/05 05:38:36 DEBUG : virtualFile: reading active writers 2026/03/05 05:38:36 DEBUG : virtualDir: reading active writers 2026/03/05 05:38:36 DEBUG : virtualFile2: reading active writers 2026/03/05 05:38:36 DEBUG : Looking for writers 2026/03/05 05:38:36 DEBUG : dir: reading active writers 2026/03/05 05:38:36 DEBUG : >WaitForWriters: === NAME TestDirReadDirAll run.go:130: removing dir "dir/virtualDir" failed - try 1/3: directory not empty --- FAIL: TestDirReadDirAll (10.43s) --- FAIL: TestDirReadDirAll/Virtual (2.86s) === RUN TestDirOpen run.go:185: Remote "drime root 'rclone-test-wigucoc0wuka'", Local "Local file system at /tmp/rclone3614224526", Modify Window "876000h0m0s" 2026/03/05 05:38:39 INFO : drime root 'rclone-test-wigucoc0wuka': poll-interval is not supported by this remote 2026/03/05 05:38:41 ERROR : dir/: Can only open directories read only 2026/03/05 05:38:41 DEBUG : WaitForWriters: timeout=30s 2026/03/05 05:38:41 DEBUG : dir: Looking for writers 2026/03/05 05:38:41 DEBUG : Looking for writers 2026/03/05 05:38:41 DEBUG : dir: reading active writers 2026/03/05 05:38:41 DEBUG : >WaitForWriters: run.go:130: removing dir "dir" failed - try 1/3: directory not empty --- PASS: TestDirOpen (4.13s) === RUN TestDirCreate run.go:185: Remote "drime root 'rclone-test-wigucoc0wuka'", Local "Local file system at /tmp/rclone3614224526", Modify Window "876000h0m0s" 2026/03/05 05:38:44 INFO : drime root 'rclone-test-wigucoc0wuka': poll-interval is not supported by this remote 2026/03/05 05:38:45 DEBUG : dir/potato: Open: flags=O_WRONLY|O_CREATE 2026/03/05 05:38:45 DEBUG : dir: Added virtual directory entry vAddFile: "potato" 2026/03/05 05:38:45 DEBUG : dir/potato: >Open: fd=dir/potato (w), err= 2026/03/05 05:38:45 DEBUG : dir: Added virtual directory entry vAddFile: "potato" 2026/03/05 05:38:45 DEBUG : drime root 'rclone-test-wigucoc0wuka': File to upload is small (5 bytes), uploading instead of streaming 2026/03/05 05:38:46 DEBUG : dir/potato: size = 5 OK 2026/03/05 05:38:46 NOTICE: drime root 'rclone-test-wigucoc0wuka': --checksum is in use but the source and destination have no hashes in common; falling back to --size-only 2026/03/05 05:38:46 DEBUG : dir/potato: Size of src and dst objects identical 2026/03/05 05:38:46 DEBUG : dir: Added virtual directory entry vAddFile: "potato" 2026/03/05 05:38:46 DEBUG : WaitForWriters: timeout=30s 2026/03/05 05:38:46 DEBUG : dir: Looking for writers 2026/03/05 05:38:46 DEBUG : file1: reading active writers 2026/03/05 05:38:46 DEBUG : potato: reading active writers 2026/03/05 05:38:46 DEBUG : Looking for writers 2026/03/05 05:38:46 DEBUG : dir: reading active writers 2026/03/05 05:38:46 DEBUG : >WaitForWriters: run.go:130: removing dir "dir" failed - try 1/3: directory not empty --- PASS: TestDirCreate (5.37s) === RUN TestDirMkdir run.go:185: Remote "drime root 'rclone-test-wigucoc0wuka'", Local "Local file system at /tmp/rclone3614224526", Modify Window "876000h0m0s" 2026/03/05 05:38:49 INFO : drime root 'rclone-test-wigucoc0wuka': poll-interval is not supported by this remote 2026/03/05 05:38:51 DEBUG : dir: Added virtual directory entry vAddDir: "sub" 2026/03/05 05:38:52 DEBUG : WaitForWriters: timeout=30s 2026/03/05 05:38:52 DEBUG : dir/sub: Looking for writers 2026/03/05 05:38:52 DEBUG : dir: Looking for writers 2026/03/05 05:38:52 DEBUG : file1: reading active writers 2026/03/05 05:38:52 DEBUG : sub: reading active writers 2026/03/05 05:38:52 DEBUG : Looking for writers 2026/03/05 05:38:52 DEBUG : dir: reading active writers 2026/03/05 05:38:52 DEBUG : >WaitForWriters: --- PASS: TestDirMkdir (5.04s) === RUN TestDirMkdirSub run.go:185: Remote "drime root 'rclone-test-wigucoc0wuka'", Local "Local file system at /tmp/rclone3614224526", Modify Window "876000h0m0s" 2026/03/05 05:38:54 INFO : drime root 'rclone-test-wigucoc0wuka': poll-interval is not supported by this remote 2026/03/05 05:38:56 DEBUG : dir: Added virtual directory entry vAddDir: "sub" 2026/03/05 05:38:57 DEBUG : dir/sub: Added virtual directory entry vAddDir: "subsub" 2026/03/05 05:38:58 DEBUG : WaitForWriters: timeout=30s 2026/03/05 05:38:58 DEBUG : dir/sub/subsub: Looking for writers 2026/03/05 05:38:58 DEBUG : dir/sub: Looking for writers 2026/03/05 05:38:58 DEBUG : subsub: reading active writers 2026/03/05 05:38:58 DEBUG : dir: Looking for writers 2026/03/05 05:38:58 DEBUG : file1: reading active writers 2026/03/05 05:38:58 DEBUG : sub: reading active writers 2026/03/05 05:38:58 DEBUG : Looking for writers 2026/03/05 05:38:58 DEBUG : dir: reading active writers 2026/03/05 05:38:58 DEBUG : >WaitForWriters: --- PASS: TestDirMkdirSub (6.42s) === RUN TestDirRemove run.go:185: Remote "drime root 'rclone-test-wigucoc0wuka'", Local "Local file system at /tmp/rclone3614224526", Modify Window "876000h0m0s" 2026/03/05 05:39:00 INFO : drime root 'rclone-test-wigucoc0wuka': poll-interval is not supported by this remote 2026/03/05 05:39:02 ERROR : dir/: Dir.Remove not empty 2026/03/05 05:39:02 DEBUG : dir/file1: Remove: 2026/03/05 05:39:02 DEBUG : dir: Added virtual directory entry vDel: "file1" 2026/03/05 05:39:02 DEBUG : dir/file1: >Remove: err= 2026/03/05 05:39:03 ERROR : dir/: Dir.Remove failed to remove directory: directory not empty dir_test.go:456: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/vfs/dir_test.go:456 Error: Received unexpected error: directory not empty Test: TestDirRemove 2026/03/05 05:39:03 DEBUG : WaitForWriters: timeout=30s 2026/03/05 05:39:03 DEBUG : dir: Looking for writers 2026/03/05 05:39:03 DEBUG : Looking for writers 2026/03/05 05:39:03 DEBUG : dir: reading active writers 2026/03/05 05:39:03 DEBUG : >WaitForWriters: fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:292: Sleeping for 6s just to make sure --- FAIL: TestDirRemove (11.39s) === RUN TestDirRemoveAll run.go:185: Remote "drime root 'rclone-test-wigucoc0wuka'", Local "Local file system at /tmp/rclone3614224526", Modify Window "876000h0m0s" 2026/03/05 05:39:12 INFO : drime root 'rclone-test-wigucoc0wuka': poll-interval is not supported by this remote 2026/03/05 05:39:14 DEBUG : dir/file1: Remove: 2026/03/05 05:39:14 DEBUG : dir: Added virtual directory entry vDel: "file1" 2026/03/05 05:39:14 DEBUG : dir/file1: >Remove: err= 2026/03/05 05:39:14 ERROR : dir/: Dir.Remove failed to remove directory: directory not empty dir_test.go:481: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/vfs/dir_test.go:481 Error: Received unexpected error: directory not empty Test: TestDirRemoveAll 2026/03/05 05:39:14 DEBUG : WaitForWriters: timeout=30s 2026/03/05 05:39:14 DEBUG : dir: Looking for writers 2026/03/05 05:39:14 DEBUG : Looking for writers 2026/03/05 05:39:14 DEBUG : dir: reading active writers 2026/03/05 05:39:14 DEBUG : >WaitForWriters: fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:292: Sleeping for 6s just to make sure --- FAIL: TestDirRemoveAll (10.99s) === RUN TestDirRemoveName run.go:185: Remote "drime root 'rclone-test-wigucoc0wuka'", Local "Local file system at /tmp/rclone3614224526", Modify Window "876000h0m0s" 2026/03/05 05:39:23 INFO : drime root 'rclone-test-wigucoc0wuka': poll-interval is not supported by this remote 2026/03/05 05:39:25 DEBUG : dir/file1: Remove: 2026/03/05 05:39:25 DEBUG : dir: Added virtual directory entry vDel: "file1" 2026/03/05 05:39:25 DEBUG : dir/file1: >Remove: err= 2026/03/05 05:39:25 DEBUG : WaitForWriters: timeout=30s 2026/03/05 05:39:25 DEBUG : dir: Looking for writers 2026/03/05 05:39:25 DEBUG : Looking for writers 2026/03/05 05:39:25 DEBUG : dir: reading active writers 2026/03/05 05:39:25 DEBUG : >WaitForWriters: --- PASS: TestDirRemoveName (3.61s) === RUN TestDirRename run.go:185: Remote "drime root 'rclone-test-wigucoc0wuka'", Local "Local file system at /tmp/rclone3614224526", Modify Window "876000h0m0s" 2026/03/05 05:39:26 INFO : drime root 'rclone-test-wigucoc0wuka': poll-interval is not supported by this remote 2026/03/05 05:39:29 ERROR : dir/not found: Dir.Rename error: file does not exist 2026/03/05 05:47:51 DEBUG : dir: forgetting directory cache 2026/03/05 05:47:51 DEBUG : forgetting directory cache 2026/03/05 05:47:51 DEBUG : dir: forgetting directory cache 2026/03/05 05:47:56 DEBUG : forgetting directory cache 2026/03/05 05:47:56 DEBUG : dir: forgetting directory cache 2026/03/05 05:47:56 DEBUG : dir/subdir: forgetting directory cache 2026/03/05 05:48:02 DEBUG : forgetting directory cache 2026/03/05 05:48:02 DEBUG : dir: forgetting directory cache 2026/03/05 05:48:05 DEBUG : forgetting directory cache 2026/03/05 05:48:05 DEBUG : dir: forgetting directory cache 2026/03/05 05:48:05 DEBUG : dir: forgetting directory cache 2026/03/05 05:48:12 DEBUG : forgetting directory cache 2026/03/05 05:48:12 DEBUG : dir: forgetting directory cache 2026/03/05 05:48:18 DEBUG : forgetting directory cache 2026/03/05 05:48:18 DEBUG : fil: forgetting directory cache 2026/03/05 05:48:18 DEBUG : fil/a: forgetting directory cache 2026/03/05 05:48:18 DEBUG : fil/a/b: forgetting directory cache 2026/03/05 05:48:18 DEBUG : dir: forgetting directory cache 2026/03/05 05:48:18 DEBUG : dir: forgetting directory cache 2026/03/05 05:48:24 DEBUG : dir: forgetting directory cache 2026/03/05 05:48:24 DEBUG : forgetting directory cache 2026/03/05 05:48:24 DEBUG : dir: forgetting directory cache 2026/03/05 05:48:27 DEBUG : forgetting directory cache 2026/03/05 05:48:27 DEBUG : dir: forgetting directory cache 2026/03/05 05:48:33 DEBUG : dir/subdir: forgetting directory cache 2026/03/05 05:48:36 DEBUG : dir/virtualDir2: forgetting directory cache 2026/03/05 05:48:36 DEBUG : forgetting directory cache 2026/03/05 05:48:36 DEBUG : dir: forgetting directory cache 2026/03/05 05:48:36 DEBUG : dir/subdir: forgetting directory cache 2026/03/05 05:48:36 DEBUG : dir/virtualDir: forgetting directory cache 2026/03/05 05:48:36 DEBUG : dir: forgetting directory cache 2026/03/05 05:48:36 DEBUG : dir/subdir: forgetting directory cache 2026/03/05 05:48:36 DEBUG : dir/virtualDir: forgetting directory cache 2026/03/05 05:48:41 DEBUG : dir: forgetting directory cache 2026/03/05 05:48:41 DEBUG : forgetting directory cache 2026/03/05 05:48:41 DEBUG : dir: forgetting directory cache 2026/03/05 05:48:45 DEBUG : forgetting directory cache 2026/03/05 05:48:45 DEBUG : dir: forgetting directory cache 2026/03/05 05:48:45 DEBUG : dir: Removed virtual directory entry vAddFile: "potato" 2026/03/05 05:48:51 DEBUG : forgetting directory cache 2026/03/05 05:48:51 DEBUG : dir: forgetting directory cache 2026/03/05 05:48:51 DEBUG : dir/sub: forgetting directory cache 2026/03/05 05:48:51 DEBUG : dir: Removed virtual directory entry vAddDir: "sub" 2026/03/05 05:48:56 DEBUG : forgetting directory cache 2026/03/05 05:48:56 DEBUG : dir: forgetting directory cache 2026/03/05 05:48:56 DEBUG : dir/sub: forgetting directory cache 2026/03/05 05:48:56 DEBUG : dir/sub/subsub: forgetting directory cache 2026/03/05 05:48:56 DEBUG : dir/sub: Removed virtual directory entry vAddDir: "subsub" 2026/03/05 05:48:56 DEBUG : dir: Removed virtual directory entry vAddDir: "sub" 2026/03/05 05:49:02 DEBUG : forgetting directory cache 2026/03/05 05:49:02 DEBUG : dir: forgetting directory cache 2026/03/05 05:49:02 DEBUG : dir: Removed virtual directory entry vDel: "file1" 2026/03/05 05:49:13 DEBUG : forgetting directory cache 2026/03/05 05:49:13 DEBUG : dir: forgetting directory cache 2026/03/05 05:49:13 DEBUG : dir: Removed virtual directory entry vDel: "file1" 2026/03/05 05:49:24 DEBUG : forgetting directory cache 2026/03/05 05:49:24 DEBUG : dir: forgetting directory cache 2026/03/05 05:49:24 DEBUG : dir: Removed virtual directory entry vDel: "file1" 2026/03/05 05:49:28 DEBUG : forgetting directory cache 2026/03/05 05:49:28 DEBUG : dir: forgetting directory cache 2026/03/05 05:58:36 DEBUG : forgetting directory cache 2026/03/05 05:58:36 DEBUG : dir: forgetting directory cache 2026/03/05 05:58:36 DEBUG : dir/subdir: forgetting directory cache 2026/03/05 05:58:36 DEBUG : dir/virtualDir: forgetting directory cache 2026/03/05 05:58:36 DEBUG : dir: forgetting directory cache 2026/03/05 05:58:36 DEBUG : dir/subdir: forgetting directory cache 2026/03/05 05:58:36 DEBUG : dir/virtualDir: forgetting directory cache 2026/03/05 06:08:36 DEBUG : forgetting directory cache 2026/03/05 06:08:36 DEBUG : dir: forgetting directory cache 2026/03/05 06:08:36 DEBUG : dir/virtualDir: forgetting directory cache 2026/03/05 06:08:36 DEBUG : dir/subdir: forgetting directory cache 2026/03/05 06:08:36 DEBUG : dir: forgetting directory cache 2026/03/05 06:08:36 DEBUG : dir/subdir: forgetting directory cache 2026/03/05 06:08:36 DEBUG : dir/virtualDir: forgetting directory cache 2026/03/05 06:18:36 DEBUG : forgetting directory cache 2026/03/05 06:18:36 DEBUG : dir: forgetting directory cache 2026/03/05 06:18:36 DEBUG : dir/virtualDir: forgetting directory cache 2026/03/05 06:18:36 DEBUG : dir/subdir: forgetting directory cache 2026/03/05 06:18:36 DEBUG : dir: forgetting directory cache 2026/03/05 06:18:36 DEBUG : dir/virtualDir: forgetting directory cache 2026/03/05 06:18:36 DEBUG : dir/subdir: forgetting directory cache 2026/03/05 06:28:36 DEBUG : dir: forgetting directory cache 2026/03/05 06:28:36 DEBUG : dir/virtualDir: forgetting directory cache 2026/03/05 06:28:36 DEBUG : dir/subdir: forgetting directory cache 2026/03/05 06:28:36 DEBUG : forgetting directory cache 2026/03/05 06:28:36 DEBUG : dir: forgetting directory cache 2026/03/05 06:28:36 DEBUG : dir/subdir: forgetting directory cache 2026/03/05 06:28:36 DEBUG : dir/virtualDir: forgetting directory cache 2026/03/05 06:38:36 DEBUG : forgetting directory cache 2026/03/05 06:38:36 DEBUG : dir: forgetting directory cache 2026/03/05 06:38:36 DEBUG : dir/subdir: forgetting directory cache 2026/03/05 06:38:36 DEBUG : dir/virtualDir: forgetting directory cache 2026/03/05 06:38:36 DEBUG : dir: forgetting directory cache 2026/03/05 06:38:36 DEBUG : dir/virtualDir: forgetting directory cache 2026/03/05 06:38:36 DEBUG : dir/subdir: forgetting directory cache 2026/03/05 06:48:36 DEBUG : forgetting directory cache 2026/03/05 06:48:36 DEBUG : dir: forgetting directory cache 2026/03/05 06:48:36 DEBUG : dir: forgetting directory cache 2026/03/05 06:48:36 DEBUG : dir/subdir: forgetting directory cache 2026/03/05 06:48:36 DEBUG : dir/subdir: forgetting directory cache 2026/03/05 06:48:36 DEBUG : dir/virtualDir: forgetting directory cache 2026/03/05 06:48:36 DEBUG : dir/virtualDir: forgetting directory cache 2026/03/05 06:58:36 DEBUG : forgetting directory cache 2026/03/05 06:58:36 DEBUG : dir: forgetting directory cache 2026/03/05 06:58:36 DEBUG : dir: forgetting directory cache 2026/03/05 06:58:36 DEBUG : dir/subdir: forgetting directory cache 2026/03/05 06:58:36 DEBUG : dir/subdir: forgetting directory cache 2026/03/05 06:58:36 DEBUG : dir/virtualDir: forgetting directory cache 2026/03/05 06:58:36 DEBUG : dir/virtualDir: forgetting directory cache 2026/03/05 07:08:36 DEBUG : forgetting directory cache 2026/03/05 07:08:36 DEBUG : dir: forgetting directory cache 2026/03/05 07:08:36 DEBUG : dir: forgetting directory cache 2026/03/05 07:08:36 DEBUG : dir/subdir: forgetting directory cache 2026/03/05 07:08:36 DEBUG : dir/virtualDir: forgetting directory cache 2026/03/05 07:08:36 DEBUG : dir/virtualDir: forgetting directory cache 2026/03/05 07:08:36 DEBUG : dir/subdir: forgetting directory cache 2026/03/05 07:18:36 DEBUG : forgetting directory cache 2026/03/05 07:18:36 DEBUG : dir: forgetting directory cache 2026/03/05 07:18:36 DEBUG : dir/subdir: forgetting directory cache 2026/03/05 07:18:36 DEBUG : dir: forgetting directory cache 2026/03/05 07:18:36 DEBUG : dir/virtualDir: forgetting directory cache 2026/03/05 07:18:36 DEBUG : dir/subdir: forgetting directory cache 2026/03/05 07:18:36 DEBUG : dir/virtualDir: forgetting directory cache 2026/03/05 07:28:36 DEBUG : dir: forgetting directory cache 2026/03/05 07:28:36 DEBUG : dir/virtualDir: forgetting directory cache 2026/03/05 07:28:36 DEBUG : dir/subdir: forgetting directory cache 2026/03/05 07:28:36 DEBUG : forgetting directory cache 2026/03/05 07:28:36 DEBUG : dir: forgetting directory cache 2026/03/05 07:28:36 DEBUG : dir/subdir: forgetting directory cache 2026/03/05 07:28:36 DEBUG : dir/virtualDir: forgetting directory cache panic: test timed out after 2h0m0s running tests: TestDirRename (1h58m23s) goroutine 818556 [running]: testing.(*M).startAlarm.func1() /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/testing/testing.go:2682 +0x345 created by time.goFunc /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/time/sleep.go:215 +0x2d goroutine 1 [chan receive, 119 minutes]: testing.(*T).Run(0xc0001d6700, {0x29d312c?, 0xc000887ae8?}, 0x2b54138) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/testing/testing.go:2005 +0x485 testing.runTests.func1(0xc0001d6700) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/testing/testing.go:2477 +0x37 testing.tRunner(0xc0001d6700, 0xc000887c28) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/testing/testing.go:1934 +0xea testing.runTests(0xc0006db2d8, {0x456b060, 0x59, 0x59}, {0x856a80?, 0x45ad630?, 0x45894e0?}) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/testing/testing.go:2475 +0x4b4 testing.(*M).Run(0xc000892960) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/testing/testing.go:2337 +0x63a github.com/rclone/rclone/fstest.TestMain(0xc000892960) /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:74 +0xa6 github.com/rclone/rclone/vfs.TestMain(...) /home/rclone/go/src/github.com/rclone/rclone/vfs/vfs_test.go:36 main.main() _testmain.go:225 +0xa6 goroutine 10 [chan receive, 120 minutes]: github.com/rclone/rclone/fs/accounting.(*tokenBucket).startSignalHandler.func1() /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:24 +0x27 created by github.com/rclone/rclone/fs/accounting.(*tokenBucket).startSignalHandler in goroutine 1 /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:21 +0xa7 goroutine 9 [syscall, 120 minutes]: os/signal.signal_recv() /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/runtime/sigqueue.go:152 +0x29 os/signal.loop() /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/os/signal/signal_unix.go:23 +0x13 created by os/signal.Notify.func1.1 in goroutine 1 /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/os/signal/signal.go:152 +0x1f goroutine 1370 [select]: net/http.(*http2ClientConn).roundTrip(0xc000a91880, 0xc000a0ea00, 0x0) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/net/http/h2_bundle.go:8680 +0x53f net/http.(*http2ClientConn).RoundTrip(...) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/net/http/h2_bundle.go:8595 net/http.(*http2Transport).RoundTripOpt(0xc000812140, 0xc000a0ea00, {0xf8?, 0xa4?}) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/net/http/h2_bundle.go:7921 +0x1ec net/http.(*http2Transport).RoundTrip(0xc0000c4ea0?, 0xc000691720?) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/net/http/h2_bundle.go:7872 +0x17 net/http.(*Transport).roundTrip(0xc0000c4ea0, 0xc000b3a8c0) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/net/http/transport.go:694 +0xa7f net/http.(*Transport).RoundTrip(...) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/net/http/roundtrip.go:33 github.com/rclone/rclone/fs/fshttp.(*Transport).RoundTrip(0xc000117b60, 0xc000b3a8c0) /home/rclone/go/src/github.com/rclone/rclone/fs/fshttp/http.go:496 +0x34a net/http.send(0xc000b3a8c0, {0x2ec2920, 0xc000117b60}, {0xc00006ab01?, 0x708910?, 0x0?}) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/net/http/client.go:259 +0x5e2 net/http.(*Client).send(0xc0004ffb00, 0xc000b3a8c0, {0x41ecf4?, 0x1e?, 0x0?}) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/net/http/client.go:180 +0x91 net/http.(*Client).do(0xc0004ffb00, 0xc000b3a8c0) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/net/http/client.go:729 +0x9c9 net/http.(*Client).Do(...) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/net/http/client.go:587 github.com/rclone/rclone/lib/rest.(*Client).Call(0xc000690190, {0x2eec088, 0x45ad040}, 0xc00006b260) /home/rclone/go/src/github.com/rclone/rclone/lib/rest/rest.go:371 +0xd87 github.com/rclone/rclone/lib/rest.(*Client).callCodec(0xc000690190, {0x2eec088, 0x45ad040}, 0xc000b389a0?, {0x22f8720?, 0xc000b2c420?}, {0x22f8760, 0xc000b42800}, 0x3?, 0x2b55b90, ...) /home/rclone/go/src/github.com/rclone/rclone/lib/rest/rest.go:579 +0x40b github.com/rclone/rclone/lib/rest.(*Client).CallJSON(...) /home/rclone/go/src/github.com/rclone/rclone/lib/rest/rest.go:522 github.com/rclone/rclone/backend/drime.(*Fs).patch.func1() /home/rclone/go/src/github.com/rclone/rclone/backend/drime/drime.go:794 +0x8d github.com/rclone/rclone/fs.pacerInvoker(0x1, 0xa, 0x50?) /home/rclone/go/src/github.com/rclone/rclone/fs/pacer.go:86 +0x32 github.com/rclone/rclone/lib/pacer.(*Pacer).call(0xc000117bc0, 0xc0009a4cd0, 0xa) /home/rclone/go/src/github.com/rclone/rclone/lib/pacer/pacer.go:228 +0xd2 github.com/rclone/rclone/lib/pacer.(*Pacer).Call(0xc000117bc0, 0xc0009a4cd0) /home/rclone/go/src/github.com/rclone/rclone/lib/pacer/pacer.go:247 +0x92 github.com/rclone/rclone/backend/drime.(*Fs).patch(0xc000812000, {0x2eec088, 0x45ad040}, {0xc000b267a0, 0x9}, {0x29b7870, 0x4}, {0xc00093ed84, 0x4}) /home/rclone/go/src/github.com/rclone/rclone/backend/drime/drime.go:793 +0x387 github.com/rclone/rclone/backend/drime.(*Fs).rename(0xc000812000, {0x2eec088, 0x45ad040}, {0xc000b267a0, 0x9}, {0xc00093ed84, 0x4}) /home/rclone/go/src/github.com/rclone/rclone/backend/drime/drime.go:805 +0x96 github.com/rclone/rclone/backend/drime.(*Fs).moveTo(0xc000812000, {0x2eec088, 0x45ad040}, {0xc000b267a0, 0x9}, {0xc00093e8d0, 0x3}, {0xc00093ed84, 0x4}, {0xc000585280, ...}, ...) /home/rclone/go/src/github.com/rclone/rclone/backend/drime/drime.go:865 +0x2c9 github.com/rclone/rclone/backend/drime.(*Fs).DirMove(0xc000812000, {0x2eec088, 0x45ad040}, {0x2f04300?, 0xc000812000}, {0xc00093e8d0, 0x3}, {0xc00093ed84?, 0x0?}) /home/rclone/go/src/github.com/rclone/rclone/backend/drime/drime.go:943 +0x125 github.com/rclone/rclone/fs/operations.DirMove({0x2eec088, 0x45ad040}, {0x2f04300, 0xc000812000}, {0xc00093e8d0, 0x3}, {0xc00093ed84, 0x4}) /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations.go:2396 +0x123 github.com/rclone/rclone/vfs.(*Dir).Rename(0xc000861790, {0x29b6650, 0x3}, {0x29b7690, 0x4}, 0xc000861790) /home/rclone/go/src/github.com/rclone/rclone/vfs/dir.go:1223 +0x812 github.com/rclone/rclone/vfs.TestDirRename(0xc000a7a380) /home/rclone/go/src/github.com/rclone/rclone/vfs/dir_test.go:537 +0x493 testing.tRunner(0xc000a7a380, 0x2b54138) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/testing/testing.go:1934 +0xea created by testing.(*T).Run in goroutine 1 /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/testing/testing.go:1997 +0x465 goroutine 818555 [select]: net/http.(*http2clientStream).writeRequest(0xc0004a9200, 0xc000a0ea00, 0x0) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/net/http/h2_bundle.go:8850 +0xc65 net/http.(*http2clientStream).doRequest(0xc0004a9200, 0x2efb210?, 0xc000aeee08?) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/net/http/h2_bundle.go:8711 +0x56 created by net/http.(*http2ClientConn).roundTrip in goroutine 1370 /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/net/http/h2_bundle.go:8616 +0x485 goroutine 818554 [IO wait]: internal/poll.runtime_pollWait(0x7ecbf009aa00, 0x72) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/runtime/netpoll.go:351 +0x85 internal/poll.(*pollDesc).wait(0xc0001f3200?, 0xc0003d3100?, 0x0) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/internal/poll/fd_poll_runtime.go:84 +0x27 internal/poll.(*pollDesc).waitRead(...) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/internal/poll/fd_poll_runtime.go:89 internal/poll.(*FD).Read(0xc0001f3200, {0xc0003d3100, 0xd80, 0xd80}) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/internal/poll/fd_unix.go:165 +0x279 net.(*netFD).Read(0xc0001f3200, {0xc0003d3100?, 0x231d5503061f3068?, 0xb500148016301804?}) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/net/fd_posix.go:68 +0x25 net.(*conn).Read(0xc000686280, {0xc0003d3100?, 0x6e656c2e692e3231?, 0x302f67726f2e7263?}) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/net/net.go:196 +0x45 github.com/rclone/rclone/fs/fshttp.(*timeoutConn).Read(0xc0006dee70, {0xc0003d3100?, 0x7ecba81b4c00?, 0x7ecbf012a5c0?}) /home/rclone/go/src/github.com/rclone/rclone/fs/fshttp/dialer.go:111 +0x29 crypto/tls.(*atLeastReader).Read(0xc0005c2c78, {0xc0003d3100?, 0x4fd810402040279?, 0xa5007e00f800fa81?}) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/crypto/tls/conn.go:816 +0x3b bytes.(*Buffer).ReadFrom(0xc000aef0a8, {0x2ec4340, 0xc0005c2c78}) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/bytes/buffer.go:217 +0x98 crypto/tls.(*Conn).readFromUntil(0xc000aeee08, {0x7ecba80fcce8, 0xc0006dee70}, 0x448c34?) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/crypto/tls/conn.go:838 +0xde crypto/tls.(*Conn).readRecordOrCCS(0xc000aeee08, 0x0) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/crypto/tls/conn.go:627 +0x3db crypto/tls.(*Conn).readRecord(...) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/crypto/tls/conn.go:589 crypto/tls.(*Conn).Read(0xc000aeee08, {0xc000826000, 0x1000, 0xc000502ee0?}) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/crypto/tls/conn.go:1392 +0x145 bufio.(*Reader).Read(0xc000c0b0e0, {0xc00031e580, 0x9, 0x50?}) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/bufio/bufio.go:245 +0x197 io.ReadAtLeast({0x2ec1180, 0xc000c0b0e0}, {0xc00031e580, 0x9, 0x9}, 0x9) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/io/io.go:335 +0x8e io.ReadFull(...) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/io/io.go:354 net/http.http2readFrameHeader({0xc00031e580, 0x9, 0xc000691770?}, {0x2ec1180?, 0xc000c0b0e0?}) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/net/http/h2_bundle.go:1811 +0x65 net/http.(*http2Framer).ReadFrame(0xc00031e540) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/net/http/h2_bundle.go:2078 +0x7d net/http.(*http2clientConnReadLoop).run(0xc000b1dfa8) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/net/http/h2_bundle.go:9539 +0xda net/http.(*http2ClientConn).readLoop(0xc000a91880) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/net/http/h2_bundle.go:9408 +0x79 created by net/http.(*http2Transport).newClientConn in goroutine 818553 /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/net/http/h2_bundle.go:8192 +0xde5 goroutine 1371 [select, 119 minutes]: github.com/rclone/rclone/vfs.(*VFS).signalHandler(0xc000931900, {0x2eec130, 0xc000711040}) /home/rclone/go/src/github.com/rclone/rclone/vfs/vfs.go:290 +0xc5 created by github.com/rclone/rclone/vfs.New in goroutine 1370 /home/rclone/go/src/github.com/rclone/rclone/vfs/vfs.go:266 +0x808 "./vfs.test -test.v -test.timeout 2h0m0s -remote TestDrime: -verbose" - Finished ERROR in 2h0m1.236418756s (try 1/5): exit status 2: Failed [TestDirReadDirAll/Virtual TestDirRemove TestDirRemoveAll]