"./sync.test -test.v -test.timeout 1h0m0s -remote TestPcloud: -verbose -fast-list" - Starting (try 1/5)
2026/01/10 03:33:09 DEBUG : Creating backend with remote "TestPcloud:rclone-test-cuhewil8jemi"
2026/01/10 03:33:09 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/01/10 03:33:10 DEBUG : Creating backend with remote "/tmp/rclone1566393574"
=== RUN   TestPipe
--- PASS: TestPipe (0.00s)
=== RUN   TestPipeConcurrent
--- PASS: TestPipeConcurrent (0.02s)
=== RUN   TestPipeOrderBy
=== RUN   TestPipeOrderBy/#00
=== RUN   TestPipeOrderBy/size
=== RUN   TestPipeOrderBy/name
=== RUN   TestPipeOrderBy/modtime
=== RUN   TestPipeOrderBy/size,ascending
=== RUN   TestPipeOrderBy/name,asc
=== RUN   TestPipeOrderBy/modtime,ascending
=== RUN   TestPipeOrderBy/size,descending
=== RUN   TestPipeOrderBy/name,desc
=== RUN   TestPipeOrderBy/modtime,descending
=== RUN   TestPipeOrderBy/size,mixed,50
=== RUN   TestPipeOrderBy/size,mixed,51
--- PASS: TestPipeOrderBy (0.00s)
    --- PASS: TestPipeOrderBy/#00 (0.00s)
    --- PASS: TestPipeOrderBy/size (0.00s)
    --- PASS: TestPipeOrderBy/name (0.00s)
    --- PASS: TestPipeOrderBy/modtime (0.00s)
    --- PASS: TestPipeOrderBy/size,ascending (0.00s)
    --- PASS: TestPipeOrderBy/name,asc (0.00s)
    --- PASS: TestPipeOrderBy/modtime,ascending (0.00s)
    --- PASS: TestPipeOrderBy/size,descending (0.00s)
    --- PASS: TestPipeOrderBy/name,desc (0.00s)
    --- PASS: TestPipeOrderBy/modtime,descending (0.00s)
    --- PASS: TestPipeOrderBy/size,mixed,50 (0.00s)
    --- PASS: TestPipeOrderBy/size,mixed,51 (0.00s)
=== RUN   TestNewLess
=== RUN   TestNewLess/blankOK
=== RUN   TestNewLess/tooManyParts
=== RUN   TestNewLess/tooManyParts2
=== RUN   TestNewLess/badMixed
=== RUN   TestNewLess/unknownComparison
=== RUN   TestNewLess/unknownSortDirection
=== RUN   TestNewLess/size
=== RUN   TestNewLess/name
=== RUN   TestNewLess/modtime
=== RUN   TestNewLess/size,ascending
=== RUN   TestNewLess/name,asc
=== RUN   TestNewLess/modtime,ascending
=== RUN   TestNewLess/size,descending
=== RUN   TestNewLess/name,desc
=== RUN   TestNewLess/modtime,descending
=== RUN   TestNewLess/modtime,mixed
=== RUN   TestNewLess/modtime,mixed,30
--- PASS: TestNewLess (0.00s)
    --- PASS: TestNewLess/blankOK (0.00s)
    --- PASS: TestNewLess/tooManyParts (0.00s)
    --- PASS: TestNewLess/tooManyParts2 (0.00s)
    --- PASS: TestNewLess/badMixed (0.00s)
    --- PASS: TestNewLess/unknownComparison (0.00s)
    --- PASS: TestNewLess/unknownSortDirection (0.00s)
    --- PASS: TestNewLess/size (0.00s)
    --- PASS: TestNewLess/name (0.00s)
    --- PASS: TestNewLess/modtime (0.00s)
    --- PASS: TestNewLess/size,ascending (0.00s)
    --- PASS: TestNewLess/name,asc (0.00s)
    --- PASS: TestNewLess/modtime,ascending (0.00s)
    --- PASS: TestNewLess/size,descending (0.00s)
    --- PASS: TestNewLess/name,desc (0.00s)
    --- PASS: TestNewLess/modtime,descending (0.00s)
    --- PASS: TestNewLess/modtime,mixed (0.00s)
    --- PASS: TestNewLess/modtime,mixed,30 (0.00s)
=== RUN   TestRcCopy
    rc_test.go:16: Skipping test on non local remote
--- SKIP: TestRcCopy (0.00s)
=== RUN   TestRcMove
    rc_test.go:16: Skipping test on non local remote
--- SKIP: TestRcMove (0.00s)
=== RUN   TestRcSync
    rc_test.go:16: Skipping test on non local remote
--- SKIP: TestRcSync (0.00s)
=== RUN   TestCopyWithDryRun
    run.go:185: Remote "pcloud root 'rclone-test-cuhewil8jemi'", Local "Local file system at /tmp/rclone1566393574", Modify Window "1s"
2026/01/10 03:33:10 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination
2026/01/10 03:33:10 NOTICE: sub dir/hello world: Skipped copy as --dry-run is set (size 11)
2026/01/10 03:33:10 DEBUG : pcloud root 'rclone-test-cuhewil8jemi': Waiting for checks to finish
2026/01/10 03:33:10 DEBUG : pcloud root 'rclone-test-cuhewil8jemi': Waiting for transfers to finish
--- PASS: TestCopyWithDryRun (0.80s)
=== RUN   TestCopy
    run.go:185: Remote "pcloud root 'rclone-test-cuhewil8jemi'", Local "Local file system at /tmp/rclone1566393574", Modify Window "1s"
2026/01/10 03:33:11 INFO  : sub dir: Set directory modification time (using DirSetModTime)
2026/01/10 03:33:11 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination
2026/01/10 03:33:11 DEBUG : pcloud root 'rclone-test-cuhewil8jemi': Waiting for checks to finish
2026/01/10 03:33:11 DEBUG : pcloud root 'rclone-test-cuhewil8jemi': Waiting for transfers to finish
2026/01/10 03:33:11 DEBUG : sub dir/hello world.051af1e7.partial: size = 11 OK
2026/01/10 03:33:11 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK
2026/01/10 03:33:11 DEBUG : sub dir/hello world.051af1e7.partial: renamed to: sub dir/hello world
2026/01/10 03:33:11 INFO  : sub dir/hello world: Copied (new)
2026/01/10 03:33:11 DEBUG : pcloud root 'rclone-test-cuhewil8jemi': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata
--- PASS: TestCopy (1.10s)
=== RUN   TestCopyMetadata
    run.go:185: Remote "pcloud root 'rclone-test-cuhewil8jemi'", Local "Local file system at /tmp/rclone1566393574", Modify Window "1s"
    sync_test.go:98: Skipping as metadata not supported
