"./sync.test -test.v -test.timeout 1h0m0s -remote TestPcloud: -verbose -test.run '^(TestSyncAfterChangingContentsOnly|TestSyncCopyDest|TestSyncDeleteBefore|TestSyncIgnoreExisting|TestSyncIgnoreTimes)$'" - Starting (try 2/5)
2026/10/10 04:38:50 DEBUG : Creating backend with remote "TestPcloud:rclone-test-fuwirub7vufo"
2026/10/10 04:38:50 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/10/10 04:38:51 DEBUG : Creating backend with remote "/tmp/rclone2653549282"
=== RUN   TestSyncIgnoreTimes
    run.go:198: Remote "pcloud root 'rclone-test-fuwirub7vufo'", Local "Local file system at /tmp/rclone2653549282", Modify Window "1s"
2026/10/10 04:38:52 DEBUG : existing: size = 6 OK
2026/10/10 04:38:52 DEBUG : existing: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2026/10/10 04:38:52 DEBUG : existing: Unchanged skipping
2026/10/10 04:38:52 DEBUG : pcloud root 'rclone-test-fuwirub7vufo': Waiting for checks to finish
2026/10/10 04:38:52 DEBUG : pcloud root 'rclone-test-fuwirub7vufo': Waiting for transfers to finish
2026/10/10 04:38:52 DEBUG : Waiting for deletions to finish
2026/10/10 04:38:52 INFO  : There was nothing to transfer
2026/10/10 04:38:52 DEBUG : existing: Transferring unconditionally as --ignore-times is in use
2026/10/10 04:38:52 DEBUG : pcloud root 'rclone-test-fuwirub7vufo': Waiting for checks to finish
2026/10/10 04:38:52 DEBUG : pcloud root 'rclone-test-fuwirub7vufo': Waiting for transfers to finish
2026/10/10 04:38:52 DEBUG : existing.f29ecfde.partial: size = 6 OK
2026/10/10 04:38:52 DEBUG : existing: sha1 = 3e2e95f5ad970eadfa7e17eaf73da97024aa5359 OK
2026/10/10 04:38:52 DEBUG : existing.f29ecfde.partial: renamed to: existing
2026/10/10 04:38:52 INFO  : existing: Copied (replaced existing)
2026/10/10 04:38:52 DEBUG : Waiting for deletions to finish
--- PASS: TestSyncIgnoreTimes (1.59s)
=== RUN   TestSyncIgnoreExisting
    run.go:198: Remote "pcloud root 'rclone-test-fuwirub7vufo'", Local "Local file system at /tmp/rclone2653549282", Modify Window "1s"
2026/10/10 04:38:53 DEBUG : existing: Need to transfer - File not found at Destination
2026/10/10 04:38:53 DEBUG : pcloud root 'rclone-test-fuwirub7vufo': Waiting for checks to finish
2026/10/10 04:38:53 DEBUG : pcloud root 'rclone-test-fuwirub7vufo': Waiting for transfers to finish
2026/10/10 04:38:53 DEBUG : existing.f29ecfde.partial: size = 6 OK
2026/10/10 04:38:53 DEBUG : existing: sha1 = 3e2e95f5ad970eadfa7e17eaf73da97024aa5359 OK
2026/10/10 04:38:53 DEBUG : existing.f29ecfde.partial: renamed to: existing
2026/10/10 04:38:53 INFO  : existing: Copied (new)
2026/10/10 04:38:53 DEBUG : Waiting for deletions to finish
2026/10/10 04:38:53 DEBUG : existing: Destination exists, skipping
2026/10/10 04:38:53 DEBUG : pcloud root 'rclone-test-fuwirub7vufo': Waiting for checks to finish
2026/10/10 04:38:53 DEBUG : pcloud root 'rclone-test-fuwirub7vufo': Waiting for transfers to finish
2026/10/10 04:38:53 DEBUG : Waiting for deletions to finish
2026/10/10 04:38:53 INFO  : There was nothing to transfer
--- PASS: TestSyncIgnoreExisting (1.04s)
=== RUN   TestSyncAfterChangingContentsOnly
    run.go:198: Remote "pcloud root 'rclone-test-fuwirub7vufo'", Local "Local file system at /tmp/rclone2653549282", Modify Window "1s"
