"./sync.test -test.v -test.timeout 2h0m0s -remote TestDrime: -list-retries 5 -verbose -test.run '^TestServerSideCopyOverSelf$'" - Starting (try 5/5)
2026/09/23 01:58:44 DEBUG : Creating backend with remote "TestDrime:rclone-test-xemubov5mopo"
2026/09/23 01:58:44 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/09/23 01:58:44 DEBUG : Creating backend with remote "/tmp/rclone1238325194"
=== RUN   TestServerSideCopyOverSelf
    run.go:198: Remote "drime root 'rclone-test-xemubov5mopo'", Local "Local file system at /tmp/rclone1238325194", Modify Window "876000h0m0s"
2026/09/23 01:58:45 DEBUG : Creating backend with remote "TestDrime:rclone-test-poyujem7neji"
    sync_test.go:656: Server side copy (if possible) drime root 'rclone-test-xemubov5mopo' -> drime root 'rclone-test-poyujem7neji'
2026/09/23 01:58:46 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination
2026/09/23 01:58:46 DEBUG : drime root 'rclone-test-poyujem7neji': Waiting for checks to finish
2026/09/23 01:58:46 DEBUG : drime root 'rclone-test-poyujem7neji': Waiting for transfers to finish
2026/09/23 01:58:47 DEBUG : sub dir/hello world: size = 11 OK
2026/09/23 01:58:47 INFO  : sub dir/hello world: Copied (server-side copy)
2026/09/23 01:58:47 DEBUG : sub dir/hello world: Removing old object on successful upload
2026/09/23 01:58:47 DEBUG : sub dir/hello world: Old object already deleted, safely ignoring
2026/09/23 01:58:48 DEBUG : sub dir/hello world: size = 17 (drime root 'rclone-test-xemubov5mopo')
2026/09/23 01:58:48 DEBUG : sub dir/hello world: size = 11 (drime root 'rclone-test-poyujem7neji')
2026/09/23 01:58:48 DEBUG : sub dir/hello world: Sizes differ
2026/09/23 01:58:48 DEBUG : drime root 'rclone-test-poyujem7neji': Waiting for checks to finish
2026/09/23 01:58:48 DEBUG : drime root 'rclone-test-poyujem7neji': Waiting for transfers to finish
2026/09/23 01:58:48 DEBUG : sub dir/hello world: Server side copy: removing existing object after successful copy
2026/09/23 01:58:48 DEBUG : sub dir/hello world: size = 17 OK
2026/09/23 01:58:48 INFO  : sub dir/hello world: Copied (server-side copy)
    fstest.go:299: Sleeping for 1s for list eventual consistency: 1/5
    fstest.go:302: Flushing the directory cache
    fstest.go:299: Sleeping for 2s for list eventual consistency: 2/5
    fstest.go:302: Flushing the directory cache
    fstest.go:299: Sleeping for 4s for list eventual consistency: 3/5
    fstest.go:302: Flushing the directory cache
    fstest.go:299: Sleeping for 8s for list eventual consistency: 4/5
    fstest.go:302: Flushing the directory cache
    fstest.go:299: Sleeping for 16s for list eventual consistency: 5/5
    fstest.go:302: Flushing the directory cache
    fstest.go:306: 
        	Error Trace:	/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:306
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:339
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:357
        	            				/home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:671
        	Error:      	Should be true
        	Test:       	TestServerSideCopyOverSelf
        	Messages:   	listing wrong, want
        	            	  sub dir/hello world (17) got
        	            	  sub dir/hello world (1) (17)
    fstest.go:192: 
        	Error Trace:	/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:192
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:309
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:339
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:357
        	            				/home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:671
        	Error:      	Should be true
        	Test:       	TestServerSideCopyOverSelf
        	Messages:   	Unexpected file "sub dir/hello world (1)"
    fstest.go:204: Not found "sub dir/hello world"
    fstest.go:207: 
        	Error Trace:	/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:207
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:311
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:339
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:357
        	            				/home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:671
        	Error:      	Not equal: 
        	            	expected: 0
        	            	actual  : 1
        	Test:       	TestServerSideCopyOverSelf
        	Messages:   	1 objects not found
2026/09/23 01:59:21 DEBUG : drime root 'rclone-test-poyujem7neji': Purge remote
    fstest.go:299: Sleeping for 1s for list eventual consistency: 1/5
    fstest.go:302: Flushing the directory cache
    fstest.go:293: Sleeping for 6s just to make sure
--- FAIL: TestServerSideCopyOverSelf (44.89s)
FAIL
2026/09/23 01:59:29 DEBUG : drime root 'rclone-test-xemubov5mopo': Purge remote
"./sync.test -test.v -test.timeout 2h0m0s -remote TestDrime: -list-retries 5 -verbose -test.run '^TestServerSideCopyOverSelf$'" - Finished ERROR in 45.412919635s (try 5/5): exit status 1: Failed [TestServerSideCopyOverSelf]