--- SKIP: TestCopyMetadata (0.20s)
=== RUN   TestCopyMetadataNoEmptyDirs
    run.go:185: Remote "pcloud root 'rclone-test-cuhewil8jemi'", Local "Local file system at /tmp/rclone1566393574", Modify Window "1s"
    sync_test.go:98: Skipping as metadata not supported
--- SKIP: TestCopyMetadataNoEmptyDirs (0.20s)
=== RUN   TestCopyMissingDirectory
    run.go:185: Remote "pcloud root 'rclone-test-cuhewil8jemi'", Local "Local file system at /tmp/rclone1566393574", Modify Window "1s"
2026/01/10 03:33:12 DEBUG : Creating backend with remote "/non-existing"
2026/01/10 03:33:12 ERROR : Local file system at /non-existing: error reading source root directory: directory not found
2026/01/10 03:33:12 DEBUG : pcloud root 'rclone-test-cuhewil8jemi': Waiting for checks to finish
2026/01/10 03:33:12 DEBUG : pcloud root 'rclone-test-cuhewil8jemi': Waiting for transfers to finish
--- PASS: TestCopyMissingDirectory (0.30s)
=== RUN   TestCopyNoTraverse
    run.go:185: Remote "pcloud root 'rclone-test-cuhewil8jemi'", Local "Local file system at /tmp/rclone1566393574", Modify Window "1s"
2026/01/10 03:33:13 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination
2026/01/10 03:33:13 DEBUG : pcloud root 'rclone-test-cuhewil8jemi': Waiting for checks to finish
2026/01/10 03:33:13 DEBUG : pcloud root 'rclone-test-cuhewil8jemi': Waiting for transfers to finish
2026/01/10 03:33:13 DEBUG : sub dir/hello world.051af1e7.partial: size = 11 OK
2026/01/10 03:33:13 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK
2026/01/10 03:33:13 DEBUG : sub dir/hello world.051af1e7.partial: renamed to: sub dir/hello world
2026/01/10 03:33:13 INFO  : sub dir/hello world: Copied (new)
--- PASS: TestCopyNoTraverse (1.10s)
=== RUN   TestCopyNoTraverseDeadlock
    run.go:185: Remote "pcloud root 'rclone-test-cuhewil8jemi'", Local "Local file system at /tmp/rclone1566393574", Modify Window "1s"
    sync_test.go:222: Only runs on local
--- SKIP: TestCopyNoTraverseDeadlock (0.20s)
=== RUN   TestCopyCheckFirst
    run.go:185: Remote "pcloud root 'rclone-test-cuhewil8jemi'", Local "Local file system at /tmp/rclone1566393574", Modify Window "1s"
2026/01/10 03:33:14 INFO  : pcloud root 'rclone-test-cuhewil8jemi': Running all checks before starting transfers
2026/01/10 03:33:14 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination
2026/01/10 03:33:14 DEBUG : pcloud root 'rclone-test-cuhewil8jemi': Waiting for checks to finish
2026/01/10 03:33:14 INFO  : pcloud root 'rclone-test-cuhewil8jemi': Checks finished, now starting transfers
2026/01/10 03:33:14 DEBUG : pcloud root 'rclone-test-cuhewil8jemi': Waiting for transfers to finish
2026/01/10 03:33:14 DEBUG : sub dir/hello world.051af1e7.partial: size = 11 OK
2026/01/10 03:33:14 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK
2026/01/10 03:33:14 DEBUG : sub dir/hello world.051af1e7.partial: renamed to: sub dir/hello world
2026/01/10 03:33:14 INFO  : sub dir/hello world: Copied (new)
--- PASS: TestCopyCheckFirst (1.10s)
=== RUN   TestSyncNoTraverse
    run.go:185: Remote "pcloud root 'rclone-test-cuhewil8jemi'", Local "Local file system at /tmp/rclone1566393574", Modify Window "1s"
2026/01/10 03:33:15 ERROR : Ignoring --no-traverse with sync
2026/01/10 03:33:15 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination
2026/01/10 03:33:15 DEBUG : pcloud root 'rclone-test-cuhewil8jemi': Waiting for checks to finish
2026/01/10 03:33:15 DEBUG : pcloud root 'rclone-test-cuhewil8jemi': Waiting for transfers to finish
2026/01/10 03:33:15 DEBUG : sub dir/hello world.051af1e7.partial: size = 11 OK
2026/01/10 03:33:15 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK
2026/01/10 03:33:15 DEBUG : sub dir/hello world.051af1e7.partial: renamed to: sub dir/hello world
2026/01/10 03:33:15 INFO  : sub dir/hello world: Copied (new)
2026/01/10 03:33:15 DEBUG : Waiting for deletions to finish
--- PASS: TestSyncNoTraverse (1.12s)
=== RUN   TestCopyWithDepth
    run.go:185: Remote "pcloud root 'rclone-test-cuhewil8jemi'", Local "Local file system at /tmp/rclone1566393574", Modify Window "1s"
2026/01/10 03:33:16 DEBUG : hello world2: Need to transfer - File not found at Destination
2026/01/10 03:33:16 DEBUG : pcloud root 'rclone-test-cuhewil8jemi': Waiting for checks to finish
2026/01/10 03:33:16 DEBUG : pcloud root 'rclone-test-cuhewil8jemi': Waiting for transfers to finish
2026/01/10 03:33:16 DEBUG : hello world2.ed194a4a.partial: size = 12 OK
2026/01/10 03:33:16 DEBUG : hello world2: sha1 = c0bf117f9c93d02c894fe8ffd6e5cecf3545c209 OK
2026/01/10 03:33:16 DEBUG : hello world2.ed194a4a.partial: renamed to: hello world2
2026/01/10 03:33:16 INFO  : hello world2: Copied (new)
--- PASS: TestCopyWithDepth (0.81s)
=== RUN   TestCopyWithFilesFrom
    run.go:185: Remote "pcloud root 'rclone-test-cuhewil8jemi'", Local "Local file system at /tmp/rclone1566393574", Modify Window "1s"
2026/01/10 03:33:17 DEBUG : potato2: Need to transfer - File not found at Destination
2026/01/10 03:33:17 DEBUG : pcloud root 'rclone-test-cuhewil8jemi': Waiting for checks to finish
2026/01/10 03:33:17 DEBUG : pcloud root 'rclone-test-cuhewil8jemi': Waiting for transfers to finish
2026/01/10 03:33:17 DEBUG : potato2.160ece37.partial: size = 11 OK
2026/01/10 03:33:17 DEBUG : potato2: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK
2026/01/10 03:33:17 DEBUG : potato2.160ece37.partial: renamed to: potato2
2026/01/10 03:33:17 INFO  : potato2: Copied (new)
--- PASS: TestCopyWithFilesFrom (0.83s)
=== RUN   TestCopyWithFilesFromAndNoTraverse
    run.go:185: Remote "pcloud root 'rclone-test-cuhewil8jemi'", Local "Local file system at /tmp/rclone1566393574", Modify Window "1s"
