"./sync.test -test.v -test.timeout 1h0m0s -remote TestKoofr: -verbose -test.run '^(TestServerSideCopy|TestServerSideCopyOverSelf|TestServerSideMove|TestSyncOverlap|TestSyncOverlapWithFilter)$'" - Starting (try 2/5)
2025/09/05 03:49:51 DEBUG : Creating backend with remote "TestKoofr:rclone-test-gerehal2caha"
2025/09/05 03:49:51 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/09/05 03:49:52 DEBUG : Creating backend with remote "/tmp/rclone3958523963"
=== RUN   TestServerSideCopy
    run.go:180: Remote "koofr:4266cdc3-3d29-4a88-ac94-738914c162b4:rclone-test-gerehal2caha", Local "Local file system at /tmp/rclone3958523963", Modify Window "1ms"
2025/09/05 03:49:53 DEBUG : Creating backend with remote "TestKoofr:rclone-test-vihicel9zapo"
    sync_test.go:618: 
        	Error Trace:	/home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:618
        	Error:      	Received unexpected error:
        	            	Get "https:/api/v2/mounts": http2: timeout awaiting response headers
        	Test:       	TestServerSideCopy
--- FAIL: TestServerSideCopy (302.70s)
=== RUN   TestServerSideCopyOverSelf
    run.go:180: Remote "koofr:4266cdc3-3d29-4a88-ac94-738914c162b4:rclone-test-gerehal2caha", Local "Local file system at /tmp/rclone3958523963", Modify Window "1ms"
2025/09/05 03:54:56 DEBUG : Creating backend with remote "TestKoofr:rclone-test-lahicec4kiwo"
2025/09/05 03:54:56 DEBUG : Config file has changed externally - reloading
    sync_test.go:656: Server side copy (if possible) koofr:4266cdc3-3d29-4a88-ac94-738914c162b4:rclone-test-gerehal2caha -> koofr:4266cdc3-3d29-4a88-ac94-738914c162b4:rclone-test-lahicec4kiwo
2025/09/05 03:54:57 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination
2025/09/05 03:54:57 DEBUG : koofr:4266cdc3-3d29-4a88-ac94-738914c162b4:rclone-test-lahicec4kiwo: Waiting for checks to finish
2025/09/05 03:54:57 DEBUG : koofr:4266cdc3-3d29-4a88-ac94-738914c162b4:rclone-test-lahicec4kiwo: Waiting for transfers to finish
2025/09/05 03:54:57 DEBUG : sub dir/hello world: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK
2025/09/05 03:54:57 INFO  : sub dir/hello world: Copied (server-side copy)
2025/09/05 03:55:00 DEBUG : koofr:4266cdc3-3d29-4a88-ac94-738914c162b4:rclone-test-lahicec4kiwo: Waiting for checks to finish
2025/09/05 03:55:00 DEBUG : sub dir/hello world: Sizes differ (src 17 vs dst 11)
2025/09/05 03:55:00 DEBUG : koofr:4266cdc3-3d29-4a88-ac94-738914c162b4:rclone-test-lahicec4kiwo: Waiting for transfers to finish
2025/09/05 03:55:01 DEBUG : sub dir/hello world: md5 = 00321a507bdb420dbbd4f53c733b1505 OK
2025/09/05 03:55:01 INFO  : sub dir/hello world: Copied (replaced existing)
2025/09/05 03:55:02 DEBUG : koofr:4266cdc3-3d29-4a88-ac94-738914c162b4:rclone-test-lahicec4kiwo: Purge object "sub dir/hello world"
2025/09/05 03:55:02 DEBUG : koofr:4266cdc3-3d29-4a88-ac94-738914c162b4:rclone-test-lahicec4kiwo: Purge dir "sub dir"
2025/09/05 03:55:03 DEBUG : koofr:4266cdc3-3d29-4a88-ac94-738914c162b4:rclone-test-lahicec4kiwo: Purge dir ""
--- PASS: TestServerSideCopyOverSelf (9.03s)
=== RUN   TestServerSideMove
    run.go:180: Remote "koofr:4266cdc3-3d29-4a88-ac94-738914c162b4:rclone-test-gerehal2caha", Local "Local file system at /tmp/rclone3958523963", Modify Window "1ms"
2025/09/05 03:55:03 DEBUG : Creating backend with remote "TestKoofr:rclone-test-zadodus2puxa"
    sync_test.go:1735: Server side move (if possible) koofr:4266cdc3-3d29-4a88-ac94-738914c162b4:rclone-test-gerehal2caha -> koofr:4266cdc3-3d29-4a88-ac94-738914c162b4:rclone-test-zadodus2puxa