2026/10/10 04:38:54 DEBUG : pcloud root 'rclone-test-fuwirub7vufo': Waiting for checks to finish
2026/10/10 04:38:54 DEBUG : potato: size = 21 OK
2026/10/10 04:38:54 DEBUG : potato: Modification times differ by 119h59m59.876543211s: 2011-12-25 12:59:59.123456789 +0000 UTC, 2011-12-30 12:59:59 +0000 UTC
2026/10/10 04:38:54 DEBUG : potato: sha1 = 1ca5094b3b73f587249eabe179c40a3eec839b1f (Local file system at /tmp/rclone2653549282)
2026/10/10 04:38:54 DEBUG : potato: sha1 = 0fd321a1023ac4e1e1054fcc0236b9d0755f722f (pcloud root 'rclone-test-fuwirub7vufo')
2026/10/10 04:38:54 DEBUG : potato: sha1 differ
2026/10/10 04:38:54 DEBUG : pcloud root 'rclone-test-fuwirub7vufo': Waiting for transfers to finish
2026/10/10 04:38:54 DEBUG : potato.a809149f.partial: size = 21 OK
2026/10/10 04:38:54 DEBUG : potato: sha1 = 1ca5094b3b73f587249eabe179c40a3eec839b1f OK
2026/10/10 04:38:54 DEBUG : potato.a809149f.partial: renamed to: potato
2026/10/10 04:38:54 INFO  : potato: Copied (replaced existing)
2026/10/10 04:38:54 DEBUG : Waiting for deletions to finish
--- PASS: TestSyncAfterChangingContentsOnly (1.14s)
=== RUN   TestSyncDeleteBefore
    run.go:198: Remote "pcloud root 'rclone-test-fuwirub7vufo'", Local "Local file system at /tmp/rclone2653549282", Modify Window "1s"
2026/10/10 04:38:55 DEBUG : Waiting for deletions to finish
2026/10/10 04:38:55 DEBUG : pcloud root 'rclone-test-fuwirub7vufo': Waiting for checks to finish
2026/10/10 04:38:55 DEBUG : pcloud root 'rclone-test-fuwirub7vufo': Waiting for transfers to finish
2026/10/10 04:38:55 INFO  : potato: Deleted
2026/10/10 04:38:55 DEBUG : potato2: Need to transfer - File not found at Destination
2026/10/10 04:38:55 DEBUG : empty space: size = 1 OK
2026/10/10 04:38:55 DEBUG : empty space: Size and modification time the same (differ by -123.456789ms, within tolerance 1s)
2026/10/10 04:38:55 DEBUG : pcloud root 'rclone-test-fuwirub7vufo': Waiting for checks to finish
2026/10/10 04:38:55 DEBUG : empty space: Unchanged skipping
2026/10/10 04:38:55 DEBUG : pcloud root 'rclone-test-fuwirub7vufo': Waiting for transfers to finish
2026/10/10 04:38:56 DEBUG : potato2.2f57cc9e.partial: size = 60 OK
2026/10/10 04:38:56 DEBUG : potato2: sha1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK
2026/10/10 04:38:56 DEBUG : potato2.2f57cc9e.partial: renamed to: potato2
2026/10/10 04:38:56 INFO  : potato2: Copied (new)
--- PASS: TestSyncDeleteBefore (1.62s)
=== RUN   TestSyncCopyDest
    run.go:198: Remote "pcloud root 'rclone-test-fuwirub7vufo'", Local "Local file system at /tmp/rclone2653549282", Modify Window "1s"