2026/01/10 03:33:18 DEBUG : potato2: Need to transfer - File not found at Destination
2026/01/10 03:33:18 DEBUG : pcloud root 'rclone-test-cuhewil8jemi': Waiting for checks to finish
2026/01/10 03:33:18 DEBUG : pcloud root 'rclone-test-cuhewil8jemi': Waiting for transfers to finish
2026/01/10 03:33:18 DEBUG : potato2.160ece37.partial: size = 11 OK
2026/01/10 03:33:18 DEBUG : potato2: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK
2026/01/10 03:33:18 DEBUG : potato2.160ece37.partial: renamed to: potato2
2026/01/10 03:33:18 INFO  : potato2: Copied (new)
--- PASS: TestCopyWithFilesFromAndNoTraverse (0.80s)
=== RUN   TestCopyEmptyDirectories
    run.go:185: Remote "pcloud root 'rclone-test-cuhewil8jemi'", Local "Local file system at /tmp/rclone1566393574", Modify Window "1s"
2026/01/10 03:33:18 DEBUG : sub dir2/sub sub dir2: Making directory with metadata
2026/01/10 03:33:18 INFO  : sub dir2/sub sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z)
2026/01/10 03:33:18 INFO  : sub dir2: Set directory modification time (using DirSetModTime)
2026/01/10 03:33:18 INFO  : sub dir: Set directory modification time (using DirSetModTime)
2026/01/10 03:33:19 INFO  : sub dir2: Making directory
2026/01/10 03:33:19 INFO  : sub dir: Making directory
2026/01/10 03:33:19 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination
2026/01/10 03:33:19 INFO  : sub dir2/sub sub dir2: Making directory
2026/01/10 03:33:19 DEBUG : pcloud root 'rclone-test-cuhewil8jemi': Waiting for checks to finish
2026/01/10 03:33:19 DEBUG : pcloud root 'rclone-test-cuhewil8jemi': Waiting for transfers to finish
2026/01/10 03:33:19 DEBUG : sub dir/hello world.051af1e7.partial: size = 11 OK
2026/01/10 03:33:19 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK
2026/01/10 03:33:19 DEBUG : sub dir/hello world.051af1e7.partial: renamed to: sub dir/hello world
2026/01/10 03:33:19 INFO  : sub dir/hello world: Copied (new)
2026/01/10 03:33:20 DEBUG : pcloud root 'rclone-test-cuhewil8jemi': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata
--- PASS: TestCopyEmptyDirectories (1.73s)
=== RUN   TestCopyNoEmptyDirectories
    run.go:185: Remote "pcloud root 'rclone-test-cuhewil8jemi'", Local "Local file system at /tmp/rclone1566393574", Modify Window "1s"
2026/01/10 03:33:20 INFO  : sub dir2: Making directory
2026/01/10 03:33:20 DEBUG : sub dir2/sub sub dir2: Making directory with metadata
2026/01/10 03:33:20 INFO  : sub dir2/sub sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z)
2026/01/10 03:33:20 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination
2026/01/10 03:33:20 DEBUG : pcloud root 'rclone-test-cuhewil8jemi': Waiting for checks to finish
2026/01/10 03:33:20 DEBUG : pcloud root 'rclone-test-cuhewil8jemi': Waiting for transfers to finish
2026/01/10 03:33:21 DEBUG : sub dir/hello world.051af1e7.partial: size = 11 OK
2026/01/10 03:33:21 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK
2026/01/10 03:33:21 DEBUG : sub dir/hello world.051af1e7.partial: renamed to: sub dir/hello world
2026/01/10 03:33:21 INFO  : sub dir/hello world: Copied (new)
--- PASS: TestCopyNoEmptyDirectories (1.13s)
=== RUN   TestMoveEmptyDirectories
    run.go:185: Remote "pcloud root 'rclone-test-cuhewil8jemi'", Local "Local file system at /tmp/rclone1566393574", Modify Window "1s"
2026/01/10 03:33:21 DEBUG : sub dir2: Making directory with metadata
2026/01/10 03:33:21 INFO  : sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z)
2026/01/10 03:33:21 INFO  : sub dir2: Making directory
2026/01/10 03:33:22 INFO  : sub dir: Making directory
2026/01/10 03:33:22 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination
2026/01/10 03:33:22 DEBUG : pcloud root 'rclone-test-cuhewil8jemi': Waiting for checks to finish
2026/01/10 03:33:22 DEBUG : pcloud root 'rclone-test-cuhewil8jemi': Waiting for transfers to finish
2026/01/10 03:33:22 DEBUG : sub dir/hello world.051af1e7.partial: size = 11 OK
2026/01/10 03:33:22 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK
2026/01/10 03:33:22 DEBUG : sub dir/hello world.051af1e7.partial: renamed to: sub dir/hello world
2026/01/10 03:33:22 INFO  : sub dir/hello world: Copied (new)
2026/01/10 03:33:22 INFO  : sub dir/hello world: Deleted
2026/01/10 03:33:22 DEBUG : pcloud root 'rclone-test-cuhewil8jemi': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata
2026/01/10 03:33:22 DEBUG : pcloud root 'rclone-test-cuhewil8jemi': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata
--- PASS: TestMoveEmptyDirectories (1.56s)
=== RUN   TestSyncNoUpdateDirModtime
    run.go:185: Remote "pcloud root 'rclone-test-cuhewil8jemi'", Local "Local file system at /tmp/rclone1566393574", Modify Window "1s"
    sync_test.go:438: Skipping test as backend does not support DirSetModTime
--- SKIP: TestSyncNoUpdateDirModtime (0.19s)
=== RUN   TestMoveNoEmptyDirectories
    run.go:185: Remote "pcloud root 'rclone-test-cuhewil8jemi'", Local "Local file system at /tmp/rclone1566393574", Modify Window "1s"
