"./sync.test -test.v -test.timeout 1h0m0s -remote TestPcloud: -verbose -fast-list -test.run '^(TestSyncBackupDirSuffixOnly|TestSyncOverlapWithFilter|TestSyncSuffix)$'" - Starting (try 2/5)
2024/11/10 02:33:29 DEBUG : Creating backend with remote "TestPcloud:rclone-test-mejexir5zesu"
2024/11/10 02:33:29 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2024/11/10 02:33:30 DEBUG : Creating backend with remote "/tmp/rclone1954619537"
=== RUN   TestSyncOverlapWithFilter
    run.go:180: Remote "pcloud root 'rclone-test-mejexir5zesu'", Local "Local file system at /tmp/rclone1954619537", Modify Window "1s"
2024/11/10 02:33:30 DEBUG : Creating backend with remote "TestPcloud:rclone-test-mejexir5zesu/rclone-sync-test"
2024/11/10 02:33:31 DEBUG : Creating backend with remote "TestPcloud:rclone-test-mejexir5zesu/rclone-sync-test-include/layer2"
2024/11/10 02:33:32 DEBUG : Creating backend with remote "TestPcloud:rclone-test-mejexir5zesu/rclone-sync-test-ignore-file"
2024/11/10 02:33:33 DEBUG : pcloud root 'rclone-test-mejexir5zesu/rclone-sync-test': Waiting for checks to finish
2024/11/10 02:33:33 DEBUG : pcloud root 'rclone-test-mejexir5zesu/rclone-sync-test': Waiting for transfers to finish
2024/11/10 02:33:33 DEBUG : Waiting for deletions to finish
2024/11/10 02:33:33 INFO  : There was nothing to transfer
2024/11/10 02:33:34 DEBUG : pcloud root 'rclone-test-mejexir5zesu': Waiting for checks to finish
2024/11/10 02:33:34 DEBUG : pcloud root 'rclone-test-mejexir5zesu': Waiting for transfers to finish
2024/11/10 02:33:34 DEBUG : Waiting for deletions to finish
2024/11/10 02:33:34 INFO  : rclone-sync-test-include: Removing directory
2024/11/10 02:33:34 DEBUG : rclone-sync-test-include: Failed to Rmdir: rmdir failed: pcloud error: Folder is not empty. (2006)
2024/11/10 02:33:34 DEBUG : pcloud root 'rclone-test-mejexir5zesu': failed to delete 1 directories
2024/11/10 02:33:34 INFO  : There was nothing to transfer
    run.go:180: Remote "pcloud root 'rclone-test-mejexir5zesu'", Local "Local file system at /tmp/rclone1954619537", Modify Window "1s"
    run.go:180: Remote "pcloud root 'rclone-test-mejexir5zesu'", Local "Local file system at /tmp/rclone1954619537", Modify Window "1s"
    run.go:180: Remote "pcloud root 'rclone-test-mejexir5zesu'", Local "Local file system at /tmp/rclone1954619537", Modify Window "1s"
    run.go:180: Remote "pcloud root 'rclone-test-mejexir5zesu'", Local "Local file system at /tmp/rclone1954619537", Modify Window "1s"
    run.go:180: Remote "pcloud root 'rclone-test-mejexir5zesu'", Local "Local file system at /tmp/rclone1954619537", Modify Window "1s"
    run.go:180: Remote "pcloud root 'rclone-test-mejexir5zesu'", Local "Local file system at /tmp/rclone1954619537", Modify Window "1s"
2024/11/10 02:33:34 DEBUG : pcloud root 'rclone-test-mejexir5zesu/rclone-sync-test-include/layer2': Waiting for checks to finish
2024/11/10 02:33:34 DEBUG : pcloud root 'rclone-test-mejexir5zesu/rclone-sync-test-include/layer2': Waiting for transfers to finish
2024/11/10 02:33:34 DEBUG : Waiting for deletions to finish
2024/11/10 02:33:34 INFO  : There was nothing to transfer
    run.go:180: Remote "pcloud root 'rclone-test-mejexir5zesu'", Local "Local file system at /tmp/rclone1954619537", Modify Window "1s"
    run.go:180: Remote "pcloud root 'rclone-test-mejexir5zesu'", Local "Local file system at /tmp/rclone1954619537", Modify Window "1s"
