"./sync.test -test.v -test.timeout 1h0m0s -remote TestPcloud: -verbose -test.run '^(TestCopyCheckFirst|TestCopyEmptyDirectories|TestCopyNoEmptyDirectories|TestCopyNoTraverse|TestMoveEmptyDirectories|TestMoveNoEmptyDirectories|TestServerSideCopy|TestSyncEmptyDirectories|TestSyncNoEmptyDirectories|TestSyncNoTraverse)$'" - Starting (try 2/5)
2025/08/28 05:11:20 DEBUG : Creating backend with remote "TestPcloud:rclone-test-fudewah9wuhe"
2025/08/28 05:11:20 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/08/28 05:11:21 DEBUG : Creating backend with remote "/tmp/rclone2651425107"
=== RUN   TestCopyNoTraverse
    run.go:180: Remote "pcloud root 'rclone-test-fudewah9wuhe'", Local "Local file system at /tmp/rclone2651425107", Modify Window "1s"
2025/08/28 05:11:21 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination
2025/08/28 05:11:21 DEBUG : pcloud root 'rclone-test-fudewah9wuhe': Waiting for checks to finish
2025/08/28 05:11:21 DEBUG : pcloud root 'rclone-test-fudewah9wuhe': Waiting for transfers to finish
2025/08/28 05:11:22 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK
2025/08/28 05:11:22 DEBUG : sub dir/hello world.adfb51a5.partial: renamed to: sub dir/hello world
2025/08/28 05:11:22 INFO  : sub dir/hello world: Copied (new)
    fstest.go:295: Sleeping for 1s for list eventual consistency: 1/3
    fstest.go:298: Flushing the directory cache
    fstest.go:289: Sleeping for 6s just to make sure
--- PASS: TestCopyNoTraverse (9.48s)
=== RUN   TestCopyCheckFirst
    run.go:180: Remote "pcloud root 'rclone-test-fudewah9wuhe'", Local "Local file system at /tmp/rclone2651425107", Modify Window "1s"
2025/08/28 05:11:30 INFO  : pcloud root 'rclone-test-fudewah9wuhe': Running all checks before starting transfers
2025/08/28 05:11:30 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination
2025/08/28 05:11:30 DEBUG : pcloud root 'rclone-test-fudewah9wuhe': Waiting for checks to finish
2025/08/28 05:11:30 INFO  : pcloud root 'rclone-test-fudewah9wuhe': Checks finished, now starting transfers
2025/08/28 05:11:30 DEBUG : pcloud root 'rclone-test-fudewah9wuhe': Waiting for transfers to finish
2025/08/28 05:11:31 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK
2025/08/28 05:11:31 DEBUG : sub dir/hello world.adfb51a5.partial: renamed to: sub dir/hello world
2025/08/28 05:11:31 INFO  : sub dir/hello world: Copied (new)
--- PASS: TestCopyCheckFirst (0.93s)
=== RUN   TestSyncNoTraverse
    run.go:180: Remote "pcloud root 'rclone-test-fudewah9wuhe'", Local "Local file system at /tmp/rclone2651425107", Modify Window "1s"
2025/08/28 05:11:31 ERROR : Ignoring --no-traverse with sync
2025/08/28 05:11:31 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination
2025/08/28 05:11:31 DEBUG : pcloud root 'rclone-test-fudewah9wuhe': Waiting for checks to finish
2025/08/28 05:11:31 DEBUG : pcloud root 'rclone-test-fudewah9wuhe': Waiting for transfers to finish
2025/08/28 05:11:32 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK
2025/08/28 05:11:32 DEBUG : sub dir/hello world.adfb51a5.partial: renamed to: sub dir/hello world
2025/08/28 05:11:32 INFO  : sub dir/hello world: Copied (new)
2025/08/28 05:11:32 DEBUG : Waiting for deletions to finish
--- PASS: TestSyncNoTraverse (0.94s)
=== RUN   TestCopyEmptyDirectories
    run.go:180: Remote "pcloud root 'rclone-test-fudewah9wuhe'", Local "Local file system at /tmp/rclone2651425107", Modify Window "1s"
