"./vfs.test -test.v -test.timeout 1h0m0s -remote TestGoFile: -verbose -test.run '^(TestDirRename|TestWriteFileModTimeWithOpenWriters)$'" - Starting (try 2/5)
2024/11/04 04:55:11 DEBUG : Creating backend with remote "TestGoFile:rclone-test-dorefam0juqi"
2024/11/04 04:55:11 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2024/11/04 04:55:12 DEBUG : Creating backend with remote "/tmp/rclone399241679"
=== RUN   TestDirRename
    run.go:180: Remote "gofile root 'rclone-test-dorefam0juqi'", Local "Local file system at /tmp/rclone399241679", Modify Window "1s"
2024/11/04 04:55:12 INFO  : gofile root 'rclone-test-dorefam0juqi': poll-interval is not supported by this remote
2024/11/04 04:55:15 DEBUG : Upload server store5 (eu) responded in 122.228528ms
2024/11/04 04:55:15 DEBUG : Upload server store6 (eu) responded in 124.542933ms
2024/11/04 04:55:15 DEBUG : dir/file1: Using upload server store5 (eu)
2024/11/04 04:55:16 DEBUG : dir/file3: Using upload server store6 (eu)
2024/11/04 04:55:16 ERROR : dir/not found: Dir.Rename error: file does not exist
2024/11/04 04:55:17 DEBUG : dir: Updating dir with dir2 0xc000995380
2024/11/04 04:55:17 DEBUG : dir: forgetting directory cache
2024/11/04 04:55:17 DEBUG : : Added virtual directory entry vDel: "dir"
2024/11/04 04:55:17 DEBUG : : Added virtual directory entry vAddDir: "dir2"
2024/11/04 04:55:19 INFO  : dir2/file1: Moved (server-side) to: file2
2024/11/04 04:55:19 DEBUG : file2: Updating file with file2 0xc0002ded80
2024/11/04 04:55:19 DEBUG : dir2: Added virtual directory entry vDel: "file1"
2024/11/04 04:55:19 DEBUG : : Added virtual directory entry vAddFile: "file2"
2024/11/04 04:55:21 INFO  : dir2/file3: Deleted
2024/11/04 04:55:22 INFO  : file2: Moved (server-side) to: dir2/file3
2024/11/04 04:55:22 DEBUG : dir2/file3: Updating file with dir2/file3 0xc0002ded80
2024/11/04 04:55:22 DEBUG : : Added virtual directory entry vDel: "file2"
2024/11/04 04:55:22 DEBUG : dir2: Added virtual directory entry vAddFile: "file3"
2024/11/04 04:55:23 DEBUG : : Added virtual directory entry vAddDir: "empty directory"
2024/11/04 04:55:23 DEBUG : empty directory: Updating dir with renamed empty directory 0xc0004ee410
2024/11/04 04:55:23 DEBUG : empty directory: forgetting directory cache
2024/11/04 04:55:23 DEBUG : : Added virtual directory entry vDel: "empty directory"
2024/11/04 04:55:23 DEBUG : : Added virtual directory entry vAddDir: "renamed empty directory"
2024/11/04 04:55:23 DEBUG : dir2: Renaming to "dir3"
2024/11/04 04:55:23 DEBUG : WaitForWriters: timeout=30s
2024/11/04 04:55:23 DEBUG : dir3: Looking for writers
2024/11/04 04:55:23 DEBUG : file3: reading active writers
2024/11/04 04:55:23 DEBUG : renamed empty directory: Looking for writers
2024/11/04 04:55:23 DEBUG : : Looking for writers
2024/11/04 04:55:23 DEBUG : dir3: reading active writers
2024/11/04 04:55:23 DEBUG : renamed empty directory: reading active writers
2024/11/04 04:55:23 DEBUG : >WaitForWriters: 
--- PASS: TestDirRename (12.74s)
=== RUN   TestWriteFileModTimeWithOpenWriters
    run.go:180: Remote "gofile root 'rclone-test-dorefam0juqi'", Local "Local file system at /tmp/rclone399241679", Modify Window "1s"
2024/11/04 04:55:25 INFO  : gofile root 'rclone-test-dorefam0juqi': poll-interval is not supported by this remote
2024/11/04 04:55:25 DEBUG : file1: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx
2024/11/04 04:55:25 DEBUG : file1: Open: flags=O_WRONLY|O_CREATE
2024/11/04 04:55:25 DEBUG : : Added virtual directory entry vAddFile: "file1"
2024/11/04 04:55:25 DEBUG : file1: >Open: fd=file1 (w), err=<nil>
2024/11/04 04:55:25 DEBUG : file1: >OpenFile: fd=file1 (w), err=<nil>
2024/11/04 04:55:25 DEBUG : time_test: Using upload server store6 (eu)
2024/11/04 04:55:26 DEBUG : Can set mod time: true
2024/11/04 04:55:26 DEBUG : : Added virtual directory entry vAddFile: "file1"
2024/11/04 04:55:26 DEBUG : gofile root 'rclone-test-dorefam0juqi': File to upload is small (2 bytes), uploading instead of streaming
2024/11/04 04:55:26 DEBUG : file1: Using upload server store6 (eu)
2024/11/04 04:55:26 DEBUG : file1: md5 = 49f68a5c8493ec2c0bf489821c21fc3b OK
2024/11/04 04:55:26 DEBUG : file1: Size and md5 of src and dst objects identical
2024/11/04 04:55:27 DEBUG : file1: Applied pending mod time 2012-11-18 17:32:31 +0000 UTC OK
2024/11/04 04:55:27 DEBUG : : Added virtual directory entry vAddFile: "file1"
2024/11/04 04:55:27 DEBUG : WaitForWriters: timeout=30s
2024/11/04 04:55:27 DEBUG : : Looking for writers
2024/11/04 04:55:27 DEBUG : file1: reading active writers
2024/11/04 04:55:27 DEBUG : >WaitForWriters: 
--- PASS: TestWriteFileModTimeWithOpenWriters (2.48s)
PASS
2024/11/04 04:55:27 DEBUG : gofile root 'rclone-test-dorefam0juqi': Purge remote
"./vfs.test -test.v -test.timeout 1h0m0s -remote TestGoFile: -verbose -test.run '^(TestDirRename|TestWriteFileModTimeWithOpenWriters)$'" - Finished OK in 16.265352219s (try 2/5)