2026/10/10 04:38:56 DEBUG : Creating backend with remote "TestPcloud:rclone-test-fuwirub7vufo/dst"
2026/10/10 04:38:57 DEBUG : Creating backend with remote "TestPcloud:rclone-test-fuwirub7vufo/CopyDest"
2026/10/10 04:38:58 DEBUG : one: Need to transfer - File not found at Destination
2026/10/10 04:38:58 DEBUG : pcloud root 'rclone-test-fuwirub7vufo/dst': Waiting for checks to finish
2026/10/10 04:38:58 DEBUG : pcloud root 'rclone-test-fuwirub7vufo/dst': Waiting for transfers to finish
2026/10/10 04:38:59 DEBUG : one.b2cc3c6c.partial: size = 3 OK
2026/10/10 04:38:59 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK
2026/10/10 04:38:59 DEBUG : one.b2cc3c6c.partial: renamed to: one
2026/10/10 04:38:59 INFO  : one: Copied (new)
2026/10/10 04:38:59 DEBUG : Waiting for deletions to finish
2026/10/10 04:38:59 DEBUG : one: size = 5 (Local file system at /tmp/rclone2653549282)
2026/10/10 04:38:59 DEBUG : one: size = 3 (pcloud root 'rclone-test-fuwirub7vufo/dst')
2026/10/10 04:38:59 DEBUG : one: Sizes differ
2026/10/10 04:38:59 DEBUG : pcloud root 'rclone-test-fuwirub7vufo/dst': Waiting for checks to finish
2026/10/10 04:38:59 DEBUG : pcloud root 'rclone-test-fuwirub7vufo/dst': Waiting for transfers to finish
2026/10/10 04:38:59 DEBUG : one.4a8516eb.partial: size = 5 OK
2026/10/10 04:38:59 DEBUG : one: sha1 = 7f376fd65843a1267de8f986d40e164e5a321c13 OK
2026/10/10 04:39:00 DEBUG : one.4a8516eb.partial: renamed to: one
2026/10/10 04:39:00 INFO  : one: Copied (replaced existing)
2026/10/10 04:39:00 DEBUG : Waiting for deletions to finish
2026/10/10 04:39:00 DEBUG : Creating backend with remote "TestPcloud:rclone-test-fuwirub7vufo/BackupDir"
2026/10/10 04:39:01 DEBUG : pcloud root 'rclone-test-fuwirub7vufo/dst': Waiting for checks to finish
2026/10/10 04:39:01 DEBUG : one: size = 5 (Local file system at /tmp/rclone2653549282)
2026/10/10 04:39:01 DEBUG : one: size = 3 (pcloud root 'rclone-test-fuwirub7vufo/dst')
2026/10/10 04:39:01 DEBUG : one: Sizes differ
2026/10/10 04:39:02 DEBUG : one: size = 5 OK
2026/10/10 04:39:02 DEBUG : one: Size and modification time the same (differ by -123.456789ms, within tolerance 1s)
2026/10/10 04:39:02 DEBUG : one: size = 5 (Local file system at /tmp/rclone2653549282)
2026/10/10 04:39:02 DEBUG : one: size = 3 (pcloud root 'rclone-test-fuwirub7vufo/dst')
2026/10/10 04:39:02 DEBUG : one: Sizes differ
2026/10/10 04:39:02 INFO  : one: Moved (server-side)
2026/10/10 04:39:02 DEBUG : one.a000e91c.partial: size = 5 OK
2026/10/10 04:39:02 DEBUG : one: sha1 = 7f376fd65843a1267de8f986d40e164e5a321c13 OK
2026/10/10 04:39:02 DEBUG : one.a000e91c.partial: renamed to: one
2026/10/10 04:39:02 INFO  : one: Copied (server-side copy)
2026/10/10 04:39:02 DEBUG : one: Destination found in --copy-dest, using server-side copy
2026/10/10 04:39:02 DEBUG : pcloud root 'rclone-test-fuwirub7vufo/dst': Waiting for transfers to finish
2026/10/10 04:39:02 DEBUG : Waiting for deletions to finish
2026/10/10 04:39:03 DEBUG : one: size = 5 OK
2026/10/10 04:39:03 DEBUG : one: Size and modification time the same (differ by -123.456789ms, within tolerance 1s)
2026/10/10 04:39:03 DEBUG : one: Unchanged skipping
2026/10/10 04:39:03 DEBUG : two: size = 3 OK
2026/10/10 04:39:03 DEBUG : two: Size and modification time the same (differ by -123.456789ms, within tolerance 1s)
2026/10/10 04:39:04 DEBUG : two.0c2263ec.partial: size = 3 OK
2026/10/10 04:39:04 DEBUG : two: sha1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b OK
2026/10/10 04:39:04 DEBUG : two.0c2263ec.partial: renamed to: two
2026/10/10 04:39:04 INFO  : two: Copied (server-side copy)
2026/10/10 04:39:04 DEBUG : two: Destination found in --copy-dest, using server-side copy
2026/10/10 04:39:04 DEBUG : pcloud root 'rclone-test-fuwirub7vufo/dst': Waiting for checks to finish
2026/10/10 04:39:04 DEBUG : pcloud root 'rclone-test-fuwirub7vufo/dst': Waiting for transfers to finish
2026/10/10 04:39:04 DEBUG : Waiting for deletions to finish
2026/10/10 04:39:04 DEBUG : one: size = 5 OK
2026/10/10 04:39:04 DEBUG : one: Size and modification time the same (differ by -123.456789ms, within tolerance 1s)
2026/10/10 04:39:04 DEBUG : one: Unchanged skipping
2026/10/10 04:39:04 DEBUG : two: size = 3 OK
2026/10/10 04:39:04 DEBUG : two: Size and modification time the same (differ by -123.456789ms, within tolerance 1s)
2026/10/10 04:39:04 DEBUG : two: Unchanged skipping
2026/10/10 04:39:04 DEBUG : pcloud root 'rclone-test-fuwirub7vufo/dst': Waiting for checks to finish
2026/10/10 04:39:04 DEBUG : pcloud root 'rclone-test-fuwirub7vufo/dst': Waiting for transfers to finish
2026/10/10 04:39:04 DEBUG : Waiting for deletions to finish
2026/10/10 04:39:04 INFO  : There was nothing to transfer
    fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3
    fstest.go:302: Flushing the directory cache
    fstest.go:293: Sleeping for 6s just to make sure
