"./sync.test -test.v -test.timeout 1h0m0s -remote TestGoFile: -verbose -fast-list -test.run '^TestServerSideCopyOverSelf$'" - Starting (try 4/5)
2024/10/26 04:50:31 DEBUG : Creating backend with remote "TestGoFile:rclone-test-donujih0veki"
2024/10/26 04:50:31 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2024/10/26 04:50:31 DEBUG : Creating backend with remote "/tmp/rclone2922419623"
=== RUN   TestServerSideCopyOverSelf
    run.go:180: Remote "gofile root 'rclone-test-donujih0veki'", Local "Local file system at /tmp/rclone2922419623", Modify Window "1s"
2024/10/26 04:50:32 DEBUG : Upload server store4 (eu) responded in 122.564798ms
2024/10/26 04:50:32 DEBUG : Upload server store1 (eu) responded in 124.717803ms
2024/10/26 04:50:32 DEBUG : sub dir/hello world: Using upload server store4 (eu)
2024/10/26 04:50:32 DEBUG : Creating backend with remote "TestGoFile:rclone-test-hopedaw8jugo"
    sync_test.go:626: Server side copy (if possible) gofile root 'rclone-test-donujih0veki' -> gofile root 'rclone-test-hopedaw8jugo'
2024/10/26 04:50:33 DEBUG : Added delayed dir = "sub dir", newDst=<nil>
2024/10/26 04:50:33 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination
2024/10/26 04:50:33 DEBUG : gofile root 'rclone-test-hopedaw8jugo': Waiting for checks to finish
2024/10/26 04:50:33 DEBUG : gofile root 'rclone-test-hopedaw8jugo': Waiting for transfers to finish
2024/10/26 04:50:33 DEBUG : Rate limited, sleep for 5s
2024/10/26 04:50:38 DEBUG : pacer: low level retry 1/10 (error Error "error-rateLimit")
2024/10/26 04:50:38 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2024/10/26 04:50:39 DEBUG : Rate limited, sleep for 5s
2024/10/26 04:50:44 DEBUG : pacer: low level retry 2/10 (error Error "error-rateLimit")
2024/10/26 04:50:44 DEBUG : pacer: Rate limited, increasing sleep to 40ms
2024/10/26 04:50:44 DEBUG : pacer: Reducing sleep to 20ms
2024/10/26 04:50:44 DEBUG : pacer: Reducing sleep to 10ms
2024/10/26 04:50:44 DEBUG : sub dir/hello world: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK
2024/10/26 04:50:44 INFO  : sub dir/hello world: Copied (server-side copy)
2024/10/26 04:50:44 INFO  : sub dir: Set directory modification time (using DirSetModTime)
    run.go:180: Remote "gofile root 'rclone-test-donujih0veki'", Local "Local file system at /tmp/rclone2922419623", Modify Window "1s"
2024/10/26 04:50:44 DEBUG : sub dir/hello world: Using upload server store1 (eu)
2024/10/26 04:50:45 DEBUG : sub dir/hello world: Removing old object on successful upload
2024/10/26 04:50:45 DEBUG : Added delayed dir = "sub dir", newDst=sub dir
2024/10/26 04:50:45 DEBUG : sub dir/hello world: Sizes differ (src 17 vs dst 11)
2024/10/26 04:50:45 DEBUG : gofile root 'rclone-test-hopedaw8jugo': Waiting for checks to finish
2024/10/26 04:50:45 DEBUG : gofile root 'rclone-test-hopedaw8jugo': Waiting for transfers to finish
2024/10/26 04:50:46 DEBUG : sub dir/hello world: md5 = 00321a507bdb420dbbd4f53c733b1505 OK
2024/10/26 04:50:46 INFO  : sub dir/hello world: Copied (server-side copy)
2024/10/26 04:50:46 INFO  : sub dir: Set directory modification time (using SetModTime)
    run.go:180: Remote "gofile root 'rclone-test-donujih0veki'", Local "Local file system at /tmp/rclone2922419623", Modify Window "1s"
    fstest.go:292: Sleeping for 1s for list eventual consistency: 1/3
    fstest.go:295: Flushing the directory cache
    fstest.go:292: Sleeping for 2s for list eventual consistency: 2/3
    fstest.go:295: Flushing the directory cache
    fstest.go:292: Sleeping for 4s for list eventual consistency: 3/3
    fstest.go:295: Flushing the directory cache
    fstest.go:299: 
        	Error Trace:	/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:299
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350
        	            				/home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:641
        	Error:      	Should be true
        	Test:       	TestServerSideCopyOverSelf
        	Messages:   	listing wrong, want
        	            	  sub dir/hello world (17) got
        	            	  sub dir/hello world (11), sub dir/hello world (17)
    fstest.go:185: 
        	Error Trace:	/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:185
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:302
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350
        	            				/home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:641
        	Error:      	Should be true
        	Test:       	TestServerSideCopyOverSelf
        	Messages:   	Unexpected file "sub dir/hello world"
2024/10/26 04:50:54 DEBUG : gofile root 'rclone-test-hopedaw8jugo': Purge remote
--- FAIL: TestServerSideCopyOverSelf (24.14s)
FAIL
2024/10/26 04:50:55 DEBUG : gofile root 'rclone-test-donujih0veki': Purge remote
"./sync.test -test.v -test.timeout 1h0m0s -remote TestGoFile: -verbose -fast-list -test.run '^TestServerSideCopyOverSelf$'" - Finished ERROR in 24.758830713s (try 4/5): exit status 1: Failed [TestServerSideCopyOverSelf]