2026/01/10 03:33:23 INFO  : sub dir2: Making directory
2026/01/10 03:33:23 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination
2026/01/10 03:33:23 DEBUG : pcloud root 'rclone-test-cuhewil8jemi': Waiting for checks to finish
2026/01/10 03:33:23 DEBUG : pcloud root 'rclone-test-cuhewil8jemi': Waiting for transfers to finish
2026/01/10 03:33:23 DEBUG : sub dir/hello world.051af1e7.partial: size = 11 OK
2026/01/10 03:33:23 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK
2026/01/10 03:33:24 DEBUG : sub dir/hello world.051af1e7.partial: renamed to: sub dir/hello world
2026/01/10 03:33:24 INFO  : sub dir/hello world: Copied (new)
2026/01/10 03:33:24 INFO  : sub dir/hello world: Deleted
--- PASS: TestMoveNoEmptyDirectories (1.11s)
=== RUN   TestSyncEmptyDirectories
    run.go:185: Remote "pcloud root 'rclone-test-cuhewil8jemi'", Local "Local file system at /tmp/rclone1566393574", Modify Window "1s"
2026/01/10 03:33:24 DEBUG : sub dir2: Making directory with metadata
2026/01/10 03:33:24 INFO  : sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z)
2026/01/10 03:33:24 INFO  : sub dir: Set directory modification time (using DirSetModTime)
2026/01/10 03:33:24 INFO  : sub dir2: Making directory
2026/01/10 03:33:24 INFO  : sub dir: Making directory
2026/01/10 03:33:25 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination
2026/01/10 03:33:25 DEBUG : pcloud root 'rclone-test-cuhewil8jemi': Waiting for checks to finish
2026/01/10 03:33:25 DEBUG : pcloud root 'rclone-test-cuhewil8jemi': Waiting for transfers to finish
2026/01/10 03:33:25 DEBUG : sub dir/hello world.051af1e7.partial: size = 11 OK
2026/01/10 03:33:25 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK
2026/01/10 03:33:25 DEBUG : sub dir/hello world.051af1e7.partial: renamed to: sub dir/hello world
2026/01/10 03:33:25 INFO  : sub dir/hello world: Copied (new)
2026/01/10 03:33:25 DEBUG : Waiting for deletions to finish
2026/01/10 03:33:25 DEBUG : pcloud root 'rclone-test-cuhewil8jemi': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata
--- PASS: TestSyncEmptyDirectories (1.40s)
=== RUN   TestSyncSetDelayedModTimes
    run.go:185: Remote "pcloud root 'rclone-test-cuhewil8jemi'", Local "Local file system at /tmp/rclone1566393574", Modify Window "1s"
    sync_test.go:537: Backend doesn't have DirModTimeUpdatesOnWrite set
    run.go:130: removing file "sub dir/hello world" failed - try 1/3: pcloud error: File not found. (2009)
    run.go:130: removing file "sub dir/hello world" failed - try 2/3: pcloud error: File not found. (2009)
    run.go:130: removing file "sub dir/hello world" failed - try 3/3: pcloud error: File not found. (2009)
    run.go:133: removing file "sub dir/hello world" failed: pcloud error: File not found. (2009)
    run.go:130: removing dir "sub dir2" failed - try 1/3: rmdir failed: pcloud error: Directory does not exist. (2005)
    run.go:130: removing dir "sub dir2" failed - try 2/3: rmdir failed: pcloud error: Directory does not exist. (2005)
    run.go:130: removing dir "sub dir2" failed - try 3/3: rmdir failed: pcloud error: Directory does not exist. (2005)
    run.go:133: removing dir "sub dir2" failed: rmdir failed: pcloud error: Directory does not exist. (2005)
    run.go:130: removing dir "sub dir" failed - try 1/3: rmdir failed: pcloud error: Directory does not exist. (2005)
    run.go:130: removing dir "sub dir" failed - try 2/3: rmdir failed: pcloud error: Directory does not exist. (2005)
    run.go:130: removing dir "sub dir" failed - try 3/3: rmdir failed: pcloud error: Directory does not exist. (2005)
    run.go:133: removing dir "sub dir" failed: rmdir failed: pcloud error: Directory does not exist. (2005)
--- SKIP: TestSyncSetDelayedModTimes (10.09s)
=== RUN   TestSyncNoEmptyDirectories
    run.go:185: Remote "pcloud root 'rclone-test-cuhewil8jemi'", Local "Local file system at /tmp/rclone1566393574", Modify Window "1s"
2026/01/10 03:33:36 INFO  : sub dir2: Making directory
2026/01/10 03:33:36 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination
2026/01/10 03:33:36 DEBUG : pcloud root 'rclone-test-cuhewil8jemi': Waiting for checks to finish
2026/01/10 03:33:36 DEBUG : pcloud root 'rclone-test-cuhewil8jemi': Waiting for transfers to finish
2026/01/10 03:35:36 DEBUG : pacer: low level retry 1/1 (error Put "https://eapi.pcloud.com/uploadfile?filename=hello+world.051af1e7.partial&folderid=21387731001&mtime=981173106&nopartial=1": EOF)
2026/01/10 03:35:36 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/01/10 03:35:36 DEBUG : pacer: Reducing sleep to 15ms
2026/01/10 03:35:37 DEBUG : pacer: Reducing sleep to 11.25ms
2026/01/10 03:35:38 DEBUG : pacer: Reducing sleep to 10ms
2026/01/10 03:35:41 DEBUG : sub dir/hello world: Received error: Put "https://eapi.pcloud.com/uploadfile?filename=hello+world.051af1e7.partial&folderid=21387731001&mtime=981173106&nopartial=1": EOF - low level retry 0/10
2026/01/10 03:35:47 ERROR : sub dir/hello world: Failed to copy: pcloud error: Directory does not exist. (2005)
2026/01/10 03:35:47 INFO  : sub dir/hello world.051af1e7.partial: Failed to remove failed partial copy: couldn't list files: pcloud error: Directory does not exist. (2005)
2026/01/10 03:35:47 ERROR : pcloud root 'rclone-test-cuhewil8jemi': not deleting files as there were IO errors
2026/01/10 03:35:47 ERROR : pcloud root 'rclone-test-cuhewil8jemi': not deleting directories as there were IO errors
    sync_test.go:597: 
        	Error Trace:	/home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:597
        	Error:      	Received unexpected error:
        	            	pcloud error: Directory does not exist. (2005)
        	Test:       	TestSyncNoEmptyDirectories
--- FAIL: TestSyncNoEmptyDirectories (131.67s)
=== RUN   TestServerSideCopy
    run.go:185: Remote "pcloud root 'rclone-test-cuhewil8jemi'", Local "Local file system at /tmp/rclone1566393574", Modify Window "1s"
