"./sync.test -test.v -test.timeout 1h0m0s -remote TestPcloud: -verbose -fast-list -test.run '^(TestSyncBackupDirWithSuffix|TestSyncCompareDest|TestSyncMultipleCompareDest|TestSyncWithExclude|TestSyncWithExcludeAndDeleteExcluded)$'" - Starting (try 4/5) 2025/04/13 06:56:04 DEBUG : Creating backend with remote "TestPcloud:rclone-test-gucodud0xufi" 2025/04/13 06:56:04 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/04/13 06:56:05 DEBUG : Creating backend with remote "/tmp/rclone1660143679" === RUN TestSyncWithExclude run.go:180: Remote "pcloud root 'rclone-test-gucodud0xufi'", Local "Local file system at /tmp/rclone1660143679", Modify Window "1s" 2025/04/13 06:56:06 DEBUG : enormous: Excluded (Size Filter) 2025/04/13 06:56:06 DEBUG : enormous: Excluded 2025/04/13 06:56:06 DEBUG : potato2: Excluded (Size Filter) 2025/04/13 06:56:06 DEBUG : potato2: Excluded 2025/04/13 06:56:06 DEBUG : empty space: Need to transfer - File not found at Destination 2025/04/13 06:56:06 DEBUG : pcloud root 'rclone-test-gucodud0xufi': Waiting for checks to finish 2025/04/13 06:56:06 DEBUG : pcloud root 'rclone-test-gucodud0xufi': Waiting for transfers to finish 2025/04/13 06:56:06 DEBUG : empty space: sha1 = 3bc15c8aae3e4124dd409035f32ea2fd6835efc9 OK 2025/04/13 06:56:06 DEBUG : empty space.56d1852a.partial: renamed to: empty space 2025/04/13 06:56:06 INFO : empty space: Copied (new) 2025/04/13 06:56:06 DEBUG : Waiting for deletions to finish run.go:180: Remote "pcloud root 'rclone-test-gucodud0xufi'", Local "Local file system at /tmp/rclone1660143679", Modify Window "1s" 2025/04/13 06:56:06 DEBUG : enormous: Excluded (Size Filter) 2025/04/13 06:56:06 DEBUG : enormous: Excluded 2025/04/13 06:56:06 DEBUG : potato2: Excluded (Size Filter) 2025/04/13 06:56:06 DEBUG : potato2: Excluded 2025/04/13 06:56:06 DEBUG : potato2: Excluded (Size Filter) 2025/04/13 06:56:06 DEBUG : Local file system at /tmp/rclone1660143679: Waiting for checks to finish 2025/04/13 06:56:06 DEBUG : empty space: Size and modification time the same (differ by 123.456789ms, within tolerance 1s) 2025/04/13 06:56:06 DEBUG : empty space: Unchanged skipping 2025/04/13 06:56:06 DEBUG : Local file system at /tmp/rclone1660143679: Waiting for transfers to finish 2025/04/13 06:56:06 DEBUG : Waiting for deletions to finish 2025/04/13 06:56:06 INFO : There was nothing to transfer run.go:180: Remote "pcloud root 'rclone-test-gucodud0xufi'", Local "Local file system at /tmp/rclone1660143679", Modify Window "1s" --- PASS: TestSyncWithExclude (2.19s) === RUN TestSyncWithExcludeAndDeleteExcluded run.go:180: Remote "pcloud root 'rclone-test-gucodud0xufi'", Local "Local file system at /tmp/rclone1660143679", Modify Window "1s" fstest.go:292: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:295: Flushing the directory cache fstest.go:292: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:295: Flushing the directory cache fstest.go:286: Sleeping for 7s just to make sure 2025/04/13 06:56:18 DEBUG : enormous: Excluded (Size Filter) 2025/04/13 06:56:18 DEBUG : enormous: Excluded 2025/04/13 06:56:18 DEBUG : potato2: Excluded (Size Filter) 2025/04/13 06:56:18 DEBUG : potato2: Excluded 2025/04/13 06:56:18 DEBUG : empty space: Need to transfer - File not found at Destination 2025/04/13 06:56:18 DEBUG : pcloud root 'rclone-test-gucodud0xufi': Waiting for checks to finish 2025/04/13 06:56:18 DEBUG : pcloud root 'rclone-test-gucodud0xufi': Waiting for transfers to finish 2025/04/13 06:56:18 DEBUG : empty space: sha1 = 3bc15c8aae3e4124dd409035f32ea2fd6835efc9 OK 2025/04/13 06:56:19 DEBUG : empty space.56d1852a.partial: renamed to: empty space 2025/04/13 06:56:19 INFO : empty space: Copied (new) 2025/04/13 06:56:19 DEBUG : Waiting for deletions to finish run.go:180: Remote "pcloud root 'rclone-test-gucodud0xufi'", Local "Local file system at /tmp/rclone1660143679", Modify Window "1s" fstest.go:292: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:295: Flushing the directory cache fstest.go:292: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:295: Flushing the directory cache fstest.go:292: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:295: Flushing the directory cache fstest.go:299: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:299 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:344 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:340 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1405 Error: Should be true Test: TestSyncWithExcludeAndDeleteExcluded Messages: listing wrong, want empty space (1) got fstest.go:197: Not found "empty space" fstest.go:200: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:200 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:304 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:344 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:340 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1405 Error: Not equal: expected: 0 actual : 1 Test: TestSyncWithExcludeAndDeleteExcluded Messages: 1 objects not found 2025/04/13 06:56:27 DEBUG : enormous: Excluded (Size Filter) 2025/04/13 06:56:27 DEBUG : potato2: Excluded (Size Filter) 2025/04/13 06:56:27 DEBUG : empty space: Size and modification time the same (differ by 123.456789ms, within tolerance 1s) 2025/04/13 06:56:27 DEBUG : empty space: Unchanged skipping 2025/04/13 06:56:27 DEBUG : Local file system at /tmp/rclone1660143679: Waiting for checks to finish 2025/04/13 06:56:27 DEBUG : Local file system at /tmp/rclone1660143679: Waiting for transfers to finish 2025/04/13 06:56:27 DEBUG : Waiting for deletions to finish 2025/04/13 06:56:27 INFO : enormous: Deleted 2025/04/13 06:56:27 INFO : potato2: Deleted 2025/04/13 06:56:27 INFO : There was nothing to transfer run.go:180: Remote "pcloud root 'rclone-test-gucodud0xufi'", Local "Local file system at /tmp/rclone1660143679", Modify Window "1s" --- FAIL: TestSyncWithExcludeAndDeleteExcluded (20.72s) === RUN TestSyncCompareDest run.go:180: Remote "pcloud root 'rclone-test-gucodud0xufi'", Local "Local file system at /tmp/rclone1660143679", Modify Window "1s" 2025/04/13 06:56:28 DEBUG : Creating backend with remote "TestPcloud:rclone-test-gucodud0xufi/dst" 2025/04/13 06:56:29 DEBUG : Creating backend with remote "TestPcloud:rclone-test-gucodud0xufi/CompareDest" 2025/04/13 06:56:30 DEBUG : one: Need to transfer - File not found at Destination 2025/04/13 06:56:30 DEBUG : pcloud root 'rclone-test-gucodud0xufi/dst': Waiting for checks to finish 2025/04/13 06:56:30 DEBUG : pcloud root 'rclone-test-gucodud0xufi/dst': Waiting for transfers to finish 2025/04/13 06:56:30 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2025/04/13 06:56:30 DEBUG : one.b824e1ab.partial: renamed to: one 2025/04/13 06:56:30 INFO : one: Copied (new) 2025/04/13 06:56:30 DEBUG : Waiting for deletions to finish 2025/04/13 06:56:31 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/04/13 06:56:31 DEBUG : pcloud root 'rclone-test-gucodud0xufi/dst': Waiting for checks to finish 2025/04/13 06:56:31 DEBUG : pcloud root 'rclone-test-gucodud0xufi/dst': Waiting for transfers to finish 2025/04/13 06:56:31 DEBUG : one: sha1 = 7f376fd65843a1267de8f986d40e164e5a321c13 OK 2025/04/13 06:56:31 DEBUG : one.406dcb2c.partial: renamed to: one 2025/04/13 06:56:31 INFO : one: Copied (replaced existing) 2025/04/13 06:56:31 DEBUG : Waiting for deletions to finish fstest.go:292: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:295: Flushing the directory cache fstest.go:292: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:295: Flushing the directory cache fstest.go:286: Sleeping for 7s just to make sure 2025/04/13 06:56:43 DEBUG : pcloud root 'rclone-test-gucodud0xufi/dst': Waiting for checks to finish 2025/04/13 06:56:43 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/04/13 06:56:43 DEBUG : one: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2025/04/13 06:56:43 DEBUG : one: Destination found in --compare-dest, skipping 2025/04/13 06:56:43 DEBUG : pcloud root 'rclone-test-gucodud0xufi/dst': Waiting for transfers to finish 2025/04/13 06:56:43 DEBUG : Waiting for deletions to finish 2025/04/13 06:56:43 INFO : There was nothing to transfer 2025/04/13 06:56:44 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/04/13 06:56:45 DEBUG : two: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2025/04/13 06:56:45 DEBUG : two: Destination found in --compare-dest, skipping 2025/04/13 06:56:45 DEBUG : pcloud root 'rclone-test-gucodud0xufi/dst': Waiting for checks to finish 2025/04/13 06:56:45 DEBUG : one: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2025/04/13 06:56:45 DEBUG : one: Destination found in --compare-dest, skipping 2025/04/13 06:56:45 DEBUG : pcloud root 'rclone-test-gucodud0xufi/dst': Waiting for transfers to finish 2025/04/13 06:56:45 DEBUG : Waiting for deletions to finish 2025/04/13 06:56:45 INFO : There was nothing to transfer 2025/04/13 06:56:45 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/04/13 06:56:45 DEBUG : two: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2025/04/13 06:56:45 DEBUG : two: Destination found in --compare-dest, skipping 2025/04/13 06:56:45 DEBUG : pcloud root 'rclone-test-gucodud0xufi/dst': Waiting for checks to finish 2025/04/13 06:56:45 DEBUG : one: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2025/04/13 06:56:45 DEBUG : one: Destination found in --compare-dest, skipping 2025/04/13 06:56:45 DEBUG : pcloud root 'rclone-test-gucodud0xufi/dst': Waiting for transfers to finish 2025/04/13 06:56:45 DEBUG : Waiting for deletions to finish 2025/04/13 06:56:45 INFO : There was nothing to transfer 2025/04/13 06:56:46 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/04/13 06:56:46 DEBUG : two: Modification times differ by -120h0m0s: 2011-12-30 12:59:59 +0000 UTC, 2011-12-25 12:59:59 +0000 UTC 2025/04/13 06:56:46 DEBUG : one: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2025/04/13 06:56:46 DEBUG : one: Destination found in --compare-dest, skipping 2025/04/13 06:56:46 DEBUG : two: sha1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b OK 2025/04/13 06:56:46 DEBUG : two: Destination found in --compare-dest, skipping 2025/04/13 06:56:46 DEBUG : pcloud root 'rclone-test-gucodud0xufi/dst': Waiting for checks to finish 2025/04/13 06:56:46 DEBUG : pcloud root 'rclone-test-gucodud0xufi/dst': Waiting for transfers to finish 2025/04/13 06:56:46 DEBUG : Waiting for deletions to finish 2025/04/13 06:56:46 INFO : There was nothing to transfer 2025/04/13 06:56:47 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/04/13 06:56:47 DEBUG : two: Sizes differ (src 5 vs dst 3) 2025/04/13 06:56:47 DEBUG : two: Need to transfer - File not found at Destination 2025/04/13 06:56:47 DEBUG : pcloud root 'rclone-test-gucodud0xufi/dst': Waiting for checks to finish 2025/04/13 06:56:47 DEBUG : one: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2025/04/13 06:56:47 DEBUG : one: Destination found in --compare-dest, skipping 2025/04/13 06:56:47 DEBUG : pcloud root 'rclone-test-gucodud0xufi/dst': Waiting for transfers to finish 2025/04/13 06:56:48 DEBUG : two: sha1 = a4be8877ad88f3109a6411985a1f359e10e2d930 OK 2025/04/13 06:56:48 DEBUG : two.1503b713.partial: renamed to: two 2025/04/13 06:56:48 INFO : two: Copied (new) 2025/04/13 06:56:48 DEBUG : Waiting for deletions to finish --- PASS: TestSyncCompareDest (21.45s) === RUN TestSyncMultipleCompareDest run.go:180: Remote "pcloud root 'rclone-test-gucodud0xufi'", Local "Local file system at /tmp/rclone1660143679", Modify Window "1s" 2025/04/13 06:56:50 DEBUG : Creating backend with remote "TestPcloud:rclone-test-gucodud0xufi/dest" 2025/04/13 06:56:51 DEBUG : Creating backend with remote "TestPcloud:rclone-test-gucodud0xufi/pre-dest1" 2025/04/13 06:56:51 DEBUG : Creating backend with remote "TestPcloud:rclone-test-gucodud0xufi/pre-dest2" 2025/04/13 06:56:52 DEBUG : 1: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/04/13 06:56:52 DEBUG : 1: Destination found in --compare-dest, skipping 2025/04/13 06:56:52 DEBUG : 2: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/04/13 06:56:52 DEBUG : 2: Destination found in --compare-dest, skipping 2025/04/13 06:56:52 DEBUG : 3: Need to transfer - File not found at Destination 2025/04/13 06:56:52 DEBUG : pcloud root 'rclone-test-gucodud0xufi/dest': Waiting for checks to finish 2025/04/13 06:56:52 DEBUG : pcloud root 'rclone-test-gucodud0xufi/dest': Waiting for transfers to finish 2025/04/13 06:56:52 DEBUG : 3: sha1 = 77de68daecd823babbb58edb1c8e14d7106e83bb OK 2025/04/13 06:56:52 DEBUG : 3.b37ac6a8.partial: renamed to: 3 2025/04/13 06:56:52 INFO : 3: Copied (new) 2025/04/13 06:56:52 DEBUG : Waiting for deletions to finish --- PASS: TestSyncMultipleCompareDest (4.45s) === RUN TestSyncBackupDirWithSuffix run.go:180: Remote "pcloud root 'rclone-test-gucodud0xufi'", Local "Local file system at /tmp/rclone1660143679", Modify Window "1s" 2025/04/13 06:56:54 DEBUG : Creating backend with remote "TestPcloud:rclone-test-gucodud0xufi/dst" 2025/04/13 06:56:55 DEBUG : Creating backend with remote "TestPcloud:rclone-test-gucodud0xufi/backup" 2025/04/13 06:56:56 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/04/13 06:56:56 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/04/13 06:56:56 DEBUG : two: Unchanged skipping 2025/04/13 06:56:56 DEBUG : pcloud root 'rclone-test-gucodud0xufi/dst': Waiting for checks to finish 2025/04/13 06:56:56 INFO : one: Moved (server-side) to: one.bak 2025/04/13 06:56:56 DEBUG : pcloud root 'rclone-test-gucodud0xufi/dst': Waiting for transfers to finish 2025/04/13 06:56:56 DEBUG : one: sha1 = d63a9910ed57f038b76549c070df6196b2102f42 OK 2025/04/13 06:56:56 DEBUG : one.a87a5b8d.partial: renamed to: one 2025/04/13 06:56:56 INFO : one: Copied (new) 2025/04/13 06:56:56 DEBUG : Waiting for deletions to finish 2025/04/13 06:56:56 INFO : three.txt: Moved (server-side) to: three.txt.bak 2025/04/13 06:56:56 INFO : three.txt: Moved into backup dir 2025/04/13 06:56:57 DEBUG : one: Sizes differ (src 5 vs dst 4) 2025/04/13 06:56:57 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/04/13 06:56:57 DEBUG : two: Unchanged skipping 2025/04/13 06:56:57 DEBUG : pcloud root 'rclone-test-gucodud0xufi/dst': Waiting for checks to finish 2025/04/13 06:56:58 INFO : one.bak: Deleted 2025/04/13 06:56:58 INFO : one: Moved (server-side) to: one.bak 2025/04/13 06:56:58 DEBUG : pcloud root 'rclone-test-gucodud0xufi/dst': Waiting for transfers to finish 2025/04/13 06:56:58 DEBUG : one: sha1 = 88a8c2880f555638f990d1ec4c8359b25caa20c7 OK 2025/04/13 06:56:58 DEBUG : one.1503b713.partial: renamed to: one 2025/04/13 06:56:58 INFO : one: Copied (new) 2025/04/13 06:56:58 DEBUG : Waiting for deletions to finish 2025/04/13 06:56:58 INFO : three.txt.bak: Deleted 2025/04/13 06:56:58 INFO : three.txt: Moved (server-side) to: three.txt.bak 2025/04/13 06:56:58 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirWithSuffix (5.62s) FAIL 2025/04/13 06:56:59 DEBUG : pcloud root 'rclone-test-gucodud0xufi': Purge remote "./sync.test -test.v -test.timeout 1h0m0s -remote TestPcloud: -verbose -fast-list -test.run '^(TestSyncBackupDirWithSuffix|TestSyncCompareDest|TestSyncMultipleCompareDest|TestSyncWithExclude|TestSyncWithExcludeAndDeleteExcluded)$'" - Finished ERROR in 55.16307801s (try 4/5): exit status 1: Failed [TestSyncWithExcludeAndDeleteExcluded]