2025/09/05 03:55:07 DEBUG : koofr:4266cdc3-3d29-4a88-ac94-738914c162b4:rclone-test-zadodus2puxa: Using server-side directory move
2025/09/05 03:55:07 INFO  : koofr:4266cdc3-3d29-4a88-ac94-738914c162b4:rclone-test-zadodus2puxa: Server side directory move failed - fallback to file moves: can't move directory - incompatible remotes
2025/09/05 03:55:07 DEBUG : potato2: Need to transfer - File not found at Destination
2025/09/05 03:55:07 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ms)
2025/09/05 03:55:07 DEBUG : empty space: Unchanged skipping
2025/09/05 03:55:07 DEBUG : potato3: Sizes differ (src 68 vs dst 60)
2025/09/05 03:55:07 DEBUG : koofr:4266cdc3-3d29-4a88-ac94-738914c162b4:rclone-test-zadodus2puxa: Waiting for checks to finish
2025/09/05 03:55:07 INFO  : empty space: Deleted
2025/09/05 03:55:07 DEBUG : koofr:4266cdc3-3d29-4a88-ac94-738914c162b4:rclone-test-zadodus2puxa: Waiting for transfers to finish
2025/09/05 03:55:07 INFO  : potato3: Deleted
2025/09/05 03:55:07 INFO  : potato2: Moved (server-side)
2025/09/05 03:55:07 INFO  : potato3: Moved (server-side)
2025/09/05 03:55:07 DEBUG : Creating backend with remote "TestKoofr:rclone-test-gusacud2misa"
2025/09/05 03:55:08 DEBUG : koofr:4266cdc3-3d29-4a88-ac94-738914c162b4:rclone-test-gusacud2misa: Using server-side directory move
2025/09/05 03:55:08 INFO  : koofr:4266cdc3-3d29-4a88-ac94-738914c162b4:rclone-test-gusacud2misa: Server side directory move succeeded
2025/09/05 03:55:08 ERROR : error listing: directory not found
2025/09/05 03:55:08 DEBUG : koofr:4266cdc3-3d29-4a88-ac94-738914c162b4:rclone-test-gusacud2misa: Purge object "empty space"
2025/09/05 03:55:08 DEBUG : koofr:4266cdc3-3d29-4a88-ac94-738914c162b4:rclone-test-gusacud2misa: Purge object "potato2"
2025/09/05 03:55:08 DEBUG : koofr:4266cdc3-3d29-4a88-ac94-738914c162b4:rclone-test-gusacud2misa: Purge object "potato3"
2025/09/05 03:55:08 DEBUG : koofr:4266cdc3-3d29-4a88-ac94-738914c162b4:rclone-test-gusacud2misa: Purge dir ""
2025/09/05 03:55:09 ERROR : error listing: directory not found
2025/09/05 03:55:09 DEBUG : koofr:4266cdc3-3d29-4a88-ac94-738914c162b4:rclone-test-zadodus2puxa: Purge dir ""
2025/09/05 03:55:09 NOTICE: purge failed to rmdir "": directory not found
2025/09/05 03:55:09 NOTICE: purge failed: directory not found
--- PASS: TestServerSideMove (5.62s)
=== RUN   TestSyncOverlap
    run.go:180: Remote "koofr:4266cdc3-3d29-4a88-ac94-738914c162b4:rclone-test-gerehal2caha", Local "Local file system at /tmp/rclone3958523963", Modify Window "1ms"
2025/09/05 03:55:09 DEBUG : Creating backend with remote "TestKoofr:rclone-test-gerehal2caha/rclone-sync-test"
--- PASS: TestSyncOverlap (0.32s)
=== RUN   TestSyncOverlapWithFilter
    run.go:180: Remote "koofr:4266cdc3-3d29-4a88-ac94-738914c162b4:rclone-test-gerehal2caha", Local "Local file system at /tmp/rclone3958523963", Modify Window "1ms"
