"./operations.test -test.v -test.timeout 2h0m0s -remote TestDrime: -verbose -test.run '^(TestApplyTransforms|TestCat|TestCheckDownload|TestCheckSumDownload|TestCopyFile|TestCopyFileCompareDest|TestCopyFileCopyDest|TestCopyFileMaxTransfer|TestCopyInplace|TestCopyLongFile|TestHashSums|TestListDirSortedFn|TestLsLong|TestLsWithFilesFrom|TestLsd|TestMaxDelete|TestMaxDeleteSize|TestMkdir|TestMultithreadCopyAbort|TestParseSumFile|TestPurge|TestReadFile|TestRmdirsNoLeaveRoot|TestStatJSON)$|^TestCheckSizeOnly$/^1$|^TestListJSON$/^(Default|DirsOnly|Metadata|Recurse)$|^TestMultithreadCopy$/^(upload=false,size=10485759,streams=2|upload=false,size=10485760,streams=2|upload=false,size=10485761,streams=2|upload=true,size=10485759,streams=2|upload=true,size=10485760,streams=2|upload=true,size=10485761,streams=2)$'" - Starting (try 2/5)
2026/02/02 03:02:57 DEBUG : Creating backend with remote "TestDrime:rclone-test-bejazic8foyi"
2026/02/02 03:02:57 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/02/02 03:02:58 DEBUG : Creating backend with remote "/tmp/rclone4034720377"
=== RUN TestMultithreadCopy
run.go:185: Remote "drime root 'rclone-test-bejazic8foyi'", Local "Local file system at /tmp/rclone4034720377", 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 03:03:04 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 03: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 03:03:11 DEBUG : test-multithread-copy-false-10485759-2: multi-thread copy: disabling buffering because destination uses OpenWriterAt
2026/02/02 03:03:11 DEBUG : test-multithread-copy-false-10485759-2: multi-thread copy: write buffer set to 131072
2026/02/02 03:03:11 DEBUG : test-multithread-copy-false-10485759-2: multi-thread copy: chunk size 64Mi was bigger than source file size 10.000Mi
2026/02/02 03:03:11 DEBUG : test-multithread-copy-false-10485759-2: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 2
2026/02/02 03:03:11 DEBUG : test-multithread-copy-false-10485759-2: multi-thread copy: number of streams 4 was bigger than number of chunks 1
2026/02/02 03:03:11 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 03:03:11 DEBUG : test-multithread-copy-false-10485759-2: multi-thread copy: chunk 1/1 (0-10485759) size 10.000Mi starting
2026/02/02 03:03:12 DEBUG : test-multithread-copy-false-10485759-2: writing chunk 0
2026/02/02 03:03:13 DEBUG : test-multithread-copy-false-10485759-2: multi-thread copy: chunk 1/1 (0-10485759) size 10.000Mi finished
2026/02/02 03:03:13 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 03:03:26 DEBUG : test-multithread-copy-false-10485760-2: multi-thread copy: disabling buffering because destination uses OpenWriterAt
2026/02/02 03:03:26 DEBUG : test-multithread-copy-false-10485760-2: multi-thread copy: write buffer set to 131072
2026/02/02 03:03:26 DEBUG : test-multithread-copy-false-10485760-2: multi-thread copy: chunk size 64Mi was bigger than source file size 10Mi
2026/02/02 03:03:26 DEBUG : test-multithread-copy-false-10485760-2: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 2
2026/02/02 03:03:26 DEBUG : test-multithread-copy-false-10485760-2: multi-thread copy: number of streams 4 was bigger than number of chunks 1
2026/02/02 03:03:26 DEBUG : test-multithread-copy-false-10485760-2: Starting multi-thread copy with 1 chunks of size 10Mi with 1 parallel streams
2026/02/02 03:03:26 DEBUG : test-multithread-copy-false-10485760-2: multi-thread copy: chunk 1/1 (0-10485760) size 10Mi starting
2026/02/02 03:03:26 DEBUG : test-multithread-copy-false-10485760-2: writing chunk 0
2026/02/02 03:03:27 DEBUG : test-multithread-copy-false-10485760-2: multi-thread copy: chunk 1/1 (0-10485760) size 10Mi finished
2026/02/02 03:03:27 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 03:03:40 DEBUG : test-multithread-copy-false-10485761-2: multi-thread copy: disabling buffering because destination uses OpenWriterAt
2026/02/02 03:03:40 DEBUG : test-multithread-copy-false-10485761-2: multi-thread copy: write buffer set to 131072
2026/02/02 03:03:40 DEBUG : test-multithread-copy-false-10485761-2: multi-thread copy: chunk size 64Mi was bigger than source file size 10.000Mi
2026/02/02 03:03:40 DEBUG : test-multithread-copy-false-10485761-2: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 2
2026/02/02 03:03:40 DEBUG : test-multithread-copy-false-10485761-2: multi-thread copy: number of streams 4 was bigger than number of chunks 1
2026/02/02 03:03:40 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 03:03:40 DEBUG : test-multithread-copy-false-10485761-2: multi-thread copy: chunk 1/1 (0-10485761) size 10.000Mi starting
2026/02/02 03:03:41 DEBUG : test-multithread-copy-false-10485761-2: writing chunk 0
2026/02/02 03:03:41 DEBUG : test-multithread-copy-false-10485761-2: multi-thread copy: chunk 1/1 (0-10485761) size 10.000Mi finished
2026/02/02 03:03:41 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 03:03:52 DEBUG : test-multithread-copy-true-10485759-2: multi-thread copy: disabling buffering because source is local disk
2026/02/02 03:03:54 DEBUG : test-multithread-copy-true-10485759-2: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 2
2026/02/02 03:03:54 DEBUG : test-multithread-copy-true-10485759-2: multi-thread copy: number of streams 4 was bigger than number of chunks 2
2026/02/02 03:03:54 DEBUG : test-multithread-copy-true-10485759-2: Starting multi-thread copy with 2 chunks of size 5Mi with 2 parallel streams
2026/02/02 03:03:54 DEBUG : test-multithread-copy-true-10485759-2: multi-thread copy: chunk 2/2 (5242880-10485759) size 5.000Mi starting
2026/02/02 03:03:54 DEBUG : test-multithread-copy-true-10485759-2: multi-thread copy: chunk 1/2 (0-5242880) size 5Mi starting
2026/02/02 03:03:54 DEBUG : test-multithread-copy-true-10485759-2: multi-thread copy: chunk 2/2 (5242880-10485759) size 5.000Mi finished
2026/02/02 03:03:55 DEBUG : test-multithread-copy-true-10485759-2: multi-thread copy: chunk 1/2 (0-5242880) size 5Mi finished
2026/02/02 03:03:57 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 03:04:08 DEBUG : test-multithread-copy-true-10485760-2: multi-thread copy: disabling buffering because source is local disk
2026/02/02 03:04:09 DEBUG : test-multithread-copy-true-10485760-2: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 2
2026/02/02 03:04:09 DEBUG : test-multithread-copy-true-10485760-2: multi-thread copy: number of streams 4 was bigger than number of chunks 2
2026/02/02 03:04:09 DEBUG : test-multithread-copy-true-10485760-2: Starting multi-thread copy with 2 chunks of size 5Mi with 2 parallel streams
2026/02/02 03:04:09 DEBUG : test-multithread-copy-true-10485760-2: multi-thread copy: chunk 2/2 (5242880-10485760) size 5Mi starting
2026/02/02 03:04:09 DEBUG : test-multithread-copy-true-10485760-2: multi-thread copy: chunk 1/2 (0-5242880) size 5Mi starting
2026/02/02 03:04:10 DEBUG : test-multithread-copy-true-10485760-2: multi-thread copy: chunk 1/2 (0-5242880) size 5Mi finished
2026/02/02 03:04:10 DEBUG : test-multithread-copy-true-10485760-2: multi-thread copy: chunk 2/2 (5242880-10485760) size 5Mi finished
2026/02/02 03:04:11 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 03:04:22 DEBUG : test-multithread-copy-true-10485761-2: multi-thread copy: disabling buffering because source is local disk
2026/02/02 03:04:24 DEBUG : test-multithread-copy-true-10485761-2: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 2
2026/02/02 03:04:24 DEBUG : test-multithread-copy-true-10485761-2: multi-thread copy: number of streams 4 was bigger than number of chunks 3
2026/02/02 03:04:24 DEBUG : test-multithread-copy-true-10485761-2: Starting multi-thread copy with 3 chunks of size 5Mi with 3 parallel streams
2026/02/02 03:04:24 DEBUG : test-multithread-copy-true-10485761-2: multi-thread copy: chunk 3/3 (10485760-10485761) size 1 starting
2026/02/02 03:04:24 DEBUG : test-multithread-copy-true-10485761-2: multi-thread copy: chunk 1/3 (0-5242880) size 5Mi starting
2026/02/02 03:04:24 DEBUG : test-multithread-copy-true-10485761-2: multi-thread copy: chunk 2/3 (5242880-10485760) size 5Mi starting
2026/02/02 03:04:24 DEBUG : test-multithread-copy-true-10485761-2: multi-thread copy: chunk 3/3 (10485760-10485761) size 1 finished
2026/02/02 03:04:24 DEBUG : test-multithread-copy-true-10485761-2: multi-thread copy: chunk 2/3 (5242880-10485760) size 5Mi finished
2026/02/02 03:04:24 DEBUG : test-multithread-copy-true-10485761-2: multi-thread copy: chunk 1/3 (0-5242880) size 5Mi finished
2026/02/02 03:04:26 DEBUG : test-multithread-copy-true-10485761-2: Finished multi-thread copy with 3 parts of size 5Mi
2026/02/02 03:04:28 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/02/02 03:04:28 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 03:04:28 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/02/02 03:04:28 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/02/02 03:04:28 DEBUG : pacer: low level retry 2/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 03:04:28 DEBUG : pacer: Rate limited, increasing sleep to 40ms
2026/02/02 03:04:28 DEBUG : pacer: Reducing sleep to 20ms
2026/02/02 03:04:29 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/02/02 03:04:29 DEBUG : pacer: Rate limited, increasing sleep to 40ms
2026/02/02 03:04:29 DEBUG : pacer: low level retry 2/10 (error Error "Server Error")
2026/02/02 03:04:29 DEBUG : pacer: Rate limited, increasing sleep to 80ms
2026/02/02 03:04:29 DEBUG : pacer: low level retry 3/10 (error Error "Server Error")
2026/02/02 03:04:29 DEBUG : pacer: Rate limited, increasing sleep to 160ms
2026/02/02 03:04:29 DEBUG : pacer: low level retry 4/10 (error Error "Server Error")
2026/02/02 03:04:29 DEBUG : pacer: Rate limited, increasing sleep to 320ms
2026/02/02 03:04:30 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/02/02 03:04:30 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 03:04:30 DEBUG : pacer: Rate limited, increasing sleep to 640ms
2026/02/02 03:04:30 DEBUG : pacer: low level retry 6/10 (error Error "Server Error")
2026/02/02 03:04:30 DEBUG : pacer: Rate limited, increasing sleep to 1.28s
2026/02/02 03:04:31 DEBUG : pacer: low level retry 7/10 (error Error "Server Error")
2026/02/02 03:04:31 DEBUG : pacer: Rate limited, increasing sleep to 2.56s
2026/02/02 03:04:32 DEBUG : pacer: low level retry 8/10 (error Error "Server Error")
2026/02/02 03:04:32 DEBUG : pacer: Rate limited, increasing sleep to 5.12s
2026/02/02 03:04:35 DEBUG : pacer: low level retry 9/10 (error Error "Server Error")
2026/02/02 03:04:35 DEBUG : pacer: Rate limited, increasing sleep to 10.24s
2026/02/02 03:04:40 DEBUG : pacer: low level retry 10/10 (error Error "Server Error")
2026/02/02 03:04:40 DEBUG : pacer: Rate limited, increasing sleep to 20s
=== 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 03:04:50 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/02/02 03:05:10 DEBUG : pacer: low level retry 2/10 (error Error "Server Error")
2026/02/02 03:05:30 DEBUG : pacer: low level retry 3/10 (error Error "Server Error")
2026/02/02 03:05:50 DEBUG : pacer: low level retry 4/10 (error Error "Server Error")
2026/02/02 03:06:10 DEBUG : pacer: low level retry 5/10 (error Error "Server Error")
2026/02/02 03:06:30 DEBUG : pacer: low level retry 6/10 (error Error "Server Error")
2026/02/02 03:06:50 DEBUG : pacer: low level retry 7/10 (error Error "Server Error")
2026/02/02 03:07:10 DEBUG : pacer: low level retry 8/10 (error Error "Server Error")
2026/02/02 03:07:30 DEBUG : pacer: low level retry 9/10 (error Error "Server Error")
2026/02/02 03:07:51 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 03:08:10 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/02/02 03:08:30 DEBUG : pacer: low level retry 2/10 (error Error "Server Error")
2026/02/02 03:08:51 DEBUG : pacer: low level retry 3/10 (error Error "Server Error")
2026/02/02 03:09:10 DEBUG : pacer: low level retry 4/10 (error Error "Server Error")
2026/02/02 03:09:31 DEBUG : pacer: low level retry 5/10 (error Error "Server Error")
2026/02/02 03:09:50 DEBUG : pacer: low level retry 6/10 (error Error "Server Error")
2026/02/02 03:10:11 DEBUG : pacer: low level retry 7/10 (error Error "Server Error")
2026/02/02 03:10:30 DEBUG : pacer: low level retry 8/10 (error Error "Server Error")
2026/02/02 03:10:50 DEBUG : pacer: low level retry 9/10 (error Error "Server Error")
2026/02/02 03:11:10 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/02/02 03:11:10 DEBUG : pacer: low level retry 10/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")
run.go:130: removing file "test-multithread-copy-true-10485761-2" failed - try 3/3: failed to delete item: 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"
run.go:133: removing file "test-multithread-copy-true-10485761-2" failed: failed to delete item: 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 03:11:30 DEBUG : pacer: Reducing sleep to 10s
--- FAIL: TestMultithreadCopy (512.22s)
--- FAIL: TestMultithreadCopy/upload=false,size=10485759,streams=2 (14.05s)
--- FAIL: TestMultithreadCopy/upload=false,size=10485760,streams=2 (14.20s)
--- FAIL: TestMultithreadCopy/upload=false,size=10485761,streams=2 (14.51s)
--- FAIL: TestMultithreadCopy/upload=true,size=10485759,streams=2 (15.31s)
--- FAIL: TestMultithreadCopy/upload=true,size=10485760,streams=2 (15.11s)
--- FAIL: TestMultithreadCopy/upload=true,size=10485761,streams=2 (14.99s)
=== RUN TestMultithreadCopyAbort
run.go:185: Remote "drime root 'rclone-test-bejazic8foyi'", Local "Local file system at /tmp/rclone4034720377", Modify Window "876000h0m0s"
2026/02/02 03:11:50 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/02/02 03:11:50 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 03:11:50 DEBUG : pacer: Rate limited, increasing sleep to 20s
2026/02/02 03:12:00 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/02/02 03:12:00 DEBUG : pacer: low level retry 2/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 03:12:20 DEBUG : pacer: Reducing sleep to 10s
2026/02/02 03:12:40 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/02/02 03:12:40 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 03:12:40 DEBUG : pacer: Rate limited, increasing sleep to 20s
2026/02/02 03:12:50 DEBUG : pacer: Reducing sleep to 10s
2026/02/02 03:13:10 DEBUG : pacer: Reducing sleep to 5s
2026/02/02 03:13:21 DEBUG : pacer: Reducing sleep to 2.5s
2026/02/02 03:13:25 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/02/02 03:13:25 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 03:13:25 DEBUG : pacer: Rate limited, increasing sleep to 5s
2026/02/02 03:13:28 DEBUG : pacer: Reducing sleep to 2.5s
2026/02/02 03:13:29 DEBUG : test-multithread-abort: multi-thread copy: disabling buffering because source is local disk
2026/02/02 03:13:33 DEBUG : pacer: Reducing sleep to 1.25s
2026/02/02 03:13:33 DEBUG : test-multithread-abort: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 1
2026/02/02 03:13:33 DEBUG : test-multithread-abort: multi-thread copy: number of streams 4 was bigger than number of chunks 3
2026/02/02 03:13:33 DEBUG : test-multithread-abort: Starting multi-thread copy with 3 chunks of size 5Mi with 3 parallel streams
2026/02/02 03:13:33 DEBUG : test-multithread-abort: multi-thread copy: chunk 3/3 (10485760-10485761) size 1 starting
2026/02/02 03:13:33 DEBUG : Open with options = [RangeOption(10485760,10485760)]
2026/02/02 03:13:33 DEBUG : test-multithread-abort: multi-thread copy: chunk 2/3 (5242880-10485760) size 5Mi starting
2026/02/02 03:13:33 DEBUG : test-multithread-abort: multi-thread copy: chunk 1/3 (0-5242880) size 5Mi starting
2026/02/02 03:13:33 DEBUG : Open with options = [RangeOption(0,5242879)]
2026/02/02 03:13:33 DEBUG : Open with options = [RangeOption(5242880,10485759)]
2026/02/02 03:13:36 DEBUG : pacer: Reducing sleep to 625ms
2026/02/02 03:13:37 DEBUG : pacer: Reducing sleep to 312.5ms
2026/02/02 03:13:38 DEBUG : pacer: Reducing sleep to 156.25ms
2026/02/02 03:13:38 DEBUG : test-multithread-abort: multi-thread copy: chunk 1/3 (0-5242880) size 5Mi finished
2026/02/02 03:13:38 DEBUG : pacer: Reducing sleep to 78.125ms
2026/02/02 03:13:38 DEBUG : test-multithread-abort: multi-thread copy: chunk 2/3 (5242880-10485760) size 5Mi finished
2026/02/02 03:13:38 DEBUG : Returning error reader
2026/02/02 03:13:38 DEBUG : pacer: Reducing sleep to 39.0625ms
2026/02/02 03:13:38 DEBUG : BOOM: simulated read failure
2026/02/02 03:13:38 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 0/10: BOOM: simulated read failure
2026/02/02 03:13:38 DEBUG : Open with options = [RangeOption(10485760,10485760)]
2026/02/02 03:13:39 DEBUG : Returning error reader
2026/02/02 03:13:39 DEBUG : BOOM: simulated read failure
2026/02/02 03:13:39 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 1/10: BOOM: simulated read failure
2026/02/02 03:13:39 DEBUG : Open with options = [RangeOption(10485760,10485760)]
2026/02/02 03:13:40 DEBUG : Returning error reader
2026/02/02 03:13:40 DEBUG : BOOM: simulated read failure
2026/02/02 03:13:40 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 2/10: BOOM: simulated read failure
2026/02/02 03:13:40 DEBUG : Open with options = [RangeOption(10485760,10485760)]
2026/02/02 03:13:41 DEBUG : Returning error reader
2026/02/02 03:13:41 DEBUG : BOOM: simulated read failure
2026/02/02 03:13:41 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 3/10: BOOM: simulated read failure
2026/02/02 03:13:41 DEBUG : Open with options = [RangeOption(10485760,10485760)]
2026/02/02 03:13:42 DEBUG : Returning error reader
2026/02/02 03:13:42 DEBUG : BOOM: simulated read failure
2026/02/02 03:13:42 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 4/10: BOOM: simulated read failure
2026/02/02 03:13:42 DEBUG : Open with options = [RangeOption(10485760,10485760)]
2026/02/02 03:13:43 DEBUG : Returning error reader
2026/02/02 03:13:43 DEBUG : BOOM: simulated read failure
2026/02/02 03:13:43 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 5/10: BOOM: simulated read failure
2026/02/02 03:13:43 DEBUG : Open with options = [RangeOption(10485760,10485760)]
2026/02/02 03:13:44 DEBUG : Returning error reader
2026/02/02 03:13:44 DEBUG : BOOM: simulated read failure
2026/02/02 03:13:44 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 6/10: BOOM: simulated read failure
2026/02/02 03:13:44 DEBUG : Open with options = [RangeOption(10485760,10485760)]
2026/02/02 03:13:45 DEBUG : Returning error reader
2026/02/02 03:13:45 DEBUG : BOOM: simulated read failure
2026/02/02 03:13:45 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 7/10: BOOM: simulated read failure
2026/02/02 03:13:45 DEBUG : Open with options = [RangeOption(10485760,10485760)]
2026/02/02 03:13:46 DEBUG : Returning error reader
2026/02/02 03:13:46 DEBUG : BOOM: simulated read failure
2026/02/02 03:13:46 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 8/10: BOOM: simulated read failure
2026/02/02 03:13:46 DEBUG : Open with options = [RangeOption(10485760,10485760)]
2026/02/02 03:13:47 DEBUG : Returning error reader
2026/02/02 03:13:47 DEBUG : BOOM: simulated read failure
2026/02/02 03:13:47 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 9/10: BOOM: simulated read failure
2026/02/02 03:13:47 DEBUG : Open with options = [RangeOption(10485760,10485760)]
2026/02/02 03:13:48 DEBUG : Returning error reader
2026/02/02 03:13:48 DEBUG : BOOM: simulated read failure
2026/02/02 03:13:48 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 10/10: BOOM: simulated read failure
2026/02/02 03:13:48 DEBUG : test-multithread-abort: Reopen failed after offset 0 bytes read: failed to reopen: too many retries
2026/02/02 03:13:48 DEBUG : pacer: Reducing sleep to 19.53125ms
2026/02/02 03:13:48 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/edaf87a1-e57a-4cec-aa8a-6751d6ea4b88/edaf87a1-e57a-4cec-aa8a-6751d6ea4b88?uploadId=AK-_siwptiKus4vTmf6FrRnncUljH2PA8Wic6JgYmXlwEyMjoIGBiv2tJkM_cGy5hzYEKLpFl9WYS5_9EffFe1BT27sBAKzHEOOJ4Ye0ZyNsZML3UnBM68tAjQkpDBlou3GA0jzSMMm0P1KGPc4mqm0RJFBlvaj8Nn5WG8B2IlmsQdopaQhH4QWFynQKnsjDxVuOazG4HSz3Y6bRtyNSjQRm0rXulfwUEQcVCuN3H4fO4NF7LOg2UPZE-PCfKtsOOluIm51KyS5Z8CW5sSxTC4J4vrTHKhRqZGHTshj-r1KB-33EeKCqQsaCuwB6Q0BEy1aACGGs-b7utfet9R8F3d4&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=20260202T031338Z&X-Amz-SignedHeaders=host&X-Amz-Expires=1800&X-Amz-Signature=b467a514cb2cef93eca4d1afab73551d151c31424dc2c5606de496ab33ef7c02": BOOM: simulated read failure
2026/02/02 03:13:48 DEBUG : test-multithread-abort: multi-thread copy: cancelling transfer on exit
2026/02/02 03:13:49 DEBUG : pacer: Reducing sleep to 10ms
2026/02/02 03:13:51 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/02/02 03:13:51 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/02/02 03:13:52 DEBUG : pacer: low level retry 2/10 (error Error "Server Error")
2026/02/02 03:13:52 DEBUG : pacer: Rate limited, increasing sleep to 40ms
2026/02/02 03:13:52 DEBUG : pacer: low level retry 3/10 (error Error "Server Error")
2026/02/02 03:13:52 DEBUG : pacer: Rate limited, increasing sleep to 80ms
2026/02/02 03:13:53 DEBUG : pacer: low level retry 4/10 (error Error "Server Error")
2026/02/02 03:13:53 DEBUG : pacer: Rate limited, increasing sleep to 160ms
2026/02/02 03:13:53 DEBUG : pacer: low level retry 5/10 (error Error "Server Error")
2026/02/02 03:13:53 DEBUG : pacer: Rate limited, increasing sleep to 320ms
2026/02/02 03:13:54 DEBUG : pacer: low level retry 6/10 (error Error "Server Error")
2026/02/02 03:13:54 DEBUG : pacer: Rate limited, increasing sleep to 640ms
2026/02/02 03:13:54 DEBUG : pacer: low level retry 7/10 (error Error "Server Error")
2026/02/02 03:13:54 DEBUG : pacer: Rate limited, increasing sleep to 1.28s
2026/02/02 03:13:55 DEBUG : pacer: low level retry 8/10 (error Error "Server Error")
2026/02/02 03:13:55 DEBUG : pacer: Rate limited, increasing sleep to 2.56s
2026/02/02 03:13:56 DEBUG : pacer: low level retry 9/10 (error Error "Server Error")
2026/02/02 03:13:56 DEBUG : pacer: Rate limited, increasing sleep to 5.12s
2026/02/02 03:13:59 DEBUG : pacer: low level retry 10/10 (error Error "Server Error")
2026/02/02 03:13:59 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 03:14:04 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/02/02 03:14:04 DEBUG : pacer: Rate limited, increasing sleep to 20s
2026/02/02 03:14:14 DEBUG : pacer: low level retry 2/10 (error Error "Server Error")
2026/02/02 03:14:34 DEBUG : pacer: low level retry 3/10 (error Error "Server Error")
2026/02/02 03:14:54 DEBUG : pacer: low level retry 4/10 (error Error "Server Error")
2026/02/02 03:15:14 DEBUG : pacer: low level retry 5/10 (error Error "Server Error")
2026/02/02 03:15:34 DEBUG : pacer: low level retry 6/10 (error Error "Server Error")
2026/02/02 03:15:54 DEBUG : pacer: low level retry 7/10 (error Error "Server Error")
2026/02/02 03:16:14 DEBUG : pacer: low level retry 8/10 (error Error "Server Error")
2026/02/02 03:16:34 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/02/02 03:16:34 DEBUG : pacer: low level retry 9/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 03:16:54 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/02/02 03:16:54 DEBUG : pacer: low level retry 10/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")
run.go:130: removing file "test-multithread-abort" failed - try 2/3: failed to delete item: 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 03:17:14 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/02/02 03:17:34 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/02/02 03:17:34 DEBUG : pacer: low level retry 2/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 03:17:54 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/02/02 03:17:54 DEBUG : pacer: low level retry 3/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 03:18:14 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/02/02 03:18:14 DEBUG : pacer: low level retry 4/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 03:18:34 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/02/02 03:18:34 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 03:18:54 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/02/02 03:18:54 DEBUG : pacer: low level retry 6/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 03:19:14 DEBUG : pacer: low level retry 7/10 (error Error "Server Error")
2026/02/02 03:19:34 DEBUG : pacer: low level retry 8/10 (error Error "Server Error")
2026/02/02 03:19:54 DEBUG : pacer: low level retry 9/10 (error Error "Server Error")
2026/02/02 03:20:14 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 03:20:34 DEBUG : pacer: Reducing sleep to 10s
--- PASS: TestMultithreadCopyAbort (543.91s)
=== RUN TestCheckDownload
run.go:185: Remote "drime root 'rclone-test-bejazic8foyi'", Local "Local file system at /tmp/rclone4034720377", Modify Window "876000h0m0s"
2026/02/02 03:20:54 DEBUG : pacer: Reducing sleep to 5s
2026/02/02 03:21:05 DEBUG : pacer: Reducing sleep to 2.5s
2026/02/02 03:21:09 DEBUG : pacer: Reducing sleep to 1.25s
=== RUN TestCheckDownload/1
=== RUN TestCheckDownload/2
2026/02/02 03:21:14 DEBUG : pacer: Reducing sleep to 39.0625ms
2026/02/02 03:21:15 DEBUG : pacer: Reducing sleep to 19.53125ms
2026/02/02 03:21:16 DEBUG : pacer: Reducing sleep to 10ms
=== 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:292: Sleeping for 7s just to make sure
=== RUN TestCheckDownload/3
=== NAME TestCheckDownload
fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3
fstest.go:301: Flushing the directory cache
fstest.go:292: Sleeping for 6s just to make sure
=== RUN TestCheckDownload/4
=== RUN TestCheckDownload/5
=== NAME TestCheckDownload
fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3
fstest.go:301: Flushing the directory cache
fstest.go:292: Sleeping for 6s just to make sure
=== RUN TestCheckDownload/6
=== RUN TestCheckDownload/7
=== NAME TestCheckDownload
fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3
fstest.go:301: Flushing the directory cache
fstest.go:292: Sleeping for 6s just to make sure
--- PASS: TestCheckDownload (84.59s)
--- PASS: TestCheckDownload/1 (4.06s)
--- PASS: TestCheckDownload/2 (0.86s)
--- PASS: TestCheckDownload/3 (0.50s)
--- PASS: TestCheckDownload/4 (0.84s)
--- PASS: TestCheckDownload/5 (0.81s)
--- PASS: TestCheckDownload/6 (0.71s)
--- PASS: TestCheckDownload/7 (0.74s)
=== RUN TestCheckSizeOnly
run.go:185: Remote "drime root 'rclone-test-bejazic8foyi'", Local "Local file system at /tmp/rclone4034720377", 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 TestCheckSizeOnly/1
=== NAME TestCheckSizeOnly
fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3
fstest.go:301: Flushing the directory cache
fstest.go:292: Sleeping for 6s just to make sure
fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3
fstest.go:301: Flushing the directory cache
fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3
fstest.go:301: Flushing the directory cache
fstest.go:292: Sleeping for 7s just to make sure
fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3
fstest.go:301: Flushing the directory cache
fstest.go:292: Sleeping for 6s just to make sure
fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3
fstest.go:301: Flushing the directory cache
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: TestCheckSizeOnly (60.07s)
--- PASS: TestCheckSizeOnly/1 (0.45s)
=== RUN TestParseSumFile
run.go:185: Remote "drime root 'rclone-test-bejazic8foyi'", Local "Local file system at /tmp/rclone4034720377", Modify Window "876000h0m0s"
fstest.go:542: Sleeping for 1s for findObject eventual consistency: 1/3 (object not found)
2026/02/02 03:23:02 NOTICE: test.sum: improperly formatted checksum line 4
2026/02/02 03:23:02 NOTICE: test.sum: improperly formatted checksum line 5
2026/02/02 03:23:02 NOTICE: test.sum: improperly formatted checksum line 6
2026/02/02 03:23:02 NOTICE: test.sum: 2 warning(s) suppressed...
2026/02/02 03:23:04 DEBUG : test.sum: Removing old object on successful upload
2026/02/02 03:23:04 NOTICE: test.sum: improperly formatted checksum line 4
2026/02/02 03:23:04 NOTICE: test.sum: improperly formatted checksum line 5
2026/02/02 03:23:04 NOTICE: test.sum: improperly formatted checksum line 6
2026/02/02 03:23:04 NOTICE: test.sum: 2 warning(s) suppressed...
2026/02/02 03:23:05 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/02/02 03:23:05 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/02/02 03:23:05 DEBUG : pacer: low level retry 2/10 (error Error "Server Error")
2026/02/02 03:23:05 DEBUG : pacer: Rate limited, increasing sleep to 40ms
2026/02/02 03:23:06 DEBUG : pacer: low level retry 3/10 (error Error "Server Error")
2026/02/02 03:23:06 DEBUG : pacer: Rate limited, increasing sleep to 80ms
2026/02/02 03:23:06 DEBUG : pacer: low level retry 4/10 (error Error "Server Error")
2026/02/02 03:23:06 DEBUG : pacer: Rate limited, increasing sleep to 160ms
2026/02/02 03:23:07 DEBUG : pacer: low level retry 5/10 (error Error "Server Error")
2026/02/02 03:23:07 DEBUG : pacer: Rate limited, increasing sleep to 320ms
2026/02/02 03:23:07 DEBUG : pacer: low level retry 6/10 (error Error "Server Error")
2026/02/02 03:23:07 DEBUG : pacer: Rate limited, increasing sleep to 640ms
2026/02/02 03:23:07 DEBUG : pacer: low level retry 7/10 (error Error "Server Error")
2026/02/02 03:23:07 DEBUG : pacer: Rate limited, increasing sleep to 1.28s
2026/02/02 03:23:08 DEBUG : pacer: low level retry 8/10 (error Error "Server Error")
2026/02/02 03:23:08 DEBUG : pacer: Rate limited, increasing sleep to 2.56s
2026/02/02 03:23:09 DEBUG : pacer: low level retry 9/10 (error Error "Server Error")
2026/02/02 03:23:09 DEBUG : pacer: Rate limited, increasing sleep to 5.12s
2026/02/02 03:23:12 DEBUG : pacer: low level retry 10/10 (error Error "Server Error")
2026/02/02 03:23:12 DEBUG : pacer: Rate limited, increasing sleep to 10.24s
run.go:130: removing file "test.sum" failed - try 1/3: failed to delete item: Error "Server Error"
2026/02/02 03:23:17 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/02/02 03:23:17 DEBUG : pacer: Rate limited, increasing sleep to 20s
2026/02/02 03:23:27 DEBUG : pacer: low level retry 2/10 (error Error "Server Error")
2026/02/02 03:23:47 DEBUG : pacer: low level retry 3/10 (error Error "Server Error")
2026/02/02 03:24:07 DEBUG : pacer: low level retry 4/10 (error Error "Server Error")
2026/02/02 03:24:27 DEBUG : pacer: low level retry 5/10 (error Error "Server Error")
2026/02/02 03:24:47 DEBUG : pacer: low level retry 6/10 (error Error "Server Error")
2026/02/02 03:25:07 DEBUG : pacer: low level retry 7/10 (error Error "Server Error")
2026/02/02 03:25:27 DEBUG : pacer: low level retry 8/10 (error Error "Server Error")
2026/02/02 03:25:47 DEBUG : pacer: low level retry 9/10 (error Error "Server Error")
2026/02/02 03:26:07 DEBUG : pacer: low level retry 10/10 (error Error "Server Error")
run.go:130: removing file "test.sum" failed - try 2/3: failed to delete item: Error "Server Error"
2026/02/02 03:26:27 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/02/02 03:26:47 DEBUG : pacer: low level retry 2/10 (error Error "Server Error")
2026/02/02 03:27:07 DEBUG : pacer: low level retry 3/10 (error Error "Server Error")
2026/02/02 03:27:27 DEBUG : pacer: low level retry 4/10 (error Error "Server Error")
2026/02/02 03:27:47 DEBUG : pacer: low level retry 5/10 (error Error "Server Error")
2026/02/02 03:28:07 DEBUG : pacer: low level retry 6/10 (error Error "Server Error")
2026/02/02 03:28:27 DEBUG : pacer: low level retry 7/10 (error Error "Server Error")
2026/02/02 03:28:47 DEBUG : pacer: low level retry 8/10 (error Error "Server Error")
2026/02/02 03:29:07 DEBUG : pacer: low level retry 9/10 (error Error "Server Error")
2026/02/02 03:29:27 DEBUG : pacer: low level retry 10/10 (error Error "Server Error")
run.go:130: removing file "test.sum" failed - try 3/3: failed to delete item: Error "Server Error"
run.go:133: removing file "test.sum" failed: failed to delete item: Error "Server Error"
2026/02/02 03:29:47 DEBUG : pacer: Reducing sleep to 10s
fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3
fstest.go:301: Flushing the directory cache
2026/02/02 03:30:07 DEBUG : pacer: Reducing sleep to 5s
2026/02/02 03:30:17 DEBUG : pacer: Reducing sleep to 2.5s
fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3
fstest.go:301: Flushing the directory cache
2026/02/02 03:30:22 DEBUG : pacer: Reducing sleep to 1.25s
2026/02/02 03:30:25 DEBUG : pacer: Reducing sleep to 625ms
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: TestParseSumFile
Messages: listing wrong, want
got
test.sum (90)
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: TestParseSumFile
Messages: Unexpected file "test.sum"
--- FAIL: TestParseSumFile (449.81s)
=== RUN TestCheckSumDownload
run.go:185: Remote "drime root 'rclone-test-bejazic8foyi'", Local "Local file system at /tmp/rclone4034720377", Modify Window "876000h0m0s"
2026/02/02 03:30:29 DEBUG : Creating backend with remote "TestDrime:rclone-test-bejazic8foyi/data"
2026/02/02 03:30:29 DEBUG : Config file has changed externally - reloading
2026/02/02 03:30:30 DEBUG : pacer: Reducing sleep to 312.5ms
2026/02/02 03:30:31 DEBUG : pacer: Reducing sleep to 156.25ms
2026/02/02 03:30:31 DEBUG : pacer: Reducing sleep to 78.125ms
2026/02/02 03:30:31 DEBUG : pacer: Reducing sleep to 39.0625ms
2026/02/02 03:30:33 DEBUG : pacer: Reducing sleep to 19.53125ms
2026/02/02 03:30:33 DEBUG : pacer: Reducing sleep to 10ms
2026/02/02 03:30:34 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 (90)
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 : 90
Test: TestCheckSumDownload
Messages: test.sum: size incorrect file=41 vs obj=90
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 03:30:49 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:450
/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 (41) got
data/banana (13), test.sum (41)
fstest.go:203: Not found "data/potato"
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:450
/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/subtest2
2026/02/02 03:31:01 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/subtest3
2026/02/02 03:31:17 DEBUG : test.sum: Removing old object on successful upload
=== RUN TestCheckSumDownload/subtest4
2026/02/02 03:31:21 DEBUG : test.sum: Removing old object on successful upload
=== NAME TestCheckSumDownload
fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3
fstest.go:301: Flushing the directory cache
fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3
fstest.go:301: Flushing the directory cache
fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3
fstest.go:301: Flushing the directory cache
fstest.go:305:
Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350
/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345
/home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:493
/home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:541
/usr/local/go/src/runtime/asm_amd64.s:1700
Error: Should be true
Test: TestCheckSumDownload
Messages: listing wrong, want
data/banana (13), data/potato (15), test.sum (123) got
data/banana (13), data/potato (15), test.sum (82)
fstest.go:150:
Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:150
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:195
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350
/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345
/home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:493
/home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:541
Error: Not equal:
expected: 123
actual : 82
Test: TestCheckSumDownload
Messages: test.sum: size incorrect file=123 vs obj=82
=== RUN TestCheckSumDownload/subtest5
2026/02/02 03:31:33 DEBUG : test.sum: Removing old object on successful upload
=== RUN TestCheckSumDownload/subtest6
=== NAME TestCheckSumDownload
check_test.go:409:
Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:409
/home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:428
Error: Not equal:
expected: 2
actual : 1
Test: TestCheckSumDownload
Messages: wrong error count in run 6
check_test.go:384:
Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:384
/home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:417
/home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:428
Error: Not equal:
expected: []string{"", "* potato", "+ orange", "= banana"}
actual : []string{"", "+ orange", "= banana", "= potato"}
Diff:
--- Expected
+++ Actual
@@ -2,5 +2,5 @@
(string) "",
- (string) (len=8) "* potato",
(string) (len=8) "+ orange",
- (string) (len=8) "= banana"
+ (string) (len=8) "= banana",
+ (string) (len=8) "= potato"
}
Test: TestCheckSumDownload
Messages: wrong combined result in run 6
check_test.go:384:
Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:384
/home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:420
/home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:428
Error: Not equal:
expected: []string{"", "banana"}
actual : []string{"", "banana", "potato"}
Diff:
--- Expected
+++ Actual
@@ -1,4 +1,5 @@
-([]string) (len=2) {
+([]string) (len=3) {
(string) "",
- (string) (len=6) "banana"
+ (string) (len=6) "banana",
+ (string) (len=6) "potato"
}
Test: TestCheckSumDownload
Messages: wrong match result in run 6
check_test.go:384:
Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:384
/home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:421
/home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:428
Error: Not equal:
expected: []string{"", "potato"}
actual : []string{}
Diff:
--- Expected
+++ Actual
@@ -1,4 +1,2 @@
-([]string) (len=2) {
- (string) "",
- (string) (len=6) "potato"
+([]string) {
}
Test: TestCheckSumDownload
Messages: wrong differ result in run 6
2026/02/02 03:31:36 DEBUG : data/banana: Removing old object on successful upload
2026/02/02 03:31:38 DEBUG : data/potato: Removing old object on successful upload
2026/02/02 03:31:39 DEBUG : test.sum: Removing old object on successful upload
2026/02/02 03:31:40 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/02/02 03:31:40 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/02/02 03:31:40 DEBUG : pacer: low level retry 2/10 (error Error "Server Error")
2026/02/02 03:31:40 DEBUG : pacer: Rate limited, increasing sleep to 40ms
2026/02/02 03:31:41 DEBUG : pacer: low level retry 3/10 (error Error "Server Error")
2026/02/02 03:31:41 DEBUG : pacer: Rate limited, increasing sleep to 80ms
2026/02/02 03:31:41 DEBUG : pacer: low level retry 4/10 (error Error "Server Error")
2026/02/02 03:31:41 DEBUG : pacer: Rate limited, increasing sleep to 160ms
2026/02/02 03:31:41 DEBUG : pacer: low level retry 5/10 (error Error "Server Error")
2026/02/02 03:31:41 DEBUG : pacer: Rate limited, increasing sleep to 320ms
2026/02/02 03:31:41 DEBUG : pacer: low level retry 6/10 (error Error "Server Error")
2026/02/02 03:31:41 DEBUG : pacer: Rate limited, increasing sleep to 640ms
2026/02/02 03:31:42 DEBUG : pacer: low level retry 7/10 (error Error "Server Error")
2026/02/02 03:31:42 DEBUG : pacer: Rate limited, increasing sleep to 1.28s
2026/02/02 03:31:42 DEBUG : pacer: low level retry 8/10 (error Error "Server Error")
2026/02/02 03:31:42 DEBUG : pacer: Rate limited, increasing sleep to 2.56s
2026/02/02 03:31:44 DEBUG : pacer: low level retry 9/10 (error Error "Server Error")
2026/02/02 03:31:44 DEBUG : pacer: Rate limited, increasing sleep to 5.12s
2026/02/02 03:31:46 DEBUG : pacer: low level retry 10/10 (error Error "Server Error")
2026/02/02 03:31:46 DEBUG : pacer: Rate limited, increasing sleep to 10.24s
run.go:286: Retry Put of "test.sum" to drime root 'rclone-test-bejazic8foyi': 1/10 (failed to delete existing object: failed to delete item: Error "Server Error")
2026/02/02 03:31:51 DEBUG : pacer: Reducing sleep to 5.12s
2026/02/02 03:32:02 DEBUG : pacer: Reducing sleep to 2.56s
2026/02/02 03:32:02 DEBUG : test.sum: Removing old object on successful upload
2026/02/02 03:32:07 DEBUG : pacer: Reducing sleep to 1.28s
2026/02/02 03:32:09 DEBUG : pacer: Reducing sleep to 640ms
2026/02/02 03:32:10 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 03:32:12 DEBUG : pacer: Reducing sleep to 160ms
2026/02/02 03:32:12 DEBUG : pacer: Reducing sleep to 80ms
2026/02/02 03:32:13 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 03:32:15 DEBUG : pacer: Reducing sleep to 20ms
2026/02/02 03:32:15 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:525
/home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:541
/usr/local/go/src/runtime/asm_amd64.s:1700
Error: Should be true
Test: TestCheckSumDownload
Messages: listing wrong, want
data/banana (13), data/potato (15), test.sum (82) got
data/banana (13), data/potato (15), test.sum (123), 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
2026/02/02 03:32:23 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/02/02 03:32:23 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/02/02 03:32:24 DEBUG : pacer: Reducing sleep to 10ms
=== 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 (129.41s)
--- PASS: TestCheckSumDownload/subtest1 (3.22s)
--- PASS: TestCheckSumDownload/subtest2 (1.41s)
--- PASS: TestCheckSumDownload/subtest3 (1.53s)
--- PASS: TestCheckSumDownload/subtest4 (1.47s)
--- PASS: TestCheckSumDownload/subtest5 (1.61s)
--- PASS: TestCheckSumDownload/subtest6 (1.12s)
--- PASS: TestCheckSumDownload/subtest7 (1.70s)
=== RUN TestApplyTransforms
2026/02/02 03:32:38 DEBUG : Creating backend with remote "TestDrime:rclone-test-vazurim6yufi"
2026/02/02 03:32:38 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/02/02 03:32:39 DEBUG : Creating backend with remote "/tmp/rclone1473579512"
run.go:185: Remote "drime root 'rclone-test-vazurim6yufi'", Local "Local file system at /tmp/rclone1473579512", Modify Window "876000h0m0s"
upper checkfile vs. lower remote (without normalization)
2026/02/02 03:32:43 ERROR : HELLO, WORLD!: file not in drime root 'rclone-test-vazurim6yufi'
2026/02/02 03:32:43 NOTICE: drime root 'rclone-test-vazurim6yufi': 1 files missing
2026/02/02 03:32:43 NOTICE: drime root 'rclone-test-vazurim6yufi': 0 differences found
2026/02/02 03:32:43 NOTICE: drime root 'rclone-test-vazurim6yufi': 1 errors while checking
upper checkfile vs. lower remote (with normalization)
2026/02/02 03:32:44 ERROR : hello, world!: file not in drime root 'rclone-test-vazurim6yufi'
2026/02/02 03:32:44 NOTICE: drime root 'rclone-test-vazurim6yufi': 1 files missing
2026/02/02 03:32:44 NOTICE: drime root 'rclone-test-vazurim6yufi': 0 differences found
2026/02/02 03:32:44 NOTICE: drime root 'rclone-test-vazurim6yufi': 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-vazurim6yufi'
Test: TestApplyTransforms
Messages: unexpected error for upper checkfile vs. lower remote (with normalization) HELLO, WORLD! hello, world!
2026/02/02 03:32:44 DEBUG : Creating backend with remote "TestDrime:rclone-test-ruwipod9diji"
2026/02/02 03:32:44 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/02/02 03:32:45 DEBUG : Creating backend with remote "/tmp/rclone1583624959"
run.go:185: Remote "drime root 'rclone-test-ruwipod9diji'", Local "Local file system at /tmp/rclone1583624959", Modify Window "876000h0m0s"
lower checkfile vs. upper remote (without normalization)
2026/02/02 03:32:49 ERROR : hello, world!: file not in drime root 'rclone-test-ruwipod9diji'
2026/02/02 03:32:49 NOTICE: drime root 'rclone-test-ruwipod9diji': 1 files missing
2026/02/02 03:32:49 NOTICE: drime root 'rclone-test-ruwipod9diji': 0 differences found
2026/02/02 03:32:49 NOTICE: drime root 'rclone-test-ruwipod9diji': 1 errors while checking
lower checkfile vs. upper remote (with normalization)
2026/02/02 03:32:49 ERROR : hello, world!: file not in drime root 'rclone-test-ruwipod9diji'
2026/02/02 03:32:49 NOTICE: drime root 'rclone-test-ruwipod9diji': 1 files missing
2026/02/02 03:32:49 NOTICE: drime root 'rclone-test-ruwipod9diji': 0 differences found
2026/02/02 03:32:49 NOTICE: drime root 'rclone-test-ruwipod9diji': 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-ruwipod9diji'
Test: TestApplyTransforms
Messages: unexpected error for lower checkfile vs. upper remote (with normalization) hello, world! HELLO, WORLD!
2026/02/02 03:32:49 DEBUG : Creating backend with remote "TestDrime:rclone-test-vofelag0boto"
2026/02/02 03:32:49 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/02/02 03:32:50 DEBUG : Creating backend with remote "/tmp/rclone865634576"
run.go:185: Remote "drime root 'rclone-test-vofelag0boto'", Local "Local file system at /tmp/rclone865634576", Modify Window "876000h0m0s"
lower checkfile vs. upperlowermixed remote (without normalization)
2026/02/02 03:32:53 ERROR : hello, world!: file not in drime root 'rclone-test-vofelag0boto'
2026/02/02 03:32:53 NOTICE: drime root 'rclone-test-vofelag0boto': 1 files missing
2026/02/02 03:32:53 NOTICE: drime root 'rclone-test-vofelag0boto': 0 differences found
2026/02/02 03:32:53 NOTICE: drime root 'rclone-test-vofelag0boto': 1 errors while checking
lower checkfile vs. upperlowermixed remote (with normalization)
2026/02/02 03:32:53 ERROR : hello, world!: file not in drime root 'rclone-test-vofelag0boto'
2026/02/02 03:32:53 NOTICE: drime root 'rclone-test-vofelag0boto': 1 files missing
2026/02/02 03:32:53 NOTICE: drime root 'rclone-test-vofelag0boto': 0 differences found
2026/02/02 03:32:53 NOTICE: drime root 'rclone-test-vofelag0boto': 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-vofelag0boto'
Test: TestApplyTransforms
Messages: unexpected error for lower checkfile vs. upperlowermixed remote (with normalization) hello, world! HeLlO, wOrLd!
2026/02/02 03:32:53 DEBUG : Creating backend with remote "TestDrime:rclone-test-xisavon7siba"
2026/02/02 03:32:53 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/02/02 03:32:54 DEBUG : Creating backend with remote "/tmp/rclone2996051864"
run.go:185: Remote "drime root 'rclone-test-xisavon7siba'", Local "Local file system at /tmp/rclone2996051864", Modify Window "876000h0m0s"
upperlowermixed checkfile vs. upper remote (without normalization)
2026/02/02 03:32:56 ERROR : HeLlO, wOrLd!: file not in drime root 'rclone-test-xisavon7siba'
2026/02/02 03:32:56 NOTICE: drime root 'rclone-test-xisavon7siba': 1 files missing
2026/02/02 03:32:56 NOTICE: drime root 'rclone-test-xisavon7siba': 0 differences found
2026/02/02 03:32:56 NOTICE: drime root 'rclone-test-xisavon7siba': 1 errors while checking
upperlowermixed checkfile vs. upper remote (with normalization)
2026/02/02 03:32:56 ERROR : hello, world!: file not in drime root 'rclone-test-xisavon7siba'
2026/02/02 03:32:56 NOTICE: drime root 'rclone-test-xisavon7siba': 1 files missing
2026/02/02 03:32:56 NOTICE: drime root 'rclone-test-xisavon7siba': 0 differences found
2026/02/02 03:32:56 NOTICE: drime root 'rclone-test-xisavon7siba': 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-xisavon7siba'
Test: TestApplyTransforms
Messages: unexpected error for upperlowermixed checkfile vs. upper remote (with normalization) HeLlO, wOrLd! HELLO, WORLD!
2026/02/02 03:32:56 DEBUG : Creating backend with remote "TestDrime:rclone-test-holetaw3qoba"
2026/02/02 03:32:56 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/02/02 03:32:57 DEBUG : Creating backend with remote "/tmp/rclone446154055"
run.go:185: Remote "drime root 'rclone-test-holetaw3qoba'", Local "Local file system at /tmp/rclone446154055", Modify Window "876000h0m0s"
NFD checkfile vs. NFC remote (without normalization)
2026/02/02 03:33:00 ERROR : 測試_Русский___ě_áñ: file not in drime root 'rclone-test-holetaw3qoba'
2026/02/02 03:33:00 NOTICE: drime root 'rclone-test-holetaw3qoba': 1 files missing
2026/02/02 03:33:00 NOTICE: drime root 'rclone-test-holetaw3qoba': 0 differences found
2026/02/02 03:33:00 NOTICE: drime root 'rclone-test-holetaw3qoba': 1 errors while checking
NFD checkfile vs. NFC remote (with normalization)
2026/02/02 03:33:01 ERROR : 測試_русский___ě_áñ: file not in drime root 'rclone-test-holetaw3qoba'
2026/02/02 03:33:01 NOTICE: drime root 'rclone-test-holetaw3qoba': 1 files missing
2026/02/02 03:33:01 NOTICE: drime root 'rclone-test-holetaw3qoba': 0 differences found
2026/02/02 03:33:01 NOTICE: drime root 'rclone-test-holetaw3qoba': 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-holetaw3qoba'
Test: TestApplyTransforms
Messages: unexpected error for NFD checkfile vs. NFC remote (with normalization) 測試_Русский___ě_áñ 測試_Русский___ě_áñ
2026/02/02 03:33:01 DEBUG : Creating backend with remote "TestDrime:rclone-test-nolalal9xoqa"
2026/02/02 03:33:01 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/02/02 03:33:02 DEBUG : Creating backend with remote "/tmp/rclone162161008"
run.go:185: Remote "drime root 'rclone-test-nolalal9xoqa'", Local "Local file system at /tmp/rclone162161008", Modify Window "876000h0m0s"
NFC checkfile vs. NFD remote (without normalization)
2026/02/02 03:33:04 ERROR : 測試_Русский___ě_áñ: file not in drime root 'rclone-test-nolalal9xoqa'
2026/02/02 03:33:04 NOTICE: drime root 'rclone-test-nolalal9xoqa': 1 files missing
2026/02/02 03:33:04 NOTICE: drime root 'rclone-test-nolalal9xoqa': 0 differences found
2026/02/02 03:33:04 NOTICE: drime root 'rclone-test-nolalal9xoqa': 1 errors while checking
NFC checkfile vs. NFD remote (with normalization)
2026/02/02 03:33:05 ERROR : 測試_русский___ě_áñ: file not in drime root 'rclone-test-nolalal9xoqa'
2026/02/02 03:33:05 NOTICE: drime root 'rclone-test-nolalal9xoqa': 1 files missing
2026/02/02 03:33:05 NOTICE: drime root 'rclone-test-nolalal9xoqa': 0 differences found
2026/02/02 03:33:05 NOTICE: drime root 'rclone-test-nolalal9xoqa': 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-nolalal9xoqa'
Test: TestApplyTransforms
Messages: unexpected error for NFC checkfile vs. NFD remote (with normalization) 測試_Русский___ě_áñ 測試_Русский___ě_áñ
2026/02/02 03:33:05 DEBUG : Creating backend with remote "TestDrime:rclone-test-jukaran3vole"
2026/02/02 03:33:05 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/02/02 03:33:05 DEBUG : Creating backend with remote "/tmp/rclone1279855865"
run.go:185: Remote "drime root 'rclone-test-jukaran3vole'", Local "Local file system at /tmp/rclone1279855865", Modify Window "876000h0m0s"
NFDx2 checkfile vs. both remote (without normalization)
2026/02/02 03:33:08 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in drime root 'rclone-test-jukaran3vole'
2026/02/02 03:33:08 NOTICE: drime root 'rclone-test-jukaran3vole': 1 files missing
2026/02/02 03:33:08 NOTICE: drime root 'rclone-test-jukaran3vole': 0 differences found
2026/02/02 03:33:08 NOTICE: drime root 'rclone-test-jukaran3vole': 1 errors while checking
NFDx2 checkfile vs. both remote (with normalization)
2026/02/02 03:33:08 ERROR : 測試_русский___ě_áñ測試_русский___ě_áñ: file not in drime root 'rclone-test-jukaran3vole'
2026/02/02 03:33:08 NOTICE: drime root 'rclone-test-jukaran3vole': 1 files missing
2026/02/02 03:33:08 NOTICE: drime root 'rclone-test-jukaran3vole': 0 differences found
2026/02/02 03:33:08 NOTICE: drime root 'rclone-test-jukaran3vole': 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-jukaran3vole'
Test: TestApplyTransforms
Messages: unexpected error for NFDx2 checkfile vs. both remote (with normalization) 測試_Русский___ě_áñ測試_Русский___ě_áñ 測試_Русский___ě_áñ測試_Русский___ě_áñ
2026/02/02 03:33:08 DEBUG : Creating backend with remote "TestDrime:rclone-test-husexol6nima"
2026/02/02 03:33:08 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/02/02 03:33:08 DEBUG : Creating backend with remote "/tmp/rclone3195520432"
run.go:185: Remote "drime root 'rclone-test-husexol6nima'", Local "Local file system at /tmp/rclone3195520432", Modify Window "876000h0m0s"
NFCx2 checkfile vs. both remote (without normalization)
2026/02/02 03:33:11 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in drime root 'rclone-test-husexol6nima'
2026/02/02 03:33:11 NOTICE: drime root 'rclone-test-husexol6nima': 1 files missing
2026/02/02 03:33:11 NOTICE: drime root 'rclone-test-husexol6nima': 0 differences found
2026/02/02 03:33:11 NOTICE: drime root 'rclone-test-husexol6nima': 1 errors while checking
NFCx2 checkfile vs. both remote (with normalization)
2026/02/02 03:33:11 ERROR : 測試_русский___ě_áñ測試_русский___ě_áñ: file not in drime root 'rclone-test-husexol6nima'
2026/02/02 03:33:11 NOTICE: drime root 'rclone-test-husexol6nima': 1 files missing
2026/02/02 03:33:11 NOTICE: drime root 'rclone-test-husexol6nima': 0 differences found
2026/02/02 03:33:11 NOTICE: drime root 'rclone-test-husexol6nima': 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-husexol6nima'
Test: TestApplyTransforms
Messages: unexpected error for NFCx2 checkfile vs. both remote (with normalization) 測試_Русский___ě_áñ測試_Русский___ě_áñ 測試_Русский___ě_áñ測試_Русский___ě_áñ
2026/02/02 03:33:11 DEBUG : Creating backend with remote "TestDrime:rclone-test-liyusuy5fugi"
2026/02/02 03:33:11 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/02/02 03:33:12 DEBUG : Creating backend with remote "/tmp/rclone1655827691"
run.go:185: Remote "drime root 'rclone-test-liyusuy5fugi'", Local "Local file system at /tmp/rclone1655827691", Modify Window "876000h0m0s"
both checkfile vs. NFDx2 remote (without normalization)
2026/02/02 03:33:15 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in drime root 'rclone-test-liyusuy5fugi'
2026/02/02 03:33:15 NOTICE: drime root 'rclone-test-liyusuy5fugi': 1 files missing
2026/02/02 03:33:15 NOTICE: drime root 'rclone-test-liyusuy5fugi': 0 differences found
2026/02/02 03:33:15 NOTICE: drime root 'rclone-test-liyusuy5fugi': 1 errors while checking
both checkfile vs. NFDx2 remote (with normalization)
2026/02/02 03:33:15 ERROR : 測試_русский___ě_áñ測試_русский___ě_áñ: file not in drime root 'rclone-test-liyusuy5fugi'
2026/02/02 03:33:15 NOTICE: drime root 'rclone-test-liyusuy5fugi': 1 files missing
2026/02/02 03:33:15 NOTICE: drime root 'rclone-test-liyusuy5fugi': 0 differences found
2026/02/02 03:33:15 NOTICE: drime root 'rclone-test-liyusuy5fugi': 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-liyusuy5fugi'
Test: TestApplyTransforms
Messages: unexpected error for both checkfile vs. NFDx2 remote (with normalization) 測試_Русский___ě_áñ測試_Русский___ě_áñ 測試_Русский___ě_áñ測試_Русский___ě_áñ
2026/02/02 03:33:15 DEBUG : Creating backend with remote "TestDrime:rclone-test-hafomij3hufi"
2026/02/02 03:33:15 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/02/02 03:33:16 DEBUG : Creating backend with remote "/tmp/rclone1373150863"
run.go:185: Remote "drime root 'rclone-test-hafomij3hufi'", Local "Local file system at /tmp/rclone1373150863", Modify Window "876000h0m0s"
both checkfile vs. NFCx2 remote (without normalization)
2026/02/02 03:33:19 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in drime root 'rclone-test-hafomij3hufi'
2026/02/02 03:33:19 NOTICE: drime root 'rclone-test-hafomij3hufi': 1 files missing
2026/02/02 03:33:19 NOTICE: drime root 'rclone-test-hafomij3hufi': 0 differences found
2026/02/02 03:33:19 NOTICE: drime root 'rclone-test-hafomij3hufi': 1 errors while checking
both checkfile vs. NFCx2 remote (with normalization)
2026/02/02 03:33:19 ERROR : 測試_русский___ě_áñ測試_русский___ě_áñ: file not in drime root 'rclone-test-hafomij3hufi'
2026/02/02 03:33:19 NOTICE: drime root 'rclone-test-hafomij3hufi': 1 files missing
2026/02/02 03:33:19 NOTICE: drime root 'rclone-test-hafomij3hufi': 0 differences found
2026/02/02 03:33:19 NOTICE: drime root 'rclone-test-hafomij3hufi': 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-hafomij3hufi'
Test: TestApplyTransforms
Messages: unexpected error for both checkfile vs. NFCx2 remote (with normalization) 測試_Русский___ě_áñ測試_Русский___ě_áñ 測試_Русский___ě_áñ測試_Русский___ě_áñ
2026/02/02 03:33:19 DEBUG : drime root 'rclone-test-hafomij3hufi': Purge remote
2026/02/02 03:33:19 DEBUG : drime root 'rclone-test-liyusuy5fugi': Purge remote
2026/02/02 03:33:20 DEBUG : drime root 'rclone-test-husexol6nima': Purge remote
2026/02/02 03:33:20 DEBUG : drime root 'rclone-test-jukaran3vole': Purge remote
2026/02/02 03:33:21 DEBUG : drime root 'rclone-test-nolalal9xoqa': Purge remote
2026/02/02 03:33:21 DEBUG : drime root 'rclone-test-holetaw3qoba': Purge remote
2026/02/02 03:33:21 DEBUG : drime root 'rclone-test-xisavon7siba': Purge remote
2026/02/02 03:33:22 DEBUG : drime root 'rclone-test-vofelag0boto': Purge remote
2026/02/02 03:33:22 DEBUG : drime root 'rclone-test-ruwipod9diji': Purge remote
2026/02/02 03:33:22 DEBUG : drime root 'rclone-test-vazurim6yufi': Purge remote
--- FAIL: TestApplyTransforms (44.26s)
=== RUN TestCopyFile
run.go:185: Remote "drime root 'rclone-test-bejazic8foyi'", Local "Local file system at /tmp/rclone4034720377", Modify Window "876000h0m0s"
2026/02/02 03:33:23 DEBUG : file1: Need to transfer - File not found at Destination
2026/02/02 03:33:25 DEBUG : sub/file2: size = 14 OK
2026/02/02 03:33:25 INFO : file1: Copied (new) to: sub/file2
2026/02/02 03:33:26 DEBUG : sub/file2: size = 14 OK
2026/02/02 03:33:26 DEBUG : file1: Sizes identical
2026/02/02 03:33:26 DEBUG : file1: Unchanged skipping
2026/02/02 03:33:26 DEBUG : drime root 'rclone-test-bejazic8foyi': 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 (20.43s)
=== RUN TestCopyLongFile
run.go:185: Remote "drime root 'rclone-test-bejazic8foyi'", Local "Local file system at /tmp/rclone4034720377", 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:292: Sleeping for 6s just to make sure
--- SKIP: TestCopyLongFile (10.46s)
=== RUN TestCopyFileCompareDest
run.go:185: Remote "drime root 'rclone-test-bejazic8foyi'", Local "Local file system at /tmp/rclone4034720377", Modify Window "876000h0m0s"
2026/02/02 03:33:53 DEBUG : Creating backend with remote "TestDrime:rclone-test-bejazic8foyi/dst"
2026/02/02 03:33:55 DEBUG : Creating backend with remote "TestDrime:rclone-test-bejazic8foyi/CompareDest"
2026/02/02 03:33:56 DEBUG : one: Need to transfer - File not found at Destination
2026/02/02 03:33:59 DEBUG : one: size = 3 OK
2026/02/02 03:33:59 INFO : one: Copied (new)
2026/02/02 03:34:00 DEBUG : one: size = 5 (Local file system at /tmp/rclone4034720377)
2026/02/02 03:34:00 DEBUG : one: size = 3 (drime root 'rclone-test-bejazic8foyi/dst')
2026/02/02 03:34:00 DEBUG : one: Sizes differ
2026/02/02 03:34:01 DEBUG : one: Removing old object on successful upload
2026/02/02 03:34:01 DEBUG : one: size = 5 OK
2026/02/02 03:34:01 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:225
Error: Should be true
Test: TestCopyFileCompareDest
Messages: listing wrong, want
dst/one (5) got
dst/one (3)
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:225
Error: Not equal:
expected: 5
actual : 3
Test: TestCopyFileCompareDest
Messages: dst/one: size incorrect file=5 vs obj=3
2026/02/02 03:34:14 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: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:231
Error: Should be true
Test: TestCopyFileCompareDest
Messages: listing wrong, want
CompareDest/one (5), dst/one (3) got
CompareDest/one (5), 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:231
Error: Not equal:
expected: 3
actual : 5
Test: TestCopyFileCompareDest
Messages: dst/one: size incorrect file=3 vs obj=5
2026/02/02 03:34:27 DEBUG : one: size = 5 (Local file system at /tmp/rclone4034720377)
2026/02/02 03:34:27 DEBUG : one: size = 3 (drime root 'rclone-test-bejazic8foyi/dst')
2026/02/02 03:34:27 DEBUG : one: Sizes differ
2026/02/02 03:34:27 DEBUG : one: size = 5 OK
2026/02/02 03:34:27 DEBUG : one: Sizes identical
2026/02/02 03:34:27 DEBUG : one: 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: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 03:34:39 DEBUG : two: Need to transfer - File not found at Destination
2026/02/02 03:34:40 DEBUG : two: size = 3 OK
2026/02/02 03:34:40 DEBUG : two: Sizes identical
2026/02/02 03:34:40 DEBUG : two: Destination found in --compare-dest, skipping
2026/02/02 03:34:41 DEBUG : two: Need to transfer - File not found at Destination
2026/02/02 03:34:41 DEBUG : two: size = 3 OK
2026/02/02 03:34:41 DEBUG : two: Sizes identical
2026/02/02 03:34:41 DEBUG : two: Destination found in --compare-dest, skipping
2026/02/02 03:34:44 DEBUG : two: Need to transfer - File not found at Destination
2026/02/02 03:34:44 DEBUG : two: size = 5 (Local file system at /tmp/rclone4034720377)
2026/02/02 03:34:44 DEBUG : two: size = 3 (drime root 'rclone-test-bejazic8foyi/CompareDest')
2026/02/02 03:34:44 DEBUG : two: Sizes differ
2026/02/02 03:34:46 DEBUG : two: size = 5 OK
2026/02/02 03:34:46 INFO : two: Copied (new)
fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3
fstest.go:301: Flushing the directory cache
fstest.go:292: Sleeping for 6s just to make sure
run.go:130: removing dir "dst" failed - try 1/3: directory not empty
run.go:130: removing dir "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"}
Diff:
--- Expected
+++ Actual
@@ -1,2 +1,3 @@
-([]string) {
+([]string) (len=1) {
+ (string) (len=11) "CompareDest"
}
Test: TestCopyFileCompareDest
Messages: directories
--- FAIL: TestCopyFileCompareDest (81.44s)
=== RUN TestCopyFileCopyDest
run.go:185: Remote "drime root 'rclone-test-bejazic8foyi'", Local "Local file system at /tmp/rclone4034720377", Modify Window "876000h0m0s"
2026/02/02 03:35:15 DEBUG : Creating backend with remote "TestDrime:rclone-test-bejazic8foyi/dst"
2026/02/02 03:35:16 DEBUG : Creating backend with remote "TestDrime:rclone-test-bejazic8foyi/CopyDest"
2026/02/02 03:35:18 DEBUG : one: Need to transfer - File not found at Destination
2026/02/02 03:35:20 DEBUG : one: size = 3 OK
2026/02/02 03:35:20 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: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:295
Error: Should be true
Test: TestCopyFileCopyDest
Messages: listing wrong, want
dst/one (3) got
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:295
/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 03:35:33 DEBUG : one: size = 5 (Local file system at /tmp/rclone4034720377)
2026/02/02 03:35:33 DEBUG : one: size = 3 (drime root 'rclone-test-bejazic8foyi/dst')
2026/02/02 03:35:33 DEBUG : one: Sizes differ
2026/02/02 03:35:35 DEBUG : one: Removing old object on successful upload
2026/02/02 03:35:35 DEBUG : one: size = 5 OK
2026/02/02 03:35:35 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:308
Error: Should be true
Test: TestCopyFileCopyDest
Messages: listing wrong, want
dst/one (5) got
dst/one (3)
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:308
Error: Not equal:
expected: 5
actual : 3
Test: TestCopyFileCopyDest
Messages: dst/one: size incorrect file=5 vs obj=3
2026/02/02 03:35:49 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 03:36:05 DEBUG : Creating backend with remote "TestDrime:rclone-test-bejazic8foyi/BackupDir"
2026/02/02 03:36:08 DEBUG : one: size = 5 (Local file system at /tmp/rclone4034720377)
2026/02/02 03:36:08 DEBUG : one: size = 3 (drime root 'rclone-test-bejazic8foyi/dst')
2026/02/02 03:36:08 DEBUG : one: Sizes differ
2026/02/02 03:36:08 DEBUG : one: size = 5 OK
2026/02/02 03:36:08 DEBUG : one: Sizes identical
2026/02/02 03:36:08 DEBUG : one: size = 5 (Local file system at /tmp/rclone4034720377)
2026/02/02 03:36:08 DEBUG : one: size = 3 (drime root 'rclone-test-bejazic8foyi/dst')
2026/02/02 03:36:08 DEBUG : one: Sizes differ
2026/02/02 03:36:11 INFO : one: Moved (server-side)
2026/02/02 03:36:12 DEBUG : one: size = 5 OK
2026/02/02 03:36:12 INFO : one: Copied (server-side copy)
2026/02/02 03:36:12 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
CopyDest/one (5)
fstest.go:203: Not found "dst/one"
fstest.go:203: Not found "BackupDir/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 : 2
Test: TestCopyFileCopyDest
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/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:333
Error: Should be true
Test: TestCopyFileCopyDest
Messages: listing wrong, want
BackupDir/one (3), CopyDest/one (5), CopyDest/two (3), dst/one (5) got
BackupDir/one (3), CopyDest/one (5), dst/one (5)
fstest.go:203: Not found "CopyDest/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:333
/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 03:36:35 DEBUG : two: Need to transfer - File not found at Destination
2026/02/02 03:36:35 DEBUG : two: size = 3 OK
2026/02/02 03:36:35 DEBUG : two: Sizes identical
2026/02/02 03:36:36 DEBUG : two: size = 3 OK
2026/02/02 03:36:36 INFO : two: Copied (server-side copy)
2026/02/02 03:36:36 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 03:36:47 DEBUG : two: size = 3 OK
2026/02/02 03:36:47 DEBUG : two: Sizes identical
2026/02/02 03:36:47 DEBUG : two: Unchanged skipping
fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3
fstest.go:301: Flushing the directory cache
fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3
fstest.go:301: Flushing the directory cache
fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3
fstest.go:301: Flushing the directory cache
fstest.go:305:
Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350
/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345
/home/rclone/go/src/github.com/rclone/rclone/fs/operations/copy_test.go:353
Error: Should be true
Test: TestCopyFileCopyDest
Messages: listing wrong, want
BackupDir/one (3), CopyDest/one (5), CopyDest/three (5), CopyDest/two (3), dst/one (5), dst/two (3) got
BackupDir/one (3), CopyDest/one (5), CopyDest/two (3), dst/one (5), dst/two (3)
fstest.go:203: Not found "CopyDest/three"
fstest.go:206:
Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:206
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:310
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350
/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345
/home/rclone/go/src/github.com/rclone/rclone/fs/operations/copy_test.go:353
/usr/local/go/src/runtime/asm_amd64.s:1700
Error: Not equal:
expected: 0
actual : 1
Test: TestCopyFileCopyDest
Messages: 1 objects not found
2026/02/02 03:37:00 DEBUG : three: Need to transfer - File not found at Destination
2026/02/02 03:37:00 DEBUG : three: size = 7 (Local file system at /tmp/rclone4034720377)
2026/02/02 03:37:00 DEBUG : three: size = 5 (drime root 'rclone-test-bejazic8foyi/CopyDest')
2026/02/02 03:37:00 DEBUG : three: Sizes differ
2026/02/02 03:37:00 DEBUG : three: Destination not found in --copy-dest
2026/02/02 03:37:01 DEBUG : three: size = 7 OK
2026/02/02 03:37:01 INFO : three: Copied (new)
fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3
fstest.go:301: Flushing the directory cache
fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3
fstest.go:301: Flushing the directory cache
fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3
fstest.go:301: Flushing the directory cache
fstest.go:305:
Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350
/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345
/home/rclone/go/src/github.com/rclone/rclone/fs/operations/copy_test.go:362
Error: Should be true
Test: TestCopyFileCopyDest
Messages: listing wrong, want
BackupDir/one (3), CopyDest/one (5), CopyDest/three (5), CopyDest/two (3), dst/one (5), dst/three (7), dst/two (3) got
BackupDir/one (3), CopyDest/one (5), CopyDest/three (5), CopyDest/two (3), dst/one (5), dst/two (3)
fstest.go:203: Not found "dst/three"
fstest.go:206:
Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:206
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:310
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350
/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345
/home/rclone/go/src/github.com/rclone/rclone/fs/operations/copy_test.go:362
/usr/local/go/src/runtime/asm_amd64.s:1700
Error: Not equal:
expected: 0
actual : 1
Test: TestCopyFileCopyDest
Messages: 1 objects not found
run.go:130: removing dir "dst" failed - try 1/3: directory not empty
run.go:130: removing dir "dst" failed - try 2/3: directory not empty
run.go:130: removing dir "dst" failed - try 3/3: directory not empty
run.go:133: removing dir "dst" failed: directory not empty
run.go:130: removing dir "CopyDest" failed - try 1/3: directory not empty
run.go:130: removing dir "CopyDest" failed - try 2/3: directory not empty
run.go:130: removing dir "CopyDest" failed - try 3/3: directory not empty
run.go:133: removing dir "CopyDest" failed: directory not empty
fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3
fstest.go:301: Flushing the directory cache
fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3
fstest.go:301: Flushing the directory cache
fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3
fstest.go:301: Flushing the directory cache
fstest.go:327:
Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:327
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338
/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178
/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393
/usr/local/go/src/testing/testing.go:1211
/usr/local/go/src/testing/testing.go:1445
/usr/local/go/src/testing/testing.go:1786
Error: Not equal:
expected: []string{}
actual : []string{"CopyDest", "dst"}
Diff:
--- Expected
+++ Actual
@@ -1,2 +1,4 @@
-([]string) {
+([]string) (len=2) {
+ (string) (len=8) "CopyDest",
+ (string) (len=3) "dst"
}
Test: TestCopyFileCopyDest
Messages: directories
--- FAIL: TestCopyFileCopyDest (138.45s)
=== RUN TestCopyInplace
run.go:185: Remote "drime root 'rclone-test-bejazic8foyi'", Local "Local file system at /tmp/rclone4034720377", 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", "dst"}
Diff:
--- Expected
+++ Actual
@@ -1,2 +1,4 @@
-([]string) {
+([]string) (len=2) {
+ (string) (len=8) "CopyDest",
+ (string) (len=3) "dst"
}
Test: TestCopyInplace
Messages: directories
--- FAIL: TestCopyInplace (13.21s)
=== RUN TestCopyFileMaxTransfer
run.go:185: Remote "drime root 'rclone-test-bejazic8foyi'", Local "Local file system at /tmp/rclone4034720377", Modify Window "876000h0m0s"
2026/02/02 03:37:47 DEBUG : TestCopyFileMaxTransfer/file1: Need to transfer - File not found at Destination
2026/02/02 03:37:49 DEBUG : TestCopyFileMaxTransfer/file1: size = 14 OK
2026/02/02 03:37:49 INFO : TestCopyFileMaxTransfer/file1: Copied (new)
2026/02/02 03:37:51 DEBUG : TestCopyFileMaxTransfer/file2: Need to transfer - File not found at Destination
2026/02/02 03:37:51 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 03:37:52 DEBUG : TestCopyFileMaxTransfer/file3: Need to transfer - File not found at Destination
2026/02/02 03:37:53 DEBUG : TestCopyFileMaxTransfer/file4: Need to transfer - File not found at Destination
2026/02/02 03:37:55 DEBUG : TestCopyFileMaxTransfer/file4: size = 2062 OK
2026/02/02 03:37:55 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
run.go:130: removing dir "TestCopyFileMaxTransfer" failed - try 2/3: directory not empty
--- PASS: TestCopyFileMaxTransfer (26.52s)
=== RUN TestListDirSortedFn
run.go:185: Remote "drime root 'rclone-test-bejazic8foyi'", Local "Local file system at /tmp/rclone4034720377", Modify Window "876000h0m0s"
fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3
fstest.go:301: Flushing the directory cache
fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3
fstest.go:301: Flushing the directory cache
fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3
fstest.go:301: Flushing the directory cache
fstest.go:305:
Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350
/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345
/home/rclone/go/src/github.com/rclone/rclone/fs/operations/listdirsorted_test.go:36
/home/rclone/go/src/github.com/rclone/rclone/fs/operations/listdirsorted_test.go:125
/usr/local/go/src/runtime/asm_amd64.s:1700
Error: Should be true
Test: TestListDirSortedFn
Messages: listing wrong, want
a.txt (11), sub dir/hello world (11), sub dir/hello world2 (11), sub dir/ignore dir/.ignore (1), sub dir/ignore dir/should be ignored (9), sub dir/sub sub dir/hello world3 (11), zend.txt (5) got
a.txt (11), sub dir/hello world (11), sub dir/hello world2 (11), sub dir/ignore dir/.ignore (1), sub dir/sub sub dir/hello world3 (11), zend.txt (5)
fstest.go:203: Not found "sub dir/ignore dir/should be ignored"
fstest.go:206:
Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:206
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:310
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350
/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345
/home/rclone/go/src/github.com/rclone/rclone/fs/operations/listdirsorted_test.go:36
/home/rclone/go/src/github.com/rclone/rclone/fs/operations/listdirsorted_test.go:125
Error: Not equal:
expected: 0
actual : 1
Test: TestListDirSortedFn
Messages: 1 objects not found
2026/02/02 03:38:37 DEBUG : a.txt: Excluded (Size Filter)
2026/02/02 03:38:37 DEBUG : a.txt: Excluded
2026/02/02 03:38:37 DEBUG : sub dir/hello world2: Excluded (Size Filter)
2026/02/02 03:38:37 DEBUG : sub dir/hello world2: Excluded
2026/02/02 03:38:37 DEBUG : sub dir/hello world: Excluded (Size Filter)
2026/02/02 03:38:37 DEBUG : sub dir/hello world: Excluded
2026/02/02 03:38:38 DEBUG : sub dir/ignore dir: Excluded
2026/02/02 03:38:38 DEBUG : sub dir/hello world2: Excluded (Size Filter)
2026/02/02 03:38:38 DEBUG : sub dir/hello world2: Excluded
2026/02/02 03:38:38 DEBUG : sub dir/hello world: Excluded (Size Filter)
2026/02/02 03:38:38 DEBUG : sub dir/hello world: Excluded
2026/02/02 03:38:38 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 (52.02s)
=== RUN TestListJSON
run.go:185: Remote "drime root 'rclone-test-bejazic8foyi'", Local "Local file system at /tmp/rclone4034720377", 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/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/Metadata
lsjson_test.go:202:
Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/lsjson_test.go:202
Error: Not equal:
expected: 2
actual : 3
Test: TestListJSON/Metadata
Messages: Wrong number of results
=== NAME TestListJSON
run.go:130: removing dir "sub dir" failed - try 1/3: directory not empty
run.go:130: removing dir "sub dir" failed - try 2/3: directory not empty
run.go:130: removing dir "sub dir" failed - try 3/3: directory not empty
run.go:133: removing dir "sub dir" failed: directory not empty
run.go:130: removing dir "sub" failed - try 1/3: directory not empty
run.go:130: removing dir "sub" failed - try 2/3: directory not empty
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: TestListJSON
Messages: directories
--- FAIL: TestListJSON (36.63s)
--- FAIL: TestListJSON/Default (0.40s)
--- FAIL: TestListJSON/DirsOnly (0.51s)
--- FAIL: TestListJSON/Recurse (1.05s)
--- FAIL: TestListJSON/Metadata (0.21s)
=== RUN TestStatJSON
run.go:185: Remote "drime root 'rclone-test-bejazic8foyi'", Local "Local file system at /tmp/rclone4034720377", Modify Window "876000h0m0s"
fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3
fstest.go:301: Flushing the directory cache
fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3
fstest.go:301: Flushing the directory cache
fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3
fstest.go:301: Flushing the directory cache
fstest.go:305:
Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350
/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345
/home/rclone/go/src/github.com/rclone/rclone/fs/operations/lsjson_test.go:263
Error: Should be true
Test: TestStatJSON
Messages: listing wrong, want
file1 (5), sub/file2 (9) got
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:263
/usr/local/go/src/runtime/asm_amd64.s:1700
Error: Not equal:
expected: 0
actual : 2
Test: TestStatJSON
Messages: 2 objects not found
=== RUN TestStatJSON/Root
=== RUN TestStatJSON/RootFilesOnly
=== RUN TestStatJSON/RootDirsOnly
=== RUN TestStatJSON/Dir
=== RUN TestStatJSON/DirWithTrailingSlash
=== RUN TestStatJSON/File
=== RUN TestStatJSON/NotFound
=== RUN TestStatJSON/DirFilesOnly
=== RUN TestStatJSON/FileFilesOnly
=== RUN TestStatJSON/NotFoundFilesOnly
=== RUN TestStatJSON/DirDirsOnly
=== RUN TestStatJSON/FileDirsOnly
=== RUN TestStatJSON/NotFoundDirsOnly
=== RUN TestStatJSON/RootNotFound
2026/02/02 03:40:01 DEBUG : Creating backend with remote "TestDrime:rclone-test-bejazic8foyi/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 (38.84s)
--- PASS: TestStatJSON/Root (0.98s)
--- PASS: TestStatJSON/RootFilesOnly (0.00s)
--- PASS: TestStatJSON/RootDirsOnly (0.29s)
--- PASS: TestStatJSON/Dir (0.40s)
--- PASS: TestStatJSON/DirWithTrailingSlash (0.42s)
--- PASS: TestStatJSON/File (0.68s)
--- PASS: TestStatJSON/NotFound (0.67s)
--- PASS: TestStatJSON/DirFilesOnly (0.36s)
--- PASS: TestStatJSON/FileFilesOnly (0.60s)
--- PASS: TestStatJSON/NotFoundFilesOnly (0.28s)
--- PASS: TestStatJSON/DirDirsOnly (0.20s)
--- PASS: TestStatJSON/FileDirsOnly (0.18s)
--- PASS: TestStatJSON/NotFoundDirsOnly (0.17s)
--- PASS: TestStatJSON/RootNotFound (1.75s)
=== RUN TestMkdir
run.go:185: Remote "drime root 'rclone-test-bejazic8foyi'", Local "Local file system at /tmp/rclone4034720377", Modify Window "876000h0m0s"
2026/02/02 03:40:20 INFO : drime root 'rclone-test-bejazic8foyi': Making directory
2026/02/02 03:40:21 INFO : drime root 'rclone-test-bejazic8foyi': 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 (12.53s)
=== RUN TestLsd
run.go:185: Remote "drime root 'rclone-test-bejazic8foyi'", Local "Local file system at /tmp/rclone4034720377", 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 (19.38s)
=== RUN TestLsWithFilesFrom
run.go:185: Remote "drime root 'rclone-test-bejazic8foyi'", Local "Local file system at /tmp/rclone4034720377", Modify Window "876000h0m0s"
fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3
fstest.go:301: Flushing the directory cache
fstest.go:292: Sleeping for 6s just to make sure
2026/02/02 03:41:05 DEBUG : sub dir: Excluded
2026/02/02 03:41:05 DEBUG : empty space: Excluded (FilesFrom Filter)
2026/02/02 03:41:05 DEBUG : empty space: Excluded
--- PASS: TestLsWithFilesFrom (15.88s)
=== RUN TestLsLong
run.go:185: Remote "drime root 'rclone-test-bejazic8foyi'", Local "Local file system at /tmp/rclone4034720377", 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 (22.26s)
=== RUN TestHashSums
run.go:185: Remote "drime root 'rclone-test-bejazic8foyi'", Local "Local file system at /tmp/rclone4034720377", 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 (21.78s)
=== RUN TestMaxDelete
run.go:185: Remote "drime root 'rclone-test-bejazic8foyi'", Local "Local file system at /tmp/rclone4034720377", 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
small (10)
fstest.go:203: Not found "medium"
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 : 2
Test: TestMaxDelete
Messages: 2 objects not found
2026/02/02 03:42:05 DEBUG : Waiting for deletions to finish
2026/02/02 03:42:06 ERROR : small: Got fatal error on delete: --max-delete threshold reached
2026/02/02 03:42:06 INFO : large: Deleted
2026/02/02 03:42:06 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 03:42:07 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/02/02 03:42:07 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/02/02 03:42:08 DEBUG : pacer: low level retry 2/10 (error Error "Server Error")
2026/02/02 03:42:08 DEBUG : pacer: Rate limited, increasing sleep to 40ms
2026/02/02 03:42:08 DEBUG : pacer: low level retry 3/10 (error Error "Server Error")
2026/02/02 03:42:08 DEBUG : pacer: Rate limited, increasing sleep to 80ms
2026/02/02 03:42:08 DEBUG : pacer: low level retry 4/10 (error Error "Server Error")
2026/02/02 03:42:08 DEBUG : pacer: Rate limited, increasing sleep to 160ms
2026/02/02 03:42:09 DEBUG : pacer: low level retry 5/10 (error Error "Server Error")
2026/02/02 03:42:09 DEBUG : pacer: Rate limited, increasing sleep to 320ms
2026/02/02 03:42:09 DEBUG : pacer: low level retry 6/10 (error Error "Server Error")
2026/02/02 03:42:09 DEBUG : pacer: Rate limited, increasing sleep to 640ms
2026/02/02 03:42:10 DEBUG : pacer: low level retry 7/10 (error Error "Server Error")
2026/02/02 03:42:10 DEBUG : pacer: Rate limited, increasing sleep to 1.28s
2026/02/02 03:42:10 DEBUG : pacer: low level retry 8/10 (error Error "Server Error")
2026/02/02 03:42:10 DEBUG : pacer: Rate limited, increasing sleep to 2.56s
2026/02/02 03:42:11 DEBUG : pacer: low level retry 9/10 (error Error "Server Error")
2026/02/02 03:42:11 DEBUG : pacer: Rate limited, increasing sleep to 5.12s
2026/02/02 03:42:14 DEBUG : pacer: low level retry 10/10 (error Error "Server Error")
2026/02/02 03:42:14 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 03:42:19 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/02/02 03:42:19 DEBUG : pacer: Rate limited, increasing sleep to 20s
2026/02/02 03:42:29 DEBUG : pacer: low level retry 2/10 (error Error "Server Error")
2026/02/02 03:42:49 DEBUG : pacer: low level retry 3/10 (error Error "Server Error")
2026/02/02 03:43:09 DEBUG : pacer: low level retry 4/10 (error Error "Server Error")
2026/02/02 03:43:29 DEBUG : pacer: low level retry 5/10 (error Error "Server Error")
2026/02/02 03:43:49 DEBUG : pacer: low level retry 6/10 (error Error "Server Error")
2026/02/02 03:44:09 DEBUG : pacer: low level retry 7/10 (error Error "Server Error")
2026/02/02 03:44:29 DEBUG : pacer: low level retry 8/10 (error Error "Server Error")
2026/02/02 03:44:49 DEBUG : pacer: low level retry 9/10 (error Error "Server Error")
2026/02/02 03:45:09 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 03:45:29 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/02/02 03:45:50 DEBUG : pacer: low level retry 2/10 (error Error "Server Error")
2026/02/02 03:46:09 DEBUG : pacer: low level retry 3/10 (error Error "Server Error")
2026/02/02 03:46:29 DEBUG : pacer: low level retry 4/10 (error Error "Server Error")
2026/02/02 03:46:49 DEBUG : pacer: low level retry 5/10 (error Error "Server Error")
2026/02/02 03:47:10 DEBUG : pacer: low level retry 6/10 (error Error "Server Error")
2026/02/02 03:47:29 DEBUG : pacer: low level retry 7/10 (error Error "Server Error")
2026/02/02 03:47:49 DEBUG : pacer: low level retry 8/10 (error Error "Server Error")
2026/02/02 03:48:09 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/02/02 03:48:09 DEBUG : pacer: low level retry 9/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 03:48:29 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 03:48:49 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/02/02 03:49:09 DEBUG : pacer: low level retry 2/10 (error Error "Server Error")
2026/02/02 03:49:29 DEBUG : pacer: low level retry 3/10 (error Error "Server Error")
2026/02/02 03:49:49 DEBUG : pacer: low level retry 4/10 (error Error "Server Error")
2026/02/02 03:50:09 DEBUG : pacer: low level retry 5/10 (error Error "Server Error")
2026/02/02 03:50:29 DEBUG : pacer: low level retry 6/10 (error Error "Server Error")
2026/02/02 03:50:50 DEBUG : pacer: low level retry 7/10 (error Error "Server Error")
2026/02/02 03:51:09 DEBUG : pacer: low level retry 8/10 (error Error "Server Error")
2026/02/02 03:51:29 DEBUG : pacer: low level retry 9/10 (error Error "Server Error")
2026/02/02 03:51:50 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 03:52:09 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/02/02 03:52:29 DEBUG : pacer: low level retry 2/10 (error Error "Server Error")
2026/02/02 03:52:49 DEBUG : pacer: low level retry 3/10 (error Error "Server Error")
2026/02/02 03:53:10 DEBUG : pacer: low level retry 4/10 (error Error "Server Error")
2026/02/02 03:53:29 DEBUG : pacer: low level retry 5/10 (error Error "Server Error")
2026/02/02 03:53:49 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/02/02 03:53:49 DEBUG : pacer: low level retry 6/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 03:54:10 DEBUG : pacer: low level retry 7/10 (error Error "Server Error")
2026/02/02 03:54:29 DEBUG : pacer: low level retry 8/10 (error Error "Server Error")
2026/02/02 03:54:49 DEBUG : pacer: low level retry 9/10 (error Error "Server Error")
2026/02/02 03:55:09 DEBUG : pacer: low level retry 10/10 (error Error "Server Error")
run.go:130: removing file "medium" failed - try 2/3: failed to delete item: Error "Server Error"
2026/02/02 03:55:29 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/02/02 03:55:50 DEBUG : pacer: low level retry 2/10 (error Error "Server Error")
2026/02/02 03:56:10 DEBUG : pacer: low level retry 3/10 (error Error "Server Error")
2026/02/02 03:56:29 DEBUG : pacer: low level retry 4/10 (error Error "Server Error")
2026/02/02 03:56:50 DEBUG : pacer: low level retry 5/10 (error Error "Server Error")
2026/02/02 03:57:10 DEBUG : pacer: low level retry 6/10 (error Error "Server Error")
2026/02/02 03:57:29 DEBUG : pacer: low level retry 7/10 (error Error "Server Error")
2026/02/02 03:57:49 DEBUG : pacer: low level retry 8/10 (error Error "Server Error")
2026/02/02 03:58:10 DEBUG : pacer: low level retry 9/10 (error Error "Server Error")
2026/02/02 03:58:29 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 03:58:50 DEBUG : pacer: Reducing sleep to 10s
2026/02/02 03:59:10 DEBUG : pacer: Reducing sleep to 5s
2026/02/02 03:59:19 DEBUG : pacer: Reducing sleep to 2.5s
2026/02/02 03:59:25 DEBUG : pacer: Reducing sleep to 1.25s
--- FAIL: TestMaxDelete (1052.84s)
=== RUN TestMaxDeleteSize
run.go:185: Remote "drime root 'rclone-test-bejazic8foyi'", Local "Local file system at /tmp/rclone4034720377", Modify Window "876000h0m0s"
2026/02/02 03:59:27 DEBUG : pacer: Reducing sleep to 625ms
2026/02/02 03:59:29 DEBUG : pacer: Reducing sleep to 312.5ms
2026/02/02 03:59:29 DEBUG : pacer: Reducing sleep to 156.25ms
2026/02/02 03:59:30 DEBUG : pacer: Reducing sleep to 78.125ms
2026/02/02 03:59:31 DEBUG : pacer: Reducing sleep to 39.0625ms
2026/02/02 03:59:32 DEBUG : pacer: Reducing sleep to 19.53125ms
2026/02/02 03:59:32 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:292: Sleeping for 7s just to make sure
2026/02/02 03:59:44 DEBUG : Waiting for deletions to finish
2026/02/02 03:59:44 ERROR : small: Got fatal error on delete: --max-delete-size threshold reached
2026/02/02 03:59:45 INFO : medium: Deleted
2026/02/02 03:59:45 INFO : large: 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 03:59:46 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/02/02 03:59:46 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/02/02 03:59:46 DEBUG : pacer: low level retry 2/10 (error Error "Server Error")
2026/02/02 03:59:46 DEBUG : pacer: Rate limited, increasing sleep to 40ms
2026/02/02 03:59:46 DEBUG : pacer: low level retry 3/10 (error Error "Server Error")
2026/02/02 03:59:46 DEBUG : pacer: Rate limited, increasing sleep to 80ms
2026/02/02 03:59:47 DEBUG : pacer: low level retry 4/10 (error Error "Server Error")
2026/02/02 03:59:47 DEBUG : pacer: Rate limited, increasing sleep to 160ms
2026/02/02 03:59:47 DEBUG : pacer: low level retry 5/10 (error Error "Server Error")
2026/02/02 03:59:47 DEBUG : pacer: Rate limited, increasing sleep to 320ms
2026/02/02 03:59:47 DEBUG : pacer: low level retry 6/10 (error Error "Server Error")
2026/02/02 03:59:47 DEBUG : pacer: Rate limited, increasing sleep to 640ms
2026/02/02 03:59:48 DEBUG : pacer: low level retry 7/10 (error Error "Server Error")
2026/02/02 03:59:48 DEBUG : pacer: Rate limited, increasing sleep to 1.28s
2026/02/02 03:59:48 DEBUG : pacer: low level retry 8/10 (error Error "Server Error")
2026/02/02 03:59:48 DEBUG : pacer: Rate limited, increasing sleep to 2.56s
2026/02/02 03:59:50 DEBUG : pacer: low level retry 9/10 (error Error "Server Error")
2026/02/02 03:59:50 DEBUG : pacer: Rate limited, increasing sleep to 5.12s
2026/02/02 03:59:53 DEBUG : pacer: low level retry 10/10 (error Error "Server Error")
2026/02/02 03:59:53 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 03:59:57 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/02/02 03:59:57 DEBUG : pacer: Rate limited, increasing sleep to 20s
2026/02/02 04:00:07 DEBUG : pacer: low level retry 2/10 (error Error "Server Error")
2026/02/02 04:00:28 DEBUG : pacer: low level retry 3/10 (error Error "Server Error")
2026/02/02 04:00:48 DEBUG : pacer: low level retry 4/10 (error Error "Server Error")
2026/02/02 04:01:08 DEBUG : pacer: low level retry 5/10 (error Error "Server Error")
2026/02/02 04:01:28 DEBUG : pacer: low level retry 6/10 (error Error "Server Error")
2026/02/02 04:01:48 DEBUG : pacer: low level retry 7/10 (error Error "Server Error")
2026/02/02 04:02:08 DEBUG : pacer: low level retry 8/10 (error Error "Server Error")
2026/02/02 04:02:28 DEBUG : pacer: low level retry 9/10 (error Error "Server Error")
2026/02/02 04:02:48 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 04:03:08 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/02/02 04:03:28 DEBUG : pacer: low level retry 2/10 (error Error "Server Error")
2026/02/02 04:03:48 DEBUG : pacer: low level retry 3/10 (error Error "Server Error")
2026/02/02 04:04:07 DEBUG : pacer: low level retry 4/10 (error Error "Server Error")
2026/02/02 04:04:28 DEBUG : pacer: low level retry 5/10 (error Error "Server Error")
2026/02/02 04:04:48 DEBUG : pacer: low level retry 6/10 (error Error "Server Error")
2026/02/02 04:05:08 DEBUG : pacer: low level retry 7/10 (error Error "Server Error")
2026/02/02 04:05:27 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/02/02 04:05:27 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 04:05:48 DEBUG : pacer: low level retry 9/10 (error Error "Server Error")
2026/02/02 04:06:08 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 04:06:28 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/02/02 04:06:48 DEBUG : pacer: low level retry 2/10 (error Error "Server Error")
2026/02/02 04:07:08 DEBUG : pacer: low level retry 3/10 (error Error "Server Error")
2026/02/02 04:07:27 DEBUG : pacer: low level retry 4/10 (error Error "Server Error")
2026/02/02 04:07:48 DEBUG : pacer: low level retry 5/10 (error Error "Server Error")
2026/02/02 04:08:08 DEBUG : pacer: low level retry 6/10 (error Error "Server Error")
2026/02/02 04:08:28 DEBUG : pacer: low level retry 7/10 (error Error "Server Error")
2026/02/02 04:08:48 DEBUG : pacer: low level retry 8/10 (error Error "Server Error")
2026/02/02 04:09:08 DEBUG : pacer: low level retry 9/10 (error Error "Server Error")
2026/02/02 04:09:27 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 04:09:48 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/02/02 04:10:08 DEBUG : pacer: low level retry 2/10 (error Error "Server Error")
2026/02/02 04:10:28 DEBUG : pacer: low level retry 3/10 (error Error "Server Error")
2026/02/02 04:10:47 DEBUG : pacer: low level retry 4/10 (error Error "Server Error")
2026/02/02 04:11:08 DEBUG : pacer: low level retry 5/10 (error Error "Server Error")
2026/02/02 04:11:28 DEBUG : pacer: low level retry 6/10 (error Error "Server Error")
2026/02/02 04:11:48 DEBUG : pacer: low level retry 7/10 (error Error "Server Error")
2026/02/02 04:12:08 DEBUG : pacer: low level retry 8/10 (error Error "Server Error")
2026/02/02 04:12:28 DEBUG : pacer: low level retry 9/10 (error Error "Server Error")
2026/02/02 04:12:47 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 04:13:08 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/02/02 04:13:28 DEBUG : pacer: low level retry 2/10 (error Error "Server Error")
2026/02/02 04:13:48 DEBUG : pacer: low level retry 3/10 (error Error "Server Error")
2026/02/02 04:14:08 DEBUG : pacer: low level retry 4/10 (error Error "Server Error")
2026/02/02 04:14:28 DEBUG : pacer: low level retry 5/10 (error Error "Server Error")
2026/02/02 04:14:48 DEBUG : pacer: low level retry 6/10 (error Error "Server Error")
2026/02/02 04:15:08 DEBUG : pacer: low level retry 7/10 (error Error "Server Error")
2026/02/02 04:15:28 DEBUG : pacer: low level retry 8/10 (error Error "Server Error")
2026/02/02 04:15:48 DEBUG : pacer: low level retry 9/10 (error Error "Server Error")
2026/02/02 04:16:08 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 04:16:28 DEBUG : pacer: Reducing sleep to 10s
2026/02/02 04:16:48 DEBUG : pacer: Reducing sleep to 5s
2026/02/02 04:16:58 DEBUG : pacer: Reducing sleep to 2.5s
2026/02/02 04:17:02 DEBUG : pacer: Reducing sleep to 1.25s
--- FAIL: TestMaxDeleteSize (1057.52s)
=== RUN TestReadFile
run.go:185: Remote "drime root 'rclone-test-bejazic8foyi'", Local "Local file system at /tmp/rclone4034720377", Modify Window "876000h0m0s"
2026/02/02 04:17:05 DEBUG : pacer: Reducing sleep to 625ms
2026/02/02 04:17:07 DEBUG : pacer: Reducing sleep to 312.5ms
2026/02/02 04:17:08 DEBUG : pacer: Reducing sleep to 156.25ms
fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3
fstest.go:301: Flushing the directory cache
2026/02/02 04:17:09 DEBUG : pacer: Reducing sleep to 78.125ms
2026/02/02 04:17:09 DEBUG : pacer: Reducing sleep to 39.0625ms
fstest.go:292: Sleeping for 6s just to make sure
2026/02/02 04:17:16 DEBUG : pacer: Reducing sleep to 19.53125ms
2026/02/02 04:17:16 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:292: Sleeping for 7s just to make sure
--- PASS: TestReadFile (25.85s)
=== RUN TestCat
run.go:185: Remote "drime root 'rclone-test-bejazic8foyi'", Local "Local file system at /tmp/rclone4034720377", 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:298: Sleeping for 2s for list eventual consistency: 2/3
fstest.go:301: Flushing the directory cache
fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3
fstest.go:301: Flushing the directory cache
fstest.go:305:
Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338
/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178
/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393
/usr/local/go/src/testing/testing.go:1211
/usr/local/go/src/testing/testing.go:1445
/usr/local/go/src/testing/testing.go:1786
Error: Should be true
Test: TestCat
Messages: listing wrong, want
got
file1 (10), file2 (9)
fstest.go:191:
Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338
/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178
/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393
/usr/local/go/src/testing/testing.go:1211
/usr/local/go/src/testing/testing.go:1445
/usr/local/go/src/testing/testing.go:1786
Error: Should be true
Test: TestCat
Messages: Unexpected file "file1"
fstest.go:191:
Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338
/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178
/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393
/usr/local/go/src/testing/testing.go:1211
/usr/local/go/src/testing/testing.go:1445
/usr/local/go/src/testing/testing.go:1786
Error: Should be true
Test: TestCat
Messages: Unexpected file "file2"
--- FAIL: TestCat (27.19s)
=== RUN TestPurge
2026/02/02 04:17:55 DEBUG : Creating backend with remote "TestDrime:rclone-test-zuxufor8zore"
2026/02/02 04:17:55 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/02/02 04:17:56 DEBUG : Creating backend with remote "/tmp/rclone2964624115"
run.go:185: Remote "drime root 'rclone-test-zuxufor8zore'", Local "Local file system at /tmp/rclone2964624115", Modify Window "876000h0m0s"
2026/02/02 04:18:01 INFO : A2: Making directory
2026/02/02 04:18:02 INFO : A1/B2: Making directory
2026/02/02 04:18:02 INFO : A1/B2/C2: Making directory
2026/02/02 04:18:03 INFO : A1/B1/C3: Making directory
2026/02/02 04:18:03 INFO : A3: Making directory
2026/02/02 04:18:04 INFO : A3/B3: Making directory
2026/02/02 04:18:05 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
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:633
Error: Should be true
Test: TestPurge
Messages: listing wrong, want
got
A1/two (3)
fstest.go:191:
Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338
/home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:633
Error: Should be true
Test: TestPurge
Messages: Unexpected file "A1/two"
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:633
Error: Not equal:
expected: []string{}
actual : []string{"A1", "A1/B2", "A1/B2/C2", "A2", "A3", "A3/B3", "A3/B3/C4"}
Diff:
--- Expected
+++ Actual
@@ -1,2 +1,9 @@
-([]string) {
+([]string) (len=7) {
+ (string) (len=2) "A1",
+ (string) (len=5) "A1/B2",
+ (string) (len=8) "A1/B2/C2",
+ (string) (len=2) "A2",
+ (string) (len=2) "A3",
+ (string) (len=5) "A3/B3",
+ (string) (len=8) "A3/B3/C4"
}
Test: TestPurge
Messages: directories
2026/02/02 04:18:34 DEBUG : drime root 'rclone-test-zuxufor8zore': Purge remote
2026/02/02 04:18:35 NOTICE: purge failed: directory not found
--- FAIL: TestPurge (39.53s)
=== RUN TestRmdirsNoLeaveRoot
run.go:185: Remote "drime root 'rclone-test-bejazic8foyi'", Local "Local file system at /tmp/rclone4034720377", Modify Window "876000h0m0s"
2026/02/02 04:18:41 INFO : A2: Making directory
2026/02/02 04:18:42 INFO : A1/B2: Making directory
2026/02/02 04:18:42 INFO : A1/B2/C2: Making directory
2026/02/02 04:18:44 INFO : A1/B1/C3: Making directory
2026/02/02 04:18:45 INFO : A3: Making directory
2026/02/02 04:18:45 INFO : A3/B3: Making directory
2026/02/02 04:18:46 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 04:19:01 DEBUG : removing 1 level 3 directories
2026/02/02 04:19:01 INFO : A3/B3/C4: Removing directory
2026/02/02 04:19:05 DEBUG : removing 2 level 3 directories
2026/02/02 04:19:05 INFO : A1/B2/C2: Removing directory
2026/02/02 04:19:05 INFO : A1/B1/C3: Removing directory
2026/02/02 04:19:05 DEBUG : removing 2 level 2 directories
2026/02/02 04:19:05 INFO : A3/B3: Removing directory
2026/02/02 04:19:05 INFO : A1/B2: Removing directory
2026/02/02 04:19:05 ERROR : A1/B2: Failed to rmdir: directory not empty
2026/02/02 04:19:06 DEBUG : removing 2 level 1 directories
2026/02/02 04:19:06 INFO : A3: Removing directory
2026/02/02 04:19:06 INFO : A2: Removing directory
2026/02/02 04:19:06 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 04:19:10 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/02/02 04:19:10 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/02/02 04:19:11 DEBUG : pacer: low level retry 2/10 (error Error "Server Error")
2026/02/02 04:19:11 DEBUG : pacer: Rate limited, increasing sleep to 40ms
2026/02/02 04:19:11 DEBUG : pacer: low level retry 3/10 (error Error "Server Error")
2026/02/02 04:19:11 DEBUG : pacer: Rate limited, increasing sleep to 80ms
2026/02/02 04:19:11 DEBUG : pacer: low level retry 4/10 (error Error "Server Error")
2026/02/02 04:19:11 DEBUG : pacer: Rate limited, increasing sleep to 160ms
2026/02/02 04:19:12 DEBUG : pacer: low level retry 5/10 (error Error "Server Error")
2026/02/02 04:19:12 DEBUG : pacer: Rate limited, increasing sleep to 320ms
2026/02/02 04:19:12 DEBUG : pacer: low level retry 6/10 (error Error "Server Error")
2026/02/02 04:19:12 DEBUG : pacer: Rate limited, increasing sleep to 640ms
2026/02/02 04:19:12 DEBUG : pacer: low level retry 7/10 (error Error "Server Error")
2026/02/02 04:19:12 DEBUG : pacer: Rate limited, increasing sleep to 1.28s
2026/02/02 04:19:13 DEBUG : pacer: low level retry 8/10 (error Error "Server Error")
2026/02/02 04:19:13 DEBUG : pacer: Rate limited, increasing sleep to 2.56s
2026/02/02 04:19:14 DEBUG : pacer: low level retry 9/10 (error Error "Server Error")
2026/02/02 04:19:14 DEBUG : pacer: Rate limited, increasing sleep to 5.12s
2026/02/02 04:19:17 DEBUG : pacer: low level retry 10/10 (error Error "Server Error")
2026/02/02 04:19:17 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 04:19:22 DEBUG : pacer: Reducing sleep to 5.12s
2026/02/02 04:19:32 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/02/02 04:19:32 DEBUG : pacer: Rate limited, increasing sleep to 10.24s
2026/02/02 04:19:37 DEBUG : pacer: low level retry 2/10 (error Error "Server Error")
2026/02/02 04:19:37 DEBUG : pacer: Rate limited, increasing sleep to 20s
2026/02/02 04:19:47 DEBUG : pacer: low level retry 3/10 (error Error "Server Error")
2026/02/02 04:20:07 DEBUG : pacer: low level retry 4/10 (error Error "Server Error")
2026/02/02 04:20:27 DEBUG : pacer: low level retry 5/10 (error Error "Server Error")
2026/02/02 04:20:48 DEBUG : pacer: low level retry 6/10 (error Error "Server Error")
2026/02/02 04:21:08 DEBUG : pacer: low level retry 7/10 (error Error "Server Error")
2026/02/02 04:21:27 DEBUG : pacer: low level retry 8/10 (error Error "Server Error")
2026/02/02 04:21:48 DEBUG : pacer: low level retry 9/10 (error Error "Server Error")
2026/02/02 04:22:08 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 04:22:27 DEBUG : pacer: Reducing sleep to 10s
2026/02/02 04:22:48 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/02/02 04:22:48 DEBUG : pacer: Rate limited, increasing sleep to 20s
2026/02/02 04:22:58 DEBUG : pacer: low level retry 2/10 (error Error "Server Error")
2026/02/02 04:23:18 DEBUG : pacer: low level retry 3/10 (error Error "Server Error")
2026/02/02 04:23:38 DEBUG : pacer: low level retry 4/10 (error Error "Server Error")
2026/02/02 04:23:58 DEBUG : pacer: low level retry 5/10 (error Error "Server Error")
2026/02/02 04:24:18 DEBUG : pacer: low level retry 6/10 (error Error "Server Error")
2026/02/02 04:24:38 DEBUG : pacer: low level retry 7/10 (error Error "Server Error")
2026/02/02 04:24:58 DEBUG : pacer: low level retry 8/10 (error Error "Server Error")
2026/02/02 04:25:18 DEBUG : pacer: low level retry 9/10 (error Error "Server Error")
2026/02/02 04:25:38 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 04:25:58 DEBUG : pacer: Reducing sleep to 10s
2026/02/02 04:26:18 DEBUG : pacer: Reducing sleep to 5s
2026/02/02 04:26:28 DEBUG : pacer: Reducing sleep to 2.5s
2026/02/02 04:26:33 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/02/02 04:26:33 DEBUG : pacer: Rate limited, increasing sleep to 5s
2026/02/02 04:26:35 DEBUG : pacer: low level retry 2/10 (error Error "Server Error")
2026/02/02 04:26:35 DEBUG : pacer: Rate limited, increasing sleep to 10s
2026/02/02 04:26:40 DEBUG : pacer: low level retry 3/10 (error Error "Server Error")
2026/02/02 04:26:40 DEBUG : pacer: Rate limited, increasing sleep to 20s
2026/02/02 04:26:50 DEBUG : pacer: low level retry 4/10 (error Error "Server Error")
2026/02/02 04:27:10 DEBUG : pacer: low level retry 5/10 (error Error "Server Error")
2026/02/02 04:27:30 DEBUG : pacer: low level retry 6/10 (error Error "Server Error")
2026/02/02 04:27:50 DEBUG : pacer: low level retry 7/10 (error Error "Server Error")
2026/02/02 04:28:10 DEBUG : pacer: low level retry 8/10 (error Error "Server Error")
2026/02/02 04:28:30 DEBUG : pacer: low level retry 9/10 (error Error "Server Error")
2026/02/02 04:28:50 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 04:29:10 DEBUG : pacer: Reducing sleep to 10s
2026/02/02 04:29:30 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/02/02 04:29:30 DEBUG : pacer: Rate limited, increasing sleep to 20s
2026/02/02 04:29:40 DEBUG : pacer: low level retry 2/10 (error Error "Server Error")
2026/02/02 04:30:00 DEBUG : pacer: low level retry 3/10 (error Error "Server Error")
2026/02/02 04:30:21 DEBUG : pacer: low level retry 4/10 (error Error "Server Error")
2026/02/02 04:30:40 DEBUG : pacer: low level retry 5/10 (error Error "Server Error")
2026/02/02 04:31:00 DEBUG : pacer: low level retry 6/10 (error Error "Server Error")
2026/02/02 04:31:21 DEBUG : pacer: low level retry 7/10 (error Error "Server Error")
2026/02/02 04:31:40 DEBUG : pacer: low level retry 8/10 (error Error "Server Error")
2026/02/02 04:32:01 DEBUG : pacer: low level retry 9/10 (error Error "Server Error")
2026/02/02 04:32:21 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 04:32:41 DEBUG : pacer: Reducing sleep to 10s
2026/02/02 04:33:01 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/02/02 04:33:01 DEBUG : pacer: Rate limited, increasing sleep to 20s
2026/02/02 04:33:11 DEBUG : pacer: low level retry 2/10 (error Error "Server Error")
2026/02/02 04:33:31 DEBUG : pacer: low level retry 3/10 (error Error "Server Error")
2026/02/02 04:33:51 DEBUG : pacer: low level retry 4/10 (error Error "Server Error")
2026/02/02 04:34:11 DEBUG : pacer: low level retry 5/10 (error Error "Server Error")
2026/02/02 04:34:31 DEBUG : pacer: low level retry 6/10 (error Error "Server Error")
2026/02/02 04:34:51 DEBUG : pacer: low level retry 7/10 (error Error "Server Error")
2026/02/02 04:35:11 DEBUG : pacer: low level retry 8/10 (error Error "Server Error")
2026/02/02 04:35:31 DEBUG : pacer: low level retry 9/10 (error Error "Server Error")
2026/02/02 04:35:51 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 04:36:11 DEBUG : pacer: Reducing sleep to 10s
2026/02/02 04:36:31 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/02/02 04:36:31 DEBUG : pacer: Rate limited, increasing sleep to 20s
2026/02/02 04:36:41 DEBUG : pacer: low level retry 2/10 (error Error "Server Error")
2026/02/02 04:37:01 DEBUG : pacer: low level retry 3/10 (error Error "Server Error")
2026/02/02 04:37:21 DEBUG : pacer: low level retry 4/10 (error Error "Server Error")
2026/02/02 04:37:41 DEBUG : pacer: low level retry 5/10 (error Error "Server Error")
2026/02/02 04:38:01 DEBUG : pacer: low level retry 6/10 (error Error "Server Error")
2026/02/02 04:38:21 DEBUG : pacer: low level retry 7/10 (error Error "Server Error")
2026/02/02 04:38:41 DEBUG : pacer: low level retry 8/10 (error Error "Server Error")
2026/02/02 04:39:01 DEBUG : pacer: low level retry 9/10 (error Error "Server Error")
2026/02/02 04:39:21 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 04:39:41 DEBUG : pacer: Reducing sleep to 10s
2026/02/02 04:40:01 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/02/02 04:40:01 DEBUG : pacer: Rate limited, increasing sleep to 20s
2026/02/02 04:40:11 DEBUG : pacer: low level retry 2/10 (error Error "Server Error")
2026/02/02 04:40:31 DEBUG : pacer: low level retry 3/10 (error Error "Server Error")
2026/02/02 04:40:51 DEBUG : pacer: low level retry 4/10 (error Error "Server Error")
2026/02/02 04:41:11 DEBUG : pacer: low level retry 5/10 (error Error "Server Error")
2026/02/02 04:41:31 DEBUG : pacer: low level retry 6/10 (error Error "Server Error")
2026/02/02 04:41:51 DEBUG : pacer: low level retry 7/10 (error Error "Server Error")
2026/02/02 04:42:11 DEBUG : pacer: low level retry 8/10 (error Error "Server Error")
2026/02/02 04:42:31 DEBUG : pacer: low level retry 9/10 (error Error "Server Error")
2026/02/02 04:42:51 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 04:43:11 DEBUG : pacer: Reducing sleep to 10s
2026/02/02 04:43:31 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/02/02 04:43:31 DEBUG : pacer: Rate limited, increasing sleep to 20s
2026/02/02 04:43:41 DEBUG : pacer: low level retry 2/10 (error Error "Server Error")
2026/02/02 04:44:01 DEBUG : pacer: low level retry 3/10 (error Error "Server Error")
2026/02/02 04:44:21 DEBUG : pacer: low level retry 4/10 (error Error "Server Error")
2026/02/02 04:44:41 DEBUG : pacer: low level retry 5/10 (error Error "Server Error")
2026/02/02 04:45:01 DEBUG : pacer: low level retry 6/10 (error Error "Server Error")
2026/02/02 04:45:21 DEBUG : pacer: low level retry 7/10 (error Error "Server Error")
2026/02/02 04:45:41 DEBUG : pacer: low level retry 8/10 (error Error "Server Error")
2026/02/02 04:46:01 DEBUG : pacer: low level retry 9/10 (error Error "Server Error")
2026/02/02 04:46:21 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 04:46:42 DEBUG : pacer: Reducing sleep to 10s
2026/02/02 04:47:01 DEBUG : pacer: Reducing sleep to 5s
2026/02/02 04:47:11 DEBUG : pacer: Reducing sleep to 2.5s
2026/02/02 04:47:16 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/02/02 04:47:16 DEBUG : pacer: Rate limited, increasing sleep to 5s
2026/02/02 04:47:19 DEBUG : pacer: low level retry 2/10 (error Error "Server Error")
2026/02/02 04:47:19 DEBUG : pacer: Rate limited, increasing sleep to 10s
2026/02/02 04:47:24 DEBUG : pacer: low level retry 3/10 (error Error "Server Error")
2026/02/02 04:47:24 DEBUG : pacer: Rate limited, increasing sleep to 20s
2026/02/02 04:47:34 DEBUG : pacer: low level retry 4/10 (error Error "Server Error")
2026/02/02 04:47:54 DEBUG : pacer: low level retry 5/10 (error Error "Server Error")
2026/02/02 04:48:14 DEBUG : pacer: low level retry 6/10 (error Error "Server Error")
2026/02/02 04:48:34 DEBUG : pacer: low level retry 7/10 (error Error "Server Error")
2026/02/02 04:48:54 DEBUG : pacer: low level retry 8/10 (error Error "Server Error")
2026/02/02 04:49:14 DEBUG : pacer: low level retry 9/10 (error Error "Server Error")
2026/02/02 04:49:34 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 04:49:54 DEBUG : pacer: Reducing sleep to 10s
2026/02/02 04:50:14 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/02/02 04:50:14 DEBUG : pacer: Rate limited, increasing sleep to 20s
2026/02/02 04:50:24 DEBUG : pacer: low level retry 2/10 (error Error "Server Error")
2026/02/02 04:50:44 DEBUG : pacer: low level retry 3/10 (error Error "Server Error")
2026/02/02 04:51:04 DEBUG : pacer: low level retry 4/10 (error Error "Server Error")
2026/02/02 04:51:24 DEBUG : pacer: low level retry 5/10 (error Error "Server Error")
2026/02/02 04:51:44 DEBUG : pacer: low level retry 6/10 (error Error "Server Error")
2026/02/02 04:52:04 DEBUG : pacer: low level retry 7/10 (error Error "Server Error")
2026/02/02 04:52:24 DEBUG : pacer: low level retry 8/10 (error Error "Server Error")
2026/02/02 04:52:44 DEBUG : pacer: low level retry 9/10 (error Error "Server Error")
2026/02/02 04:53:04 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 04:53:24 DEBUG : pacer: Reducing sleep to 10s
2026/02/02 04:53:44 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/02/02 04:53:44 DEBUG : pacer: Rate limited, increasing sleep to 20s
2026/02/02 04:53:54 DEBUG : pacer: low level retry 2/10 (error Error "Server Error")
2026/02/02 04:54:14 DEBUG : pacer: low level retry 3/10 (error Error "Server Error")
2026/02/02 04:54:34 DEBUG : pacer: low level retry 4/10 (error Error "Server Error")
2026/02/02 04:54:54 DEBUG : pacer: low level retry 5/10 (error Error "Server Error")
2026/02/02 04:55:14 DEBUG : pacer: low level retry 6/10 (error Error "Server Error")
2026/02/02 04:55:34 DEBUG : pacer: low level retry 7/10 (error Error "Server Error")
2026/02/02 04:55:54 DEBUG : pacer: low level retry 8/10 (error Error "Server Error")
2026/02/02 04:56:14 DEBUG : pacer: low level retry 9/10 (error Error "Server Error")
2026/02/02 04:56:34 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 04:56:54 DEBUG : pacer: Reducing sleep to 10s
2026/02/02 04:57:14 DEBUG : pacer: Reducing sleep to 5s
2026/02/02 04:57:24 DEBUG : pacer: Reducing sleep to 2.5s
2026/02/02 04:57:29 DEBUG : pacer: Reducing sleep to 1.25s
2026/02/02 04:57:32 DEBUG : pacer: Reducing sleep to 625ms
2026/02/02 04:57:33 DEBUG : pacer: Reducing sleep to 312.5ms
2026/02/02 04:57:33 DEBUG : pacer: Reducing sleep to 156.25ms
--- FAIL: TestRmdirsNoLeaveRoot (2338.43s)
FAIL
2026/02/02 04:57:33 DEBUG : drime root 'rclone-test-bejazic8foyi': Purge remote
2026/02/02 04:57:34 DEBUG : pacer: Reducing sleep to 78.125ms
"./operations.test -test.v -test.timeout 2h0m0s -remote TestDrime: -verbose -test.run '^(TestApplyTransforms|TestCat|TestCheckDownload|TestCheckSumDownload|TestCopyFile|TestCopyFileCompareDest|TestCopyFileCopyDest|TestCopyFileMaxTransfer|TestCopyInplace|TestCopyLongFile|TestHashSums|TestListDirSortedFn|TestLsLong|TestLsWithFilesFrom|TestLsd|TestMaxDelete|TestMaxDeleteSize|TestMkdir|TestMultithreadCopyAbort|TestParseSumFile|TestPurge|TestReadFile|TestRmdirsNoLeaveRoot|TestStatJSON)$|^TestCheckSizeOnly$/^1$|^TestListJSON$/^(Default|DirsOnly|Metadata|Recurse)$|^TestMultithreadCopy$/^(upload=false,size=10485759,streams=2|upload=false,size=10485760,streams=2|upload=false,size=10485761,streams=2|upload=true,size=10485759,streams=2|upload=true,size=10485760,streams=2|upload=true,size=10485761,streams=2)$'" - Finished ERROR in 1h54m36.85501511s (try 2/5): exit status 1: 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 TestParseSumFile TestCheckSumDownload TestApplyTransforms TestCopyFile TestCopyFileCompareDest TestCopyFileCopyDest TestCopyInplace TestListDirSortedFn TestListJSON/Default TestListJSON/DirsOnly TestListJSON/Recurse TestListJSON/Metadata TestStatJSON TestMkdir TestLsd TestLsLong TestHashSums TestMaxDelete TestMaxDeleteSize TestCat TestPurge TestRmdirsNoLeaveRoot]