2024/11/10 02:33:35 DEBUG : pcloud root 'rclone-test-mejexir5zesu': Waiting for checks to finish
2024/11/10 02:33:35 DEBUG : pcloud root 'rclone-test-mejexir5zesu': Waiting for transfers to finish
2024/11/10 02:33:35 DEBUG : Waiting for deletions to finish
2024/11/10 02:33:35 INFO  : rclone-sync-test-include: Removing directory
2024/11/10 02:33:35 DEBUG : rclone-sync-test-include: Failed to Rmdir: rmdir failed: pcloud error: Folder is not empty. (2006)
2024/11/10 02:33:35 DEBUG : pcloud root 'rclone-test-mejexir5zesu': failed to delete 1 directories
2024/11/10 02:33:35 INFO  : There was nothing to transfer
    run.go:180: Remote "pcloud root 'rclone-test-mejexir5zesu'", Local "Local file system at /tmp/rclone1954619537", Modify Window "1s"
    run.go:180: Remote "pcloud root 'rclone-test-mejexir5zesu'", Local "Local file system at /tmp/rclone1954619537", Modify Window "1s"
    run.go:180: Remote "pcloud root 'rclone-test-mejexir5zesu'", Local "Local file system at /tmp/rclone1954619537", Modify Window "1s"
    run.go:180: Remote "pcloud root 'rclone-test-mejexir5zesu'", Local "Local file system at /tmp/rclone1954619537", Modify Window "1s"
2024/11/10 02:33:36 DEBUG : pcloud root 'rclone-test-mejexir5zesu/rclone-sync-test-ignore-file': Waiting for checks to finish
2024/11/10 02:33:36 DEBUG : pcloud root 'rclone-test-mejexir5zesu/rclone-sync-test-ignore-file': Waiting for transfers to finish
2024/11/10 02:33:36 DEBUG : Waiting for deletions to finish
2024/11/10 02:33:36 INFO  : There was nothing to transfer
    run.go:180: Remote "pcloud root 'rclone-test-mejexir5zesu'", Local "Local file system at /tmp/rclone1954619537", Modify Window "1s"
    run.go:180: Remote "pcloud root 'rclone-test-mejexir5zesu'", Local "Local file system at /tmp/rclone1954619537", Modify Window "1s"
    run.go:180: Remote "pcloud root 'rclone-test-mejexir5zesu'", Local "Local file system at /tmp/rclone1954619537", Modify Window "1s"
    run.go:180: Remote "pcloud root 'rclone-test-mejexir5zesu'", Local "Local file system at /tmp/rclone1954619537", Modify Window "1s"
    run.go:180: Remote "pcloud root 'rclone-test-mejexir5zesu'", Local "Local file system at /tmp/rclone1954619537", Modify Window "1s"
--- PASS: TestSyncOverlapWithFilter (9.20s)
=== RUN   TestSyncBackupDirSuffixOnly
    run.go:180: Remote "pcloud root 'rclone-test-mejexir5zesu'", Local "Local file system at /tmp/rclone1954619537", Modify Window "1s"
2024/11/10 02:33:40 DEBUG : Creating backend with remote "TestPcloud:rclone-test-mejexir5zesu/dst"
2024/11/10 02:33:40 DEBUG : one: Sizes differ (src 4 vs dst 3)
2024/11/10 02:33:40 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2024/11/10 02:33:40 DEBUG : two: Unchanged skipping
2024/11/10 02:33:40 DEBUG : pcloud root 'rclone-test-mejexir5zesu/dst': Waiting for checks to finish
2024/11/10 02:33:40 INFO  : one: Moved (server-side) to: one.bak
2024/11/10 02:33:40 DEBUG : pcloud root 'rclone-test-mejexir5zesu/dst': Waiting for transfers to finish
2024/11/10 02:33:40 DEBUG : one: sha1 = d63a9910ed57f038b76549c070df6196b2102f42 OK
2024/11/10 02:33:41 DEBUG : one.a87a5b8d.partial: renamed to: one
2024/11/10 02:33:41 INFO  : one: Copied (new)
2024/11/10 02:33:41 DEBUG : Waiting for deletions to finish
2024/11/10 02:33:41 INFO  : three.txt: Moved (server-side) to: three.txt.bak
2024/11/10 02:33:41 INFO  : three.txt: Moved into backup dir
2024/11/10 02:33:42 DEBUG : one.bak: Excluded (Path Filter)
2024/11/10 02:33:42 DEBUG : three.txt.bak: Excluded (Path Filter)
2024/11/10 02:33:42 DEBUG : one: Sizes differ (src 5 vs dst 4)
2024/11/10 02:33:42 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2024/11/10 02:33:42 DEBUG : pcloud root 'rclone-test-mejexir5zesu/dst': Waiting for checks to finish
2024/11/10 02:33:42 DEBUG : two: Unchanged skipping
2024/11/10 02:33:42 INFO  : one.bak: Deleted
2024/11/10 02:33:42 INFO  : one: Moved (server-side) to: one.bak
2024/11/10 02:33:42 DEBUG : pcloud root 'rclone-test-mejexir5zesu/dst': Waiting for transfers to finish
2024/11/10 02:33:42 DEBUG : one: sha1 = 88a8c2880f555638f990d1ec4c8359b25caa20c7 OK
2024/11/10 02:33:42 DEBUG : one.1503b713.partial: renamed to: one
2024/11/10 02:33:42 INFO  : one: Copied (new)
2024/11/10 02:33:42 DEBUG : Waiting for deletions to finish
2024/11/10 02:33:42 INFO  : three.txt.bak: Deleted
2024/11/10 02:33:43 INFO  : three.txt: Moved (server-side) to: three.txt.bak
2024/11/10 02:33:43 INFO  : three.txt: Moved into backup dir
--- PASS: TestSyncBackupDirSuffixOnly (4.58s)
=== RUN   TestSyncSuffix
    run.go:180: Remote "pcloud root 'rclone-test-mejexir5zesu'", Local "Local file system at /tmp/rclone1954619537", Modify Window "1s"
