"./sync.test -test.v -test.timeout 1h0m0s -remote TestGoFile: -verbose -fast-list -test.run '^(TestServerSideMove|TestServerSideMoveWithFilter)$'" - Starting (try 2/5)
2025/04/03 01:16:17 DEBUG : Creating backend with remote "TestGoFile:rclone-test-xaviyob0lozu"
2025/04/03 01:16:17 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/04/03 01:16:17 DEBUG : Creating backend with remote "/tmp/rclone4246891858"
=== RUN   TestServerSideMove
    run.go:180: Remote "gofile root 'rclone-test-xaviyob0lozu'", Local "Local file system at /tmp/rclone4246891858", Modify Window "1s"
2025/04/03 01:16:17 DEBUG : Creating backend with remote "TestGoFile:rclone-test-vejabef8zapi"
2025/04/03 01:16:18 DEBUG : Upload server store-eu-par-4 (eu) responded in 128.692034ms
2025/04/03 01:16:18 DEBUG : Upload server store2 (eu) responded in 134.559571ms
2025/04/03 01:16:18 DEBUG : potato2: Using upload server store-eu-par-4 (eu)
2025/04/03 01:16:18 DEBUG : Upload server store1 (eu) responded in 134.264868ms
2025/04/03 01:16:18 DEBUG : empty space: Using upload server store2 (eu)
2025/04/03 01:16:18 DEBUG : potato3: Using upload server store2 (eu)
    sync_test.go:1705: Server side move (if possible) gofile root 'rclone-test-xaviyob0lozu' -> gofile root 'rclone-test-vejabef8zapi'
2025/04/03 01:16:19 DEBUG : Upload server store-eu-par-2 (eu) responded in 124.527502ms
2025/04/03 01:16:19 DEBUG : Upload server store-eu-par-4 (eu) responded in 125.493175ms
2025/04/03 01:16:19 DEBUG : empty space: Using upload server store-eu-par-2 (eu)
2025/04/03 01:16:19 DEBUG : potato3: Using upload server store-eu-par-2 (eu)
2025/04/03 01:16:19 DEBUG : gofile root 'rclone-test-vejabef8zapi': Using server-side directory move
2025/04/03 01:16:19 INFO  : gofile root 'rclone-test-vejabef8zapi': Server side directory move failed - fallback to file moves: can't copy directory - destination already exists
2025/04/03 01:16:19 DEBUG : potato2: Need to transfer - File not found at Destination
2025/04/03 01:16:19 DEBUG : potato3: Sizes differ (src 68 vs dst 60)
2025/04/03 01:16:19 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1s)
2025/04/03 01:16:19 DEBUG : gofile root 'rclone-test-vejabef8zapi': Waiting for checks to finish
2025/04/03 01:16:19 DEBUG : empty space: Unchanged skipping
2025/04/03 01:16:20 INFO  : potato3: Deleted
2025/04/03 01:16:20 INFO  : empty space: Deleted
2025/04/03 01:16:20 DEBUG : gofile root 'rclone-test-vejabef8zapi': Waiting for transfers to finish
2025/04/03 01:16:20 INFO  : potato2: Moved (server-side)
2025/04/03 01:16:20 INFO  : potato3: Moved (server-side)
2025/04/03 01:16:20 DEBUG : Creating backend with remote "TestGoFile:rclone-test-litavox2ruwo"
2025/04/03 01:16:20 DEBUG : gofile root 'rclone-test-litavox2ruwo': Using server-side directory move
2025/04/03 01:16:20 INFO  : gofile root 'rclone-test-litavox2ruwo': Server side directory move succeeded
2025/04/03 01:16:21 DEBUG : gofile root 'rclone-test-litavox2ruwo': Purge remote
2025/04/03 01:16:21 DEBUG : gofile root 'rclone-test-vejabef8zapi': Purge remote
2025/04/03 01:16:21 NOTICE: purge failed: directory not found
--- PASS: TestServerSideMove (4.56s)
=== RUN   TestServerSideMoveWithFilter
    run.go:180: Remote "gofile root 'rclone-test-xaviyob0lozu'", Local "Local file system at /tmp/rclone4246891858", Modify Window "1s"