2026/01/10 03:37:47 DEBUG : pacer: low level retry 1/1 (error Put "https://eapi.pcloud.com/uploadfile?filename=hello+world&folderid=21387731001&mtime=981173106&nopartial=1": EOF)
2026/01/10 03:37:47 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/01/10 03:37:48 DEBUG : pacer: Reducing sleep to 15ms
2026/01/10 03:37:49 DEBUG : pacer: Reducing sleep to 11.25ms
2026/01/10 03:37:50 DEBUG : pacer: Reducing sleep to 10ms
    run.go:286: Retry Put of "sub dir/hello world" to pcloud root 'rclone-test-cuhewil8jemi': 1/10 (Put "https://eapi.pcloud.com/uploadfile?filename=hello+world&folderid=21387731001&mtime=981173106&nopartial=1": EOF)
2026/01/10 03:39:55 DEBUG : pacer: low level retry 1/1 (error Put "https://eapi.pcloud.com/uploadfile?filename=hello+world&folderid=21387731001&mtime=981173106&nopartial=1": EOF)
2026/01/10 03:39:55 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/01/10 03:39:55 DEBUG : pacer: Reducing sleep to 15ms
2026/01/10 03:39:56 DEBUG : pacer: Reducing sleep to 11.25ms
2026/01/10 03:39:57 DEBUG : pacer: Reducing sleep to 10ms
    run.go:286: Retry Put of "sub dir/hello world" to pcloud root 'rclone-test-cuhewil8jemi': 2/10 (Put "https://eapi.pcloud.com/uploadfile?filename=hello+world&folderid=21387731001&mtime=981173106&nopartial=1": EOF)
2026/01/10 03:42:03 DEBUG : pacer: low level retry 1/1 (error Put "https://eapi.pcloud.com/uploadfile?filename=hello+world&folderid=21387731001&mtime=981173106&nopartial=1": EOF)
2026/01/10 03:42:03 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/01/10 03:42:03 DEBUG : pacer: Reducing sleep to 15ms
2026/01/10 03:42:04 DEBUG : pacer: Reducing sleep to 11.25ms
2026/01/10 03:42:05 DEBUG : pacer: Reducing sleep to 10ms
    run.go:286: Retry Put of "sub dir/hello world" to pcloud root 'rclone-test-cuhewil8jemi': 3/10 (Put "https://eapi.pcloud.com/uploadfile?filename=hello+world&folderid=21387731001&mtime=981173106&nopartial=1": EOF)
2026/01/10 03:44:10 DEBUG : pacer: low level retry 1/1 (error Put "https://eapi.pcloud.com/uploadfile?filename=hello+world&folderid=21387731001&mtime=981173106&nopartial=1": EOF)
2026/01/10 03:44:10 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/01/10 03:44:10 DEBUG : pacer: Reducing sleep to 15ms
2026/01/10 03:44:11 DEBUG : pacer: Reducing sleep to 11.25ms
2026/01/10 03:44:13 DEBUG : pacer: Reducing sleep to 10ms
    run.go:286: Retry Put of "sub dir/hello world" to pcloud root 'rclone-test-cuhewil8jemi': 4/10 (Put "https://eapi.pcloud.com/uploadfile?filename=hello+world&folderid=21387731001&mtime=981173106&nopartial=1": EOF)
2026/01/10 03:46:18 DEBUG : pacer: low level retry 1/1 (error Put "https://eapi.pcloud.com/uploadfile?filename=hello+world&folderid=21387731001&mtime=981173106&nopartial=1": EOF)
2026/01/10 03:46:18 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/01/10 03:46:18 DEBUG : pacer: Reducing sleep to 15ms
2026/01/10 03:46:19 DEBUG : pacer: Reducing sleep to 11.25ms
2026/01/10 03:46:20 DEBUG : pacer: Reducing sleep to 10ms
    run.go:286: Retry Put of "sub dir/hello world" to pcloud root 'rclone-test-cuhewil8jemi': 5/10 (Put "https://eapi.pcloud.com/uploadfile?filename=hello+world&folderid=21387731001&mtime=981173106&nopartial=1": EOF)
2026/01/10 03:48:25 DEBUG : pacer: low level retry 1/1 (error Put "https://eapi.pcloud.com/uploadfile?filename=hello+world&folderid=21387731001&mtime=981173106&nopartial=1": EOF)
2026/01/10 03:48:25 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/01/10 03:48:26 DEBUG : pacer: Reducing sleep to 15ms
2026/01/10 03:48:27 DEBUG : pacer: Reducing sleep to 11.25ms
2026/01/10 03:48:28 DEBUG : pacer: Reducing sleep to 10ms
    run.go:286: Retry Put of "sub dir/hello world" to pcloud root 'rclone-test-cuhewil8jemi': 6/10 (Put "https://eapi.pcloud.com/uploadfile?filename=hello+world&folderid=21387731001&mtime=981173106&nopartial=1": EOF)
2026/01/10 03:50:33 DEBUG : pacer: low level retry 1/1 (error Put "https://eapi.pcloud.com/uploadfile?filename=hello+world&folderid=21387731001&mtime=981173106&nopartial=1": EOF)
2026/01/10 03:50:33 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/01/10 03:50:33 DEBUG : pacer: Reducing sleep to 15ms
2026/01/10 03:50:34 DEBUG : pacer: Reducing sleep to 11.25ms
2026/01/10 03:50:35 DEBUG : pacer: Reducing sleep to 10ms
    run.go:286: Retry Put of "sub dir/hello world" to pcloud root 'rclone-test-cuhewil8jemi': 7/10 (Put "https://eapi.pcloud.com/uploadfile?filename=hello+world&folderid=21387731001&mtime=981173106&nopartial=1": EOF)
2026/01/10 03:52:41 DEBUG : pacer: low level retry 1/1 (error Put "https://eapi.pcloud.com/uploadfile?filename=hello+world&folderid=21387731001&mtime=981173106&nopartial=1": EOF)
2026/01/10 03:52:41 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/01/10 03:52:41 DEBUG : pacer: Reducing sleep to 15ms
2026/01/10 03:52:42 DEBUG : pacer: Reducing sleep to 11.25ms
2026/01/10 03:52:43 DEBUG : pacer: Reducing sleep to 10ms
    run.go:286: Retry Put of "sub dir/hello world" to pcloud root 'rclone-test-cuhewil8jemi': 8/10 (Put "https://eapi.pcloud.com/uploadfile?filename=hello+world&folderid=21387731001&mtime=981173106&nopartial=1": EOF)
