"./operations.test -test.v -test.timeout 2h0m0s -remote TestDrime: -verbose" - Starting (try 1/5) 2026/02/03 02:00:10 DEBUG : Creating backend with remote "TestDrime:rclone-test-yohamax0qoza" 2026/02/03 02:00:10 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/02/03 02:00:11 DEBUG : Creating backend with remote "/tmp/rclone1795778209" === RUN TestDoMultiThreadCopy --- PASS: TestDoMultiThreadCopy (0.00s) === RUN TestMultithreadCalculateNumChunks === RUN TestMultithreadCalculateNumChunks/{size:1_chunkSize:65536_wantNumChunks:1} === RUN TestMultithreadCalculateNumChunks/{size:1048576_chunkSize:1_wantNumChunks:1048576} === RUN TestMultithreadCalculateNumChunks/{size:1048576_chunkSize:2_wantNumChunks:524288} === RUN TestMultithreadCalculateNumChunks/{size:1048577_chunkSize:2_wantNumChunks:524289} === RUN TestMultithreadCalculateNumChunks/{size:1048575_chunkSize:2_wantNumChunks:524288} --- PASS: TestMultithreadCalculateNumChunks (0.00s) --- PASS: TestMultithreadCalculateNumChunks/{size:1_chunkSize:65536_wantNumChunks:1} (0.00s) --- PASS: TestMultithreadCalculateNumChunks/{size:1048576_chunkSize:1_wantNumChunks:1048576} (0.00s) --- PASS: TestMultithreadCalculateNumChunks/{size:1048576_chunkSize:2_wantNumChunks:524288} (0.00s) --- PASS: TestMultithreadCalculateNumChunks/{size:1048577_chunkSize:2_wantNumChunks:524289} (0.00s) --- PASS: TestMultithreadCalculateNumChunks/{size:1048575_chunkSize:2_wantNumChunks:524288} (0.00s) === RUN TestMultithreadCopy run.go:185: Remote "drime root 'rclone-test-yohamax0qoza'", Local "Local file system at /tmp/rclone1795778209", Modify Window "876000h0m0s" === RUN TestMultithreadCopy/upload=false,size=10485759,streams=2 fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/multithread_test.go:191 Error: Should be true Test: TestMultithreadCopy/upload=false,size=10485759,streams=2 Messages: listing wrong, want test-multithread-copy-false-10485759-2 (10485759) got fstest.go:203: Not found "test-multithread-copy-false-10485759-2" fstest.go:206: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:206 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:310 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/multithread_test.go:191 /usr/local/go/src/runtime/asm_amd64.s:1700 Error: Not equal: expected: 0 actual : 1 Test: TestMultithreadCopy/upload=false,size=10485759,streams=2 Messages: 1 objects not found 2026/02/03 02:00:26 DEBUG : test-multithread-copy-false-10485759-2: multi-thread copy: disabling buffering because destination uses OpenWriterAt 2026/02/03 02:00:26 DEBUG : test-multithread-copy-false-10485759-2: multi-thread copy: write buffer set to 131072 2026/02/03 02:00:26 DEBUG : test-multithread-copy-false-10485759-2: multi-thread copy: chunk size 64Mi was bigger than source file size 10.000Mi 2026/02/03 02:00:26 DEBUG : test-multithread-copy-false-10485759-2: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 2 2026/02/03 02:00:26 DEBUG : test-multithread-copy-false-10485759-2: multi-thread copy: number of streams 4 was bigger than number of chunks 1 2026/02/03 02:00:26 DEBUG : test-multithread-copy-false-10485759-2: Starting multi-thread copy with 1 chunks of size 10.000Mi with 1 parallel streams 2026/02/03 02:00:26 DEBUG : test-multithread-copy-false-10485759-2: multi-thread copy: chunk 1/1 (0-10485759) size 10.000Mi starting 2026/02/03 02:00:26 DEBUG : test-multithread-copy-false-10485759-2: writing chunk 0 2026/02/03 02:00:27 DEBUG : test-multithread-copy-false-10485759-2: multi-thread copy: chunk 1/1 (0-10485759) size 10.000Mi finished 2026/02/03 02:00:27 DEBUG : test-multithread-copy-false-10485759-2: Finished multi-thread copy with 1 parts of size 10.000Mi === RUN TestMultithreadCopy/upload=false,size=10485760,streams=2 fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/multithread_test.go:191 Error: Should be true Test: TestMultithreadCopy/upload=false,size=10485760,streams=2 Messages: listing wrong, want test-multithread-copy-false-10485760-2 (10485760) got test-multithread-copy-false-10485759-2 (10485759) fstest.go:191: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/multithread_test.go:191 /usr/local/go/src/runtime/asm_amd64.s:1700 Error: Should be true Test: TestMultithreadCopy/upload=false,size=10485760,streams=2 Messages: Unexpected file "test-multithread-copy-false-10485759-2" fstest.go:203: Not found "test-multithread-copy-false-10485760-2" fstest.go:206: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:206 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:310 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/multithread_test.go:191 /usr/local/go/src/runtime/asm_amd64.s:1700 Error: Not equal: expected: 0 actual : 1 Test: TestMultithreadCopy/upload=false,size=10485760,streams=2 Messages: 1 objects not found 2026/02/03 02:00:40 DEBUG : test-multithread-copy-false-10485760-2: multi-thread copy: disabling buffering because destination uses OpenWriterAt 2026/02/03 02:00:40 DEBUG : test-multithread-copy-false-10485760-2: multi-thread copy: write buffer set to 131072 2026/02/03 02:00:40 DEBUG : test-multithread-copy-false-10485760-2: multi-thread copy: chunk size 64Mi was bigger than source file size 10Mi 2026/02/03 02:00:40 DEBUG : test-multithread-copy-false-10485760-2: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 2 2026/02/03 02:00:40 DEBUG : test-multithread-copy-false-10485760-2: multi-thread copy: number of streams 4 was bigger than number of chunks 1 2026/02/03 02:00:40 DEBUG : test-multithread-copy-false-10485760-2: Starting multi-thread copy with 1 chunks of size 10Mi with 1 parallel streams 2026/02/03 02:00:40 DEBUG : test-multithread-copy-false-10485760-2: multi-thread copy: chunk 1/1 (0-10485760) size 10Mi starting 2026/02/03 02:00:41 DEBUG : test-multithread-copy-false-10485760-2: writing chunk 0 2026/02/03 02:00:41 DEBUG : test-multithread-copy-false-10485760-2: multi-thread copy: chunk 1/1 (0-10485760) size 10Mi finished 2026/02/03 02:00:41 DEBUG : test-multithread-copy-false-10485760-2: Finished multi-thread copy with 1 parts of size 10Mi === RUN TestMultithreadCopy/upload=false,size=10485761,streams=2 fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:292: Sleeping for 7s just to make sure 2026/02/03 02:00:58 DEBUG : test-multithread-copy-false-10485761-2: multi-thread copy: disabling buffering because destination uses OpenWriterAt 2026/02/03 02:00:58 DEBUG : test-multithread-copy-false-10485761-2: multi-thread copy: write buffer set to 131072 2026/02/03 02:00:58 DEBUG : test-multithread-copy-false-10485761-2: multi-thread copy: chunk size 64Mi was bigger than source file size 10.000Mi 2026/02/03 02:00:58 DEBUG : test-multithread-copy-false-10485761-2: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 2 2026/02/03 02:00:58 DEBUG : test-multithread-copy-false-10485761-2: multi-thread copy: number of streams 4 was bigger than number of chunks 1 2026/02/03 02:00:58 DEBUG : test-multithread-copy-false-10485761-2: Starting multi-thread copy with 1 chunks of size 10.000Mi with 1 parallel streams 2026/02/03 02:00:58 DEBUG : test-multithread-copy-false-10485761-2: multi-thread copy: chunk 1/1 (0-10485761) size 10.000Mi starting 2026/02/03 02:00:59 DEBUG : test-multithread-copy-false-10485761-2: writing chunk 0 2026/02/03 02:00:59 DEBUG : test-multithread-copy-false-10485761-2: multi-thread copy: chunk 1/1 (0-10485761) size 10.000Mi finished 2026/02/03 02:00:59 DEBUG : test-multithread-copy-false-10485761-2: Finished multi-thread copy with 1 parts of size 10.000Mi === RUN TestMultithreadCopy/upload=true,size=10485759,streams=2 2026/02/03 02:01:01 DEBUG : test-multithread-copy-true-10485759-2: multi-thread copy: disabling buffering because source is local disk 2026/02/03 02:01:02 DEBUG : test-multithread-copy-true-10485759-2: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 2 2026/02/03 02:01:02 DEBUG : test-multithread-copy-true-10485759-2: multi-thread copy: number of streams 4 was bigger than number of chunks 2 2026/02/03 02:01:02 DEBUG : test-multithread-copy-true-10485759-2: Starting multi-thread copy with 2 chunks of size 5Mi with 2 parallel streams 2026/02/03 02:01:02 DEBUG : test-multithread-copy-true-10485759-2: multi-thread copy: chunk 2/2 (5242880-10485759) size 5.000Mi starting 2026/02/03 02:01:02 DEBUG : test-multithread-copy-true-10485759-2: multi-thread copy: chunk 1/2 (0-5242880) size 5Mi starting 2026/02/03 02:01:03 DEBUG : test-multithread-copy-true-10485759-2: multi-thread copy: chunk 1/2 (0-5242880) size 5Mi finished 2026/02/03 02:01:03 DEBUG : test-multithread-copy-true-10485759-2: multi-thread copy: chunk 2/2 (5242880-10485759) size 5.000Mi finished multithread_test.go:218: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/multithread_test.go:218 Error: Received unexpected error: multi-thread copy: failed to find object after copy: object not found Test: TestMultithreadCopy/upload=true,size=10485759,streams=2 === RUN TestMultithreadCopy/upload=true,size=10485760,streams=2 fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:339 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/multithread_test.go:187 Error: Should be true Test: TestMultithreadCopy/upload=true,size=10485760,streams=2 Messages: listing wrong, want test-multithread-copy-true-10485760-2 (10485760) got test-multithread-copy-true-10485759-2 (10485759), test-multithread-copy-true-10485760-2 (10485760) fstest.go:191: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:339 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/multithread_test.go:187 /usr/local/go/src/runtime/asm_amd64.s:1700 Error: Should be true Test: TestMultithreadCopy/upload=true,size=10485760,streams=2 Messages: Unexpected file "test-multithread-copy-true-10485759-2" 2026/02/03 02:01:13 DEBUG : test-multithread-copy-true-10485760-2: multi-thread copy: disabling buffering because source is local disk 2026/02/03 02:01:14 DEBUG : test-multithread-copy-true-10485760-2: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 2 2026/02/03 02:01:14 DEBUG : test-multithread-copy-true-10485760-2: multi-thread copy: number of streams 4 was bigger than number of chunks 2 2026/02/03 02:01:14 DEBUG : test-multithread-copy-true-10485760-2: Starting multi-thread copy with 2 chunks of size 5Mi with 2 parallel streams 2026/02/03 02:01:14 DEBUG : test-multithread-copy-true-10485760-2: multi-thread copy: chunk 2/2 (5242880-10485760) size 5Mi starting 2026/02/03 02:01:14 DEBUG : test-multithread-copy-true-10485760-2: multi-thread copy: chunk 1/2 (0-5242880) size 5Mi starting 2026/02/03 02:01:15 DEBUG : test-multithread-copy-true-10485760-2: multi-thread copy: chunk 1/2 (0-5242880) size 5Mi finished 2026/02/03 02:01:15 DEBUG : test-multithread-copy-true-10485760-2: multi-thread copy: chunk 2/2 (5242880-10485760) size 5Mi finished 2026/02/03 02:01:17 DEBUG : test-multithread-copy-true-10485760-2: Finished multi-thread copy with 2 parts of size 5Mi fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/multithread_test.go:222 Error: Should be true Test: TestMultithreadCopy/upload=true,size=10485760,streams=2 Messages: listing wrong, want test-multithread-copy-true-10485760-2 (10485760) got test-multithread-copy-true-10485759-2 (10485759), test-multithread-copy-true-10485760-2 (10485760) fstest.go:191: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/multithread_test.go:222 Error: Should be true Test: TestMultithreadCopy/upload=true,size=10485760,streams=2 Messages: Unexpected file "test-multithread-copy-true-10485759-2" fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/multithread_test.go:223 Error: Should be true Test: TestMultithreadCopy/upload=true,size=10485760,streams=2 Messages: listing wrong, want test-multithread-copy-true-10485760-2 (10485760) got test-multithread-copy-true-10485759-2 (10485759), test-multithread-copy-true-10485760-2 (10485760) fstest.go:191: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/multithread_test.go:223 Error: Should be true Test: TestMultithreadCopy/upload=true,size=10485760,streams=2 Messages: Unexpected file "test-multithread-copy-true-10485759-2" === RUN TestMultithreadCopy/upload=true,size=10485761,streams=2 fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/multithread_test.go:186 Error: Should be true Test: TestMultithreadCopy/upload=true,size=10485761,streams=2 Messages: listing wrong, want got test-multithread-copy-true-10485759-2 (10485759), test-multithread-copy-true-10485760-2 (10485760) fstest.go:191: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/multithread_test.go:186 /usr/local/go/src/runtime/asm_amd64.s:1700 Error: Should be true Test: TestMultithreadCopy/upload=true,size=10485761,streams=2 Messages: Unexpected file "test-multithread-copy-true-10485759-2" fstest.go:191: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/multithread_test.go:186 /usr/local/go/src/runtime/asm_amd64.s:1700 Error: Should be true Test: TestMultithreadCopy/upload=true,size=10485761,streams=2 Messages: Unexpected file "test-multithread-copy-true-10485760-2" fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:339 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/multithread_test.go:187 Error: Should be true Test: TestMultithreadCopy/upload=true,size=10485761,streams=2 Messages: listing wrong, want test-multithread-copy-true-10485761-2 (10485761) got test-multithread-copy-true-10485759-2 (10485759), test-multithread-copy-true-10485761-2 (10485761) fstest.go:191: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:339 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/multithread_test.go:187 /usr/local/go/src/runtime/asm_amd64.s:1700 Error: Should be true Test: TestMultithreadCopy/upload=true,size=10485761,streams=2 Messages: Unexpected file "test-multithread-copy-true-10485759-2" 2026/02/03 02:01:50 DEBUG : test-multithread-copy-true-10485761-2: multi-thread copy: disabling buffering because source is local disk 2026/02/03 02:01:51 DEBUG : test-multithread-copy-true-10485761-2: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 2 2026/02/03 02:01:51 DEBUG : test-multithread-copy-true-10485761-2: multi-thread copy: number of streams 4 was bigger than number of chunks 3 2026/02/03 02:01:51 DEBUG : test-multithread-copy-true-10485761-2: Starting multi-thread copy with 3 chunks of size 5Mi with 3 parallel streams 2026/02/03 02:01:51 DEBUG : test-multithread-copy-true-10485761-2: multi-thread copy: chunk 3/3 (10485760-10485761) size 1 starting 2026/02/03 02:01:51 DEBUG : test-multithread-copy-true-10485761-2: multi-thread copy: chunk 1/3 (0-5242880) size 5Mi starting 2026/02/03 02:01:51 DEBUG : test-multithread-copy-true-10485761-2: multi-thread copy: chunk 2/3 (5242880-10485760) size 5Mi starting 2026/02/03 02:01:51 DEBUG : test-multithread-copy-true-10485761-2: multi-thread copy: chunk 3/3 (10485760-10485761) size 1 finished 2026/02/03 02:01:51 DEBUG : test-multithread-copy-true-10485761-2: multi-thread copy: chunk 2/3 (5242880-10485760) size 5Mi finished 2026/02/03 02:01:52 DEBUG : test-multithread-copy-true-10485761-2: multi-thread copy: chunk 1/3 (0-5242880) size 5Mi finished 2026/02/03 02:01:55 DEBUG : test-multithread-copy-true-10485761-2: Finished multi-thread copy with 3 parts of size 5Mi fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/multithread_test.go:222 Error: Should be true Test: TestMultithreadCopy/upload=true,size=10485761,streams=2 Messages: listing wrong, want test-multithread-copy-true-10485761-2 (10485761) got test-multithread-copy-true-10485759-2 (10485759), test-multithread-copy-true-10485761-2 (10485761) fstest.go:191: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/multithread_test.go:222 Error: Should be true Test: TestMultithreadCopy/upload=true,size=10485761,streams=2 Messages: Unexpected file "test-multithread-copy-true-10485759-2" fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/multithread_test.go:223 Error: Should be true Test: TestMultithreadCopy/upload=true,size=10485761,streams=2 Messages: listing wrong, want test-multithread-copy-true-10485761-2 (10485761) got test-multithread-copy-true-10485759-2 (10485759), test-multithread-copy-true-10485761-2 (10485761) fstest.go:191: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/multithread_test.go:223 Error: Should be true Test: TestMultithreadCopy/upload=true,size=10485761,streams=2 Messages: Unexpected file "test-multithread-copy-true-10485759-2" 2026/02/03 02:02:12 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/03 02:02:12 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2026/02/03 02:02:12 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/03 02:02:12 DEBUG : pacer: Rate limited, increasing sleep to 40ms 2026/02/03 02:02:12 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/03 02:02:12 DEBUG : pacer: Rate limited, increasing sleep to 80ms 2026/02/03 02:02:13 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/03 02:02:13 DEBUG : pacer: Rate limited, increasing sleep to 160ms 2026/02/03 02:02:13 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/03 02:02:13 DEBUG : pacer: Rate limited, increasing sleep to 320ms 2026/02/03 02:02:13 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/03 02:02:13 DEBUG : pacer: Rate limited, increasing sleep to 640ms 2026/02/03 02:02:14 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/03 02:02:14 DEBUG : pacer: Rate limited, increasing sleep to 1.28s 2026/02/03 02:02:14 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/03 02:02:14 DEBUG : pacer: Rate limited, increasing sleep to 2.56s 2026/02/03 02:02:16 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/03 02:02:16 DEBUG : pacer: Rate limited, increasing sleep to 5.12s 2026/02/03 02:02:18 DEBUG : pacer: low level retry 10/10 (error Error "Server Error") 2026/02/03 02:02:18 DEBUG : pacer: Rate limited, increasing sleep to 10.24s === NAME TestMultithreadCopy run.go:130: removing file "test-multithread-copy-true-10485761-2" failed - try 1/3: failed to delete item: Error "Server Error" 2026/02/03 02:02:23 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/03 02:02:23 DEBUG : pacer: Rate limited, increasing sleep to 20s 2026/02/03 02:02:34 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/03 02:02:54 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/03 02:03:14 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/03 02:03:33 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/03 02:03:54 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/03 02:04:14 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/03 02:04:33 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/03 02:04:54 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/03 02:05:14 DEBUG : pacer: low level retry 10/10 (error Error "Server Error") run.go:130: removing file "test-multithread-copy-true-10485761-2" failed - try 2/3: failed to delete item: Error "Server Error" 2026/02/03 02:05:34 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/03 02:05:54 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/03 02:06:14 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/03 02:06:34 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/03 02:06:54 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/03 02:07:14 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/03 02:07:34 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/03 02:07:54 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/03 02:08:14 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/03 02:08:34 DEBUG : pacer: low level retry 10/10 (error Error "Server Error") run.go:130: removing file "test-multithread-copy-true-10485761-2" failed - try 3/3: failed to delete item: Error "Server Error" run.go:133: removing file "test-multithread-copy-true-10485761-2" failed: failed to delete item: Error "Server Error" 2026/02/03 02:08:54 DEBUG : pacer: Reducing sleep to 10s --- FAIL: TestMultithreadCopy (522.48s) --- FAIL: TestMultithreadCopy/upload=false,size=10485759,streams=2 (15.21s) --- FAIL: TestMultithreadCopy/upload=false,size=10485760,streams=2 (14.62s) --- PASS: TestMultithreadCopy/upload=false,size=10485761,streams=2 (17.84s) --- FAIL: TestMultithreadCopy/upload=true,size=10485759,streams=2 (4.70s) --- FAIL: TestMultithreadCopy/upload=true,size=10485760,streams=2 (28.08s) --- FAIL: TestMultithreadCopy/upload=true,size=10485761,streams=2 (38.10s) === RUN TestMultithreadCopyAbort run.go:185: Remote "drime root 'rclone-test-yohamax0qoza'", Local "Local file system at /tmp/rclone1795778209", Modify Window "876000h0m0s" 2026/02/03 02:09:14 DEBUG : pacer: Reducing sleep to 5s 2026/02/03 02:09:24 DEBUG : pacer: Reducing sleep to 2.5s 2026/02/03 02:09:29 DEBUG : pacer: Reducing sleep to 1.25s 2026/02/03 02:09:34 DEBUG : pacer: Reducing sleep to 625ms 2026/02/03 02:09:34 DEBUG : pacer: Reducing sleep to 312.5ms fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache 2026/02/03 02:09:35 DEBUG : pacer: Reducing sleep to 156.25ms 2026/02/03 02:09:36 DEBUG : pacer: Reducing sleep to 78.125ms fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache 2026/02/03 02:09:38 DEBUG : pacer: Reducing sleep to 39.0625ms 2026/02/03 02:09:38 DEBUG : pacer: Reducing sleep to 19.53125ms fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/multithread_test.go:305 Error: Should be true Test: TestMultithreadCopyAbort Messages: listing wrong, want test-multithread-abort (100) got fstest.go:203: Not found "test-multithread-abort" fstest.go:206: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:206 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:310 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/multithread_test.go:305 /usr/local/go/src/runtime/asm_amd64.s:1700 Error: Not equal: expected: 0 actual : 1 Test: TestMultithreadCopyAbort Messages: 1 objects not found 2026/02/03 02:09:43 DEBUG : test-multithread-abort: multi-thread copy: disabling buffering because source is local disk 2026/02/03 02:09:43 DEBUG : pacer: Reducing sleep to 10ms 2026/02/03 02:09:44 DEBUG : test-multithread-abort: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 1 2026/02/03 02:09:44 DEBUG : test-multithread-abort: multi-thread copy: number of streams 4 was bigger than number of chunks 3 2026/02/03 02:09:44 DEBUG : test-multithread-abort: Starting multi-thread copy with 3 chunks of size 5Mi with 3 parallel streams 2026/02/03 02:09:44 DEBUG : test-multithread-abort: multi-thread copy: chunk 3/3 (10485760-10485761) size 1 starting 2026/02/03 02:09:44 DEBUG : Open with options = [RangeOption(10485760,10485760)] 2026/02/03 02:09:44 DEBUG : test-multithread-abort: multi-thread copy: chunk 1/3 (0-5242880) size 5Mi starting 2026/02/03 02:09:44 DEBUG : Open with options = [RangeOption(0,5242879)] 2026/02/03 02:09:44 DEBUG : test-multithread-abort: multi-thread copy: chunk 2/3 (5242880-10485760) size 5Mi starting 2026/02/03 02:09:44 DEBUG : Open with options = [RangeOption(5242880,10485759)] 2026/02/03 02:09:45 DEBUG : test-multithread-abort: multi-thread copy: chunk 2/3 (5242880-10485760) size 5Mi finished 2026/02/03 02:09:45 DEBUG : test-multithread-abort: multi-thread copy: chunk 1/3 (0-5242880) size 5Mi finished 2026/02/03 02:09:45 DEBUG : Returning error reader 2026/02/03 02:09:45 DEBUG : BOOM: simulated read failure 2026/02/03 02:09:45 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 0/10: BOOM: simulated read failure 2026/02/03 02:09:45 DEBUG : Open with options = [RangeOption(10485760,10485760)] 2026/02/03 02:09:46 DEBUG : Returning error reader 2026/02/03 02:09:46 DEBUG : BOOM: simulated read failure 2026/02/03 02:09:46 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 1/10: BOOM: simulated read failure 2026/02/03 02:09:46 DEBUG : Open with options = [RangeOption(10485760,10485760)] 2026/02/03 02:09:47 DEBUG : Returning error reader 2026/02/03 02:09:47 DEBUG : BOOM: simulated read failure 2026/02/03 02:09:47 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 2/10: BOOM: simulated read failure 2026/02/03 02:09:47 DEBUG : Open with options = [RangeOption(10485760,10485760)] 2026/02/03 02:09:48 DEBUG : Returning error reader 2026/02/03 02:09:48 DEBUG : BOOM: simulated read failure 2026/02/03 02:09:48 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 3/10: BOOM: simulated read failure 2026/02/03 02:09:48 DEBUG : Open with options = [RangeOption(10485760,10485760)] 2026/02/03 02:09:49 DEBUG : Returning error reader 2026/02/03 02:09:49 DEBUG : BOOM: simulated read failure 2026/02/03 02:09:49 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 4/10: BOOM: simulated read failure 2026/02/03 02:09:49 DEBUG : Open with options = [RangeOption(10485760,10485760)] 2026/02/03 02:09:50 DEBUG : Returning error reader 2026/02/03 02:09:50 DEBUG : BOOM: simulated read failure 2026/02/03 02:09:50 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 5/10: BOOM: simulated read failure 2026/02/03 02:09:50 DEBUG : Open with options = [RangeOption(10485760,10485760)] 2026/02/03 02:09:51 DEBUG : Returning error reader 2026/02/03 02:09:51 DEBUG : BOOM: simulated read failure 2026/02/03 02:09:51 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 6/10: BOOM: simulated read failure 2026/02/03 02:09:51 DEBUG : Open with options = [RangeOption(10485760,10485760)] 2026/02/03 02:09:52 DEBUG : Returning error reader 2026/02/03 02:09:52 DEBUG : BOOM: simulated read failure 2026/02/03 02:09:52 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 7/10: BOOM: simulated read failure 2026/02/03 02:09:52 DEBUG : Open with options = [RangeOption(10485760,10485760)] 2026/02/03 02:09:53 DEBUG : Returning error reader 2026/02/03 02:09:53 DEBUG : BOOM: simulated read failure 2026/02/03 02:09:53 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 8/10: BOOM: simulated read failure 2026/02/03 02:09:53 DEBUG : Open with options = [RangeOption(10485760,10485760)] 2026/02/03 02:09:54 DEBUG : Returning error reader 2026/02/03 02:09:54 DEBUG : BOOM: simulated read failure 2026/02/03 02:09:54 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 9/10: BOOM: simulated read failure 2026/02/03 02:09:54 DEBUG : Open with options = [RangeOption(10485760,10485760)] 2026/02/03 02:09:55 DEBUG : Returning error reader 2026/02/03 02:09:55 DEBUG : BOOM: simulated read failure 2026/02/03 02:09:55 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 10/10: BOOM: simulated read failure 2026/02/03 02:09:55 DEBUG : test-multithread-abort: Reopen failed after offset 0 bytes read: failed to reopen: too many retries 2026/02/03 02:09:55 DEBUG : test-multithread-abort: multi-thread copy: chunk 3/3 failed: multi-thread copy: failed to write chunk: failed to upload part 3: Put "https://drimestorage.18b804eaa6bc9759e2278f8d5367f42f.r2.cloudflarestorage.com/uploads/4c460cfa-b4f1-4a25-9764-8b6a2e7f2880/4c460cfa-b4f1-4a25-9764-8b6a2e7f2880?uploadId=ALmnvq50HxMko8TtTzB4Ff31nmcIEV3hkFEzbU25YlOL0DfhGXVuDsHFRLG2aMsOxjM1sGGn4WWr33V-TQUAfJpvgHDL4J1212W_J7EAulOKkfMWVdie1dKzm9kKZldJEnVWIjK2j-v3cD42yJFhjaFCMMb4ExpyTkcXmvujX1NR5hXVaNWtdfk6bbR_C_t90ScH-PqcMaTE6pKKcTbaaBNo5O73JTlmWpWsd09Vz03NtXz8GGNCFUPpEHaHgnsFoZIvI9R28LOSCbnFgB6YiaxtcyfEoUD2729QoFJrcFD7hEFQ8HFxaa7nM37IO0u9LZIUVM1HcJWcy0OJwIRhjzM&partNumber=3&X-Amz-Content-Sha256=UNSIGNED-PAYLOAD&X-Amz-Algorithm=AWS4-HMAC-SHA256&X-Amz-Credential=db9b71e81fa19eaf54eb4264526ca7a8%2F20260203%2FWEUR%2Fs3%2Faws4_request&X-Amz-Date=20260203T020945Z&X-Amz-SignedHeaders=host&X-Amz-Expires=1800&X-Amz-Signature=3a675c79f5b1da30b33708688d85a791fd4338df0ed37973233943874340a326": BOOM: simulated read failure 2026/02/03 02:09:55 DEBUG : test-multithread-abort: multi-thread copy: cancelling transfer on exit 2026/02/03 02:09:57 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/03 02:09:57 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2026/02/03 02:09:58 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/03 02:09:58 DEBUG : pacer: Rate limited, increasing sleep to 40ms 2026/02/03 02:09:58 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/03 02:09:58 DEBUG : pacer: Rate limited, increasing sleep to 80ms 2026/02/03 02:09:58 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/03 02:09:58 DEBUG : pacer: Rate limited, increasing sleep to 160ms 2026/02/03 02:09:58 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/03 02:09:58 DEBUG : pacer: Rate limited, increasing sleep to 320ms 2026/02/03 02:09:59 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/03 02:09:59 DEBUG : pacer: Rate limited, increasing sleep to 640ms 2026/02/03 02:09:59 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/03 02:09:59 DEBUG : pacer: Rate limited, increasing sleep to 1.28s 2026/02/03 02:10:00 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/03 02:10:00 DEBUG : pacer: Rate limited, increasing sleep to 2.56s 2026/02/03 02:10:01 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/03 02:10:01 DEBUG : pacer: Rate limited, increasing sleep to 5.12s 2026/02/03 02:10:04 DEBUG : pacer: low level retry 10/10 (error Error "Server Error") 2026/02/03 02:10:04 DEBUG : pacer: Rate limited, increasing sleep to 10.24s run.go:130: removing file "test-multithread-abort" failed - try 1/3: failed to delete item: Error "Server Error" 2026/02/03 02:10:08 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/03 02:10:08 DEBUG : pacer: Rate limited, increasing sleep to 20s 2026/02/03 02:10:19 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/03 02:10:39 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/03 02:10:59 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/03 02:11:19 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/03 02:11:39 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/03 02:11:59 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/03 02:12:19 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/03 02:12:39 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/03 02:12:59 DEBUG : pacer: low level retry 10/10 (error Error "Server Error") run.go:130: removing file "test-multithread-abort" failed - try 2/3: failed to delete item: Error "Server Error" 2026/02/03 02:13:19 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/03 02:13:39 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/03 02:13:59 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/03 02:14:19 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/03 02:14:39 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/03 02:14:59 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/03 02:15:19 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/03 02:15:39 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/03 02:15:59 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/03 02:16:19 DEBUG : pacer: low level retry 10/10 (error Error "Server Error") run.go:130: removing file "test-multithread-abort" failed - try 3/3: failed to delete item: Error "Server Error" run.go:133: removing file "test-multithread-abort" failed: failed to delete item: Error "Server Error" 2026/02/03 02:16:39 DEBUG : pacer: Reducing sleep to 10s --- FAIL: TestMultithreadCopyAbort (465.47s) === RUN TestSizeDiffers 2026/02/03 02:16:39 DEBUG : a: size = 0 OK 2026/02/03 02:16:39 DEBUG : a: size = 1 (memory) 2026/02/03 02:16:39 DEBUG : a: size = 2 (memory) --- PASS: TestSizeDiffers (0.00s) === RUN TestReOpen === RUN TestReOpen/Normal === RUN TestReOpen/Normal/Basics 2026/02/03 02:16:39 DEBUG : potato: Seek from 10 to 0 === RUN TestReOpen/Normal/ErrorAtStart === RUN TestReOpen/Normal/WithErrors 2026/02/03 02:16:39 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error 2026/02/03 02:16:39 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error 2026/02/03 02:16:39 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error === RUN TestReOpen/Normal/TooManyErrors 2026/02/03 02:16:39 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error 2026/02/03 02:16:39 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error 2026/02/03 02:16:39 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error 2026/02/03 02:16:39 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries === RUN TestReOpen/Normal/ReadAt 2026/02/03 02:16:39 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2026/02/03 02:16:39 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2026/02/03 02:16:39 DEBUG : potato: Seek from 5 to 1 === RUN TestReOpen/Normal/Seek 2026/02/03 02:16:39 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2026/02/03 02:16:39 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2026/02/03 02:16:39 DEBUG : potato: Seek from 5 to 2 === RUN TestReOpen/Normal/AccountRead === RUN TestReOpen/Normal/AccountReadDelay 2026/02/03 02:16:39 DEBUG : potato: Seek from 10 to 0 2026/02/03 02:16:39 DEBUG : potato: Seek from 10 to 0 2026/02/03 02:16:39 DEBUG : potato: Seek from 10 to 0 === RUN TestReOpen/Normal/AccountReadError === RUN TestReOpen/WithRangeOption === RUN TestReOpen/WithRangeOption/Basics 2026/02/03 02:16:39 DEBUG : potato: Seek from 7 to 0 === RUN TestReOpen/WithRangeOption/ErrorAtStart === RUN TestReOpen/WithRangeOption/WithErrors 2026/02/03 02:16:39 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error 2026/02/03 02:16:39 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error 2026/02/03 02:16:39 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error === RUN TestReOpen/WithRangeOption/TooManyErrors 2026/02/03 02:16:39 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error 2026/02/03 02:16:39 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error 2026/02/03 02:16:39 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error 2026/02/03 02:16:39 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries === RUN TestReOpen/WithRangeOption/ReadAt 2026/02/03 02:16:39 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2026/02/03 02:16:39 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2026/02/03 02:16:39 DEBUG : potato: Seek from 5 to 1 === RUN TestReOpen/WithRangeOption/Seek 2026/02/03 02:16:39 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2026/02/03 02:16:39 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2026/02/03 02:16:39 DEBUG : potato: Seek from 5 to 2 2026/02/03 02:16:39 DEBUG : potato: Seek from 7 to 4 === RUN TestReOpen/WithRangeOption/AccountRead === RUN TestReOpen/WithRangeOption/AccountReadDelay 2026/02/03 02:16:39 DEBUG : potato: Seek from 7 to 0 2026/02/03 02:16:39 DEBUG : potato: Seek from 7 to 0 2026/02/03 02:16:39 DEBUG : potato: Seek from 7 to 0 === RUN TestReOpen/WithRangeOption/AccountReadError === RUN TestReOpen/WithSeekOption === RUN TestReOpen/WithSeekOption/Basics 2026/02/03 02:16:39 DEBUG : potato: Seek from 8 to 0 === RUN TestReOpen/WithSeekOption/ErrorAtStart === RUN TestReOpen/WithSeekOption/WithErrors 2026/02/03 02:16:39 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error 2026/02/03 02:16:39 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error 2026/02/03 02:16:39 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error === RUN TestReOpen/WithSeekOption/TooManyErrors 2026/02/03 02:16:39 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error 2026/02/03 02:16:39 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error 2026/02/03 02:16:39 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error 2026/02/03 02:16:39 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries === RUN TestReOpen/WithSeekOption/ReadAt 2026/02/03 02:16:39 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2026/02/03 02:16:39 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2026/02/03 02:16:39 DEBUG : potato: Seek from 5 to 1 === RUN TestReOpen/WithSeekOption/Seek 2026/02/03 02:16:39 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2026/02/03 02:16:39 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2026/02/03 02:16:39 DEBUG : potato: Seek from 5 to 2 2026/02/03 02:16:39 DEBUG : potato: Seek from 7 to 5 === RUN TestReOpen/WithSeekOption/AccountRead === RUN TestReOpen/WithSeekOption/AccountReadDelay 2026/02/03 02:16:39 DEBUG : potato: Seek from 8 to 0 2026/02/03 02:16:39 DEBUG : potato: Seek from 8 to 0 2026/02/03 02:16:39 DEBUG : potato: Seek from 8 to 0 === RUN TestReOpen/WithSeekOption/AccountReadError === RUN TestReOpen/UnknownSize === RUN TestReOpen/UnknownSize/Basics 2026/02/03 02:16:39 DEBUG : potato: Seek from 9 to 0 === RUN TestReOpen/UnknownSize/ErrorAtStart === RUN TestReOpen/UnknownSize/WithErrors 2026/02/03 02:16:39 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error 2026/02/03 02:16:39 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error 2026/02/03 02:16:39 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error === RUN TestReOpen/UnknownSize/TooManyErrors 2026/02/03 02:16:39 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error 2026/02/03 02:16:39 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error 2026/02/03 02:16:39 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error 2026/02/03 02:16:39 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries === RUN TestReOpen/UnknownSize/ReadAt 2026/02/03 02:16:39 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2026/02/03 02:16:39 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2026/02/03 02:16:39 DEBUG : potato: Seek from 5 to 1 === RUN TestReOpen/UnknownSize/Seek 2026/02/03 02:16:39 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2026/02/03 02:16:39 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2026/02/03 02:16:39 DEBUG : potato: Seek from 5 to 2 2026/02/03 02:16:39 DEBUG : potato: Seek from 7 to 6 === RUN TestReOpen/UnknownSize/AccountRead === RUN TestReOpen/UnknownSize/AccountReadDelay 2026/02/03 02:16:39 DEBUG : potato: Seek from 9 to 0 2026/02/03 02:16:39 DEBUG : potato: Seek from 9 to 0 2026/02/03 02:16:39 DEBUG : potato: Seek from 9 to 0 === RUN TestReOpen/UnknownSize/AccountReadError --- PASS: TestReOpen (0.00s) --- PASS: TestReOpen/Normal (0.00s) --- PASS: TestReOpen/Normal/Basics (0.00s) --- PASS: TestReOpen/Normal/ErrorAtStart (0.00s) --- PASS: TestReOpen/Normal/WithErrors (0.00s) --- PASS: TestReOpen/Normal/TooManyErrors (0.00s) --- PASS: TestReOpen/Normal/ReadAt (0.00s) --- PASS: TestReOpen/Normal/Seek (0.00s) --- PASS: TestReOpen/Normal/AccountRead (0.00s) --- PASS: TestReOpen/Normal/AccountReadDelay (0.00s) --- PASS: TestReOpen/Normal/AccountReadError (0.00s) --- PASS: TestReOpen/WithRangeOption (0.00s) --- PASS: TestReOpen/WithRangeOption/Basics (0.00s) --- PASS: TestReOpen/WithRangeOption/ErrorAtStart (0.00s) --- PASS: TestReOpen/WithRangeOption/WithErrors (0.00s) --- PASS: TestReOpen/WithRangeOption/TooManyErrors (0.00s) --- PASS: TestReOpen/WithRangeOption/ReadAt (0.00s) --- PASS: TestReOpen/WithRangeOption/Seek (0.00s) --- PASS: TestReOpen/WithRangeOption/AccountRead (0.00s) --- PASS: TestReOpen/WithRangeOption/AccountReadDelay (0.00s) --- PASS: TestReOpen/WithRangeOption/AccountReadError (0.00s) --- PASS: TestReOpen/WithSeekOption (0.00s) --- PASS: TestReOpen/WithSeekOption/Basics (0.00s) --- PASS: TestReOpen/WithSeekOption/ErrorAtStart (0.00s) --- PASS: TestReOpen/WithSeekOption/WithErrors (0.00s) --- PASS: TestReOpen/WithSeekOption/TooManyErrors (0.00s) --- PASS: TestReOpen/WithSeekOption/ReadAt (0.00s) --- PASS: TestReOpen/WithSeekOption/Seek (0.00s) --- PASS: TestReOpen/WithSeekOption/AccountRead (0.00s) --- PASS: TestReOpen/WithSeekOption/AccountReadDelay (0.00s) --- PASS: TestReOpen/WithSeekOption/AccountReadError (0.00s) --- PASS: TestReOpen/UnknownSize (0.00s) --- PASS: TestReOpen/UnknownSize/Basics (0.00s) --- PASS: TestReOpen/UnknownSize/ErrorAtStart (0.00s) --- PASS: TestReOpen/UnknownSize/WithErrors (0.00s) --- PASS: TestReOpen/UnknownSize/TooManyErrors (0.00s) --- PASS: TestReOpen/UnknownSize/ReadAt (0.00s) --- PASS: TestReOpen/UnknownSize/Seek (0.00s) --- PASS: TestReOpen/UnknownSize/AccountRead (0.00s) --- PASS: TestReOpen/UnknownSize/AccountReadDelay (0.00s) --- PASS: TestReOpen/UnknownSize/AccountReadError (0.00s) === RUN TestCheck run.go:185: Remote "drime root 'rclone-test-yohamax0qoza'", Local "Local file system at /tmp/rclone1795778209", Modify Window "876000h0m0s" 2026/02/03 02:16:59 DEBUG : pacer: Reducing sleep to 5s 2026/02/03 02:17:11 DEBUG : pacer: Reducing sleep to 2.5s 2026/02/03 02:17:14 DEBUG : pacer: Reducing sleep to 1.25s === RUN TestCheck/1 === RUN TestCheck/2 2026/02/03 02:17:18 DEBUG : pacer: Reducing sleep to 156.25ms 2026/02/03 02:17:20 DEBUG : pacer: Reducing sleep to 78.125ms 2026/02/03 02:17:20 DEBUG : pacer: Reducing sleep to 39.0625ms === NAME TestCheck fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache 2026/02/03 02:17:22 DEBUG : pacer: Reducing sleep to 19.53125ms 2026/02/03 02:17:22 DEBUG : pacer: Reducing sleep to 10ms fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:292: Sleeping for 7s just to make sure === RUN TestCheck/3 === NAME TestCheck fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:292: Sleeping for 6s just to make sure === RUN TestCheck/4 === RUN TestCheck/5 === NAME TestCheck fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:292: Sleeping for 6s just to make sure === RUN TestCheck/6 === RUN TestCheck/7 === NAME TestCheck fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:292: Sleeping for 6s just to make sure --- PASS: TestCheck (84.37s) --- PASS: TestCheck/1 (2.43s) --- PASS: TestCheck/2 (1.28s) --- PASS: TestCheck/3 (0.27s) --- PASS: TestCheck/4 (0.29s) --- PASS: TestCheck/5 (0.36s) --- PASS: TestCheck/6 (0.27s) --- PASS: TestCheck/7 (0.29s) === RUN TestCheckFsError 2026/02/03 02:18:03 DEBUG : Creating backend with remote "nonexistent" 2026/02/03 02:18:03 DEBUG : Config file has changed externally - reloading 2026/02/03 02:18:03 DEBUG : Creating backend with remote "nonexistent" 2026/02/03 02:18:03 DEBUG : Local file system at /home/rclone/go/src/github.com/rclone/rclone/fs/operations/nonexistent: Waiting for checks to finish 2026/02/03 02:18:03 ERROR : Local file system at /home/rclone/go/src/github.com/rclone/rclone/fs/operations/nonexistent: error reading source root directory: directory not found 2026/02/03 02:18:03 NOTICE: Local file system at /home/rclone/go/src/github.com/rclone/rclone/fs/operations/nonexistent: 0 differences found 2026/02/03 02:18:03 NOTICE: Local file system at /home/rclone/go/src/github.com/rclone/rclone/fs/operations/nonexistent: 2 errors while checking --- PASS: TestCheckFsError (0.00s) === RUN TestCheckDownload run.go:185: Remote "drime root 'rclone-test-yohamax0qoza'", Local "Local file system at /tmp/rclone1795778209", Modify Window "876000h0m0s" fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:292: Sleeping for 6s just to make sure === RUN TestCheckDownload/1 === RUN TestCheckDownload/2 === NAME TestCheckDownload fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:292: Sleeping for 6s just to make sure === RUN TestCheckDownload/3 === NAME TestCheckDownload fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:292: Sleeping for 6s just to make sure === RUN TestCheckDownload/4 === RUN TestCheckDownload/5 === NAME TestCheckDownload fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:292: Sleeping for 6s just to make sure === RUN TestCheckDownload/6 === RUN TestCheckDownload/7 === NAME TestCheckDownload fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:292: Sleeping for 6s just to make sure --- PASS: TestCheckDownload (55.49s) --- PASS: TestCheckDownload/1 (0.60s) --- PASS: TestCheckDownload/2 (0.66s) --- PASS: TestCheckDownload/3 (0.77s) --- PASS: TestCheckDownload/4 (0.61s) --- PASS: TestCheckDownload/5 (0.59s) --- PASS: TestCheckDownload/6 (0.85s) --- PASS: TestCheckDownload/7 (0.69s) === RUN TestCheckSizeOnly run.go:185: Remote "drime root 'rclone-test-yohamax0qoza'", Local "Local file system at /tmp/rclone1795778209", Modify Window "876000h0m0s" fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:292: Sleeping for 6s just to make sure === RUN TestCheckSizeOnly/1 === RUN TestCheckSizeOnly/2 === NAME TestCheckSizeOnly fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:292: Sleeping for 6s just to make sure === RUN TestCheckSizeOnly/3 === RUN TestCheckSizeOnly/4 === RUN TestCheckSizeOnly/5 === NAME TestCheckSizeOnly fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:161 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:181 Error: Should be true Test: TestCheckSizeOnly Messages: listing wrong, want empty space (1), potato2 (60), remotepotato (60), rutabaga (8) got empty space (1), potato2 (60), rutabaga (8) fstest.go:203: Not found "remotepotato" fstest.go:206: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:206 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:310 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:161 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:181 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:212 Error: Not equal: expected: 0 actual : 1 Test: TestCheckSizeOnly Messages: 1 objects not found === RUN TestCheckSizeOnly/6 === RUN TestCheckSizeOnly/7 === NAME TestCheckSizeOnly fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393 /usr/local/go/src/testing/testing.go:1211 /usr/local/go/src/testing/testing.go:1445 /usr/local/go/src/testing/testing.go:1786 Error: Should be true Test: TestCheckSizeOnly Messages: listing wrong, want got empty space (1), potato2 (60), remotepotato (60), rutabaga (8) fstest.go:191: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393 /usr/local/go/src/testing/testing.go:1211 /usr/local/go/src/testing/testing.go:1445 /usr/local/go/src/testing/testing.go:1786 Error: Should be true Test: TestCheckSizeOnly Messages: Unexpected file "empty space" fstest.go:191: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393 /usr/local/go/src/testing/testing.go:1211 /usr/local/go/src/testing/testing.go:1445 /usr/local/go/src/testing/testing.go:1786 Error: Should be true Test: TestCheckSizeOnly Messages: Unexpected file "potato2" fstest.go:191: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393 /usr/local/go/src/testing/testing.go:1211 /usr/local/go/src/testing/testing.go:1445 /usr/local/go/src/testing/testing.go:1786 Error: Should be true Test: TestCheckSizeOnly Messages: Unexpected file "remotepotato" fstest.go:191: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393 /usr/local/go/src/testing/testing.go:1211 /usr/local/go/src/testing/testing.go:1445 /usr/local/go/src/testing/testing.go:1786 Error: Should be true Test: TestCheckSizeOnly Messages: Unexpected file "rutabaga" --- FAIL: TestCheckSizeOnly (48.01s) --- PASS: TestCheckSizeOnly/1 (0.29s) --- PASS: TestCheckSizeOnly/2 (0.17s) --- PASS: TestCheckSizeOnly/3 (0.46s) --- PASS: TestCheckSizeOnly/4 (0.25s) --- PASS: TestCheckSizeOnly/5 (0.30s) --- PASS: TestCheckSizeOnly/6 (0.51s) --- PASS: TestCheckSizeOnly/7 (0.47s) === RUN TestCheckEqualReaders --- PASS: TestCheckEqualReaders (0.00s) === RUN TestParseSumFile run.go:185: Remote "drime root 'rclone-test-yohamax0qoza'", Local "Local file system at /tmp/rclone1795778209", Modify Window "876000h0m0s" fstest.go:542: Sleeping for 1s for findObject eventual consistency: 1/3 (object not found) fstest.go:542: Sleeping for 1.5s for findObject eventual consistency: 2/3 (object not found) fstest.go:542: Sleeping for 2.25s for findObject eventual consistency: 3/3 (object not found) fstest.go:546: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:546 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:309 Error: Received unexpected error: object not found Test: TestParseSumFile --- FAIL: TestParseSumFile (8.56s) === RUN TestCheckSum run.go:185: Remote "drime root 'rclone-test-yohamax0qoza'", Local "Local file system at /tmp/rclone1795778209", Modify Window "876000h0m0s" 2026/02/03 02:19:55 DEBUG : Creating backend with remote "TestDrime:rclone-test-yohamax0qoza/data" 2026/02/03 02:19:55 DEBUG : Config file has changed externally - reloading check_test.go:350: drime root 'rclone-test-yohamax0qoza/data' lacks md5, skipping --- SKIP: TestCheckSum (1.63s) === RUN TestCheckSumDownload run.go:185: Remote "drime root 'rclone-test-yohamax0qoza'", Local "Local file system at /tmp/rclone1795778209", Modify Window "876000h0m0s" 2026/02/03 02:19:57 DEBUG : Creating backend with remote "TestDrime:rclone-test-yohamax0qoza/data" 2026/02/03 02:20:03 DEBUG : test.sum: Removing old object on successful upload fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:436 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:541 /usr/local/go/src/runtime/asm_amd64.s:1700 Error: Should be true Test: TestCheckSumDownload Messages: listing wrong, want data/banana (13), test.sum (41) got test.sum (81) fstest.go:150: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:150 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:195 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:436 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:541 Error: Not equal: expected: 41 actual : 81 Test: TestCheckSumDownload Messages: test.sum: size incorrect file=41 vs obj=81 fstest.go:203: Not found "data/banana" fstest.go:206: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:206 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:310 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:436 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:541 Error: Not equal: expected: 0 actual : 1 Test: TestCheckSumDownload Messages: 1 objects not found === RUN TestCheckSumDownload/subtest1 2026/02/03 02:20:21 DEBUG : test.sum: Removing old object on successful upload === NAME TestCheckSumDownload fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:292: Sleeping for 6s just to make sure === RUN TestCheckSumDownload/subtest2 2026/02/03 02:20:34 DEBUG : test.sum: Removing old object on successful upload === NAME TestCheckSumDownload fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:292: Sleeping for 6s just to make sure === RUN TestCheckSumDownload/subtest3 2026/02/03 02:20:49 DEBUG : test.sum: Removing old object on successful upload === RUN TestCheckSumDownload/subtest4 2026/02/03 02:20:54 DEBUG : test.sum: Removing old object on successful upload === NAME TestCheckSumDownload fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:493 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:541 /usr/local/go/src/runtime/asm_amd64.s:1700 Error: Should be true Test: TestCheckSumDownload Messages: listing wrong, want data/banana (13), data/potato (15), test.sum (123) got data/banana (13), data/potato (15), test.sum (82) fstest.go:150: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:150 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:195 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:493 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:541 Error: Not equal: expected: 123 actual : 82 Test: TestCheckSumDownload Messages: test.sum: size incorrect file=123 vs obj=82 === RUN TestCheckSumDownload/subtest5 2026/02/03 02:21:08 DEBUG : test.sum: Removing old object on successful upload === RUN TestCheckSumDownload/subtest6 === NAME TestCheckSumDownload check_test.go:409: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:409 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:428 Error: Not equal: expected: 2 actual : 1 Test: TestCheckSumDownload Messages: wrong error count in run 6 check_test.go:384: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:384 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:417 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:428 Error: Not equal: expected: []string{"", "* potato", "+ orange", "= banana"} actual : []string{"", "+ orange", "= banana", "= potato"} Diff: --- Expected +++ Actual @@ -2,5 +2,5 @@ (string) "", - (string) (len=8) "* potato", (string) (len=8) "+ orange", - (string) (len=8) "= banana" + (string) (len=8) "= banana", + (string) (len=8) "= potato" } Test: TestCheckSumDownload Messages: wrong combined result in run 6 check_test.go:384: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:384 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:420 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:428 Error: Not equal: expected: []string{"", "banana"} actual : []string{"", "banana", "potato"} Diff: --- Expected +++ Actual @@ -1,4 +1,5 @@ -([]string) (len=2) { +([]string) (len=3) { (string) "", - (string) (len=6) "banana" + (string) (len=6) "banana", + (string) (len=6) "potato" } Test: TestCheckSumDownload Messages: wrong match result in run 6 check_test.go:384: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:384 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:421 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:428 Error: Not equal: expected: []string{"", "potato"} actual : []string{} Diff: --- Expected +++ Actual @@ -1,4 +1,2 @@ -([]string) (len=2) { - (string) "", - (string) (len=6) "potato" +([]string) { } Test: TestCheckSumDownload Messages: wrong differ result in run 6 2026/02/03 02:21:12 DEBUG : data/banana: Removing old object on successful upload 2026/02/03 02:21:14 DEBUG : data/potato: Removing old object on successful upload 2026/02/03 02:21:17 DEBUG : test.sum: Removing old object on successful upload fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:525 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:541 /usr/local/go/src/runtime/asm_amd64.s:1700 Error: Should be true Test: TestCheckSumDownload Messages: listing wrong, want data/banana (13), data/potato (15), test.sum (82) got data/banana (13), data/potato (15), test.sum (123) fstest.go:150: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:150 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:195 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:525 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:541 Error: Not equal: expected: 82 actual : 123 Test: TestCheckSumDownload Messages: test.sum: size incorrect file=82 vs obj=123 === RUN TestCheckSumDownload/subtest7 === NAME TestCheckSumDownload run.go:130: removing dir "data" failed - try 1/3: directory not empty run.go:130: removing dir "data" failed - try 2/3: directory not empty run.go:130: removing dir "data" failed - try 3/3: directory not empty run.go:133: removing dir "data" failed: directory not empty fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393 /usr/local/go/src/testing/testing.go:1211 /usr/local/go/src/testing/testing.go:1445 /usr/local/go/src/testing/testing.go:1786 Error: Should be true Test: TestCheckSumDownload Messages: listing wrong, want got data/banana (13), data/potato (15) fstest.go:191: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393 /usr/local/go/src/testing/testing.go:1211 /usr/local/go/src/testing/testing.go:1445 /usr/local/go/src/testing/testing.go:1786 Error: Should be true Test: TestCheckSumDownload Messages: Unexpected file "data/banana" fstest.go:191: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393 /usr/local/go/src/testing/testing.go:1211 /usr/local/go/src/testing/testing.go:1445 /usr/local/go/src/testing/testing.go:1786 Error: Should be true Test: TestCheckSumDownload Messages: Unexpected file "data/potato" fstest.go:327: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:327 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393 /usr/local/go/src/testing/testing.go:1211 /usr/local/go/src/testing/testing.go:1445 /usr/local/go/src/testing/testing.go:1786 Error: Not equal: expected: []string{} actual : []string{"data"} Diff: --- Expected +++ Actual @@ -1,2 +1,3 @@ -([]string) { +([]string) (len=1) { + (string) (len=4) "data" } Test: TestCheckSumDownload Messages: directories --- FAIL: TestCheckSumDownload (105.27s) --- PASS: TestCheckSumDownload/subtest1 (2.03s) --- PASS: TestCheckSumDownload/subtest2 (1.52s) --- PASS: TestCheckSumDownload/subtest3 (1.90s) --- PASS: TestCheckSumDownload/subtest4 (0.94s) --- PASS: TestCheckSumDownload/subtest5 (1.24s) --- PASS: TestCheckSumDownload/subtest6 (1.46s) --- PASS: TestCheckSumDownload/subtest7 (1.94s) === RUN TestApplyTransforms 2026/02/03 02:21:42 DEBUG : Creating backend with remote "TestDrime:rclone-test-tobiduy1cejo" 2026/02/03 02:21:42 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/02/03 02:21:43 DEBUG : Creating backend with remote "/tmp/rclone394744746" run.go:185: Remote "drime root 'rclone-test-tobiduy1cejo'", Local "Local file system at /tmp/rclone394744746", Modify Window "876000h0m0s" upper checkfile vs. lower remote (without normalization) 2026/02/03 02:21:46 ERROR : HELLO, WORLD!: file not in drime root 'rclone-test-tobiduy1cejo' 2026/02/03 02:21:46 NOTICE: drime root 'rclone-test-tobiduy1cejo': 1 files missing 2026/02/03 02:21:46 NOTICE: drime root 'rclone-test-tobiduy1cejo': 0 differences found 2026/02/03 02:21:46 NOTICE: drime root 'rclone-test-tobiduy1cejo': 1 errors while checking upper checkfile vs. lower remote (with normalization) 2026/02/03 02:21:47 ERROR : hello, world!: file not in drime root 'rclone-test-tobiduy1cejo' 2026/02/03 02:21:47 NOTICE: drime root 'rclone-test-tobiduy1cejo': 1 files missing 2026/02/03 02:21:47 NOTICE: drime root 'rclone-test-tobiduy1cejo': 0 differences found 2026/02/03 02:21:47 NOTICE: drime root 'rclone-test-tobiduy1cejo': 1 errors while checking check_test.go:591: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:591 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:594 Error: Received unexpected error: file not in drime root 'rclone-test-tobiduy1cejo' Test: TestApplyTransforms Messages: unexpected error for upper checkfile vs. lower remote (with normalization) HELLO, WORLD! hello, world! 2026/02/03 02:21:47 DEBUG : Creating backend with remote "TestDrime:rclone-test-votorix3saqa" 2026/02/03 02:21:47 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/02/03 02:21:48 DEBUG : Creating backend with remote "/tmp/rclone2361076201" run.go:185: Remote "drime root 'rclone-test-votorix3saqa'", Local "Local file system at /tmp/rclone2361076201", Modify Window "876000h0m0s" lower checkfile vs. upper remote (without normalization) 2026/02/03 02:21:52 ERROR : hello, world!: file not in drime root 'rclone-test-votorix3saqa' 2026/02/03 02:21:52 NOTICE: drime root 'rclone-test-votorix3saqa': 1 files missing 2026/02/03 02:21:52 NOTICE: drime root 'rclone-test-votorix3saqa': 0 differences found 2026/02/03 02:21:52 NOTICE: drime root 'rclone-test-votorix3saqa': 1 errors while checking lower checkfile vs. upper remote (with normalization) 2026/02/03 02:21:52 ERROR : hello, world!: file not in drime root 'rclone-test-votorix3saqa' 2026/02/03 02:21:52 NOTICE: drime root 'rclone-test-votorix3saqa': 1 files missing 2026/02/03 02:21:52 NOTICE: drime root 'rclone-test-votorix3saqa': 0 differences found 2026/02/03 02:21:52 NOTICE: drime root 'rclone-test-votorix3saqa': 1 errors while checking check_test.go:591: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:591 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:595 Error: Received unexpected error: file not in drime root 'rclone-test-votorix3saqa' Test: TestApplyTransforms Messages: unexpected error for lower checkfile vs. upper remote (with normalization) hello, world! HELLO, WORLD! 2026/02/03 02:21:52 DEBUG : Creating backend with remote "TestDrime:rclone-test-fuzacet4kada" 2026/02/03 02:21:52 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/02/03 02:21:53 DEBUG : Creating backend with remote "/tmp/rclone3159275294" run.go:185: Remote "drime root 'rclone-test-fuzacet4kada'", Local "Local file system at /tmp/rclone3159275294", Modify Window "876000h0m0s" lower checkfile vs. upperlowermixed remote (without normalization) 2026/02/03 02:21:56 ERROR : hello, world!: file not in drime root 'rclone-test-fuzacet4kada' 2026/02/03 02:21:56 NOTICE: drime root 'rclone-test-fuzacet4kada': 1 files missing 2026/02/03 02:21:56 NOTICE: drime root 'rclone-test-fuzacet4kada': 0 differences found 2026/02/03 02:21:56 NOTICE: drime root 'rclone-test-fuzacet4kada': 1 errors while checking lower checkfile vs. upperlowermixed remote (with normalization) 2026/02/03 02:21:56 ERROR : hello, world!: file not in drime root 'rclone-test-fuzacet4kada' 2026/02/03 02:21:56 NOTICE: drime root 'rclone-test-fuzacet4kada': 1 files missing 2026/02/03 02:21:56 NOTICE: drime root 'rclone-test-fuzacet4kada': 0 differences found 2026/02/03 02:21:56 NOTICE: drime root 'rclone-test-fuzacet4kada': 1 errors while checking check_test.go:591: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:591 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:596 Error: Received unexpected error: file not in drime root 'rclone-test-fuzacet4kada' Test: TestApplyTransforms Messages: unexpected error for lower checkfile vs. upperlowermixed remote (with normalization) hello, world! HeLlO, wOrLd! 2026/02/03 02:21:56 DEBUG : Creating backend with remote "TestDrime:rclone-test-kerexin5veyo" 2026/02/03 02:21:56 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/02/03 02:21:57 DEBUG : Creating backend with remote "/tmp/rclone94520626" run.go:185: Remote "drime root 'rclone-test-kerexin5veyo'", Local "Local file system at /tmp/rclone94520626", Modify Window "876000h0m0s" upperlowermixed checkfile vs. upper remote (without normalization) 2026/02/03 02:22:05 ERROR : HeLlO, wOrLd!: file not in drime root 'rclone-test-kerexin5veyo' 2026/02/03 02:22:05 NOTICE: drime root 'rclone-test-kerexin5veyo': 1 files missing 2026/02/03 02:22:05 NOTICE: drime root 'rclone-test-kerexin5veyo': 0 differences found 2026/02/03 02:22:05 NOTICE: drime root 'rclone-test-kerexin5veyo': 1 errors while checking upperlowermixed checkfile vs. upper remote (with normalization) 2026/02/03 02:22:05 ERROR : hello, world!: file not in drime root 'rclone-test-kerexin5veyo' 2026/02/03 02:22:05 NOTICE: drime root 'rclone-test-kerexin5veyo': 1 files missing 2026/02/03 02:22:05 NOTICE: drime root 'rclone-test-kerexin5veyo': 0 differences found 2026/02/03 02:22:05 NOTICE: drime root 'rclone-test-kerexin5veyo': 1 errors while checking check_test.go:591: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:591 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:597 Error: Received unexpected error: file not in drime root 'rclone-test-kerexin5veyo' Test: TestApplyTransforms Messages: unexpected error for upperlowermixed checkfile vs. upper remote (with normalization) HeLlO, wOrLd! HELLO, WORLD! 2026/02/03 02:22:05 DEBUG : Creating backend with remote "TestDrime:rclone-test-sadopaq5yimi" 2026/02/03 02:22:05 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/02/03 02:22:06 DEBUG : Creating backend with remote "/tmp/rclone157726679" run.go:185: Remote "drime root 'rclone-test-sadopaq5yimi'", Local "Local file system at /tmp/rclone157726679", Modify Window "876000h0m0s" NFD checkfile vs. NFC remote (without normalization) 2026/02/03 02:22:09 ERROR : 測試_Русский___ě_áñ: file not in drime root 'rclone-test-sadopaq5yimi' 2026/02/03 02:22:09 NOTICE: drime root 'rclone-test-sadopaq5yimi': 1 files missing 2026/02/03 02:22:09 NOTICE: drime root 'rclone-test-sadopaq5yimi': 0 differences found 2026/02/03 02:22:09 NOTICE: drime root 'rclone-test-sadopaq5yimi': 1 errors while checking NFD checkfile vs. NFC remote (with normalization) 2026/02/03 02:22:09 ERROR : 測試_русский___ě_áñ: file not in drime root 'rclone-test-sadopaq5yimi' 2026/02/03 02:22:09 NOTICE: drime root 'rclone-test-sadopaq5yimi': 1 files missing 2026/02/03 02:22:09 NOTICE: drime root 'rclone-test-sadopaq5yimi': 0 differences found 2026/02/03 02:22:09 NOTICE: drime root 'rclone-test-sadopaq5yimi': 1 errors while checking check_test.go:591: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:591 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:598 Error: Received unexpected error: file not in drime root 'rclone-test-sadopaq5yimi' Test: TestApplyTransforms Messages: unexpected error for NFD checkfile vs. NFC remote (with normalization) 測試_Русский___ě_áñ 測試_Русский___ě_áñ 2026/02/03 02:22:09 DEBUG : Creating backend with remote "TestDrime:rclone-test-sigaluq6hevo" 2026/02/03 02:22:09 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/02/03 02:22:10 DEBUG : Creating backend with remote "/tmp/rclone3931704840" run.go:185: Remote "drime root 'rclone-test-sigaluq6hevo'", Local "Local file system at /tmp/rclone3931704840", Modify Window "876000h0m0s" NFC checkfile vs. NFD remote (without normalization) 2026/02/03 02:22:15 ERROR : 測試_Русский___ě_áñ: file not in drime root 'rclone-test-sigaluq6hevo' 2026/02/03 02:22:15 NOTICE: drime root 'rclone-test-sigaluq6hevo': 1 files missing 2026/02/03 02:22:15 NOTICE: drime root 'rclone-test-sigaluq6hevo': 0 differences found 2026/02/03 02:22:15 NOTICE: drime root 'rclone-test-sigaluq6hevo': 1 errors while checking NFC checkfile vs. NFD remote (with normalization) 2026/02/03 02:22:15 ERROR : 測試_русский___ě_áñ: file not in drime root 'rclone-test-sigaluq6hevo' 2026/02/03 02:22:15 NOTICE: drime root 'rclone-test-sigaluq6hevo': 1 files missing 2026/02/03 02:22:15 NOTICE: drime root 'rclone-test-sigaluq6hevo': 0 differences found 2026/02/03 02:22:15 NOTICE: drime root 'rclone-test-sigaluq6hevo': 1 errors while checking check_test.go:591: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:591 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:599 Error: Received unexpected error: file not in drime root 'rclone-test-sigaluq6hevo' Test: TestApplyTransforms Messages: unexpected error for NFC checkfile vs. NFD remote (with normalization) 測試_Русский___ě_áñ 測試_Русский___ě_áñ 2026/02/03 02:22:15 DEBUG : Creating backend with remote "TestDrime:rclone-test-hupehot2kezo" 2026/02/03 02:22:15 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/02/03 02:22:16 DEBUG : Creating backend with remote "/tmp/rclone3490579351" run.go:185: Remote "drime root 'rclone-test-hupehot2kezo'", Local "Local file system at /tmp/rclone3490579351", Modify Window "876000h0m0s" NFDx2 checkfile vs. both remote (without normalization) 2026/02/03 02:22:19 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in drime root 'rclone-test-hupehot2kezo' 2026/02/03 02:22:19 NOTICE: drime root 'rclone-test-hupehot2kezo': 1 files missing 2026/02/03 02:22:19 NOTICE: drime root 'rclone-test-hupehot2kezo': 0 differences found 2026/02/03 02:22:19 NOTICE: drime root 'rclone-test-hupehot2kezo': 1 errors while checking NFDx2 checkfile vs. both remote (with normalization) 2026/02/03 02:22:20 ERROR : 測試_русский___ě_áñ測試_русский___ě_áñ: file not in drime root 'rclone-test-hupehot2kezo' 2026/02/03 02:22:20 NOTICE: drime root 'rclone-test-hupehot2kezo': 1 files missing 2026/02/03 02:22:20 NOTICE: drime root 'rclone-test-hupehot2kezo': 0 differences found 2026/02/03 02:22:20 NOTICE: drime root 'rclone-test-hupehot2kezo': 1 errors while checking check_test.go:591: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:591 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:600 Error: Received unexpected error: file not in drime root 'rclone-test-hupehot2kezo' Test: TestApplyTransforms Messages: unexpected error for NFDx2 checkfile vs. both remote (with normalization) 測試_Русский___ě_áñ測試_Русский___ě_áñ 測試_Русский___ě_áñ測試_Русский___ě_áñ 2026/02/03 02:22:20 DEBUG : Creating backend with remote "TestDrime:rclone-test-bucuwev7jopo" 2026/02/03 02:22:20 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/02/03 02:22:20 DEBUG : Creating backend with remote "/tmp/rclone2025079318" run.go:185: Remote "drime root 'rclone-test-bucuwev7jopo'", Local "Local file system at /tmp/rclone2025079318", Modify Window "876000h0m0s" NFCx2 checkfile vs. both remote (without normalization) 2026/02/03 02:22:23 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in drime root 'rclone-test-bucuwev7jopo' 2026/02/03 02:22:23 NOTICE: drime root 'rclone-test-bucuwev7jopo': 1 files missing 2026/02/03 02:22:23 NOTICE: drime root 'rclone-test-bucuwev7jopo': 0 differences found 2026/02/03 02:22:23 NOTICE: drime root 'rclone-test-bucuwev7jopo': 1 errors while checking NFCx2 checkfile vs. both remote (with normalization) 2026/02/03 02:22:24 ERROR : 測試_русский___ě_áñ測試_русский___ě_áñ: file not in drime root 'rclone-test-bucuwev7jopo' 2026/02/03 02:22:24 NOTICE: drime root 'rclone-test-bucuwev7jopo': 1 files missing 2026/02/03 02:22:24 NOTICE: drime root 'rclone-test-bucuwev7jopo': 0 differences found 2026/02/03 02:22:24 NOTICE: drime root 'rclone-test-bucuwev7jopo': 1 errors while checking check_test.go:591: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:591 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:601 Error: Received unexpected error: file not in drime root 'rclone-test-bucuwev7jopo' Test: TestApplyTransforms Messages: unexpected error for NFCx2 checkfile vs. both remote (with normalization) 測試_Русский___ě_áñ測試_Русский___ě_áñ 測試_Русский___ě_áñ測試_Русский___ě_áñ 2026/02/03 02:22:24 DEBUG : Creating backend with remote "TestDrime:rclone-test-bovezec1tite" 2026/02/03 02:22:24 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/02/03 02:22:24 DEBUG : Creating backend with remote "/tmp/rclone3137824430" run.go:185: Remote "drime root 'rclone-test-bovezec1tite'", Local "Local file system at /tmp/rclone3137824430", Modify Window "876000h0m0s" both checkfile vs. NFDx2 remote (without normalization) 2026/02/03 02:22:28 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in drime root 'rclone-test-bovezec1tite' 2026/02/03 02:22:28 NOTICE: drime root 'rclone-test-bovezec1tite': 1 files missing 2026/02/03 02:22:28 NOTICE: drime root 'rclone-test-bovezec1tite': 0 differences found 2026/02/03 02:22:28 NOTICE: drime root 'rclone-test-bovezec1tite': 1 errors while checking both checkfile vs. NFDx2 remote (with normalization) 2026/02/03 02:22:28 ERROR : 測試_русский___ě_áñ測試_русский___ě_áñ: file not in drime root 'rclone-test-bovezec1tite' 2026/02/03 02:22:28 NOTICE: drime root 'rclone-test-bovezec1tite': 1 files missing 2026/02/03 02:22:28 NOTICE: drime root 'rclone-test-bovezec1tite': 0 differences found 2026/02/03 02:22:28 NOTICE: drime root 'rclone-test-bovezec1tite': 1 errors while checking check_test.go:591: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:591 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:602 Error: Received unexpected error: file not in drime root 'rclone-test-bovezec1tite' Test: TestApplyTransforms Messages: unexpected error for both checkfile vs. NFDx2 remote (with normalization) 測試_Русский___ě_áñ測試_Русский___ě_áñ 測試_Русский___ě_áñ測試_Русский___ě_áñ 2026/02/03 02:22:28 DEBUG : Creating backend with remote "TestDrime:rclone-test-zadifev6wahi" 2026/02/03 02:22:28 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/02/03 02:22:29 DEBUG : Creating backend with remote "/tmp/rclone264734004" run.go:185: Remote "drime root 'rclone-test-zadifev6wahi'", Local "Local file system at /tmp/rclone264734004", Modify Window "876000h0m0s" both checkfile vs. NFCx2 remote (without normalization) 2026/02/03 02:22:33 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in drime root 'rclone-test-zadifev6wahi' 2026/02/03 02:22:33 NOTICE: drime root 'rclone-test-zadifev6wahi': 1 files missing 2026/02/03 02:22:33 NOTICE: drime root 'rclone-test-zadifev6wahi': 0 differences found 2026/02/03 02:22:33 NOTICE: drime root 'rclone-test-zadifev6wahi': 1 errors while checking both checkfile vs. NFCx2 remote (with normalization) 2026/02/03 02:22:33 ERROR : 測試_русский___ě_áñ測試_русский___ě_áñ: file not in drime root 'rclone-test-zadifev6wahi' 2026/02/03 02:22:33 NOTICE: drime root 'rclone-test-zadifev6wahi': 1 files missing 2026/02/03 02:22:33 NOTICE: drime root 'rclone-test-zadifev6wahi': 0 differences found 2026/02/03 02:22:33 NOTICE: drime root 'rclone-test-zadifev6wahi': 1 errors while checking check_test.go:591: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:591 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:603 Error: Received unexpected error: file not in drime root 'rclone-test-zadifev6wahi' Test: TestApplyTransforms Messages: unexpected error for both checkfile vs. NFCx2 remote (with normalization) 測試_Русский___ě_áñ測試_Русский___ě_áñ 測試_Русский___ě_áñ測試_Русский___ě_áñ 2026/02/03 02:22:33 DEBUG : drime root 'rclone-test-zadifev6wahi': Purge remote 2026/02/03 02:22:34 DEBUG : drime root 'rclone-test-bovezec1tite': Purge remote 2026/02/03 02:22:34 DEBUG : drime root 'rclone-test-bucuwev7jopo': Purge remote 2026/02/03 02:22:34 DEBUG : drime root 'rclone-test-hupehot2kezo': Purge remote 2026/02/03 02:22:34 DEBUG : drime root 'rclone-test-sigaluq6hevo': Purge remote 2026/02/03 02:22:34 DEBUG : drime root 'rclone-test-sadopaq5yimi': Purge remote 2026/02/03 02:22:35 DEBUG : drime root 'rclone-test-kerexin5veyo': Purge remote 2026/02/03 02:22:35 DEBUG : drime root 'rclone-test-fuzacet4kada': Purge remote 2026/02/03 02:22:35 DEBUG : drime root 'rclone-test-votorix3saqa': Purge remote 2026/02/03 02:22:35 DEBUG : drime root 'rclone-test-tobiduy1cejo': Purge remote --- FAIL: TestApplyTransforms (53.29s) === RUN TestTruncateString --- PASS: TestTruncateString (0.00s) === RUN TestCopyFile run.go:185: Remote "drime root 'rclone-test-yohamax0qoza'", Local "Local file system at /tmp/rclone1795778209", Modify Window "876000h0m0s" 2026/02/03 02:22:37 DEBUG : file1: Need to transfer - File not found at Destination 2026/02/03 02:22:39 DEBUG : sub/file2: size = 14 OK 2026/02/03 02:22:39 INFO : file1: Copied (new) to: sub/file2 2026/02/03 02:22:40 DEBUG : sub/file2: size = 14 OK 2026/02/03 02:22:40 DEBUG : file1: Sizes identical 2026/02/03 02:22:40 DEBUG : file1: Unchanged skipping 2026/02/03 02:22:40 DEBUG : drime root 'rclone-test-yohamax0qoza': don't need to copy/move sub/file2, it is already at target location run.go:130: removing dir "sub" failed - try 1/3: directory not empty run.go:130: removing dir "sub" failed - try 2/3: directory not empty run.go:130: removing dir "sub" failed - try 3/3: directory not empty run.go:133: removing dir "sub" failed: directory not empty fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:327: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:327 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393 /usr/local/go/src/testing/testing.go:1211 /usr/local/go/src/testing/testing.go:1445 /usr/local/go/src/testing/testing.go:1786 Error: Not equal: expected: []string{} actual : []string{"sub"} Diff: --- Expected +++ Actual @@ -1,2 +1,3 @@ -([]string) { +([]string) (len=1) { + (string) (len=3) "sub" } Test: TestCopyFile Messages: directories --- FAIL: TestCopyFile (19.06s) === RUN TestCopyLongFile run.go:185: Remote "drime root 'rclone-test-yohamax0qoza'", Local "Local file system at /tmp/rclone1795778209", Modify Window "876000h0m0s" copy_test.go:154: Test only runs on local fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:292: Sleeping for 7s just to make sure --- SKIP: TestCopyLongFile (13.55s) === RUN TestCopyFileBackupDir run.go:185: Remote "drime root 'rclone-test-yohamax0qoza'", Local "Local file system at /tmp/rclone1795778209", Modify Window "876000h0m0s" fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:292: Sleeping for 6s just to make sure 2026/02/03 02:23:19 DEBUG : Creating backend with remote "TestDrime:rclone-test-yohamax0qoza/backup" 2026/02/03 02:23:20 DEBUG : dst/file1: size = 14 (Local file system at /tmp/rclone1795778209) 2026/02/03 02:23:20 DEBUG : dst/file1: size = 18 (drime root 'rclone-test-yohamax0qoza') 2026/02/03 02:23:20 DEBUG : dst/file1: Sizes differ 2026/02/03 02:23:21 INFO : dst/file1: Moved (server-side) 2026/02/03 02:23:24 DEBUG : dst/file1: Removing old object on successful upload 2026/02/03 02:23:24 DEBUG : dst/file1: size = 14 OK 2026/02/03 02:23:24 INFO : dst/file1: Copied (new) 2026/02/03 02:23:25 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/03 02:23:25 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2026/02/03 02:23:26 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/03 02:23:26 DEBUG : pacer: Rate limited, increasing sleep to 40ms 2026/02/03 02:23:26 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/03 02:23:26 DEBUG : pacer: Rate limited, increasing sleep to 80ms 2026/02/03 02:23:26 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/03 02:23:26 DEBUG : pacer: Rate limited, increasing sleep to 160ms 2026/02/03 02:23:26 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/03 02:23:26 DEBUG : pacer: Rate limited, increasing sleep to 320ms 2026/02/03 02:23:26 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/03 02:23:26 DEBUG : pacer: Rate limited, increasing sleep to 640ms 2026/02/03 02:23:27 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/03 02:23:27 DEBUG : pacer: Rate limited, increasing sleep to 1.28s 2026/02/03 02:23:28 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/03 02:23:28 DEBUG : pacer: Rate limited, increasing sleep to 2.56s 2026/02/03 02:23:29 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/03 02:23:29 DEBUG : pacer: Rate limited, increasing sleep to 5.12s 2026/02/03 02:23:31 DEBUG : pacer: low level retry 10/10 (error Error "Server Error") 2026/02/03 02:23:31 DEBUG : pacer: Rate limited, increasing sleep to 10.24s run.go:130: removing file "backup/dst/file1" failed - try 1/3: failed to delete item: Error "Server Error" 2026/02/03 02:23:36 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/03 02:23:36 DEBUG : pacer: Rate limited, increasing sleep to 20s 2026/02/03 02:23:47 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/03 02:24:07 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/03 02:24:27 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/03 02:24:47 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/03 02:25:07 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/03 02:25:27 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/03 02:25:47 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/03 02:26:07 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/03 02:26:27 DEBUG : pacer: low level retry 10/10 (error Error "Server Error") run.go:130: removing file "backup/dst/file1" failed - try 2/3: failed to delete item: Error "Server Error" 2026/02/03 02:26:47 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/03 02:27:07 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/03 02:27:27 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/03 02:27:47 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/03 02:28:07 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/03 02:28:27 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/03 02:28:47 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/03 02:29:07 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/03 02:29:27 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/03 02:29:47 DEBUG : pacer: low level retry 10/10 (error Error "Server Error") run.go:130: removing file "backup/dst/file1" failed - try 3/3: failed to delete item: Error "Server Error" run.go:133: removing file "backup/dst/file1" failed: failed to delete item: Error "Server Error" 2026/02/03 02:30:07 DEBUG : pacer: Reducing sleep to 10s 2026/02/03 02:30:27 DEBUG : pacer: Reducing sleep to 5s 2026/02/03 02:30:37 DEBUG : pacer: Reducing sleep to 2.5s 2026/02/03 02:30:42 DEBUG : pacer: Reducing sleep to 1.25s 2026/02/03 02:30:44 DEBUG : pacer: Reducing sleep to 625ms 2026/02/03 02:30:46 DEBUG : pacer: Reducing sleep to 312.5ms 2026/02/03 02:30:46 DEBUG : pacer: Reducing sleep to 156.25ms --- PASS: TestCopyFileBackupDir (457.92s) === RUN TestCopyFileCompareDest run.go:185: Remote "drime root 'rclone-test-yohamax0qoza'", Local "Local file system at /tmp/rclone1795778209", Modify Window "876000h0m0s" 2026/02/03 02:30:46 DEBUG : Creating backend with remote "TestDrime:rclone-test-yohamax0qoza/dst" 2026/02/03 02:30:46 DEBUG : Config file has changed externally - reloading 2026/02/03 02:30:48 DEBUG : Creating backend with remote "TestDrime:rclone-test-yohamax0qoza/CompareDest" 2026/02/03 02:30:49 DEBUG : one: Need to transfer - File not found at Destination 2026/02/03 02:30:52 DEBUG : one: size = 3 OK 2026/02/03 02:30:52 INFO : one: Copied (new) 2026/02/03 02:30:52 DEBUG : pacer: Reducing sleep to 78.125ms fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache 2026/02/03 02:30:54 DEBUG : pacer: Reducing sleep to 39.0625ms 2026/02/03 02:30:54 DEBUG : pacer: Reducing sleep to 19.53125ms fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache 2026/02/03 02:30:56 DEBUG : pacer: Reducing sleep to 10ms fstest.go:292: Sleeping for 7s just to make sure 2026/02/03 02:31:04 DEBUG : one: size = 5 (Local file system at /tmp/rclone1795778209) 2026/02/03 02:31:04 DEBUG : one: size = 3 (drime root 'rclone-test-yohamax0qoza/dst') 2026/02/03 02:31:04 DEBUG : one: Sizes differ 2026/02/03 02:31:09 DEBUG : one: Removing old object on successful upload 2026/02/03 02:31:09 DEBUG : one: size = 5 OK 2026/02/03 02:31:09 INFO : one: Copied (replaced existing) 2026/02/03 02:31:12 DEBUG : dst/one: Removing old object on successful upload fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:292: Sleeping for 6s just to make sure 2026/02/03 02:31:27 DEBUG : one: size = 5 (Local file system at /tmp/rclone1795778209) 2026/02/03 02:31:27 DEBUG : one: size = 3 (drime root 'rclone-test-yohamax0qoza/dst') 2026/02/03 02:31:27 DEBUG : one: Sizes differ 2026/02/03 02:31:27 DEBUG : one: size = 5 OK 2026/02/03 02:31:27 DEBUG : one: Sizes identical 2026/02/03 02:31:27 DEBUG : one: Destination found in --compare-dest, skipping 2026/02/03 02:31:36 DEBUG : two: Need to transfer - File not found at Destination 2026/02/03 02:31:36 DEBUG : two: size = 3 OK 2026/02/03 02:31:36 DEBUG : two: Sizes identical 2026/02/03 02:31:36 DEBUG : two: Destination found in --compare-dest, skipping 2026/02/03 02:31:37 DEBUG : two: Need to transfer - File not found at Destination 2026/02/03 02:31:37 DEBUG : two: size = 3 OK 2026/02/03 02:31:37 DEBUG : two: Sizes identical 2026/02/03 02:31:37 DEBUG : two: Destination found in --compare-dest, skipping 2026/02/03 02:31:38 DEBUG : two: Need to transfer - File not found at Destination 2026/02/03 02:31:38 DEBUG : two: size = 5 (Local file system at /tmp/rclone1795778209) 2026/02/03 02:31:38 DEBUG : two: size = 3 (drime root 'rclone-test-yohamax0qoza/CompareDest') 2026/02/03 02:31:38 DEBUG : two: Sizes differ 2026/02/03 02:31:44 DEBUG : two: size = 5 OK 2026/02/03 02:31:44 INFO : two: Copied (new) fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:292: Sleeping for 6s just to make sure run.go:130: removing dir "dst" failed - try 1/3: directory not empty run.go:130: removing dir "dst" failed - try 2/3: directory not empty --- PASS: TestCopyFileCompareDest (70.46s) === RUN TestCopyFileCopyDest run.go:185: Remote "drime root 'rclone-test-yohamax0qoza'", Local "Local file system at /tmp/rclone1795778209", Modify Window "876000h0m0s" 2026/02/03 02:31:57 DEBUG : Creating backend with remote "TestDrime:rclone-test-yohamax0qoza/dst" 2026/02/03 02:31:58 DEBUG : Creating backend with remote "TestDrime:rclone-test-yohamax0qoza/CopyDest" 2026/02/03 02:32:00 DEBUG : one: Need to transfer - File not found at Destination 2026/02/03 02:32:03 DEBUG : one: size = 3 OK 2026/02/03 02:32:03 INFO : one: Copied (new) fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:292: Sleeping for 7s just to make sure 2026/02/03 02:32:15 DEBUG : one: size = 5 (Local file system at /tmp/rclone1795778209) 2026/02/03 02:32:15 DEBUG : one: size = 3 (drime root 'rclone-test-yohamax0qoza/dst') 2026/02/03 02:32:15 DEBUG : one: Sizes differ 2026/02/03 02:32:17 DEBUG : one: Removing old object on successful upload 2026/02/03 02:32:17 DEBUG : one: size = 5 OK 2026/02/03 02:32:17 INFO : one: Copied (replaced existing) 2026/02/03 02:32:21 DEBUG : dst/one: Removing old object on successful upload fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:292: Sleeping for 7s just to make sure 2026/02/03 02:32:36 DEBUG : Creating backend with remote "TestDrime:rclone-test-yohamax0qoza/BackupDir" 2026/02/03 02:32:38 DEBUG : one: size = 5 (Local file system at /tmp/rclone1795778209) 2026/02/03 02:32:38 DEBUG : one: size = 3 (drime root 'rclone-test-yohamax0qoza/dst') 2026/02/03 02:32:38 DEBUG : one: Sizes differ 2026/02/03 02:32:38 DEBUG : one: size = 5 OK 2026/02/03 02:32:38 DEBUG : one: Sizes identical 2026/02/03 02:32:38 DEBUG : one: size = 5 (Local file system at /tmp/rclone1795778209) 2026/02/03 02:32:38 DEBUG : one: size = 3 (drime root 'rclone-test-yohamax0qoza/dst') 2026/02/03 02:32:38 DEBUG : one: Sizes differ 2026/02/03 02:32:39 INFO : one: Moved (server-side) 2026/02/03 02:32:40 DEBUG : one: size = 5 OK 2026/02/03 02:32:40 INFO : one: Copied (server-side copy) 2026/02/03 02:32:40 DEBUG : one: Destination found in --copy-dest, using server-side copy fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/copy_test.go:327 Error: Should be true Test: TestCopyFileCopyDest Messages: listing wrong, want BackupDir/one (3), CopyDest/one (5), dst/one (5) got BackupDir/one (3), CopyDest/one (5) fstest.go:203: Not found "dst/one" fstest.go:206: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:206 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:310 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/copy_test.go:327 /usr/local/go/src/runtime/asm_amd64.s:1700 Error: Not equal: expected: 0 actual : 1 Test: TestCopyFileCopyDest Messages: 1 objects not found 2026/02/03 02:32:59 DEBUG : two: Need to transfer - File not found at Destination 2026/02/03 02:32:59 DEBUG : two: size = 3 OK 2026/02/03 02:32:59 DEBUG : two: Sizes identical 2026/02/03 02:33:00 DEBUG : two: size = 3 OK 2026/02/03 02:33:00 INFO : two: Copied (server-side copy) 2026/02/03 02:33:00 DEBUG : two: Destination found in --copy-dest, using server-side copy fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/copy_test.go:342 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 BackupDir/one (3), CopyDest/one (5), CopyDest/two (3), dst/one (5) fstest.go:203: Not found "dst/two" fstest.go:206: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:206 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:310 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/copy_test.go:342 /usr/local/go/src/runtime/asm_amd64.s:1700 Error: Not equal: expected: 0 actual : 1 Test: TestCopyFileCopyDest Messages: 1 objects not found 2026/02/03 02:33:09 DEBUG : two: size = 3 OK 2026/02/03 02:33:09 DEBUG : two: Sizes identical 2026/02/03 02:33:09 DEBUG : two: Unchanged skipping fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/copy_test.go:353 Error: Should be true Test: TestCopyFileCopyDest Messages: listing wrong, want BackupDir/one (3), CopyDest/one (5), CopyDest/three (5), CopyDest/two (3), dst/one (5), dst/two (3) got BackupDir/one (3), CopyDest/one (5), CopyDest/two (3), dst/one (5), dst/two (3) fstest.go:203: Not found "CopyDest/three" fstest.go:206: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:206 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:310 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/copy_test.go:353 /usr/local/go/src/runtime/asm_amd64.s:1700 Error: Not equal: expected: 0 actual : 1 Test: TestCopyFileCopyDest Messages: 1 objects not found 2026/02/03 02:33:23 DEBUG : three: Need to transfer - File not found at Destination 2026/02/03 02:33:24 DEBUG : three: size = 7 (Local file system at /tmp/rclone1795778209) 2026/02/03 02:33:24 DEBUG : three: size = 5 (drime root 'rclone-test-yohamax0qoza/CopyDest') 2026/02/03 02:33:24 DEBUG : three: Sizes differ 2026/02/03 02:33:24 DEBUG : three: Destination not found in --copy-dest 2026/02/03 02:33:26 DEBUG : three: size = 7 OK 2026/02/03 02:33:26 INFO : three: Copied (new) fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/copy_test.go:362 Error: Should be true Test: TestCopyFileCopyDest Messages: listing wrong, want BackupDir/one (3), CopyDest/one (5), CopyDest/three (5), CopyDest/two (3), dst/one (5), dst/three (7), dst/two (3) got BackupDir/one (3), CopyDest/one (5), CopyDest/three (5), CopyDest/two (3), dst/one (5), dst/two (3) fstest.go:203: Not found "dst/three" fstest.go:206: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:206 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:310 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/copy_test.go:362 /usr/local/go/src/runtime/asm_amd64.s:1700 Error: Not equal: expected: 0 actual : 1 Test: TestCopyFileCopyDest Messages: 1 objects not found run.go:130: removing dir "dst" failed - try 1/3: directory not empty run.go:130: removing dir "dst" failed - try 2/3: directory not empty run.go:130: removing dir "dst" failed - try 3/3: directory not empty run.go:133: removing dir "dst" failed: directory not empty run.go:130: removing dir "CopyDest" failed - try 1/3: directory not empty run.go:130: removing dir "CopyDest" failed - try 2/3: directory not empty run.go:130: removing dir "CopyDest" failed - try 3/3: directory not empty run.go:133: removing dir "CopyDest" failed: directory not empty fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:327: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:327 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393 /usr/local/go/src/testing/testing.go:1211 /usr/local/go/src/testing/testing.go:1445 /usr/local/go/src/testing/testing.go:1786 Error: Not equal: expected: []string{} actual : []string{"CopyDest", "dst"} Diff: --- Expected +++ Actual @@ -1,2 +1,4 @@ -([]string) { +([]string) (len=2) { + (string) (len=8) "CopyDest", + (string) (len=3) "dst" } Test: TestCopyFileCopyDest Messages: directories --- FAIL: TestCopyFileCopyDest (118.26s) === RUN TestCopyInplace run.go:185: Remote "drime root 'rclone-test-yohamax0qoza'", Local "Local file system at /tmp/rclone1795778209", Modify Window "876000h0m0s" copy_test.go:371: Partial uploads not supported --- SKIP: TestCopyInplace (2.56s) === RUN TestCopyLongFileName run.go:185: Remote "drime root 'rclone-test-yohamax0qoza'", Local "Local file system at /tmp/rclone1795778209", Modify Window "876000h0m0s" copy_test.go:404: Partial uploads not supported --- SKIP: TestCopyLongFileName (0.41s) === RUN TestCopyLongFileNameCollision run.go:185: Remote "drime root 'rclone-test-yohamax0qoza'", Local "Local file system at /tmp/rclone1795778209", Modify Window "876000h0m0s" copy_test.go:437: Partial uploads not supported --- SKIP: TestCopyLongFileNameCollision (0.33s) === RUN TestCopyFileMaxTransfer run.go:185: Remote "drime root 'rclone-test-yohamax0qoza'", Local "Local file system at /tmp/rclone1795778209", Modify Window "876000h0m0s" 2026/02/03 02:33:58 DEBUG : TestCopyFileMaxTransfer/file1: Need to transfer - File not found at Destination 2026/02/03 02:34:05 DEBUG : TestCopyFileMaxTransfer/file1: size = 14 OK 2026/02/03 02:34:05 INFO : TestCopyFileMaxTransfer/file1: Copied (new) fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:292: Sleeping for 7s just to make sure 2026/02/03 02:34:17 DEBUG : TestCopyFileMaxTransfer/file2: Need to transfer - File not found at Destination 2026/02/03 02:34:17 ERROR : TestCopyFileMaxTransfer/file2: Failed to copy: failed to upload file: Post "https://app.drime.cloud/api/v1/uploads": failed to copy data: max transfer limit reached as set by --max-transfer 2026/02/03 02:34:18 DEBUG : TestCopyFileMaxTransfer/file3: Need to transfer - File not found at Destination 2026/02/03 02:34:19 DEBUG : TestCopyFileMaxTransfer/file4: Need to transfer - File not found at Destination 2026/02/03 02:34:21 DEBUG : TestCopyFileMaxTransfer/file4: size = 2062 OK 2026/02/03 02:34:21 INFO : TestCopyFileMaxTransfer/file4: Copied (new) run.go:130: removing dir "TestCopyFileMaxTransfer" failed - try 1/3: directory not empty run.go:130: removing dir "TestCopyFileMaxTransfer" failed - try 2/3: directory not empty run.go:130: removing dir "TestCopyFileMaxTransfer" failed - try 3/3: directory not empty run.go:133: removing dir "TestCopyFileMaxTransfer" failed: directory not empty fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:327: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:327 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393 /usr/local/go/src/testing/testing.go:1211 /usr/local/go/src/testing/testing.go:1445 /usr/local/go/src/testing/testing.go:1786 Error: Not equal: expected: []string{} actual : []string{"TestCopyFileMaxTransfer"} Diff: --- Expected +++ Actual @@ -1,2 +1,3 @@ -([]string) { +([]string) (len=1) { + (string) (len=23) "TestCopyFileMaxTransfer" } Test: TestCopyFileMaxTransfer Messages: directories --- FAIL: TestCopyFileMaxTransfer (38.78s) === RUN TestDeduplicateInteractive run.go:185: Remote "drime root 'rclone-test-yohamax0qoza'", Local "Local file system at /tmp/rclone1795778209", Modify Window "876000h0m0s" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:292: Sleeping for 7s just to make sure --- SKIP: TestDeduplicateInteractive (13.21s) === RUN TestDeduplicateSkip run.go:185: Remote "drime root 'rclone-test-yohamax0qoza'", Local "Local file system at /tmp/rclone1795778209", Modify Window "876000h0m0s" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateSkip (0.40s) === RUN TestDeduplicateSizeOnly run.go:185: Remote "drime root 'rclone-test-yohamax0qoza'", Local "Local file system at /tmp/rclone1795778209", Modify Window "876000h0m0s" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateSizeOnly (0.36s) === RUN TestDeduplicateFirst run.go:185: Remote "drime root 'rclone-test-yohamax0qoza'", Local "Local file system at /tmp/rclone1795778209", Modify Window "876000h0m0s" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateFirst (1.10s) === RUN TestDeduplicateNewest run.go:185: Remote "drime root 'rclone-test-yohamax0qoza'", Local "Local file system at /tmp/rclone1795778209", Modify Window "876000h0m0s" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateNewest (0.50s) === RUN TestDeduplicateNewestByHash run.go:185: Remote "drime root 'rclone-test-yohamax0qoza'", Local "Local file system at /tmp/rclone1795778209", Modify Window "876000h0m0s" dedupe_test.go:36: Can't run this test without a hash --- SKIP: TestDeduplicateNewestByHash (0.58s) === RUN TestDeduplicateOldest run.go:185: Remote "drime root 'rclone-test-yohamax0qoza'", Local "Local file system at /tmp/rclone1795778209", Modify Window "876000h0m0s" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateOldest (1.14s) === RUN TestDeduplicateLargest run.go:185: Remote "drime root 'rclone-test-yohamax0qoza'", Local "Local file system at /tmp/rclone1795778209", Modify Window "876000h0m0s" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateLargest (0.75s) === RUN TestDeduplicateSmallest run.go:185: Remote "drime root 'rclone-test-yohamax0qoza'", Local "Local file system at /tmp/rclone1795778209", Modify Window "876000h0m0s" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateSmallest (1.06s) === RUN TestDeduplicateRename run.go:185: Remote "drime root 'rclone-test-yohamax0qoza'", Local "Local file system at /tmp/rclone1795778209", Modify Window "876000h0m0s" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateRename (0.42s) === RUN TestMergeDirs run.go:185: Remote "drime root 'rclone-test-yohamax0qoza'", Local "Local file system at /tmp/rclone1795778209", Modify Window "876000h0m0s" dedupe_test.go:256: Can't merge directories --- SKIP: TestMergeDirs (0.42s) === RUN TestListDirSorted run.go:185: Remote "drime root 'rclone-test-yohamax0qoza'", Local "Local file system at /tmp/rclone1795778209", Modify Window "876000h0m0s" fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:292: Sleeping for 6s just to make sure 2026/02/03 02:35:30 DEBUG : a.txt: Excluded (Size Filter) 2026/02/03 02:35:30 DEBUG : a.txt: Excluded 2026/02/03 02:35:30 DEBUG : sub dir/hello world2: Excluded (Size Filter) 2026/02/03 02:35:30 DEBUG : sub dir/hello world2: Excluded 2026/02/03 02:35:30 DEBUG : sub dir/hello world: Excluded (Size Filter) 2026/02/03 02:35:30 DEBUG : sub dir/hello world: Excluded 2026/02/03 02:35:31 DEBUG : sub dir/ignore dir: Excluded 2026/02/03 02:35:31 DEBUG : sub dir/hello world2: Excluded (Size Filter) 2026/02/03 02:35:31 DEBUG : sub dir/hello world2: Excluded 2026/02/03 02:35:31 DEBUG : sub dir/hello world: Excluded (Size Filter) 2026/02/03 02:35:31 DEBUG : sub dir/hello world: Excluded 2026/02/03 02:35:31 DEBUG : sub dir/ignore dir: Excluded run.go:130: removing dir "sub dir/sub sub dir" failed - try 1/3: directory not empty run.go:130: removing dir "sub dir/sub sub dir" failed - try 2/3: directory not empty run.go:130: removing dir "sub dir/sub sub dir" failed - try 3/3: directory not empty run.go:133: removing dir "sub dir/sub sub dir" failed: directory not empty run.go:130: removing dir "sub dir/ignore dir" failed - try 1/3: directory not empty run.go:130: removing dir "sub dir/ignore dir" failed - try 2/3: directory not empty run.go:130: removing dir "sub dir/ignore dir" failed - try 3/3: directory not empty run.go:133: removing dir "sub dir/ignore dir" failed: directory not empty run.go:130: removing dir "sub dir" failed - try 1/3: directory not empty run.go:130: removing dir "sub dir" failed - try 2/3: directory not empty run.go:130: removing dir "sub dir" failed - try 3/3: directory not empty run.go:133: removing dir "sub dir" failed: directory not empty fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:327: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:327 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393 /usr/local/go/src/testing/testing.go:1211 /usr/local/go/src/testing/testing.go:1445 /usr/local/go/src/testing/testing.go:1786 Error: Not equal: expected: []string{} actual : []string{"sub dir", "sub dir/ignore dir", "sub dir/sub sub dir"} Diff: --- Expected +++ Actual @@ -1,2 +1,5 @@ -([]string) { +([]string) (len=3) { + (string) (len=7) "sub dir", + (string) (len=18) "sub dir/ignore dir", + (string) (len=19) "sub dir/sub sub dir" } Test: TestListDirSorted Messages: directories --- FAIL: TestListDirSorted (59.24s) === RUN TestListDirSortedFn run.go:185: Remote "drime root 'rclone-test-yohamax0qoza'", Local "Local file system at /tmp/rclone1795778209", Modify Window "876000h0m0s" fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/listdirsorted_test.go:36 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/listdirsorted_test.go:125 /usr/local/go/src/runtime/asm_amd64.s:1700 Error: Should be true Test: TestListDirSortedFn Messages: listing wrong, want 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), sub dir/sub sub dir/hello world3 (11), zend.txt (5) 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:203: Not found "sub dir/sub sub dir/hello world3" fstest.go:206: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:206 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:310 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/listdirsorted_test.go:36 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/listdirsorted_test.go:125 Error: Not equal: expected: 0 actual : 1 Test: TestListDirSortedFn Messages: 1 objects not found 2026/02/03 02:36:33 DEBUG : a.txt: Excluded (Size Filter) 2026/02/03 02:36:33 DEBUG : a.txt: Excluded 2026/02/03 02:36:33 DEBUG : sub dir/hello world2: Excluded (Size Filter) 2026/02/03 02:36:33 DEBUG : sub dir/hello world2: Excluded 2026/02/03 02:36:33 DEBUG : sub dir/hello world: Excluded (Size Filter) 2026/02/03 02:36:33 DEBUG : sub dir/hello world: Excluded 2026/02/03 02:36:34 DEBUG : sub dir/ignore dir: Excluded 2026/02/03 02:36:34 DEBUG : sub dir/hello world2: Excluded (Size Filter) 2026/02/03 02:36:34 DEBUG : sub dir/hello world2: Excluded 2026/02/03 02:36:34 DEBUG : sub dir/hello world: Excluded (Size Filter) 2026/02/03 02:36:34 DEBUG : sub dir/hello world: Excluded 2026/02/03 02:36:34 DEBUG : sub dir/ignore dir: Excluded run.go:130: removing dir "sub dir/sub sub dir" failed - try 1/3: directory not empty run.go:130: removing dir "sub dir/sub sub dir" failed - try 2/3: directory not empty run.go:130: removing dir "sub dir/sub sub dir" failed - try 3/3: directory not empty run.go:133: removing dir "sub dir/sub sub dir" failed: directory not empty run.go:130: removing dir "sub dir/ignore dir" failed - try 1/3: directory not empty run.go:130: removing dir "sub dir/ignore dir" failed - try 2/3: directory not empty run.go:130: removing dir "sub dir/ignore dir" failed - try 3/3: directory not empty run.go:133: removing dir "sub dir/ignore dir" failed: directory not empty run.go:130: removing dir "sub dir" failed - try 1/3: directory not empty run.go:130: removing dir "sub dir" failed - try 2/3: directory not empty run.go:130: removing dir "sub dir" failed - try 3/3: directory not empty run.go:133: removing dir "sub dir" failed: directory not empty fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:327: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:327 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393 /usr/local/go/src/testing/testing.go:1211 /usr/local/go/src/testing/testing.go:1445 /usr/local/go/src/testing/testing.go:1786 Error: Not equal: expected: []string{} actual : []string{"sub dir", "sub dir/ignore dir", "sub dir/sub sub dir"} Diff: --- Expected +++ Actual @@ -1,2 +1,5 @@ -([]string) { +([]string) (len=3) { + (string) (len=7) "sub dir", + (string) (len=18) "sub dir/ignore dir", + (string) (len=19) "sub dir/sub sub dir" } Test: TestListDirSortedFn Messages: directories --- FAIL: TestListDirSortedFn (62.75s) === RUN TestListJSON run.go:185: Remote "drime root 'rclone-test-yohamax0qoza'", Local "Local file system at /tmp/rclone1795778209", Modify Window "876000h0m0s" fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:292: Sleeping for 7s just to make sure === RUN TestListJSON/Default lsjson_test.go:202: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/lsjson_test.go:202 Error: Not equal: expected: 2 actual : 3 Test: TestListJSON/Default Messages: Wrong number of results === RUN TestListJSON/FilesOnly === RUN TestListJSON/DirsOnly lsjson_test.go:202: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/lsjson_test.go:202 Error: Not equal: expected: 1 actual : 2 Test: TestListJSON/DirsOnly Messages: Wrong number of results === RUN TestListJSON/Recurse lsjson_test.go:202: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/lsjson_test.go:202 Error: Not equal: expected: 3 actual : 6 Test: TestListJSON/Recurse Messages: Wrong number of results === RUN TestListJSON/SubDir === RUN TestListJSON/NoModTime === RUN TestListJSON/NoMimeType === RUN TestListJSON/ShowHash === RUN TestListJSON/HashTypes 2026/02/03 02:37:20 ERROR : file1: Failed to read hash: hash type not supported === RUN TestListJSON/Metadata lsjson_test.go:202: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/lsjson_test.go:202 Error: Not equal: expected: 2 actual : 3 Test: TestListJSON/Metadata Messages: Wrong number of results === NAME TestListJSON run.go:130: removing dir "sub dir" failed - try 1/3: directory not empty run.go:130: removing dir "sub dir" failed - try 2/3: directory not empty run.go:130: removing dir "sub dir" failed - try 3/3: directory not empty run.go:133: removing dir "sub dir" failed: directory not empty run.go:130: removing dir "sub" failed - try 1/3: directory not empty run.go:130: removing dir "sub" failed - try 2/3: directory not empty run.go:130: removing dir "sub" failed - try 3/3: directory not empty run.go:133: removing dir "sub" failed: directory not empty fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:327: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:327 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393 /usr/local/go/src/testing/testing.go:1211 /usr/local/go/src/testing/testing.go:1445 /usr/local/go/src/testing/testing.go:1786 Error: Not equal: expected: []string{} actual : []string{"sub", "sub dir"} Diff: --- Expected +++ Actual @@ -1,2 +1,4 @@ -([]string) { +([]string) (len=2) { + (string) (len=3) "sub", + (string) (len=7) "sub dir" } Test: TestListJSON Messages: directories --- FAIL: TestListJSON (40.10s) --- FAIL: TestListJSON/Default (0.49s) --- PASS: TestListJSON/FilesOnly (0.20s) --- FAIL: TestListJSON/DirsOnly (0.27s) --- FAIL: TestListJSON/Recurse (0.90s) --- PASS: TestListJSON/SubDir (0.50s) --- PASS: TestListJSON/NoModTime (0.25s) --- PASS: TestListJSON/NoMimeType (0.20s) --- PASS: TestListJSON/ShowHash (0.17s) --- PASS: TestListJSON/HashTypes (0.23s) --- FAIL: TestListJSON/Metadata (0.15s) === RUN TestStatJSON run.go:185: Remote "drime root 'rclone-test-yohamax0qoza'", Local "Local file system at /tmp/rclone1795778209", Modify Window "876000h0m0s" fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/lsjson_test.go:263 Error: Should be true Test: TestStatJSON Messages: listing wrong, want file1 (5), sub/file2 (9) got file1 (5) fstest.go:203: Not found "sub/file2" fstest.go:206: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:206 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:310 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/lsjson_test.go:263 /usr/local/go/src/runtime/asm_amd64.s:1700 Error: Not equal: expected: 0 actual : 1 Test: TestStatJSON Messages: 1 objects not found === 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 2026/02/03 02:38:00 DEBUG : Creating backend with remote "TestDrime:rclone-test-yohamax0qoza/notfound" 2026/02/03 02:38:00 DEBUG : Config file has changed externally - reloading === NAME TestStatJSON run.go:130: removing dir "sub" failed - try 1/3: directory not empty run.go:130: removing dir "sub" failed - try 2/3: directory not empty run.go:130: removing dir "sub" failed - try 3/3: directory not empty run.go:133: removing dir "sub" failed: directory not empty fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:327: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:327 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393 /usr/local/go/src/testing/testing.go:1211 /usr/local/go/src/testing/testing.go:1445 /usr/local/go/src/testing/testing.go:1786 Error: Not equal: expected: []string{} actual : []string{"sub"} Diff: --- Expected +++ Actual @@ -1,2 +1,3 @@ -([]string) { +([]string) (len=1) { + (string) (len=3) "sub" } Test: TestStatJSON Messages: directories --- FAIL: TestStatJSON (37.66s) --- PASS: TestStatJSON/Root (0.75s) --- PASS: TestStatJSON/RootFilesOnly (0.00s) --- PASS: TestStatJSON/RootDirsOnly (0.21s) --- PASS: TestStatJSON/Dir (0.78s) --- PASS: TestStatJSON/DirWithTrailingSlash (0.21s) --- PASS: TestStatJSON/File (0.15s) --- PASS: TestStatJSON/NotFound (0.49s) --- PASS: TestStatJSON/DirFilesOnly (0.20s) --- PASS: TestStatJSON/FileFilesOnly (0.21s) --- PASS: TestStatJSON/NotFoundFilesOnly (0.35s) --- PASS: TestStatJSON/DirDirsOnly (0.27s) --- PASS: TestStatJSON/FileDirsOnly (0.26s) --- PASS: TestStatJSON/NotFoundDirsOnly (0.24s) --- PASS: TestStatJSON/RootNotFound (1.74s) === RUN TestMkdir run.go:185: Remote "drime root 'rclone-test-yohamax0qoza'", Local "Local file system at /tmp/rclone1795778209", Modify Window "876000h0m0s" 2026/02/03 02:38:17 INFO : drime root 'rclone-test-yohamax0qoza': Making directory 2026/02/03 02:38:18 INFO : drime root 'rclone-test-yohamax0qoza': Making directory fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:327: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:327 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393 /usr/local/go/src/testing/testing.go:1211 /usr/local/go/src/testing/testing.go:1445 /usr/local/go/src/testing/testing.go:1786 Error: Not equal: expected: []string{} actual : []string{"sub"} Diff: --- Expected +++ Actual @@ -1,2 +1,3 @@ -([]string) { +([]string) (len=1) { + (string) (len=3) "sub" } Test: TestMkdir Messages: directories --- FAIL: TestMkdir (11.09s) === RUN TestLsd run.go:185: Remote "drime root 'rclone-test-yohamax0qoza'", Local "Local file system at /tmp/rclone1795778209", Modify Window "876000h0m0s" run.go:130: removing dir "sub dir" failed - try 1/3: directory not empty run.go:130: removing dir "sub dir" failed - try 2/3: directory not empty run.go:130: removing dir "sub dir" failed - try 3/3: directory not empty run.go:133: removing dir "sub dir" failed: directory not empty fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393 /usr/local/go/src/testing/testing.go:1211 /usr/local/go/src/testing/testing.go:1445 /usr/local/go/src/testing/testing.go:1786 Error: Should be true Test: TestLsd Messages: listing wrong, want got sub dir/hello world (11) fstest.go:191: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393 /usr/local/go/src/testing/testing.go:1211 /usr/local/go/src/testing/testing.go:1445 /usr/local/go/src/testing/testing.go:1786 Error: Should be true Test: TestLsd Messages: Unexpected file "sub dir/hello world" fstest.go:327: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:327 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393 /usr/local/go/src/testing/testing.go:1211 /usr/local/go/src/testing/testing.go:1445 /usr/local/go/src/testing/testing.go:1786 Error: Not equal: expected: []string{} actual : []string{"sub dir"} Diff: --- Expected +++ Actual @@ -1,2 +1,3 @@ -([]string) { +([]string) (len=1) { + (string) (len=7) "sub dir" } Test: TestLsd Messages: directories --- FAIL: TestLsd (17.35s) === RUN TestLs run.go:185: Remote "drime root 'rclone-test-yohamax0qoza'", Local "Local file system at /tmp/rclone1795778209", Modify Window "876000h0m0s" fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:96 Error: Should be true Test: TestLs Messages: listing wrong, want empty space (1), potato2 (60) got fstest.go:203: Not found "potato2" fstest.go:203: Not found "empty space" fstest.go:206: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:206 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:310 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:96 /usr/local/go/src/runtime/asm_amd64.s:1700 Error: Not equal: expected: 0 actual : 2 Test: TestLs Messages: 2 objects not found fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393 /usr/local/go/src/testing/testing.go:1211 /usr/local/go/src/testing/testing.go:1445 /usr/local/go/src/testing/testing.go:1786 Error: Should be true Test: TestLs Messages: listing wrong, want got empty space (1), potato2 (60) fstest.go:191: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393 /usr/local/go/src/testing/testing.go:1211 /usr/local/go/src/testing/testing.go:1445 /usr/local/go/src/testing/testing.go:1786 Error: Should be true Test: TestLs Messages: Unexpected file "empty space" fstest.go:191: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393 /usr/local/go/src/testing/testing.go:1211 /usr/local/go/src/testing/testing.go:1445 /usr/local/go/src/testing/testing.go:1786 Error: Should be true Test: TestLs Messages: Unexpected file "potato2" fstest.go:327: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:327 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393 /usr/local/go/src/testing/testing.go:1211 /usr/local/go/src/testing/testing.go:1445 /usr/local/go/src/testing/testing.go:1786 Error: Not equal: expected: []string{} actual : []string{"sub dir"} Diff: --- Expected +++ Actual @@ -1,2 +1,3 @@ -([]string) { +([]string) (len=1) { + (string) (len=7) "sub dir" } Test: TestLs Messages: directories --- FAIL: TestLs (25.48s) === RUN TestLsWithFilesFrom run.go:185: Remote "drime root 'rclone-test-yohamax0qoza'", Local "Local file system at /tmp/rclone1795778209", Modify Window "876000h0m0s" fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:113 Error: Should be true Test: TestLsWithFilesFrom Messages: listing wrong, want empty space (1), potato2 (60) got fstest.go:203: Not found "potato2" fstest.go:203: Not found "empty space" fstest.go:206: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:206 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:310 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:113 /usr/local/go/src/runtime/asm_amd64.s:1700 Error: Not equal: expected: 0 actual : 2 Test: TestLsWithFilesFrom Messages: 2 objects not found 2026/02/03 02:39:25 DEBUG : empty space: Excluded (FilesFrom Filter) 2026/02/03 02:39:25 DEBUG : empty space: Excluded fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393 /usr/local/go/src/testing/testing.go:1211 /usr/local/go/src/testing/testing.go:1445 /usr/local/go/src/testing/testing.go:1786 Error: Should be true Test: TestLsWithFilesFrom Messages: listing wrong, want got empty space (1), potato2 (60) fstest.go:191: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393 /usr/local/go/src/testing/testing.go:1211 /usr/local/go/src/testing/testing.go:1445 /usr/local/go/src/testing/testing.go:1786 Error: Should be true Test: TestLsWithFilesFrom Messages: Unexpected file "empty space" fstest.go:191: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393 /usr/local/go/src/testing/testing.go:1211 /usr/local/go/src/testing/testing.go:1445 /usr/local/go/src/testing/testing.go:1786 Error: Should be true Test: TestLsWithFilesFrom Messages: Unexpected file "potato2" --- FAIL: TestLsWithFilesFrom (23.84s) === RUN TestLsLong run.go:185: Remote "drime root 'rclone-test-yohamax0qoza'", Local "Local file system at /tmp/rclone1795778209", Modify Window "876000h0m0s" fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:144 Error: Should be true Test: TestLsLong Messages: listing wrong, want empty space (1), potato2 (60) got fstest.go:203: Not found "potato2" fstest.go:203: Not found "empty space" fstest.go:206: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:206 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:310 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:144 /usr/local/go/src/runtime/asm_amd64.s:1700 Error: Not equal: expected: 0 actual : 2 Test: TestLsLong Messages: 2 objects not found fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393 /usr/local/go/src/testing/testing.go:1211 /usr/local/go/src/testing/testing.go:1445 /usr/local/go/src/testing/testing.go:1786 Error: Should be true Test: TestLsLong Messages: listing wrong, want got empty space (1), potato2 (60) fstest.go:191: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393 /usr/local/go/src/testing/testing.go:1211 /usr/local/go/src/testing/testing.go:1445 /usr/local/go/src/testing/testing.go:1786 Error: Should be true Test: TestLsLong Messages: Unexpected file "empty space" fstest.go:191: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393 /usr/local/go/src/testing/testing.go:1211 /usr/local/go/src/testing/testing.go:1445 /usr/local/go/src/testing/testing.go:1786 Error: Should be true Test: TestLsLong Messages: Unexpected file "potato2" --- FAIL: TestLsLong (24.14s) === RUN TestHashSums run.go:185: Remote "drime root 'rclone-test-yohamax0qoza'", Local "Local file system at /tmp/rclone1795778209", Modify Window "876000h0m0s" fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:292: Sleeping for 7s just to make sure fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:292: Sleeping for 7s just to make sure --- PASS: TestHashSums (31.23s) === RUN TestHashSumsWithErrors 2026/02/03 02:40:30 DEBUG : Creating backend with remote ":memory:" 2026/02/03 02:40:30 ERROR : file1: hash unsupported: hash type not supported --- PASS: TestHashSumsWithErrors (0.00s) === RUN TestHashStream 2026/02/03 02:40:30 DEBUG : Creating md5 hash of 0 bytes read from input stream 2026/02/03 02:40:30 DEBUG : Creating md5 hash of 0 bytes read from input stream 2026/02/03 02:40:30 DEBUG : Creating sha1 hash of 0 bytes read from input stream 2026/02/03 02:40:30 DEBUG : Creating sha1 hash of 0 bytes read from input stream 2026/02/03 02:40:30 DEBUG : Creating md5 hash of 12 bytes read from input stream 2026/02/03 02:40:30 DEBUG : Creating md5 hash of 12 bytes read from input stream 2026/02/03 02:40:30 DEBUG : Creating sha1 hash of 12 bytes read from input stream 2026/02/03 02:40:30 DEBUG : Creating sha1 hash of 12 bytes read from input stream --- PASS: TestHashStream (0.00s) === RUN TestSuffixName --- PASS: TestSuffixName (0.00s) === RUN TestCount run.go:185: Remote "drime root 'rclone-test-yohamax0qoza'", Local "Local file system at /tmp/rclone1795778209", Modify Window "876000h0m0s" run.go:130: removing dir "sub dir" failed - try 1/3: directory not empty run.go:130: removing dir "sub dir" failed - try 2/3: directory not empty run.go:130: removing dir "sub dir" failed - try 3/3: directory not empty run.go:133: removing dir "sub dir" failed: directory not empty fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:327: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:327 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393 /usr/local/go/src/testing/testing.go:1211 /usr/local/go/src/testing/testing.go:1445 /usr/local/go/src/testing/testing.go:1786 Error: Not equal: expected: []string{} actual : []string{"sub dir"} Diff: --- Expected +++ Actual @@ -1,2 +1,3 @@ -([]string) { +([]string) (len=1) { + (string) (len=7) "sub dir" } Test: TestCount Messages: directories --- FAIL: TestCount (22.99s) === RUN TestDelete run.go:185: Remote "drime root 'rclone-test-yohamax0qoza'", Local "Local file system at /tmp/rclone1795778209", Modify Window "876000h0m0s" fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:419 Error: Should be true Test: TestDelete Messages: listing wrong, want large (100), medium (60), small (10) got medium (60), small (10) fstest.go:203: Not found "large" fstest.go:206: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:206 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:310 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:419 /usr/local/go/src/runtime/asm_amd64.s:1700 Error: Not equal: expected: 0 actual : 1 Test: TestDelete Messages: 1 objects not found 2026/02/03 02:41:11 DEBUG : Waiting for deletions to finish 2026/02/03 02:41:12 DEBUG : large: Excluded (Size Filter) 2026/02/03 02:41:12 DEBUG : large: Excluded 2026/02/03 02:41:12 INFO : small: Deleted 2026/02/03 02:41:12 INFO : medium: Deleted fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:423 Error: Should be true Test: TestDelete Messages: listing wrong, want large (100) got large (100), medium (60), small (10) fstest.go:191: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:423 /usr/local/go/src/runtime/asm_amd64.s:1700 Error: Should be true Test: TestDelete Messages: Unexpected file "medium" fstest.go:191: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:423 /usr/local/go/src/runtime/asm_amd64.s:1700 Error: Should be true Test: TestDelete Messages: Unexpected file "small" 2026/02/03 02:41:22 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/03 02:41:22 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2026/02/03 02:41:22 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/03 02:41:22 DEBUG : pacer: Rate limited, increasing sleep to 40ms 2026/02/03 02:41:22 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/03 02:41:22 DEBUG : pacer: Rate limited, increasing sleep to 80ms 2026/02/03 02:41:23 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/03 02:41:23 DEBUG : pacer: Rate limited, increasing sleep to 160ms 2026/02/03 02:41:23 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/03 02:41:23 DEBUG : pacer: Rate limited, increasing sleep to 320ms 2026/02/03 02:41:23 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/03 02:41:23 DEBUG : pacer: Rate limited, increasing sleep to 640ms 2026/02/03 02:41:24 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/03 02:41:24 DEBUG : pacer: Rate limited, increasing sleep to 1.28s 2026/02/03 02:41:24 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/03 02:41:24 DEBUG : pacer: Rate limited, increasing sleep to 2.56s 2026/02/03 02:41:26 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/03 02:41:26 DEBUG : pacer: Rate limited, increasing sleep to 5.12s 2026/02/03 02:41:28 DEBUG : pacer: low level retry 10/10 (error Error "Server Error") 2026/02/03 02:41:28 DEBUG : pacer: Rate limited, increasing sleep to 10.24s run.go:130: removing file "medium" failed - try 1/3: failed to delete item: Error "Server Error" 2026/02/03 02:41:33 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/03 02:41:33 DEBUG : pacer: Rate limited, increasing sleep to 20s 2026/02/03 02:41:44 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/03 02:42:03 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/03 02:42:24 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/03 02:42:44 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/03 02:43:04 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/03 02:43:23 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/03 02:43:44 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/03 02:44:03 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/03 02:44:24 DEBUG : pacer: low level retry 10/10 (error Error "Server Error") run.go:130: removing file "medium" failed - try 2/3: failed to delete item: Error "Server Error" 2026/02/03 02:44:44 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/03 02:45:04 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/03 02:45:23 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/03 02:45:44 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/03 02:46:04 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/03 02:46:24 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/03 02:46:44 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/03 02:47:04 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/03 02:47:23 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/03 02:47:44 DEBUG : pacer: low level retry 10/10 (error Error "Server Error") run.go:130: removing file "medium" failed - try 3/3: failed to delete item: Error "Server Error" run.go:133: removing file "medium" failed: failed to delete item: Error "Server Error" 2026/02/03 02:48:04 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/03 02:48:23 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/03 02:48:44 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/03 02:49:04 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/03 02:49:23 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/03 02:49:44 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/03 02:50:04 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/03 02:50:24 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/03 02:50:44 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/03 02:51:03 DEBUG : pacer: low level retry 10/10 (error Error "Server Error") run.go:130: removing file "small" failed - try 1/3: failed to delete item: Error "Server Error" 2026/02/03 02:51:24 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/03 02:51:44 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/03 02:52:04 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/03 02:52:24 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/03 02:52:44 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/03 02:53:04 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/03 02:53:23 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/03 02:53:44 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/03 02:54:04 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/03 02:54:24 DEBUG : pacer: low level retry 10/10 (error Error "Server Error") run.go:130: removing file "small" failed - try 2/3: failed to delete item: Error "Server Error" 2026/02/03 02:54:44 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/03 02:55:03 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/03 02:55:24 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/03 02:55:44 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/03 02:56:04 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/03 02:56:24 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/03 02:56:44 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/03 02:57:04 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/03 02:57:24 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/03 02:57:44 DEBUG : pacer: low level retry 10/10 (error Error "Server Error") run.go:130: removing file "small" failed - try 3/3: failed to delete item: Error "Server Error" run.go:133: removing file "small" failed: failed to delete item: Error "Server Error" 2026/02/03 02:58:03 DEBUG : pacer: Reducing sleep to 10s 2026/02/03 02:58:24 DEBUG : pacer: Reducing sleep to 5s 2026/02/03 02:58:34 DEBUG : pacer: Reducing sleep to 2.5s 2026/02/03 02:58:38 DEBUG : pacer: Reducing sleep to 1.25s --- FAIL: TestDelete (1065.45s) === RUN TestMaxDelete run.go:185: Remote "drime root 'rclone-test-yohamax0qoza'", Local "Local file system at /tmp/rclone1795778209", Modify Window "876000h0m0s" 2026/02/03 02:58:41 DEBUG : pacer: Reducing sleep to 625ms 2026/02/03 02:58:45 DEBUG : pacer: Reducing sleep to 312.5ms 2026/02/03 02:58:46 DEBUG : pacer: Reducing sleep to 156.25ms 2026/02/03 02:58:50 DEBUG : pacer: Reducing sleep to 78.125ms 2026/02/03 02:58:50 DEBUG : pacer: Reducing sleep to 39.0625ms 2026/02/03 02:58:53 DEBUG : pacer: Reducing sleep to 19.53125ms 2026/02/03 02:58:53 DEBUG : pacer: Reducing sleep to 10ms fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:447 Error: Should be true Test: TestMaxDelete Messages: listing wrong, want large (100), medium (60), small (10) got medium (60), small (10) fstest.go:203: Not found "large" fstest.go:206: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:206 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:310 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:447 /usr/local/go/src/runtime/asm_amd64.s:1700 Error: Not equal: expected: 0 actual : 1 Test: TestMaxDelete Messages: 1 objects not found 2026/02/03 02:59:01 DEBUG : Waiting for deletions to finish 2026/02/03 02:59:02 ERROR : small: Got fatal error on delete: --max-delete threshold reached 2026/02/03 02:59:03 INFO : medium: Deleted 2026/02/03 02:59:03 INFO : large: Deleted operations_test.go:453: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:453 Error: Not equal: expected: 1 actual : 3 Test: TestMaxDelete 2026/02/03 02:59:04 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/03 02:59:04 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2026/02/03 02:59:04 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/03 02:59:04 DEBUG : pacer: Rate limited, increasing sleep to 40ms 2026/02/03 02:59:05 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/03 02:59:05 DEBUG : pacer: Rate limited, increasing sleep to 80ms 2026/02/03 02:59:05 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/03 02:59:05 DEBUG : pacer: Rate limited, increasing sleep to 160ms 2026/02/03 02:59:05 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/03 02:59:05 DEBUG : pacer: Rate limited, increasing sleep to 320ms 2026/02/03 02:59:05 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/03 02:59:05 DEBUG : pacer: Rate limited, increasing sleep to 640ms 2026/02/03 02:59:06 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/03 02:59:06 DEBUG : pacer: Rate limited, increasing sleep to 1.28s 2026/02/03 02:59:06 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/03 02:59:06 DEBUG : pacer: Rate limited, increasing sleep to 2.56s 2026/02/03 02:59:08 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/03 02:59:08 DEBUG : pacer: Rate limited, increasing sleep to 5.12s 2026/02/03 02:59:10 DEBUG : pacer: low level retry 10/10 (error Error "Server Error") 2026/02/03 02:59:10 DEBUG : pacer: Rate limited, increasing sleep to 10.24s run.go:130: removing file "large" failed - try 1/3: failed to delete item: Error "Server Error" 2026/02/03 02:59:15 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/03 02:59:15 DEBUG : pacer: Rate limited, increasing sleep to 20s 2026/02/03 02:59:26 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/03 02:59:46 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/03 03:00:06 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/03 03:00:26 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/03 03:00:46 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/03 03:01:06 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/03 03:01:26 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/03 03:01:46 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/03 03:02:05 DEBUG : pacer: low level retry 10/10 (error Error "Server Error") run.go:130: removing file "large" failed - try 2/3: failed to delete item: Error "Server Error" 2026/02/03 03:02:26 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/03 03:02:46 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/03 03:03:06 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/03 03:03:25 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/03 03:03:46 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/03 03:04:06 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/03 03:04:26 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/03 03:04:46 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/03 03:05:06 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/03 03:05:26 DEBUG : pacer: low level retry 10/10 (error Error "Server Error") run.go:130: removing file "large" failed - try 3/3: failed to delete item: Error "Server Error" run.go:133: removing file "large" failed: failed to delete item: Error "Server Error" 2026/02/03 03:05:46 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/03 03:06:06 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/03 03:06:26 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/03 03:06:46 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/03 03:07:06 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/03 03:07:26 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/03 03:07:46 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/03 03:08:06 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/03 03:08:26 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/03 03:08:46 DEBUG : pacer: low level retry 10/10 (error Error "Server Error") run.go:130: removing file "medium" failed - try 1/3: failed to delete item: Error "Server Error" 2026/02/03 03:09:06 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/03 03:09:26 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/03 03:09:46 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/03 03:10:06 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/03 03:10:26 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/03 03:10:46 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/03 03:11:05 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/03 03:11:26 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/03 03:11:46 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/03 03:12:06 DEBUG : pacer: low level retry 10/10 (error Error "Server Error") run.go:130: removing file "medium" failed - try 2/3: failed to delete item: Error "Server Error" 2026/02/03 03:12:26 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/03 03:12:45 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/03 03:13:06 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/03 03:13:26 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/03 03:13:46 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/03 03:14:06 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/03 03:14:25 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/03 03:14:45 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/03 03:15:06 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/03 03:15:26 DEBUG : pacer: low level retry 10/10 (error Error "Server Error") run.go:130: removing file "medium" failed - try 3/3: failed to delete item: Error "Server Error" run.go:133: removing file "medium" failed: failed to delete item: Error "Server Error" 2026/02/03 03:15:46 DEBUG : pacer: Reducing sleep to 10s 2026/02/03 03:16:06 DEBUG : pacer: Reducing sleep to 5s 2026/02/03 03:16:16 DEBUG : pacer: Reducing sleep to 2.5s 2026/02/03 03:16:20 DEBUG : pacer: Reducing sleep to 1.25s --- FAIL: TestMaxDelete (1062.01s) === RUN TestMaxDeleteSizeLargeFile run.go:185: Remote "drime root 'rclone-test-yohamax0qoza'", Local "Local file system at /tmp/rclone1795778209", Modify Window "876000h0m0s" 2026/02/03 03:16:23 DEBUG : pacer: Reducing sleep to 625ms 2026/02/03 03:16:29 DEBUG : pacer: Reducing sleep to 312.5ms 2026/02/03 03:16:29 DEBUG : pacer: Reducing sleep to 156.25ms 2026/02/03 03:16:31 DEBUG : pacer: Reducing sleep to 78.125ms 2026/02/03 03:16:31 DEBUG : pacer: Reducing sleep to 39.0625ms 2026/02/03 03:16:34 DEBUG : pacer: Reducing sleep to 19.53125ms 2026/02/03 03:16:35 DEBUG : pacer: Reducing sleep to 10ms fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:468 Error: Should be true Test: TestMaxDeleteSizeLargeFile Messages: listing wrong, want large (100), medium (60), small (10) got medium (60), small (10) fstest.go:203: Not found "large" fstest.go:206: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:206 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:310 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:468 /usr/local/go/src/runtime/asm_amd64.s:1700 Error: Not equal: expected: 0 actual : 1 Test: TestMaxDeleteSizeLargeFile Messages: 1 objects not found 2026/02/03 03:16:43 DEBUG : Waiting for deletions to finish 2026/02/03 03:16:43 ERROR : large: Got fatal error on delete: --max-delete-size threshold reached 2026/02/03 03:16:44 INFO : medium: Deleted 2026/02/03 03:16:44 INFO : small: Deleted fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:472 Error: Should be true Test: TestMaxDeleteSizeLargeFile Messages: listing wrong, want large (100) got large (100), medium (60), small (10) fstest.go:191: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:472 /usr/local/go/src/runtime/asm_amd64.s:1700 Error: Should be true Test: TestMaxDeleteSizeLargeFile Messages: Unexpected file "medium" fstest.go:191: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:472 /usr/local/go/src/runtime/asm_amd64.s:1700 Error: Should be true Test: TestMaxDeleteSizeLargeFile Messages: Unexpected file "small" 2026/02/03 03:16:53 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/03 03:16:53 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2026/02/03 03:16:54 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/03 03:16:54 DEBUG : pacer: Rate limited, increasing sleep to 40ms 2026/02/03 03:16:54 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/03 03:16:54 DEBUG : pacer: Rate limited, increasing sleep to 80ms 2026/02/03 03:16:54 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/03 03:16:54 DEBUG : pacer: Rate limited, increasing sleep to 160ms 2026/02/03 03:16:54 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/03 03:16:54 DEBUG : pacer: Rate limited, increasing sleep to 320ms 2026/02/03 03:16:55 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/03 03:16:55 DEBUG : pacer: Rate limited, increasing sleep to 640ms 2026/02/03 03:16:55 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/03 03:16:55 DEBUG : pacer: Rate limited, increasing sleep to 1.28s 2026/02/03 03:16:56 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/03 03:16:56 DEBUG : pacer: Rate limited, increasing sleep to 2.56s 2026/02/03 03:16:57 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/03 03:16:57 DEBUG : pacer: Rate limited, increasing sleep to 5.12s 2026/02/03 03:17:00 DEBUG : pacer: low level retry 10/10 (error Error "Server Error") 2026/02/03 03:17:00 DEBUG : pacer: Rate limited, increasing sleep to 10.24s run.go:130: removing file "medium" failed - try 1/3: failed to delete item: Error "Server Error" 2026/02/03 03:17:04 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/03 03:17:04 DEBUG : pacer: Rate limited, increasing sleep to 20s 2026/02/03 03:17:15 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/03 03:17:35 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/03 03:17:55 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/03 03:18:15 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/03 03:18:35 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/03 03:18:55 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/03 03:19:15 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/03 03:19:35 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/03 03:19:55 DEBUG : pacer: low level retry 10/10 (error Error "Server Error") run.go:130: removing file "medium" failed - try 2/3: failed to delete item: Error "Server Error" 2026/02/03 03:20:15 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/03 03:20:35 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/03 03:20:55 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/03 03:21:15 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/03 03:21:35 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/03 03:21:55 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/03 03:22:15 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/03 03:22:35 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/03 03:22:55 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/03 03:23:15 DEBUG : pacer: low level retry 10/10 (error Error "Server Error") run.go:130: removing file "medium" failed - try 3/3: failed to delete item: Error "Server Error" run.go:133: removing file "medium" failed: failed to delete item: Error "Server Error" 2026/02/03 03:23:35 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/03 03:23:55 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/03 03:24:15 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/03 03:24:35 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/03 03:24:55 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/03 03:25:15 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/03 03:25:35 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/03 03:25:55 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/03 03:26:15 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/03 03:26:35 DEBUG : pacer: low level retry 10/10 (error Error "Server Error") run.go:130: removing file "small" failed - try 1/3: failed to delete item: Error "Server Error" 2026/02/03 03:27:10 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/03 03:27:16 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/03 03:27:35 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/03 03:27:55 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value 2026/02/03 03:27:55 DEBUG : pacer: low level retry 4/10 (error Error "500 Internal Server Error (500): \r\n500 Internal Server Error\r\n\r\n

500 Internal Server Error

\r\n
nginx
\r\n\r\n\r\n") 2026/02/03 03:28:15 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/03 03:28:35 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value 2026/02/03 03:28:35 DEBUG : pacer: low level retry 6/10 (error Error "500 Internal Server Error (500): \r\n500 Internal Server Error\r\n\r\n

500 Internal Server Error

\r\n
nginx
\r\n\r\n\r\n") 2026/02/03 03:28:57 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/03 03:29:15 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value 2026/02/03 03:29:15 DEBUG : pacer: Reducing sleep to 10s run.go:130: removing file "small" failed - try 2/3: failed to delete item: Error "525 (525): \n\n\n\n \n\n\ndrime.cloud | 525: SSL handshake failed\n\n\n\n\n\n\n\n\n
\n
\n
\n

\n SSL handshake failed\n Error code 525\n

\n
\n Visit cloudflare.com for more information.\n
\n
2026-02-03 03:29:15 UTC
\n
\n
\n
\n
\n
\n
\n \n \n \n \n
\n You\n

\n \n Browser\n \n

\n \n Working\n \n
\n
\n
\n \n \n \n \n
\n Frankfurt\n

\n \n Cloudflare\n \n

\n \n Working\n \n
\n
\n
\n \n \n \n \n
\n app.drime.cloud\n

\n \n Host\n \n

\n \n Error\n \n
\n
\n
\n
\n\n
\n
\n
\n

What happened?

\n

Cloudflare is unable to establish an SSL connection to the origin server.

\n
\n
\n

What can I do?

\n \n

If you're a visitor of this website:

\n

Please try again in a few minutes.

\n\n

If you're the owner of this website:

\n

It appears that the SSL configuration used is not compatible with Cloudflare. This could happen for a several reasons, including no shared cipher suites. Additional troubleshooting information here.

\n
\n
\n
\n\n
\n

\n Cloudflare Ray ID: 9c7ed7c53bbb3603\n •\n \n Performance & security by Cloudflare\n \n

\n \n
\n\n
\n
\n\n" 2026/02/03 03:29:35 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/03 03:29:35 DEBUG : pacer: Rate limited, increasing sleep to 20s 2026/02/03 03:30:01 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/03 03:30:07 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/03 03:30:25 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value 2026/02/03 03:30:25 DEBUG : pacer: Reducing sleep to 10s run.go:130: removing file "small" failed - try 3/3: failed to delete item: Error "525 (525): \n\n\n\n \n\n\ndrime.cloud | 525: SSL handshake failed\n\n\n\n\n\n\n\n\n
\n
\n
\n

\n SSL handshake failed\n Error code 525\n

\n
\n Visit cloudflare.com for more information.\n
\n
2026-02-03 03:30:25 UTC
\n
\n
\n
\n
\n
\n
\n \n \n \n \n
\n You\n

\n \n Browser\n \n

\n \n Working\n \n
\n
\n
\n \n \n \n \n
\n Frankfurt\n

\n \n Cloudflare\n \n

\n \n Working\n \n
\n
\n
\n \n \n \n \n
\n app.drime.cloud\n

\n \n Host\n \n

\n \n Error\n \n
\n
\n
\n
\n\n
\n
\n
\n

What happened?

\n

Cloudflare is unable to establish an SSL connection to the origin server.

\n
\n
\n

What can I do?

\n \n

If you're a visitor of this website:

\n

Please try again in a few minutes.

\n\n

If you're the owner of this website:

\n

It appears that the SSL configuration used is not compatible with Cloudflare. This could happen for a several reasons, including no shared cipher suites. Additional troubleshooting information here.

\n
\n
\n
\n\n
\n

\n Cloudflare Ray ID: 9c7ed97abb753603\n •\n \n Performance & security by Cloudflare\n \n

\n \n
\n\n
\n
\n\n" run.go:133: removing file "small" failed: failed to delete item: Error "525 (525): \n\n\n\n \n\n\ndrime.cloud | 525: SSL handshake failed\n\n\n\n\n\n\n\n\n
\n
\n
\n

\n SSL handshake failed\n Error code 525\n

\n
\n Visit cloudflare.com for more information.\n
\n
2026-02-03 03:30:25 UTC
\n
\n
\n
\n
\n
\n
\n \n \n \n \n
\n You\n

\n \n Browser\n \n

\n \n Working\n \n
\n
\n
\n \n \n \n \n
\n Frankfurt\n

\n \n Cloudflare\n \n

\n \n Working\n \n
\n
\n
\n \n \n \n \n
\n app.drime.cloud\n

\n \n Host\n \n

\n \n Error\n \n
\n
\n
\n
\n\n
\n
\n
\n

What happened?

\n

Cloudflare is unable to establish an SSL connection to the origin server.

\n
\n
\n

What can I do?

\n \n

If you're a visitor of this website:

\n

Please try again in a few minutes.

\n\n

If you're the owner of this website:

\n

It appears that the SSL configuration used is not compatible with Cloudflare. This could happen for a several reasons, including no shared cipher suites. Additional troubleshooting information here.

\n
\n
\n
\n\n
\n

\n Cloudflare Ray ID: 9c7ed97abb753603\n •\n \n Performance & security by Cloudflare\n \n

\n \n
\n\n
\n
\n\n" 2026/02/03 03:30:45 DEBUG : pacer: Reducing sleep to 5s 2026/02/03 03:31:15 DEBUG : pacer: Reducing sleep to 2.5s 2026/02/03 03:31:18 DEBUG : pacer: Reducing sleep to 1.25s --- FAIL: TestMaxDeleteSizeLargeFile (898.08s) === RUN TestMaxDeleteSize run.go:185: Remote "drime root 'rclone-test-yohamax0qoza'", Local "Local file system at /tmp/rclone1795778209", Modify Window "876000h0m0s" 2026/02/03 03:31:19 DEBUG : pacer: Reducing sleep to 625ms 2026/02/03 03:31:23 DEBUG : pacer: Reducing sleep to 312.5ms 2026/02/03 03:31:24 DEBUG : pacer: Reducing sleep to 156.25ms 2026/02/03 03:31:29 DEBUG : pacer: Reducing sleep to 78.125ms 2026/02/03 03:31:29 DEBUG : pacer: Reducing sleep to 39.0625ms 2026/02/03 03:31:34 DEBUG : pacer: Reducing sleep to 19.53125ms 2026/02/03 03:31:34 DEBUG : pacer: Reducing sleep to 10ms fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:486 Error: Should be true Test: TestMaxDeleteSize Messages: listing wrong, want large (100), medium (60), small (10) got medium (60), small (10) fstest.go:203: Not found "large" fstest.go:206: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:206 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:310 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:486 /usr/local/go/src/runtime/asm_amd64.s:1700 Error: Not equal: expected: 0 actual : 1 Test: TestMaxDeleteSize Messages: 1 objects not found 2026/02/03 03:31:42 DEBUG : Waiting for deletions to finish 2026/02/03 03:31:43 ERROR : small: Got fatal error on delete: --max-delete-size threshold reached 2026/02/03 03:31:43 INFO : large: Deleted 2026/02/03 03:31:43 INFO : medium: Deleted operations_test.go:492: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:492 Error: Not equal: expected: 1 actual : 3 Test: TestMaxDeleteSize 2026/02/03 03:31:44 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/03 03:31:44 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2026/02/03 03:31:44 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/03 03:31:44 DEBUG : pacer: Rate limited, increasing sleep to 40ms 2026/02/03 03:31:44 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/03 03:31:44 DEBUG : pacer: Rate limited, increasing sleep to 80ms 2026/02/03 03:31:45 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/03 03:31:45 DEBUG : pacer: Rate limited, increasing sleep to 160ms 2026/02/03 03:31:45 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/03 03:31:45 DEBUG : pacer: Rate limited, increasing sleep to 320ms 2026/02/03 03:31:45 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/03 03:31:45 DEBUG : pacer: Rate limited, increasing sleep to 640ms 2026/02/03 03:31:46 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/03 03:31:46 DEBUG : pacer: Rate limited, increasing sleep to 1.28s 2026/02/03 03:31:46 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/03 03:31:46 DEBUG : pacer: Rate limited, increasing sleep to 2.56s 2026/02/03 03:31:48 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/03 03:31:48 DEBUG : pacer: Rate limited, increasing sleep to 5.12s 2026/02/03 03:31:50 DEBUG : pacer: low level retry 10/10 (error Error "Server Error") 2026/02/03 03:31:50 DEBUG : pacer: Rate limited, increasing sleep to 10.24s run.go:130: removing file "large" failed - try 1/3: failed to delete item: Error "Server Error" 2026/02/03 03:31:55 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/03 03:31:55 DEBUG : pacer: Rate limited, increasing sleep to 20s 2026/02/03 03:32:06 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/03 03:32:26 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/03 03:32:46 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/03 03:33:59 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/03 03:34:00 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/03 03:34:19 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/03 03:34:39 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/03 03:35:00 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/03 03:35:20 DEBUG : pacer: low level retry 10/10 (error Error "Server Error") run.go:130: removing file "large" failed - try 2/3: failed to delete item: Error "Server Error" 2026/02/03 03:35:40 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/03 03:36:00 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/03 03:36:20 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/03 03:36:40 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/03 03:37:00 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/03 03:37:20 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/03 03:37:40 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/03 03:38:00 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/03 03:38:20 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/03 03:38:40 DEBUG : pacer: low level retry 10/10 (error Error "Server Error") run.go:130: removing file "large" failed - try 3/3: failed to delete item: Error "Server Error" run.go:133: removing file "large" failed: failed to delete item: Error "Server Error" 2026/02/03 03:39:00 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/03 03:39:20 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/03 03:39:40 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/03 03:40:00 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/03 03:40:20 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/03 03:40:40 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/03 03:41:00 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/03 03:41:20 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/03 03:41:40 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/03 03:41:59 DEBUG : pacer: low level retry 10/10 (error Error "Server Error") run.go:130: removing file "medium" failed - try 1/3: failed to delete item: Error "Server Error" 2026/02/03 03:42:20 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/03 03:42:40 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/03 03:43:00 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/03 03:43:20 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/03 03:43:40 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/03 03:44:00 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/03 03:44:20 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/03 03:44:40 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/03 03:45:00 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/03 03:53:09 DEBUG : pacer: low level retry 10/10 (error Error "Server Error") run.go:130: removing file "medium" failed - try 2/3: failed to delete item: Error "Server Error" 2026/02/03 03:53:11 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/03 03:53:31 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/03 03:53:51 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/03 03:54:11 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/03 03:54:31 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/03 03:54:51 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/03 03:55:11 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/03 03:55:31 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/03 03:55:51 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/03 03:56:11 DEBUG : pacer: low level retry 10/10 (error Error "Server Error") run.go:130: removing file "medium" failed - try 3/3: failed to delete item: Error "Server Error" run.go:133: removing file "medium" failed: failed to delete item: Error "Server Error" 2026/02/03 03:56:31 DEBUG : pacer: Reducing sleep to 10s 2026/02/03 03:56:51 DEBUG : pacer: Reducing sleep to 5s 2026/02/03 03:57:00 DEBUG : pacer: Reducing sleep to 2.5s 2026/02/03 03:57:06 DEBUG : pacer: Reducing sleep to 1.25s --- FAIL: TestMaxDeleteSize (1547.32s) === RUN TestReadFile run.go:185: Remote "drime root 'rclone-test-yohamax0qoza'", Local "Local file system at /tmp/rclone1795778209", Modify Window "876000h0m0s" 2026/02/03 03:57:08 DEBUG : pacer: Reducing sleep to 625ms 2026/02/03 03:57:17 DEBUG : pacer: Reducing sleep to 312.5ms 2026/02/03 03:57:18 DEBUG : pacer: Reducing sleep to 156.25ms 2026/02/03 03:57:18 DEBUG : pacer: Reducing sleep to 78.125ms 2026/02/03 03:57:18 DEBUG : pacer: Reducing sleep to 39.0625ms 2026/02/03 03:57:18 DEBUG : pacer: Reducing sleep to 19.53125ms 2026/02/03 03:57:19 DEBUG : pacer: Reducing sleep to 10ms fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:510 Error: Should be true Test: TestReadFile Messages: listing wrong, want got ReadFile (28) fstest.go:191: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:510 /usr/local/go/src/runtime/asm_amd64.s:1700 Error: Should be true Test: TestReadFile Messages: Unexpected file "ReadFile" --- FAIL: TestReadFile (22.50s) === RUN TestRetry 2026/02/03 03:57:28 DEBUG : Received error: Wrapped EOF is retriable: EOF - low level retry 1/5 2026/02/03 03:57:28 DEBUG : Received error: Wrapped EOF is retriable: EOF - low level retry 2/5 2026/02/03 03:57:28 DEBUG : Sleeping for 10ms (as indicated by the server) to obey Retry-After error: BANG: trying again in 10ms 2026/02/03 03:57:28 DEBUG : Sleeping for 10ms (as indicated by the server) to obey Retry-After error: BANG: trying again in 10ms 2026/02/03 03:57:28 DEBUG : Sleeping for 10ms (as indicated by the server) to obey Retry-After error: BANG: trying again in 10ms 2026/02/03 03:57:28 DEBUG : Sleeping for 10ms (as indicated by the server) to obey Retry-After error: BANG: trying again in 10ms 2026/02/03 03:57:28 DEBUG : Sleeping for 10ms (as indicated by the server) to obey Retry-After error: BANG: trying again in 10ms --- PASS: TestRetry (0.05s) === RUN TestCat run.go:185: Remote "drime root 'rclone-test-yohamax0qoza'", Local "Local file system at /tmp/rclone1795778209", Modify Window "876000h0m0s" fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:545 Error: Should be true Test: TestCat Messages: listing wrong, want file1 (10), file2 (9) got file1 (10) fstest.go:203: Not found "file2" fstest.go:206: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:206 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:310 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:545 /usr/local/go/src/runtime/asm_amd64.s:1700 Error: Not equal: expected: 0 actual : 1 Test: TestCat Messages: 1 objects not found fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393 /usr/local/go/src/testing/testing.go:1211 /usr/local/go/src/testing/testing.go:1445 /usr/local/go/src/testing/testing.go:1786 Error: Should be true Test: TestCat Messages: listing wrong, want got file1 (10), file2 (9) fstest.go:191: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393 /usr/local/go/src/testing/testing.go:1211 /usr/local/go/src/testing/testing.go:1445 /usr/local/go/src/testing/testing.go:1786 Error: Should be true Test: TestCat Messages: Unexpected file "file1" fstest.go:191: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393 /usr/local/go/src/testing/testing.go:1211 /usr/local/go/src/testing/testing.go:1445 /usr/local/go/src/testing/testing.go:1786 Error: Should be true Test: TestCat Messages: Unexpected file "file2" --- FAIL: TestCat (36.39s) === RUN TestPurge 2026/02/03 03:58:05 DEBUG : Creating backend with remote "TestDrime:rclone-test-hojoluv9qobi" 2026/02/03 03:58:05 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/02/03 03:58:06 DEBUG : Creating backend with remote "/tmp/rclone18357773" run.go:185: Remote "drime root 'rclone-test-hojoluv9qobi'", Local "Local file system at /tmp/rclone18357773", Modify Window "876000h0m0s" 2026/02/03 03:58:11 INFO : A2: Making directory 2026/02/03 03:58:12 INFO : A1/B2: Making directory 2026/02/03 03:58:12 INFO : A1/B2/C2: Making directory 2026/02/03 03:58:13 INFO : A1/B1/C3: Making directory 2026/02/03 03:58:13 INFO : A3: Making directory 2026/02/03 03:58:14 INFO : A3/B3: Making directory 2026/02/03 03:58:14 INFO : A3/B3/C4: Making directory fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:292: Sleeping for 7s just to make sure fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:292: Sleeping for 6s just to make sure fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache 2026/02/03 03:58:46 ERROR : error listing: directory not found fstest.go:292: Sleeping for 6s just to make sure 2026/02/03 03:58:52 DEBUG : drime root 'rclone-test-hojoluv9qobi': Purge remote 2026/02/03 03:58:52 NOTICE: purge failed: directory not found --- PASS: TestPurge (47.73s) === RUN TestRmdirsNoLeaveRoot run.go:185: Remote "drime root 'rclone-test-yohamax0qoza'", Local "Local file system at /tmp/rclone1795778209", Modify Window "876000h0m0s" 2026/02/03 03:58:56 INFO : A2: Making directory 2026/02/03 03:58:57 INFO : A1/B2: Making directory 2026/02/03 03:58:58 INFO : A1/B2/C2: Making directory 2026/02/03 03:58:58 INFO : A1/B1/C3: Making directory 2026/02/03 03:58:59 INFO : A3: Making directory 2026/02/03 03:59:00 INFO : A3/B3: Making directory 2026/02/03 03:59:00 INFO : A3/B3/C4: Making directory fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:662 Error: Should be true Test: TestRmdirsNoLeaveRoot Messages: listing wrong, want A1/B1/C1/one (3), A1/two (3) got A1/B1/C1/one (3) fstest.go:203: Not found "A1/two" fstest.go:206: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:206 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:310 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:662 Error: Not equal: expected: 0 actual : 1 Test: TestRmdirsNoLeaveRoot Messages: 1 objects not found 2026/02/03 03:59:16 DEBUG : removing 1 level 3 directories 2026/02/03 03:59:16 INFO : A3/B3/C4: Removing directory 2026/02/03 03:59:19 DEBUG : removing 2 level 3 directories 2026/02/03 03:59:19 INFO : A1/B2/C2: Removing directory 2026/02/03 03:59:19 INFO : A1/B1/C3: Removing directory 2026/02/03 03:59:20 DEBUG : removing 2 level 2 directories 2026/02/03 03:59:20 INFO : A3/B3: Removing directory 2026/02/03 03:59:20 INFO : A1/B2: Removing directory 2026/02/03 03:59:20 ERROR : A1/B2: Failed to rmdir: directory not empty 2026/02/03 03:59:21 DEBUG : removing 2 level 1 directories 2026/02/03 03:59:21 INFO : A3: Removing directory 2026/02/03 03:59:21 INFO : A2: Removing directory 2026/02/03 03:59:21 ERROR : A3: Failed to rmdir: directory not empty operations_test.go:705: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:705 Error: Received unexpected error: failed to remove directories: 2 errors: last error: directory not empty Test: TestRmdirsNoLeaveRoot 2026/02/03 03:59:23 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/03 03:59:23 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2026/02/03 03:59:24 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/03 03:59:24 DEBUG : pacer: Rate limited, increasing sleep to 40ms 2026/02/03 03:59:24 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/03 03:59:24 DEBUG : pacer: Rate limited, increasing sleep to 80ms 2026/02/03 03:59:24 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/03 03:59:24 DEBUG : pacer: Rate limited, increasing sleep to 160ms 2026/02/03 03:59:24 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/03 03:59:24 DEBUG : pacer: Rate limited, increasing sleep to 320ms 2026/02/03 03:59:25 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/03 03:59:25 DEBUG : pacer: Rate limited, increasing sleep to 640ms 2026/02/03 03:59:25 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/03 03:59:25 DEBUG : pacer: Rate limited, increasing sleep to 1.28s 2026/02/03 03:59:26 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/03 03:59:26 DEBUG : pacer: Rate limited, increasing sleep to 2.56s 2026/02/03 03:59:27 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/03 03:59:27 DEBUG : pacer: Rate limited, increasing sleep to 5.12s 2026/02/03 03:59:30 DEBUG : pacer: low level retry 10/10 (error Error "Server Error") 2026/02/03 03:59:30 DEBUG : pacer: Rate limited, increasing sleep to 10.24s run.go:130: removing dir "A3/B3" failed - try 1/3: failed to delete item: Error "Server Error" 2026/02/03 03:59:34 DEBUG : pacer: Reducing sleep to 5.12s 2026/02/03 03:59:45 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/03 03:59:45 DEBUG : pacer: Rate limited, increasing sleep to 10.24s 2026/02/03 03:59:50 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/03 03:59:50 DEBUG : pacer: Rate limited, increasing sleep to 20s 2026/02/03 04:00:00 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") panic: test timed out after 2h0m0s running tests: TestRmdirsNoLeaveRoot (1m19s) goroutine 10420 [running]: testing.(*M).startAlarm.func1() /usr/local/go/src/testing/testing.go:2484 +0x394 created by time.goFunc /usr/local/go/src/time/sleep.go:215 +0x2d goroutine 1 [chan receive, 1 minutes]: testing.(*T).Run(0xc000424e00, {0x29883bd?, 0xc000807ad8?}, 0x2ad8798) /usr/local/go/src/testing/testing.go:1859 +0x431 testing.runTests.func1(0xc000424e00) /usr/local/go/src/testing/testing.go:2279 +0x37 testing.tRunner(0xc000424e00, 0xc000807c18) /usr/local/go/src/testing/testing.go:1792 +0xf4 testing.runTests(0xc000a68240, {0x44a3d80, 0x6f, 0x6f}, {0x849500?, 0x44df3c0?, 0x44bb600?}) /usr/local/go/src/testing/testing.go:2277 +0x4b4 testing.(*M).Run(0xc0008f05a0) /usr/local/go/src/testing/testing.go:2142 +0x64a github.com/rclone/rclone/fstest.TestMain(0xc0008f05a0) /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:74 +0xa6 github.com/rclone/rclone/fs/operations_test.TestMain(...) /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:61 main.main() _testmain.go:269 +0xa9 goroutine 10101 [chan receive]: github.com/rclone/rclone/lib/pacer.(*Pacer).beginCall(0xc00016a540, 0x0) /home/rclone/go/src/github.com/rclone/rclone/lib/pacer/pacer.go:162 +0x26 github.com/rclone/rclone/lib/pacer.(*Pacer).call(0xc00016a540, 0xc000b73a80, 0xa) /home/rclone/go/src/github.com/rclone/rclone/lib/pacer/pacer.go:215 +0xb2 github.com/rclone/rclone/lib/pacer.(*Pacer).Call(0xc00016a540, 0xc000b73a80) /home/rclone/go/src/github.com/rclone/rclone/lib/pacer/pacer.go:235 +0x93 github.com/rclone/rclone/backend/drime.(*Fs).deleteObject(0xc0007cc000, {0x2e7a9d8, 0x44dee20}, {0xc000013b70, 0x9}) /home/rclone/go/src/github.com/rclone/rclone/backend/drime/drime.go:703 +0x1eb github.com/rclone/rclone/backend/drime.(*Fs).purgeCheck(0xc0007cc000, {0x2e7a9d8, 0x44dee20}, {0xc000013bd5, 0x5}, 0x1) /home/rclone/go/src/github.com/rclone/rclone/backend/drime/drime.go:744 +0x156 github.com/rclone/rclone/backend/drime.(*Fs).Rmdir(0x4?, {0x2e7a9d8?, 0x44dee20?}, {0xc000013bd5?, 0xc000003c00?}) /home/rclone/go/src/github.com/rclone/rclone/backend/drime/drime.go:757 +0x28 github.com/rclone/rclone/fstest.newRunIndividual.func1.2() /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:174 +0x30 github.com/rclone/rclone/fstest.retry(0xc000b621c0, {0xc000a28fa8, 0x14}, 0xc0007c3920) /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:126 +0xde github.com/rclone/rclone/fstest.newRunIndividual.func1() /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:173 +0x271 github.com/rclone/rclone/fstest.(*Run).Finalise(0xc000416d90) /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393 +0x1c testing.(*common).Cleanup.func1() /usr/local/go/src/testing/testing.go:1211 +0x106 testing.(*common).runCleanup(0xc000b621c0, 0xc000003c00?) /usr/local/go/src/testing/testing.go:1445 +0xea testing.tRunner.func2() /usr/local/go/src/testing/testing.go:1786 +0x25 runtime.Goexit() /usr/local/go/src/runtime/panic.go:636 +0x5e testing.(*common).FailNow(0xc000b621c0) /usr/local/go/src/testing/testing.go:1041 +0x4a github.com/stretchr/testify/require.NoError({0x2e64790, 0xc000b621c0}, {0x2e50aa0, 0xc000637520}, {0x0, 0x0, 0x0}) /home/rclone/go/pkg/mod/github.com/stretchr/testify@v1.11.1/require/require.go:1401 +0xca github.com/rclone/rclone/fs/operations_test.TestRmdirsNoLeaveRoot(0xc000b621c0) /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:705 +0xaa7 testing.tRunner(0xc000b621c0, 0x2ad8798) /usr/local/go/src/testing/testing.go:1792 +0xf4 created by testing.(*T).Run in goroutine 1 /usr/local/go/src/testing/testing.go:1851 +0x413 goroutine 51 [syscall, 120 minutes]: os/signal.signal_recv() /usr/local/go/src/runtime/sigqueue.go:152 +0x29 os/signal.loop() /usr/local/go/src/os/signal/signal_unix.go:23 +0x13 created by os/signal.Notify.func1.1 in goroutine 1 /usr/local/go/src/os/signal/signal.go:152 +0x1f goroutine 52 [chan receive, 120 minutes]: github.com/rclone/rclone/fs/accounting.(*tokenBucket).startSignalHandler.func1() /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:24 +0x27 created by github.com/rclone/rclone/fs/accounting.(*tokenBucket).startSignalHandler in goroutine 1 /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:21 +0xa7 goroutine 2286 [chan receive (nil chan), 101 minutes]: github.com/rclone/rclone/fs/march.(*March).Run.func2() /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:257 +0x3c created by github.com/rclone/rclone/fs/march.(*March).Run in goroutine 2276 /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:255 +0x378 goroutine 3339 [chan receive, 98 minutes]: github.com/rclone/rclone/fs/accounting.(*tokenBucket).startSignalHandler.func1() /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:24 +0x27 created by github.com/rclone/rclone/fs/accounting.(*tokenBucket).startSignalHandler in goroutine 3030 /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:21 +0xa7 goroutine 9758 [chan receive, 3 minutes]: github.com/rclone/rclone/fs/accounting.(*tokenBucket).startSignalHandler.func1() /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:24 +0x27 created by github.com/rclone/rclone/fs/accounting.(*tokenBucket).startSignalHandler in goroutine 9757 /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:21 +0xa7 goroutine 1307 [chan receive (nil chan), 104 minutes]: github.com/rclone/rclone/fs/march.(*March).Run.func2() /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:257 +0x3c created by github.com/rclone/rclone/fs/march.(*March).Run in goroutine 1281 /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:255 +0x378 goroutine 90 [chan receive, 119 minutes]: github.com/rclone/rclone/lib/atexit.Register.func1.1() /home/rclone/go/src/github.com/rclone/rclone/lib/atexit/atexit.go:45 +0x29 created by github.com/rclone/rclone/lib/atexit.Register.func1 in goroutine 37 /home/rclone/go/src/github.com/rclone/rclone/lib/atexit/atexit.go:44 +0x68 goroutine 2103 [chan receive (nil chan), 101 minutes]: github.com/rclone/rclone/fs/march.(*March).Run.func2() /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:257 +0x3c created by github.com/rclone/rclone/fs/march.(*March).Run in goroutine 2093 /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:255 +0x378 goroutine 2178 [chan receive (nil chan), 101 minutes]: github.com/rclone/rclone/fs/march.(*March).Run.func2() /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:257 +0x3c created by github.com/rclone/rclone/fs/march.(*March).Run in goroutine 2136 /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:255 +0x378 goroutine 2161 [chan receive (nil chan), 101 minutes]: github.com/rclone/rclone/fs/march.(*March).Run.func2() /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:257 +0x3c created by github.com/rclone/rclone/fs/march.(*March).Run in goroutine 2147 /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:255 +0x378 goroutine 3467 [chan receive, 98 minutes]: github.com/rclone/rclone/fs/accounting.(*tokenBucket).startSignalHandler.func1() /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:24 +0x27 created by github.com/rclone/rclone/fs/accounting.(*tokenBucket).startSignalHandler in goroutine 3030 /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:21 +0xa7 goroutine 1763 [chan receive (nil chan), 101 minutes]: github.com/rclone/rclone/fs/march.(*March).Run.func2() /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:257 +0x3c created by github.com/rclone/rclone/fs/march.(*March).Run in goroutine 1721 /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:255 +0x378 goroutine 3031 [chan receive, 99 minutes]: github.com/rclone/rclone/fs/accounting.(*tokenBucket).startSignalHandler.func1() /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:24 +0x27 created by github.com/rclone/rclone/fs/accounting.(*tokenBucket).startSignalHandler in goroutine 3030 /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:21 +0xa7 goroutine 1665 [chan receive (nil chan), 101 minutes]: github.com/rclone/rclone/fs/march.(*March).Run.func2() /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:257 +0x3c created by github.com/rclone/rclone/fs/march.(*March).Run in goroutine 1655 /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:255 +0x378 goroutine 1908 [chan receive (nil chan), 101 minutes]: github.com/rclone/rclone/fs/march.(*March).Run.func2() /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:257 +0x3c created by github.com/rclone/rclone/fs/march.(*March).Run in goroutine 1898 /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:255 +0x378 goroutine 1418 [chan receive (nil chan), 102 minutes]: github.com/rclone/rclone/fs/march.(*March).Run.func2() /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:257 +0x3c created by github.com/rclone/rclone/fs/march.(*March).Run in goroutine 1408 /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:255 +0x378 goroutine 1539 [chan receive (nil chan), 102 minutes]: github.com/rclone/rclone/fs/march.(*March).Run.func2() /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:257 +0x3c created by github.com/rclone/rclone/fs/march.(*March).Run in goroutine 1514 /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:255 +0x378 goroutine 3118 [chan receive, 98 minutes]: github.com/rclone/rclone/fs/accounting.(*tokenBucket).startSignalHandler.func1() /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:24 +0x27 created by github.com/rclone/rclone/fs/accounting.(*tokenBucket).startSignalHandler in goroutine 3030 /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:21 +0xa7 goroutine 3531 [chan receive, 97 minutes]: github.com/rclone/rclone/fs/accounting.(*tokenBucket).startSignalHandler.func1() /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:24 +0x27 created by github.com/rclone/rclone/fs/accounting.(*tokenBucket).startSignalHandler in goroutine 3030 /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:21 +0xa7 goroutine 1604 [chan receive (nil chan), 102 minutes]: github.com/rclone/rclone/fs/march.(*March).Run.func2() /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:257 +0x3c created by github.com/rclone/rclone/fs/march.(*March).Run in goroutine 1594 /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:255 +0x378 goroutine 3615 [chan receive, 97 minutes]: github.com/rclone/rclone/fs/accounting.(*tokenBucket).startSignalHandler.func1() /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:24 +0x27 created by github.com/rclone/rclone/fs/accounting.(*tokenBucket).startSignalHandler in goroutine 3030 /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:21 +0xa7 goroutine 1460 [chan receive (nil chan), 102 minutes]: github.com/rclone/rclone/fs/march.(*March).Run.func2() /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:257 +0x3c created by github.com/rclone/rclone/fs/march.(*March).Run in goroutine 1450 /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:255 +0x378 goroutine 1261 [chan receive (nil chan), 104 minutes]: github.com/rclone/rclone/fs/march.(*March).Run.func2() /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:257 +0x3c created by github.com/rclone/rclone/fs/march.(*March).Run in goroutine 1251 /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:255 +0x378 goroutine 1929 [chan receive (nil chan), 101 minutes]: github.com/rclone/rclone/fs/march.(*March).Run.func2() /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:257 +0x3c created by github.com/rclone/rclone/fs/march.(*March).Run in goroutine 1871 /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:255 +0x378 goroutine 1844 [chan receive (nil chan), 101 minutes]: github.com/rclone/rclone/fs/march.(*March).Run.func2() /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:257 +0x3c created by github.com/rclone/rclone/fs/march.(*March).Run in goroutine 1834 /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:255 +0x378 goroutine 1205 [chan receive (nil chan), 104 minutes]: github.com/rclone/rclone/fs/march.(*March).Run.func2() /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:257 +0x3c created by github.com/rclone/rclone/fs/march.(*March).Run in goroutine 1195 /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:255 +0x378 goroutine 1477 [chan receive (nil chan), 102 minutes]: github.com/rclone/rclone/fs/march.(*March).Run.func2() /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:257 +0x3c created by github.com/rclone/rclone/fs/march.(*March).Run in goroutine 1387 /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:255 +0x378 goroutine 1668 [chan receive (nil chan), 102 minutes]: github.com/rclone/rclone/fs/march.(*March).Run.func2() /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:257 +0x3c created by github.com/rclone/rclone/fs/march.(*March).Run in goroutine 1626 /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:255 +0x378 goroutine 1348 [chan receive (nil chan), 104 minutes]: github.com/rclone/rclone/fs/march.(*March).Run.func2() /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:257 +0x3c created by github.com/rclone/rclone/fs/march.(*March).Run in goroutine 1210 /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:255 +0x378 goroutine 2051 [chan receive (nil chan), 101 minutes]: github.com/rclone/rclone/fs/march.(*March).Run.func2() /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:257 +0x3c created by github.com/rclone/rclone/fs/march.(*March).Run in goroutine 2041 /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:255 +0x378 goroutine 2245 [chan receive (nil chan), 101 minutes]: github.com/rclone/rclone/fs/march.(*March).Run.func2() /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:257 +0x3c created by github.com/rclone/rclone/fs/march.(*March).Run in goroutine 2235 /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:255 +0x378 goroutine 2322 [chan receive (nil chan), 101 minutes]: github.com/rclone/rclone/fs/march.(*March).Run.func2() /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:257 +0x3c created by github.com/rclone/rclone/fs/march.(*March).Run in goroutine 2056 /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:255 +0x378 goroutine 3396 [chan receive, 98 minutes]: github.com/rclone/rclone/fs/accounting.(*tokenBucket).startSignalHandler.func1() /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:24 +0x27 created by github.com/rclone/rclone/fs/accounting.(*tokenBucket).startSignalHandler in goroutine 3030 /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:21 +0xa7 goroutine 3550 [chan receive, 97 minutes]: github.com/rclone/rclone/fs/accounting.(*tokenBucket).startSignalHandler.func1() /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:24 +0x27 created by github.com/rclone/rclone/fs/accounting.(*tokenBucket).startSignalHandler in goroutine 3030 /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:21 +0xa7 goroutine 3198 [chan receive, 98 minutes]: github.com/rclone/rclone/fs/accounting.(*tokenBucket).startSignalHandler.func1() /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:24 +0x27 created by github.com/rclone/rclone/fs/accounting.(*tokenBucket).startSignalHandler in goroutine 3030 /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:21 +0xa7 goroutine 3083 [chan receive, 98 minutes]: github.com/rclone/rclone/fs/accounting.(*tokenBucket).startSignalHandler.func1() /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:24 +0x27 created by github.com/rclone/rclone/fs/accounting.(*tokenBucket).startSignalHandler in goroutine 3030 /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:21 +0xa7 goroutine 10438 [sleep]: time.Sleep(0x4a817c800) /usr/local/go/src/runtime/time.go:338 +0x165 github.com/rclone/rclone/lib/pacer.(*Pacer).beginCall.func1(0xc000a5c760?) /home/rclone/go/src/github.com/rclone/rclone/lib/pacer/pacer.go:170 +0x1c created by github.com/rclone/rclone/lib/pacer.(*Pacer).beginCall in goroutine 10101 /home/rclone/go/src/github.com/rclone/rclone/lib/pacer/pacer.go:169 +0xf6 goroutine 9335 [IO wait]: internal/poll.runtime_pollWait(0x7202a946ae30, 0x72) /usr/local/go/src/runtime/netpoll.go:351 +0x85 internal/poll.(*pollDesc).wait(0xc00064c180?, 0xc000029300?, 0x0) /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x27 internal/poll.(*pollDesc).waitRead(...) /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 internal/poll.(*FD).Read(0xc00064c180, {0xc000029300, 0x1300, 0x1300}) /usr/local/go/src/internal/poll/fd_unix.go:165 +0x27a net.(*netFD).Read(0xc00064c180, {0xc000029300?, 0x1260?, 0xc000636280?}) /usr/local/go/src/net/fd_posix.go:55 +0x25 net.(*conn).Read(0xc000578000, {0xc000029300?, 0xc00002939b?, 0x5?}) /usr/local/go/src/net/net.go:194 +0x45 github.com/rclone/rclone/fs/fshttp.(*timeoutConn).Read(0xc0007d2030, {0xc000029300?, 0x7202a821e178?, 0x7202f1944f30?}) /home/rclone/go/src/github.com/rclone/rclone/fs/fshttp/dialer.go:111 +0x29 crypto/tls.(*atLeastReader).Read(0xc0009fa678, {0xc000029300?, 0x1260?, 0x3?}) /usr/local/go/src/crypto/tls/conn.go:809 +0x3b bytes.(*Buffer).ReadFrom(0xc0007869b8, {0x2e523a0, 0xc0009fa678}) /usr/local/go/src/bytes/buffer.go:211 +0x98 crypto/tls.(*Conn).readFromUntil(0xc000786708, {0x7202a8288a98, 0xc0007d2030}, 0x4426d4?) /usr/local/go/src/crypto/tls/conn.go:831 +0xde crypto/tls.(*Conn).readRecordOrCCS(0xc000786708, 0x0) /usr/local/go/src/crypto/tls/conn.go:629 +0x3cf crypto/tls.(*Conn).readRecord(...) /usr/local/go/src/crypto/tls/conn.go:591 crypto/tls.(*Conn).Read(0xc000786708, {0xc000a2b000, 0x1000, 0xc000aa8ca0?}) /usr/local/go/src/crypto/tls/conn.go:1385 +0x145 bufio.(*Reader).Read(0xc0003a99e0, {0xc0005a42e0, 0x9, 0x4423d50?}) /usr/local/go/src/bufio/bufio.go:245 +0x197 io.ReadAtLeast({0x2e50740, 0xc0003a99e0}, {0xc0005a42e0, 0x9, 0x9}, 0x9) /usr/local/go/src/io/io.go:335 +0x91 io.ReadFull(...) /usr/local/go/src/io/io.go:354 net/http.http2readFrameHeader({0xc0005a42e0, 0x9, 0xc000241080?}, {0x2e50740?, 0xc0003a99e0?}) /usr/local/go/src/net/http/h2_bundle.go:1805 +0x65 net/http.(*http2Framer).ReadFrame(0xc0005a42a0) /usr/local/go/src/net/http/h2_bundle.go:2072 +0x7d net/http.(*http2clientConnReadLoop).run(0xc000aa8fa8) /usr/local/go/src/net/http/h2_bundle.go:9933 +0xda net/http.(*http2ClientConn).readLoop(0xc000b62000) /usr/local/go/src/net/http/h2_bundle.go:9812 +0x79 created by net/http.(*http2Transport).newClientConn in goroutine 9431 /usr/local/go/src/net/http/h2_bundle.go:8334 +0xde5 "./operations.test -test.v -test.timeout 2h0m0s -remote TestDrime: -verbose" - Finished ERROR in 2h0m0.793810161s (try 1/5): exit status 2: Failed [TestMultithreadCopy/upload=false,size=10485759,streams=2 TestMultithreadCopy/upload=false,size=10485760,streams=2 TestMultithreadCopy/upload=true,size=10485759,streams=2 TestMultithreadCopy/upload=true,size=10485760,streams=2 TestMultithreadCopy/upload=true,size=10485761,streams=2 TestMultithreadCopyAbort TestCheckSizeOnly TestParseSumFile TestCheckSumDownload TestApplyTransforms TestCopyFile TestCopyFileCopyDest TestCopyFileMaxTransfer TestListDirSorted TestListDirSortedFn TestListJSON/Default TestListJSON/DirsOnly TestListJSON/Recurse TestListJSON/Metadata TestStatJSON TestMkdir TestLsd TestLs TestLsWithFilesFrom TestLsLong TestCount TestDelete TestMaxDelete TestMaxDeleteSizeLargeFile TestMaxDeleteSize TestReadFile TestCat]