"./vfs.test -test.v -test.timeout 1h0m0s -remote TestGoFile: -verbose -test.run '^TestFileRename$/^off,forceCache=false$'" - Starting (try 4/5)
2024/11/03 04:51:30 DEBUG : Creating backend with remote "TestGoFile:rclone-test-yehedol8fite"
2024/11/03 04:51:30 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2024/11/03 04:51:30 DEBUG : Creating backend with remote "/tmp/rclone4291457729"
=== RUN   TestFileRename
=== RUN   TestFileRename/off,forceCache=false
    run.go:180: Remote "gofile root 'rclone-test-yehedol8fite'", Local "Local file system at /tmp/rclone4291457729", Modify Window "1s"
2024/11/03 04:51:30 INFO  : gofile root 'rclone-test-yehedol8fite': poll-interval is not supported by this remote
2024/11/03 04:51:31 DEBUG : Upload server store5 (eu) responded in 124.881891ms
2024/11/03 04:51:31 DEBUG : Upload server store6 (eu) responded in 130.580504ms
2024/11/03 04:51:31 DEBUG : dir/file1: Using upload server store5 (eu)
2024/11/03 04:51:32 DEBUG : WaitForWriters: timeout=30s
2024/11/03 04:51:32 DEBUG : dir: Looking for writers
2024/11/03 04:51:32 DEBUG : file1: reading active writers
2024/11/03 04:51:32 DEBUG : : Looking for writers
2024/11/03 04:51:32 DEBUG : dir: reading active writers
2024/11/03 04:51:32 DEBUG : >WaitForWriters: 
2024/11/03 04:51:32 INFO  : dir/file1: Moved (server-side) to: newLeaf
2024/11/03 04:51:32 DEBUG : newLeaf: Updating file with newLeaf 0xc000612000
2024/11/03 04:51:32 DEBUG : dir: Added virtual directory entry vDel: "file1"
2024/11/03 04:51:32 DEBUG : : Added virtual directory entry vAddFile: "newLeaf"
2024/11/03 04:51:32 DEBUG : Rate limited, sleep for 5s
2024/11/03 04:51:37 DEBUG : pacer: low level retry 1/10 (error Error "error-rateLimit")
2024/11/03 04:51:37 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2024/11/03 04:51:37 DEBUG : Rate limited, sleep for 5s
2024/11/03 04:51:42 DEBUG : pacer: low level retry 2/10 (error Error "error-rateLimit")
2024/11/03 04:51:42 DEBUG : pacer: Rate limited, increasing sleep to 40ms
2024/11/03 04:51:43 DEBUG : Rate limited, sleep for 5s
2024/11/03 04:51:48 DEBUG : pacer: low level retry 3/10 (error Error "error-rateLimit")
2024/11/03 04:51:48 DEBUG : pacer: Rate limited, increasing sleep to 80ms
2024/11/03 04:51:48 DEBUG : Rate limited, sleep for 5s
2024/11/03 04:51:53 DEBUG : pacer: low level retry 4/10 (error Error "error-rateLimit")
2024/11/03 04:51:53 DEBUG : pacer: Rate limited, increasing sleep to 160ms
2024/11/03 04:51:53 DEBUG : Rate limited, sleep for 5s
2024/11/03 04:51:58 DEBUG : pacer: low level retry 5/10 (error Error "error-rateLimit")
2024/11/03 04:51:58 DEBUG : pacer: Rate limited, increasing sleep to 320ms
2024/11/03 04:51:58 DEBUG : Rate limited, sleep for 5s
2024/11/03 04:52:03 DEBUG : pacer: low level retry 6/10 (error Error "error-rateLimit")
2024/11/03 04:52:03 DEBUG : pacer: Rate limited, increasing sleep to 640ms
2024/11/03 04:52:03 DEBUG : Rate limited, sleep for 5s
2024/11/03 04:52:08 DEBUG : pacer: low level retry 7/10 (error Error "error-rateLimit")
2024/11/03 04:52:08 DEBUG : pacer: Rate limited, increasing sleep to 1.28s
2024/11/03 04:52:08 DEBUG : Rate limited, sleep for 5s
2024/11/03 04:52:13 DEBUG : pacer: low level retry 8/10 (error Error "error-rateLimit")
2024/11/03 04:52:13 DEBUG : pacer: Rate limited, increasing sleep to 2.56s
2024/11/03 04:52:13 DEBUG : Rate limited, sleep for 5s
2024/11/03 04:52:18 DEBUG : pacer: low level retry 9/10 (error Error "error-rateLimit")
2024/11/03 04:52:18 DEBUG : pacer: Rate limited, increasing sleep to 5.12s
2024/11/03 04:52:18 DEBUG : Rate limited, sleep for 5s
2024/11/03 04:52:23 DEBUG : pacer: low level retry 10/10 (error Error "error-rateLimit")
2024/11/03 04:52:23 DEBUG : pacer: Rate limited, increasing sleep to 10.24s
2024/11/03 04:52:23 ERROR : newLeaf: Couldn't move: failed to move item: Error "error-rateLimit"
2024/11/03 04:52:23 ERROR : dir/file1: File.Rename error: failed to move item: Error "error-rateLimit"
2024/11/03 04:52:23 ERROR : newLeaf: Dir.Rename error: failed to move item: Error "error-rateLimit"
    file_test.go:358: 
        	Error Trace:	/home/rclone/go/src/github.com/rclone/rclone/vfs/file_test.go:358
        	            				/home/rclone/go/src/github.com/rclone/rclone/vfs/file_test.go:413
        	Error:      	Received unexpected error:
        	            	failed to move item: Error "error-rateLimit"
        	Test:       	TestFileRename/off,forceCache=false
2024/11/03 04:52:23 DEBUG : WaitForWriters: timeout=30s
2024/11/03 04:52:23 DEBUG : dir: Looking for writers
2024/11/03 04:52:23 DEBUG : : Looking for writers
2024/11/03 04:52:23 DEBUG : dir: reading active writers
2024/11/03 04:52:23 DEBUG : newLeaf: reading active writers
2024/11/03 04:52:23 DEBUG : >WaitForWriters: 
2024/11/03 04:52:23 DEBUG : pacer: Reducing sleep to 5.12s
2024/11/03 04:52:34 DEBUG : pacer: Reducing sleep to 2.56s
2024/11/03 04:52:39 DEBUG : pacer: Reducing sleep to 1.28s
2024/11/03 04:52:41 DEBUG : pacer: Reducing sleep to 640ms
2024/11/03 04:52:42 DEBUG : pacer: Reducing sleep to 320ms
--- FAIL: TestFileRename (72.13s)
    --- FAIL: TestFileRename/off,forceCache=false (72.13s)
FAIL
2024/11/03 04:52:42 DEBUG : gofile root 'rclone-test-yehedol8fite': Purge remote
2024/11/03 04:52:43 DEBUG : pacer: Reducing sleep to 160ms
"./vfs.test -test.v -test.timeout 1h0m0s -remote TestGoFile: -verbose -test.run '^TestFileRename$/^off,forceCache=false$'" - Finished ERROR in 1m13.818352244s (try 4/5): exit status 1: Failed [TestFileRename/off,forceCache=false]