2026/01/10 03:54:48 DEBUG : pacer: low level retry 1/1 (error Put "https://eapi.pcloud.com/uploadfile?filename=hello+world&folderid=21387731001&mtime=981173106&nopartial=1": EOF)
2026/01/10 03:54:48 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/01/10 03:54:48 DEBUG : pacer: Reducing sleep to 15ms
2026/01/10 03:54:49 DEBUG : pacer: Reducing sleep to 11.25ms
2026/01/10 03:54:51 DEBUG : pacer: Reducing sleep to 10ms
    run.go:286: Retry Put of "sub dir/hello world" to pcloud root 'rclone-test-cuhewil8jemi': 9/10 (Put "https://eapi.pcloud.com/uploadfile?filename=hello+world&folderid=21387731001&mtime=981173106&nopartial=1": EOF)
2026/01/10 03:56:56 DEBUG : pacer: low level retry 1/1 (error Put "https://eapi.pcloud.com/uploadfile?filename=hello+world&folderid=21387731001&mtime=981173106&nopartial=1": EOF)
2026/01/10 03:56:56 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/01/10 03:56:56 DEBUG : pacer: Reducing sleep to 15ms
2026/01/10 03:56:57 DEBUG : pacer: Reducing sleep to 11.25ms
2026/01/10 03:56:58 DEBUG : pacer: Reducing sleep to 10ms
    run.go:290: Failed to put "sub dir/hello world" to "pcloud root 'rclone-test-cuhewil8jemi'": Put "https://eapi.pcloud.com/uploadfile?filename=hello+world&folderid=21387731001&mtime=981173106&nopartial=1": EOF
--- FAIL: TestServerSideCopy (1274.21s)
=== RUN   TestCopyOverSelf
    run.go:185: Remote "pcloud root 'rclone-test-cuhewil8jemi'", Local "Local file system at /tmp/rclone1566393574", Modify Window "1s"
2026/01/10 03:59:02 DEBUG : pacer: low level retry 1/1 (error Put "https://eapi.pcloud.com/uploadfile?filename=hello+world&folderid=21387731001&mtime=981173106&nopartial=1": EOF)
2026/01/10 03:59:02 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/01/10 03:59:02 DEBUG : pacer: Reducing sleep to 15ms
2026/01/10 03:59:03 DEBUG : pacer: Reducing sleep to 11.25ms
2026/01/10 03:59:04 DEBUG : pacer: Reducing sleep to 10ms
    run.go:286: Retry Put of "sub dir/hello world" to pcloud root 'rclone-test-cuhewil8jemi': 1/10 (Put "https://eapi.pcloud.com/uploadfile?filename=hello+world&folderid=21387731001&mtime=981173106&nopartial=1": EOF)
2026/01/10 04:01:09 DEBUG : pacer: low level retry 1/1 (error Put "https://eapi.pcloud.com/uploadfile?filename=hello+world&folderid=21387731001&mtime=981173106&nopartial=1": EOF)
2026/01/10 04:01:09 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/01/10 04:01:09 DEBUG : pacer: Reducing sleep to 15ms
2026/01/10 04:01:10 DEBUG : pacer: Reducing sleep to 11.25ms
2026/01/10 04:01:12 DEBUG : pacer: Reducing sleep to 10ms
    run.go:286: Retry Put of "sub dir/hello world" to pcloud root 'rclone-test-cuhewil8jemi': 2/10 (Put "https://eapi.pcloud.com/uploadfile?filename=hello+world&folderid=21387731001&mtime=981173106&nopartial=1": EOF)
2026/01/10 04:03:17 DEBUG : pacer: low level retry 1/1 (error Put "https://eapi.pcloud.com/uploadfile?filename=hello+world&folderid=21387731001&mtime=981173106&nopartial=1": EOF)
2026/01/10 04:03:17 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/01/10 04:03:17 DEBUG : pacer: Reducing sleep to 15ms
2026/01/10 04:03:18 DEBUG : pacer: Reducing sleep to 11.25ms
2026/01/10 04:03:19 DEBUG : pacer: Reducing sleep to 10ms
    run.go:286: Retry Put of "sub dir/hello world" to pcloud root 'rclone-test-cuhewil8jemi': 3/10 (Put "https://eapi.pcloud.com/uploadfile?filename=hello+world&folderid=21387731001&mtime=981173106&nopartial=1": EOF)
2026/01/10 04:05:25 DEBUG : pacer: low level retry 1/1 (error Put "https://eapi.pcloud.com/uploadfile?filename=hello+world&folderid=21387731001&mtime=981173106&nopartial=1": EOF)
2026/01/10 04:05:25 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/01/10 04:05:26 DEBUG : pacer: Reducing sleep to 15ms
2026/01/10 04:05:27 DEBUG : pacer: Reducing sleep to 11.25ms
2026/01/10 04:05:28 DEBUG : pacer: Reducing sleep to 10ms
    run.go:286: Retry Put of "sub dir/hello world" to pcloud root 'rclone-test-cuhewil8jemi': 4/10 (Put "https://eapi.pcloud.com/uploadfile?filename=hello+world&folderid=21387731001&mtime=981173106&nopartial=1": EOF)
2026/01/10 04:07:33 DEBUG : pacer: low level retry 1/1 (error Put "https://eapi.pcloud.com/uploadfile?filename=hello+world&folderid=21387731001&mtime=981173106&nopartial=1": EOF)
2026/01/10 04:07:33 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/01/10 04:07:33 DEBUG : pacer: Reducing sleep to 15ms
2026/01/10 04:07:34 DEBUG : pacer: Reducing sleep to 11.25ms
2026/01/10 04:07:35 DEBUG : pacer: Reducing sleep to 10ms
    run.go:286: Retry Put of "sub dir/hello world" to pcloud root 'rclone-test-cuhewil8jemi': 5/10 (Put "https://eapi.pcloud.com/uploadfile?filename=hello+world&folderid=21387731001&mtime=981173106&nopartial=1": EOF)
2026/01/10 04:09:41 DEBUG : pacer: low level retry 1/1 (error Put "https://eapi.pcloud.com/uploadfile?filename=hello+world&folderid=21387731001&mtime=981173106&nopartial=1": EOF)
2026/01/10 04:09:41 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/01/10 04:09:41 DEBUG : pacer: Reducing sleep to 15ms
2026/01/10 04:09:42 DEBUG : pacer: Reducing sleep to 11.25ms
2026/01/10 04:09:43 DEBUG : pacer: Reducing sleep to 10ms
    run.go:286: Retry Put of "sub dir/hello world" to pcloud root 'rclone-test-cuhewil8jemi': 6/10 (Put "https://eapi.pcloud.com/uploadfile?filename=hello+world&folderid=21387731001&mtime=981173106&nopartial=1": EOF)