2025/08/28 05:11:32 DEBUG : sub dir2/sub sub dir2: Making directory with metadata
2025/08/28 05:11:32 INFO  : sub dir2/sub sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z)
2025/08/28 05:11:32 INFO  : sub dir2: Set directory modification time (using DirSetModTime)
2025/08/28 05:11:32 INFO  : sub dir: Set directory modification time (using DirSetModTime)
2025/08/28 05:11:32 INFO  : sub dir2: Making directory
2025/08/28 05:11:32 INFO  : sub dir: Making directory
2025/08/28 05:11:33 INFO  : sub dir2/sub sub dir2: Making directory
2025/08/28 05:11:33 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination
2025/08/28 05:11:33 DEBUG : pcloud root 'rclone-test-fudewah9wuhe': Waiting for checks to finish
2025/08/28 05:11:33 DEBUG : pcloud root 'rclone-test-fudewah9wuhe': Waiting for transfers to finish
2025/08/28 05:11:33 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK
2025/08/28 05:11:33 DEBUG : sub dir/hello world.adfb51a5.partial: renamed to: sub dir/hello world
2025/08/28 05:11:33 INFO  : sub dir/hello world: Copied (new)
2025/08/28 05:11:33 DEBUG : pcloud root 'rclone-test-fudewah9wuhe': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata
--- PASS: TestCopyEmptyDirectories (1.45s)
=== RUN   TestCopyNoEmptyDirectories
    run.go:180: Remote "pcloud root 'rclone-test-fudewah9wuhe'", Local "Local file system at /tmp/rclone2651425107", Modify Window "1s"
2025/08/28 05:11:34 INFO  : sub dir2: Making directory
2025/08/28 05:11:34 DEBUG : sub dir2/sub sub dir2: Making directory with metadata
2025/08/28 05:11:34 INFO  : sub dir2/sub sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z)
2025/08/28 05:11:34 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination
2025/08/28 05:11:34 DEBUG : pcloud root 'rclone-test-fudewah9wuhe': Waiting for checks to finish
2025/08/28 05:11:34 DEBUG : pcloud root 'rclone-test-fudewah9wuhe': Waiting for transfers to finish
2025/08/28 05:11:34 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK
2025/08/28 05:11:34 DEBUG : sub dir/hello world.adfb51a5.partial: renamed to: sub dir/hello world
2025/08/28 05:11:34 INFO  : sub dir/hello world: Copied (new)
--- PASS: TestCopyNoEmptyDirectories (0.92s)
=== RUN   TestMoveEmptyDirectories
    run.go:180: Remote "pcloud root 'rclone-test-fudewah9wuhe'", Local "Local file system at /tmp/rclone2651425107", Modify Window "1s"
2025/08/28 05:11:35 DEBUG : sub dir2: Making directory with metadata
2025/08/28 05:11:35 INFO  : sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z)
2025/08/28 05:11:35 INFO  : sub dir2: Making directory
2025/08/28 05:11:35 INFO  : sub dir: Making directory
2025/08/28 05:11:35 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination
2025/08/28 05:11:35 DEBUG : pcloud root 'rclone-test-fudewah9wuhe': Waiting for checks to finish
2025/08/28 05:11:35 DEBUG : pcloud root 'rclone-test-fudewah9wuhe': Waiting for transfers to finish
2025/08/28 05:11:35 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK
2025/08/28 05:11:35 DEBUG : sub dir/hello world.adfb51a5.partial: renamed to: sub dir/hello world
2025/08/28 05:11:35 INFO  : sub dir/hello world: Copied (new)
2025/08/28 05:11:35 INFO  : sub dir/hello world: Deleted
2025/08/28 05:11:35 DEBUG : pcloud root 'rclone-test-fudewah9wuhe': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata
2025/08/28 05:11:35 DEBUG : pcloud root 'rclone-test-fudewah9wuhe': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata
--- PASS: TestMoveEmptyDirectories (1.29s)
=== RUN   TestMoveNoEmptyDirectories
    run.go:180: Remote "pcloud root 'rclone-test-fudewah9wuhe'", Local "Local file system at /tmp/rclone2651425107", Modify Window "1s"
2025/08/28 05:11:36 INFO  : sub dir2: Making directory
2025/08/28 05:11:36 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination
2025/08/28 05:11:36 DEBUG : pcloud root 'rclone-test-fudewah9wuhe': Waiting for checks to finish
2025/08/28 05:11:36 DEBUG : pcloud root 'rclone-test-fudewah9wuhe': Waiting for transfers to finish
2025/08/28 05:11:36 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK
2025/08/28 05:11:36 DEBUG : sub dir/hello world.adfb51a5.partial: renamed to: sub dir/hello world
2025/08/28 05:11:36 INFO  : sub dir/hello world: Copied (new)
2025/08/28 05:11:36 INFO  : sub dir/hello world: Deleted
--- PASS: TestMoveNoEmptyDirectories (0.94s)
=== RUN   TestSyncEmptyDirectories
    run.go:180: Remote "pcloud root 'rclone-test-fudewah9wuhe'", Local "Local file system at /tmp/rclone2651425107", Modify Window "1s"
