"./vfs.test -test.v -test.timeout 1h0m0s -remote TestGoFile: -verbose -test.run '^TestFileRename$/^off,forceCache=false$'" - Starting (try 5/5)
2024/11/03 04:52:43 DEBUG : Creating backend with remote "TestGoFile:rclone-test-xuroriw9vusa"
2024/11/03 04:52:43 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2024/11/03 04:52:44 DEBUG : Creating backend with remote "/tmp/rclone686398285"
=== RUN   TestFileRename
=== RUN   TestFileRename/off,forceCache=false
    run.go:180: Remote "gofile root 'rclone-test-xuroriw9vusa'", Local "Local file system at /tmp/rclone686398285", Modify Window "1s"
2024/11/03 04:52:44 INFO  : gofile root 'rclone-test-xuroriw9vusa': poll-interval is not supported by this remote
2024/11/03 04:52:45 DEBUG : Upload server store4 (eu) responded in 121.717294ms
2024/11/03 04:52:45 DEBUG : Upload server store6 (eu) responded in 125.793042ms
2024/11/03 04:52:45 DEBUG : dir/file1: Using upload server store6 (eu)
2024/11/03 04:52:45 DEBUG : WaitForWriters: timeout=30s
2024/11/03 04:52:45 DEBUG : dir: Looking for writers
2024/11/03 04:52:45 DEBUG : file1: reading active writers
2024/11/03 04:52:45 DEBUG : : Looking for writers
2024/11/03 04:52:45 DEBUG : dir: reading active writers
2024/11/03 04:52:45 DEBUG : >WaitForWriters: 
2024/11/03 04:52:46 DEBUG : Rate limited, sleep for 5s
2024/11/03 04:52:51 DEBUG : pacer: low level retry 1/10 (error Error "error-rateLimit")
2024/11/03 04:52:51 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2024/11/03 04:52:51 DEBUG : Rate limited, sleep for 5s
2024/11/03 04:52:56 DEBUG : pacer: low level retry 2/10 (error Error "error-rateLimit")
2024/11/03 04:52:56 DEBUG : pacer: Rate limited, increasing sleep to 40ms
2024/11/03 04:52:56 DEBUG : Rate limited, sleep for 5s
2024/11/03 04:53:01 DEBUG : pacer: low level retry 3/10 (error Error "error-rateLimit")
2024/11/03 04:53:01 DEBUG : pacer: Rate limited, increasing sleep to 80ms
2024/11/03 04:53:01 DEBUG : Rate limited, sleep for 5s
2024/11/03 04:53:06 DEBUG : pacer: low level retry 4/10 (error Error "error-rateLimit")
2024/11/03 04:53:06 DEBUG : pacer: Rate limited, increasing sleep to 160ms
2024/11/03 04:53:06 DEBUG : Rate limited, sleep for 5s
2024/11/03 04:53:11 DEBUG : pacer: low level retry 5/10 (error Error "error-rateLimit")
2024/11/03 04:53:11 DEBUG : pacer: Rate limited, increasing sleep to 320ms
2024/11/03 04:53:11 DEBUG : Rate limited, sleep for 5s
2024/11/03 04:53:16 DEBUG : pacer: low level retry 6/10 (error Error "error-rateLimit")
2024/11/03 04:53:16 DEBUG : pacer: Rate limited, increasing sleep to 640ms
2024/11/03 04:53:16 DEBUG : Rate limited, sleep for 5s
2024/11/03 04:53:21 DEBUG : pacer: low level retry 7/10 (error Error "error-rateLimit")
2024/11/03 04:53:21 DEBUG : pacer: Rate limited, increasing sleep to 1.28s
2024/11/03 04:53:21 DEBUG : Rate limited, sleep for 5s
2024/11/03 04:53:26 DEBUG : pacer: low level retry 8/10 (error Error "error-rateLimit")
2024/11/03 04:53:26 DEBUG : pacer: Rate limited, increasing sleep to 2.56s
2024/11/03 04:53:26 DEBUG : Rate limited, sleep for 5s
2024/11/03 04:53:31 DEBUG : pacer: low level retry 9/10 (error Error "error-rateLimit")
2024/11/03 04:53:31 DEBUG : pacer: Rate limited, increasing sleep to 5.12s
2024/11/03 04:53:31 DEBUG : Rate limited, sleep for 5s
2024/11/03 04:53:36 DEBUG : pacer: low level retry 10/10 (error Error "error-rateLimit")
2024/11/03 04:53:36 DEBUG : pacer: Rate limited, increasing sleep to 10.24s
2024/11/03 04:53:36 ERROR : dir/file1: Couldn't move: failed to move item: Error "error-rateLimit"
2024/11/03 04:53:36 ERROR : newLeaf: File.Rename error: failed to move item: Error "error-rateLimit"
2024/11/03 04:53:36 ERROR : dir/file1: Dir.Rename error: failed to move item: Error "error-rateLimit"
    file_test.go:342: 
        	Error Trace:	/home/rclone/go/src/github.com/rclone/rclone/vfs/file_test.go:342
        	            				/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:53:36 DEBUG : WaitForWriters: timeout=30s
2024/11/03 04:53:36 DEBUG : dir: Looking for writers
2024/11/03 04:53:36 DEBUG : file1: reading active writers
2024/11/03 04:53:36 DEBUG : : Looking for writers
2024/11/03 04:53:36 DEBUG : dir: reading active writers
2024/11/03 04:53:36 DEBUG : >WaitForWriters: 
2024/11/03 04:53:36 DEBUG : pacer: Reducing sleep to 5.12s
2024/11/03 04:53:46 DEBUG : pacer: Reducing sleep to 2.56s
2024/11/03 04:53:52 DEBUG : pacer: Reducing sleep to 1.28s
2024/11/03 04:53:54 DEBUG : pacer: Reducing sleep to 640ms
2024/11/03 04:53:55 DEBUG : pacer: Reducing sleep to 320ms
--- FAIL: TestFileRename (71.69s)
    --- FAIL: TestFileRename/off,forceCache=false (71.69s)
FAIL
2024/11/03 04:53:55 DEBUG : gofile root 'rclone-test-xuroriw9vusa': Purge remote
2024/11/03 04:53:56 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 1m12.849732617s (try 5/5): exit status 1: Failed [TestFileRename/off,forceCache=false]