"./sync.test -test.v -test.timeout 1h0m0s -remote TestPcloud: -verbose -test.run '^(TestServerSideMoveWithFilter|TestSyncCopyDest|TestSyncImmutable|TestSyncOverlapWithFilter|TestSyncReplaceDirModTime|TestSyncSuffixKeepExtension|TestSyncUTFNorm)$'" - Starting (try 3/5) 2025/04/13 04:07:18 DEBUG : Creating backend with remote "TestPcloud:rclone-test-gayucod4bopo" 2025/04/13 04:07:18 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/04/13 04:07:19 DEBUG : Creating backend with remote "/tmp/rclone1617556687" === RUN TestServerSideMoveWithFilter run.go:180: Remote "pcloud root 'rclone-test-gayucod4bopo'", Local "Local file system at /tmp/rclone1617556687", Modify Window "1s" 2025/04/13 04:07:19 DEBUG : Creating backend with remote "TestPcloud:rclone-test-poyuras8ridu" fstest.go:292: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:295: Flushing the directory cache fstest.go:286: Sleeping for 6s just to make sure sync_test.go:1705: Server side move (if possible) pcloud root 'rclone-test-gayucod4bopo' -> pcloud root 'rclone-test-poyuras8ridu' fstest.go:292: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:295: Flushing the directory cache fstest.go:286: Sleeping for 6s just to make sure 2025/04/13 04:07:37 DEBUG : pcloud root 'rclone-test-poyuras8ridu': Waiting for checks to finish 2025/04/13 04:07:37 DEBUG : pcloud root 'rclone-test-poyuras8ridu': Waiting for transfers to finish 2025/04/13 04:07:37 INFO : There was nothing to transfer 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:1720 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1894 Error: Should be true Test: TestServerSideMoveWithFilter Messages: listing wrong, want empty space (1) got empty space (1), potato2 (60), potato3 (68) fstest.go:185: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:185 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:302 /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:1720 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1894 Error: Should be true Test: TestServerSideMoveWithFilter Messages: Unexpected file "potato2" fstest.go:185: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:185 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:302 /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:1720 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1894 Error: Should be true Test: TestServerSideMoveWithFilter Messages: Unexpected file "potato3" 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:350 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1729 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1894 Error: Should be true Test: TestServerSideMoveWithFilter Messages: listing wrong, want empty space (1), potato2 (60), potato3 (68) got empty space (1), potato3 (60) fstest.go:137: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:137 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:143 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:189 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:302 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1729 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1894 Error: Should be true Test: TestServerSideMoveWithFilter Messages: pcloud root 'rclone-test-poyuras8ridu'/potato3: sha1 hash incorrect - expecting "568f8850f3c1a384731dbe40c6d72e1dcae342a0" got "9dc7f7d3279715991a22853f5981df582b7f9f6d" fstest.go:137: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:137 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:143 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:189 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:302 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1729 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1894 Error: Should be true Test: TestServerSideMoveWithFilter Messages: pcloud root 'rclone-test-poyuras8ridu'/potato3: sha256 hash incorrect - expecting "92fe302f416bd441fbeb62f9c9b22cb66a5551aa14a504cff9dc5baeccd46f38" got "d398f81cd00b370b116d049d2f3b73a3a7ed35446486effb789791a7e0b98e9c" fstest.go:144: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:144 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:189 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:302 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1729 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1894 Error: Not equal: expected: 68 actual : 60 Test: TestServerSideMoveWithFilter Messages: potato3: size incorrect file=68 vs obj=60 fstest.go:121: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:121 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:126 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:145 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:189 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:302 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1729 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1894 Error: Should be true Test: TestServerSideMoveWithFilter Messages: potato3: Modification time difference too big |95456h54m53.123456789s| > 1s (want 2011-12-25 12:59:59.123456789 +0000 UTC vs got 2001-02-03 04:05:06 +0000 UTC) (precision 1s) fstest.go:197: Not found "potato2" 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:350 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1729 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1894 Error: Not equal: expected: 0 actual : 1 Test: TestServerSideMoveWithFilter Messages: 1 objects not found 2025/04/13 04:07:53 DEBUG : Creating backend with remote "TestPcloud:rclone-test-sinejum9saci" 2025/04/13 04:07:54 DEBUG : empty space: Excluded (Size Filter) 2025/04/13 04:07:54 DEBUG : empty space: Excluded 2025/04/13 04:07:54 DEBUG : potato3: Need to transfer - File not found at Destination 2025/04/13 04:07:54 DEBUG : pcloud root 'rclone-test-sinejum9saci': Waiting for checks to finish 2025/04/13 04:07:54 DEBUG : pcloud root 'rclone-test-sinejum9saci': Waiting for transfers to finish 2025/04/13 04:07:54 INFO : potato3: Moved (server-side) 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:350 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1749 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1894 Error: Should be true Test: TestServerSideMoveWithFilter Messages: listing wrong, want potato2 (60), potato3 (68) got fstest.go:197: Not found "potato2" fstest.go:197: Not found "potato3" 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:350 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1749 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1894 Error: Not equal: expected: 0 actual : 2 Test: TestServerSideMoveWithFilter Messages: 2 objects not found fstest.go:292: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:295: Flushing the directory cache fstest.go:286: Sleeping for 6s just to make sure 2025/04/13 04:08:10 DEBUG : pcloud root 'rclone-test-sinejum9saci': Purge remote 2025/04/13 04:08:10 NOTICE: purge failed: directory not found 2025/04/13 04:08:10 DEBUG : pcloud root 'rclone-test-poyuras8ridu': Purge remote 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/run.go:173 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:388 /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/testing/testing.go:1176 /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/testing/testing.go:1354 /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/testing/testing.go:1684 Error: Should be true Test: TestServerSideMoveWithFilter Messages: listing wrong, want got empty space (1), potato2 (60), potato3 (68) fstest.go:185: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:185 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:302 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:173 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:388 /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/testing/testing.go:1176 /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/testing/testing.go:1354 /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/testing/testing.go:1684 Error: Should be true Test: TestServerSideMoveWithFilter Messages: Unexpected file "empty space" fstest.go:185: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:185 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:302 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:173 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:388 /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/testing/testing.go:1176 /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/testing/testing.go:1354 /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/testing/testing.go:1684 Error: Should be true Test: TestServerSideMoveWithFilter Messages: Unexpected file "potato2" fstest.go:185: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:185 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:302 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:173 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:388 /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/testing/testing.go:1176 /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/testing/testing.go:1354 /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/testing/testing.go:1684 Error: Should be true Test: TestServerSideMoveWithFilter Messages: Unexpected file "potato3" --- FAIL: TestServerSideMoveWithFilter (59.44s) === RUN TestSyncOverlapWithFilter run.go:180: Remote "pcloud root 'rclone-test-gayucod4bopo'", Local "Local file system at /tmp/rclone1617556687", Modify Window "1s" 2025/04/13 04:08:18 DEBUG : Creating backend with remote "TestPcloud:rclone-test-gayucod4bopo/rclone-sync-test" 2025/04/13 04:08:19 DEBUG : Creating backend with remote "TestPcloud:rclone-test-gayucod4bopo/rclone-sync-test-include/layer2" 2025/04/13 04:08:20 DEBUG : Creating backend with remote "TestPcloud:rclone-test-gayucod4bopo/rclone-sync-test-ignore-file" 2025/04/13 04:08:22 DEBUG : rclone-sync-test: Excluded 2025/04/13 04:08:22 DEBUG : rclone-sync-test-ignore-file: Excluded 2025/04/13 04:08:22 DEBUG : rclone-sync-test-include/layer2: Excluded 2025/04/13 04:08:22 DEBUG : pcloud root 'rclone-test-gayucod4bopo/rclone-sync-test': Waiting for checks to finish 2025/04/13 04:08:22 DEBUG : pcloud root 'rclone-test-gayucod4bopo/rclone-sync-test': Waiting for transfers to finish 2025/04/13 04:08:22 DEBUG : Waiting for deletions to finish 2025/04/13 04:08:22 INFO : There was nothing to transfer 2025/04/13 04:08:23 DEBUG : rclone-sync-test: Excluded 2025/04/13 04:08:23 DEBUG : rclone-sync-test-ignore-file: Excluded 2025/04/13 04:08:23 DEBUG : rclone-sync-test-include/layer2: Excluded 2025/04/13 04:08:23 DEBUG : pcloud root 'rclone-test-gayucod4bopo': Waiting for checks to finish 2025/04/13 04:08:23 DEBUG : pcloud root 'rclone-test-gayucod4bopo': Waiting for transfers to finish 2025/04/13 04:08:23 DEBUG : Waiting for deletions to finish 2025/04/13 04:08:23 INFO : rclone-sync-test-include: Removing directory 2025/04/13 04:08:23 DEBUG : rclone-sync-test-include: Failed to Rmdir: rmdir failed: pcloud error: Folder is not empty. (2006) 2025/04/13 04:08:23 DEBUG : pcloud root 'rclone-test-gayucod4bopo': failed to delete 1 directories 2025/04/13 04:08:23 INFO : There was nothing to transfer run.go:180: Remote "pcloud root 'rclone-test-gayucod4bopo'", Local "Local file system at /tmp/rclone1617556687", Modify Window "1s" run.go:180: Remote "pcloud root 'rclone-test-gayucod4bopo'", Local "Local file system at /tmp/rclone1617556687", Modify Window "1s" run.go:180: Remote "pcloud root 'rclone-test-gayucod4bopo'", Local "Local file system at /tmp/rclone1617556687", Modify Window "1s" run.go:180: Remote "pcloud root 'rclone-test-gayucod4bopo'", Local "Local file system at /tmp/rclone1617556687", Modify Window "1s" run.go:180: Remote "pcloud root 'rclone-test-gayucod4bopo'", Local "Local file system at /tmp/rclone1617556687", Modify Window "1s" run.go:180: Remote "pcloud root 'rclone-test-gayucod4bopo'", Local "Local file system at /tmp/rclone1617556687", Modify Window "1s" 2025/04/13 04:08:23 DEBUG : rclone-sync-test: Excluded 2025/04/13 04:08:24 DEBUG : rclone-sync-test-ignore-file: Excluded 2025/04/13 04:08:24 DEBUG : rclone-sync-test-include/layer2: Excluded 2025/04/13 04:08:24 DEBUG : pcloud root 'rclone-test-gayucod4bopo/rclone-sync-test-include/layer2': Waiting for checks to finish 2025/04/13 04:08:24 DEBUG : pcloud root 'rclone-test-gayucod4bopo/rclone-sync-test-include/layer2': Waiting for transfers to finish 2025/04/13 04:08:24 DEBUG : Waiting for deletions to finish 2025/04/13 04:08:24 INFO : There was nothing to transfer run.go:180: Remote "pcloud root 'rclone-test-gayucod4bopo'", Local "Local file system at /tmp/rclone1617556687", Modify Window "1s" run.go:180: Remote "pcloud root 'rclone-test-gayucod4bopo'", Local "Local file system at /tmp/rclone1617556687", Modify Window "1s" 2025/04/13 04:08:24 DEBUG : rclone-sync-test: Excluded 2025/04/13 04:08:24 DEBUG : rclone-sync-test-ignore-file: Excluded 2025/04/13 04:08:24 DEBUG : rclone-sync-test-include/layer2: Excluded 2025/04/13 04:08:24 DEBUG : pcloud root 'rclone-test-gayucod4bopo': Waiting for checks to finish 2025/04/13 04:08:24 DEBUG : pcloud root 'rclone-test-gayucod4bopo': Waiting for transfers to finish 2025/04/13 04:08:24 DEBUG : Waiting for deletions to finish 2025/04/13 04:08:24 INFO : rclone-sync-test-include: Removing directory 2025/04/13 04:08:24 DEBUG : rclone-sync-test-include: Failed to Rmdir: rmdir failed: pcloud error: Folder is not empty. (2006) 2025/04/13 04:08:24 DEBUG : pcloud root 'rclone-test-gayucod4bopo': failed to delete 1 directories 2025/04/13 04:08:24 INFO : There was nothing to transfer run.go:180: Remote "pcloud root 'rclone-test-gayucod4bopo'", Local "Local file system at /tmp/rclone1617556687", Modify Window "1s" run.go:180: Remote "pcloud root 'rclone-test-gayucod4bopo'", Local "Local file system at /tmp/rclone1617556687", Modify Window "1s" run.go:180: Remote "pcloud root 'rclone-test-gayucod4bopo'", Local "Local file system at /tmp/rclone1617556687", Modify Window "1s" run.go:180: Remote "pcloud root 'rclone-test-gayucod4bopo'", Local "Local file system at /tmp/rclone1617556687", Modify Window "1s" 2025/04/13 04:08:25 DEBUG : : Excluded 2025/04/13 04:08:25 DEBUG : rclone-sync-test: Excluded 2025/04/13 04:08:25 DEBUG : rclone-sync-test-ignore-file: Excluded 2025/04/13 04:08:25 DEBUG : rclone-sync-test-include/layer2: Excluded 2025/04/13 04:08:25 DEBUG : pcloud root 'rclone-test-gayucod4bopo/rclone-sync-test-ignore-file': Waiting for checks to finish 2025/04/13 04:08:25 DEBUG : pcloud root 'rclone-test-gayucod4bopo/rclone-sync-test-ignore-file': Waiting for transfers to finish 2025/04/13 04:08:25 DEBUG : Waiting for deletions to finish 2025/04/13 04:08:25 INFO : There was nothing to transfer run.go:180: Remote "pcloud root 'rclone-test-gayucod4bopo'", Local "Local file system at /tmp/rclone1617556687", Modify Window "1s" run.go:180: Remote "pcloud root 'rclone-test-gayucod4bopo'", Local "Local file system at /tmp/rclone1617556687", Modify Window "1s" run.go:180: Remote "pcloud root 'rclone-test-gayucod4bopo'", Local "Local file system at /tmp/rclone1617556687", Modify Window "1s" run.go:180: Remote "pcloud root 'rclone-test-gayucod4bopo'", Local "Local file system at /tmp/rclone1617556687", Modify Window "1s" run.go:180: Remote "pcloud root 'rclone-test-gayucod4bopo'", Local "Local file system at /tmp/rclone1617556687", Modify Window "1s" --- PASS: TestSyncOverlapWithFilter (9.95s) === RUN TestSyncCopyDest run.go:180: Remote "pcloud root 'rclone-test-gayucod4bopo'", Local "Local file system at /tmp/rclone1617556687", Modify Window "1s" 2025/04/13 04:08:28 DEBUG : Creating backend with remote "TestPcloud:rclone-test-gayucod4bopo/dst" 2025/04/13 04:08:29 DEBUG : Creating backend with remote "TestPcloud:rclone-test-gayucod4bopo/CopyDest" 2025/04/13 04:08:30 DEBUG : one: Need to transfer - File not found at Destination 2025/04/13 04:08:30 DEBUG : pcloud root 'rclone-test-gayucod4bopo/dst': Waiting for checks to finish 2025/04/13 04:08:30 DEBUG : pcloud root 'rclone-test-gayucod4bopo/dst': Waiting for transfers to finish 2025/04/13 04:08:30 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2025/04/13 04:08:30 DEBUG : one.b824e1ab.partial: renamed to: one 2025/04/13 04:08:30 INFO : one: Copied (new) 2025/04/13 04:08:30 DEBUG : Waiting for deletions to finish 2025/04/13 04:08:31 DEBUG : pcloud root 'rclone-test-gayucod4bopo/dst': Waiting for checks to finish 2025/04/13 04:08:31 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/04/13 04:08:31 DEBUG : pcloud root 'rclone-test-gayucod4bopo/dst': Waiting for transfers to finish 2025/04/13 04:08:31 DEBUG : one: sha1 = 7f376fd65843a1267de8f986d40e164e5a321c13 OK 2025/04/13 04:08:31 DEBUG : one.406dcb2c.partial: renamed to: one 2025/04/13 04:08:31 INFO : one: Copied (replaced existing) 2025/04/13 04:08:31 DEBUG : Waiting for deletions to finish 2025/04/13 04:08:32 DEBUG : Creating backend with remote "TestPcloud:rclone-test-gayucod4bopo/BackupDir" 2025/04/13 04:08:33 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/04/13 04:08:33 DEBUG : pcloud root 'rclone-test-gayucod4bopo/dst': Waiting for checks to finish 2025/04/13 04:08:33 DEBUG : one: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2025/04/13 04:08:33 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/04/13 04:08:33 INFO : one: Moved (server-side) 2025/04/13 04:08:34 DEBUG : one: sha1 = 7f376fd65843a1267de8f986d40e164e5a321c13 OK 2025/04/13 04:08:34 DEBUG : one.d88fe5df.partial: renamed to: one 2025/04/13 04:08:34 INFO : one: Copied (server-side copy) 2025/04/13 04:08:34 DEBUG : one: Destination found in --copy-dest, using server-side copy 2025/04/13 04:08:34 DEBUG : pcloud root 'rclone-test-gayucod4bopo/dst': Waiting for transfers to finish 2025/04/13 04:08:34 DEBUG : Waiting for deletions to finish 2025/04/13 04:08:35 DEBUG : one: Need to transfer - File not found at Destination 2025/04/13 04:08:35 DEBUG : two: Need to transfer - File not found at Destination 2025/04/13 04:08:35 DEBUG : pcloud root 'rclone-test-gayucod4bopo/dst': Waiting for checks to finish 2025/04/13 04:08:35 DEBUG : pcloud root 'rclone-test-gayucod4bopo/dst': Waiting for transfers to finish 2025/04/13 04:08:35 DEBUG : one: sha1 = 7f376fd65843a1267de8f986d40e164e5a321c13 OK 2025/04/13 04:08:35 DEBUG : two: sha1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b OK 2025/04/13 04:08:35 DEBUG : one.406dcb2c.partial: renamed to: one 2025/04/13 04:08:35 INFO : one: Copied (new) 2025/04/13 04:08:35 DEBUG : two.5d8fa229.partial: renamed to: two 2025/04/13 04:08:35 INFO : two: Copied (new) 2025/04/13 04:08:35 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: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:2312 Error: Should be true Test: TestSyncCopyDest Messages: listing wrong, want BackupDir/one (3), CopyDest/one (5), CopyDest/two (3), dst/one (5), dst/two (3) got fstest.go:197: Not found "CopyDest/one" fstest.go:197: Not found "dst/one" fstest.go:197: Not found "BackupDir/one" fstest.go:197: Not found "CopyDest/two" fstest.go:197: Not found "dst/two" 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:2312 Error: Not equal: expected: 0 actual : 5 Test: TestSyncCopyDest Messages: 5 objects not found 2025/04/13 04:08:43 DEBUG : one: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2025/04/13 04:08:43 DEBUG : one: Unchanged skipping 2025/04/13 04:08:43 DEBUG : two: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2025/04/13 04:08:43 DEBUG : two: Unchanged skipping 2025/04/13 04:08:43 DEBUG : pcloud root 'rclone-test-gayucod4bopo/dst': Waiting for checks to finish 2025/04/13 04:08:43 DEBUG : pcloud root 'rclone-test-gayucod4bopo/dst': Waiting for transfers to finish 2025/04/13 04:08:43 DEBUG : Waiting for deletions to finish 2025/04/13 04:08:43 INFO : There was nothing to transfer 2025/04/13 04:08:44 DEBUG : one: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2025/04/13 04:08:44 DEBUG : one: Unchanged skipping 2025/04/13 04:08:44 DEBUG : three: Sizes differ (src 7 vs dst 5) 2025/04/13 04:08:44 DEBUG : three: Destination not found in --copy-dest 2025/04/13 04:08:44 DEBUG : three: Need to transfer - File not found at Destination 2025/04/13 04:08:44 DEBUG : two: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2025/04/13 04:08:44 DEBUG : two: Unchanged skipping 2025/04/13 04:08:44 DEBUG : pcloud root 'rclone-test-gayucod4bopo/dst': Waiting for checks to finish 2025/04/13 04:08:44 DEBUG : pcloud root 'rclone-test-gayucod4bopo/dst': Waiting for transfers to finish 2025/04/13 04:08:45 DEBUG : three: sha1 = 370e613335f3cf2f19b2a2d35a86402f55c659f8 OK 2025/04/13 04:08:45 DEBUG : three.8cc66b1.partial: renamed to: three 2025/04/13 04:08:45 INFO : three: Copied (new) 2025/04/13 04:08:45 DEBUG : Waiting for deletions to finish --- FAIL: TestSyncCopyDest (17.93s) === RUN TestSyncSuffixKeepExtension run.go:180: Remote "pcloud root 'rclone-test-gayucod4bopo'", Local "Local file system at /tmp/rclone1617556687", Modify Window "1s" 2025/04/13 04:08:47 DEBUG : Creating backend with remote "TestPcloud:rclone-test-gayucod4bopo/dst" 2025/04/13 04:08:48 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/04/13 04:08:48 INFO : one: Moved (server-side) to: one-2019-01-01 2025/04/13 04:08:48 DEBUG : one: sha1 = d63a9910ed57f038b76549c070df6196b2102f42 OK 2025/04/13 04:08:48 DEBUG : one.a87a5b8d.partial: renamed to: one 2025/04/13 04:08:48 INFO : one: Copied (new) 2025/04/13 04:08:48 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/04/13 04:08:48 DEBUG : two: Unchanged skipping 2025/04/13 04:08:48 DEBUG : three.txt: Sizes differ (src 6 vs dst 5) 2025/04/13 04:08:48 INFO : three.txt: Moved (server-side) to: three-2019-01-01.txt 2025/04/13 04:08:48 DEBUG : three.txt: sha1 = 92e0258fe3c7456f15dff78d509f6e3c9166c254 OK 2025/04/13 04:08:48 DEBUG : three.txt.468f3f0c.partial: renamed to: three.txt 2025/04/13 04:08:48 INFO : three.txt: Copied (new) 2025/04/13 04:08:50 DEBUG : one: Sizes differ (src 5 vs dst 4) 2025/04/13 04:08:50 INFO : one-2019-01-01: Deleted 2025/04/13 04:08:50 INFO : one: Moved (server-side) to: one-2019-01-01 2025/04/13 04:08:50 DEBUG : one: sha1 = 88a8c2880f555638f990d1ec4c8359b25caa20c7 OK 2025/04/13 04:08:50 DEBUG : one.1503b713.partial: renamed to: one 2025/04/13 04:08:50 INFO : one: Copied (new) 2025/04/13 04:08:50 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/04/13 04:08:50 DEBUG : two: Unchanged skipping 2025/04/13 04:08:50 DEBUG : three.txt: Sizes differ (src 19 vs dst 6) 2025/04/13 04:08:50 INFO : three-2019-01-01.txt: Deleted 2025/04/13 04:08:50 INFO : three.txt: Moved (server-side) to: three-2019-01-01.txt 2025/04/13 04:08:50 DEBUG : three.txt: sha1 = 476e1707cbd7f0497a7dc4d44f0b07b8566ffc30 OK 2025/04/13 04:08:51 DEBUG : three.txt.ff911d53.partial: renamed to: three.txt 2025/04/13 04:08:51 INFO : three.txt: Copied (new) --- PASS: TestSyncSuffixKeepExtension (5.41s) === RUN TestSyncUTFNorm run.go:180: Remote "pcloud root 'rclone-test-gayucod4bopo'", Local "Local file system at /tmp/rclone1617556687", Modify Window "1s" 2025/04/13 04:08:52 DEBUG : Testêé: Sizes differ (src 14 vs dst 18) 2025/04/13 04:08:52 DEBUG : pcloud root 'rclone-test-gayucod4bopo': Waiting for checks to finish 2025/04/13 04:08:52 DEBUG : pcloud root 'rclone-test-gayucod4bopo': Waiting for transfers to finish 2025/04/13 04:08:52 DEBUG : Testêé: sha1 = a54d88e06612d820bc3be72877c74f257b561b19 OK 2025/04/13 04:08:52 DEBUG : Testêé.53508f02.partial: renamed to: Testêé 2025/04/13 04:08:52 INFO : Testêé: Copied (replaced existing) to: Testêé 2025/04/13 04:08:52 DEBUG : Waiting for deletions to finish --- PASS: TestSyncUTFNorm (0.98s) === RUN TestSyncImmutable run.go:180: Remote "pcloud root 'rclone-test-gayucod4bopo'", Local "Local file system at /tmp/rclone1617556687", Modify Window "1s" 2025/04/13 04:08:53 DEBUG : existing: Need to transfer - File not found at Destination 2025/04/13 04:08:53 DEBUG : pcloud root 'rclone-test-gayucod4bopo': Waiting for checks to finish 2025/04/13 04:08:53 DEBUG : pcloud root 'rclone-test-gayucod4bopo': Waiting for transfers to finish 2025/04/13 04:08:53 DEBUG : existing: sha1 = 3e2e95f5ad970eadfa7e17eaf73da97024aa5359 OK 2025/04/13 04:08:53 DEBUG : existing.468f3f0c.partial: renamed to: existing 2025/04/13 04:08:53 INFO : existing: Copied (new) 2025/04/13 04:08:53 DEBUG : Waiting for deletions to finish run.go:180: Remote "pcloud root 'rclone-test-gayucod4bopo'", Local "Local file system at /tmp/rclone1617556687", Modify Window "1s" 2025/04/13 04:08:53 DEBUG : existing: Sizes differ (src 8 vs dst 6) 2025/04/13 04:08:53 DEBUG : pcloud root 'rclone-test-gayucod4bopo': Waiting for checks to finish 2025/04/13 04:08:53 ERROR : existing: Source and destination exist but do not match: immutable file modified 2025/04/13 04:08:53 DEBUG : pcloud root 'rclone-test-gayucod4bopo': Waiting for transfers to finish 2025/04/13 04:08:53 ERROR : pcloud root 'rclone-test-gayucod4bopo': not deleting files as there were IO errors 2025/04/13 04:08:53 ERROR : pcloud root 'rclone-test-gayucod4bopo': not deleting directories as there were IO errors run.go:180: Remote "pcloud root 'rclone-test-gayucod4bopo'", Local "Local file system at /tmp/rclone1617556687", Modify Window "1s" --- PASS: TestSyncImmutable (1.52s) === RUN TestSyncReplaceDirModTime run.go:180: Remote "pcloud root 'rclone-test-gayucod4bopo'", Local "Local file system at /tmp/rclone1617556687", Modify Window "1s" 2025/04/13 04:08:54 DEBUG : empty_dir: Making directory with metadata 2025/04/13 04:08:54 INFO : empty_dir: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/04/13 04:08:54 DEBUG : empty_on_remote: Making directory with metadata 2025/04/13 04:08:54 INFO : empty_on_remote: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/04/13 04:08:54 INFO : empty_on_remote: Making directory 2025/04/13 04:08:54 INFO : empty_on_remote: Made directory with modification time 2011-12-25 12:59:59.123456789 +0000 UTC run.go:180: Remote "pcloud root 'rclone-test-gayucod4bopo'", Local "Local file system at /tmp/rclone1617556687", Modify Window "1s" 2025/04/13 04:08:55 INFO : test_dir1: Set directory modification time (using DirSetModTime) 2025/04/13 04:08:55 INFO : test_dir2: Set directory modification time (using DirSetModTime) 2025/04/13 04:08:55 INFO : test_dir2/sub_dir: Set directory modification time (using DirSetModTime) 2025/04/13 04:08:55 INFO : empty_on_remote: Set directory modification time (using DirSetModTime) run.go:180: Remote "pcloud root 'rclone-test-gayucod4bopo'", Local "Local file system at /tmp/rclone1617556687", Modify Window "1s" --- PASS: TestSyncReplaceDirModTime (2.65s) FAIL 2025/04/13 04:08:57 DEBUG : pcloud root 'rclone-test-gayucod4bopo': Purge remote "./sync.test -test.v -test.timeout 1h0m0s -remote TestPcloud: -verbose -test.run '^(TestServerSideMoveWithFilter|TestSyncCopyDest|TestSyncImmutable|TestSyncOverlapWithFilter|TestSyncReplaceDirModTime|TestSyncSuffixKeepExtension|TestSyncUTFNorm)$'" - Finished ERROR in 1m38.646538449s (try 3/5): exit status 1: Failed [TestServerSideMoveWithFilter TestSyncCopyDest]