2025/08/28 05:11:37 DEBUG : sub dir2: Making directory with metadata
2025/08/28 05:11:37 INFO  : sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z)
2025/08/28 05:11:37 INFO  : sub dir: Set directory modification time (using DirSetModTime)
2025/08/28 05:11:37 INFO  : sub dir2: Making directory
2025/08/28 05:11:37 INFO  : sub dir: Making directory
2025/08/28 05:11:37 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination
2025/08/28 05:11:37 DEBUG : pcloud root 'rclone-test-fudewah9wuhe': Waiting for checks to finish
2025/08/28 05:11:37 DEBUG : pcloud root 'rclone-test-fudewah9wuhe': Waiting for transfers to finish
2025/08/28 05:11:37 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK
2025/08/28 05:11:37 DEBUG : sub dir/hello world.adfb51a5.partial: renamed to: sub dir/hello world
2025/08/28 05:11:37 INFO  : sub dir/hello world: Copied (new)
2025/08/28 05:11:37 DEBUG : Waiting for deletions to finish
2025/08/28 05:11:38 DEBUG : pcloud root 'rclone-test-fudewah9wuhe': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata
--- PASS: TestSyncEmptyDirectories (1.19s)
=== RUN   TestSyncNoEmptyDirectories
    run.go:180: Remote "pcloud root 'rclone-test-fudewah9wuhe'", Local "Local file system at /tmp/rclone2651425107", Modify Window "1s"
2025/08/28 05:11:38 INFO  : sub dir2: Making directory
2025/08/28 05:11:38 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination
2025/08/28 05:11:38 DEBUG : pcloud root 'rclone-test-fudewah9wuhe': Waiting for checks to finish
2025/08/28 05:11:38 DEBUG : pcloud root 'rclone-test-fudewah9wuhe': Waiting for transfers to finish
2025/08/28 05:11:38 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK
2025/08/28 05:11:38 DEBUG : sub dir/hello world.adfb51a5.partial: renamed to: sub dir/hello world
2025/08/28 05:11:38 INFO  : sub dir/hello world: Copied (new)
2025/08/28 05:11:38 DEBUG : Waiting for deletions to finish
--- PASS: TestSyncNoEmptyDirectories (0.95s)
=== RUN   TestServerSideCopy
    run.go:180: Remote "pcloud root 'rclone-test-fudewah9wuhe'", Local "Local file system at /tmp/rclone2651425107", Modify Window "1s"
2025/08/28 05:11:39 DEBUG : Creating backend with remote "TestPcloud:rclone-test-curicon5zumo"
    sync_test.go:620: Server side copy (if possible) pcloud root 'rclone-test-fudewah9wuhe' -> pcloud root 'rclone-test-curicon5zumo'
2025/08/28 05:11:40 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination
2025/08/28 05:11:40 DEBUG : pcloud root 'rclone-test-curicon5zumo': Waiting for checks to finish
2025/08/28 05:11:40 DEBUG : pcloud root 'rclone-test-curicon5zumo': Waiting for transfers to finish
2025/08/28 05:11:41 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK
2025/08/28 05:11:41 DEBUG : sub dir/hello world.e0e42c92.partial: renamed to: sub dir/hello world
2025/08/28 05:11:41 INFO  : sub dir/hello world: Copied (server-side copy)
2025/08/28 05:11:41 DEBUG : pcloud root 'rclone-test-curicon5zumo': Purge remote
--- PASS: TestServerSideCopy (2.83s)
PASS
2025/08/28 05:11:42 DEBUG : pcloud root 'rclone-test-fudewah9wuhe': Purge remote
"./sync.test -test.v -test.timeout 1h0m0s -remote TestPcloud: -verbose -test.run '^(TestCopyCheckFirst|TestCopyEmptyDirectories|TestCopyNoEmptyDirectories|TestCopyNoTraverse|TestMoveEmptyDirectories|TestMoveNoEmptyDirectories|TestServerSideCopy|TestSyncEmptyDirectories|TestSyncNoEmptyDirectories|TestSyncNoTraverse)$'" - Finished OK in 21.72606406s (try 2/5)