2025/09/05 03:55:09 DEBUG : Creating backend with remote "TestKoofr:rclone-test-gerehal2caha/rclone-sync-test"
2025/09/05 03:55:10 DEBUG : Creating backend with remote "TestKoofr:rclone-test-gerehal2caha/rclone-sync-test-include/layer2"
2025/09/05 03:55:11 DEBUG : Creating backend with remote "TestKoofr:rclone-test-gerehal2caha/rclone-sync-test-ignore-file"
2025/09/05 03:55:13 DEBUG : rclone-sync-test: Excluded
2025/09/05 03:55:13 DEBUG : rclone-sync-test-ignore-file: Excluded
2025/09/05 03:55:13 DEBUG : rclone-sync-test-include/layer2: Excluded
2025/09/05 03:55:13 DEBUG : koofr:4266cdc3-3d29-4a88-ac94-738914c162b4:rclone-test-gerehal2caha/rclone-sync-test: Waiting for checks to finish
2025/09/05 03:55:13 DEBUG : koofr:4266cdc3-3d29-4a88-ac94-738914c162b4:rclone-test-gerehal2caha/rclone-sync-test: Waiting for transfers to finish
2025/09/05 03:55:13 DEBUG : Waiting for deletions to finish
2025/09/05 03:55:13 INFO  : There was nothing to transfer
2025/09/05 03:55:13 DEBUG : rclone-sync-test: Excluded
2025/09/05 03:55:13 DEBUG : rclone-sync-test-ignore-file: Excluded
2025/09/05 03:55:13 DEBUG : rclone-sync-test-include/layer2: Excluded
2025/09/05 03:55:13 DEBUG : koofr:4266cdc3-3d29-4a88-ac94-738914c162b4:rclone-test-gerehal2caha: Waiting for checks to finish
2025/09/05 03:55:13 DEBUG : koofr:4266cdc3-3d29-4a88-ac94-738914c162b4:rclone-test-gerehal2caha: Waiting for transfers to finish
2025/09/05 03:55:13 DEBUG : Waiting for deletions to finish
2025/09/05 03:55:13 INFO  : rclone-sync-test-include: Removing directory
2025/09/05 03:55:13 DEBUG : rclone-sync-test-include: Failed to Rmdir: directory not empty
2025/09/05 03:55:13 DEBUG : koofr:4266cdc3-3d29-4a88-ac94-738914c162b4:rclone-test-gerehal2caha: failed to delete 1 directories
2025/09/05 03:55:13 INFO  : There was nothing to transfer
2025/09/05 03:55:14 DEBUG : rclone-sync-test: Excluded
2025/09/05 03:55:14 DEBUG : rclone-sync-test-ignore-file: Excluded
2025/09/05 03:55:14 DEBUG : rclone-sync-test-include/layer2: Excluded
2025/09/05 03:55:14 DEBUG : koofr:4266cdc3-3d29-4a88-ac94-738914c162b4:rclone-test-gerehal2caha/rclone-sync-test-include/layer2: Waiting for checks to finish
2025/09/05 03:55:14 DEBUG : koofr:4266cdc3-3d29-4a88-ac94-738914c162b4:rclone-test-gerehal2caha/rclone-sync-test-include/layer2: Waiting for transfers to finish
2025/09/05 03:55:14 DEBUG : Waiting for deletions to finish
2025/09/05 03:55:14 INFO  : There was nothing to transfer
2025/09/05 03:55:14 DEBUG : rclone-sync-test: Excluded
2025/09/05 03:55:14 DEBUG : rclone-sync-test-ignore-file: Excluded
2025/09/05 03:55:14 DEBUG : rclone-sync-test-include/layer2: Excluded
2025/09/05 03:55:14 DEBUG : koofr:4266cdc3-3d29-4a88-ac94-738914c162b4:rclone-test-gerehal2caha: Waiting for checks to finish
2025/09/05 03:55:14 DEBUG : koofr:4266cdc3-3d29-4a88-ac94-738914c162b4:rclone-test-gerehal2caha: Waiting for transfers to finish
2025/09/05 03:55:14 DEBUG : Waiting for deletions to finish
2025/09/05 03:55:14 INFO  : rclone-sync-test-include: Removing directory
2025/09/05 03:55:14 DEBUG : rclone-sync-test-include: Failed to Rmdir: directory not empty
2025/09/05 03:55:14 DEBUG : koofr:4266cdc3-3d29-4a88-ac94-738914c162b4:rclone-test-gerehal2caha: failed to delete 1 directories
2025/09/05 03:55:14 INFO  : There was nothing to transfer
2025/09/05 03:55:15 DEBUG : Excluded
2025/09/05 03:55:15 DEBUG : rclone-sync-test: Excluded
2025/09/05 03:55:15 DEBUG : rclone-sync-test-ignore-file: Excluded
2025/09/05 03:55:15 DEBUG : rclone-sync-test-include/layer2: Excluded
2025/09/05 03:55:15 DEBUG : koofr:4266cdc3-3d29-4a88-ac94-738914c162b4:rclone-test-gerehal2caha/rclone-sync-test-ignore-file: Waiting for checks to finish
2025/09/05 03:55:15 DEBUG : koofr:4266cdc3-3d29-4a88-ac94-738914c162b4:rclone-test-gerehal2caha/rclone-sync-test-ignore-file: Waiting for transfers to finish
2025/09/05 03:55:15 DEBUG : Waiting for deletions to finish
2025/09/05 03:55:15 INFO  : There was nothing to transfer
--- PASS: TestSyncOverlapWithFilter (6.35s)
FAIL
2025/09/05 03:55:16 DEBUG : koofr:4266cdc3-3d29-4a88-ac94-738914c162b4:rclone-test-gerehal2caha: Purge dir ""
"./sync.test -test.v -test.timeout 1h0m0s -remote TestKoofr: -verbose -test.run '^(TestServerSideCopy|TestServerSideCopyOverSelf|TestServerSideMove|TestSyncOverlap|TestSyncOverlapWithFilter)$'" - Finished ERROR in 5m24.637863213s (try 2/5): exit status 1: Failed [TestServerSideCopy]