2026/01/10 04:11:48 DEBUG : pacer: low level retry 1/1 (error Put "https://eapi.pcloud.com/uploadfile?filename=hello+world&folderid=21387731001&mtime=981173106&nopartial=1": EOF)
2026/01/10 04:11:48 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/01/10 04:11:48 DEBUG : pacer: Reducing sleep to 15ms
2026/01/10 04:11:50 DEBUG : pacer: Reducing sleep to 11.25ms
2026/01/10 04:11:51 DEBUG : pacer: Reducing sleep to 10ms
    run.go:286: Retry Put of "sub dir/hello world" to pcloud root 'rclone-test-cuhewil8jemi': 7/10 (Put "https://eapi.pcloud.com/uploadfile?filename=hello+world&folderid=21387731001&mtime=981173106&nopartial=1": EOF)
2026/01/10 04:13:56 DEBUG : pacer: low level retry 1/1 (error Put "https://eapi.pcloud.com/uploadfile?filename=hello+world&folderid=21387731001&mtime=981173106&nopartial=1": EOF)
2026/01/10 04:13:56 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/01/10 04:13:56 DEBUG : pacer: Reducing sleep to 15ms
2026/01/10 04:13:57 DEBUG : pacer: Reducing sleep to 11.25ms
2026/01/10 04:13:58 DEBUG : pacer: Reducing sleep to 10ms
    run.go:286: Retry Put of "sub dir/hello world" to pcloud root 'rclone-test-cuhewil8jemi': 8/10 (Put "https://eapi.pcloud.com/uploadfile?filename=hello+world&folderid=21387731001&mtime=981173106&nopartial=1": EOF)
2026/01/10 04:16:04 DEBUG : pacer: low level retry 1/1 (error Put "https://eapi.pcloud.com/uploadfile?filename=hello+world&folderid=21387731001&mtime=981173106&nopartial=1": EOF)
2026/01/10 04:16:04 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/01/10 04:16:04 DEBUG : pacer: Reducing sleep to 15ms
2026/01/10 04:16:05 DEBUG : pacer: Reducing sleep to 11.25ms
2026/01/10 04:16:06 DEBUG : pacer: Reducing sleep to 10ms
    run.go:286: Retry Put of "sub dir/hello world" to pcloud root 'rclone-test-cuhewil8jemi': 9/10 (Put "https://eapi.pcloud.com/uploadfile?filename=hello+world&folderid=21387731001&mtime=981173106&nopartial=1": EOF)
2026/01/10 04:18:11 DEBUG : pacer: low level retry 1/1 (error Put "https://eapi.pcloud.com/uploadfile?filename=hello+world&folderid=21387731001&mtime=981173106&nopartial=1": EOF)
2026/01/10 04:18:11 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/01/10 04:18:11 DEBUG : pacer: Reducing sleep to 15ms
2026/01/10 04:18:12 DEBUG : pacer: Reducing sleep to 11.25ms
2026/01/10 04:18:13 DEBUG : pacer: Reducing sleep to 10ms
    run.go:290: Failed to put "sub dir/hello world" to "pcloud root 'rclone-test-cuhewil8jemi'": Put "https://eapi.pcloud.com/uploadfile?filename=hello+world&folderid=21387731001&mtime=981173106&nopartial=1": EOF
--- FAIL: TestCopyOverSelf (1275.32s)
=== RUN   TestServerSideCopyOverSelf
    run.go:185: Remote "pcloud root 'rclone-test-cuhewil8jemi'", Local "Local file system at /tmp/rclone1566393574", Modify Window "1s"
2026/01/10 04:20:17 DEBUG : pacer: low level retry 1/1 (error Put "https://eapi.pcloud.com/uploadfile?filename=hello+world&folderid=21387731001&mtime=981173106&nopartial=1": EOF)
2026/01/10 04:20:17 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/01/10 04:20:17 DEBUG : pacer: Reducing sleep to 15ms
2026/01/10 04:20:18 DEBUG : pacer: Reducing sleep to 11.25ms
2026/01/10 04:20:19 DEBUG : pacer: Reducing sleep to 10ms
    run.go:286: Retry Put of "sub dir/hello world" to pcloud root 'rclone-test-cuhewil8jemi': 1/10 (Put "https://eapi.pcloud.com/uploadfile?filename=hello+world&folderid=21387731001&mtime=981173106&nopartial=1": EOF)
2026/01/10 04:22:25 DEBUG : pacer: low level retry 1/1 (error Put "https://eapi.pcloud.com/uploadfile?filename=hello+world&folderid=21387731001&mtime=981173106&nopartial=1": EOF)
2026/01/10 04:22:25 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/01/10 04:22:25 DEBUG : pacer: Reducing sleep to 15ms
2026/01/10 04:22:26 DEBUG : pacer: Reducing sleep to 11.25ms
2026/01/10 04:22:27 DEBUG : pacer: Reducing sleep to 10ms
    run.go:286: Retry Put of "sub dir/hello world" to pcloud root 'rclone-test-cuhewil8jemi': 2/10 (Put "https://eapi.pcloud.com/uploadfile?filename=hello+world&folderid=21387731001&mtime=981173106&nopartial=1": EOF)
2026/01/10 04:24:32 DEBUG : pacer: low level retry 1/1 (error Put "https://eapi.pcloud.com/uploadfile?filename=hello+world&folderid=21387731001&mtime=981173106&nopartial=1": EOF)
2026/01/10 04:24:32 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/01/10 04:24:32 DEBUG : pacer: Reducing sleep to 15ms
2026/01/10 04:24:33 DEBUG : pacer: Reducing sleep to 11.25ms
2026/01/10 04:24:34 DEBUG : pacer: Reducing sleep to 10ms
    run.go:286: Retry Put of "sub dir/hello world" to pcloud root 'rclone-test-cuhewil8jemi': 3/10 (Put "https://eapi.pcloud.com/uploadfile?filename=hello+world&folderid=21387731001&mtime=981173106&nopartial=1": EOF)
2026/01/10 04:26:40 DEBUG : pacer: low level retry 1/1 (error Put "https://eapi.pcloud.com/uploadfile?filename=hello+world&folderid=21387731001&mtime=981173106&nopartial=1": EOF)
2026/01/10 04:26:40 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/01/10 04:26:40 DEBUG : pacer: Reducing sleep to 15ms
2026/01/10 04:26:41 DEBUG : pacer: Reducing sleep to 11.25ms
2026/01/10 04:26:42 DEBUG : pacer: Reducing sleep to 10ms
    run.go:286: Retry Put of "sub dir/hello world" to pcloud root 'rclone-test-cuhewil8jemi': 4/10 (Put "https://eapi.pcloud.com/uploadfile?filename=hello+world&folderid=21387731001&mtime=981173106&nopartial=1": EOF)
