"./operations.test -test.v -test.timeout 2h0m0s -remote TestDrime: -verbose" - Starting (try 1/5)
2026/02/02 01:02:56 DEBUG : Creating backend with remote "TestDrime:rclone-test-seretuh0vefa"
2026/02/02 01:02:56 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/02/02 01:02:57 DEBUG : Creating backend with remote "/tmp/rclone4245148629"
=== 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-seretuh0vefa'", Local "Local file system at /tmp/rclone4245148629", 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
2026/02/02 01:03:03 ERROR : error listing: directory not found
fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3
fstest.go:301: Flushing the directory cache
2026/02/02 01:03:06 ERROR : error listing: directory not found
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/02 01:03:10 DEBUG : test-multithread-copy-false-10485759-2: multi-thread copy: disabling buffering because destination uses OpenWriterAt
2026/02/02 01:03:10 DEBUG : test-multithread-copy-false-10485759-2: multi-thread copy: write buffer set to 131072
2026/02/02 01:03:10 DEBUG : test-multithread-copy-false-10485759-2: multi-thread copy: chunk size 64Mi was bigger than source file size 10.000Mi
2026/02/02 01:03:10 DEBUG : test-multithread-copy-false-10485759-2: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 2
2026/02/02 01:03:10 DEBUG : test-multithread-copy-false-10485759-2: multi-thread copy: number of streams 4 was bigger than number of chunks 1
2026/02/02 01:03:10 DEBUG : test-multithread-copy-false-10485759-2: Starting multi-thread copy with 1 chunks of size 10.000Mi with 1 parallel streams
2026/02/02 01:03:10 DEBUG : test-multithread-copy-false-10485759-2: multi-thread copy: chunk 1/1 (0-10485759) size 10.000Mi starting
2026/02/02 01:03:11 DEBUG : test-multithread-copy-false-10485759-2: writing chunk 0
2026/02/02 01:03:11 DEBUG : test-multithread-copy-false-10485759-2: multi-thread copy: chunk 1/1 (0-10485759) size 10.000Mi finished
2026/02/02 01:03:11 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/02 01:03:25 DEBUG : test-multithread-copy-false-10485760-2: multi-thread copy: disabling buffering because destination uses OpenWriterAt
2026/02/02 01:03:25 DEBUG : test-multithread-copy-false-10485760-2: multi-thread copy: write buffer set to 131072
2026/02/02 01:03:25 DEBUG : test-multithread-copy-false-10485760-2: multi-thread copy: chunk size 64Mi was bigger than source file size 10Mi
2026/02/02 01:03:25 DEBUG : test-multithread-copy-false-10485760-2: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 2
2026/02/02 01:03:25 DEBUG : test-multithread-copy-false-10485760-2: multi-thread copy: number of streams 4 was bigger than number of chunks 1
2026/02/02 01:03:25 DEBUG : test-multithread-copy-false-10485760-2: Starting multi-thread copy with 1 chunks of size 10Mi with 1 parallel streams
2026/02/02 01:03:25 DEBUG : test-multithread-copy-false-10485760-2: multi-thread copy: chunk 1/1 (0-10485760) size 10Mi starting
2026/02/02 01:03:25 DEBUG : test-multithread-copy-false-10485760-2: writing chunk 0
2026/02/02 01:03:26 DEBUG : test-multithread-copy-false-10485760-2: multi-thread copy: chunk 1/1 (0-10485760) size 10Mi finished
2026/02/02 01:03:26 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: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=10485761,streams=2
Messages: listing wrong, want
test-multithread-copy-false-10485761-2 (10485761) got
test-multithread-copy-false-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:191
/usr/local/go/src/runtime/asm_amd64.s:1700
Error: Should be true
Test: TestMultithreadCopy/upload=false,size=10485761,streams=2
Messages: Unexpected file "test-multithread-copy-false-10485760-2"
fstest.go:203: Not found "test-multithread-copy-false-10485761-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=10485761,streams=2
Messages: 1 objects not found
2026/02/02 01:03:39 DEBUG : test-multithread-copy-false-10485761-2: multi-thread copy: disabling buffering because destination uses OpenWriterAt
2026/02/02 01:03:39 DEBUG : test-multithread-copy-false-10485761-2: multi-thread copy: write buffer set to 131072
2026/02/02 01:03:39 DEBUG : test-multithread-copy-false-10485761-2: multi-thread copy: chunk size 64Mi was bigger than source file size 10.000Mi
2026/02/02 01:03:39 DEBUG : test-multithread-copy-false-10485761-2: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 2
2026/02/02 01:03:39 DEBUG : test-multithread-copy-false-10485761-2: multi-thread copy: number of streams 4 was bigger than number of chunks 1
2026/02/02 01:03:39 DEBUG : test-multithread-copy-false-10485761-2: Starting multi-thread copy with 1 chunks of size 10.000Mi with 1 parallel streams
2026/02/02 01:03:39 DEBUG : test-multithread-copy-false-10485761-2: multi-thread copy: chunk 1/1 (0-10485761) size 10.000Mi starting
2026/02/02 01:03:39 DEBUG : test-multithread-copy-false-10485761-2: writing chunk 0
2026/02/02 01:03:40 DEBUG : test-multithread-copy-false-10485761-2: multi-thread copy: chunk 1/1 (0-10485761) size 10.000Mi finished
2026/02/02 01:03:40 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
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=10485759,streams=2
Messages: listing wrong, want
got
test-multithread-copy-false-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: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=10485759,streams=2
Messages: Unexpected file "test-multithread-copy-false-10485761-2"
2026/02/02 01:03:51 DEBUG : test-multithread-copy-true-10485759-2: multi-thread copy: disabling buffering because source is local disk
2026/02/02 01:03:52 DEBUG : test-multithread-copy-true-10485759-2: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 2
2026/02/02 01:03:52 DEBUG : test-multithread-copy-true-10485759-2: multi-thread copy: number of streams 4 was bigger than number of chunks 2
2026/02/02 01:03:52 DEBUG : test-multithread-copy-true-10485759-2: Starting multi-thread copy with 2 chunks of size 5Mi with 2 parallel streams
2026/02/02 01:03:52 DEBUG : test-multithread-copy-true-10485759-2: multi-thread copy: chunk 2/2 (5242880-10485759) size 5.000Mi starting
2026/02/02 01:03:52 DEBUG : test-multithread-copy-true-10485759-2: multi-thread copy: chunk 1/2 (0-5242880) size 5Mi starting
2026/02/02 01:03:52 DEBUG : test-multithread-copy-true-10485759-2: multi-thread copy: chunk 1/2 (0-5242880) size 5Mi finished
2026/02/02 01:03:52 DEBUG : test-multithread-copy-true-10485759-2: multi-thread copy: chunk 2/2 (5242880-10485759) size 5.000Mi finished
2026/02/02 01:03:54 DEBUG : test-multithread-copy-true-10485759-2: Finished multi-thread copy with 2 parts of size 5Mi
=== RUN TestMultithreadCopy/upload=true,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:186
Error: Should be true
Test: TestMultithreadCopy/upload=true,size=10485760,streams=2
Messages: listing wrong, want
got
test-multithread-copy-true-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:186
/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/02 01:04:04 DEBUG : test-multithread-copy-true-10485760-2: multi-thread copy: disabling buffering because source is local disk
2026/02/02 01:04:05 DEBUG : test-multithread-copy-true-10485760-2: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 2
2026/02/02 01:04:05 DEBUG : test-multithread-copy-true-10485760-2: multi-thread copy: number of streams 4 was bigger than number of chunks 2
2026/02/02 01:04:05 DEBUG : test-multithread-copy-true-10485760-2: Starting multi-thread copy with 2 chunks of size 5Mi with 2 parallel streams
2026/02/02 01:04:05 DEBUG : test-multithread-copy-true-10485760-2: multi-thread copy: chunk 2/2 (5242880-10485760) size 5Mi starting
2026/02/02 01:04:05 DEBUG : test-multithread-copy-true-10485760-2: multi-thread copy: chunk 1/2 (0-5242880) size 5Mi starting
2026/02/02 01:04:06 DEBUG : test-multithread-copy-true-10485760-2: multi-thread copy: chunk 1/2 (0-5242880) size 5Mi finished
2026/02/02 01:04:06 DEBUG : test-multithread-copy-true-10485760-2: multi-thread copy: chunk 2/2 (5242880-10485760) size 5Mi finished
2026/02/02 01:04:07 DEBUG : test-multithread-copy-true-10485760-2: Finished multi-thread copy with 2 parts of size 5Mi
=== 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-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-10485760-2"
2026/02/02 01:04:17 DEBUG : test-multithread-copy-true-10485761-2: multi-thread copy: disabling buffering because source is local disk
2026/02/02 01:04:18 DEBUG : test-multithread-copy-true-10485761-2: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 2
2026/02/02 01:04:18 DEBUG : test-multithread-copy-true-10485761-2: multi-thread copy: number of streams 4 was bigger than number of chunks 3
2026/02/02 01:04:18 DEBUG : test-multithread-copy-true-10485761-2: Starting multi-thread copy with 3 chunks of size 5Mi with 3 parallel streams
2026/02/02 01:04:18 DEBUG : test-multithread-copy-true-10485761-2: multi-thread copy: chunk 3/3 (10485760-10485761) size 1 starting
2026/02/02 01:04:18 DEBUG : test-multithread-copy-true-10485761-2: multi-thread copy: chunk 2/3 (5242880-10485760) size 5Mi starting
2026/02/02 01:04:18 DEBUG : test-multithread-copy-true-10485761-2: multi-thread copy: chunk 1/3 (0-5242880) size 5Mi starting
2026/02/02 01:04:19 DEBUG : test-multithread-copy-true-10485761-2: multi-thread copy: chunk 3/3 (10485760-10485761) size 1 finished
2026/02/02 01:04:19 DEBUG : test-multithread-copy-true-10485761-2: multi-thread copy: chunk 1/3 (0-5242880) size 5Mi finished
2026/02/02 01:04:19 DEBUG : test-multithread-copy-true-10485761-2: multi-thread copy: chunk 2/3 (5242880-10485760) size 5Mi finished
2026/02/02 01:04:20 DEBUG : test-multithread-copy-true-10485761-2: Finished multi-thread copy with 3 parts of size 5Mi
2026/02/02 01:04:22 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/02/02 01:04:22 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/02/02 01:04:22 DEBUG : pacer: low level retry 2/10 (error Error "Server Error")
2026/02/02 01:04:22 DEBUG : pacer: Rate limited, increasing sleep to 40ms
2026/02/02 01:04:23 DEBUG : pacer: low level retry 3/10 (error Error "Server Error")
2026/02/02 01:04:23 DEBUG : pacer: Rate limited, increasing sleep to 80ms
2026/02/02 01:04:23 DEBUG : pacer: low level retry 4/10 (error Error "Server Error")
2026/02/02 01:04:23 DEBUG : pacer: Rate limited, increasing sleep to 160ms
2026/02/02 01:04:23 DEBUG : pacer: low level retry 5/10 (error Error "Server Error")
2026/02/02 01:04:23 DEBUG : pacer: Rate limited, increasing sleep to 320ms
2026/02/02 01:04:24 DEBUG : pacer: low level retry 6/10 (error Error "Server Error")
2026/02/02 01:04:24 DEBUG : pacer: Rate limited, increasing sleep to 640ms
2026/02/02 01:04:24 DEBUG : pacer: low level retry 7/10 (error Error "Server Error")
2026/02/02 01:04:24 DEBUG : pacer: Rate limited, increasing sleep to 1.28s
2026/02/02 01:04:25 DEBUG : pacer: low level retry 8/10 (error Error "Server Error")
2026/02/02 01:04:25 DEBUG : pacer: Rate limited, increasing sleep to 2.56s
2026/02/02 01:04:26 DEBUG : pacer: low level retry 9/10 (error Error "Server Error")
2026/02/02 01:04:26 DEBUG : pacer: Rate limited, increasing sleep to 5.12s
2026/02/02 01:04:29 DEBUG : pacer: low level retry 10/10 (error Error "Server Error")
2026/02/02 01:04:29 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/02 01:04:34 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/02/02 01:04:34 DEBUG : pacer: Rate limited, increasing sleep to 20s
2026/02/02 01:04:44 DEBUG : pacer: low level retry 2/10 (error Error "Server Error")
2026/02/02 01:05:04 DEBUG : pacer: low level retry 3/10 (error Error "Server Error")
2026/02/02 01:05:24 DEBUG : pacer: low level retry 4/10 (error Error "Server Error")
2026/02/02 01:05:44 DEBUG : pacer: low level retry 5/10 (error Error "Server Error")
2026/02/02 01:06:04 DEBUG : pacer: low level retry 6/10 (error Error "Server Error")
2026/02/02 01:06:24 DEBUG : pacer: low level retry 7/10 (error Error "Server Error")
2026/02/02 01:06:44 DEBUG : pacer: low level retry 8/10 (error Error "Server Error")
2026/02/02 01:07:04 DEBUG : pacer: low level retry 9/10 (error Error "Server Error")
2026/02/02 01:07:24 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/02 01:07:44 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/02/02 01:08:04 DEBUG : pacer: low level retry 2/10 (error Error "Server Error")
2026/02/02 01:08:24 DEBUG : pacer: low level retry 3/10 (error Error "Server Error")
2026/02/02 01:08:44 DEBUG : pacer: low level retry 4/10 (error Error "Server Error")
2026/02/02 01:09:04 DEBUG : pacer: low level retry 5/10 (error Error "Server Error")
2026/02/02 01:09:24 DEBUG : pacer: low level retry 6/10 (error Error "Server Error")
2026/02/02 01:09:44 DEBUG : pacer: low level retry 7/10 (error Error "Server Error")
2026/02/02 01:10:04 DEBUG : pacer: low level retry 8/10 (error Error "Server Error")
2026/02/02 01:10:24 DEBUG : pacer: low level retry 9/10 (error Error "Server Error")
2026/02/02 01:10:44 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/02 01:11:04 DEBUG : pacer: Reducing sleep to 10s
--- FAIL: TestMultithreadCopy (487.36s)
--- FAIL: TestMultithreadCopy/upload=false,size=10485759,streams=2 (13.96s)
--- FAIL: TestMultithreadCopy/upload=false,size=10485760,streams=2 (13.91s)
--- FAIL: TestMultithreadCopy/upload=false,size=10485761,streams=2 (14.10s)
--- FAIL: TestMultithreadCopy/upload=true,size=10485759,streams=2 (13.88s)
--- FAIL: TestMultithreadCopy/upload=true,size=10485760,streams=2 (13.62s)
--- FAIL: TestMultithreadCopy/upload=true,size=10485761,streams=2 (13.08s)
=== RUN TestMultithreadCopyAbort
run.go:185: Remote "drime root 'rclone-test-seretuh0vefa'", Local "Local file system at /tmp/rclone4245148629", Modify Window "876000h0m0s"
2026/02/02 01:11:24 DEBUG : pacer: Reducing sleep to 5s
2026/02/02 01:11:34 DEBUG : pacer: Reducing sleep to 2.5s
2026/02/02 01:11:39 DEBUG : pacer: Reducing sleep to 1.25s
2026/02/02 01:11:42 DEBUG : pacer: Reducing sleep to 625ms
2026/02/02 01:11:43 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/02 01:11:44 DEBUG : pacer: Reducing sleep to 156.25ms
2026/02/02 01:11:44 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/02 01:11:47 DEBUG : pacer: Reducing sleep to 39.0625ms
2026/02/02 01:11:47 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/02 01:11:52 DEBUG : test-multithread-abort: multi-thread copy: disabling buffering because source is local disk
2026/02/02 01:11:52 DEBUG : pacer: Reducing sleep to 10ms
2026/02/02 01:11:53 DEBUG : test-multithread-abort: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 1
2026/02/02 01:11:53 DEBUG : test-multithread-abort: multi-thread copy: number of streams 4 was bigger than number of chunks 3
2026/02/02 01:11:53 DEBUG : test-multithread-abort: Starting multi-thread copy with 3 chunks of size 5Mi with 3 parallel streams
2026/02/02 01:11:53 DEBUG : test-multithread-abort: multi-thread copy: chunk 3/3 (10485760-10485761) size 1 starting
2026/02/02 01:11:53 DEBUG : test-multithread-abort: multi-thread copy: chunk 1/3 (0-5242880) size 5Mi starting
2026/02/02 01:11:53 DEBUG : Open with options = [RangeOption(0,5242879)]
2026/02/02 01:11:53 DEBUG : test-multithread-abort: multi-thread copy: chunk 2/3 (5242880-10485760) size 5Mi starting
2026/02/02 01:11:53 DEBUG : Open with options = [RangeOption(5242880,10485759)]
2026/02/02 01:11:53 DEBUG : Open with options = [RangeOption(10485760,10485760)]
2026/02/02 01:11:53 DEBUG : test-multithread-abort: multi-thread copy: chunk 2/3 (5242880-10485760) size 5Mi finished
2026/02/02 01:11:54 DEBUG : test-multithread-abort: multi-thread copy: chunk 1/3 (0-5242880) size 5Mi finished
2026/02/02 01:11:54 DEBUG : Returning error reader
2026/02/02 01:11:54 DEBUG : BOOM: simulated read failure
2026/02/02 01:11:54 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 0/10: BOOM: simulated read failure
2026/02/02 01:11:54 DEBUG : Open with options = [RangeOption(10485760,10485760)]
2026/02/02 01:11:55 DEBUG : Returning error reader
2026/02/02 01:11:55 DEBUG : BOOM: simulated read failure
2026/02/02 01:11:55 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 1/10: BOOM: simulated read failure
2026/02/02 01:11:55 DEBUG : Open with options = [RangeOption(10485760,10485760)]
2026/02/02 01:11:56 DEBUG : Returning error reader
2026/02/02 01:11:56 DEBUG : BOOM: simulated read failure
2026/02/02 01:11:56 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 2/10: BOOM: simulated read failure
2026/02/02 01:11:56 DEBUG : Open with options = [RangeOption(10485760,10485760)]
2026/02/02 01:11:57 DEBUG : Returning error reader
2026/02/02 01:11:57 DEBUG : BOOM: simulated read failure
2026/02/02 01:11:57 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 3/10: BOOM: simulated read failure
2026/02/02 01:11:57 DEBUG : Open with options = [RangeOption(10485760,10485760)]
2026/02/02 01:11:58 DEBUG : Returning error reader
2026/02/02 01:11:58 DEBUG : BOOM: simulated read failure
2026/02/02 01:11:58 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 4/10: BOOM: simulated read failure
2026/02/02 01:11:58 DEBUG : Open with options = [RangeOption(10485760,10485760)]
2026/02/02 01:11:59 DEBUG : Returning error reader
2026/02/02 01:11:59 DEBUG : BOOM: simulated read failure
2026/02/02 01:11:59 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 5/10: BOOM: simulated read failure
2026/02/02 01:11:59 DEBUG : Open with options = [RangeOption(10485760,10485760)]
2026/02/02 01:12:00 DEBUG : Returning error reader
2026/02/02 01:12:00 DEBUG : BOOM: simulated read failure
2026/02/02 01:12:00 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 6/10: BOOM: simulated read failure
2026/02/02 01:12:00 DEBUG : Open with options = [RangeOption(10485760,10485760)]
2026/02/02 01:12:01 DEBUG : Returning error reader
2026/02/02 01:12:01 DEBUG : BOOM: simulated read failure
2026/02/02 01:12:01 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 7/10: BOOM: simulated read failure
2026/02/02 01:12:01 DEBUG : Open with options = [RangeOption(10485760,10485760)]
2026/02/02 01:12:02 DEBUG : Returning error reader
2026/02/02 01:12:02 DEBUG : BOOM: simulated read failure
2026/02/02 01:12:02 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 8/10: BOOM: simulated read failure
2026/02/02 01:12:02 DEBUG : Open with options = [RangeOption(10485760,10485760)]
2026/02/02 01:12:03 DEBUG : Returning error reader
2026/02/02 01:12:03 DEBUG : BOOM: simulated read failure
2026/02/02 01:12:03 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 9/10: BOOM: simulated read failure
2026/02/02 01:12:03 DEBUG : Open with options = [RangeOption(10485760,10485760)]
2026/02/02 01:12:04 DEBUG : Returning error reader
2026/02/02 01:12:04 DEBUG : BOOM: simulated read failure
2026/02/02 01:12:04 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 10/10: BOOM: simulated read failure
2026/02/02 01:12:04 DEBUG : test-multithread-abort: Reopen failed after offset 0 bytes read: failed to reopen: too many retries
2026/02/02 01:12:04 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/b2715c34-c992-4b9f-8098-d7add1a28114/b2715c34-c992-4b9f-8098-d7add1a28114?uploadId=AMQUDjboyfu8mpF44osxvCQkSOYTf5nlHU_YPxnsiO-rUFSkeosB6_kwo01jl8LP3beu-pZBZi5sfmuIiWHzkteOn0mmuNZlx2Wfjg57rNmUtPB9Q9lSYtMe2-Ed9_R2V6ZVc1x3I5PtbRoz6KHGxBt-bL7VnZryywHPixWJYRyV_0i1rdqCzr2LIqaPjNqqv0yRpuC2yWJ5ObS3QWX7n-PuxzDrOXI3k3u-OWtmrAU-ld2Przr6DkAxM0KW0J5vEkgmJsxTJUmkGj4if980QQvX685RKQt23hgDNLhpy3DOYd6U_1OxRYLKqWYs5G1u2dJdXsM5l5YpPL1w_e8IR5Q&partNumber=3&X-Amz-Content-Sha256=UNSIGNED-PAYLOAD&X-Amz-Algorithm=AWS4-HMAC-SHA256&X-Amz-Credential=db9b71e81fa19eaf54eb4264526ca7a8%2F20260202%2FWEUR%2Fs3%2Faws4_request&X-Amz-Date=20260202T011154Z&X-Amz-SignedHeaders=host&X-Amz-Expires=1800&X-Amz-Signature=6c095f3c60f00e9f10936a61e3c92781adefdb82f5d107b5cd69ce807cb5f8a4": BOOM: simulated read failure
2026/02/02 01:12:04 DEBUG : test-multithread-abort: multi-thread copy: cancelling transfer on exit
2026/02/02 01:12:06 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/02/02 01:12:06 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/02/02 01:12:06 DEBUG : pacer: low level retry 2/10 (error Error "Server Error")
2026/02/02 01:12:06 DEBUG : pacer: Rate limited, increasing sleep to 40ms
2026/02/02 01:12:07 DEBUG : pacer: low level retry 3/10 (error Error "Server Error")
2026/02/02 01:12:07 DEBUG : pacer: Rate limited, increasing sleep to 80ms
2026/02/02 01:12:07 DEBUG : pacer: low level retry 4/10 (error Error "Server Error")
2026/02/02 01:12:07 DEBUG : pacer: Rate limited, increasing sleep to 160ms
2026/02/02 01:12:07 DEBUG : pacer: low level retry 5/10 (error Error "Server Error")
2026/02/02 01:12:07 DEBUG : pacer: Rate limited, increasing sleep to 320ms
2026/02/02 01:12:08 DEBUG : pacer: low level retry 6/10 (error Error "Server Error")
2026/02/02 01:12:08 DEBUG : pacer: Rate limited, increasing sleep to 640ms
2026/02/02 01:12:08 DEBUG : pacer: low level retry 7/10 (error Error "Server Error")
2026/02/02 01:12:08 DEBUG : pacer: Rate limited, increasing sleep to 1.28s
2026/02/02 01:12:09 DEBUG : pacer: low level retry 8/10 (error Error "Server Error")
2026/02/02 01:12:09 DEBUG : pacer: Rate limited, increasing sleep to 2.56s
2026/02/02 01:12:10 DEBUG : pacer: low level retry 9/10 (error Error "Server Error")
2026/02/02 01:12:10 DEBUG : pacer: Rate limited, increasing sleep to 5.12s
2026/02/02 01:12:12 DEBUG : pacer: low level retry 10/10 (error Error "Server Error")
2026/02/02 01:12:12 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/02 01:12:18 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/02/02 01:12:18 DEBUG : pacer: Rate limited, increasing sleep to 20s
2026/02/02 01:12:28 DEBUG : pacer: low level retry 2/10 (error Error "Server Error")
2026/02/02 01:12:48 DEBUG : pacer: low level retry 3/10 (error Error "Server Error")
2026/02/02 01:13:08 DEBUG : pacer: low level retry 4/10 (error Error "Server Error")
2026/02/02 01:13:28 DEBUG : pacer: low level retry 5/10 (error Error "Server Error")
2026/02/02 01:13:48 DEBUG : pacer: low level retry 6/10 (error Error "Server Error")
2026/02/02 01:14:08 DEBUG : pacer: low level retry 7/10 (error Error "Server Error")
2026/02/02 01:14:28 DEBUG : pacer: low level retry 8/10 (error Error "Server Error")
2026/02/02 01:14:48 DEBUG : pacer: low level retry 9/10 (error Error "Server Error")
2026/02/02 01:15:08 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/02 01:15:28 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/02/02 01:15:48 DEBUG : pacer: low level retry 2/10 (error Error "Server Error")
2026/02/02 01:16:08 DEBUG : pacer: low level retry 3/10 (error Error "Server Error")
2026/02/02 01:16:28 DEBUG : pacer: low level retry 4/10 (error Error "Server Error")
2026/02/02 01:16:48 DEBUG : pacer: low level retry 5/10 (error Error "Server Error")
2026/02/02 01:17:08 DEBUG : pacer: low level retry 6/10 (error Error "Server Error")
2026/02/02 01:17:28 DEBUG : pacer: low level retry 7/10 (error Error "Server Error")
2026/02/02 01:17:48 DEBUG : pacer: low level retry 8/10 (error Error "Server Error")
2026/02/02 01:18:08 DEBUG : pacer: low level retry 9/10 (error Error "Server Error")
2026/02/02 01:18:28 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/02 01:18:48 DEBUG : pacer: Reducing sleep to 10s
--- FAIL: TestMultithreadCopyAbort (464.02s)
=== RUN TestSizeDiffers
2026/02/02 01:18:48 DEBUG : a: size = 0 OK
2026/02/02 01:18:48 DEBUG : a: size = 1 (memory)
2026/02/02 01:18:48 DEBUG : a: size = 2 (memory)
--- PASS: TestSizeDiffers (0.00s)
=== RUN TestReOpen
=== RUN TestReOpen/Normal
=== RUN TestReOpen/Normal/Basics
2026/02/02 01:18:48 DEBUG : potato: Seek from 10 to 0
=== RUN TestReOpen/Normal/ErrorAtStart
=== RUN TestReOpen/Normal/WithErrors
2026/02/02 01:18:48 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error
2026/02/02 01:18:48 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error
2026/02/02 01:18:48 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error
=== RUN TestReOpen/Normal/TooManyErrors
2026/02/02 01:18:48 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error
2026/02/02 01:18:48 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error
2026/02/02 01:18:48 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error
2026/02/02 01:18:48 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries
=== RUN TestReOpen/Normal/ReadAt
2026/02/02 01:18:48 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error
2026/02/02 01:18:48 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error
2026/02/02 01:18:48 DEBUG : potato: Seek from 5 to 1
=== RUN TestReOpen/Normal/Seek
2026/02/02 01:18:48 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error
2026/02/02 01:18:48 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error
2026/02/02 01:18:48 DEBUG : potato: Seek from 5 to 2
=== RUN TestReOpen/Normal/AccountRead
=== RUN TestReOpen/Normal/AccountReadDelay
2026/02/02 01:18:48 DEBUG : potato: Seek from 10 to 0
2026/02/02 01:18:48 DEBUG : potato: Seek from 10 to 0
2026/02/02 01:18:48 DEBUG : potato: Seek from 10 to 0
=== RUN TestReOpen/Normal/AccountReadError
=== RUN TestReOpen/WithRangeOption
=== RUN TestReOpen/WithRangeOption/Basics
2026/02/02 01:18:48 DEBUG : potato: Seek from 7 to 0
=== RUN TestReOpen/WithRangeOption/ErrorAtStart
=== RUN TestReOpen/WithRangeOption/WithErrors
2026/02/02 01:18:48 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error
2026/02/02 01:18:48 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error
2026/02/02 01:18:48 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error
=== RUN TestReOpen/WithRangeOption/TooManyErrors
2026/02/02 01:18:48 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error
2026/02/02 01:18:48 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error
2026/02/02 01:18:48 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error
2026/02/02 01:18:48 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries
=== RUN TestReOpen/WithRangeOption/ReadAt
2026/02/02 01:18:48 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error
2026/02/02 01:18:48 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error
2026/02/02 01:18:48 DEBUG : potato: Seek from 5 to 1
=== RUN TestReOpen/WithRangeOption/Seek
2026/02/02 01:18:48 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error
2026/02/02 01:18:48 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error
2026/02/02 01:18:48 DEBUG : potato: Seek from 5 to 2
2026/02/02 01:18:48 DEBUG : potato: Seek from 7 to 4
=== RUN TestReOpen/WithRangeOption/AccountRead
=== RUN TestReOpen/WithRangeOption/AccountReadDelay
2026/02/02 01:18:48 DEBUG : potato: Seek from 7 to 0
2026/02/02 01:18:48 DEBUG : potato: Seek from 7 to 0
2026/02/02 01:18:48 DEBUG : potato: Seek from 7 to 0
=== RUN TestReOpen/WithRangeOption/AccountReadError
=== RUN TestReOpen/WithSeekOption
=== RUN TestReOpen/WithSeekOption/Basics
2026/02/02 01:18:48 DEBUG : potato: Seek from 8 to 0
=== RUN TestReOpen/WithSeekOption/ErrorAtStart
=== RUN TestReOpen/WithSeekOption/WithErrors
2026/02/02 01:18:48 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error
2026/02/02 01:18:48 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error
2026/02/02 01:18:48 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error
=== RUN TestReOpen/WithSeekOption/TooManyErrors
2026/02/02 01:18:48 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error
2026/02/02 01:18:48 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error
2026/02/02 01:18:48 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error
2026/02/02 01:18:48 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries
=== RUN TestReOpen/WithSeekOption/ReadAt
2026/02/02 01:18:48 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error
2026/02/02 01:18:48 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error
2026/02/02 01:18:48 DEBUG : potato: Seek from 5 to 1
=== RUN TestReOpen/WithSeekOption/Seek
2026/02/02 01:18:48 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error
2026/02/02 01:18:48 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error
2026/02/02 01:18:48 DEBUG : potato: Seek from 5 to 2
2026/02/02 01:18:48 DEBUG : potato: Seek from 7 to 5
=== RUN TestReOpen/WithSeekOption/AccountRead
=== RUN TestReOpen/WithSeekOption/AccountReadDelay
2026/02/02 01:18:48 DEBUG : potato: Seek from 8 to 0
2026/02/02 01:18:48 DEBUG : potato: Seek from 8 to 0
2026/02/02 01:18:48 DEBUG : potato: Seek from 8 to 0
=== RUN TestReOpen/WithSeekOption/AccountReadError
=== RUN TestReOpen/UnknownSize
=== RUN TestReOpen/UnknownSize/Basics
2026/02/02 01:18:48 DEBUG : potato: Seek from 9 to 0
=== RUN TestReOpen/UnknownSize/ErrorAtStart
=== RUN TestReOpen/UnknownSize/WithErrors
2026/02/02 01:18:48 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error
2026/02/02 01:18:48 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error
2026/02/02 01:18:48 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error
=== RUN TestReOpen/UnknownSize/TooManyErrors
2026/02/02 01:18:48 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error
2026/02/02 01:18:48 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error
2026/02/02 01:18:48 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error
2026/02/02 01:18:48 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries
=== RUN TestReOpen/UnknownSize/ReadAt
2026/02/02 01:18:48 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error
2026/02/02 01:18:48 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error
2026/02/02 01:18:48 DEBUG : potato: Seek from 5 to 1
=== RUN TestReOpen/UnknownSize/Seek
2026/02/02 01:18:48 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error
2026/02/02 01:18:48 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error
2026/02/02 01:18:48 DEBUG : potato: Seek from 5 to 2
2026/02/02 01:18:48 DEBUG : potato: Seek from 7 to 6
=== RUN TestReOpen/UnknownSize/AccountRead
=== RUN TestReOpen/UnknownSize/AccountReadDelay
2026/02/02 01:18:48 DEBUG : potato: Seek from 9 to 0
2026/02/02 01:18:48 DEBUG : potato: Seek from 9 to 0
2026/02/02 01:18:48 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-seretuh0vefa'", Local "Local file system at /tmp/rclone4245148629", Modify Window "876000h0m0s"
2026/02/02 01:19:08 DEBUG : pacer: Reducing sleep to 5s
2026/02/02 01:19:19 DEBUG : pacer: Reducing sleep to 2.5s
2026/02/02 01:19:23 DEBUG : pacer: Reducing sleep to 1.25s
=== RUN TestCheck/1
=== RUN TestCheck/2
2026/02/02 01:19:28 DEBUG : pacer: Reducing sleep to 156.25ms
2026/02/02 01:19:29 DEBUG : pacer: Reducing sleep to 78.125ms
2026/02/02 01:19:29 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/02 01:19:31 DEBUG : pacer: Reducing sleep to 19.53125ms
2026/02/02 01:19:32 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 (85.71s)
--- PASS: TestCheck/1 (2.31s)
--- PASS: TestCheck/2 (1.46s)
--- PASS: TestCheck/3 (0.37s)
--- PASS: TestCheck/4 (0.34s)
--- PASS: TestCheck/5 (0.57s)
--- PASS: TestCheck/6 (0.29s)
--- PASS: TestCheck/7 (0.29s)
=== RUN TestCheckFsError
2026/02/02 01:20:14 DEBUG : Creating backend with remote "nonexistent"
2026/02/02 01:20:14 DEBUG : Creating backend with remote "nonexistent"
2026/02/02 01:20:14 DEBUG : Local file system at /home/rclone/go/src/github.com/rclone/rclone/fs/operations/nonexistent: Waiting for checks to finish
2026/02/02 01:20:14 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/02 01:20:14 NOTICE: Local file system at /home/rclone/go/src/github.com/rclone/rclone/fs/operations/nonexistent: 0 differences found
2026/02/02 01:20:14 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-seretuh0vefa'", Local "Local file system at /tmp/rclone4245148629", 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 TestCheckDownload/1
=== RUN TestCheckDownload/2
=== 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: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:138
/home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:204
/usr/local/go/src/runtime/asm_amd64.s:1700
Error: Should be true
Test: TestCheckDownload
Messages: listing wrong, want
empty space (1), potato2 (60), rutabaga (8) got
empty space (1), rutabaga (8)
fstest.go:203: Not found "potato2"
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:138
/home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:204
Error: Not equal:
expected: 0
actual : 1
Test: TestCheckDownload
Messages: 1 objects not found
=== 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: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:204
/usr/local/go/src/runtime/asm_amd64.s:1700
Error: Should be true
Test: TestCheckDownload
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:204
Error: Not equal:
expected: 0
actual : 1
Test: TestCheckDownload
Messages: 1 objects not found
=== 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: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: TestCheckDownload
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: TestCheckDownload
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: TestCheckDownload
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: TestCheckDownload
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: TestCheckDownload
Messages: Unexpected file "rutabaga"
--- FAIL: TestCheckDownload (54.33s)
--- PASS: TestCheckDownload/1 (0.96s)
--- PASS: TestCheckDownload/2 (0.97s)
--- PASS: TestCheckDownload/3 (0.54s)
--- PASS: TestCheckDownload/4 (1.38s)
--- PASS: TestCheckDownload/5 (0.80s)
--- PASS: TestCheckDownload/6 (1.36s)
--- PASS: TestCheckDownload/7 (0.70s)
=== RUN TestCheckSizeOnly
run.go:185: Remote "drime root 'rclone-test-seretuh0vefa'", Local "Local file system at /tmp/rclone4245148629", 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/check_test.go:99
/home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:181
Error: Should be true
Test: TestCheckSizeOnly
Messages: listing wrong, want
rutabaga (8) got
fstest.go:203: Not found "rutabaga"
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:99
/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/1
check_test.go:80: 1: Got error when not expecting one: 1 differences found
check_test.go:86: 1: Expecting 0 errors but got 1
check_test.go:92: 1: Expecting 1 total matching files but got 0
=== NAME TestCheckSizeOnly
check_test.go:52:
Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:52
/home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:56
/home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:94
Error: Not equal:
expected: []string{"", "= rutabaga"}
actual : []string{"", "+ rutabaga"}
Diff:
--- Expected
+++ Actual
@@ -2,3 +2,3 @@
(string) "",
- (string) (len=10) "= rutabaga"
+ (string) (len=10) "+ rutabaga"
}
Test: TestCheckSizeOnly
Messages: combined
check_test.go:52:
Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:52
/home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:58
/home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:94
Error: Not equal:
expected: []string{}
actual : []string{"", "rutabaga"}
Diff:
--- Expected
+++ Actual
@@ -1,2 +1,4 @@
-([]string) {
+([]string) (len=2) {
+ (string) "",
+ (string) (len=8) "rutabaga"
}
Test: TestCheckSizeOnly
Messages: missingondst
check_test.go:52:
Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:52
/home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:59
/home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:94
Error: Not equal:
expected: []string{"", "rutabaga"}
actual : []string{}
Diff:
--- Expected
+++ Actual
@@ -1,4 +1,2 @@
-([]string) (len=2) {
- (string) "",
- (string) (len=8) "rutabaga"
+([]string) {
}
Test: TestCheckSizeOnly
Messages: match
=== 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: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:122
/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), rutabaga (8) got
rutabaga (8)
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/check_test.go:122
/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/3
=== 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:138
/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), rutabaga (8) got
empty space (1), rutabaga (8)
fstest.go:203: Not found "potato2"
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:138
/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/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 (54.50s)
--- FAIL: TestCheckSizeOnly/1 (0.62s)
--- PASS: TestCheckSizeOnly/2 (0.33s)
--- PASS: TestCheckSizeOnly/3 (0.52s)
--- PASS: TestCheckSizeOnly/4 (0.71s)
--- PASS: TestCheckSizeOnly/5 (0.51s)
--- PASS: TestCheckSizeOnly/6 (0.44s)
--- PASS: TestCheckSizeOnly/7 (0.22s)
=== RUN TestCheckEqualReaders
--- PASS: TestCheckEqualReaders (0.00s)
=== RUN TestParseSumFile
run.go:185: Remote "drime root 'rclone-test-seretuh0vefa'", Local "Local file system at /tmp/rclone4245148629", 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.43s)
=== RUN TestCheckSum
run.go:185: Remote "drime root 'rclone-test-seretuh0vefa'", Local "Local file system at /tmp/rclone4245148629", Modify Window "876000h0m0s"
2026/02/02 01:22:11 DEBUG : Creating backend with remote "TestDrime:rclone-test-seretuh0vefa/data"
2026/02/02 01:22:11 DEBUG : Config file has changed externally - reloading
check_test.go:350: drime root 'rclone-test-seretuh0vefa/data' lacks md5, skipping
--- SKIP: TestCheckSum (2.11s)
=== RUN TestCheckSumDownload
run.go:185: Remote "drime root 'rclone-test-seretuh0vefa'", Local "Local file system at /tmp/rclone4245148629", Modify Window "876000h0m0s"
2026/02/02 01:22:13 DEBUG : Creating backend with remote "TestDrime:rclone-test-seretuh0vefa/data"
2026/02/02 01:22:18 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/02 01:22:33 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:292: Sleeping for 7s just to make sure
=== RUN TestCheckSumDownload/subtest2
2026/02/02 01:22:50 DEBUG : test.sum: Removing old object on successful upload
=== RUN TestCheckSumDownload/subtest3
2026/02/02 01:22:54 DEBUG : test.sum: Removing old object on successful upload
=== RUN TestCheckSumDownload/subtest4
=== NAME TestCheckSumDownload
check_test.go:407:
Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:407
/home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:428
Error: An error is expected but got nil.
Test: TestCheckSumDownload
Messages: no expected error in run 4
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: 1
actual : 0
Test: TestCheckSumDownload
Messages: wrong error count in run 4
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{"", "* banana", "= potato"}
actual : []string{"", "= banana", "= potato"}
Diff:
--- Expected
+++ Actual
@@ -2,3 +2,3 @@
(string) "",
- (string) (len=8) "* banana",
+ (string) (len=8) "= banana",
(string) (len=8) "= potato"
Test: TestCheckSumDownload
Messages: wrong combined result in run 4
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{"", "potato"}
actual : []string{"", "banana", "potato"}
Diff:
--- Expected
+++ Actual
@@ -1,3 +1,4 @@
-([]string) (len=2) {
+([]string) (len=3) {
(string) "",
+ (string) (len=6) "banana",
(string) (len=6) "potato"
Test: TestCheckSumDownload
Messages: wrong match result in run 4
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{"", "banana"}
actual : []string{}
Diff:
--- Expected
+++ Actual
@@ -1,4 +1,2 @@
-([]string) (len=2) {
- (string) "",
- (string) (len=6) "banana"
+([]string) {
}
Test: TestCheckSumDownload
Messages: wrong differ result in run 4
2026/02/02 01:22:58 DEBUG : test.sum: Removing old object on successful upload
2026/02/02 01:22:58 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/02/02 01:22:58 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/02/02 01:22:59 DEBUG : pacer: low level retry 2/10 (error Error "Server Error")
2026/02/02 01:22:59 DEBUG : pacer: Rate limited, increasing sleep to 40ms
2026/02/02 01:22:59 DEBUG : pacer: low level retry 3/10 (error Error "Server Error")
2026/02/02 01:22:59 DEBUG : pacer: Rate limited, increasing sleep to 80ms
2026/02/02 01:22:59 DEBUG : pacer: low level retry 4/10 (error Error "Server Error")
2026/02/02 01:22:59 DEBUG : pacer: Rate limited, increasing sleep to 160ms
2026/02/02 01:23:00 DEBUG : pacer: low level retry 5/10 (error Error "Server Error")
2026/02/02 01:23:00 DEBUG : pacer: Rate limited, increasing sleep to 320ms
2026/02/02 01:23:00 DEBUG : pacer: low level retry 6/10 (error Error "Server Error")
2026/02/02 01:23:00 DEBUG : pacer: Rate limited, increasing sleep to 640ms
2026/02/02 01:23:00 DEBUG : pacer: low level retry 7/10 (error Error "Server Error")
2026/02/02 01:23:00 DEBUG : pacer: Rate limited, increasing sleep to 1.28s
2026/02/02 01:23:01 DEBUG : pacer: low level retry 8/10 (error Error "Server Error")
2026/02/02 01:23:01 DEBUG : pacer: Rate limited, increasing sleep to 2.56s
2026/02/02 01:23:02 DEBUG : pacer: low level retry 9/10 (error Error "Server Error")
2026/02/02 01:23:02 DEBUG : pacer: Rate limited, increasing sleep to 5.12s
2026/02/02 01:23:05 DEBUG : pacer: low level retry 10/10 (error Error "Server Error")
2026/02/02 01:23:05 DEBUG : pacer: Rate limited, increasing sleep to 10.24s
run.go:286: Retry Put of "test.sum" to drime root 'rclone-test-seretuh0vefa': 1/10 (failed to delete existing object: failed to delete item: Error "Server Error")
2026/02/02 01:23:10 DEBUG : pacer: Reducing sleep to 5.12s
2026/02/02 01:23:21 DEBUG : pacer: Reducing sleep to 2.56s
2026/02/02 01:23:21 DEBUG : test.sum: Removing old object on successful upload
2026/02/02 01:23:26 DEBUG : pacer: Reducing sleep to 1.28s
2026/02/02 01:23:28 DEBUG : pacer: Reducing sleep to 640ms
2026/02/02 01:23:29 DEBUG : pacer: Reducing sleep to 320ms
fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3
fstest.go:301: Flushing the directory cache
2026/02/02 01:23:31 DEBUG : pacer: Reducing sleep to 160ms
2026/02/02 01:23:31 DEBUG : pacer: Reducing sleep to 80ms
2026/02/02 01:23:32 DEBUG : pacer: Reducing sleep to 40ms
fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3
fstest.go:301: Flushing the directory cache
2026/02/02 01:23:34 DEBUG : pacer: Reducing sleep to 20ms
2026/02/02 01:23:34 DEBUG : pacer: Reducing sleep to 10ms
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 (123), test.sum (82)
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/check_test.go:493
/home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:541
Error: Should be true
Test: TestCheckSumDownload
Messages: Unexpected file "test.sum"
=== RUN TestCheckSumDownload/subtest5
2026/02/02 01:23:42 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:508
/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 (123), test.sum (82)
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/check_test.go:508
/home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:541
Error: Should be true
Test: TestCheckSumDownload
Messages: Unexpected file "test.sum"
=== RUN TestCheckSumDownload/subtest6
2026/02/02 01:23:57 DEBUG : data/banana: Removing old object on successful upload
2026/02/02 01:23:59 DEBUG : data/potato: Removing old object on successful upload
2026/02/02 01:24:02 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: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 (82), test.sum (82)
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/check_test.go:525
/home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:541
Error: Should be true
Test: TestCheckSumDownload
Messages: Unexpected file "test.sum"
=== 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: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 (137.17s)
--- PASS: TestCheckSumDownload/subtest1 (2.33s)
--- PASS: TestCheckSumDownload/subtest2 (1.30s)
--- PASS: TestCheckSumDownload/subtest3 (1.57s)
--- PASS: TestCheckSumDownload/subtest4 (1.33s)
--- PASS: TestCheckSumDownload/subtest5 (2.30s)
--- PASS: TestCheckSumDownload/subtest6 (2.96s)
--- PASS: TestCheckSumDownload/subtest7 (2.17s)
=== RUN TestApplyTransforms
2026/02/02 01:24:31 DEBUG : Creating backend with remote "TestDrime:rclone-test-sunokat6qozi"
2026/02/02 01:24:31 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/02/02 01:24:32 DEBUG : Creating backend with remote "/tmp/rclone3244883749"
run.go:185: Remote "drime root 'rclone-test-sunokat6qozi'", Local "Local file system at /tmp/rclone3244883749", Modify Window "876000h0m0s"
upper checkfile vs. lower remote (without normalization)
2026/02/02 01:24:35 ERROR : HELLO, WORLD!: file not in drime root 'rclone-test-sunokat6qozi'
2026/02/02 01:24:35 NOTICE: drime root 'rclone-test-sunokat6qozi': 1 files missing
2026/02/02 01:24:35 NOTICE: drime root 'rclone-test-sunokat6qozi': 0 differences found
2026/02/02 01:24:35 NOTICE: drime root 'rclone-test-sunokat6qozi': 1 errors while checking
upper checkfile vs. lower remote (with normalization)
2026/02/02 01:24:35 ERROR : hello, world!: file not in drime root 'rclone-test-sunokat6qozi'
2026/02/02 01:24:35 NOTICE: drime root 'rclone-test-sunokat6qozi': 1 files missing
2026/02/02 01:24:35 NOTICE: drime root 'rclone-test-sunokat6qozi': 0 differences found
2026/02/02 01:24:35 NOTICE: drime root 'rclone-test-sunokat6qozi': 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-sunokat6qozi'
Test: TestApplyTransforms
Messages: unexpected error for upper checkfile vs. lower remote (with normalization) HELLO, WORLD! hello, world!
2026/02/02 01:24:35 DEBUG : Creating backend with remote "TestDrime:rclone-test-zawudaq9telu"
2026/02/02 01:24:35 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/02/02 01:24:36 DEBUG : Creating backend with remote "/tmp/rclone4167940362"
run.go:185: Remote "drime root 'rclone-test-zawudaq9telu'", Local "Local file system at /tmp/rclone4167940362", Modify Window "876000h0m0s"
lower checkfile vs. upper remote (without normalization)
2026/02/02 01:24:39 ERROR : hello, world!: file not in drime root 'rclone-test-zawudaq9telu'
2026/02/02 01:24:39 NOTICE: drime root 'rclone-test-zawudaq9telu': 1 files missing
2026/02/02 01:24:39 NOTICE: drime root 'rclone-test-zawudaq9telu': 0 differences found
2026/02/02 01:24:39 NOTICE: drime root 'rclone-test-zawudaq9telu': 1 errors while checking
lower checkfile vs. upper remote (with normalization)
2026/02/02 01:24:39 ERROR : hello, world!: file not in drime root 'rclone-test-zawudaq9telu'
2026/02/02 01:24:39 NOTICE: drime root 'rclone-test-zawudaq9telu': 1 files missing
2026/02/02 01:24:39 NOTICE: drime root 'rclone-test-zawudaq9telu': 0 differences found
2026/02/02 01:24:39 NOTICE: drime root 'rclone-test-zawudaq9telu': 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-zawudaq9telu'
Test: TestApplyTransforms
Messages: unexpected error for lower checkfile vs. upper remote (with normalization) hello, world! HELLO, WORLD!
2026/02/02 01:24:39 DEBUG : Creating backend with remote "TestDrime:rclone-test-zaluner9liro"
2026/02/02 01:24:39 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/02/02 01:24:40 DEBUG : Creating backend with remote "/tmp/rclone2557540518"
run.go:185: Remote "drime root 'rclone-test-zaluner9liro'", Local "Local file system at /tmp/rclone2557540518", Modify Window "876000h0m0s"
lower checkfile vs. upperlowermixed remote (without normalization)
2026/02/02 01:24:44 ERROR : hello, world!: file not in drime root 'rclone-test-zaluner9liro'
2026/02/02 01:24:44 NOTICE: drime root 'rclone-test-zaluner9liro': 1 files missing
2026/02/02 01:24:44 NOTICE: drime root 'rclone-test-zaluner9liro': 0 differences found
2026/02/02 01:24:44 NOTICE: drime root 'rclone-test-zaluner9liro': 1 errors while checking
lower checkfile vs. upperlowermixed remote (with normalization)
2026/02/02 01:24:44 ERROR : hello, world!: file not in drime root 'rclone-test-zaluner9liro'
2026/02/02 01:24:44 NOTICE: drime root 'rclone-test-zaluner9liro': 1 files missing
2026/02/02 01:24:44 NOTICE: drime root 'rclone-test-zaluner9liro': 0 differences found
2026/02/02 01:24:44 NOTICE: drime root 'rclone-test-zaluner9liro': 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-zaluner9liro'
Test: TestApplyTransforms
Messages: unexpected error for lower checkfile vs. upperlowermixed remote (with normalization) hello, world! HeLlO, wOrLd!
2026/02/02 01:24:44 DEBUG : Creating backend with remote "TestDrime:rclone-test-tasobok7wugu"
2026/02/02 01:24:44 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/02/02 01:24:45 DEBUG : Creating backend with remote "/tmp/rclone430663895"
run.go:185: Remote "drime root 'rclone-test-tasobok7wugu'", Local "Local file system at /tmp/rclone430663895", Modify Window "876000h0m0s"
upperlowermixed checkfile vs. upper remote (without normalization)
2026/02/02 01:24:49 ERROR : HeLlO, wOrLd!: file not in drime root 'rclone-test-tasobok7wugu'
2026/02/02 01:24:49 NOTICE: drime root 'rclone-test-tasobok7wugu': 1 files missing
2026/02/02 01:24:49 NOTICE: drime root 'rclone-test-tasobok7wugu': 0 differences found
2026/02/02 01:24:49 NOTICE: drime root 'rclone-test-tasobok7wugu': 1 errors while checking
upperlowermixed checkfile vs. upper remote (with normalization)
2026/02/02 01:24:50 ERROR : hello, world!: file not in drime root 'rclone-test-tasobok7wugu'
2026/02/02 01:24:50 NOTICE: drime root 'rclone-test-tasobok7wugu': 1 files missing
2026/02/02 01:24:50 NOTICE: drime root 'rclone-test-tasobok7wugu': 0 differences found
2026/02/02 01:24:50 NOTICE: drime root 'rclone-test-tasobok7wugu': 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-tasobok7wugu'
Test: TestApplyTransforms
Messages: unexpected error for upperlowermixed checkfile vs. upper remote (with normalization) HeLlO, wOrLd! HELLO, WORLD!
2026/02/02 01:24:50 DEBUG : Creating backend with remote "TestDrime:rclone-test-yepacok8gudu"
2026/02/02 01:24:50 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/02/02 01:24:50 DEBUG : Creating backend with remote "/tmp/rclone1515651213"
run.go:185: Remote "drime root 'rclone-test-yepacok8gudu'", Local "Local file system at /tmp/rclone1515651213", Modify Window "876000h0m0s"
NFD checkfile vs. NFC remote (without normalization)
2026/02/02 01:24:54 ERROR : 測試_Русский___ě_áñ: file not in drime root 'rclone-test-yepacok8gudu'
2026/02/02 01:24:54 NOTICE: drime root 'rclone-test-yepacok8gudu': 1 files missing
2026/02/02 01:24:54 NOTICE: drime root 'rclone-test-yepacok8gudu': 0 differences found
2026/02/02 01:24:54 NOTICE: drime root 'rclone-test-yepacok8gudu': 1 errors while checking
NFD checkfile vs. NFC remote (with normalization)
2026/02/02 01:24:54 ERROR : 測試_русский___ě_áñ: file not in drime root 'rclone-test-yepacok8gudu'
2026/02/02 01:24:54 NOTICE: drime root 'rclone-test-yepacok8gudu': 1 files missing
2026/02/02 01:24:54 NOTICE: drime root 'rclone-test-yepacok8gudu': 0 differences found
2026/02/02 01:24:54 NOTICE: drime root 'rclone-test-yepacok8gudu': 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-yepacok8gudu'
Test: TestApplyTransforms
Messages: unexpected error for NFD checkfile vs. NFC remote (with normalization) 測試_Русский___ě_áñ 測試_Русский___ě_áñ
2026/02/02 01:24:54 DEBUG : Creating backend with remote "TestDrime:rclone-test-cicamod5mudi"
2026/02/02 01:24:54 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/02/02 01:24:55 DEBUG : Creating backend with remote "/tmp/rclone3988304442"
run.go:185: Remote "drime root 'rclone-test-cicamod5mudi'", Local "Local file system at /tmp/rclone3988304442", Modify Window "876000h0m0s"
NFC checkfile vs. NFD remote (without normalization)
2026/02/02 01:24:58 ERROR : 測試_Русский___ě_áñ: file not in drime root 'rclone-test-cicamod5mudi'
2026/02/02 01:24:58 NOTICE: drime root 'rclone-test-cicamod5mudi': 1 files missing
2026/02/02 01:24:58 NOTICE: drime root 'rclone-test-cicamod5mudi': 0 differences found
2026/02/02 01:24:58 NOTICE: drime root 'rclone-test-cicamod5mudi': 1 errors while checking
NFC checkfile vs. NFD remote (with normalization)
2026/02/02 01:24:58 ERROR : 測試_русский___ě_áñ: file not in drime root 'rclone-test-cicamod5mudi'
2026/02/02 01:24:58 NOTICE: drime root 'rclone-test-cicamod5mudi': 1 files missing
2026/02/02 01:24:58 NOTICE: drime root 'rclone-test-cicamod5mudi': 0 differences found
2026/02/02 01:24:58 NOTICE: drime root 'rclone-test-cicamod5mudi': 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-cicamod5mudi'
Test: TestApplyTransforms
Messages: unexpected error for NFC checkfile vs. NFD remote (with normalization) 測試_Русский___ě_áñ 測試_Русский___ě_áñ
2026/02/02 01:24:58 DEBUG : Creating backend with remote "TestDrime:rclone-test-wumotan9povu"
2026/02/02 01:24:58 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/02/02 01:24:59 DEBUG : Creating backend with remote "/tmp/rclone3480972307"
run.go:185: Remote "drime root 'rclone-test-wumotan9povu'", Local "Local file system at /tmp/rclone3480972307", Modify Window "876000h0m0s"
NFDx2 checkfile vs. both remote (without normalization)
2026/02/02 01:25:01 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in drime root 'rclone-test-wumotan9povu'
2026/02/02 01:25:01 NOTICE: drime root 'rclone-test-wumotan9povu': 1 files missing
2026/02/02 01:25:01 NOTICE: drime root 'rclone-test-wumotan9povu': 0 differences found
2026/02/02 01:25:01 NOTICE: drime root 'rclone-test-wumotan9povu': 1 errors while checking
NFDx2 checkfile vs. both remote (with normalization)
2026/02/02 01:25:01 ERROR : 測試_русский___ě_áñ測試_русский___ě_áñ: file not in drime root 'rclone-test-wumotan9povu'
2026/02/02 01:25:01 NOTICE: drime root 'rclone-test-wumotan9povu': 1 files missing
2026/02/02 01:25:01 NOTICE: drime root 'rclone-test-wumotan9povu': 0 differences found
2026/02/02 01:25:01 NOTICE: drime root 'rclone-test-wumotan9povu': 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-wumotan9povu'
Test: TestApplyTransforms
Messages: unexpected error for NFDx2 checkfile vs. both remote (with normalization) 測試_Русский___ě_áñ測試_Русский___ě_áñ 測試_Русский___ě_áñ測試_Русский___ě_áñ
2026/02/02 01:25:01 DEBUG : Creating backend with remote "TestDrime:rclone-test-pujiqar6joko"
2026/02/02 01:25:01 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/02/02 01:25:02 DEBUG : Creating backend with remote "/tmp/rclone2268120378"
run.go:185: Remote "drime root 'rclone-test-pujiqar6joko'", Local "Local file system at /tmp/rclone2268120378", Modify Window "876000h0m0s"
NFCx2 checkfile vs. both remote (without normalization)
2026/02/02 01:25:05 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in drime root 'rclone-test-pujiqar6joko'
2026/02/02 01:25:05 NOTICE: drime root 'rclone-test-pujiqar6joko': 1 files missing
2026/02/02 01:25:05 NOTICE: drime root 'rclone-test-pujiqar6joko': 0 differences found
2026/02/02 01:25:05 NOTICE: drime root 'rclone-test-pujiqar6joko': 1 errors while checking
NFCx2 checkfile vs. both remote (with normalization)
2026/02/02 01:25:06 ERROR : 測試_русский___ě_áñ測試_русский___ě_áñ: file not in drime root 'rclone-test-pujiqar6joko'
2026/02/02 01:25:06 NOTICE: drime root 'rclone-test-pujiqar6joko': 1 files missing
2026/02/02 01:25:06 NOTICE: drime root 'rclone-test-pujiqar6joko': 0 differences found
2026/02/02 01:25:06 NOTICE: drime root 'rclone-test-pujiqar6joko': 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-pujiqar6joko'
Test: TestApplyTransforms
Messages: unexpected error for NFCx2 checkfile vs. both remote (with normalization) 測試_Русский___ě_áñ測試_Русский___ě_áñ 測試_Русский___ě_áñ測試_Русский___ě_áñ
2026/02/02 01:25:06 DEBUG : Creating backend with remote "TestDrime:rclone-test-lewaput9jomo"
2026/02/02 01:25:06 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/02/02 01:25:07 DEBUG : Creating backend with remote "/tmp/rclone3981613142"
run.go:185: Remote "drime root 'rclone-test-lewaput9jomo'", Local "Local file system at /tmp/rclone3981613142", Modify Window "876000h0m0s"
both checkfile vs. NFDx2 remote (without normalization)
2026/02/02 01:25:10 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in drime root 'rclone-test-lewaput9jomo'
2026/02/02 01:25:10 NOTICE: drime root 'rclone-test-lewaput9jomo': 1 files missing
2026/02/02 01:25:10 NOTICE: drime root 'rclone-test-lewaput9jomo': 0 differences found
2026/02/02 01:25:10 NOTICE: drime root 'rclone-test-lewaput9jomo': 1 errors while checking
both checkfile vs. NFDx2 remote (with normalization)
2026/02/02 01:25:11 ERROR : 測試_русский___ě_áñ測試_русский___ě_áñ: file not in drime root 'rclone-test-lewaput9jomo'
2026/02/02 01:25:11 NOTICE: drime root 'rclone-test-lewaput9jomo': 1 files missing
2026/02/02 01:25:11 NOTICE: drime root 'rclone-test-lewaput9jomo': 0 differences found
2026/02/02 01:25:11 NOTICE: drime root 'rclone-test-lewaput9jomo': 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-lewaput9jomo'
Test: TestApplyTransforms
Messages: unexpected error for both checkfile vs. NFDx2 remote (with normalization) 測試_Русский___ě_áñ測試_Русский___ě_áñ 測試_Русский___ě_áñ測試_Русский___ě_áñ
2026/02/02 01:25:11 DEBUG : Creating backend with remote "TestDrime:rclone-test-ramobap2kevu"
2026/02/02 01:25:11 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/02/02 01:25:12 DEBUG : Creating backend with remote "/tmp/rclone1083624217"
run.go:185: Remote "drime root 'rclone-test-ramobap2kevu'", Local "Local file system at /tmp/rclone1083624217", Modify Window "876000h0m0s"
both checkfile vs. NFCx2 remote (without normalization)
2026/02/02 01:25:15 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in drime root 'rclone-test-ramobap2kevu'
2026/02/02 01:25:15 NOTICE: drime root 'rclone-test-ramobap2kevu': 1 files missing
2026/02/02 01:25:15 NOTICE: drime root 'rclone-test-ramobap2kevu': 0 differences found
2026/02/02 01:25:15 NOTICE: drime root 'rclone-test-ramobap2kevu': 1 errors while checking
both checkfile vs. NFCx2 remote (with normalization)
2026/02/02 01:25:15 ERROR : 測試_русский___ě_áñ測試_русский___ě_áñ: file not in drime root 'rclone-test-ramobap2kevu'
2026/02/02 01:25:15 NOTICE: drime root 'rclone-test-ramobap2kevu': 1 files missing
2026/02/02 01:25:15 NOTICE: drime root 'rclone-test-ramobap2kevu': 0 differences found
2026/02/02 01:25:15 NOTICE: drime root 'rclone-test-ramobap2kevu': 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-ramobap2kevu'
Test: TestApplyTransforms
Messages: unexpected error for both checkfile vs. NFCx2 remote (with normalization) 測試_Русский___ě_áñ測試_Русский___ě_áñ 測試_Русский___ě_áñ測試_Русский___ě_áñ
2026/02/02 01:25:15 DEBUG : drime root 'rclone-test-ramobap2kevu': Purge remote
2026/02/02 01:25:16 DEBUG : drime root 'rclone-test-lewaput9jomo': Purge remote
2026/02/02 01:25:16 DEBUG : drime root 'rclone-test-pujiqar6joko': Purge remote
2026/02/02 01:25:17 DEBUG : drime root 'rclone-test-wumotan9povu': Purge remote
2026/02/02 01:25:17 DEBUG : drime root 'rclone-test-cicamod5mudi': Purge remote
2026/02/02 01:25:18 DEBUG : drime root 'rclone-test-yepacok8gudu': Purge remote
2026/02/02 01:25:18 DEBUG : drime root 'rclone-test-tasobok7wugu': Purge remote
2026/02/02 01:25:18 DEBUG : drime root 'rclone-test-zaluner9liro': Purge remote
2026/02/02 01:25:19 DEBUG : drime root 'rclone-test-zawudaq9telu': Purge remote
2026/02/02 01:25:19 DEBUG : drime root 'rclone-test-sunokat6qozi': Purge remote
--- FAIL: TestApplyTransforms (48.89s)
=== RUN TestTruncateString
--- PASS: TestTruncateString (0.00s)
=== RUN TestCopyFile
run.go:185: Remote "drime root 'rclone-test-seretuh0vefa'", Local "Local file system at /tmp/rclone4245148629", Modify Window "876000h0m0s"
2026/02/02 01:25:20 DEBUG : file1: Need to transfer - File not found at Destination
2026/02/02 01:25:22 DEBUG : sub/file2: size = 14 OK
2026/02/02 01:25:22 INFO : file1: Copied (new) to: sub/file2
2026/02/02 01:25:24 DEBUG : sub/file2: size = 14 OK
2026/02/02 01:25:24 DEBUG : file1: Sizes identical
2026/02/02 01:25:24 DEBUG : file1: Unchanged skipping
2026/02/02 01:25:25 DEBUG : drime root 'rclone-test-seretuh0vefa': 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 (24.29s)
=== RUN TestCopyLongFile
run.go:185: Remote "drime root 'rclone-test-seretuh0vefa'", Local "Local file system at /tmp/rclone4245148629", 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: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
/usr/local/go/src/runtime/panic.go:636
/usr/local/go/src/testing/testing.go:1156
/usr/local/go/src/testing/testing.go:1132
/home/rclone/go/src/github.com/rclone/rclone/fs/operations/copy_test.go:154
Error: Not equal:
expected: []string{}
actual : []string{"sub"}
Diff:
--- Expected
+++ Actual
@@ -1,2 +1,3 @@
-([]string) {
+([]string) (len=1) {
+ (string) (len=3) "sub"
}
Test: TestCopyLongFile
Messages: directories
--- FAIL: TestCopyLongFile (12.38s)
=== RUN TestCopyFileBackupDir
run.go:185: Remote "drime root 'rclone-test-seretuh0vefa'", Local "Local file system at /tmp/rclone4245148629", Modify Window "876000h0m0s"
2026/02/02 01:26:01 DEBUG : Creating backend with remote "TestDrime:rclone-test-seretuh0vefa/backup"
2026/02/02 01:26:03 DEBUG : dst/file1: size = 14 (Local file system at /tmp/rclone4245148629)
2026/02/02 01:26:03 DEBUG : dst/file1: size = 18 (drime root 'rclone-test-seretuh0vefa')
2026/02/02 01:26:03 DEBUG : dst/file1: Sizes differ
2026/02/02 01:26:05 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/02/02 01:26:05 DEBUG : pacer: low level retry 1/10 (error Error "500 Internal Server Error (500): \r\n500 Internal Server Error\r\n\r\n500 Internal Server Error
\r\n
nginx\r\n\r\n\r\n")
2026/02/02 01:26:05 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/02/02 01:26:05 DEBUG : pacer: Reducing sleep to 10ms
2026/02/02 01:26:08 INFO : dst/file1: Moved (server-side)
2026/02/02 01:26:10 DEBUG : dst/file1: Removing old object on successful upload
2026/02/02 01:26:11 DEBUG : dst/file1: size = 14 OK
2026/02/02 01:26:11 INFO : dst/file1: Copied (new)
2026/02/02 01:26:14 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/02/02 01:26:14 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/02/02 01:26:15 DEBUG : pacer: low level retry 2/10 (error Error "Server Error")
2026/02/02 01:26:15 DEBUG : pacer: Rate limited, increasing sleep to 40ms
2026/02/02 01:26:15 DEBUG : pacer: low level retry 3/10 (error Error "Server Error")
2026/02/02 01:26:15 DEBUG : pacer: Rate limited, increasing sleep to 80ms
2026/02/02 01:26:16 DEBUG : pacer: low level retry 4/10 (error Error "Server Error")
2026/02/02 01:26:16 DEBUG : pacer: Rate limited, increasing sleep to 160ms
2026/02/02 01:26:16 DEBUG : pacer: low level retry 5/10 (error Error "Server Error")
2026/02/02 01:26:16 DEBUG : pacer: Rate limited, increasing sleep to 320ms
2026/02/02 01:26:16 DEBUG : pacer: low level retry 6/10 (error Error "Server Error")
2026/02/02 01:26:16 DEBUG : pacer: Rate limited, increasing sleep to 640ms
2026/02/02 01:26:17 DEBUG : pacer: low level retry 7/10 (error Error "Server Error")
2026/02/02 01:26:17 DEBUG : pacer: Rate limited, increasing sleep to 1.28s
2026/02/02 01:26:17 DEBUG : pacer: low level retry 8/10 (error Error "Server Error")
2026/02/02 01:26:17 DEBUG : pacer: Rate limited, increasing sleep to 2.56s
2026/02/02 01:26:19 DEBUG : pacer: low level retry 9/10 (error Error "Server Error")
2026/02/02 01:26:19 DEBUG : pacer: Rate limited, increasing sleep to 5.12s
2026/02/02 01:26:21 DEBUG : pacer: low level retry 10/10 (error Error "Server Error")
2026/02/02 01:26:21 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/02 01:26:26 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/02/02 01:26:26 DEBUG : pacer: Rate limited, increasing sleep to 20s
2026/02/02 01:26:37 DEBUG : pacer: low level retry 2/10 (error Error "Server Error")
2026/02/02 01:26:57 DEBUG : pacer: low level retry 3/10 (error Error "Server Error")
2026/02/02 01:27:17 DEBUG : pacer: low level retry 4/10 (error Error "Server Error")
2026/02/02 01:27:37 DEBUG : pacer: low level retry 5/10 (error Error "Server Error")
2026/02/02 01:27:57 DEBUG : pacer: low level retry 6/10 (error Error "Server Error")
2026/02/02 01:28:17 DEBUG : pacer: low level retry 7/10 (error Error "Server Error")
2026/02/02 01:28:37 DEBUG : pacer: low level retry 8/10 (error Error "Server Error")
2026/02/02 01:28:57 DEBUG : pacer: low level retry 9/10 (error Error "Server Error")
2026/02/02 01:29:17 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/02 01:29:37 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/02/02 01:29:57 DEBUG : pacer: low level retry 2/10 (error Error "Server Error")
2026/02/02 01:30:17 DEBUG : pacer: low level retry 3/10 (error Error "Server Error")
2026/02/02 01:30:37 DEBUG : pacer: low level retry 4/10 (error Error "Server Error")
2026/02/02 01:30:57 DEBUG : pacer: low level retry 5/10 (error Error "Server Error")
2026/02/02 01:31:17 DEBUG : pacer: low level retry 6/10 (error Error "Server Error")
2026/02/02 01:31:37 DEBUG : pacer: low level retry 7/10 (error Error "Server Error")
2026/02/02 01:31:57 DEBUG : pacer: low level retry 8/10 (error Error "Server Error")
2026/02/02 01:32:17 DEBUG : pacer: low level retry 9/10 (error Error "Server Error")
2026/02/02 01:32:37 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/02 01:32:57 DEBUG : pacer: Reducing sleep to 10s
2026/02/02 01:33:17 DEBUG : pacer: Reducing sleep to 5s
2026/02/02 01:33:27 DEBUG : pacer: Reducing sleep to 2.5s
2026/02/02 01:33:32 DEBUG : pacer: Reducing sleep to 1.25s
2026/02/02 01:33:35 DEBUG : pacer: Reducing sleep to 625ms
2026/02/02 01:33:36 DEBUG : pacer: Reducing sleep to 312.5ms
2026/02/02 01:33:36 DEBUG : pacer: Reducing sleep to 156.25ms
--- PASS: TestCopyFileBackupDir (460.08s)
=== RUN TestCopyFileCompareDest
run.go:185: Remote "drime root 'rclone-test-seretuh0vefa'", Local "Local file system at /tmp/rclone4245148629", Modify Window "876000h0m0s"
2026/02/02 01:33:36 DEBUG : Creating backend with remote "TestDrime:rclone-test-seretuh0vefa/dst"
2026/02/02 01:33:36 DEBUG : Config file has changed externally - reloading
2026/02/02 01:33:39 DEBUG : Creating backend with remote "TestDrime:rclone-test-seretuh0vefa/CompareDest"
2026/02/02 01:33:42 DEBUG : one: Need to transfer - File not found at Destination
2026/02/02 01:33:44 DEBUG : one: size = 3 OK
2026/02/02 01:33:44 INFO : one: Copied (new)
2026/02/02 01:33:45 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/02 01:33:46 DEBUG : pacer: Reducing sleep to 39.0625ms
2026/02/02 01:33:46 DEBUG : pacer: Reducing sleep to 19.53125ms
2026/02/02 01:33:47 DEBUG : pacer: Reducing sleep to 10ms
fstest.go:292: Sleeping for 6s just to make sure
2026/02/02 01:33:54 DEBUG : one: size = 5 (Local file system at /tmp/rclone4245148629)
2026/02/02 01:33:54 DEBUG : one: size = 3 (drime root 'rclone-test-seretuh0vefa/dst')
2026/02/02 01:33:54 DEBUG : one: Sizes differ
2026/02/02 01:33:55 DEBUG : one: Removing old object on successful upload
2026/02/02 01:33:55 DEBUG : one: size = 5 OK
2026/02/02 01:33:55 INFO : one: Copied (replaced existing)
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/02 01:34:07 DEBUG : dst/one: Removing old object on successful upload
2026/02/02 01:34:11 DEBUG : one: size = 5 (Local file system at /tmp/rclone4245148629)
2026/02/02 01:34:11 DEBUG : one: size = 3 (drime root 'rclone-test-seretuh0vefa/dst')
2026/02/02 01:34:11 DEBUG : one: Sizes differ
2026/02/02 01:34:12 DEBUG : one: Removing old object on successful upload
2026/02/02 01:34:12 DEBUG : one: size = 5 OK
2026/02/02 01:34:12 INFO : one: Copied (replaced existing)
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:242
Error: Should be true
Test: TestCopyFileCompareDest
Messages: listing wrong, want
CompareDest/one (5), CompareDest/two (3), dst/one (3) got
CompareDest/one (5), dst/one (3)
fstest.go:203: Not found "CompareDest/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:242
/usr/local/go/src/runtime/asm_amd64.s:1700
Error: Not equal:
expected: 0
actual : 1
Test: TestCopyFileCompareDest
Messages: 1 objects not found
2026/02/02 01:34:24 DEBUG : two: Need to transfer - File not found at Destination
2026/02/02 01:34:25 DEBUG : two: size = 3 OK
2026/02/02 01:34:25 DEBUG : two: Sizes identical
2026/02/02 01:34:25 DEBUG : two: Destination found in --compare-dest, 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:248
Error: Should be true
Test: TestCopyFileCompareDest
Messages: listing wrong, want
CompareDest/one (5), CompareDest/two (3), dst/one (3) got
CompareDest/one (5), CompareDest/two (3), dst/one (5)
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/copy_test.go:248
Error: Not equal:
expected: 3
actual : 5
Test: TestCopyFileCompareDest
Messages: dst/one: size incorrect file=3 vs obj=5
2026/02/02 01:34:36 DEBUG : two: Need to transfer - File not found at Destination
2026/02/02 01:34:37 DEBUG : two: size = 3 OK
2026/02/02 01:34:37 DEBUG : two: Sizes identical
2026/02/02 01:34:37 DEBUG : two: Destination found in --compare-dest, 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:254
Error: Should be true
Test: TestCopyFileCompareDest
Messages: listing wrong, want
CompareDest/one (5), CompareDest/two (3), dst/one (3) got
CompareDest/one (5), CompareDest/two (3), dst/one (5)
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/copy_test.go:254
Error: Not equal:
expected: 3
actual : 5
Test: TestCopyFileCompareDest
Messages: dst/one: size incorrect file=3 vs obj=5
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:258
Error: Should be true
Test: TestCopyFileCompareDest
Messages: listing wrong, want
CompareDest/one (5), CompareDest/two (3), dst/one (3) got
CompareDest/one (5), CompareDest/two (3), dst/one (5)
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/copy_test.go:258
Error: Not equal:
expected: 3
actual : 5
Test: TestCopyFileCompareDest
Messages: dst/one: size incorrect file=3 vs obj=5
2026/02/02 01:35:00 DEBUG : two: Need to transfer - File not found at Destination
2026/02/02 01:35:00 DEBUG : two: size = 5 (Local file system at /tmp/rclone4245148629)
2026/02/02 01:35:00 DEBUG : two: size = 3 (drime root 'rclone-test-seretuh0vefa/CompareDest')
2026/02/02 01:35:00 DEBUG : two: Sizes differ
2026/02/02 01:35:01 DEBUG : two: size = 5 OK
2026/02/02 01:35:01 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: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:267
Error: Should be true
Test: TestCopyFileCompareDest
Messages: listing wrong, want
CompareDest/one (5), CompareDest/two (3), dst/one (3), dst/two (5) got
CompareDest/one (5), CompareDest/two (3), dst/one (5)
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/copy_test.go:267
Error: Not equal:
expected: 3
actual : 5
Test: TestCopyFileCompareDest
Messages: dst/one: size incorrect file=3 vs obj=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:267
/usr/local/go/src/runtime/asm_amd64.s:1700
Error: Not equal:
expected: 0
actual : 1
Test: TestCopyFileCompareDest
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 "CompareDest" failed - try 1/3: directory not empty
run.go:130: removing dir "CompareDest" failed - try 2/3: directory not empty
run.go:130: removing dir "CompareDest" failed - try 3/3: directory not empty
run.go:133: removing dir "CompareDest" 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{"CompareDest", "dst"}
Diff:
--- Expected
+++ Actual
@@ -1,2 +1,4 @@
-([]string) {
+([]string) (len=2) {
+ (string) (len=11) "CompareDest",
+ (string) (len=3) "dst"
}
Test: TestCopyFileCompareDest
Messages: directories
--- FAIL: TestCopyFileCompareDest (117.09s)
=== RUN TestCopyFileCopyDest
run.go:185: Remote "drime root 'rclone-test-seretuh0vefa'", Local "Local file system at /tmp/rclone4245148629", Modify Window "876000h0m0s"
2026/02/02 01:35:33 DEBUG : Creating backend with remote "TestDrime:rclone-test-seretuh0vefa/dst"
2026/02/02 01:35:34 DEBUG : Creating backend with remote "TestDrime:rclone-test-seretuh0vefa/CopyDest"
2026/02/02 01:35:37 DEBUG : one: Need to transfer - File not found at Destination
2026/02/02 01:35:39 DEBUG : one: size = 3 OK
2026/02/02 01:35:39 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/02 01:35:54 DEBUG : one: size = 5 (Local file system at /tmp/rclone4245148629)
2026/02/02 01:35:54 DEBUG : one: size = 3 (drime root 'rclone-test-seretuh0vefa/dst')
2026/02/02 01:35:54 DEBUG : one: Sizes differ
2026/02/02 01:35:56 DEBUG : one: Removing old object on successful upload
2026/02/02 01:35:57 DEBUG : one: size = 5 OK
2026/02/02 01:35:57 INFO : one: Copied (replaced existing)
2026/02/02 01:36:00 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/02 01:36:18 DEBUG : Creating backend with remote "TestDrime:rclone-test-seretuh0vefa/BackupDir"
2026/02/02 01:36:20 DEBUG : one: size = 5 (Local file system at /tmp/rclone4245148629)
2026/02/02 01:36:20 DEBUG : one: size = 3 (drime root 'rclone-test-seretuh0vefa/dst')
2026/02/02 01:36:20 DEBUG : one: Sizes differ
2026/02/02 01:36:21 DEBUG : one: size = 5 OK
2026/02/02 01:36:21 DEBUG : one: Sizes identical
2026/02/02 01:36:21 DEBUG : one: size = 5 (Local file system at /tmp/rclone4245148629)
2026/02/02 01:36:21 DEBUG : one: size = 3 (drime root 'rclone-test-seretuh0vefa/dst')
2026/02/02 01:36:21 DEBUG : one: Sizes differ
2026/02/02 01:36:23 INFO : one: Moved (server-side)
2026/02/02 01:36:25 DEBUG : one: size = 5 OK
2026/02/02 01:36:25 INFO : one: Copied (server-side copy)
2026/02/02 01:36:25 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
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/02 01:36:49 DEBUG : two: Need to transfer - File not found at Destination
2026/02/02 01:36:50 DEBUG : two: size = 3 OK
2026/02/02 01:36:50 DEBUG : two: Sizes identical
2026/02/02 01:36:51 DEBUG : two: size = 3 OK
2026/02/02 01:36:51 INFO : two: Copied (server-side copy)
2026/02/02 01:36:51 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/02 01:37:02 DEBUG : two: size = 3 OK
2026/02/02 01:37:02 DEBUG : two: Sizes identical
2026/02/02 01:37:02 DEBUG : two: Unchanged skipping
2026/02/02 01:37:07 DEBUG : three: Need to transfer - File not found at Destination
2026/02/02 01:37:08 DEBUG : three: size = 7 (Local file system at /tmp/rclone4245148629)
2026/02/02 01:37:08 DEBUG : three: size = 5 (drime root 'rclone-test-seretuh0vefa/CopyDest')
2026/02/02 01:37:08 DEBUG : three: Sizes differ
2026/02/02 01:37:08 DEBUG : three: Destination not found in --copy-dest
2026/02/02 01:37:09 DEBUG : three: size = 7 OK
2026/02/02 01:37:09 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:292: Sleeping for 7s just to make sure
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"}
Diff:
--- Expected
+++ Actual
@@ -1,2 +1,3 @@
-([]string) {
+([]string) (len=1) {
+ (string) (len=8) "CopyDest"
}
Test: TestCopyFileCopyDest
Messages: directories
--- FAIL: TestCopyFileCopyDest (134.62s)
=== RUN TestCopyInplace
run.go:185: Remote "drime root 'rclone-test-seretuh0vefa'", Local "Local file system at /tmp/rclone4245148629", Modify Window "876000h0m0s"
copy_test.go:371: Partial uploads not supported
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
/usr/local/go/src/runtime/panic.go:636
/usr/local/go/src/testing/testing.go:1156
/usr/local/go/src/testing/testing.go:1132
/home/rclone/go/src/github.com/rclone/rclone/fs/operations/copy_test.go:371
Error: Not equal:
expected: []string{}
actual : []string{"CopyDest"}
Diff:
--- Expected
+++ Actual
@@ -1,2 +1,3 @@
-([]string) {
+([]string) (len=1) {
+ (string) (len=8) "CopyDest"
}
Test: TestCopyInplace
Messages: directories
--- FAIL: TestCopyInplace (13.45s)
=== RUN TestCopyLongFileName
run.go:185: Remote "drime root 'rclone-test-seretuh0vefa'", Local "Local file system at /tmp/rclone4245148629", Modify Window "876000h0m0s"
copy_test.go:404: Partial uploads not supported
--- SKIP: TestCopyLongFileName (1.06s)
=== RUN TestCopyLongFileNameCollision
run.go:185: Remote "drime root 'rclone-test-seretuh0vefa'", Local "Local file system at /tmp/rclone4245148629", Modify Window "876000h0m0s"
copy_test.go:437: Partial uploads not supported
--- SKIP: TestCopyLongFileNameCollision (1.08s)
=== RUN TestCopyFileMaxTransfer
run.go:185: Remote "drime root 'rclone-test-seretuh0vefa'", Local "Local file system at /tmp/rclone4245148629", Modify Window "876000h0m0s"
2026/02/02 01:38:04 DEBUG : TestCopyFileMaxTransfer/file1: Need to transfer - File not found at Destination
2026/02/02 01:38:07 DEBUG : TestCopyFileMaxTransfer/file1: size = 14 OK
2026/02/02 01:38:07 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: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:493
Error: Should be true
Test: TestCopyFileMaxTransfer
Messages: listing wrong, want
TestCopyFileMaxTransfer/file1 (14) got
fstest.go:203: Not found "TestCopyFileMaxTransfer/file1"
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:493
/usr/local/go/src/runtime/asm_amd64.s:1700
Error: Not equal:
expected: 0
actual : 1
Test: TestCopyFileMaxTransfer
Messages: 1 objects not found
2026/02/02 01:38:18 DEBUG : TestCopyFileMaxTransfer/file2: Need to transfer - File not found at Destination
2026/02/02 01:38:18 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/02 01:38:19 DEBUG : TestCopyFileMaxTransfer/file3: Need to transfer - File not found at Destination
2026/02/02 01:38:20 DEBUG : TestCopyFileMaxTransfer/file4: Need to transfer - File not found at Destination
2026/02/02 01:38:21 DEBUG : TestCopyFileMaxTransfer/file4: size = 2062 OK
2026/02/02 01:38:21 INFO : TestCopyFileMaxTransfer/file4: 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
run.go:130: removing dir "TestCopyFileMaxTransfer" failed - try 1/3: 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 (44.49s)
=== RUN TestDeduplicateInteractive
run.go:185: Remote "drime root 'rclone-test-seretuh0vefa'", Local "Local file system at /tmp/rclone4245148629", Modify Window "876000h0m0s"
dedupe_test.go:24: Can't test deduplicate - no duplicate files possible
--- SKIP: TestDeduplicateInteractive (0.99s)
=== RUN TestDeduplicateSkip
run.go:185: Remote "drime root 'rclone-test-seretuh0vefa'", Local "Local file system at /tmp/rclone4245148629", Modify Window "876000h0m0s"
dedupe_test.go:24: Can't test deduplicate - no duplicate files possible
--- SKIP: TestDeduplicateSkip (0.83s)
=== RUN TestDeduplicateSizeOnly
run.go:185: Remote "drime root 'rclone-test-seretuh0vefa'", Local "Local file system at /tmp/rclone4245148629", Modify Window "876000h0m0s"
dedupe_test.go:24: Can't test deduplicate - no duplicate files possible
--- SKIP: TestDeduplicateSizeOnly (0.59s)
=== RUN TestDeduplicateFirst
run.go:185: Remote "drime root 'rclone-test-seretuh0vefa'", Local "Local file system at /tmp/rclone4245148629", Modify Window "876000h0m0s"
dedupe_test.go:24: Can't test deduplicate - no duplicate files possible
--- SKIP: TestDeduplicateFirst (1.11s)
=== RUN TestDeduplicateNewest
run.go:185: Remote "drime root 'rclone-test-seretuh0vefa'", Local "Local file system at /tmp/rclone4245148629", Modify Window "876000h0m0s"
dedupe_test.go:24: Can't test deduplicate - no duplicate files possible
--- SKIP: TestDeduplicateNewest (0.98s)
=== RUN TestDeduplicateNewestByHash
run.go:185: Remote "drime root 'rclone-test-seretuh0vefa'", Local "Local file system at /tmp/rclone4245148629", Modify Window "876000h0m0s"
dedupe_test.go:36: Can't run this test without a hash
--- SKIP: TestDeduplicateNewestByHash (0.74s)
=== RUN TestDeduplicateOldest
run.go:185: Remote "drime root 'rclone-test-seretuh0vefa'", Local "Local file system at /tmp/rclone4245148629", Modify Window "876000h0m0s"
dedupe_test.go:24: Can't test deduplicate - no duplicate files possible
--- SKIP: TestDeduplicateOldest (0.63s)
=== RUN TestDeduplicateLargest
run.go:185: Remote "drime root 'rclone-test-seretuh0vefa'", Local "Local file system at /tmp/rclone4245148629", Modify Window "876000h0m0s"
dedupe_test.go:24: Can't test deduplicate - no duplicate files possible
--- SKIP: TestDeduplicateLargest (0.78s)
=== RUN TestDeduplicateSmallest
run.go:185: Remote "drime root 'rclone-test-seretuh0vefa'", Local "Local file system at /tmp/rclone4245148629", Modify Window "876000h0m0s"
dedupe_test.go:24: Can't test deduplicate - no duplicate files possible
--- SKIP: TestDeduplicateSmallest (1.14s)
=== RUN TestDeduplicateRename
run.go:185: Remote "drime root 'rclone-test-seretuh0vefa'", Local "Local file system at /tmp/rclone4245148629", Modify Window "876000h0m0s"
dedupe_test.go:24: Can't test deduplicate - no duplicate files possible
--- SKIP: TestDeduplicateRename (0.57s)
=== RUN TestMergeDirs
run.go:185: Remote "drime root 'rclone-test-seretuh0vefa'", Local "Local file system at /tmp/rclone4245148629", Modify Window "876000h0m0s"
dedupe_test.go:256: Can't merge directories
--- SKIP: TestMergeDirs (0.76s)
=== RUN TestListDirSorted
run.go:185: Remote "drime root 'rclone-test-seretuh0vefa'", Local "Local file system at /tmp/rclone4245148629", 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
2026/02/02 01:39:30 DEBUG : a.txt: Excluded (Size Filter)
2026/02/02 01:39:30 DEBUG : a.txt: Excluded
2026/02/02 01:39:32 DEBUG : sub dir/hello world2: Excluded (Size Filter)
2026/02/02 01:39:32 DEBUG : sub dir/hello world2: Excluded
2026/02/02 01:39:32 DEBUG : sub dir/hello world: Excluded (Size Filter)
2026/02/02 01:39:32 DEBUG : sub dir/hello world: Excluded
2026/02/02 01:39:33 DEBUG : sub dir/ignore dir: Excluded
2026/02/02 01:39:33 DEBUG : sub dir/hello world2: Excluded (Size Filter)
2026/02/02 01:39:33 DEBUG : sub dir/hello world2: Excluded
2026/02/02 01:39:33 DEBUG : sub dir/hello world: Excluded (Size Filter)
2026/02/02 01:39:33 DEBUG : sub dir/hello world: Excluded
2026/02/02 01:39:34 DEBUG : sub dir/ignore dir: Excluded
run.go:130: removing dir "sub dir/sub sub dir" failed - try 1/3: directory not empty
--- PASS: TestListDirSorted (49.33s)
=== RUN TestListDirSortedFn
run.go:185: Remote "drime root 'rclone-test-seretuh0vefa'", Local "Local file system at /tmp/rclone4245148629", 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
2026/02/02 01:40:17 DEBUG : a.txt: Excluded (Size Filter)
2026/02/02 01:40:17 DEBUG : a.txt: Excluded
2026/02/02 01:40:17 DEBUG : sub dir/hello world2: Excluded (Size Filter)
2026/02/02 01:40:17 DEBUG : sub dir/hello world2: Excluded
2026/02/02 01:40:17 DEBUG : sub dir/hello world: Excluded (Size Filter)
2026/02/02 01:40:17 DEBUG : sub dir/hello world: Excluded
2026/02/02 01:40:19 DEBUG : sub dir/ignore dir: Excluded
2026/02/02 01:40:19 DEBUG : sub dir/hello world2: Excluded (Size Filter)
2026/02/02 01:40:19 DEBUG : sub dir/hello world2: Excluded
2026/02/02 01:40:19 DEBUG : sub dir/hello world: Excluded (Size Filter)
2026/02/02 01:40:19 DEBUG : sub dir/hello world: Excluded
2026/02/02 01:40:19 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" 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/sub sub dir"}
Diff:
--- Expected
+++ Actual
@@ -1,2 +1,4 @@
-([]string) {
+([]string) (len=2) {
+ (string) (len=7) "sub dir",
+ (string) (len=19) "sub dir/sub sub dir"
}
Test: TestListDirSortedFn
Messages: directories
--- FAIL: TestListDirSortedFn (61.56s)
=== RUN TestListJSON
run.go:185: Remote "drime root 'rclone-test-seretuh0vefa'", Local "Local file system at /tmp/rclone4245148629", 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:43
Error: Should be true
Test: TestListJSON
Messages: listing wrong, want
file1 (5), sub/file2 (9) got
fstest.go:203: Not found "file1"
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:43
/usr/local/go/src/runtime/asm_amd64.s:1700
Error: Not equal:
expected: 0
actual : 2
Test: TestListJSON
Messages: 2 objects not found
=== 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 : 5
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/02 01:41:10 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
--- FAIL: TestListJSON (30.93s)
--- FAIL: TestListJSON/Default (0.93s)
--- PASS: TestListJSON/FilesOnly (0.37s)
--- FAIL: TestListJSON/DirsOnly (0.63s)
--- FAIL: TestListJSON/Recurse (1.83s)
--- PASS: TestListJSON/SubDir (0.84s)
--- PASS: TestListJSON/NoModTime (0.48s)
--- PASS: TestListJSON/NoMimeType (0.29s)
--- PASS: TestListJSON/ShowHash (0.66s)
--- PASS: TestListJSON/HashTypes (0.59s)
--- FAIL: TestListJSON/Metadata (0.46s)
=== RUN TestStatJSON
run.go:185: Remote "drime root 'rclone-test-seretuh0vefa'", Local "Local file system at /tmp/rclone4245148629", 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 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/02 01:41:45 DEBUG : Creating backend with remote "TestDrime:rclone-test-seretuh0vefa/notfound"
=== 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 (46.93s)
--- PASS: TestStatJSON/Root (0.47s)
--- PASS: TestStatJSON/RootFilesOnly (0.00s)
--- PASS: TestStatJSON/RootDirsOnly (0.45s)
--- PASS: TestStatJSON/Dir (0.72s)
--- PASS: TestStatJSON/DirWithTrailingSlash (0.71s)
--- PASS: TestStatJSON/File (0.51s)
--- PASS: TestStatJSON/NotFound (0.76s)
--- PASS: TestStatJSON/DirFilesOnly (0.52s)
--- PASS: TestStatJSON/FileFilesOnly (0.98s)
--- PASS: TestStatJSON/NotFoundFilesOnly (0.38s)
--- PASS: TestStatJSON/DirDirsOnly (0.85s)
--- PASS: TestStatJSON/FileDirsOnly (0.37s)
--- PASS: TestStatJSON/NotFoundDirsOnly (0.77s)
--- PASS: TestStatJSON/RootNotFound (3.34s)
=== RUN TestMkdir
run.go:185: Remote "drime root 'rclone-test-seretuh0vefa'", Local "Local file system at /tmp/rclone4245148629", Modify Window "876000h0m0s"
2026/02/02 01:42:06 INFO : drime root 'rclone-test-seretuh0vefa': Making directory
2026/02/02 01:42:07 INFO : drime root 'rclone-test-seretuh0vefa': 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 (13.29s)
=== RUN TestLsd
run.go:185: Remote "drime root 'rclone-test-seretuh0vefa'", Local "Local file system at /tmp/rclone4245148629", 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: TestLsd
Messages: directories
--- FAIL: TestLsd (20.98s)
=== RUN TestLs
run.go:185: Remote "drime root 'rclone-test-seretuh0vefa'", Local "Local file system at /tmp/rclone4245148629", 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
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: TestLs (25.27s)
=== RUN TestLsWithFilesFrom
run.go:185: Remote "drime root 'rclone-test-seretuh0vefa'", Local "Local file system at /tmp/rclone4245148629", Modify Window "876000h0m0s"
2026/02/02 01:43:10 DEBUG : empty space: Excluded (FilesFrom Filter)
2026/02/02 01:43:10 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 (15.25s)
=== RUN TestLsLong
run.go:185: Remote "drime root 'rclone-test-seretuh0vefa'", Local "Local file system at /tmp/rclone4245148629", 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 (25.02s)
=== RUN TestHashSums
run.go:185: Remote "drime root 'rclone-test-seretuh0vefa'", Local "Local file system at /tmp/rclone4245148629", 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:186
Error: Should be true
Test: TestHashSums
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:186
/usr/local/go/src/runtime/asm_amd64.s:1700
Error: Not equal:
expected: 0
actual : 2
Test: TestHashSums
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: TestHashSums
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: TestHashSums
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: TestHashSums
Messages: Unexpected file "potato2"
--- FAIL: TestHashSums (24.73s)
=== RUN TestHashSumsWithErrors
2026/02/02 01:44:10 DEBUG : Creating backend with remote ":memory:"
2026/02/02 01:44:10 DEBUG : Config file has changed externally - reloading
2026/02/02 01:44:10 ERROR : file1: hash unsupported: hash type not supported
--- PASS: TestHashSumsWithErrors (0.00s)
=== RUN TestHashStream
2026/02/02 01:44:10 DEBUG : Creating md5 hash of 0 bytes read from input stream
2026/02/02 01:44:10 DEBUG : Creating md5 hash of 0 bytes read from input stream
2026/02/02 01:44:10 DEBUG : Creating sha1 hash of 0 bytes read from input stream
2026/02/02 01:44:10 DEBUG : Creating sha1 hash of 0 bytes read from input stream
2026/02/02 01:44:10 DEBUG : Creating md5 hash of 12 bytes read from input stream
2026/02/02 01:44:10 DEBUG : Creating md5 hash of 12 bytes read from input stream
2026/02/02 01:44:10 DEBUG : Creating sha1 hash of 12 bytes read from input stream
2026/02/02 01:44:10 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-seretuh0vefa'", Local "Local file system at /tmp/rclone4245148629", 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
--- PASS: TestCount (23.58s)
=== RUN TestDelete
run.go:185: Remote "drime root 'rclone-test-seretuh0vefa'", Local "Local file system at /tmp/rclone4245148629", 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
2026/02/02 01:44:52 DEBUG : Waiting for deletions to finish
2026/02/02 01:44:53 DEBUG : large: Excluded (Size Filter)
2026/02/02 01:44:53 DEBUG : large: Excluded
2026/02/02 01:44:53 INFO : small: Deleted
2026/02/02 01:44:53 INFO : medium: Deleted
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
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: TestDelete (41.76s)
=== RUN TestMaxDelete
run.go:185: Remote "drime root 'rclone-test-seretuh0vefa'", Local "Local file system at /tmp/rclone4245148629", 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: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/02 01:45:30 DEBUG : Waiting for deletions to finish
2026/02/02 01:45:31 ERROR : small: Got fatal error on delete: --max-delete threshold reached
2026/02/02 01:45:31 INFO : large: Deleted
2026/02/02 01:45:31 INFO : medium: 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/02 01:45:33 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/02/02 01:45:33 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/02/02 01:45:33 DEBUG : pacer: low level retry 2/10 (error Error "Server Error")
2026/02/02 01:45:33 DEBUG : pacer: Rate limited, increasing sleep to 40ms
2026/02/02 01:45:33 DEBUG : pacer: low level retry 3/10 (error Error "Server Error")
2026/02/02 01:45:33 DEBUG : pacer: Rate limited, increasing sleep to 80ms
2026/02/02 01:45:34 DEBUG : pacer: low level retry 4/10 (error Error "Server Error")
2026/02/02 01:45:34 DEBUG : pacer: Rate limited, increasing sleep to 160ms
2026/02/02 01:45:34 DEBUG : pacer: low level retry 5/10 (error Error "Server Error")
2026/02/02 01:45:34 DEBUG : pacer: Rate limited, increasing sleep to 320ms
2026/02/02 01:45:35 DEBUG : pacer: low level retry 6/10 (error Error "Server Error")
2026/02/02 01:45:35 DEBUG : pacer: Rate limited, increasing sleep to 640ms
2026/02/02 01:45:36 DEBUG : pacer: low level retry 7/10 (error Error "Server Error")
2026/02/02 01:45:36 DEBUG : pacer: Rate limited, increasing sleep to 1.28s
2026/02/02 01:45:37 DEBUG : pacer: low level retry 8/10 (error Error "Server Error")
2026/02/02 01:45:37 DEBUG : pacer: Rate limited, increasing sleep to 2.56s
2026/02/02 01:45:38 DEBUG : pacer: low level retry 9/10 (error Error "Server Error")
2026/02/02 01:45:38 DEBUG : pacer: Rate limited, increasing sleep to 5.12s
2026/02/02 01:45:40 DEBUG : pacer: low level retry 10/10 (error Error "Server Error")
2026/02/02 01:45:40 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/02 01:45:46 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/02/02 01:45:46 DEBUG : pacer: Rate limited, increasing sleep to 20s
2026/02/02 01:45:56 DEBUG : pacer: low level retry 2/10 (error Error "Server Error")
2026/02/02 01:46:16 DEBUG : pacer: low level retry 3/10 (error Error "Server Error")
2026/02/02 01:46:36 DEBUG : pacer: low level retry 4/10 (error Error "Server Error")
2026/02/02 01:46:56 DEBUG : pacer: low level retry 5/10 (error Error "Server Error")
2026/02/02 01:47:16 DEBUG : pacer: low level retry 6/10 (error Error "Server Error")
2026/02/02 01:47:36 DEBUG : pacer: low level retry 7/10 (error Error "Server Error")
2026/02/02 01:47:56 DEBUG : pacer: low level retry 8/10 (error Error "Server Error")
2026/02/02 01:48:16 DEBUG : pacer: low level retry 9/10 (error Error "Server Error")
2026/02/02 01:48:36 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/02 01:48:56 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/02/02 01:49:16 DEBUG : pacer: low level retry 2/10 (error Error "Server Error")
2026/02/02 01:49:36 DEBUG : pacer: low level retry 3/10 (error Error "Server Error")
2026/02/02 01:49:56 DEBUG : pacer: low level retry 4/10 (error Error "Server Error")
2026/02/02 01:50:16 DEBUG : pacer: low level retry 5/10 (error Error "Server Error")
2026/02/02 01:50:36 DEBUG : pacer: low level retry 6/10 (error Error "Server Error")
2026/02/02 01:50:56 DEBUG : pacer: low level retry 7/10 (error Error "Server Error")
2026/02/02 01:51:16 DEBUG : pacer: low level retry 8/10 (error Error "Server Error")
2026/02/02 01:51:36 DEBUG : pacer: low level retry 9/10 (error Error "Server Error")
2026/02/02 01:51:56 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/02 01:52:16 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/02/02 01:52:36 DEBUG : pacer: low level retry 2/10 (error Error "Server Error")
2026/02/02 01:52:56 DEBUG : pacer: low level retry 3/10 (error Error "Server Error")
2026/02/02 01:53:16 DEBUG : pacer: low level retry 4/10 (error Error "Server Error")
2026/02/02 01:53:36 DEBUG : pacer: low level retry 5/10 (error Error "Server Error")
2026/02/02 01:53:56 DEBUG : pacer: low level retry 6/10 (error Error "Server Error")
2026/02/02 01:54:16 DEBUG : pacer: low level retry 7/10 (error Error "Server Error")
2026/02/02 01:54:36 DEBUG : pacer: low level retry 8/10 (error Error "Server Error")
2026/02/02 01:54:56 DEBUG : pacer: low level retry 9/10 (error Error "Server Error")
2026/02/02 01:55:16 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/02 01:55:36 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/02/02 01:55:56 DEBUG : pacer: low level retry 2/10 (error Error "Server Error")
2026/02/02 01:56:16 DEBUG : pacer: low level retry 3/10 (error Error "Server Error")
2026/02/02 01:56:36 DEBUG : pacer: low level retry 4/10 (error Error "Server Error")
2026/02/02 01:56:56 DEBUG : pacer: low level retry 5/10 (error Error "Server Error")
2026/02/02 01:57:16 DEBUG : pacer: low level retry 6/10 (error Error "Server Error")
2026/02/02 01:57:36 DEBUG : pacer: low level retry 7/10 (error Error "Server Error")
2026/02/02 01:57:56 DEBUG : pacer: low level retry 8/10 (error Error "Server Error")
2026/02/02 01:58:16 DEBUG : pacer: low level retry 9/10 (error Error "Server Error")
2026/02/02 01:58:36 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/02 01:58:56 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/02/02 01:59:16 DEBUG : pacer: low level retry 2/10 (error Error "Server Error")
2026/02/02 01:59:36 DEBUG : pacer: low level retry 3/10 (error Error "Server Error")
2026/02/02 01:59:56 DEBUG : pacer: low level retry 4/10 (error Error "Server Error")
2026/02/02 02:00:16 DEBUG : pacer: low level retry 5/10 (error Error "Server Error")
2026/02/02 02:00:36 DEBUG : pacer: low level retry 6/10 (error Error "Server Error")
2026/02/02 02:00:55 DEBUG : pacer: low level retry 7/10 (error Error "Server Error")
2026/02/02 02:01:16 DEBUG : pacer: low level retry 8/10 (error Error "Server Error")
2026/02/02 02:01:36 DEBUG : pacer: low level retry 9/10 (error Error "Server Error")
2026/02/02 02:01:56 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/02 02:02:16 DEBUG : pacer: Reducing sleep to 10s
2026/02/02 02:02:36 DEBUG : pacer: Reducing sleep to 5s
2026/02/02 02:02:46 DEBUG : pacer: Reducing sleep to 2.5s
2026/02/02 02:02:51 DEBUG : pacer: Reducing sleep to 1.25s
--- FAIL: TestMaxDelete (1055.11s)
=== RUN TestMaxDeleteSizeLargeFile
run.go:185: Remote "drime root 'rclone-test-seretuh0vefa'", Local "Local file system at /tmp/rclone4245148629", Modify Window "876000h0m0s"
2026/02/02 02:02:53 DEBUG : pacer: Reducing sleep to 625ms
2026/02/02 02:02:55 DEBUG : pacer: Reducing sleep to 312.5ms
2026/02/02 02:02:55 DEBUG : pacer: Reducing sleep to 156.25ms
2026/02/02 02:02:57 DEBUG : pacer: Reducing sleep to 78.125ms
2026/02/02 02:02:57 DEBUG : pacer: Reducing sleep to 39.0625ms
2026/02/02 02:02:59 DEBUG : pacer: Reducing sleep to 19.53125ms
2026/02/02 02:03:00 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:292: Sleeping for 6s just to make sure
2026/02/02 02:03:07 DEBUG : Waiting for deletions to finish
2026/02/02 02:03:08 ERROR : large: Got fatal error on delete: --max-delete-size threshold reached
2026/02/02 02:03:08 INFO : small: Deleted
2026/02/02 02:03:08 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: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
--- PASS: TestMaxDeleteSizeLargeFile (39.23s)
=== RUN TestMaxDeleteSize
run.go:185: Remote "drime root 'rclone-test-seretuh0vefa'", Local "Local file system at /tmp/rclone4245148629", Modify Window "876000h0m0s"
2026/02/02 02:03:35 DEBUG : Waiting for deletions to finish
2026/02/02 02:03:35 ERROR : small: Got fatal error on delete: --max-delete-size threshold reached
2026/02/02 02:03:36 INFO : large: Deleted
2026/02/02 02:03:36 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/02 02:03:38 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/02/02 02:03:38 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/02/02 02:03:38 DEBUG : pacer: low level retry 2/10 (error Error "Server Error")
2026/02/02 02:03:38 DEBUG : pacer: Rate limited, increasing sleep to 40ms
2026/02/02 02:03:38 DEBUG : pacer: low level retry 3/10 (error Error "Server Error")
2026/02/02 02:03:38 DEBUG : pacer: Rate limited, increasing sleep to 80ms
2026/02/02 02:03:39 DEBUG : pacer: low level retry 4/10 (error Error "Server Error")
2026/02/02 02:03:39 DEBUG : pacer: Rate limited, increasing sleep to 160ms
2026/02/02 02:03:39 DEBUG : pacer: low level retry 5/10 (error Error "Server Error")
2026/02/02 02:03:39 DEBUG : pacer: Rate limited, increasing sleep to 320ms
2026/02/02 02:03:39 DEBUG : pacer: low level retry 6/10 (error Error "Server Error")
2026/02/02 02:03:39 DEBUG : pacer: Rate limited, increasing sleep to 640ms
2026/02/02 02:03:40 DEBUG : pacer: low level retry 7/10 (error Error "Server Error")
2026/02/02 02:03:40 DEBUG : pacer: Rate limited, increasing sleep to 1.28s
2026/02/02 02:03:40 DEBUG : pacer: low level retry 8/10 (error Error "Server Error")
2026/02/02 02:03:40 DEBUG : pacer: Rate limited, increasing sleep to 2.56s
2026/02/02 02:03:41 DEBUG : pacer: low level retry 9/10 (error Error "Server Error")
2026/02/02 02:03:41 DEBUG : pacer: Rate limited, increasing sleep to 5.12s
2026/02/02 02:03:44 DEBUG : pacer: low level retry 10/10 (error Error "Server Error")
2026/02/02 02:03:44 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/02 02:03:49 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/02/02 02:03:49 DEBUG : pacer: Rate limited, increasing sleep to 20s
2026/02/02 02:03:59 DEBUG : pacer: low level retry 2/10 (error Error "Server Error")
2026/02/02 02:04:20 DEBUG : pacer: low level retry 3/10 (error Error "Server Error")
2026/02/02 02:04:39 DEBUG : pacer: low level retry 4/10 (error Error "Server Error")
2026/02/02 02:04:59 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/02/02 02:04:59 DEBUG : pacer: low level retry 5/10 (error Error "500 Internal Server Error (500): \r\n500 Internal Server Error\r\n\r\n500 Internal Server Error
\r\n
nginx\r\n\r\n\r\n")
2026/02/02 02:05:20 DEBUG : pacer: low level retry 6/10 (error Error "Server Error")
2026/02/02 02:05:39 DEBUG : pacer: low level retry 7/10 (error Error "Server Error")
2026/02/02 02:05:59 DEBUG : pacer: low level retry 8/10 (error Error "Server Error")
2026/02/02 02:06:19 DEBUG : pacer: low level retry 9/10 (error Error "Server Error")
2026/02/02 02:06:39 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/02 02:06:59 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/02/02 02:07:19 DEBUG : pacer: low level retry 2/10 (error Error "Server Error")
2026/02/02 02:07:39 DEBUG : pacer: low level retry 3/10 (error Error "Server Error")
2026/02/02 02:07:59 DEBUG : pacer: low level retry 4/10 (error Error "Server Error")
2026/02/02 02:08:19 DEBUG : pacer: low level retry 5/10 (error Error "Server Error")
2026/02/02 02:08:39 DEBUG : pacer: low level retry 6/10 (error Error "Server Error")
2026/02/02 02:09:00 DEBUG : pacer: low level retry 7/10 (error Error "Server Error")
2026/02/02 02:09:20 DEBUG : pacer: low level retry 8/10 (error Error "Server Error")
2026/02/02 02:09:40 DEBUG : pacer: low level retry 9/10 (error Error "Server Error")
2026/02/02 02:10:00 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/02 02:10:19 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/02/02 02:10:40 DEBUG : pacer: low level retry 2/10 (error Error "Server Error")
2026/02/02 02:10:59 DEBUG : pacer: low level retry 3/10 (error Error "Server Error")
2026/02/02 02:11:19 DEBUG : pacer: low level retry 4/10 (error Error "Server Error")
2026/02/02 02:11:40 DEBUG : pacer: low level retry 5/10 (error Error "Server Error")
2026/02/02 02:11:59 DEBUG : pacer: low level retry 6/10 (error Error "Server Error")
2026/02/02 02:12:19 DEBUG : pacer: low level retry 7/10 (error Error "Server Error")
2026/02/02 02:12:40 DEBUG : pacer: low level retry 8/10 (error Error "Server Error")
2026/02/02 02:13:00 DEBUG : pacer: low level retry 9/10 (error Error "Server Error")
2026/02/02 02:13:20 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/02 02:13:40 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/02/02 02:13:59 DEBUG : pacer: low level retry 2/10 (error Error "Server Error")
2026/02/02 02:14:19 DEBUG : pacer: low level retry 3/10 (error Error "Server Error")
2026/02/02 02:14:39 DEBUG : pacer: low level retry 4/10 (error Error "Server Error")
2026/02/02 02:14:59 DEBUG : pacer: low level retry 5/10 (error Error "Server Error")
2026/02/02 02:15:19 DEBUG : pacer: low level retry 6/10 (error Error "Server Error")
2026/02/02 02:15:39 DEBUG : pacer: low level retry 7/10 (error Error "Server Error")
2026/02/02 02:15:59 DEBUG : pacer: low level retry 8/10 (error Error "Server Error")
2026/02/02 02:16:19 DEBUG : pacer: low level retry 9/10 (error Error "Server Error")
2026/02/02 02:16:39 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/02 02:16:59 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/02/02 02:17:19 DEBUG : pacer: low level retry 2/10 (error Error "Server Error")
2026/02/02 02:17:40 DEBUG : pacer: low level retry 3/10 (error Error "Server Error")
2026/02/02 02:18:00 DEBUG : pacer: low level retry 4/10 (error Error "Server Error")
2026/02/02 02:18:19 DEBUG : pacer: low level retry 5/10 (error Error "Server Error")
2026/02/02 02:18:40 DEBUG : pacer: low level retry 6/10 (error Error "Server Error")
2026/02/02 02:19:00 DEBUG : pacer: low level retry 7/10 (error Error "Server Error")
2026/02/02 02:19:19 DEBUG : pacer: low level retry 8/10 (error Error "Server Error")
2026/02/02 02:19:39 DEBUG : pacer: low level retry 9/10 (error Error "Server Error")
2026/02/02 02:19:59 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/02 02:20:20 DEBUG : pacer: Reducing sleep to 10s
2026/02/02 02:20:39 DEBUG : pacer: Reducing sleep to 5s
2026/02/02 02:20:50 DEBUG : pacer: Reducing sleep to 2.5s
2026/02/02 02:20:54 DEBUG : pacer: Reducing sleep to 1.25s
--- FAIL: TestMaxDeleteSize (1044.25s)
=== RUN TestReadFile
run.go:185: Remote "drime root 'rclone-test-seretuh0vefa'", Local "Local file system at /tmp/rclone4245148629", Modify Window "876000h0m0s"
2026/02/02 02:20:57 DEBUG : pacer: Reducing sleep to 625ms
2026/02/02 02:20:59 DEBUG : pacer: Reducing sleep to 312.5ms
2026/02/02 02:21:00 DEBUG : pacer: Reducing sleep to 156.25ms
2026/02/02 02:21:00 DEBUG : pacer: Reducing sleep to 78.125ms
2026/02/02 02:21:01 DEBUG : pacer: Reducing sleep to 39.0625ms
2026/02/02 02:21:01 DEBUG : pacer: Reducing sleep to 19.53125ms
2026/02/02 02:21:02 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 (17.76s)
=== RUN TestRetry
2026/02/02 02:21:12 DEBUG : Received error: Wrapped EOF is retriable: EOF - low level retry 1/5
2026/02/02 02:21:12 DEBUG : Received error: Wrapped EOF is retriable: EOF - low level retry 2/5
2026/02/02 02:21:12 DEBUG : Sleeping for 10ms (as indicated by the server) to obey Retry-After error: BANG: trying again in 10ms
2026/02/02 02:21:12 DEBUG : Sleeping for 10ms (as indicated by the server) to obey Retry-After error: BANG: trying again in 10ms
2026/02/02 02:21:12 DEBUG : Sleeping for 10ms (as indicated by the server) to obey Retry-After error: BANG: trying again in 10ms
2026/02/02 02:21:12 DEBUG : Sleeping for 10ms (as indicated by the server) to obey Retry-After error: BANG: trying again in 10ms
2026/02/02 02:21:12 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-seretuh0vefa'", Local "Local file system at /tmp/rclone4245148629", 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
fstest.go:203: Not found "file1"
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 : 2
Test: TestCat
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:292: Sleeping for 7s just to make sure
--- FAIL: TestCat (31.08s)
=== RUN TestPurge
2026/02/02 02:21:43 DEBUG : Creating backend with remote "TestDrime:rclone-test-ruceqog1cube"
2026/02/02 02:21:43 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/02/02 02:21:44 DEBUG : Creating backend with remote "/tmp/rclone2692636187"
run.go:185: Remote "drime root 'rclone-test-ruceqog1cube'", Local "Local file system at /tmp/rclone2692636187", Modify Window "876000h0m0s"
2026/02/02 02:21:49 INFO : A2: Making directory
2026/02/02 02:21:50 INFO : A1/B2: Making directory
2026/02/02 02:21:51 INFO : A1/B2/C2: Making directory
2026/02/02 02:21:52 INFO : A1/B1/C3: Making directory
2026/02/02 02:21:52 INFO : A3: Making directory
2026/02/02 02:21:53 INFO : A3/B3: Making directory
2026/02/02 02:21:54 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:590
Error: Should be true
Test: TestPurge
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:590
Error: Not equal:
expected: 0
actual : 1
Test: TestPurge
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
2026/02/02 02:22:22 ERROR : error listing: directory not found
fstest.go:292: Sleeping for 7s just to make sure
2026/02/02 02:22:29 DEBUG : drime root 'rclone-test-ruceqog1cube': Purge remote
2026/02/02 02:22:29 NOTICE: purge failed: directory not found
--- FAIL: TestPurge (46.08s)
=== RUN TestRmdirsNoLeaveRoot
run.go:185: Remote "drime root 'rclone-test-seretuh0vefa'", Local "Local file system at /tmp/rclone4245148629", Modify Window "876000h0m0s"
2026/02/02 02:22:34 INFO : A2: Making directory
2026/02/02 02:22:34 INFO : A1/B2: Making directory
2026/02/02 02:22:35 INFO : A1/B2/C2: Making directory
2026/02/02 02:22:36 INFO : A1/B1/C3: Making directory
2026/02/02 02:22:37 INFO : A3: Making directory
2026/02/02 02:22:38 INFO : A3/B3: Making directory
2026/02/02 02:22:38 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/02 02:22:57 DEBUG : removing 1 level 3 directories
2026/02/02 02:22:57 INFO : A3/B3/C4: Removing directory
2026/02/02 02:23:01 DEBUG : removing 2 level 3 directories
2026/02/02 02:23:01 INFO : A1/B2/C2: Removing directory
2026/02/02 02:23:01 INFO : A1/B1/C3: Removing directory
2026/02/02 02:23:02 DEBUG : removing 2 level 2 directories
2026/02/02 02:23:02 INFO : A3/B3: Removing directory
2026/02/02 02:23:02 INFO : A1/B2: Removing directory
2026/02/02 02:23:02 ERROR : A1/B2: Failed to rmdir: directory not empty
2026/02/02 02:23:03 DEBUG : removing 2 level 1 directories
2026/02/02 02:23:03 INFO : A3: Removing directory
2026/02/02 02:23:03 INFO : A2: Removing directory
2026/02/02 02:23:03 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/02 02:23:07 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/02/02 02:23:07 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/02/02 02:23:08 DEBUG : pacer: low level retry 2/10 (error Error "Server Error")
2026/02/02 02:23:08 DEBUG : pacer: Rate limited, increasing sleep to 40ms
2026/02/02 02:23:08 DEBUG : pacer: low level retry 3/10 (error Error "Server Error")
2026/02/02 02:23:08 DEBUG : pacer: Rate limited, increasing sleep to 80ms
2026/02/02 02:23:09 DEBUG : pacer: low level retry 4/10 (error Error "Server Error")
2026/02/02 02:23:09 DEBUG : pacer: Rate limited, increasing sleep to 160ms
2026/02/02 02:23:09 DEBUG : pacer: low level retry 5/10 (error Error "Server Error")
2026/02/02 02:23:09 DEBUG : pacer: Rate limited, increasing sleep to 320ms
2026/02/02 02:23:09 DEBUG : pacer: low level retry 6/10 (error Error "Server Error")
2026/02/02 02:23:09 DEBUG : pacer: Rate limited, increasing sleep to 640ms
2026/02/02 02:23:10 DEBUG : pacer: low level retry 7/10 (error Error "Server Error")
2026/02/02 02:23:10 DEBUG : pacer: Rate limited, increasing sleep to 1.28s
2026/02/02 02:23:10 DEBUG : pacer: low level retry 8/10 (error Error "Server Error")
2026/02/02 02:23:10 DEBUG : pacer: Rate limited, increasing sleep to 2.56s
2026/02/02 02:23:12 DEBUG : pacer: low level retry 9/10 (error Error "Server Error")
2026/02/02 02:23:12 DEBUG : pacer: Rate limited, increasing sleep to 5.12s
2026/02/02 02:23:14 DEBUG : pacer: low level retry 10/10 (error Error "Server Error")
2026/02/02 02:23:14 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/02 02:23:19 DEBUG : pacer: Reducing sleep to 5.12s
2026/02/02 02:23:30 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/02/02 02:23:30 DEBUG : pacer: Rate limited, increasing sleep to 10.24s
2026/02/02 02:23:35 DEBUG : pacer: low level retry 2/10 (error Error "Server Error")
2026/02/02 02:23:35 DEBUG : pacer: Rate limited, increasing sleep to 20s
2026/02/02 02:23:45 DEBUG : pacer: low level retry 3/10 (error Error "Server Error")
2026/02/02 02:24:05 DEBUG : pacer: low level retry 4/10 (error Error "Server Error")
2026/02/02 02:24:25 DEBUG : pacer: low level retry 5/10 (error Error "Server Error")
2026/02/02 02:24:45 DEBUG : pacer: low level retry 6/10 (error Error "Server Error")
2026/02/02 02:25:06 DEBUG : pacer: low level retry 7/10 (error Error "Server Error")
2026/02/02 02:25:25 DEBUG : pacer: low level retry 8/10 (error Error "Server Error")
2026/02/02 02:25:45 DEBUG : pacer: low level retry 9/10 (error Error "Server Error")
2026/02/02 02:26:06 DEBUG : pacer: low level retry 10/10 (error Error "Server Error")
run.go:130: removing dir "A3/B3" failed - try 2/3: failed to delete item: Error "Server Error"
2026/02/02 02:26:25 DEBUG : pacer: Reducing sleep to 10s
2026/02/02 02:26:45 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/02/02 02:26:45 DEBUG : pacer: Rate limited, increasing sleep to 20s
2026/02/02 02:26:55 DEBUG : pacer: low level retry 2/10 (error Error "Server Error")
2026/02/02 02:27:15 DEBUG : pacer: low level retry 3/10 (error Error "Server Error")
2026/02/02 02:27:35 DEBUG : pacer: low level retry 4/10 (error Error "Server Error")
2026/02/02 02:27:55 DEBUG : pacer: low level retry 5/10 (error Error "Server Error")
2026/02/02 02:28:15 DEBUG : pacer: low level retry 6/10 (error Error "Server Error")
2026/02/02 02:28:35 DEBUG : pacer: low level retry 7/10 (error Error "Server Error")
2026/02/02 02:28:56 DEBUG : pacer: low level retry 8/10 (error Error "Server Error")
2026/02/02 02:29:15 DEBUG : pacer: low level retry 9/10 (error Error "Server Error")
2026/02/02 02:29:35 DEBUG : pacer: low level retry 10/10 (error Error "Server Error")
run.go:130: removing dir "A3/B3" failed - try 3/3: failed to delete item: Error "Server Error"
run.go:133: removing dir "A3/B3" failed: failed to delete item: Error "Server Error"
2026/02/02 02:29:55 DEBUG : pacer: low level retry 1/10 (error Get "https://app.drime.cloud/api/v1/drive/file-entries?page=1&parentIds=497209755&perPage=1000": read tcp [2a01:4f9:c011:405e::1]:46846->[2606:4700::6812:cb0]:443: read: connection reset by peer)
2026/02/02 02:30:15 DEBUG : pacer: Reducing sleep to 10s
2026/02/02 02:30:36 DEBUG : pacer: Reducing sleep to 5s
2026/02/02 02:30:46 DEBUG : pacer: Reducing sleep to 2.5s
2026/02/02 02:30:50 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/02/02 02:30:50 DEBUG : pacer: Rate limited, increasing sleep to 5s
2026/02/02 02:30:53 DEBUG : pacer: low level retry 2/10 (error Error "Server Error")
2026/02/02 02:30:53 DEBUG : pacer: Rate limited, increasing sleep to 10s
2026/02/02 02:30:58 DEBUG : pacer: low level retry 3/10 (error Error "Server Error")
2026/02/02 02:30:58 DEBUG : pacer: Rate limited, increasing sleep to 20s
2026/02/02 02:31:08 DEBUG : pacer: low level retry 4/10 (error Error "Server Error")
2026/02/02 02:31:28 DEBUG : pacer: low level retry 5/10 (error Error "Server Error")
2026/02/02 02:31:48 DEBUG : pacer: low level retry 6/10 (error Error "Server Error")
2026/02/02 02:32:08 DEBUG : pacer: low level retry 7/10 (error Error "Server Error")
2026/02/02 02:32:28 DEBUG : pacer: low level retry 8/10 (error Error "Server Error")
2026/02/02 02:32:48 DEBUG : pacer: low level retry 9/10 (error Error "Server Error")
2026/02/02 02:33:08 DEBUG : pacer: low level retry 10/10 (error Error "Server Error")
run.go:130: removing dir "A2" failed - try 1/3: failed to delete item: Error "Server Error"
2026/02/02 02:33:28 DEBUG : pacer: Reducing sleep to 10s
2026/02/02 02:33:48 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/02/02 02:33:48 DEBUG : pacer: Rate limited, increasing sleep to 20s
2026/02/02 02:33:58 DEBUG : pacer: low level retry 2/10 (error Error "Server Error")
2026/02/02 02:34:18 DEBUG : pacer: low level retry 3/10 (error Error "Server Error")
2026/02/02 02:34:38 DEBUG : pacer: low level retry 4/10 (error Error "Server Error")
2026/02/02 02:34:58 DEBUG : pacer: low level retry 5/10 (error Error "Server Error")
2026/02/02 02:35:18 DEBUG : pacer: low level retry 6/10 (error Error "Server Error")
2026/02/02 02:35:38 DEBUG : pacer: low level retry 7/10 (error Error "Server Error")
2026/02/02 02:35:58 DEBUG : pacer: low level retry 8/10 (error Error "Server Error")
2026/02/02 02:36:18 DEBUG : pacer: low level retry 9/10 (error Error "Server Error")
2026/02/02 02:36:38 DEBUG : pacer: low level retry 10/10 (error Error "Server Error")
run.go:130: removing dir "A2" failed - try 2/3: failed to delete item: Error "Server Error"
2026/02/02 02:36:58 DEBUG : pacer: Reducing sleep to 10s
2026/02/02 02:37:18 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/02/02 02:37:18 DEBUG : pacer: Rate limited, increasing sleep to 20s
2026/02/02 02:37:28 DEBUG : pacer: low level retry 2/10 (error Error "Server Error")
2026/02/02 02:37:48 DEBUG : pacer: low level retry 3/10 (error Error "Server Error")
2026/02/02 02:38:08 DEBUG : pacer: low level retry 4/10 (error Error "Server Error")
2026/02/02 02:38:28 DEBUG : pacer: low level retry 5/10 (error Error "Server Error")
2026/02/02 02:38:48 DEBUG : pacer: low level retry 6/10 (error Error "Server Error")
2026/02/02 02:39:08 DEBUG : pacer: low level retry 7/10 (error Error "Server Error")
2026/02/02 02:39:28 DEBUG : pacer: low level retry 8/10 (error Error "Server Error")
2026/02/02 02:39:48 DEBUG : pacer: low level retry 9/10 (error Error "Server Error")
2026/02/02 02:40:08 DEBUG : pacer: low level retry 10/10 (error Error "Server Error")
run.go:130: removing dir "A2" failed - try 3/3: failed to delete item: Error "Server Error"
run.go:133: removing dir "A2" failed: failed to delete item: Error "Server Error"
2026/02/02 02:40:28 DEBUG : pacer: Reducing sleep to 10s
2026/02/02 02:40:48 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/02/02 02:40:48 DEBUG : pacer: Rate limited, increasing sleep to 20s
2026/02/02 02:40:58 DEBUG : pacer: low level retry 2/10 (error Error "Server Error")
2026/02/02 02:41:18 DEBUG : pacer: low level retry 3/10 (error Error "Server Error")
2026/02/02 02:41:38 DEBUG : pacer: low level retry 4/10 (error Error "Server Error")
2026/02/02 02:41:58 DEBUG : pacer: low level retry 5/10 (error Error "Server Error")
2026/02/02 02:42:18 DEBUG : pacer: low level retry 6/10 (error Error "Server Error")
2026/02/02 02:42:38 DEBUG : pacer: low level retry 7/10 (error Error "Server Error")
2026/02/02 02:42:58 DEBUG : pacer: low level retry 8/10 (error Error "Server Error")
2026/02/02 02:43:18 DEBUG : pacer: low level retry 9/10 (error Error "Server Error")
2026/02/02 02:43:39 DEBUG : pacer: low level retry 10/10 (error Error "Server Error")
run.go:130: removing dir "A1/B2/C2" failed - try 1/3: failed to delete item: Error "Server Error"
2026/02/02 02:43:58 DEBUG : pacer: Reducing sleep to 10s
2026/02/02 02:44:19 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/02/02 02:44:19 DEBUG : pacer: Rate limited, increasing sleep to 20s
2026/02/02 02:44:28 DEBUG : pacer: low level retry 2/10 (error Error "Server Error")
2026/02/02 02:44:49 DEBUG : pacer: low level retry 3/10 (error Error "Server Error")
2026/02/02 02:45:09 DEBUG : pacer: low level retry 4/10 (error Error "Server Error")
2026/02/02 02:45:29 DEBUG : pacer: low level retry 5/10 (error Error "Server Error")
2026/02/02 02:45:49 DEBUG : pacer: low level retry 6/10 (error Error "Server Error")
2026/02/02 02:46:09 DEBUG : pacer: low level retry 7/10 (error Error "Server Error")
2026/02/02 02:46:28 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/02/02 02:46:28 DEBUG : pacer: low level retry 8/10 (error Error "500 Internal Server Error (500): \r\n500 Internal Server Error\r\n\r\n500 Internal Server Error
\r\n
nginx\r\n\r\n\r\n")
2026/02/02 02:46:49 DEBUG : pacer: low level retry 9/10 (error Error "Server Error")
2026/02/02 02:47:09 DEBUG : pacer: low level retry 10/10 (error Error "Server Error")
run.go:130: removing dir "A1/B2/C2" failed - try 2/3: failed to delete item: Error "Server Error"
2026/02/02 02:47:29 DEBUG : pacer: Reducing sleep to 10s
2026/02/02 02:47:49 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/02/02 02:47:49 DEBUG : pacer: Rate limited, increasing sleep to 20s
2026/02/02 02:47:59 DEBUG : pacer: low level retry 2/10 (error Error "Server Error")
2026/02/02 02:48:19 DEBUG : pacer: low level retry 3/10 (error Error "Server Error")
2026/02/02 02:48:39 DEBUG : pacer: low level retry 4/10 (error Error "Server Error")
2026/02/02 02:48:59 DEBUG : pacer: low level retry 5/10 (error Error "Server Error")
2026/02/02 02:49:19 DEBUG : pacer: low level retry 6/10 (error Error "Server Error")
2026/02/02 02:49:39 DEBUG : pacer: low level retry 7/10 (error Error "Server Error")
2026/02/02 02:49:59 DEBUG : pacer: low level retry 8/10 (error Error "Server Error")
2026/02/02 02:50:19 DEBUG : pacer: low level retry 9/10 (error Error "Server Error")
2026/02/02 02:50:39 DEBUG : pacer: low level retry 10/10 (error Error "Server Error")
run.go:130: removing dir "A1/B2/C2" failed - try 3/3: failed to delete item: Error "Server Error"
run.go:133: removing dir "A1/B2/C2" failed: failed to delete item: Error "Server Error"
2026/02/02 02:50:59 DEBUG : pacer: Reducing sleep to 10s
2026/02/02 02:51:19 DEBUG : pacer: Reducing sleep to 5s
2026/02/02 02:51:29 DEBUG : pacer: Reducing sleep to 2.5s
2026/02/02 02:51:34 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/02/02 02:51:34 DEBUG : pacer: Rate limited, increasing sleep to 5s
2026/02/02 02:51:36 DEBUG : pacer: low level retry 2/10 (error Error "Server Error")
2026/02/02 02:51:36 DEBUG : pacer: Rate limited, increasing sleep to 10s
2026/02/02 02:51:42 DEBUG : pacer: low level retry 3/10 (error Error "Server Error")
2026/02/02 02:51:42 DEBUG : pacer: Rate limited, increasing sleep to 20s
2026/02/02 02:51:51 DEBUG : pacer: low level retry 4/10 (error Error "Server Error")
2026/02/02 02:52:11 DEBUG : pacer: low level retry 5/10 (error Error "Server Error")
2026/02/02 02:52:32 DEBUG : pacer: low level retry 6/10 (error Error "Server Error")
2026/02/02 02:52:51 DEBUG : pacer: low level retry 7/10 (error Error "Server Error")
2026/02/02 02:53:12 DEBUG : pacer: low level retry 8/10 (error Error "Server Error")
2026/02/02 02:53:32 DEBUG : pacer: low level retry 9/10 (error Error "Server Error")
2026/02/02 02:53:51 DEBUG : pacer: low level retry 10/10 (error Error "Server Error")
run.go:130: removing dir "A1/B1/C3" failed - try 1/3: failed to delete item: Error "Server Error"
2026/02/02 02:54:11 DEBUG : pacer: Reducing sleep to 10s
2026/02/02 02:54:31 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/02/02 02:54:31 DEBUG : pacer: Rate limited, increasing sleep to 20s
2026/02/02 02:54:41 DEBUG : pacer: low level retry 2/10 (error Error "Server Error")
2026/02/02 02:55:01 DEBUG : pacer: low level retry 3/10 (error Error "Server Error")
2026/02/02 02:55:21 DEBUG : pacer: low level retry 4/10 (error Error "Server Error")
2026/02/02 02:55:41 DEBUG : pacer: low level retry 5/10 (error Error "Server Error")
2026/02/02 02:56:02 DEBUG : pacer: low level retry 6/10 (error Error "Server Error")
2026/02/02 02:56:21 DEBUG : pacer: low level retry 7/10 (error Error "Server Error")
2026/02/02 02:56:42 DEBUG : pacer: low level retry 8/10 (error Error "Server Error")
2026/02/02 02:57:02 DEBUG : pacer: low level retry 9/10 (error Error "Server Error")
2026/02/02 02:57:22 DEBUG : pacer: low level retry 10/10 (error Error "Server Error")
run.go:130: removing dir "A1/B1/C3" failed - try 2/3: failed to delete item: Error "Server Error"
2026/02/02 02:57:42 DEBUG : pacer: Reducing sleep to 10s
2026/02/02 02:58:02 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/02/02 02:58:02 DEBUG : pacer: Rate limited, increasing sleep to 20s
2026/02/02 02:58:12 DEBUG : pacer: low level retry 2/10 (error Error "Server Error")
2026/02/02 02:58:32 DEBUG : pacer: low level retry 3/10 (error Error "Server Error")
2026/02/02 02:58:52 DEBUG : pacer: low level retry 4/10 (error Error "Server Error")
2026/02/02 02:59:12 DEBUG : pacer: low level retry 5/10 (error Error "Server Error")
2026/02/02 02:59:31 DEBUG : pacer: low level retry 6/10 (error Error "Server Error")
2026/02/02 02:59:52 DEBUG : pacer: low level retry 7/10 (error Error "Server Error")
2026/02/02 03:00:12 DEBUG : pacer: low level retry 8/10 (error Error "Server Error")
2026/02/02 03:00:32 DEBUG : pacer: low level retry 9/10 (error Error "Server Error")
2026/02/02 03:00:52 DEBUG : pacer: low level retry 10/10 (error Error "Server Error")
run.go:130: removing dir "A1/B1/C3" failed - try 3/3: failed to delete item: Error "Server Error"
run.go:133: removing dir "A1/B1/C3" failed: failed to delete item: Error "Server Error"
2026/02/02 03:01:12 DEBUG : pacer: Reducing sleep to 10s
2026/02/02 03:01:32 DEBUG : pacer: Reducing sleep to 5s
2026/02/02 03:01:42 DEBUG : pacer: Reducing sleep to 2.5s
2026/02/02 03:01:47 DEBUG : pacer: Reducing sleep to 1.25s
2026/02/02 03:01:49 DEBUG : pacer: Reducing sleep to 625ms
2026/02/02 03:01:51 DEBUG : pacer: Reducing sleep to 312.5ms
2026/02/02 03:01:51 DEBUG : pacer: Reducing sleep to 156.25ms
--- FAIL: TestRmdirsNoLeaveRoot (2362.06s)
=== RUN TestRmdirsLeaveRoot
run.go:185: Remote "drime root 'rclone-test-seretuh0vefa'", Local "Local file system at /tmp/rclone4245148629", Modify Window "876000h0m0s"
2026/02/02 03:01:51 INFO : A1: Making directory
2026/02/02 03:01:52 DEBUG : pacer: Reducing sleep to 78.125ms
2026/02/02 03:01:52 DEBUG : pacer: Reducing sleep to 39.0625ms
2026/02/02 03:01:52 INFO : A1/B1: Making directory
2026/02/02 03:01:53 DEBUG : pacer: Reducing sleep to 19.53125ms
2026/02/02 03:01:54 DEBUG : pacer: Reducing sleep to 10ms
2026/02/02 03:01:54 INFO : A1/B1/C1: 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/fs/operations/operations_test.go:750
Error: Not equal:
expected: []string{"A1", "A1/B1", "A1/B1/C1"}
actual : []string{}
Diff:
--- Expected
+++ Actual
@@ -1,5 +1,2 @@
-([]string) (len=3) {
- (string) (len=2) "A1",
- (string) (len=5) "A1/B1",
- (string) (len=8) "A1/B1/C1"
+([]string) {
}
Test: TestRmdirsLeaveRoot
Messages: directories
2026/02/02 03:02:07 DEBUG : removing 1 level 3 directories
2026/02/02 03:02:07 INFO : A1/B1/C1: Removing directory
2026/02/02 03:02:07 DEBUG : removing 1 level 2 directories
2026/02/02 03:02:07 INFO : A1/B1: Removing directory
2026/02/02 03:02:08 ERROR : A1/B1: Failed to rmdir: directory not empty
operations_test.go:762:
Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:762
Error: Received unexpected error:
failed to remove directories: directory not empty
Test: TestRmdirsLeaveRoot
2026/02/02 03:02:10 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/02/02 03:02:10 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/02/02 03:02:11 DEBUG : pacer: low level retry 2/10 (error Error "Server Error")
2026/02/02 03:02:11 DEBUG : pacer: Rate limited, increasing sleep to 40ms
2026/02/02 03:02:11 DEBUG : pacer: low level retry 3/10 (error Error "Server Error")
2026/02/02 03:02:11 DEBUG : pacer: Rate limited, increasing sleep to 80ms
2026/02/02 03:02:11 DEBUG : pacer: low level retry 4/10 (error Error "Server Error")
2026/02/02 03:02:11 DEBUG : pacer: Rate limited, increasing sleep to 160ms
2026/02/02 03:02:12 DEBUG : pacer: low level retry 5/10 (error Error "Server Error")
2026/02/02 03:02:12 DEBUG : pacer: Rate limited, increasing sleep to 320ms
2026/02/02 03:02:13 DEBUG : pacer: low level retry 6/10 (error Error "Server Error")
2026/02/02 03:02:13 DEBUG : pacer: Rate limited, increasing sleep to 640ms
2026/02/02 03:02:13 DEBUG : pacer: low level retry 7/10 (error Error "Server Error")
2026/02/02 03:02:13 DEBUG : pacer: Rate limited, increasing sleep to 1.28s
2026/02/02 03:02:14 DEBUG : pacer: low level retry 8/10 (error Error "Server Error")
2026/02/02 03:02:14 DEBUG : pacer: Rate limited, increasing sleep to 2.56s
2026/02/02 03:02:15 DEBUG : pacer: low level retry 9/10 (error Error "Server Error")
2026/02/02 03:02:15 DEBUG : pacer: Rate limited, increasing sleep to 5.12s
2026/02/02 03:02:17 DEBUG : pacer: low level retry 10/10 (error Error "Server Error")
2026/02/02 03:02:17 DEBUG : pacer: Rate limited, increasing sleep to 10.24s
run.go:130: removing dir "A1/B1/C1" failed - try 1/3: failed to delete item: Error "Server Error"
2026/02/02 03:02:23 DEBUG : pacer: Reducing sleep to 5.12s
2026/02/02 03:02:33 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/02/02 03:02:33 DEBUG : pacer: Rate limited, increasing sleep to 10.24s
2026/02/02 03:02:38 DEBUG : pacer: low level retry 2/10 (error Error "Server Error")
2026/02/02 03:02:38 DEBUG : pacer: Rate limited, increasing sleep to 20s
2026/02/02 03:02:48 DEBUG : pacer: low level retry 3/10 (error Error "Server Error")
panic: test timed out after 2h0m0s
running tests:
TestRmdirsLeaveRoot (1m6s)
goroutine 10684 [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]:
testing.(*T).Run(0xc000472fc0, {0x2980fea?, 0xc000807ad8?}, 0x2ad8738)
/usr/local/go/src/testing/testing.go:1859 +0x431
testing.runTests.func1(0xc000472fc0)
/usr/local/go/src/testing/testing.go:2279 +0x37
testing.tRunner(0xc000472fc0, 0xc000807c18)
/usr/local/go/src/testing/testing.go:1792 +0xf4
testing.runTests(0xc00078c528, {0x44a3d60, 0x6f, 0x6f}, {0x849500?, 0x44df3a0?, 0x44bb5e0?})
/usr/local/go/src/testing/testing.go:2277 +0x4b4
testing.(*M).Run(0xc0007ed220)
/usr/local/go/src/testing/testing.go:2142 +0x64a
github.com/rclone/rclone/fstest.TestMain(0xc0007ed220)
/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 34 [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 35 [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 2180 [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 2138
/home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:255 +0x378
goroutine 1631 [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 1621
/home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:255 +0x378
goroutine 1738 [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 1584
/home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:255 +0x378
goroutine 1379 [chan receive (nil chan), 103 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 1369
/home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:255 +0x378
goroutine 110 [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 53
/home/rclone/go/src/github.com/rclone/rclone/lib/atexit/atexit.go:44 +0x68
goroutine 3317 [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 3180
/home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:21 +0xa7
goroutine 3609 [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 3180
/home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:21 +0xa7
goroutine 9469 [chan receive, 41 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 9468
/home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:21 +0xa7
goroutine 1429 [chan receive (nil chan), 103 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 1323
/home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:255 +0x378
goroutine 2381 [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 2371
/home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:255 +0x378
goroutine 1420 [chan receive (nil chan), 103 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 1410
/home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:255 +0x378
goroutine 10563 [chan receive]:
github.com/rclone/rclone/lib/pacer.(*Pacer).beginCall(0xc0005a88a0, 0x0)
/home/rclone/go/src/github.com/rclone/rclone/lib/pacer/pacer.go:162 +0x26
github.com/rclone/rclone/lib/pacer.(*Pacer).call(0xc0005a88a0, 0xc0000ad9c0, 0xa)
/home/rclone/go/src/github.com/rclone/rclone/lib/pacer/pacer.go:215 +0xb2
github.com/rclone/rclone/lib/pacer.(*Pacer).Call(0xc0005a88a0, 0xc0000ad9c0)
/home/rclone/go/src/github.com/rclone/rclone/lib/pacer/pacer.go:235 +0x93
github.com/rclone/rclone/backend/drime.(*Fs).deleteObject(0xc0001488c0, {0x2e7a958, 0x44dee00}, {0xc000786580, 0x9})
/home/rclone/go/src/github.com/rclone/rclone/backend/drime/drime.go:703 +0x1eb
github.com/rclone/rclone/backend/drime.(*Fs).purgeCheck(0xc0001488c0, {0x2e7a958, 0x44dee00}, {0xc0007865d8, 0x8}, 0x1)
/home/rclone/go/src/github.com/rclone/rclone/backend/drime/drime.go:744 +0x156
github.com/rclone/rclone/backend/drime.(*Fs).Rmdir(0x4?, {0x2e7a958?, 0x44dee00?}, {0xc0007865d8?, 0xc000d63a40?})
/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(0xc000b28c40, {0xc0007f83c0, 0x17}, 0xc0009ddbd8)
/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(0xc0003d43f0)
/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(0xc000b28c40, 0xc000d63a40?)
/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(0xc000b28c40)
/usr/local/go/src/testing/testing.go:1041 +0x4a
github.com/stretchr/testify/require.NoError({0x2e64710, 0xc000b28c40}, {0x2e50a20, 0xc000ad6ae0}, {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.TestRmdirsLeaveRoot(0xc000b28c40)
/home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:762 +0x2dc
testing.tRunner(0xc000b28c40, 0x2ad8738)
/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 1253 [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 1243
/home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:255 +0x378
goroutine 1911 [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 1885
/home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:255 +0x378
goroutine 3392 [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 3180
/home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:21 +0xa7
goroutine 1485 [chan receive (nil chan), 103 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 1476
/home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:255 +0x378
goroutine 2351 [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 2341
/home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:255 +0x378
goroutine 10252 [IO wait]:
internal/poll.runtime_pollWait(0x783fece69808, 0x72)
/usr/local/go/src/runtime/netpoll.go:351 +0x85
internal/poll.(*pollDesc).wait(0xc0002c0a80?, 0xc00055c600?, 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(0xc0002c0a80, {0xc00055c600, 0x1300, 0x1300})
/usr/local/go/src/internal/poll/fd_unix.go:165 +0x27a
net.(*netFD).Read(0xc0002c0a80, {0xc00055c600?, 0x1260?, 0xc0007146c0?})
/usr/local/go/src/net/fd_posix.go:55 +0x25
net.(*conn).Read(0xc00003a040, {0xc00055c600?, 0xc00055c69b?, 0x5?})
/usr/local/go/src/net/net.go:194 +0x45
github.com/rclone/rclone/fs/fshttp.(*timeoutConn).Read(0xc0004ae588, {0xc00055c600?, 0x783fa435d408?, 0x783fecfc0a78?})
/home/rclone/go/src/github.com/rclone/rclone/fs/fshttp/dialer.go:111 +0x29
crypto/tls.(*atLeastReader).Read(0xc000668a50, {0xc00055c600?, 0x1260?, 0x4?})
/usr/local/go/src/crypto/tls/conn.go:809 +0x3b
bytes.(*Buffer).ReadFrom(0xc000467eb8, {0x2e52320, 0xc000668a50})
/usr/local/go/src/bytes/buffer.go:211 +0x98
crypto/tls.(*Conn).readFromUntil(0xc000467c08, {0x783fa41d1a98, 0xc0004ae588}, 0x4426d4?)
/usr/local/go/src/crypto/tls/conn.go:831 +0xde
crypto/tls.(*Conn).readRecordOrCCS(0xc000467c08, 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(0xc000467c08, {0xc0005f3000, 0x1000, 0xc000094ca0?})
/usr/local/go/src/crypto/tls/conn.go:1385 +0x145
bufio.(*Reader).Read(0xc0005a96e0, {0xc000000040, 0x9, 0x4423d30?})
/usr/local/go/src/bufio/bufio.go:245 +0x197
io.ReadAtLeast({0x2e506c0, 0xc0005a96e0}, {0xc000000040, 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({0xc000000040, 0x9, 0xc000b20c30?}, {0x2e506c0?, 0xc0005a96e0?})
/usr/local/go/src/net/http/h2_bundle.go:1805 +0x65
net/http.(*http2Framer).ReadFrame(0xc000000000)
/usr/local/go/src/net/http/h2_bundle.go:2072 +0x7d
net/http.(*http2clientConnReadLoop).run(0xc000094fa8)
/usr/local/go/src/net/http/h2_bundle.go:9933 +0xda
net/http.(*http2ClientConn).readLoop(0xc000b28540)
/usr/local/go/src/net/http/h2_bundle.go:9812 +0x79
created by net/http.(*http2Transport).newClientConn in goroutine 10251
/usr/local/go/src/net/http/h2_bundle.go:8334 +0xde5
goroutine 2052 [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 2042
/home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:255 +0x378
goroutine 1148 [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 1138
/home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:255 +0x378
goroutine 1316 [chan receive (nil chan), 103 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 1290
/home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:255 +0x378
goroutine 1198 [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 1188
/home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:255 +0x378
goroutine 3199 [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 3180
/home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:21 +0xa7
goroutine 1682 [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 1672
/home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:255 +0x378
goroutine 1816 [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 1806
/home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:255 +0x378
goroutine 2228 [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 2218
/home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:255 +0x378
goroutine 3821 [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 3180
/home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:21 +0xa7
goroutine 3589 [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 3180
/home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:21 +0xa7
goroutine 2300 [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 2290
/home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:255 +0x378
goroutine 1563 [chan receive (nil chan), 103 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 1553
/home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:255 +0x378
goroutine 1854 [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 1844
/home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:255 +0x378
goroutine 3181 [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 3180
/home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:21 +0xa7
goroutine 10682 [sleep]:
time.Sleep(0x4a817c800)
/usr/local/go/src/runtime/time.go:338 +0x165
github.com/rclone/rclone/lib/pacer.(*Pacer).beginCall.func1(0xc000266d08?)
/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 10563
/home/rclone/go/src/github.com/rclone/rclone/lib/pacer/pacer.go:169 +0xf6
goroutine 3722 [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 3180
/home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:21 +0xa7
goroutine 2110 [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 2100
/home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:255 +0x378
goroutine 3477 [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 3180
/home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:21 +0xa7
goroutine 3528 [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 3180
/home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:21 +0xa7
"./operations.test -test.v -test.timeout 2h0m0s -remote TestDrime: -verbose" - Finished ERROR in 2h0m1.112457505s (try 1/5): exit status 2: Failed [TestMultithreadCopy/upload=false,size=10485759,streams=2 TestMultithreadCopy/upload=false,size=10485760,streams=2 TestMultithreadCopy/upload=false,size=10485761,streams=2 TestMultithreadCopy/upload=true,size=10485759,streams=2 TestMultithreadCopy/upload=true,size=10485760,streams=2 TestMultithreadCopy/upload=true,size=10485761,streams=2 TestMultithreadCopyAbort TestCheckDownload TestCheckSizeOnly/1 TestParseSumFile TestCheckSumDownload TestApplyTransforms TestCopyFile TestCopyLongFile TestCopyFileCompareDest TestCopyFileCopyDest TestCopyInplace TestCopyFileMaxTransfer TestListDirSortedFn TestListJSON/Default TestListJSON/DirsOnly TestListJSON/Recurse TestListJSON/Metadata TestStatJSON TestMkdir TestLsd TestLsWithFilesFrom TestLsLong TestHashSums TestMaxDelete TestMaxDeleteSize TestReadFile TestCat TestPurge TestRmdirsNoLeaveRoot]