2025/04/03 01:16:21 DEBUG : Creating backend with remote "TestGoFile:rclone-test-wazudam6rofi"
2025/04/03 01:16:22 DEBUG : potato2: Using upload server store-eu-par-4 (eu)
2025/04/03 01:16:22 DEBUG : empty space: Using upload server store-eu-par-4 (eu)
2025/04/03 01:16:22 DEBUG : potato3: Using upload server store2 (eu)
    sync_test.go:1705: Server side move (if possible) gofile root 'rclone-test-xaviyob0lozu' -> gofile root 'rclone-test-wazudam6rofi'
2025/04/03 01:16:23 DEBUG : Upload server store-eu-par-2 (eu) responded in 125.605104ms
2025/04/03 01:16:23 DEBUG : Upload server store10 (eu) responded in 133.042396ms
2025/04/03 01:16:23 DEBUG : empty space: Using upload server store-eu-par-2 (eu)
2025/04/03 01:16:23 DEBUG : Upload server store5 (eu) responded in 133.232382ms
2025/04/03 01:16:23 DEBUG : potato3: Using upload server store-eu-par-2 (eu)
2025/04/03 01:16:23 DEBUG : empty space: Excluded (Size Filter)
2025/04/03 01:16:23 DEBUG : empty space: Excluded (Size Filter)
2025/04/03 01:16:23 DEBUG : potato2: Need to transfer - File not found at Destination
2025/04/03 01:16:23 DEBUG : potato3: Sizes differ (src 68 vs dst 60)
2025/04/03 01:16:23 DEBUG : gofile root 'rclone-test-wazudam6rofi': Waiting for checks to finish
2025/04/03 01:16:23 DEBUG : gofile root 'rclone-test-wazudam6rofi': Waiting for transfers to finish
2025/04/03 01:16:23 INFO  : potato3: Deleted
2025/04/03 01:16:23 INFO  : potato2: Moved (server-side)
2025/04/03 01:16:23 INFO  : potato3: Moved (server-side)
2025/04/03 01:16:24 DEBUG : Creating backend with remote "TestGoFile:rclone-test-papugon0felo"
2025/04/03 01:16:24 DEBUG : empty space: Excluded (Size Filter)
2025/04/03 01:16:24 DEBUG : potato2: Need to transfer - File not found at Destination
2025/04/03 01:16:24 DEBUG : potato3: Need to transfer - File not found at Destination
2025/04/03 01:16:24 DEBUG : gofile root 'rclone-test-papugon0felo': Waiting for checks to finish
2025/04/03 01:16:24 DEBUG : gofile root 'rclone-test-papugon0felo': Waiting for transfers to finish
2025/04/03 01:16:25 DEBUG : Rate limited, sleep for 5s
2025/04/03 01:16:25 INFO  : potato3: Moved (server-side)
2025/04/03 01:16:30 DEBUG : pacer: low level retry 1/10 (error Error "error-rateLimit")
2025/04/03 01:16:30 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2025/04/03 01:16:30 DEBUG : pacer: Reducing sleep to 10ms
2025/04/03 01:16:30 INFO  : potato2: Moved (server-side)
2025/04/03 01:16:30 DEBUG : gofile root 'rclone-test-papugon0felo': Purge remote
2025/04/03 01:16:30 DEBUG : gofile root 'rclone-test-wazudam6rofi': Purge remote
--- PASS: TestServerSideMoveWithFilter (9.49s)
PASS
2025/04/03 01:16:31 DEBUG : gofile root 'rclone-test-xaviyob0lozu': Purge remote
"./sync.test -test.v -test.timeout 1h0m0s -remote TestGoFile: -verbose -fast-list -test.run '^(TestServerSideMove|TestServerSideMoveWithFilter)$'" - Finished OK in 14.580661598s (try 2/5)