"./sync.test -test.v -test.timeout 1h0m0s -remote TestGoFile: -verbose -fast-list -test.run '^(TestNothingToTransferWithEmptyDirs|TestServerSideMoveOverSelf)$'" - Starting (try 2/5)
2025/01/12 01:34:52 DEBUG : Creating backend with remote "TestGoFile:rclone-test-jabesag3vexi"
2025/01/12 01:34:52 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/01/12 01:34:53 DEBUG : Creating backend with remote "/tmp/rclone1477737254"
2025/01/12 01:34:53 DEBUG : Config file has changed externally - reloading
=== RUN   TestServerSideMoveOverSelf
    run.go:180: Remote "gofile root 'rclone-test-jabesag3vexi'", Local "Local file system at /tmp/rclone1477737254", Modify Window "1s"
2025/01/12 01:34:54 DEBUG : Upload server store1 (eu) responded in 124.336028ms
2025/01/12 01:34:54 DEBUG : Upload server store-eu-par-4 (eu) responded in 126.261576ms
2025/01/12 01:34:54 DEBUG : sub dir/hello world: Using upload server store-eu-par-4 (eu)
2025/01/12 01:34:55 DEBUG : Creating backend with remote "TestGoFile:rclone-test-jotidev6suju"
2025/01/12 01:34:55 DEBUG : Config file has changed externally - reloading
    sync_test.go:671: Server side copy (if possible) gofile root 'rclone-test-jabesag3vexi' -> gofile root 'rclone-test-jotidev6suju'
2025/01/12 01:34:56 DEBUG : Added delayed dir = "sub dir", newDst=<nil>
2025/01/12 01:34:56 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination
2025/01/12 01:34:56 DEBUG : gofile root 'rclone-test-jotidev6suju': Waiting for checks to finish
2025/01/12 01:34:56 DEBUG : gofile root 'rclone-test-jotidev6suju': Waiting for transfers to finish
2025/01/12 01:34:59 DEBUG : sub dir/hello world: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK
2025/01/12 01:34:59 INFO  : sub dir/hello world: Copied (server-side copy)
2025/01/12 01:34:59 INFO  : sub dir: Set directory modification time (using DirSetModTime)
    run.go:180: Remote "gofile root 'rclone-test-jabesag3vexi'", Local "Local file system at /tmp/rclone1477737254", Modify Window "1s"
2025/01/12 01:35:00 DEBUG : sub dir/hello world: Using upload server store-eu-par-4 (eu)
2025/01/12 01:35:00 DEBUG : sub dir/hello world: Removing old object on successful upload
2025/01/12 01:35:01 DEBUG : gofile root 'rclone-test-jotidev6suju': Using server-side directory move
2025/01/12 01:35:01 INFO  : gofile root 'rclone-test-jotidev6suju': Server side directory move failed - fallback to file moves: can't copy directory - destination already exists
2025/01/12 01:35:02 DEBUG : Added delayed dir = "sub dir", newDst=sub dir
2025/01/12 01:35:02 DEBUG : sub dir/hello world: Sizes differ (src 17 vs dst 11)
2025/01/12 01:35:02 DEBUG : gofile root 'rclone-test-jotidev6suju': Waiting for checks to finish
2025/01/12 01:35:02 DEBUG : gofile root 'rclone-test-jotidev6suju': Waiting for transfers to finish
2025/01/12 01:35:03 INFO  : sub dir/hello world: Deleted
2025/01/12 01:35:03 INFO  : sub dir/hello world: Moved (server-side)
2025/01/12 01:35:04 INFO  : sub dir: Set directory modification time (using SetModTime)
2025/01/12 01:35:05 DEBUG : sub dir/hello world: Using upload server store1 (eu)
2025/01/12 01:35:06 DEBUG : testing file moves
2025/01/12 01:35:06 DEBUG : Added delayed dir = "sub dir", newDst=sub dir
2025/01/12 01:35:06 DEBUG : sub dir/hello world: Sizes differ (src 24 vs dst 17)
2025/01/12 01:35:06 DEBUG : gofile root 'rclone-test-jotidev6suju': Waiting for checks to finish
2025/01/12 01:35:06 DEBUG : gofile root 'rclone-test-jotidev6suju': Waiting for transfers to finish
2025/01/12 01:35:06 INFO  : sub dir/hello world: Deleted
2025/01/12 01:35:07 INFO  : sub dir/hello world: Moved (server-side)
2025/01/12 01:35:07 INFO  : sub dir: Set directory modification time (using SetModTime)
    run.go:180: Remote "gofile root 'rclone-test-jabesag3vexi'", Local "Local file system at /tmp/rclone1477737254", Modify Window "1s"
2025/01/12 01:35:08 DEBUG : gofile root 'rclone-test-jotidev6suju': Purge remote
--- PASS: TestServerSideMoveOverSelf (19.00s)
=== RUN   TestNothingToTransferWithEmptyDirs
    run.go:180: Remote "gofile root 'rclone-test-jabesag3vexi'", Local "Local file system at /tmp/rclone1477737254", Modify Window "1s"
2025/01/12 01:35:12 INFO  : sub dir: Set directory modification time (using DirSetModTime)
2025/01/12 01:35:12 INFO  : sub dir: Making directory
2025/01/12 01:35:13 INFO  : sub dir: Made directory with modification time 2011-12-30 12:59:59 +0000 UTC
    run.go:180: Remote "gofile root 'rclone-test-jabesag3vexi'", Local "Local file system at /tmp/rclone1477737254", Modify Window "1s"
    run.go:180: Remote "gofile root 'rclone-test-jabesag3vexi'", Local "Local file system at /tmp/rclone1477737254", Modify Window "1s"
2025/01/12 01:35:34 INFO  : sub dir2: Set directory modification time (using DirSetModTime)
2025/01/12 01:35:35 INFO  : sub dir2: Set directory modification time (using DirSetModTime)
2025/01/12 01:35:35 INFO  : sub dirEmpty/sub dirEmpty2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z)
2025/01/12 01:35:35 INFO  : sub dirEmpty: Set directory modification time (using DirSetModTime)
    run.go:180: Remote "gofile root 'rclone-test-jabesag3vexi'", Local "Local file system at /tmp/rclone1477737254", Modify Window "1s"
--- PASS: TestNothingToTransferWithEmptyDirs (49.18s)
PASS
2025/01/12 01:36:01 DEBUG : gofile root 'rclone-test-jabesag3vexi': Purge remote
"./sync.test -test.v -test.timeout 1h0m0s -remote TestGoFile: -verbose -fast-list -test.run '^(TestNothingToTransferWithEmptyDirs|TestServerSideMoveOverSelf)$'" - Finished OK in 1m9.398913206s (try 2/5)