2026/01/10 04:28:47 DEBUG : pacer: low level retry 1/1 (error Put "https://eapi.pcloud.com/uploadfile?filename=hello+world&folderid=21387731001&mtime=981173106&nopartial=1": EOF)
2026/01/10 04:28:47 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/01/10 04:28:47 DEBUG : pacer: Reducing sleep to 15ms
2026/01/10 04:28:49 DEBUG : pacer: Reducing sleep to 11.25ms
2026/01/10 04:28:50 DEBUG : pacer: Reducing sleep to 10ms
    run.go:286: Retry Put of "sub dir/hello world" to pcloud root 'rclone-test-cuhewil8jemi': 5/10 (Put "https://eapi.pcloud.com/uploadfile?filename=hello+world&folderid=21387731001&mtime=981173106&nopartial=1": EOF)
2026/01/10 04:30:55 DEBUG : pacer: low level retry 1/1 (error Put "https://eapi.pcloud.com/uploadfile?filename=hello+world&folderid=21387731001&mtime=981173106&nopartial=1": EOF)
2026/01/10 04:30:55 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/01/10 04:30:55 DEBUG : pacer: Reducing sleep to 15ms
2026/01/10 04:30:56 DEBUG : pacer: Reducing sleep to 11.25ms
2026/01/10 04:30:57 DEBUG : pacer: Reducing sleep to 10ms
    run.go:286: Retry Put of "sub dir/hello world" to pcloud root 'rclone-test-cuhewil8jemi': 6/10 (Put "https://eapi.pcloud.com/uploadfile?filename=hello+world&folderid=21387731001&mtime=981173106&nopartial=1": EOF)
2026/01/10 04:33:03 DEBUG : pacer: low level retry 1/1 (error Put "https://eapi.pcloud.com/uploadfile?filename=hello+world&folderid=21387731001&mtime=981173106&nopartial=1": EOF)
2026/01/10 04:33:03 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/01/10 04:33:03 DEBUG : pacer: Reducing sleep to 15ms
2026/01/10 04:33:04 DEBUG : pacer: Reducing sleep to 11.25ms
2026/01/10 04:33:05 DEBUG : pacer: Reducing sleep to 10ms
    run.go:286: Retry Put of "sub dir/hello world" to pcloud root 'rclone-test-cuhewil8jemi': 7/10 (Put "https://eapi.pcloud.com/uploadfile?filename=hello+world&folderid=21387731001&mtime=981173106&nopartial=1": EOF)
panic: test timed out after 1h0m0s
	running tests:
		TestServerSideCopyOverSelf (14m53s)

goroutine 4342 [running]:
testing.(*M).startAlarm.func1()
	/usr/local/go/src/testing/testing.go:2484 +0x394
created by time.goFunc
	/usr/local/go/src/time/sleep.go:215 +0x2d

goroutine 1 [chan receive, 14 minutes]:
testing.(*T).Run(0xc0001036c0, {0x28afac2?, 0xc00006bad8?}, 0x29c41e8)
	/usr/local/go/src/testing/testing.go:1859 +0x431
testing.runTests.func1(0xc0001036c0)
	/usr/local/go/src/testing/testing.go:2279 +0x37
testing.tRunner(0xc0001036c0, 0xc00006bc18)
	/usr/local/go/src/testing/testing.go:1792 +0xf4
testing.runTests(0xc0001133c8, {0x41e4800, 0x6c, 0x6c}, {0xee0f3bf76?, 0x41fbac0?, 0x41fbac0?})
	/usr/local/go/src/testing/testing.go:2277 +0x4b4
testing.(*M).Run(0xc0006d0a00)
	/usr/local/go/src/testing/testing.go:2142 +0x64a
github.com/rclone/rclone/fstest.TestMain(0xc0006d0a00)
	/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:74 +0xa6
github.com/rclone/rclone/fs/sync.TestMain(...)
	/home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:45
main.main()
	_testmain.go:261 +0xa9

goroutine 34 [syscall, 60 minutes]:
os/signal.signal_recv()
	/usr/local/go/src/runtime/sigqueue.go:152 +0x29
os/signal.loop()
	/usr/local/go/src/os/signal/signal_unix.go:23 +0x13
created by os/signal.Notify.func1.1 in goroutine 1
	/usr/local/go/src/os/signal/signal.go:152 +0x1f

goroutine 35 [chan receive, 60 minutes]:
github.com/rclone/rclone/fs/accounting.(*tokenBucket).startSignalHandler.func1()
	/home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:24 +0x27
created by github.com/rclone/rclone/fs/accounting.(*tokenBucket).startSignalHandler in goroutine 1
	/home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:21 +0xa7

goroutine 36 [select, 60 minutes]:
github.com/rclone/rclone/lib/oauthutil.(*Renew).renewOnExpiry(0xc00071dc00)
	/home/rclone/go/src/github.com/rclone/rclone/lib/oauthutil/renew.go:43 +0x86
created by github.com/rclone/rclone/lib/oauthutil.NewRenew in goroutine 1
	/home/rclone/go/src/github.com/rclone/rclone/lib/oauthutil/renew.go:32 +0xe5

goroutine 3857 [sleep]:
time.Sleep(0x77359400)
	/usr/local/go/src/runtime/time.go:338 +0x165
github.com/rclone/rclone/fstest.(*Run).WriteObjectTo(0xc00017ae00, {0x2d56cd8, 0x421f2e0}, {0x2d6eff0, 0xc00033c580}, {0x2897293, 0x13}, {0x287f139, 0xb}, {0x1dcd64ff, ...}, ...)
	/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:287 +0x5c7
github.com/rclone/rclone/fstest.(*Run).WriteObject(...)
	/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:297
github.com/rclone/rclone/fs/sync.TestServerSideCopyOverSelf(0xc0004a9500)
	/home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:650 +0xaf
testing.tRunner(0xc0004a9500, 0x29c41e8)
	/usr/local/go/src/testing/testing.go:1792 +0xf4
created by testing.(*T).Run in goroutine 1
	/usr/local/go/src/testing/testing.go:1851 +0x413

goroutine 278 [chan receive, 59 minutes]:
github.com/rclone/rclone/lib/atexit.Register.func1.1()
	/home/rclone/go/src/github.com/rclone/rclone/lib/atexit/atexit.go:45 +0x29
created by github.com/rclone/rclone/lib/atexit.Register.func1 in goroutine 249
	/home/rclone/go/src/github.com/rclone/rclone/lib/atexit/atexit.go:44 +0x68
"./sync.test -test.v -test.timeout 1h0m0s -remote TestPcloud: -verbose -fast-list" - Finished ERROR in 1h0m0.685887758s (try 1/5): exit status 2: Failed [TestSyncNoEmptyDirectories TestServerSideCopy TestCopyOverSelf]