2026/10/10 04:39:23 DEBUG : one: size = 5 OK
2026/10/10 04:39:23 DEBUG : one: Size and modification time the same (differ by -123.456789ms, within tolerance 1s)
2026/10/10 04:39:23 DEBUG : one: Unchanged skipping
2026/10/10 04:39:23 DEBUG : three: size = 7 (Local file system at /tmp/rclone2653549282)
2026/10/10 04:39:23 DEBUG : three: size = 5 (pcloud root 'rclone-test-fuwirub7vufo/CopyDest')
2026/10/10 04:39:23 DEBUG : three: Sizes differ
2026/10/10 04:39:23 DEBUG : three: Destination not found in --copy-dest
2026/10/10 04:39:23 DEBUG : three: Need to transfer - File not found at Destination
2026/10/10 04:39:23 DEBUG : pcloud root 'rclone-test-fuwirub7vufo/dst': Waiting for checks to finish
2026/10/10 04:39:23 DEBUG : two: size = 3 OK
2026/10/10 04:39:23 DEBUG : two: Size and modification time the same (differ by -123.456789ms, within tolerance 1s)
2026/10/10 04:39:23 DEBUG : two: Unchanged skipping
2026/10/10 04:39:23 DEBUG : pcloud root 'rclone-test-fuwirub7vufo/dst': Waiting for transfers to finish
2026/10/10 04:39:23 DEBUG : three.63cf7c49.partial: size = 7 OK
2026/10/10 04:39:23 DEBUG : three: sha1 = 370e613335f3cf2f19b2a2d35a86402f55c659f8 OK
2026/10/10 04:39:23 DEBUG : three.63cf7c49.partial: renamed to: three
2026/10/10 04:39:23 INFO  : three: Copied (new)
2026/10/10 04:39:23 DEBUG : Waiting for deletions to finish
--- PASS: TestSyncCopyDest (28.96s)
PASS
2026/10/10 04:39:25 DEBUG : pcloud root 'rclone-test-fuwirub7vufo': Purge remote
"./sync.test -test.v -test.timeout 1h0m0s -remote TestPcloud: -verbose -test.run '^(TestSyncAfterChangingContentsOnly|TestSyncCopyDest|TestSyncDeleteBefore|TestSyncIgnoreExisting|TestSyncIgnoreTimes)$'" - Finished OK in 35.294743779s (try 2/5)