"./operations.test -test.v -test.timeout 1h0m0s -remote TestPcloud: -verbose -test.run '^(TestCat|TestCopyFileCopyDest|TestCopyURL|TestListDirSortedFn|TestLs|TestLsLong|TestLsWithFilesFrom|TestLsd|TestMoveFile|TestMultithreadCopyAbort|TestRemoveExisting|TestRmdirsLeaveRoot|TestRmdirsNoLeaveRoot|TestRmdirsWithFilter|TestStatJSON|TestTouchDir)$|^TestCheck$/^(1|2|3|4|5|6)$|^TestCheckSizeOnly$/^(3|5|6|7)$|^TestListJSON$/^(Default|DirsOnly|FilesOnly|HashTypes|Metadata|NoMimeType|NoModTime|Recurse|ShowHash|SubDir)$|^TestMultithreadCopy$/^(upload=false,size=134217728,streams=2|upload=false,size=134217729,streams=2|upload=true,size=134217727,streams=2|upload=true,size=134217728,streams=2|upload=true,size=134217729,streams=2)$'" - Starting (try 2/5) 2025/04/14 01:19:05 DEBUG : Creating backend with remote "TestPcloud:rclone-test-yoqazey8necu" 2025/04/14 01:19:05 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/04/14 01:19:05 DEBUG : Creating backend with remote "/tmp/rclone1460511690" === RUN TestMultithreadCopy run.go:180: Remote "pcloud root 'rclone-test-yoqazey8necu'", Local "Local file system at /tmp/rclone1460511690", Modify Window "1s" === RUN TestMultithreadCopy/upload=false,size=134217728,streams=2 2025/04/14 01:20:43 DEBUG : test-multithread-copy-false-134217728-2: multi-thread copy: disabling buffering because destination uses OpenWriterAt 2025/04/14 01:20:43 DEBUG : test-multithread-copy-false-134217728-2: multi-thread copy: write buffer set to 131072 2025/04/14 01:20:43 DEBUG : test-multithread-copy-false-134217728-2: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 2 2025/04/14 01:20:43 DEBUG : test-multithread-copy-false-134217728-2: multi-thread copy: number of streams 4 was bigger than number of chunks 2 2025/04/14 01:20:43 DEBUG : test-multithread-copy-false-134217728-2: Starting multi-thread copy with 2 chunks of size 64Mi with 2 parallel streams 2025/04/14 01:20:43 DEBUG : test-multithread-copy-false-134217728-2: multi-thread copy: chunk 2/2 (67108864-134217728) size 64Mi starting 2025/04/14 01:20:43 DEBUG : test-multithread-copy-false-134217728-2: multi-thread copy: chunk 1/2 (0-67108864) size 64Mi starting 2025/04/14 01:20:43 DEBUG : Couldn't parse Date: from server edef5.pcloud.com: "Mon, 14 Apr 2025 01:20:43 +0000": parsing time "Mon, 14 Apr 2025 01:20:43 +0000" as "Mon Jan _2 15:04:05 2006": cannot parse ", 14 Apr 2025 01:20:43 +0000" as " " 2025/04/14 01:20:43 DEBUG : test-multithread-copy-false-134217728-2: writing chunk 1 2025/04/14 01:20:43 DEBUG : Couldn't parse Date: from server edef5.pcloud.com: "Mon, 14 Apr 2025 01:20:43 +0000": parsing time "Mon, 14 Apr 2025 01:20:43 +0000" as "Mon Jan _2 15:04:05 2006": cannot parse ", 14 Apr 2025 01:20:43 +0000" as " " 2025/04/14 01:20:43 DEBUG : test-multithread-copy-false-134217728-2: writing chunk 0 2025/04/14 01:20:45 DEBUG : test-multithread-copy-false-134217728-2: multi-thread copy: chunk 1/2 (0-67108864) size 64Mi finished 2025/04/14 01:20:45 DEBUG : test-multithread-copy-false-134217728-2: multi-thread copy: chunk 2/2 (67108864-134217728) size 64Mi finished 2025/04/14 01:20:45 DEBUG : test-multithread-copy-false-134217728-2: Finished multi-thread copy with 2 parts of size 64Mi === RUN TestMultithreadCopy/upload=false,size=134217729,streams=2 2025/04/14 01:22:28 DEBUG : test-multithread-copy-false-134217729-2: multi-thread copy: disabling buffering because destination uses OpenWriterAt 2025/04/14 01:22:28 DEBUG : test-multithread-copy-false-134217729-2: multi-thread copy: write buffer set to 131072 2025/04/14 01:22:28 DEBUG : test-multithread-copy-false-134217729-2: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 2 2025/04/14 01:22:28 DEBUG : test-multithread-copy-false-134217729-2: multi-thread copy: number of streams 4 was bigger than number of chunks 3 2025/04/14 01:22:28 DEBUG : test-multithread-copy-false-134217729-2: Starting multi-thread copy with 3 chunks of size 64Mi with 3 parallel streams 2025/04/14 01:22:28 DEBUG : test-multithread-copy-false-134217729-2: multi-thread copy: chunk 3/3 (134217728-134217729) size 1 starting 2025/04/14 01:22:28 DEBUG : test-multithread-copy-false-134217729-2: multi-thread copy: chunk 1/3 (0-67108864) size 64Mi starting 2025/04/14 01:22:28 DEBUG : test-multithread-copy-false-134217729-2: multi-thread copy: chunk 2/3 (67108864-134217728) size 64Mi starting 2025/04/14 01:22:29 DEBUG : Couldn't parse Date: from server edef7.pcloud.com: "Mon, 14 Apr 2025 01:22:29 +0000": parsing time "Mon, 14 Apr 2025 01:22:29 +0000" as "Mon Jan _2 15:04:05 2006": cannot parse ", 14 Apr 2025 01:22:29 +0000" as " " 2025/04/14 01:22:29 DEBUG : test-multithread-copy-false-134217729-2: writing chunk 2 2025/04/14 01:22:29 DEBUG : test-multithread-copy-false-134217729-2: multi-thread copy: chunk 3/3 (134217728-134217729) size 1 finished 2025/04/14 01:22:29 DEBUG : Couldn't parse Date: from server edef7.pcloud.com: "Mon, 14 Apr 2025 01:22:29 +0000": parsing time "Mon, 14 Apr 2025 01:22:29 +0000" as "Mon Jan _2 15:04:05 2006": cannot parse ", 14 Apr 2025 01:22:29 +0000" as " " 2025/04/14 01:22:29 DEBUG : test-multithread-copy-false-134217729-2: writing chunk 0 2025/04/14 01:22:29 DEBUG : Couldn't parse Date: from server edef7.pcloud.com: "Mon, 14 Apr 2025 01:22:29 +0000": parsing time "Mon, 14 Apr 2025 01:22:29 +0000" as "Mon Jan _2 15:04:05 2006": cannot parse ", 14 Apr 2025 01:22:29 +0000" as " " 2025/04/14 01:22:29 DEBUG : test-multithread-copy-false-134217729-2: writing chunk 1 2025/04/14 01:22:31 DEBUG : test-multithread-copy-false-134217729-2: multi-thread copy: chunk 2/3 (67108864-134217728) size 64Mi finished 2025/04/14 01:22:31 DEBUG : test-multithread-copy-false-134217729-2: multi-thread copy: chunk 1/3 (0-67108864) size 64Mi finished 2025/04/14 01:22:31 DEBUG : test-multithread-copy-false-134217729-2: Finished multi-thread copy with 3 parts of size 64Mi === RUN TestMultithreadCopy/upload=true,size=134217727,streams=2 2025/04/14 01:22:50 DEBUG : test-multithread-copy-true-134217727-2: multi-thread copy: disabling buffering because destination uses OpenWriterAt multithread_test.go:220: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/multithread_test.go:220 Error: Received unexpected error: multi-thread copy: failed to open chunk writer: open file: open new file descriptor: pcloud error: Access denied. You do not have permissions to perform this operation. (2003) Test: TestMultithreadCopy/upload=true,size=134217727,streams=2 === RUN TestMultithreadCopy/upload=true,size=134217728,streams=2 fstest.go:292: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:292: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:292: Sleeping for 4s for list eventual consistency: 3/3 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:334 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/multithread_test.go:189 Error: Should be true Test: TestMultithreadCopy/upload=true,size=134217728,streams=2 Messages: listing wrong, want test-multithread-copy-true-134217728-2 (134217728) got test-multithread-copy-true-134217727-2 (134217727), test-multithread-copy-true-134217728-2 (134217728) 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:334 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/multithread_test.go:189 Error: Should be true Test: TestMultithreadCopy/upload=true,size=134217728,streams=2 Messages: Unexpected file "test-multithread-copy-true-134217727-2" 2025/04/14 01:23:11 DEBUG : test-multithread-copy-true-134217728-2: multi-thread copy: disabling buffering because destination uses OpenWriterAt multithread_test.go:220: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/multithread_test.go:220 Error: Received unexpected error: multi-thread copy: failed to open chunk writer: open file: open new file descriptor: pcloud error: Access denied. You do not have permissions to perform this operation. (2003) Test: TestMultithreadCopy/upload=true,size=134217728,streams=2 === RUN TestMultithreadCopy/upload=true,size=134217729,streams=2 fstest.go:292: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:292: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:292: Sleeping for 4s for list eventual consistency: 3/3 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:334 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/multithread_test.go:189 Error: Should be true Test: TestMultithreadCopy/upload=true,size=134217729,streams=2 Messages: listing wrong, want test-multithread-copy-true-134217729-2 (134217729) got test-multithread-copy-true-134217727-2 (134217727), test-multithread-copy-true-134217728-2 (134217728), test-multithread-copy-true-134217729-2 (134217729) 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:334 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/multithread_test.go:189 Error: Should be true Test: TestMultithreadCopy/upload=true,size=134217729,streams=2 Messages: Unexpected file "test-multithread-copy-true-134217727-2" 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:334 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/multithread_test.go:189 Error: Should be true Test: TestMultithreadCopy/upload=true,size=134217729,streams=2 Messages: Unexpected file "test-multithread-copy-true-134217728-2" 2025/04/14 01:23:35 DEBUG : test-multithread-copy-true-134217729-2: multi-thread copy: disabling buffering because destination uses OpenWriterAt multithread_test.go:220: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/multithread_test.go:220 Error: Received unexpected error: multi-thread copy: failed to open chunk writer: open file: open new file descriptor: pcloud error: Access denied. You do not have permissions to perform this operation. (2003) Test: TestMultithreadCopy/upload=true,size=134217729,streams=2 --- FAIL: TestMultithreadCopy (269.62s) --- PASS: TestMultithreadCopy/upload=false,size=134217728,streams=2 (105.81s) --- PASS: TestMultithreadCopy/upload=false,size=134217729,streams=2 (106.35s) --- FAIL: TestMultithreadCopy/upload=true,size=134217727,streams=2 (12.39s) --- FAIL: TestMultithreadCopy/upload=true,size=134217728,streams=2 (20.66s) --- FAIL: TestMultithreadCopy/upload=true,size=134217729,streams=2 (24.24s) === RUN TestMultithreadCopyAbort run.go:180: Remote "pcloud root 'rclone-test-yoqazey8necu'", Local "Local file system at /tmp/rclone1460511690", Modify Window "1s" 2025/04/14 01:23:50 DEBUG : test-multithread-abort: multi-thread copy: disabling buffering because destination uses OpenWriterAt 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 --- PASS: TestMultithreadCopyAbort (23.07s) === RUN TestCheck run.go:180: Remote "pcloud root 'rclone-test-yoqazey8necu'", Local "Local file system at /tmp/rclone1460511690", Modify Window "1s" === RUN TestCheck/1 === RUN TestCheck/2 === RUN TestCheck/3 === RUN TestCheck/4 === RUN TestCheck/5 === RUN TestCheck/6 --- PASS: TestCheck (3.15s) --- PASS: TestCheck/1 (0.17s) --- PASS: TestCheck/2 (0.17s) --- PASS: TestCheck/3 (0.17s) --- PASS: TestCheck/4 (0.18s) --- PASS: TestCheck/5 (0.18s) --- PASS: TestCheck/6 (0.18s) === RUN TestCheckSizeOnly run.go:180: Remote "pcloud root 'rclone-test-yoqazey8necu'", Local "Local file system at /tmp/rclone1460511690", Modify Window "1s" === RUN TestCheckSizeOnly/3 === RUN TestCheckSizeOnly/5 === NAME TestCheckSizeOnly 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 === RUN TestCheckSizeOnly/6 === RUN TestCheckSizeOnly/7 --- PASS: TestCheckSizeOnly (13.09s) --- PASS: TestCheckSizeOnly/3 (0.08s) --- PASS: TestCheckSizeOnly/5 (0.08s) --- PASS: TestCheckSizeOnly/6 (0.08s) --- PASS: TestCheckSizeOnly/7 (0.08s) === RUN TestCopyFileCopyDest run.go:180: Remote "pcloud root 'rclone-test-yoqazey8necu'", Local "Local file system at /tmp/rclone1460511690", Modify Window "1s" 2025/04/14 01:24:14 DEBUG : Creating backend with remote "TestPcloud:rclone-test-yoqazey8necu/dst" 2025/04/14 01:24:14 DEBUG : Config file has changed externally - reloading 2025/04/14 01:24:16 DEBUG : Creating backend with remote "TestPcloud:rclone-test-yoqazey8necu/CopyDest" 2025/04/14 01:24:17 DEBUG : one: Need to transfer - File not found at Destination 2025/04/14 01:24:17 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2025/04/14 01:24:17 DEBUG : one.b824e1ab.partial: renamed to: one 2025/04/14 01:24:17 INFO : one: Copied (new) 2025/04/14 01:24:18 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/04/14 01:24:18 DEBUG : one: sha1 = 7f376fd65843a1267de8f986d40e164e5a321c13 OK 2025/04/14 01:24:18 DEBUG : one.406dcb2c.partial: renamed to: one 2025/04/14 01:24:18 INFO : one: Copied (replaced existing) 2025/04/14 01:24:19 DEBUG : Creating backend with remote "TestPcloud:rclone-test-yoqazey8necu/BackupDir" 2025/04/14 01:24:21 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/04/14 01:24:21 DEBUG : one: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2025/04/14 01:24:21 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/04/14 01:24:22 INFO : one: Moved (server-side) 2025/04/14 01:24:22 DEBUG : one: sha1 = 7f376fd65843a1267de8f986d40e164e5a321c13 OK 2025/04/14 01:24:22 DEBUG : one.d88fe5df.partial: renamed to: one 2025/04/14 01:24:22 INFO : one: Copied (server-side copy) 2025/04/14 01:24:22 DEBUG : one: Destination found in --copy-dest, using server-side copy 2025/04/14 01:24:23 DEBUG : two: Need to transfer - File not found at Destination 2025/04/14 01:24:23 DEBUG : two: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2025/04/14 01:24:23 DEBUG : two: sha1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b OK 2025/04/14 01:24:24 DEBUG : two.19d5dbaa.partial: renamed to: two 2025/04/14 01:24:24 INFO : two: Copied (server-side copy) 2025/04/14 01:24:24 DEBUG : two: Destination found in --copy-dest, using server-side copy 2025/04/14 01:24:24 DEBUG : two: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2025/04/14 01:24:24 DEBUG : two: Unchanged skipping 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/operations/copy_test.go:347 Error: Should be true Test: TestCopyFileCopyDest Messages: listing wrong, want BackupDir/one (3), CopyDest/one (5), CopyDest/two (3), dst/one (5), dst/two (3) got test-multithread-abort (100) 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/operations/copy_test.go:347 Error: Should be true Test: TestCopyFileCopyDest Messages: Unexpected file "test-multithread-abort" 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/operations/copy_test.go:347 Error: Not equal: expected: 0 actual : 5 Test: TestCopyFileCopyDest Messages: 5 objects not found 2025/04/14 01:24:33 DEBUG : three: Need to transfer - File not found at Destination 2025/04/14 01:24:33 DEBUG : three: Sizes differ (src 7 vs dst 5) 2025/04/14 01:24:33 DEBUG : three: Destination not found in --copy-dest 2025/04/14 01:24:33 DEBUG : three: sha1 = 370e613335f3cf2f19b2a2d35a86402f55c659f8 OK 2025/04/14 01:24:33 DEBUG : three.8cc66b1.partial: renamed to: three 2025/04/14 01:24:33 INFO : three: Copied (new) --- FAIL: TestCopyFileCopyDest (20.93s) === RUN TestListDirSortedFn run.go:180: Remote "pcloud root 'rclone-test-yoqazey8necu'", Local "Local file system at /tmp/rclone1460511690", Modify Window "1s" 2025/04/14 01:24:39 DEBUG : a.txt: Excluded (Size Filter) 2025/04/14 01:24:39 DEBUG : a.txt: Excluded 2025/04/14 01:24:39 DEBUG : sub dir/hello world: Excluded (Size Filter) 2025/04/14 01:24:39 DEBUG : sub dir/hello world: Excluded 2025/04/14 01:24:39 DEBUG : sub dir/hello world2: Excluded (Size Filter) 2025/04/14 01:24:39 DEBUG : sub dir/hello world2: Excluded 2025/04/14 01:24:40 DEBUG : sub dir/ignore dir: Excluded 2025/04/14 01:24:40 DEBUG : sub dir/hello world: Excluded (Size Filter) 2025/04/14 01:24:40 DEBUG : sub dir/hello world: Excluded 2025/04/14 01:24:40 DEBUG : sub dir/hello world2: Excluded (Size Filter) 2025/04/14 01:24:40 DEBUG : sub dir/hello world2: Excluded 2025/04/14 01:24:40 DEBUG : sub dir/ignore dir: Excluded --- PASS: TestListDirSortedFn (6.09s) === RUN TestListJSON run.go:180: Remote "pcloud root 'rclone-test-yoqazey8necu'", Local "Local file system at /tmp/rclone1460511690", Modify Window "1s" === RUN TestListJSON/Default === RUN TestListJSON/FilesOnly === RUN TestListJSON/DirsOnly === RUN TestListJSON/Recurse === RUN TestListJSON/SubDir === RUN TestListJSON/NoModTime === RUN TestListJSON/NoMimeType === RUN TestListJSON/ShowHash === RUN TestListJSON/HashTypes === RUN TestListJSON/Metadata --- PASS: TestListJSON (2.54s) --- PASS: TestListJSON/Default (0.08s) --- PASS: TestListJSON/FilesOnly (0.08s) --- PASS: TestListJSON/DirsOnly (0.08s) --- PASS: TestListJSON/Recurse (0.09s) --- PASS: TestListJSON/SubDir (0.08s) --- PASS: TestListJSON/NoModTime (0.09s) --- PASS: TestListJSON/NoMimeType (0.08s) --- PASS: TestListJSON/ShowHash (0.16s) --- PASS: TestListJSON/HashTypes (0.16s) --- PASS: TestListJSON/Metadata (0.08s) === RUN TestStatJSON run.go:180: Remote "pcloud root 'rclone-test-yoqazey8necu'", Local "Local file system at /tmp/rclone1460511690", Modify Window "1s" === RUN TestStatJSON/Root === RUN TestStatJSON/RootFilesOnly === RUN TestStatJSON/RootDirsOnly === RUN TestStatJSON/Dir === RUN TestStatJSON/DirWithTrailingSlash === RUN TestStatJSON/File === RUN TestStatJSON/NotFound === RUN TestStatJSON/DirFilesOnly === RUN TestStatJSON/FileFilesOnly === RUN TestStatJSON/NotFoundFilesOnly === RUN TestStatJSON/DirDirsOnly === RUN TestStatJSON/FileDirsOnly === RUN TestStatJSON/NotFoundDirsOnly === RUN TestStatJSON/RootNotFound 2025/04/14 01:24:46 DEBUG : Creating backend with remote "TestPcloud:rclone-test-yoqazey8necu/notfound" --- PASS: TestStatJSON (3.33s) --- PASS: TestStatJSON/Root (0.08s) --- PASS: TestStatJSON/RootFilesOnly (0.00s) --- PASS: TestStatJSON/RootDirsOnly (0.08s) --- PASS: TestStatJSON/Dir (0.16s) --- PASS: TestStatJSON/DirWithTrailingSlash (0.08s) --- PASS: TestStatJSON/File (0.08s) --- PASS: TestStatJSON/NotFound (0.16s) --- PASS: TestStatJSON/DirFilesOnly (0.08s) --- PASS: TestStatJSON/FileFilesOnly (0.08s) --- PASS: TestStatJSON/NotFoundFilesOnly (0.08s) --- PASS: TestStatJSON/DirDirsOnly (0.08s) --- PASS: TestStatJSON/FileDirsOnly (0.08s) --- PASS: TestStatJSON/NotFoundDirsOnly (0.08s) --- PASS: TestStatJSON/RootNotFound (1.19s) === RUN TestLsd run.go:180: Remote "pcloud root 'rclone-test-yoqazey8necu'", Local "Local file system at /tmp/rclone1460511690", Modify Window "1s" --- PASS: TestLsd (0.82s) === RUN TestLs run.go:180: Remote "pcloud root 'rclone-test-yoqazey8necu'", Local "Local file system at /tmp/rclone1460511690", Modify Window "1s" --- PASS: TestLs (1.15s) === RUN TestLsWithFilesFrom run.go:180: Remote "pcloud root 'rclone-test-yoqazey8necu'", Local "Local file system at /tmp/rclone1460511690", Modify Window "1s" 2025/04/14 01:24:50 DEBUG : empty space: Excluded (FilesFrom Filter) 2025/04/14 01:24:50 DEBUG : empty space: Excluded --- PASS: TestLsWithFilesFrom (0.99s) === RUN TestLsLong run.go:180: Remote "pcloud root 'rclone-test-yoqazey8necu'", Local "Local file system at /tmp/rclone1460511690", Modify Window "1s" --- PASS: TestLsLong (0.89s) === RUN TestCat run.go:180: Remote "pcloud root 'rclone-test-yoqazey8necu'", Local "Local file system at /tmp/rclone1460511690", Modify Window "1s" 2025/04/14 01:24:52 DEBUG : Couldn't parse Date: from server edef3.pcloud.com: "Mon, 14 Apr 2025 01:24:52 +0000": parsing time "Mon, 14 Apr 2025 01:24:52 +0000" as "Mon Jan _2 15:04:05 2006": cannot parse ", 14 Apr 2025 01:24:52 +0000" as " " 2025/04/14 01:24:52 DEBUG : Couldn't parse Date: from server edef5.pcloud.com: "Mon, 14 Apr 2025 01:24:52 +0000": parsing time "Mon, 14 Apr 2025 01:24:52 +0000" as "Mon Jan _2 15:04:05 2006": cannot parse ", 14 Apr 2025 01:24:52 +0000" as " " 2025/04/14 01:24:52 DEBUG : Couldn't parse Date: from server edef3.pcloud.com: "Mon, 14 Apr 2025 01:24:52 +0000": parsing time "Mon, 14 Apr 2025 01:24:52 +0000" as "Mon Jan _2 15:04:05 2006": cannot parse ", 14 Apr 2025 01:24:52 +0000" as " " 2025/04/14 01:24:52 DEBUG : Couldn't parse Date: from server edef5.pcloud.com: "Mon, 14 Apr 2025 01:24:52 +0000": parsing time "Mon, 14 Apr 2025 01:24:52 +0000" as "Mon Jan _2 15:04:05 2006": cannot parse ", 14 Apr 2025 01:24:52 +0000" as " " 2025/04/14 01:24:53 DEBUG : Couldn't parse Date: from server edef3.pcloud.com: "Mon, 14 Apr 2025 01:24:53 +0000": parsing time "Mon, 14 Apr 2025 01:24:53 +0000" as "Mon Jan _2 15:04:05 2006": cannot parse ", 14 Apr 2025 01:24:53 +0000" as " " 2025/04/14 01:24:53 DEBUG : Couldn't parse Date: from server edef5.pcloud.com: "Mon, 14 Apr 2025 01:24:53 +0000": parsing time "Mon, 14 Apr 2025 01:24:53 +0000" as "Mon Jan _2 15:04:05 2006": cannot parse ", 14 Apr 2025 01:24:53 +0000" as " " 2025/04/14 01:24:53 DEBUG : Couldn't parse Date: from server edef3.pcloud.com: "Mon, 14 Apr 2025 01:24:53 +0000": parsing time "Mon, 14 Apr 2025 01:24:53 +0000" as "Mon Jan _2 15:04:05 2006": cannot parse ", 14 Apr 2025 01:24:53 +0000" as " " 2025/04/14 01:24:53 DEBUG : Couldn't parse Date: from server edef5.pcloud.com: "Mon, 14 Apr 2025 01:24:53 +0000": parsing time "Mon, 14 Apr 2025 01:24:53 +0000" as "Mon Jan _2 15:04:05 2006": cannot parse ", 14 Apr 2025 01:24:53 +0000" as " " 2025/04/14 01:24:53 DEBUG : Couldn't parse Date: from server edef3.pcloud.com: "Mon, 14 Apr 2025 01:24:53 +0000": parsing time "Mon, 14 Apr 2025 01:24:53 +0000" as "Mon Jan _2 15:04:05 2006": cannot parse ", 14 Apr 2025 01:24:53 +0000" as " " 2025/04/14 01:24:54 DEBUG : Couldn't parse Date: from server edef5.pcloud.com: "Mon, 14 Apr 2025 01:24:53 +0000": parsing time "Mon, 14 Apr 2025 01:24:53 +0000" as "Mon Jan _2 15:04:05 2006": cannot parse ", 14 Apr 2025 01:24:53 +0000" as " " --- PASS: TestCat (2.86s) === RUN TestRmdirsNoLeaveRoot run.go:180: Remote "pcloud root 'rclone-test-yoqazey8necu'", Local "Local file system at /tmp/rclone1460511690", Modify Window "1s" 2025/04/14 01:24:55 INFO : A2: Making directory 2025/04/14 01:24:55 INFO : A1/B2: Making directory 2025/04/14 01:24:55 INFO : A1/B2/C2: Making directory 2025/04/14 01:24:55 INFO : A1/B1/C3: Making directory 2025/04/14 01:24:56 INFO : A3: Making directory 2025/04/14 01:24:56 INFO : A3/B3: Making directory 2025/04/14 01:24:56 INFO : A3/B3/C4: Making directory 2025/04/14 01:24:57 DEBUG : removing 1 level 3 directories 2025/04/14 01:24:57 INFO : A3/B3/C4: Removing directory 2025/04/14 01:24:57 DEBUG : removing 2 level 3 directories 2025/04/14 01:24:57 INFO : A1/B2/C2: Removing directory 2025/04/14 01:24:57 INFO : A1/B1/C3: Removing directory 2025/04/14 01:24:57 DEBUG : removing 2 level 2 directories 2025/04/14 01:24:57 INFO : A3/B3: Removing directory 2025/04/14 01:24:57 INFO : A1/B2: Removing directory 2025/04/14 01:24:58 DEBUG : removing 2 level 1 directories 2025/04/14 01:24:58 INFO : A3: Removing directory 2025/04/14 01:24:58 INFO : A2: Removing directory 2025/04/14 01:24:59 DEBUG : removing 1 level 3 directories 2025/04/14 01:24:59 INFO : A1/B1/C1: Removing directory 2025/04/14 01:24:59 DEBUG : removing 1 level 2 directories 2025/04/14 01:24:59 INFO : A1/B1: Removing directory 2025/04/14 01:24:59 DEBUG : removing 1 level 1 directories 2025/04/14 01:24:59 INFO : A1: Removing directory 2025/04/14 01:24:59 DEBUG : removing 1 level 0 directories 2025/04/14 01:24:59 INFO : pcloud root 'rclone-test-yoqazey8necu': Removing directory --- PASS: TestRmdirsNoLeaveRoot (6.08s) === RUN TestRmdirsLeaveRoot run.go:180: Remote "pcloud root 'rclone-test-yoqazey8necu'", Local "Local file system at /tmp/rclone1460511690", Modify Window "1s" 2025/04/14 01:25:00 INFO : A1: Making directory 2025/04/14 01:25:01 INFO : A1/B1: Making directory 2025/04/14 01:25:01 INFO : A1/B1/C1: Making directory 2025/04/14 01:25:01 DEBUG : removing 1 level 2 directories 2025/04/14 01:25:01 INFO : A1/B1: Removing directory 2025/04/14 01:25:01 ERROR : A1/B1: Failed to rmdir: rmdir failed: pcloud error: Folder is not empty. (2006) operations_test.go:762: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:762 Error: Received unexpected error: failed to remove directories: rmdir failed: pcloud error: Folder is not empty. (2006) Test: TestRmdirsLeaveRoot --- FAIL: TestRmdirsLeaveRoot (1.67s) === RUN TestRmdirsWithFilter run.go:180: Remote "pcloud root 'rclone-test-yoqazey8necu'", Local "Local file system at /tmp/rclone1460511690", Modify Window "1s" 2025/04/14 01:25:02 INFO : A1: Making directory 2025/04/14 01:25:03 INFO : A1/B1: Making directory 2025/04/14 01:25:03 INFO : A1/B1/C1: Making directory 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/fs/operations/operations_test.go:789 Error: Should be true Test: TestRmdirsWithFilter Messages: listing wrong, want got a.txt (11), sub dir/hello world (11), sub dir/hello world2 (11), sub dir/ignore dir/.ignore (1), sub dir/ignore dir/should be ignored (9), zend.txt (5) 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/fs/operations/operations_test.go:789 Error: Should be true Test: TestRmdirsWithFilter Messages: Unexpected file "sub dir/ignore dir/.ignore" 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/fs/operations/operations_test.go:789 Error: Should be true Test: TestRmdirsWithFilter Messages: Unexpected file "sub dir/ignore dir/should be ignored" 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/fs/operations/operations_test.go:789 Error: Should be true Test: TestRmdirsWithFilter Messages: Unexpected file "sub dir/hello world" 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/fs/operations/operations_test.go:789 Error: Should be true Test: TestRmdirsWithFilter Messages: Unexpected file "sub dir/hello world2" 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/fs/operations/operations_test.go:789 Error: Should be true Test: TestRmdirsWithFilter Messages: Unexpected file "a.txt" 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/fs/operations/operations_test.go:789 Error: Should be true Test: TestRmdirsWithFilter Messages: Unexpected file "zend.txt" fstest.go:321: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:321 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:789 Error: Not equal: expected: []string{"A1", "A1/B1", "A1/B1/C1"} actual : []string{"sub dir", "sub dir/ignore dir"} Diff: --- Expected +++ Actual @@ -1,5 +1,4 @@ -([]string) (len=3) { - (string) (len=2) "A1", - (string) (len=5) "A1/B1", - (string) (len=8) "A1/B1/C1" +([]string) (len=2) { + (string) (len=7) "sub dir", + (string) (len=18) "sub dir/ignore dir" } Test: TestRmdirsWithFilter Messages: directories 2025/04/14 01:25:12 DEBUG : sub dir: Excluded 2025/04/14 01:25:12 DEBUG : a.txt: Excluded (Path Filter) 2025/04/14 01:25:12 DEBUG : a.txt: Excluded 2025/04/14 01:25:12 DEBUG : zend.txt: Excluded (Path Filter) 2025/04/14 01:25:12 DEBUG : zend.txt: Excluded 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:321: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:321 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:803 Error: Not equal: expected: []string{"A1"} actual : []string{"sub dir", "sub dir/ignore dir"} Diff: --- Expected +++ Actual @@ -1,3 +1,4 @@ -([]string) (len=1) { - (string) (len=2) "A1" +([]string) (len=2) { + (string) (len=7) "sub dir", + (string) (len=18) "sub dir/ignore dir" } Test: TestRmdirsWithFilter Messages: directories run.go:125: removing file "CopyDest/one" failed - try 1/3: pcloud error: File not found. (2009) run.go:125: removing file "CopyDest/one" failed - try 2/3: pcloud error: File not found. (2009) run.go:125: removing file "CopyDest/one" failed - try 3/3: pcloud error: File not found. (2009) run.go:128: removing file "CopyDest/one" failed: pcloud error: File not found. (2009) run.go:125: removing file "CopyDest/two" failed - try 1/3: pcloud error: File not found. (2009) run.go:125: removing file "CopyDest/two" failed - try 2/3: pcloud error: File not found. (2009) run.go:125: removing file "CopyDest/two" failed - try 3/3: pcloud error: File not found. (2009) run.go:128: removing file "CopyDest/two" failed: pcloud error: File not found. (2009) run.go:125: removing file "dst/one" failed - try 1/3: pcloud error: File not found. (2009) run.go:125: removing file "dst/one" failed - try 2/3: pcloud error: File not found. (2009) run.go:125: removing file "dst/one" failed - try 3/3: pcloud error: File not found. (2009) run.go:128: removing file "dst/one" failed: pcloud error: File not found. (2009) run.go:125: removing file "dst/two" failed - try 1/3: pcloud error: File not found. (2009) run.go:125: removing file "dst/two" failed - try 2/3: pcloud error: File not found. (2009) run.go:125: removing file "dst/two" failed - try 3/3: pcloud error: File not found. (2009) run.go:128: removing file "dst/two" failed: pcloud error: File not found. (2009) run.go:125: removing dir "dst" failed - try 1/3: rmdir failed: pcloud error: Directory does not exist. (2005) run.go:125: removing dir "dst" failed - try 2/3: rmdir failed: pcloud error: Directory does not exist. (2005) run.go:125: removing dir "dst" failed - try 3/3: rmdir failed: pcloud error: Directory does not exist. (2005) run.go:128: removing dir "dst" failed: rmdir failed: pcloud error: Directory does not exist. (2005) run.go:125: removing dir "CopyDest" failed - try 1/3: rmdir failed: pcloud error: Directory does not exist. (2005) run.go:125: removing dir "CopyDest" failed - try 2/3: rmdir failed: pcloud error: Directory does not exist. (2005) run.go:125: removing dir "CopyDest" failed - try 3/3: rmdir failed: pcloud error: Directory does not exist. (2005) run.go:128: removing dir "CopyDest" failed: rmdir failed: pcloud error: Directory does not exist. (2005) run.go:125: removing dir "BackupDir" failed - try 1/3: rmdir failed: pcloud error: Directory does not exist. (2005) run.go:125: removing dir "BackupDir" failed - try 2/3: rmdir failed: pcloud error: Directory does not exist. (2005) run.go:125: removing dir "BackupDir" failed - try 3/3: rmdir failed: pcloud error: Directory does not exist. (2005) run.go:128: removing dir "BackupDir" failed: rmdir failed: pcloud error: Directory does not exist. (2005) fstest.go:276: Error listing: couldn't list files: pcloud error: Directory does not exist. (2005) --- FAIL: TestRmdirsWithFilter (41.20s) === RUN TestCopyURL run.go:180: Remote "pcloud root 'rclone-test-yoqazey8necu'", Local "Local file system at /tmp/rclone1460511690", Modify Window "1s" fstest.go:276: Error listing: couldn't list files: pcloud error: Directory does not exist. (2005) run.go:164: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:164 /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 /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/runtime/panic.go:629 /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/testing/testing.go:1006 /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/testing/testing.go:1090 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:276 /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/operations/operations_test.go:823 Error: Received unexpected error: couldn't list files: pcloud error: Directory does not exist. (2005) Test: TestCopyURL --- FAIL: TestCopyURL (0.17s) === RUN TestMoveFile run.go:180: Remote "pcloud root 'rclone-test-yoqazey8necu'", Local "Local file system at /tmp/rclone1460511690", Modify Window "1s" fstest.go:292: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:292: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:292: Sleeping for 4s for list eventual consistency: 3/3 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:334 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:934 Error: Should be true Test: TestMoveFile Messages: listing wrong, want file1 (14) got file1 (14), file2 (14) 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:334 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:934 Error: Should be true Test: TestMoveFile Messages: Unexpected file "file2" operations_test.go:940: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:940 Error: Received unexpected error: couldn't list files: pcloud error: Directory does not exist. (2005) Test: TestMoveFile run.go:164: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:164 /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 /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/runtime/panic.go:629 /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/testing/testing.go:1006 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:940 Error: Received unexpected error: couldn't list files: pcloud error: Directory does not exist. (2005) Test: TestMoveFile --- FAIL: TestMoveFile (7.17s) === RUN TestTouchDir run.go:180: Remote "pcloud root 'rclone-test-yoqazey8necu'", Local "Local file system at /tmp/rclone1460511690", Modify Window "1s" 2025/04/14 01:30:50 DEBUG : pacer: low level retry 1/1 (error Put "https://eapi.pcloud.com/uploadfile?filename=potato2&folderid=16356764621&mtime=981173106&nopartial=1": net/http: timeout awaiting response headers) 2025/04/14 01:30:50 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2025/04/14 01:30:50 DEBUG : pacer: Reducing sleep to 15ms 2025/04/14 01:30:51 DEBUG : pacer: Reducing sleep to 11.25ms 2025/04/14 01:30:53 DEBUG : pacer: Reducing sleep to 10ms run.go:281: Retry Put of "potato2" to pcloud root 'rclone-test-yoqazey8necu': 1/10 (Put "https://eapi.pcloud.com/uploadfile?filename=potato2&folderid=16356764621&mtime=981173106&nopartial=1": net/http: timeout awaiting response headers) 2025/04/14 01:35:58 DEBUG : pacer: low level retry 1/1 (error Put "https://eapi.pcloud.com/uploadfile?filename=potato2&folderid=16356764621&mtime=981173106&nopartial=1": net/http: timeout awaiting response headers) 2025/04/14 01:35:58 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2025/04/14 01:35:58 DEBUG : pacer: Reducing sleep to 15ms 2025/04/14 01:35:59 DEBUG : pacer: Reducing sleep to 11.25ms 2025/04/14 01:36:00 DEBUG : pacer: Reducing sleep to 10ms run.go:281: Retry Put of "potato2" to pcloud root 'rclone-test-yoqazey8necu': 2/10 (Put "https://eapi.pcloud.com/uploadfile?filename=potato2&folderid=16356764621&mtime=981173106&nopartial=1": net/http: timeout awaiting response headers) 2025/04/14 01:41:05 DEBUG : pacer: low level retry 1/1 (error Put "https://eapi.pcloud.com/uploadfile?filename=potato2&folderid=16356764621&mtime=981173106&nopartial=1": net/http: timeout awaiting response headers) 2025/04/14 01:41:05 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2025/04/14 01:41:05 DEBUG : pacer: Reducing sleep to 15ms 2025/04/14 01:41:06 DEBUG : pacer: Reducing sleep to 11.25ms 2025/04/14 01:41:07 DEBUG : pacer: Reducing sleep to 10ms run.go:281: Retry Put of "potato2" to pcloud root 'rclone-test-yoqazey8necu': 3/10 (Put "https://eapi.pcloud.com/uploadfile?filename=potato2&folderid=16356764621&mtime=981173106&nopartial=1": net/http: timeout awaiting response headers) 2025/04/14 01:46:13 DEBUG : pacer: low level retry 1/1 (error Put "https://eapi.pcloud.com/uploadfile?filename=potato2&folderid=16356764621&mtime=981173106&nopartial=1": net/http: timeout awaiting response headers) 2025/04/14 01:46:13 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2025/04/14 01:46:13 DEBUG : pacer: Reducing sleep to 15ms 2025/04/14 01:46:14 DEBUG : pacer: Reducing sleep to 11.25ms 2025/04/14 01:46:15 DEBUG : pacer: Reducing sleep to 10ms run.go:281: Retry Put of "potato2" to pcloud root 'rclone-test-yoqazey8necu': 4/10 (Put "https://eapi.pcloud.com/uploadfile?filename=potato2&folderid=16356764621&mtime=981173106&nopartial=1": net/http: timeout awaiting response headers) 2025/04/14 01:51:20 DEBUG : pacer: low level retry 1/1 (error Put "https://eapi.pcloud.com/uploadfile?filename=potato2&folderid=16356764621&mtime=981173106&nopartial=1": net/http: timeout awaiting response headers) 2025/04/14 01:51:20 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2025/04/14 01:51:21 DEBUG : pacer: Reducing sleep to 15ms 2025/04/14 01:51:22 DEBUG : pacer: Reducing sleep to 11.25ms 2025/04/14 01:51:23 DEBUG : pacer: Reducing sleep to 10ms run.go:281: Retry Put of "potato2" to pcloud root 'rclone-test-yoqazey8necu': 5/10 (Put "https://eapi.pcloud.com/uploadfile?filename=potato2&folderid=16356764621&mtime=981173106&nopartial=1": net/http: timeout awaiting response headers) 2025/04/14 01:56:28 DEBUG : pacer: low level retry 1/1 (error Put "https://eapi.pcloud.com/uploadfile?filename=potato2&folderid=16356764621&mtime=981173106&nopartial=1": net/http: timeout awaiting response headers) 2025/04/14 01:56:28 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2025/04/14 01:56:29 DEBUG : pacer: Reducing sleep to 15ms 2025/04/14 01:56:30 DEBUG : pacer: Reducing sleep to 11.25ms 2025/04/14 01:56:31 DEBUG : pacer: Reducing sleep to 10ms run.go:281: Retry Put of "potato2" to pcloud root 'rclone-test-yoqazey8necu': 6/10 (Put "https://eapi.pcloud.com/uploadfile?filename=potato2&folderid=16356764621&mtime=981173106&nopartial=1": net/http: timeout awaiting response headers) 2025/04/14 02:01:36 DEBUG : pacer: low level retry 1/1 (error Put "https://eapi.pcloud.com/uploadfile?filename=potato2&folderid=16356764621&mtime=981173106&nopartial=1": net/http: timeout awaiting response headers) 2025/04/14 02:01:36 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2025/04/14 02:01:36 DEBUG : pacer: Reducing sleep to 15ms 2025/04/14 02:01:37 DEBUG : pacer: Reducing sleep to 11.25ms 2025/04/14 02:01:38 DEBUG : pacer: Reducing sleep to 10ms run.go:281: Retry Put of "potato2" to pcloud root 'rclone-test-yoqazey8necu': 7/10 (Put "https://eapi.pcloud.com/uploadfile?filename=potato2&folderid=16356764621&mtime=981173106&nopartial=1": net/http: timeout awaiting response headers) 2025/04/14 02:06:44 DEBUG : pacer: low level retry 1/1 (error Put "https://eapi.pcloud.com/uploadfile?filename=potato2&folderid=16356764621&mtime=981173106&nopartial=1": net/http: timeout awaiting response headers) 2025/04/14 02:06:44 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2025/04/14 02:06:44 DEBUG : pacer: Reducing sleep to 15ms 2025/04/14 02:06:45 DEBUG : pacer: Reducing sleep to 11.25ms 2025/04/14 02:06:46 DEBUG : pacer: Reducing sleep to 10ms run.go:281: Retry Put of "potato2" to pcloud root 'rclone-test-yoqazey8necu': 8/10 (Put "https://eapi.pcloud.com/uploadfile?filename=potato2&folderid=16356764621&mtime=981173106&nopartial=1": net/http: timeout awaiting response headers) 2025/04/14 02:11:51 DEBUG : pacer: low level retry 1/1 (error Put "https://eapi.pcloud.com/uploadfile?filename=potato2&folderid=16356764621&mtime=981173106&nopartial=1": net/http: timeout awaiting response headers) 2025/04/14 02:11:51 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2025/04/14 02:11:51 DEBUG : pacer: Reducing sleep to 15ms 2025/04/14 02:11:52 DEBUG : pacer: Reducing sleep to 11.25ms 2025/04/14 02:11:53 DEBUG : pacer: Reducing sleep to 10ms run.go:281: Retry Put of "potato2" to pcloud root 'rclone-test-yoqazey8necu': 9/10 (Put "https://eapi.pcloud.com/uploadfile?filename=potato2&folderid=16356764621&mtime=981173106&nopartial=1": net/http: timeout awaiting response headers) 2025/04/14 02:16:59 DEBUG : pacer: low level retry 1/1 (error Put "https://eapi.pcloud.com/uploadfile?filename=potato2&folderid=16356764621&mtime=981173106&nopartial=1": net/http: timeout awaiting response headers) 2025/04/14 02:16:59 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2025/04/14 02:16:59 DEBUG : pacer: Reducing sleep to 15ms 2025/04/14 02:17:00 DEBUG : pacer: Reducing sleep to 11.25ms 2025/04/14 02:17:01 DEBUG : pacer: Reducing sleep to 10ms run.go:285: Failed to put "potato2" to "pcloud root 'rclone-test-yoqazey8necu'": Put "https://eapi.pcloud.com/uploadfile?filename=potato2&folderid=16356764621&mtime=981173106&nopartial=1": net/http: timeout awaiting response headers run.go:164: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:164 /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 /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/runtime/panic.go:629 /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/testing/testing.go:1006 /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/testing/testing.go:1090 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:285 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:292 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:303 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:1668 Error: Received unexpected error: couldn't list files: pcloud error: Directory does not exist. (2005) Test: TestTouchDir --- FAIL: TestTouchDir (3073.86s) === RUN TestRemoveExisting run.go:180: Remote "pcloud root 'rclone-test-yoqazey8necu'", Local "Local file system at /tmp/rclone1460511690", Modify Window "1s" run.go:285: Failed to put "sub dir/test remove existing" to "pcloud root 'rclone-test-yoqazey8necu'": couldn't list files: pcloud error: Directory does not exist. (2005) run.go:164: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:164 /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 /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/runtime/panic.go:629 /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/testing/testing.go:1006 /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/testing/testing.go:1090 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:285 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:292 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:1895 Error: Received unexpected error: couldn't list files: pcloud error: Directory does not exist. (2005) Test: TestRemoveExisting --- FAIL: TestRemoveExisting (0.16s) FAIL 2025/04/14 02:17:04 DEBUG : pcloud root 'rclone-test-yoqazey8necu': Purge remote 2025/04/14 02:17:04 NOTICE: purge failed: rmdir failed: pcloud error: Directory does not exist. (2005) "./operations.test -test.v -test.timeout 1h0m0s -remote TestPcloud: -verbose -test.run '^(TestCat|TestCopyFileCopyDest|TestCopyURL|TestListDirSortedFn|TestLs|TestLsLong|TestLsWithFilesFrom|TestLsd|TestMoveFile|TestMultithreadCopyAbort|TestRemoveExisting|TestRmdirsLeaveRoot|TestRmdirsNoLeaveRoot|TestRmdirsWithFilter|TestStatJSON|TestTouchDir)$|^TestCheck$/^(1|2|3|4|5|6)$|^TestCheckSizeOnly$/^(3|5|6|7)$|^TestListJSON$/^(Default|DirsOnly|FilesOnly|HashTypes|Metadata|NoMimeType|NoModTime|Recurse|ShowHash|SubDir)$|^TestMultithreadCopy$/^(upload=false,size=134217728,streams=2|upload=false,size=134217729,streams=2|upload=true,size=134217727,streams=2|upload=true,size=134217728,streams=2|upload=true,size=134217729,streams=2)$'" - Finished ERROR in 57m59.755622098s (try 2/5): exit status 1: Failed [TestMultithreadCopy/upload=true,size=134217727,streams=2 TestMultithreadCopy/upload=true,size=134217728,streams=2 TestMultithreadCopy/upload=true,size=134217729,streams=2 TestCopyFileCopyDest TestRmdirsLeaveRoot TestRmdirsWithFilter TestCopyURL TestMoveFile TestTouchDir TestRemoveExisting]