2024/11/10 02:33:44 DEBUG : Creating backend with remote "TestPcloud:rclone-test-mejexir5zesu/dst"
2024/11/10 02:33:45 DEBUG : one: Sizes differ (src 4 vs dst 3)
2024/11/10 02:33:45 INFO  : one: Moved (server-side) to: one.bak
2024/11/10 02:33:45 DEBUG : one: sha1 = d63a9910ed57f038b76549c070df6196b2102f42 OK
2024/11/10 02:33:45 DEBUG : one.a87a5b8d.partial: renamed to: one
2024/11/10 02:33:45 INFO  : one: Copied (new)
2024/11/10 02:33:45 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2024/11/10 02:33:45 DEBUG : two: Unchanged skipping
2024/11/10 02:33:45 DEBUG : three.txt: Sizes differ (src 6 vs dst 5)
2024/11/10 02:33:45 INFO  : three.txt: Moved (server-side) to: three.txt.bak
2024/11/10 02:33:46 DEBUG : three.txt: sha1 = 92e0258fe3c7456f15dff78d509f6e3c9166c254 OK
2024/11/10 02:33:46 DEBUG : three.txt.468f3f0c.partial: renamed to: three.txt
2024/11/10 02:33:46 INFO  : three.txt: Copied (new)
2024/11/10 02:33:47 DEBUG : one: Sizes differ (src 5 vs dst 4)
2024/11/10 02:33:47 INFO  : one.bak: Deleted
2024/11/10 02:33:47 INFO  : one: Moved (server-side) to: one.bak
2024/11/10 02:33:47 DEBUG : one: sha1 = 88a8c2880f555638f990d1ec4c8359b25caa20c7 OK
2024/11/10 02:33:47 DEBUG : one.1503b713.partial: renamed to: one
2024/11/10 02:33:47 INFO  : one: Copied (new)
2024/11/10 02:33:47 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2024/11/10 02:33:47 DEBUG : two: Unchanged skipping
2024/11/10 02:33:47 DEBUG : three.txt: Sizes differ (src 19 vs dst 6)
2024/11/10 02:33:48 INFO  : three.txt.bak: Deleted
2024/11/10 02:33:48 INFO  : three.txt: Moved (server-side) to: three.txt.bak
2024/11/10 02:33:48 DEBUG : three.txt: sha1 = 476e1707cbd7f0497a7dc4d44f0b07b8566ffc30 OK
2024/11/10 02:33:48 DEBUG : three.txt.ff911d53.partial: renamed to: three.txt
2024/11/10 02:33:48 INFO  : three.txt: Copied (new)
--- PASS: TestSyncSuffix (5.41s)
PASS
2024/11/10 02:33:49 DEBUG : pcloud root 'rclone-test-mejexir5zesu': Purge remote
"./sync.test -test.v -test.timeout 1h0m0s -remote TestPcloud: -verbose -fast-list -test.run '^(TestSyncBackupDirSuffixOnly|TestSyncOverlapWithFilter|TestSyncSuffix)$'" - Finished OK in 19.834928494s (try 2/5)