"./operations.test -test.v -test.timeout 2h0m0s -remote TestDrime: -verbose -test.run '^(TestApplyTransforms|TestCat|TestCheckSumDownload|TestCopyFile|TestCopyFileCopyDest|TestCopyFileMaxTransfer|TestCount|TestDelete|TestListDirSorted|TestListDirSortedFn|TestLs|TestLsLong|TestLsd|TestMaxDelete|TestMaxDeleteSize|TestMaxDeleteSizeLargeFile|TestMultithreadCopyAbort|TestParseSumFile|TestReadFile)$|^TestListJSON$/^(Default|DirsOnly|Metadata|Recurse)$|^TestMultithreadCopy$/^(upload=false,size=10485759,streams=2|upload=true,size=10485759,streams=2|upload=true,size=10485760,streams=2|upload=true,size=10485761,streams=2)$'" - Starting (try 3/5)
2026/02/03 05:48:48 DEBUG : Creating backend with remote "TestDrime:rclone-test-kibuwoh5suma"
2026/02/03 05:48:48 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/02/03 05:48:49 DEBUG : Creating backend with remote "/tmp/rclone3672422180"
=== RUN TestMultithreadCopy
run.go:185: Remote "drime root 'rclone-test-kibuwoh5suma'", Local "Local file system at /tmp/rclone3672422180", 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/03 05:48:55 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
fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3
fstest.go:301: Flushing the directory cache
fstest.go:305:
Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350
/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345
/home/rclone/go/src/github.com/rclone/rclone/fs/operations/multithread_test.go:191
Error: Should be true
Test: TestMultithreadCopy/upload=false,size=10485759,streams=2
Messages: listing wrong, want
test-multithread-copy-false-10485759-2 (10485759) got
fstest.go:203: Not found "test-multithread-copy-false-10485759-2"
fstest.go:206:
Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:206
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:310
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350
/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345
/home/rclone/go/src/github.com/rclone/rclone/fs/operations/multithread_test.go:191
/usr/local/go/src/runtime/asm_amd64.s:1700
Error: Not equal:
expected: 0
actual : 1
Test: TestMultithreadCopy/upload=false,size=10485759,streams=2
Messages: 1 objects not found
2026/02/03 05:49:02 DEBUG : test-multithread-copy-false-10485759-2: multi-thread copy: disabling buffering because destination uses OpenWriterAt
2026/02/03 05:49:02 DEBUG : test-multithread-copy-false-10485759-2: multi-thread copy: write buffer set to 131072
2026/02/03 05:49:02 DEBUG : test-multithread-copy-false-10485759-2: multi-thread copy: chunk size 64Mi was bigger than source file size 10.000Mi
2026/02/03 05:49:02 DEBUG : test-multithread-copy-false-10485759-2: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 2
2026/02/03 05:49:02 DEBUG : test-multithread-copy-false-10485759-2: multi-thread copy: number of streams 4 was bigger than number of chunks 1
2026/02/03 05:49:02 DEBUG : test-multithread-copy-false-10485759-2: Starting multi-thread copy with 1 chunks of size 10.000Mi with 1 parallel streams
2026/02/03 05:49:02 DEBUG : test-multithread-copy-false-10485759-2: multi-thread copy: chunk 1/1 (0-10485759) size 10.000Mi starting
2026/02/03 05:49:03 DEBUG : test-multithread-copy-false-10485759-2: writing chunk 0
2026/02/03 05:49:03 DEBUG : test-multithread-copy-false-10485759-2: multi-thread copy: chunk 1/1 (0-10485759) size 10.000Mi finished
2026/02/03 05:49:03 DEBUG : test-multithread-copy-false-10485759-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-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=10485759,streams=2
Messages: Unexpected file "test-multithread-copy-false-10485759-2"
2026/02/03 05:49:13 DEBUG : test-multithread-copy-true-10485759-2: multi-thread copy: disabling buffering because source is local disk
2026/02/03 05:49:14 DEBUG : test-multithread-copy-true-10485759-2: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 2
2026/02/03 05:49:14 DEBUG : test-multithread-copy-true-10485759-2: multi-thread copy: number of streams 4 was bigger than number of chunks 2
2026/02/03 05:49:14 DEBUG : test-multithread-copy-true-10485759-2: Starting multi-thread copy with 2 chunks of size 5Mi with 2 parallel streams
2026/02/03 05:49:14 DEBUG : test-multithread-copy-true-10485759-2: multi-thread copy: chunk 2/2 (5242880-10485759) size 5.000Mi starting
2026/02/03 05:49:14 DEBUG : test-multithread-copy-true-10485759-2: multi-thread copy: chunk 1/2 (0-5242880) size 5Mi starting
2026/02/03 05:49:14 DEBUG : test-multithread-copy-true-10485759-2: multi-thread copy: chunk 2/2 (5242880-10485759) size 5.000Mi finished
2026/02/03 05:49:14 DEBUG : test-multithread-copy-true-10485759-2: multi-thread copy: chunk 1/2 (0-5242880) size 5Mi finished
2026/02/03 05:49:20 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/03 05:49:30 DEBUG : test-multithread-copy-true-10485760-2: multi-thread copy: disabling buffering because source is local disk
2026/02/03 05:49:32 DEBUG : test-multithread-copy-true-10485760-2: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 2
2026/02/03 05:49:32 DEBUG : test-multithread-copy-true-10485760-2: multi-thread copy: number of streams 4 was bigger than number of chunks 2
2026/02/03 05:49:32 DEBUG : test-multithread-copy-true-10485760-2: Starting multi-thread copy with 2 chunks of size 5Mi with 2 parallel streams
2026/02/03 05:49:32 DEBUG : test-multithread-copy-true-10485760-2: multi-thread copy: chunk 2/2 (5242880-10485760) size 5Mi starting
2026/02/03 05:49:32 DEBUG : test-multithread-copy-true-10485760-2: multi-thread copy: chunk 1/2 (0-5242880) size 5Mi starting
2026/02/03 05:49:32 DEBUG : test-multithread-copy-true-10485760-2: multi-thread copy: chunk 1/2 (0-5242880) size 5Mi finished
2026/02/03 05:49:32 DEBUG : test-multithread-copy-true-10485760-2: multi-thread copy: chunk 2/2 (5242880-10485760) size 5Mi finished
2026/02/03 05:49:34 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/03 05:49:45 DEBUG : test-multithread-copy-true-10485761-2: multi-thread copy: disabling buffering because source is local disk
2026/02/03 05:49:46 DEBUG : test-multithread-copy-true-10485761-2: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 2
2026/02/03 05:49:46 DEBUG : test-multithread-copy-true-10485761-2: multi-thread copy: number of streams 4 was bigger than number of chunks 3
2026/02/03 05:49:46 DEBUG : test-multithread-copy-true-10485761-2: Starting multi-thread copy with 3 chunks of size 5Mi with 3 parallel streams
2026/02/03 05:49:46 DEBUG : test-multithread-copy-true-10485761-2: multi-thread copy: chunk 3/3 (10485760-10485761) size 1 starting
2026/02/03 05:49:46 DEBUG : test-multithread-copy-true-10485761-2: multi-thread copy: chunk 1/3 (0-5242880) size 5Mi starting
2026/02/03 05:49:46 DEBUG : test-multithread-copy-true-10485761-2: multi-thread copy: chunk 2/3 (5242880-10485760) size 5Mi starting
2026/02/03 05:49:46 DEBUG : test-multithread-copy-true-10485761-2: multi-thread copy: chunk 3/3 (10485760-10485761) size 1 finished
2026/02/03 05:49:47 DEBUG : test-multithread-copy-true-10485761-2: multi-thread copy: chunk 1/3 (0-5242880) size 5Mi finished
2026/02/03 05:49:47 DEBUG : test-multithread-copy-true-10485761-2: multi-thread copy: chunk 2/3 (5242880-10485760) size 5Mi finished
2026/02/03 05:49:49 DEBUG : test-multithread-copy-true-10485761-2: Finished multi-thread copy with 3 parts of size 5Mi
2026/02/03 05:49:50 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/02/03 05:49:50 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/02/03 05:49:51 DEBUG : pacer: low level retry 2/10 (error Error "Server Error")
2026/02/03 05:49:51 DEBUG : pacer: Rate limited, increasing sleep to 40ms
2026/02/03 05:49:51 DEBUG : pacer: low level retry 3/10 (error Error "Server Error")
2026/02/03 05:49:51 DEBUG : pacer: Rate limited, increasing sleep to 80ms
2026/02/03 05:49:51 DEBUG : pacer: low level retry 4/10 (error Error "Server Error")
2026/02/03 05:49:51 DEBUG : pacer: Rate limited, increasing sleep to 160ms
2026/02/03 05:49:52 DEBUG : pacer: low level retry 5/10 (error Error "Server Error")
2026/02/03 05:49:52 DEBUG : pacer: Rate limited, increasing sleep to 320ms
2026/02/03 05:49:52 DEBUG : pacer: low level retry 6/10 (error Error "Server Error")
2026/02/03 05:49:52 DEBUG : pacer: Rate limited, increasing sleep to 640ms
2026/02/03 05:49:52 DEBUG : pacer: low level retry 7/10 (error Error "Server Error")
2026/02/03 05:49:52 DEBUG : pacer: Rate limited, increasing sleep to 1.28s
2026/02/03 05:49:53 DEBUG : pacer: low level retry 8/10 (error Error "Server Error")
2026/02/03 05:49:53 DEBUG : pacer: Rate limited, increasing sleep to 2.56s
2026/02/03 05:49:54 DEBUG : pacer: low level retry 9/10 (error Error "Server Error")
2026/02/03 05:49:54 DEBUG : pacer: Rate limited, increasing sleep to 5.12s
2026/02/03 05:49:57 DEBUG : pacer: low level retry 10/10 (error Error "Server Error")
2026/02/03 05:49:57 DEBUG : pacer: Rate limited, increasing sleep to 10.24s
=== NAME TestMultithreadCopy
run.go:130: removing file "test-multithread-copy-true-10485761-2" failed - try 1/3: failed to delete item: Error "Server Error"
2026/02/03 05:50:02 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/02/03 05:50:02 DEBUG : pacer: Rate limited, increasing sleep to 20s
2026/02/03 05:50:12 DEBUG : pacer: low level retry 2/10 (error Error "Server Error")
2026/02/03 05:50:32 DEBUG : pacer: low level retry 3/10 (error Error "Server Error")
2026/02/03 05:50:52 DEBUG : pacer: low level retry 4/10 (error Error "Server Error")
2026/02/03 05:51:12 DEBUG : pacer: low level retry 5/10 (error Error "Server Error")
2026/02/03 05:51:32 DEBUG : pacer: low level retry 6/10 (error Error "Server Error")
2026/02/03 05:51:52 DEBUG : pacer: low level retry 7/10 (error Error "Server Error")
2026/02/03 05:52:12 DEBUG : pacer: low level retry 8/10 (error Error "Server Error")
2026/02/03 05:52:32 DEBUG : pacer: low level retry 9/10 (error Error "Server Error")
2026/02/03 05:52:52 DEBUG : pacer: low level retry 10/10 (error Error "Server Error")
run.go:130: removing file "test-multithread-copy-true-10485761-2" failed - try 2/3: failed to delete item: Error "Server Error"
2026/02/03 05:53:12 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/02/03 05:53:32 DEBUG : pacer: low level retry 2/10 (error Error "Server Error")
2026/02/03 05:53:52 DEBUG : pacer: low level retry 3/10 (error Error "Server Error")
2026/02/03 05:54:12 DEBUG : pacer: low level retry 4/10 (error Error "Server Error")
2026/02/03 05:54:32 DEBUG : pacer: low level retry 5/10 (error Error "Server Error")
2026/02/03 05:54:52 DEBUG : pacer: low level retry 6/10 (error Error "Server Error")
2026/02/03 05:55:12 DEBUG : pacer: low level retry 7/10 (error Error "Server Error")
2026/02/03 05:55:32 DEBUG : pacer: low level retry 8/10 (error Error "Server Error")
2026/02/03 05:55:52 DEBUG : pacer: low level retry 9/10 (error Error "Server Error")
2026/02/03 05:56:12 DEBUG : pacer: low level retry 10/10 (error Error "Server Error")
run.go:130: removing file "test-multithread-copy-true-10485761-2" failed - try 3/3: failed to delete item: Error "Server Error"
run.go:133: removing file "test-multithread-copy-true-10485761-2" failed: failed to delete item: Error "Server Error"
2026/02/03 05:56:32 DEBUG : pacer: Reducing sleep to 10s
--- FAIL: TestMultithreadCopy (463.85s)
--- FAIL: TestMultithreadCopy/upload=false,size=10485759,streams=2 (13.92s)
--- FAIL: TestMultithreadCopy/upload=true,size=10485759,streams=2 (17.29s)
--- FAIL: TestMultithreadCopy/upload=true,size=10485760,streams=2 (14.85s)
--- FAIL: TestMultithreadCopy/upload=true,size=10485761,streams=2 (13.74s)
=== RUN TestMultithreadCopyAbort
run.go:185: Remote "drime root 'rclone-test-kibuwoh5suma'", Local "Local file system at /tmp/rclone3672422180", Modify Window "876000h0m0s"
2026/02/03 05:56:53 DEBUG : pacer: Reducing sleep to 5s
2026/02/03 05:57:03 DEBUG : pacer: Reducing sleep to 2.5s
2026/02/03 05:57:08 DEBUG : pacer: Reducing sleep to 1.25s
2026/02/03 05:57:11 DEBUG : pacer: Reducing sleep to 625ms
2026/02/03 05:57:12 DEBUG : pacer: Reducing sleep to 312.5ms
fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3
fstest.go:301: Flushing the directory cache
2026/02/03 05:57:13 DEBUG : pacer: Reducing sleep to 156.25ms
2026/02/03 05:57:14 DEBUG : pacer: Reducing sleep to 78.125ms
fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3
fstest.go:301: Flushing the directory cache
2026/02/03 05:57:16 DEBUG : pacer: Reducing sleep to 39.0625ms
2026/02/03 05:57:16 DEBUG : pacer: Reducing sleep to 19.53125ms
fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3
fstest.go:301: Flushing the directory cache
fstest.go:305:
Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350
/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345
/home/rclone/go/src/github.com/rclone/rclone/fs/operations/multithread_test.go:305
Error: Should be true
Test: TestMultithreadCopyAbort
Messages: listing wrong, want
test-multithread-abort (100) got
fstest.go:203: Not found "test-multithread-abort"
fstest.go:206:
Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:206
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:310
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350
/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345
/home/rclone/go/src/github.com/rclone/rclone/fs/operations/multithread_test.go:305
/usr/local/go/src/runtime/asm_amd64.s:1700
Error: Not equal:
expected: 0
actual : 1
Test: TestMultithreadCopyAbort
Messages: 1 objects not found
2026/02/03 05:57:21 DEBUG : test-multithread-abort: multi-thread copy: disabling buffering because source is local disk
2026/02/03 05:57:22 DEBUG : pacer: Reducing sleep to 10ms
2026/02/03 05:57:22 DEBUG : test-multithread-abort: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 1
2026/02/03 05:57:22 DEBUG : test-multithread-abort: multi-thread copy: number of streams 4 was bigger than number of chunks 3
2026/02/03 05:57:22 DEBUG : test-multithread-abort: Starting multi-thread copy with 3 chunks of size 5Mi with 3 parallel streams
2026/02/03 05:57:22 DEBUG : test-multithread-abort: multi-thread copy: chunk 3/3 (10485760-10485761) size 1 starting
2026/02/03 05:57:22 DEBUG : Open with options = [RangeOption(10485760,10485760)]
2026/02/03 05:57:22 DEBUG : test-multithread-abort: multi-thread copy: chunk 1/3 (0-5242880) size 5Mi starting
2026/02/03 05:57:22 DEBUG : Open with options = [RangeOption(0,5242879)]
2026/02/03 05:57:22 DEBUG : test-multithread-abort: multi-thread copy: chunk 2/3 (5242880-10485760) size 5Mi starting
2026/02/03 05:57:22 DEBUG : Open with options = [RangeOption(5242880,10485759)]
2026/02/03 05:57:23 DEBUG : test-multithread-abort: multi-thread copy: chunk 1/3 (0-5242880) size 5Mi finished
2026/02/03 05:57:23 DEBUG : test-multithread-abort: multi-thread copy: chunk 2/3 (5242880-10485760) size 5Mi finished
2026/02/03 05:57:23 DEBUG : Returning error reader
2026/02/03 05:57:24 DEBUG : BOOM: simulated read failure
2026/02/03 05:57:24 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 0/10: BOOM: simulated read failure
2026/02/03 05:57:24 DEBUG : Open with options = [RangeOption(10485760,10485760)]
2026/02/03 05:57:25 DEBUG : Returning error reader
2026/02/03 05:57:25 DEBUG : BOOM: simulated read failure
2026/02/03 05:57:25 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 1/10: BOOM: simulated read failure
2026/02/03 05:57:25 DEBUG : Open with options = [RangeOption(10485760,10485760)]
2026/02/03 05:57:26 DEBUG : Returning error reader
2026/02/03 05:57:26 DEBUG : BOOM: simulated read failure
2026/02/03 05:57:26 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 2/10: BOOM: simulated read failure
2026/02/03 05:57:26 DEBUG : Open with options = [RangeOption(10485760,10485760)]
2026/02/03 05:57:27 DEBUG : Returning error reader
2026/02/03 05:57:27 DEBUG : BOOM: simulated read failure
2026/02/03 05:57:27 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 3/10: BOOM: simulated read failure
2026/02/03 05:57:27 DEBUG : Open with options = [RangeOption(10485760,10485760)]
2026/02/03 05:57:28 DEBUG : Returning error reader
2026/02/03 05:57:28 DEBUG : BOOM: simulated read failure
2026/02/03 05:57:28 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 4/10: BOOM: simulated read failure
2026/02/03 05:57:28 DEBUG : Open with options = [RangeOption(10485760,10485760)]
2026/02/03 05:57:29 DEBUG : Returning error reader
2026/02/03 05:57:29 DEBUG : BOOM: simulated read failure
2026/02/03 05:57:29 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 5/10: BOOM: simulated read failure
2026/02/03 05:57:29 DEBUG : Open with options = [RangeOption(10485760,10485760)]
2026/02/03 05:57:30 DEBUG : Returning error reader
2026/02/03 05:57:30 DEBUG : BOOM: simulated read failure
2026/02/03 05:57:30 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 6/10: BOOM: simulated read failure
2026/02/03 05:57:30 DEBUG : Open with options = [RangeOption(10485760,10485760)]
2026/02/03 05:57:31 DEBUG : Returning error reader
2026/02/03 05:57:31 DEBUG : BOOM: simulated read failure
2026/02/03 05:57:31 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 7/10: BOOM: simulated read failure
2026/02/03 05:57:31 DEBUG : Open with options = [RangeOption(10485760,10485760)]
2026/02/03 05:57:32 DEBUG : Returning error reader
2026/02/03 05:57:32 DEBUG : BOOM: simulated read failure
2026/02/03 05:57:32 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 8/10: BOOM: simulated read failure
2026/02/03 05:57:32 DEBUG : Open with options = [RangeOption(10485760,10485760)]
2026/02/03 05:57:33 DEBUG : Returning error reader
2026/02/03 05:57:33 DEBUG : BOOM: simulated read failure
2026/02/03 05:57:33 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 9/10: BOOM: simulated read failure
2026/02/03 05:57:33 DEBUG : Open with options = [RangeOption(10485760,10485760)]
2026/02/03 05:57:34 DEBUG : Returning error reader
2026/02/03 05:57:34 DEBUG : BOOM: simulated read failure
2026/02/03 05:57:34 DEBUG : test-multithread-abort: Reopening on read failure after offset 0 bytes: retry 10/10: BOOM: simulated read failure
2026/02/03 05:57:34 DEBUG : test-multithread-abort: Reopen failed after offset 0 bytes read: failed to reopen: too many retries
2026/02/03 05:57:34 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/8b8b39e8-6121-4c98-9d4b-1f372733ea6e/8b8b39e8-6121-4c98-9d4b-1f372733ea6e?uploadId=ADxpnPriOPyh-rd5OfLD_CpZqpuoxYk-OJ4oXS7cW_5-udjC2axKoSIXwi6-jcg9HQ8-kG64ecyr8gNfqD20ZTTwLJn0GKiVJwy05Ur41sWp9wEIUE6_jlJ3eovjnr1dC20XLq64qotB6BQ-nUsPHLWPQscfsomkZpKXC64K7hoAAQ3FbEp5QvAsHrZQ84IxIKkGz0G493yebzFXWoljkWXjftswbfEV6Xo23QG4MGRnAtxuI80e8g74nHAyO0yum4PQLusSTviPntxOVRaW_P4XdMfz5ZxIKQhy7DuIRW024qof259Tm3TeYFt8jKpwA7gRY_iycPgreYtbr3PzIRg&partNumber=3&X-Amz-Content-Sha256=UNSIGNED-PAYLOAD&X-Amz-Algorithm=AWS4-HMAC-SHA256&X-Amz-Credential=db9b71e81fa19eaf54eb4264526ca7a8%2F20260203%2FWEUR%2Fs3%2Faws4_request&X-Amz-Date=20260203T055724Z&X-Amz-SignedHeaders=host&X-Amz-Expires=1800&X-Amz-Signature=7f85f526e08b7a71402324a618101bfef9eaa70ba41fe903a6ef766e9e2b9e49": BOOM: simulated read failure
2026/02/03 05:57:34 DEBUG : test-multithread-abort: multi-thread copy: cancelling transfer on exit
2026/02/03 05:57:36 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/02/03 05:57:36 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/02/03 05:57:36 DEBUG : pacer: low level retry 2/10 (error Error "Server Error")
2026/02/03 05:57:36 DEBUG : pacer: Rate limited, increasing sleep to 40ms
2026/02/03 05:57:36 DEBUG : pacer: low level retry 3/10 (error Error "Server Error")
2026/02/03 05:57:36 DEBUG : pacer: Rate limited, increasing sleep to 80ms
2026/02/03 05:57:37 DEBUG : pacer: low level retry 4/10 (error Error "Server Error")
2026/02/03 05:57:37 DEBUG : pacer: Rate limited, increasing sleep to 160ms
2026/02/03 05:57:37 DEBUG : pacer: low level retry 5/10 (error Error "Server Error")
2026/02/03 05:57:37 DEBUG : pacer: Rate limited, increasing sleep to 320ms
2026/02/03 05:57:37 DEBUG : pacer: low level retry 6/10 (error Error "Server Error")
2026/02/03 05:57:37 DEBUG : pacer: Rate limited, increasing sleep to 640ms
2026/02/03 05:57:38 DEBUG : pacer: low level retry 7/10 (error Error "Server Error")
2026/02/03 05:57:38 DEBUG : pacer: Rate limited, increasing sleep to 1.28s
2026/02/03 05:57:38 DEBUG : pacer: low level retry 8/10 (error Error "Server Error")
2026/02/03 05:57:38 DEBUG : pacer: Rate limited, increasing sleep to 2.56s
2026/02/03 05:57:40 DEBUG : pacer: low level retry 9/10 (error Error "Server Error")
2026/02/03 05:57:40 DEBUG : pacer: Rate limited, increasing sleep to 5.12s
2026/02/03 05:57:42 DEBUG : pacer: low level retry 10/10 (error Error "Server Error")
2026/02/03 05:57:42 DEBUG : pacer: Rate limited, increasing sleep to 10.24s
run.go:130: removing file "test-multithread-abort" failed - try 1/3: failed to delete item: Error "Server Error"
2026/02/03 05:57:48 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/02/03 05:57:48 DEBUG : pacer: Rate limited, increasing sleep to 20s
2026/02/03 05:57:58 DEBUG : pacer: low level retry 2/10 (error Error "Server Error")
2026/02/03 05:58:18 DEBUG : pacer: low level retry 3/10 (error Error "Server Error")
2026/02/03 05:58:38 DEBUG : pacer: low level retry 4/10 (error Error "Server Error")
2026/02/03 05:58:58 DEBUG : pacer: low level retry 5/10 (error Error "Server Error")
2026/02/03 05:59:18 DEBUG : pacer: low level retry 6/10 (error Error "Server Error")
2026/02/03 05:59:38 DEBUG : pacer: low level retry 7/10 (error Error "Server Error")
2026/02/03 05:59:58 DEBUG : pacer: low level retry 8/10 (error Error "Server Error")
2026/02/03 06:00:18 DEBUG : pacer: low level retry 9/10 (error Error "Server Error")
2026/02/03 06:00:38 DEBUG : pacer: low level retry 10/10 (error Error "Server Error")
run.go:130: removing file "test-multithread-abort" failed - try 2/3: failed to delete item: Error "Server Error"
2026/02/03 06:00:58 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/02/03 06:01:18 DEBUG : pacer: low level retry 2/10 (error Error "Server Error")
2026/02/03 06:01:38 DEBUG : pacer: low level retry 3/10 (error Error "Server Error")
2026/02/03 06:01:58 DEBUG : pacer: low level retry 4/10 (error Error "Server Error")
2026/02/03 06:02:18 DEBUG : pacer: low level retry 5/10 (error Error "Server Error")
2026/02/03 06:02:38 DEBUG : pacer: low level retry 6/10 (error Error "Server Error")
2026/02/03 06:02:58 DEBUG : pacer: low level retry 7/10 (error Error "Server Error")
2026/02/03 06:03:18 DEBUG : pacer: low level retry 8/10 (error Error "Server Error")
2026/02/03 06:03:38 DEBUG : pacer: low level retry 9/10 (error Error "Server Error")
2026/02/03 06:03:58 DEBUG : pacer: low level retry 10/10 (error Error "Server Error")
run.go:130: removing file "test-multithread-abort" failed - try 3/3: failed to delete item: Error "Server Error"
run.go:133: removing file "test-multithread-abort" failed: failed to delete item: Error "Server Error"
2026/02/03 06:04:18 DEBUG : pacer: Reducing sleep to 10s
--- FAIL: TestMultithreadCopyAbort (465.31s)
=== RUN TestParseSumFile
run.go:185: Remote "drime root 'rclone-test-kibuwoh5suma'", Local "Local file system at /tmp/rclone3672422180", Modify Window "876000h0m0s"
2026/02/03 06:04:38 DEBUG : pacer: Reducing sleep to 5s
2026/02/03 06:04:50 DEBUG : pacer: Reducing sleep to 2.5s
2026/02/03 06:04:53 DEBUG : pacer: Reducing sleep to 1.25s
2026/02/03 06:04:55 DEBUG : pacer: Reducing sleep to 625ms
2026/02/03 06:04:55 NOTICE: test.sum: improperly formatted checksum line 4
2026/02/03 06:04:55 NOTICE: test.sum: improperly formatted checksum line 5
2026/02/03 06:04:55 NOTICE: test.sum: improperly formatted checksum line 6
2026/02/03 06:04:55 NOTICE: test.sum: 2 warning(s) suppressed...
2026/02/03 06:04:56 DEBUG : pacer: Reducing sleep to 312.5ms
2026/02/03 06:05:00 DEBUG : pacer: Reducing sleep to 156.25ms
2026/02/03 06:05:00 DEBUG : test.sum: Removing old object on successful upload
2026/02/03 06:05:00 DEBUG : pacer: Reducing sleep to 78.125ms
2026/02/03 06:05:00 DEBUG : pacer: Reducing sleep to 39.0625ms
2026/02/03 06:05:01 DEBUG : pacer: Reducing sleep to 19.53125ms
2026/02/03 06:05:01 NOTICE: test.sum: improperly formatted checksum line 4
2026/02/03 06:05:01 NOTICE: test.sum: improperly formatted checksum line 5
2026/02/03 06:05:01 NOTICE: test.sum: improperly formatted checksum line 6
2026/02/03 06:05:01 NOTICE: test.sum: 2 warning(s) suppressed...
2026/02/03 06:05:01 DEBUG : pacer: Reducing sleep to 10ms
2026/02/03 06:05:01 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/02/03 06:05:01 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/02/03 06:05:01 DEBUG : pacer: low level retry 2/10 (error Error "Server Error")
2026/02/03 06:05:01 DEBUG : pacer: Rate limited, increasing sleep to 40ms
2026/02/03 06:05:02 DEBUG : pacer: low level retry 3/10 (error Error "Server Error")
2026/02/03 06:05:02 DEBUG : pacer: Rate limited, increasing sleep to 80ms
2026/02/03 06:05:02 DEBUG : pacer: low level retry 4/10 (error Error "Server Error")
2026/02/03 06:05:02 DEBUG : pacer: Rate limited, increasing sleep to 160ms
2026/02/03 06:05:02 DEBUG : pacer: low level retry 5/10 (error Error "Server Error")
2026/02/03 06:05:02 DEBUG : pacer: Rate limited, increasing sleep to 320ms
2026/02/03 06:05:02 DEBUG : pacer: low level retry 6/10 (error Error "Server Error")
2026/02/03 06:05:02 DEBUG : pacer: Rate limited, increasing sleep to 640ms
2026/02/03 06:05:03 DEBUG : pacer: low level retry 7/10 (error Error "Server Error")
2026/02/03 06:05:03 DEBUG : pacer: Rate limited, increasing sleep to 1.28s
2026/02/03 06:05:03 DEBUG : pacer: low level retry 8/10 (error Error "Server Error")
2026/02/03 06:05:03 DEBUG : pacer: Rate limited, increasing sleep to 2.56s
2026/02/03 06:05:04 DEBUG : pacer: low level retry 9/10 (error Error "Server Error")
2026/02/03 06:05:04 DEBUG : pacer: Rate limited, increasing sleep to 5.12s
2026/02/03 06:05:07 DEBUG : pacer: low level retry 10/10 (error Error "Server Error")
2026/02/03 06:05:07 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/03 06:05:12 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/02/03 06:05:12 DEBUG : pacer: Rate limited, increasing sleep to 20s
2026/02/03 06:05:22 DEBUG : pacer: low level retry 2/10 (error Error "Server Error")
2026/02/03 06:05:42 DEBUG : pacer: low level retry 3/10 (error Error "Server Error")
2026/02/03 06:06:03 DEBUG : pacer: low level retry 4/10 (error Error "Server Error")
2026/02/03 06:06:22 DEBUG : pacer: low level retry 5/10 (error Error "Server Error")
2026/02/03 06:06:42 DEBUG : pacer: low level retry 6/10 (error Error "Server Error")
2026/02/03 06:07:02 DEBUG : pacer: low level retry 7/10 (error Error "Server Error")
2026/02/03 06:07:22 DEBUG : pacer: low level retry 8/10 (error Error "Server Error")
2026/02/03 06:07:42 DEBUG : pacer: low level retry 9/10 (error Error "Server Error")
2026/02/03 06:08:02 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/03 06:08:23 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/02/03 06:08:43 DEBUG : pacer: low level retry 2/10 (error Error "Server Error")
2026/02/03 06:09:03 DEBUG : pacer: low level retry 3/10 (error Error "Server Error")
2026/02/03 06:09:23 DEBUG : pacer: low level retry 4/10 (error Error "Server Error")
2026/02/03 06:09:43 DEBUG : pacer: low level retry 5/10 (error Error "Server Error")
2026/02/03 06:10:03 DEBUG : pacer: low level retry 6/10 (error Error "Server Error")
2026/02/03 06:10:23 DEBUG : pacer: low level retry 7/10 (error Error "Server Error")
2026/02/03 06:10:43 DEBUG : pacer: low level retry 8/10 (error Error "Server Error")
2026/02/03 06:11:03 DEBUG : pacer: low level retry 9/10 (error Error "Server Error")
2026/02/03 06:11:23 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/03 06:11:43 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/03 06:12:03 DEBUG : pacer: Reducing sleep to 5s
2026/02/03 06:12:13 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/03 06:12:18 DEBUG : pacer: Reducing sleep to 1.25s
2026/02/03 06:12:20 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 (486.46s)
=== RUN TestCheckSumDownload
run.go:185: Remote "drime root 'rclone-test-kibuwoh5suma'", Local "Local file system at /tmp/rclone3672422180", Modify Window "876000h0m0s"
2026/02/03 06:12:24 DEBUG : Creating backend with remote "TestDrime:rclone-test-kibuwoh5suma/data"
2026/02/03 06:12:24 DEBUG : Config file has changed externally - reloading
2026/02/03 06:12:26 DEBUG : pacer: Reducing sleep to 312.5ms
2026/02/03 06:12:26 DEBUG : pacer: Reducing sleep to 156.25ms
2026/02/03 06:12:27 DEBUG : pacer: Reducing sleep to 78.125ms
2026/02/03 06:12:27 DEBUG : pacer: Reducing sleep to 39.0625ms
2026/02/03 06:12:29 DEBUG : pacer: Reducing sleep to 19.53125ms
2026/02/03 06:12:29 DEBUG : pacer: Reducing sleep to 10ms
2026/02/03 06:12:31 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:292: Sleeping for 7s just to make sure
=== RUN TestCheckSumDownload/subtest1
2026/02/03 06:12:51 DEBUG : test.sum: Removing old object on successful upload
=== RUN TestCheckSumDownload/subtest2
2026/02/03 06:12:55 DEBUG : test.sum: Removing old object on successful upload
2026/02/03 06:12:56 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/02/03 06:12:56 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/02/03 06:12:56 DEBUG : pacer: low level retry 2/10 (error Error "Server Error")
2026/02/03 06:12:56 DEBUG : pacer: Rate limited, increasing sleep to 40ms
2026/02/03 06:12:56 DEBUG : pacer: low level retry 3/10 (error Error "Server Error")
2026/02/03 06:12:56 DEBUG : pacer: Rate limited, increasing sleep to 80ms
2026/02/03 06:12:56 DEBUG : pacer: low level retry 4/10 (error Error "Server Error")
2026/02/03 06:12:56 DEBUG : pacer: Rate limited, increasing sleep to 160ms
2026/02/03 06:12:56 DEBUG : pacer: low level retry 5/10 (error Error "Server Error")
2026/02/03 06:12:56 DEBUG : pacer: Rate limited, increasing sleep to 320ms
2026/02/03 06:12:57 DEBUG : pacer: low level retry 6/10 (error Error "Server Error")
2026/02/03 06:12:57 DEBUG : pacer: Rate limited, increasing sleep to 640ms
2026/02/03 06:12:57 DEBUG : pacer: low level retry 7/10 (error Error "Server Error")
2026/02/03 06:12:57 DEBUG : pacer: Rate limited, increasing sleep to 1.28s
2026/02/03 06:12:58 DEBUG : pacer: low level retry 8/10 (error Error "Server Error")
2026/02/03 06:12:58 DEBUG : pacer: Rate limited, increasing sleep to 2.56s
2026/02/03 06:12:59 DEBUG : pacer: low level retry 9/10 (error Error "Server Error")
2026/02/03 06:12:59 DEBUG : pacer: Rate limited, increasing sleep to 5.12s
2026/02/03 06:13:02 DEBUG : pacer: low level retry 10/10 (error Error "Server Error")
2026/02/03 06:13:02 DEBUG : pacer: Rate limited, increasing sleep to 10.24s
=== NAME TestCheckSumDownload
run.go:286: Retry Put of "test.sum" to drime root 'rclone-test-kibuwoh5suma': 1/10 (failed to delete existing object: failed to delete item: Error "Server Error")
2026/02/03 06:13:07 DEBUG : pacer: Reducing sleep to 5.12s
2026/02/03 06:13:19 DEBUG : pacer: Reducing sleep to 2.56s
2026/02/03 06:13:19 DEBUG : test.sum: Removing old object on successful upload
2026/02/03 06:13:22 DEBUG : pacer: Reducing sleep to 1.28s
2026/02/03 06:13:25 DEBUG : pacer: Reducing sleep to 640ms
2026/02/03 06:13:26 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/03 06:13:28 DEBUG : pacer: Reducing sleep to 160ms
2026/02/03 06:13:28 DEBUG : pacer: Reducing sleep to 80ms
2026/02/03 06:13:28 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/03 06:13:30 DEBUG : pacer: Reducing sleep to 20ms
2026/02/03 06:13:31 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:464
/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 (41), 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:464
/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/subtest3
2026/02/03 06:13:40 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:478
/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 (41), 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:478
/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/subtest4
2026/02/03 06:13:56 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 (123), test.sum (41)
fstest.go:191:
Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350
/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345
/home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:493
/home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:541
Error: Should be true
Test: TestCheckSumDownload
Messages: Unexpected file "test.sum"
=== RUN TestCheckSumDownload/subtest5
2026/02/03 06:14:09 DEBUG : test.sum: Removing old object on successful upload
=== NAME TestCheckSumDownload
fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3
fstest.go:301: Flushing the directory cache
fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3
fstest.go:301: Flushing the directory cache
fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3
fstest.go:301: Flushing the directory cache
fstest.go:305:
Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350
/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345
/home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:508
/home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:541
/usr/local/go/src/runtime/asm_amd64.s:1700
Error: Should be true
Test: TestCheckSumDownload
Messages: listing wrong, want
data/banana (13), data/potato (15), test.sum (123) got
data/banana (13), data/potato (15), test.sum (123), test.sum (41)
fstest.go:191:
Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350
/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345
/home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:508
/home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:541
Error: Should be true
Test: TestCheckSumDownload
Messages: Unexpected file "test.sum"
=== RUN TestCheckSumDownload/subtest6
2026/02/03 06:14:29 DEBUG : data/banana: Removing old object on successful upload
2026/02/03 06:14:34 DEBUG : data/potato: Removing old object on successful upload
2026/02/03 06:14:38 DEBUG : test.sum: Removing old object on successful upload
=== NAME TestCheckSumDownload
fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3
fstest.go:301: Flushing the directory cache
fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3
fstest.go:301: Flushing the directory cache
fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3
fstest.go:301: Flushing the directory cache
fstest.go:305:
Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350
/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345
/home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:525
/home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:541
/usr/local/go/src/runtime/asm_amd64.s:1700
Error: Should be true
Test: TestCheckSumDownload
Messages: listing wrong, want
data/banana (13), data/potato (15), test.sum (82) got
data/banana (13), data/potato (15), test.sum (123), test.sum (41)
fstest.go:150:
Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:150
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:195
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350
/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345
/home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:525
/home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:541
Error: Not equal:
expected: 82
actual : 123
Test: TestCheckSumDownload
Messages: test.sum: size incorrect file=82 vs obj=123
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/03 06:14:51 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/02/03 06:14:51 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/02/03 06:14:51 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:305:
Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338
/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178
/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393
/usr/local/go/src/testing/testing.go:1211
/usr/local/go/src/testing/testing.go:1445
/usr/local/go/src/testing/testing.go:1786
Error: Should be true
Test: TestCheckSumDownload
Messages: listing wrong, want
got
data/banana (13), data/potato (15)
fstest.go:191:
Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338
/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178
/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393
/usr/local/go/src/testing/testing.go:1211
/usr/local/go/src/testing/testing.go:1445
/usr/local/go/src/testing/testing.go:1786
Error: Should be true
Test: TestCheckSumDownload
Messages: Unexpected file "data/banana"
fstest.go:191:
Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338
/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178
/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393
/usr/local/go/src/testing/testing.go:1211
/usr/local/go/src/testing/testing.go:1445
/usr/local/go/src/testing/testing.go:1786
Error: Should be true
Test: TestCheckSumDownload
Messages: Unexpected file "data/potato"
fstest.go:327:
Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:327
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338
/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178
/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393
/usr/local/go/src/testing/testing.go:1211
/usr/local/go/src/testing/testing.go:1445
/usr/local/go/src/testing/testing.go:1786
Error: Not equal:
expected: []string{}
actual : []string{"data"}
Diff:
--- Expected
+++ Actual
@@ -1,2 +1,3 @@
-([]string) {
+([]string) (len=1) {
+ (string) (len=4) "data"
}
Test: TestCheckSumDownload
Messages: directories
--- FAIL: TestCheckSumDownload (160.81s)
--- PASS: TestCheckSumDownload/subtest1 (1.63s)
--- PASS: TestCheckSumDownload/subtest2 (0.96s)
--- PASS: TestCheckSumDownload/subtest3 (2.10s)
--- PASS: TestCheckSumDownload/subtest4 (1.34s)
--- PASS: TestCheckSumDownload/subtest5 (1.83s)
--- PASS: TestCheckSumDownload/subtest6 (1.91s)
--- PASS: TestCheckSumDownload/subtest7 (1.67s)
=== RUN TestApplyTransforms
2026/02/03 06:15:05 DEBUG : Creating backend with remote "TestDrime:rclone-test-qihaful5xudu"
2026/02/03 06:15:05 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/02/03 06:15:06 DEBUG : Creating backend with remote "/tmp/rclone2079723593"
run.go:185: Remote "drime root 'rclone-test-qihaful5xudu'", Local "Local file system at /tmp/rclone2079723593", Modify Window "876000h0m0s"
upper checkfile vs. lower remote (without normalization)
2026/02/03 06:15:08 ERROR : HELLO, WORLD!: file not in drime root 'rclone-test-qihaful5xudu'
2026/02/03 06:15:08 NOTICE: drime root 'rclone-test-qihaful5xudu': 1 files missing
2026/02/03 06:15:08 NOTICE: drime root 'rclone-test-qihaful5xudu': 0 differences found
2026/02/03 06:15:08 NOTICE: drime root 'rclone-test-qihaful5xudu': 1 errors while checking
upper checkfile vs. lower remote (with normalization)
2026/02/03 06:15:09 ERROR : hello, world!: file not in drime root 'rclone-test-qihaful5xudu'
2026/02/03 06:15:09 NOTICE: drime root 'rclone-test-qihaful5xudu': 1 files missing
2026/02/03 06:15:09 NOTICE: drime root 'rclone-test-qihaful5xudu': 0 differences found
2026/02/03 06:15:09 NOTICE: drime root 'rclone-test-qihaful5xudu': 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-qihaful5xudu'
Test: TestApplyTransforms
Messages: unexpected error for upper checkfile vs. lower remote (with normalization) HELLO, WORLD! hello, world!
2026/02/03 06:15:09 DEBUG : Creating backend with remote "TestDrime:rclone-test-horitef0mevo"
2026/02/03 06:15:09 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/02/03 06:15:09 DEBUG : Creating backend with remote "/tmp/rclone102431810"
run.go:185: Remote "drime root 'rclone-test-horitef0mevo'", Local "Local file system at /tmp/rclone102431810", Modify Window "876000h0m0s"
lower checkfile vs. upper remote (without normalization)
2026/02/03 06:15:12 ERROR : hello, world!: file not in drime root 'rclone-test-horitef0mevo'
2026/02/03 06:15:12 NOTICE: drime root 'rclone-test-horitef0mevo': 1 files missing
2026/02/03 06:15:12 NOTICE: drime root 'rclone-test-horitef0mevo': 0 differences found
2026/02/03 06:15:12 NOTICE: drime root 'rclone-test-horitef0mevo': 1 errors while checking
lower checkfile vs. upper remote (with normalization)
2026/02/03 06:15:12 ERROR : hello, world!: file not in drime root 'rclone-test-horitef0mevo'
2026/02/03 06:15:12 NOTICE: drime root 'rclone-test-horitef0mevo': 1 files missing
2026/02/03 06:15:12 NOTICE: drime root 'rclone-test-horitef0mevo': 0 differences found
2026/02/03 06:15:12 NOTICE: drime root 'rclone-test-horitef0mevo': 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-horitef0mevo'
Test: TestApplyTransforms
Messages: unexpected error for lower checkfile vs. upper remote (with normalization) hello, world! HELLO, WORLD!
2026/02/03 06:15:12 DEBUG : Creating backend with remote "TestDrime:rclone-test-qebucag5ziye"
2026/02/03 06:15:12 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/02/03 06:15:12 DEBUG : Creating backend with remote "/tmp/rclone830340859"
run.go:185: Remote "drime root 'rclone-test-qebucag5ziye'", Local "Local file system at /tmp/rclone830340859", Modify Window "876000h0m0s"
lower checkfile vs. upperlowermixed remote (without normalization)
2026/02/03 06:15:16 ERROR : hello, world!: file not in drime root 'rclone-test-qebucag5ziye'
2026/02/03 06:15:16 NOTICE: drime root 'rclone-test-qebucag5ziye': 1 files missing
2026/02/03 06:15:16 NOTICE: drime root 'rclone-test-qebucag5ziye': 0 differences found
2026/02/03 06:15:16 NOTICE: drime root 'rclone-test-qebucag5ziye': 1 errors while checking
lower checkfile vs. upperlowermixed remote (with normalization)
2026/02/03 06:15:16 ERROR : hello, world!: file not in drime root 'rclone-test-qebucag5ziye'
2026/02/03 06:15:16 NOTICE: drime root 'rclone-test-qebucag5ziye': 1 files missing
2026/02/03 06:15:16 NOTICE: drime root 'rclone-test-qebucag5ziye': 0 differences found
2026/02/03 06:15:16 NOTICE: drime root 'rclone-test-qebucag5ziye': 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-qebucag5ziye'
Test: TestApplyTransforms
Messages: unexpected error for lower checkfile vs. upperlowermixed remote (with normalization) hello, world! HeLlO, wOrLd!
2026/02/03 06:15:16 DEBUG : Creating backend with remote "TestDrime:rclone-test-raqoteq6xeze"
2026/02/03 06:15:16 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/02/03 06:15:17 DEBUG : Creating backend with remote "/tmp/rclone2516966484"
run.go:185: Remote "drime root 'rclone-test-raqoteq6xeze'", Local "Local file system at /tmp/rclone2516966484", Modify Window "876000h0m0s"
upperlowermixed checkfile vs. upper remote (without normalization)
2026/02/03 06:15:20 ERROR : HeLlO, wOrLd!: file not in drime root 'rclone-test-raqoteq6xeze'
2026/02/03 06:15:20 NOTICE: drime root 'rclone-test-raqoteq6xeze': 1 files missing
2026/02/03 06:15:20 NOTICE: drime root 'rclone-test-raqoteq6xeze': 0 differences found
2026/02/03 06:15:20 NOTICE: drime root 'rclone-test-raqoteq6xeze': 1 errors while checking
upperlowermixed checkfile vs. upper remote (with normalization)
2026/02/03 06:15:20 ERROR : hello, world!: file not in drime root 'rclone-test-raqoteq6xeze'
2026/02/03 06:15:20 NOTICE: drime root 'rclone-test-raqoteq6xeze': 1 files missing
2026/02/03 06:15:20 NOTICE: drime root 'rclone-test-raqoteq6xeze': 0 differences found
2026/02/03 06:15:20 NOTICE: drime root 'rclone-test-raqoteq6xeze': 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-raqoteq6xeze'
Test: TestApplyTransforms
Messages: unexpected error for upperlowermixed checkfile vs. upper remote (with normalization) HeLlO, wOrLd! HELLO, WORLD!
2026/02/03 06:15:20 DEBUG : Creating backend with remote "TestDrime:rclone-test-fiyedop1gaha"
2026/02/03 06:15:20 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/02/03 06:15:21 DEBUG : Creating backend with remote "/tmp/rclone1444289175"
run.go:185: Remote "drime root 'rclone-test-fiyedop1gaha'", Local "Local file system at /tmp/rclone1444289175", Modify Window "876000h0m0s"
NFD checkfile vs. NFC remote (without normalization)
2026/02/03 06:15:25 ERROR : 測試_Русский___ě_áñ: file not in drime root 'rclone-test-fiyedop1gaha'
2026/02/03 06:15:25 NOTICE: drime root 'rclone-test-fiyedop1gaha': 1 files missing
2026/02/03 06:15:25 NOTICE: drime root 'rclone-test-fiyedop1gaha': 0 differences found
2026/02/03 06:15:25 NOTICE: drime root 'rclone-test-fiyedop1gaha': 1 errors while checking
NFD checkfile vs. NFC remote (with normalization)
2026/02/03 06:15:26 ERROR : 測試_русский___ě_áñ: file not in drime root 'rclone-test-fiyedop1gaha'
2026/02/03 06:15:26 NOTICE: drime root 'rclone-test-fiyedop1gaha': 1 files missing
2026/02/03 06:15:26 NOTICE: drime root 'rclone-test-fiyedop1gaha': 0 differences found
2026/02/03 06:15:26 NOTICE: drime root 'rclone-test-fiyedop1gaha': 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-fiyedop1gaha'
Test: TestApplyTransforms
Messages: unexpected error for NFD checkfile vs. NFC remote (with normalization) 測試_Русский___ě_áñ 測試_Русский___ě_áñ
2026/02/03 06:15:26 DEBUG : Creating backend with remote "TestDrime:rclone-test-zizepec5goze"
2026/02/03 06:15:26 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/02/03 06:15:26 DEBUG : Creating backend with remote "/tmp/rclone3860242479"
run.go:185: Remote "drime root 'rclone-test-zizepec5goze'", Local "Local file system at /tmp/rclone3860242479", Modify Window "876000h0m0s"
NFC checkfile vs. NFD remote (without normalization)
2026/02/03 06:15:31 ERROR : 測試_Русский___ě_áñ: file not in drime root 'rclone-test-zizepec5goze'
2026/02/03 06:15:31 NOTICE: drime root 'rclone-test-zizepec5goze': 1 files missing
2026/02/03 06:15:31 NOTICE: drime root 'rclone-test-zizepec5goze': 0 differences found
2026/02/03 06:15:31 NOTICE: drime root 'rclone-test-zizepec5goze': 1 errors while checking
NFC checkfile vs. NFD remote (with normalization)
2026/02/03 06:15:31 ERROR : 測試_русский___ě_áñ: file not in drime root 'rclone-test-zizepec5goze'
2026/02/03 06:15:31 NOTICE: drime root 'rclone-test-zizepec5goze': 1 files missing
2026/02/03 06:15:31 NOTICE: drime root 'rclone-test-zizepec5goze': 0 differences found
2026/02/03 06:15:31 NOTICE: drime root 'rclone-test-zizepec5goze': 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-zizepec5goze'
Test: TestApplyTransforms
Messages: unexpected error for NFC checkfile vs. NFD remote (with normalization) 測試_Русский___ě_áñ 測試_Русский___ě_áñ
2026/02/03 06:15:31 DEBUG : Creating backend with remote "TestDrime:rclone-test-cohobeg4nule"
2026/02/03 06:15:31 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/02/03 06:15:32 DEBUG : Creating backend with remote "/tmp/rclone929420938"
run.go:185: Remote "drime root 'rclone-test-cohobeg4nule'", Local "Local file system at /tmp/rclone929420938", Modify Window "876000h0m0s"
NFDx2 checkfile vs. both remote (without normalization)
2026/02/03 06:15:34 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in drime root 'rclone-test-cohobeg4nule'
2026/02/03 06:15:34 NOTICE: drime root 'rclone-test-cohobeg4nule': 1 files missing
2026/02/03 06:15:34 NOTICE: drime root 'rclone-test-cohobeg4nule': 0 differences found
2026/02/03 06:15:34 NOTICE: drime root 'rclone-test-cohobeg4nule': 1 errors while checking
NFDx2 checkfile vs. both remote (with normalization)
2026/02/03 06:15:35 ERROR : 測試_русский___ě_áñ測試_русский___ě_áñ: file not in drime root 'rclone-test-cohobeg4nule'
2026/02/03 06:15:35 NOTICE: drime root 'rclone-test-cohobeg4nule': 1 files missing
2026/02/03 06:15:35 NOTICE: drime root 'rclone-test-cohobeg4nule': 0 differences found
2026/02/03 06:15:35 NOTICE: drime root 'rclone-test-cohobeg4nule': 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-cohobeg4nule'
Test: TestApplyTransforms
Messages: unexpected error for NFDx2 checkfile vs. both remote (with normalization) 測試_Русский___ě_áñ測試_Русский___ě_áñ 測試_Русский___ě_áñ測試_Русский___ě_áñ
2026/02/03 06:15:35 DEBUG : Creating backend with remote "TestDrime:rclone-test-zaxorot0hope"
2026/02/03 06:15:35 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/02/03 06:15:35 DEBUG : Creating backend with remote "/tmp/rclone2313979396"
run.go:185: Remote "drime root 'rclone-test-zaxorot0hope'", Local "Local file system at /tmp/rclone2313979396", Modify Window "876000h0m0s"
NFCx2 checkfile vs. both remote (without normalization)
2026/02/03 06:15:38 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in drime root 'rclone-test-zaxorot0hope'
2026/02/03 06:15:38 NOTICE: drime root 'rclone-test-zaxorot0hope': 1 files missing
2026/02/03 06:15:38 NOTICE: drime root 'rclone-test-zaxorot0hope': 0 differences found
2026/02/03 06:15:38 NOTICE: drime root 'rclone-test-zaxorot0hope': 1 errors while checking
NFCx2 checkfile vs. both remote (with normalization)
2026/02/03 06:15:38 ERROR : 測試_русский___ě_áñ測試_русский___ě_áñ: file not in drime root 'rclone-test-zaxorot0hope'
2026/02/03 06:15:38 NOTICE: drime root 'rclone-test-zaxorot0hope': 1 files missing
2026/02/03 06:15:38 NOTICE: drime root 'rclone-test-zaxorot0hope': 0 differences found
2026/02/03 06:15:38 NOTICE: drime root 'rclone-test-zaxorot0hope': 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-zaxorot0hope'
Test: TestApplyTransforms
Messages: unexpected error for NFCx2 checkfile vs. both remote (with normalization) 測試_Русский___ě_áñ測試_Русский___ě_áñ 測試_Русский___ě_áñ測試_Русский___ě_áñ
2026/02/03 06:15:38 DEBUG : Creating backend with remote "TestDrime:rclone-test-mufecin1caxo"
2026/02/03 06:15:38 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/02/03 06:15:38 DEBUG : Creating backend with remote "/tmp/rclone1480436721"
run.go:185: Remote "drime root 'rclone-test-mufecin1caxo'", Local "Local file system at /tmp/rclone1480436721", Modify Window "876000h0m0s"
both checkfile vs. NFDx2 remote (without normalization)
2026/02/03 06:15:46 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in drime root 'rclone-test-mufecin1caxo'
2026/02/03 06:15:46 NOTICE: drime root 'rclone-test-mufecin1caxo': 1 files missing
2026/02/03 06:15:46 NOTICE: drime root 'rclone-test-mufecin1caxo': 0 differences found
2026/02/03 06:15:46 NOTICE: drime root 'rclone-test-mufecin1caxo': 1 errors while checking
both checkfile vs. NFDx2 remote (with normalization)
2026/02/03 06:15:46 ERROR : 測試_русский___ě_áñ測試_русский___ě_áñ: file not in drime root 'rclone-test-mufecin1caxo'
2026/02/03 06:15:46 NOTICE: drime root 'rclone-test-mufecin1caxo': 1 files missing
2026/02/03 06:15:46 NOTICE: drime root 'rclone-test-mufecin1caxo': 0 differences found
2026/02/03 06:15:46 NOTICE: drime root 'rclone-test-mufecin1caxo': 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-mufecin1caxo'
Test: TestApplyTransforms
Messages: unexpected error for both checkfile vs. NFDx2 remote (with normalization) 測試_Русский___ě_áñ測試_Русский___ě_áñ 測試_Русский___ě_áñ測試_Русский___ě_áñ
2026/02/03 06:15:46 DEBUG : Creating backend with remote "TestDrime:rclone-test-kojubay4dire"
2026/02/03 06:15:46 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/02/03 06:15:47 DEBUG : Creating backend with remote "/tmp/rclone2999710755"
run.go:185: Remote "drime root 'rclone-test-kojubay4dire'", Local "Local file system at /tmp/rclone2999710755", Modify Window "876000h0m0s"
both checkfile vs. NFCx2 remote (without normalization)
2026/02/03 06:15:50 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in drime root 'rclone-test-kojubay4dire'
2026/02/03 06:15:50 NOTICE: drime root 'rclone-test-kojubay4dire': 1 files missing
2026/02/03 06:15:50 NOTICE: drime root 'rclone-test-kojubay4dire': 0 differences found
2026/02/03 06:15:50 NOTICE: drime root 'rclone-test-kojubay4dire': 1 errors while checking
both checkfile vs. NFCx2 remote (with normalization)
2026/02/03 06:15:50 ERROR : 測試_русский___ě_áñ測試_русский___ě_áñ: file not in drime root 'rclone-test-kojubay4dire'
2026/02/03 06:15:50 NOTICE: drime root 'rclone-test-kojubay4dire': 1 files missing
2026/02/03 06:15:50 NOTICE: drime root 'rclone-test-kojubay4dire': 0 differences found
2026/02/03 06:15:50 NOTICE: drime root 'rclone-test-kojubay4dire': 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-kojubay4dire'
Test: TestApplyTransforms
Messages: unexpected error for both checkfile vs. NFCx2 remote (with normalization) 測試_Русский___ě_áñ測試_Русский___ě_áñ 測試_Русский___ě_áñ測試_Русский___ě_áñ
2026/02/03 06:15:50 DEBUG : drime root 'rclone-test-kojubay4dire': Purge remote
2026/02/03 06:15:50 DEBUG : drime root 'rclone-test-mufecin1caxo': Purge remote
2026/02/03 06:15:50 DEBUG : drime root 'rclone-test-zaxorot0hope': Purge remote
2026/02/03 06:15:51 DEBUG : drime root 'rclone-test-cohobeg4nule': Purge remote
2026/02/03 06:15:51 DEBUG : drime root 'rclone-test-zizepec5goze': Purge remote
2026/02/03 06:15:51 DEBUG : drime root 'rclone-test-fiyedop1gaha': Purge remote
2026/02/03 06:15:52 DEBUG : drime root 'rclone-test-raqoteq6xeze': Purge remote
2026/02/03 06:15:52 DEBUG : drime root 'rclone-test-qebucag5ziye': Purge remote
2026/02/03 06:15:52 DEBUG : drime root 'rclone-test-horitef0mevo': Purge remote
2026/02/03 06:15:53 DEBUG : drime root 'rclone-test-qihaful5xudu': Purge remote
--- FAIL: TestApplyTransforms (47.87s)
=== RUN TestCopyFile
run.go:185: Remote "drime root 'rclone-test-kibuwoh5suma'", Local "Local file system at /tmp/rclone3672422180", Modify Window "876000h0m0s"
2026/02/03 06:15:53 DEBUG : file1: Need to transfer - File not found at Destination
2026/02/03 06:15:55 DEBUG : sub/file2: size = 14 OK
2026/02/03 06:15:55 INFO : file1: Copied (new) to: sub/file2
2026/02/03 06:15:56 DEBUG : sub/file2: size = 14 OK
2026/02/03 06:15:56 DEBUG : file1: Sizes identical
2026/02/03 06:15:56 DEBUG : file1: Unchanged skipping
2026/02/03 06:15:56 DEBUG : drime root 'rclone-test-kibuwoh5suma': 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 (18.36s)
=== RUN TestCopyFileCopyDest
run.go:185: Remote "drime root 'rclone-test-kibuwoh5suma'", Local "Local file system at /tmp/rclone3672422180", Modify Window "876000h0m0s"
2026/02/03 06:16:11 DEBUG : Creating backend with remote "TestDrime:rclone-test-kibuwoh5suma/dst"
2026/02/03 06:16:12 DEBUG : Creating backend with remote "TestDrime:rclone-test-kibuwoh5suma/CopyDest"
2026/02/03 06:16:13 DEBUG : one: Need to transfer - File not found at Destination
2026/02/03 06:16:18 DEBUG : one: size = 3 OK
2026/02/03 06:16:18 INFO : one: Copied (new)
2026/02/03 06:16:20 DEBUG : one: size = 5 (Local file system at /tmp/rclone3672422180)
2026/02/03 06:16:20 DEBUG : one: size = 3 (drime root 'rclone-test-kibuwoh5suma/dst')
2026/02/03 06:16:20 DEBUG : one: Sizes differ
2026/02/03 06:16:21 DEBUG : one: Removing old object on successful upload
2026/02/03 06:16:21 DEBUG : one: size = 5 OK
2026/02/03 06:16:21 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/03 06:16:35 DEBUG : dst/one: Removing old object on successful upload
fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3
fstest.go:301: Flushing the directory cache
fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3
fstest.go:301: Flushing the directory cache
fstest.go:292: Sleeping for 7s just to make sure
2026/02/03 06:16:50 DEBUG : Creating backend with remote "TestDrime:rclone-test-kibuwoh5suma/BackupDir"
2026/02/03 06:16:51 DEBUG : one: size = 5 (Local file system at /tmp/rclone3672422180)
2026/02/03 06:16:51 DEBUG : one: size = 3 (drime root 'rclone-test-kibuwoh5suma/dst')
2026/02/03 06:16:51 DEBUG : one: Sizes differ
2026/02/03 06:16:53 DEBUG : one: size = 5 OK
2026/02/03 06:16:53 DEBUG : one: Sizes identical
2026/02/03 06:16:53 DEBUG : one: size = 5 (Local file system at /tmp/rclone3672422180)
2026/02/03 06:16:53 DEBUG : one: size = 3 (drime root 'rclone-test-kibuwoh5suma/dst')
2026/02/03 06:16:53 DEBUG : one: Sizes differ
2026/02/03 06:16:54 INFO : one: Moved (server-side)
2026/02/03 06:16:55 DEBUG : one: size = 5 OK
2026/02/03 06:16:55 INFO : one: Copied (server-side copy)
2026/02/03 06:16:55 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/03 06:17:17 DEBUG : two: Need to transfer - File not found at Destination
2026/02/03 06:17:17 DEBUG : two: size = 3 OK
2026/02/03 06:17:17 DEBUG : two: Sizes identical
2026/02/03 06:17:18 DEBUG : two: size = 3 OK
2026/02/03 06:17:18 INFO : two: Copied (server-side copy)
2026/02/03 06:17:18 DEBUG : two: Destination found in --copy-dest, using server-side copy
2026/02/03 06:17:20 DEBUG : two: size = 3 OK
2026/02/03 06:17:20 DEBUG : two: Sizes identical
2026/02/03 06:17:20 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:292: Sleeping for 7s just to make sure
2026/02/03 06:17:36 DEBUG : three: Need to transfer - File not found at Destination
2026/02/03 06:17:36 DEBUG : three: size = 7 (Local file system at /tmp/rclone3672422180)
2026/02/03 06:17:36 DEBUG : three: size = 5 (drime root 'rclone-test-kibuwoh5suma/CopyDest')
2026/02/03 06:17:36 DEBUG : three: Sizes differ
2026/02/03 06:17:36 DEBUG : three: Destination not found in --copy-dest
2026/02/03 06:17:41 DEBUG : three: size = 7 OK
2026/02/03 06:17:41 INFO : three: Copied (new)
fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3
fstest.go:301: Flushing the directory cache
fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3
fstest.go:301: Flushing the directory cache
fstest.go:292: Sleeping for 7s just to make sure
run.go:130: removing dir "CopyDest" failed - try 1/3: directory not empty
run.go:130: removing dir "CopyDest" failed - try 2/3: directory not empty
run.go:130: removing dir "CopyDest" failed - try 3/3: directory not empty
run.go:133: removing dir "CopyDest" failed: directory not empty
run.go:130: removing dir "BackupDir" failed - try 1/3: directory not empty
run.go:130: removing dir "BackupDir" failed - try 2/3: directory not empty
run.go:130: removing dir "BackupDir" failed - try 3/3: directory not empty
run.go:133: removing dir "BackupDir" 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{"BackupDir", "CopyDest"}
Diff:
--- Expected
+++ Actual
@@ -1,2 +1,4 @@
-([]string) {
+([]string) (len=2) {
+ (string) (len=9) "BackupDir",
+ (string) (len=8) "CopyDest"
}
Test: TestCopyFileCopyDest
Messages: directories
--- FAIL: TestCopyFileCopyDest (122.48s)
=== RUN TestCopyFileMaxTransfer
run.go:185: Remote "drime root 'rclone-test-kibuwoh5suma'", Local "Local file system at /tmp/rclone3672422180", Modify Window "876000h0m0s"
2026/02/03 06:18:14 DEBUG : TestCopyFileMaxTransfer/file1: Need to transfer - File not found at Destination
2026/02/03 06:18:17 DEBUG : TestCopyFileMaxTransfer/file1: size = 14 OK
2026/02/03 06:18:17 INFO : TestCopyFileMaxTransfer/file1: Copied (new)
2026/02/03 06:18:18 DEBUG : TestCopyFileMaxTransfer/file2: Need to transfer - File not found at Destination
2026/02/03 06:18:18 ERROR : TestCopyFileMaxTransfer/file2: Failed to copy: failed to upload file: Post "https://app.drime.cloud/api/v1/uploads": failed to copy data: max transfer limit reached as set by --max-transfer
2026/02/03 06:18:19 DEBUG : TestCopyFileMaxTransfer/file3: Need to transfer - File not found at Destination
2026/02/03 06:18:20 DEBUG : TestCopyFileMaxTransfer/file4: Need to transfer - File not found at Destination
2026/02/03 06:18:23 DEBUG : TestCopyFileMaxTransfer/file4: size = 2062 OK
2026/02/03 06:18:23 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 (27.88s)
=== RUN TestListDirSorted
run.go:185: Remote "drime root 'rclone-test-kibuwoh5suma'", Local "Local file system at /tmp/rclone3672422180", Modify Window "876000h0m0s"
fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3
fstest.go:301: Flushing the directory cache
fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3
fstest.go:301: Flushing the directory cache
fstest.go:292: Sleeping for 7s just to make sure
2026/02/03 06:19:16 DEBUG : a.txt: Excluded (Size Filter)
2026/02/03 06:19:16 DEBUG : a.txt: Excluded
2026/02/03 06:19:16 DEBUG : sub dir/hello world2: Excluded (Size Filter)
2026/02/03 06:19:16 DEBUG : sub dir/hello world2: Excluded
2026/02/03 06:19:16 DEBUG : sub dir/hello world: Excluded (Size Filter)
2026/02/03 06:19:16 DEBUG : sub dir/hello world: Excluded
2026/02/03 06:19:17 DEBUG : sub dir/ignore dir: Excluded
2026/02/03 06:19:17 DEBUG : sub dir/hello world2: Excluded (Size Filter)
2026/02/03 06:19:17 DEBUG : sub dir/hello world2: Excluded
2026/02/03 06:19:17 DEBUG : sub dir/hello world: Excluded (Size Filter)
2026/02/03 06:19:17 DEBUG : sub dir/hello world: Excluded
2026/02/03 06:19:18 DEBUG : sub dir/ignore dir: Excluded
run.go:130: removing dir "sub dir/sub sub dir" failed - try 1/3: directory not empty
run.go:130: removing dir "sub dir/sub sub dir" failed - try 2/3: directory not empty
run.go:130: removing dir "sub dir/sub sub dir" failed - try 3/3: directory not empty
run.go:133: removing dir "sub dir/sub sub dir" failed: directory not empty
run.go:130: removing dir "sub dir/ignore dir" failed - try 1/3: directory not empty
run.go:130: removing dir "sub dir/ignore dir" failed - try 2/3: directory not empty
run.go:130: removing dir "sub dir/ignore dir" failed - try 3/3: directory not empty
run.go:133: removing dir "sub dir/ignore dir" failed: directory not empty
run.go:130: removing dir "sub dir" failed - try 1/3: directory not empty
run.go:130: removing dir "sub dir" failed - try 2/3: directory not empty
run.go:130: removing dir "sub dir" failed - try 3/3: directory not empty
run.go:133: removing dir "sub dir" failed: directory not empty
fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3
fstest.go:301: Flushing the directory cache
fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3
fstest.go:301: Flushing the directory cache
fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3
fstest.go:301: Flushing the directory cache
fstest.go:327:
Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:327
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338
/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178
/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393
/usr/local/go/src/testing/testing.go:1211
/usr/local/go/src/testing/testing.go:1445
/usr/local/go/src/testing/testing.go:1786
Error: Not equal:
expected: []string{}
actual : []string{"sub dir", "sub dir/ignore dir", "sub dir/sub sub dir"}
Diff:
--- Expected
+++ Actual
@@ -1,2 +1,5 @@
-([]string) {
+([]string) (len=3) {
+ (string) (len=7) "sub dir",
+ (string) (len=18) "sub dir/ignore dir",
+ (string) (len=19) "sub dir/sub sub dir"
}
Test: TestListDirSorted
Messages: directories
--- FAIL: TestListDirSorted (61.02s)
=== RUN TestListDirSortedFn
run.go:185: Remote "drime root 'rclone-test-kibuwoh5suma'", Local "Local file system at /tmp/rclone3672422180", Modify Window "876000h0m0s"
fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3
fstest.go:301: Flushing the directory cache
fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3
fstest.go:301: Flushing the directory cache
fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3
fstest.go:301: Flushing the directory cache
fstest.go:305:
Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350
/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345
/home/rclone/go/src/github.com/rclone/rclone/fs/operations/listdirsorted_test.go:36
/home/rclone/go/src/github.com/rclone/rclone/fs/operations/listdirsorted_test.go:125
/usr/local/go/src/runtime/asm_amd64.s:1700
Error: Should be true
Test: TestListDirSortedFn
Messages: listing wrong, want
a.txt (11), sub dir/hello world (11), sub dir/hello world2 (11), sub dir/ignore dir/.ignore (1), sub dir/ignore dir/should be ignored (9), sub dir/sub sub dir/hello world3 (11), zend.txt (5) got
a.txt (11), sub dir/hello world (11), sub dir/hello world2 (11), sub dir/ignore dir/.ignore (1), sub dir/ignore dir/should be ignored (9), zend.txt (5)
fstest.go:203: Not found "sub dir/sub sub dir/hello world3"
fstest.go:206:
Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:206
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:310
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350
/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345
/home/rclone/go/src/github.com/rclone/rclone/fs/operations/listdirsorted_test.go:36
/home/rclone/go/src/github.com/rclone/rclone/fs/operations/listdirsorted_test.go:125
Error: Not equal:
expected: 0
actual : 1
Test: TestListDirSortedFn
Messages: 1 objects not found
2026/02/03 06:20:16 DEBUG : a.txt: Excluded (Size Filter)
2026/02/03 06:20:16 DEBUG : a.txt: Excluded
2026/02/03 06:20:16 DEBUG : sub dir/hello world2: Excluded (Size Filter)
2026/02/03 06:20:16 DEBUG : sub dir/hello world2: Excluded
2026/02/03 06:20:16 DEBUG : sub dir/hello world: Excluded (Size Filter)
2026/02/03 06:20:16 DEBUG : sub dir/hello world: Excluded
2026/02/03 06:20:17 DEBUG : sub dir/ignore dir: Excluded
2026/02/03 06:20:17 DEBUG : sub dir/hello world2: Excluded (Size Filter)
2026/02/03 06:20:17 DEBUG : sub dir/hello world2: Excluded
2026/02/03 06:20:17 DEBUG : sub dir/hello world: Excluded (Size Filter)
2026/02/03 06:20:17 DEBUG : sub dir/hello world: Excluded
2026/02/03 06:20:18 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 (60.14s)
=== RUN TestListJSON
run.go:185: Remote "drime root 'rclone-test-kibuwoh5suma'", Local "Local file system at /tmp/rclone3672422180", Modify Window "876000h0m0s"
fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3
fstest.go:301: Flushing the directory cache
fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3
fstest.go:301: Flushing the directory cache
fstest.go:292: Sleeping for 7s just to make sure
=== RUN TestListJSON/Default
lsjson_test.go:202:
Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/lsjson_test.go:202
Error: Not equal:
expected: 2
actual : 3
Test: TestListJSON/Default
Messages: Wrong number of results
=== RUN TestListJSON/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
run.go:130: removing dir "sub" failed - try 3/3: directory not empty
run.go:133: removing dir "sub" failed: directory not empty
fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3
fstest.go:301: Flushing the directory cache
fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3
fstest.go:301: Flushing the directory cache
fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3
fstest.go:301: Flushing the directory cache
fstest.go:327:
Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:327
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338
/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178
/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393
/usr/local/go/src/testing/testing.go:1211
/usr/local/go/src/testing/testing.go:1445
/usr/local/go/src/testing/testing.go:1786
Error: Not equal:
expected: []string{}
actual : []string{"sub", "sub dir"}
Diff:
--- Expected
+++ Actual
@@ -1,2 +1,4 @@
-([]string) {
+([]string) (len=2) {
+ (string) (len=3) "sub",
+ (string) (len=7) "sub dir"
}
Test: TestListJSON
Messages: directories
--- FAIL: TestListJSON (40.12s)
--- FAIL: TestListJSON/Default (0.35s)
--- FAIL: TestListJSON/DirsOnly (0.23s)
--- FAIL: TestListJSON/Recurse (0.83s)
--- FAIL: TestListJSON/Metadata (0.73s)
=== RUN TestLsd
run.go:185: Remote "drime root 'rclone-test-kibuwoh5suma'", Local "Local file system at /tmp/rclone3672422180", Modify Window "876000h0m0s"
run.go:130: removing dir "sub dir" failed - try 1/3: directory not empty
run.go:130: removing dir "sub dir" failed - try 2/3: directory not empty
run.go:130: removing dir "sub dir" failed - try 3/3: directory not empty
run.go:133: removing dir "sub dir" failed: directory not empty
fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3
fstest.go:301: Flushing the directory cache
fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3
fstest.go:301: Flushing the directory cache
fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3
fstest.go:301: Flushing the directory cache
fstest.go:327:
Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:327
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338
/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178
/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393
/usr/local/go/src/testing/testing.go:1211
/usr/local/go/src/testing/testing.go:1445
/usr/local/go/src/testing/testing.go:1786
Error: Not equal:
expected: []string{}
actual : []string{"sub dir"}
Diff:
--- Expected
+++ Actual
@@ -1,2 +1,3 @@
-([]string) {
+([]string) (len=1) {
+ (string) (len=7) "sub dir"
}
Test: TestLsd
Messages: directories
--- FAIL: TestLsd (20.11s)
=== RUN TestLs
run.go:185: Remote "drime root 'rclone-test-kibuwoh5suma'", Local "Local file system at /tmp/rclone3672422180", Modify Window "876000h0m0s"
fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3
fstest.go:301: Flushing the directory cache
fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3
fstest.go:301: Flushing the directory cache
fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3
fstest.go:301: Flushing the directory cache
fstest.go:305:
Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350
/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345
/home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:96
Error: Should be true
Test: TestLs
Messages: listing wrong, want
empty space (1), potato2 (60) got
potato2 (60)
fstest.go:203: Not found "empty space"
fstest.go:206:
Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:206
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:310
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350
/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345
/home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:96
/usr/local/go/src/runtime/asm_amd64.s:1700
Error: Not equal:
expected: 0
actual : 1
Test: TestLs
Messages: 1 objects not found
fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3
fstest.go:301: Flushing the directory cache
fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3
fstest.go:301: Flushing the directory cache
fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3
fstest.go:301: Flushing the directory cache
fstest.go:305:
Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338
/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178
/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393
/usr/local/go/src/testing/testing.go:1211
/usr/local/go/src/testing/testing.go:1445
/usr/local/go/src/testing/testing.go:1786
Error: Should be true
Test: TestLs
Messages: listing wrong, want
got
empty space (1), potato2 (60)
fstest.go:191:
Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338
/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178
/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393
/usr/local/go/src/testing/testing.go:1211
/usr/local/go/src/testing/testing.go:1445
/usr/local/go/src/testing/testing.go:1786
Error: Should be true
Test: TestLs
Messages: Unexpected file "empty space"
fstest.go:191:
Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338
/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178
/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393
/usr/local/go/src/testing/testing.go:1211
/usr/local/go/src/testing/testing.go:1445
/usr/local/go/src/testing/testing.go:1786
Error: Should be true
Test: TestLs
Messages: Unexpected file "potato2"
fstest.go:327:
Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:327
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338
/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178
/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393
/usr/local/go/src/testing/testing.go:1211
/usr/local/go/src/testing/testing.go:1445
/usr/local/go/src/testing/testing.go:1786
Error: Not equal:
expected: []string{}
actual : []string{"sub dir"}
Diff:
--- Expected
+++ Actual
@@ -1,2 +1,3 @@
-([]string) {
+([]string) (len=1) {
+ (string) (len=7) "sub dir"
}
Test: TestLs
Messages: directories
--- FAIL: TestLs (34.36s)
=== RUN TestLsLong
run.go:185: Remote "drime root 'rclone-test-kibuwoh5suma'", Local "Local file system at /tmp/rclone3672422180", 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:292: Sleeping for 7s just to make sure
--- PASS: TestLsLong (31.65s)
=== RUN TestCount
run.go:185: Remote "drime root 'rclone-test-kibuwoh5suma'", Local "Local file system at /tmp/rclone3672422180", Modify Window "876000h0m0s"
run.go:130: removing dir "sub dir" failed - try 1/3: directory not empty
run.go:130: removing dir "sub dir" failed - try 2/3: directory not empty
run.go:130: removing dir "sub dir" failed - try 3/3: directory not empty
run.go:133: removing dir "sub dir" failed: directory not empty
fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3
fstest.go:301: Flushing the directory cache
fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3
fstest.go:301: Flushing the directory cache
fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3
fstest.go:301: Flushing the directory cache
fstest.go:327:
Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:327
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338
/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178
/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393
/usr/local/go/src/testing/testing.go:1211
/usr/local/go/src/testing/testing.go:1445
/usr/local/go/src/testing/testing.go:1786
Error: Not equal:
expected: []string{}
actual : []string{"sub dir"}
Diff:
--- Expected
+++ Actual
@@ -1,2 +1,3 @@
-([]string) {
+([]string) (len=1) {
+ (string) (len=7) "sub dir"
}
Test: TestCount
Messages: directories
--- FAIL: TestCount (23.90s)
=== RUN TestDelete
run.go:185: Remote "drime root 'rclone-test-kibuwoh5suma'", Local "Local file system at /tmp/rclone3672422180", Modify Window "876000h0m0s"
fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3
fstest.go:301: Flushing the directory cache
fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3
fstest.go:301: Flushing the directory cache
fstest.go:292: Sleeping for 7s just to make sure
2026/02/03 06:23:38 DEBUG : Waiting for deletions to finish
2026/02/03 06:23:38 DEBUG : large: Excluded (Size Filter)
2026/02/03 06:23:38 DEBUG : large: Excluded
2026/02/03 06:23:39 INFO : small: Deleted
2026/02/03 06:23:39 INFO : medium: Deleted
fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3
fstest.go:301: Flushing the directory cache
fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3
fstest.go:301: Flushing the directory cache
fstest.go:292: Sleeping for 7s just to make sure
fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3
fstest.go:301: Flushing the directory cache
fstest.go:292: Sleeping for 6s just to make sure
--- PASS: TestDelete (47.18s)
=== RUN TestMaxDelete
run.go:185: Remote "drime root 'rclone-test-kibuwoh5suma'", Local "Local file system at /tmp/rclone3672422180", Modify Window "876000h0m0s"
fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3
fstest.go:301: Flushing the directory cache
fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3
fstest.go:301: Flushing the directory cache
fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3
fstest.go:301: Flushing the directory cache
fstest.go:305:
Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350
/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345
/home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:447
Error: Should be true
Test: TestMaxDelete
Messages: listing wrong, want
large (100), medium (60), small (10) got
medium (60), small (10)
fstest.go:203: Not found "large"
fstest.go:206:
Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:206
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:310
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350
/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345
/home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:447
/usr/local/go/src/runtime/asm_amd64.s:1700
Error: Not equal:
expected: 0
actual : 1
Test: TestMaxDelete
Messages: 1 objects not found
2026/02/03 06:24:16 DEBUG : Waiting for deletions to finish
2026/02/03 06:24:17 ERROR : medium: Got fatal error on delete: --max-delete threshold reached
2026/02/03 06:24:17 INFO : large: Deleted
2026/02/03 06:24:17 INFO : small: Deleted
operations_test.go:453:
Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:453
Error: Not equal:
expected: 1
actual : 3
Test: TestMaxDelete
2026/02/03 06:24:18 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/02/03 06:24:18 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/02/03 06:24:19 DEBUG : pacer: low level retry 2/10 (error Error "Server Error")
2026/02/03 06:24:19 DEBUG : pacer: Rate limited, increasing sleep to 40ms
2026/02/03 06:24:19 DEBUG : pacer: low level retry 3/10 (error Error "Server Error")
2026/02/03 06:24:19 DEBUG : pacer: Rate limited, increasing sleep to 80ms
2026/02/03 06:24:19 DEBUG : pacer: low level retry 4/10 (error Error "Server Error")
2026/02/03 06:24:19 DEBUG : pacer: Rate limited, increasing sleep to 160ms
2026/02/03 06:24:20 DEBUG : pacer: low level retry 5/10 (error Error "Server Error")
2026/02/03 06:24:20 DEBUG : pacer: Rate limited, increasing sleep to 320ms
2026/02/03 06:24:20 DEBUG : pacer: low level retry 6/10 (error Error "Server Error")
2026/02/03 06:24:20 DEBUG : pacer: Rate limited, increasing sleep to 640ms
2026/02/03 06:24:20 DEBUG : pacer: low level retry 7/10 (error Error "Server Error")
2026/02/03 06:24:20 DEBUG : pacer: Rate limited, increasing sleep to 1.28s
2026/02/03 06:24:21 DEBUG : pacer: low level retry 8/10 (error Error "Server Error")
2026/02/03 06:24:21 DEBUG : pacer: Rate limited, increasing sleep to 2.56s
2026/02/03 06:24:22 DEBUG : pacer: low level retry 9/10 (error Error "Server Error")
2026/02/03 06:24:22 DEBUG : pacer: Rate limited, increasing sleep to 5.12s
2026/02/03 06:24:25 DEBUG : pacer: low level retry 10/10 (error Error "Server Error")
2026/02/03 06:24:25 DEBUG : pacer: Rate limited, increasing sleep to 10.24s
run.go:130: removing file "large" failed - try 1/3: failed to delete item: Error "Server Error"
2026/02/03 06:24:30 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/02/03 06:24:30 DEBUG : pacer: Rate limited, increasing sleep to 20s
2026/02/03 06:24:40 DEBUG : pacer: low level retry 2/10 (error Error "Server Error")
2026/02/03 06:25:00 DEBUG : pacer: low level retry 3/10 (error Error "Server Error")
2026/02/03 06:25:20 DEBUG : pacer: low level retry 4/10 (error Error "Server Error")
2026/02/03 06:25:40 DEBUG : pacer: low level retry 5/10 (error Error "Server Error")
2026/02/03 06:26:00 DEBUG : pacer: low level retry 6/10 (error Error "Server Error")
2026/02/03 06:26:20 DEBUG : pacer: low level retry 7/10 (error Error "Server Error")
2026/02/03 06:26:40 DEBUG : pacer: low level retry 8/10 (error Error "Server Error")
2026/02/03 06:27:00 DEBUG : pacer: low level retry 9/10 (error Error "Server Error")
2026/02/03 06:27:20 DEBUG : pacer: low level retry 10/10 (error Error "Server Error")
run.go:130: removing file "large" failed - try 2/3: failed to delete item: Error "Server Error"
2026/02/03 06:27:40 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/02/03 06:28:00 DEBUG : pacer: low level retry 2/10 (error Error "Server Error")
2026/02/03 06:28:20 DEBUG : pacer: low level retry 3/10 (error Error "Server Error")
2026/02/03 06:28:40 DEBUG : pacer: low level retry 4/10 (error Error "Server Error")
2026/02/03 06:29:00 DEBUG : pacer: low level retry 5/10 (error Error "Server Error")
2026/02/03 06:29:20 DEBUG : pacer: low level retry 6/10 (error Error "Server Error")
2026/02/03 06:29:40 DEBUG : pacer: low level retry 7/10 (error Error "Server Error")
2026/02/03 06:30:00 DEBUG : pacer: low level retry 8/10 (error Error "Server Error")
2026/02/03 06:30:20 DEBUG : pacer: low level retry 9/10 (error Error "Server Error")
2026/02/03 06:30:40 DEBUG : pacer: low level retry 10/10 (error Error "Server Error")
run.go:130: removing file "large" failed - try 3/3: failed to delete item: Error "Server Error"
run.go:133: removing file "large" failed: failed to delete item: Error "Server Error"
2026/02/03 06:31:00 DEBUG : pacer: Reducing sleep to 10s
2026/02/03 06:31:20 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/02/03 06:31:20 DEBUG : pacer: Rate limited, increasing sleep to 20s
2026/02/03 06:31:30 DEBUG : pacer: low level retry 2/10 (error Error "Server Error")
2026/02/03 06:31:50 DEBUG : pacer: low level retry 3/10 (error Error "Server Error")
2026/02/03 06:32:10 DEBUG : pacer: low level retry 4/10 (error Error "Server Error")
2026/02/03 06:32:30 DEBUG : pacer: low level retry 5/10 (error Error "Server Error")
2026/02/03 06:32:50 DEBUG : pacer: low level retry 6/10 (error Error "Server Error")
2026/02/03 06:33:10 DEBUG : pacer: low level retry 7/10 (error Error "Server Error")
2026/02/03 06:33:30 DEBUG : pacer: low level retry 8/10 (error Error "Server Error")
2026/02/03 06:33:50 DEBUG : pacer: low level retry 9/10 (error Error "Server Error")
2026/02/03 06:34:10 DEBUG : pacer: low level retry 10/10 (error Error "Server Error")
run.go:130: removing file "small" failed - try 1/3: failed to delete item: Error "Server Error"
2026/02/03 06:34:30 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/02/03 06:34:50 DEBUG : pacer: low level retry 2/10 (error Error "Server Error")
2026/02/03 06:35:10 DEBUG : pacer: low level retry 3/10 (error Error "Server Error")
2026/02/03 06:35:30 DEBUG : pacer: low level retry 4/10 (error Error "Server Error")
2026/02/03 06:35:50 DEBUG : pacer: low level retry 5/10 (error Error "Server Error")
2026/02/03 06:36:10 DEBUG : pacer: low level retry 6/10 (error Error "Server Error")
2026/02/03 06:36:30 DEBUG : pacer: low level retry 7/10 (error Error "Server Error")
2026/02/03 06:36:50 DEBUG : pacer: low level retry 8/10 (error Error "Server Error")
2026/02/03 06:37:10 DEBUG : pacer: low level retry 9/10 (error Error "Server Error")
2026/02/03 06:37:30 DEBUG : pacer: low level retry 10/10 (error Error "Server Error")
run.go:130: removing file "small" failed - try 2/3: failed to delete item: Error "Server Error"
2026/02/03 06:37:50 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/02/03 06:38:10 DEBUG : pacer: low level retry 2/10 (error Error "Server Error")
2026/02/03 06:38:30 DEBUG : pacer: low level retry 3/10 (error Error "Server Error")
2026/02/03 06:38:50 DEBUG : pacer: low level retry 4/10 (error Error "Server Error")
2026/02/03 06:39:10 DEBUG : pacer: low level retry 5/10 (error Error "Server Error")
2026/02/03 06:39:30 DEBUG : pacer: low level retry 6/10 (error Error "Server Error")
2026/02/03 06:39:50 DEBUG : pacer: low level retry 7/10 (error Error "Server Error")
2026/02/03 06:40:10 DEBUG : pacer: low level retry 8/10 (error Error "Server Error")
2026/02/03 06:40:30 DEBUG : pacer: low level retry 9/10 (error Error "Server Error")
2026/02/03 06:40:50 DEBUG : pacer: low level retry 10/10 (error Error "Server Error")
run.go:130: removing file "small" failed - try 3/3: failed to delete item: Error "Server Error"
run.go:133: removing file "small" failed: failed to delete item: Error "Server Error"
2026/02/03 06:41:10 DEBUG : pacer: Reducing sleep to 10s
2026/02/03 06:41:30 DEBUG : pacer: Reducing sleep to 5s
2026/02/03 06:41:40 DEBUG : pacer: Reducing sleep to 2.5s
--- FAIL: TestMaxDelete (1060.17s)
=== RUN TestMaxDeleteSizeLargeFile
run.go:185: Remote "drime root 'rclone-test-kibuwoh5suma'", Local "Local file system at /tmp/rclone3672422180", Modify Window "876000h0m0s"
2026/02/03 06:41:45 DEBUG : pacer: Reducing sleep to 1.25s
2026/02/03 06:41:52 DEBUG : pacer: Reducing sleep to 625ms
2026/02/03 06:41:52 DEBUG : pacer: Reducing sleep to 312.5ms
2026/02/03 06:41:54 DEBUG : pacer: Reducing sleep to 156.25ms
2026/02/03 06:41:55 DEBUG : pacer: Reducing sleep to 78.125ms
2026/02/03 06:41:58 DEBUG : pacer: Reducing sleep to 39.0625ms
2026/02/03 06:41:58 DEBUG : pacer: Reducing sleep to 19.53125ms
2026/02/03 06:41:58 DEBUG : Waiting for deletions to finish
2026/02/03 06:41:58 DEBUG : pacer: Reducing sleep to 10ms
2026/02/03 06:41:58 ERROR : large: Got fatal error on delete: --max-delete-size threshold reached
2026/02/03 06:41:58 INFO : small: Deleted
2026/02/03 06:41:59 INFO : medium: Deleted
fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3
fstest.go:301: Flushing the directory cache
fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3
fstest.go:301: Flushing the directory cache
fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3
fstest.go:301: Flushing the directory cache
fstest.go:305:
Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350
/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345
/home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:472
Error: Should be true
Test: TestMaxDeleteSizeLargeFile
Messages: listing wrong, want
large (100) got
large (100), medium (60), small (10)
fstest.go:191:
Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350
/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345
/home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:472
/usr/local/go/src/runtime/asm_amd64.s:1700
Error: Should be true
Test: TestMaxDeleteSizeLargeFile
Messages: Unexpected file "medium"
fstest.go:191:
Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350
/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345
/home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:472
/usr/local/go/src/runtime/asm_amd64.s:1700
Error: Should be true
Test: TestMaxDeleteSizeLargeFile
Messages: Unexpected file "small"
2026/02/03 06:42:08 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/02/03 06:42:08 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/02/03 06:42:09 DEBUG : pacer: low level retry 2/10 (error Error "Server Error")
2026/02/03 06:42:09 DEBUG : pacer: Rate limited, increasing sleep to 40ms
2026/02/03 06:42:09 DEBUG : pacer: low level retry 3/10 (error Error "Server Error")
2026/02/03 06:42:09 DEBUG : pacer: Rate limited, increasing sleep to 80ms
2026/02/03 06:42:09 DEBUG : pacer: low level retry 4/10 (error Error "Server Error")
2026/02/03 06:42:09 DEBUG : pacer: Rate limited, increasing sleep to 160ms
2026/02/03 06:42:09 DEBUG : pacer: low level retry 5/10 (error Error "Server Error")
2026/02/03 06:42:09 DEBUG : pacer: Rate limited, increasing sleep to 320ms
2026/02/03 06:42:09 DEBUG : pacer: low level retry 6/10 (error Error "Server Error")
2026/02/03 06:42:09 DEBUG : pacer: Rate limited, increasing sleep to 640ms
2026/02/03 06:42:10 DEBUG : pacer: low level retry 7/10 (error Error "Server Error")
2026/02/03 06:42:10 DEBUG : pacer: Rate limited, increasing sleep to 1.28s
2026/02/03 06:42:10 DEBUG : pacer: low level retry 8/10 (error Error "Server Error")
2026/02/03 06:42:10 DEBUG : pacer: Rate limited, increasing sleep to 2.56s
2026/02/03 06:42:12 DEBUG : pacer: low level retry 9/10 (error Error "Server Error")
2026/02/03 06:42:12 DEBUG : pacer: Rate limited, increasing sleep to 5.12s
2026/02/03 06:42:14 DEBUG : pacer: low level retry 10/10 (error Error "Server Error")
2026/02/03 06:42:14 DEBUG : pacer: Rate limited, increasing sleep to 10.24s
run.go:130: removing file "medium" failed - try 1/3: failed to delete item: Error "Server Error"
2026/02/03 06:42:19 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/02/03 06:42:19 DEBUG : pacer: Rate limited, increasing sleep to 20s
2026/02/03 06:42:30 DEBUG : pacer: low level retry 2/10 (error Error "Server Error")
2026/02/03 06:42:49 DEBUG : pacer: low level retry 3/10 (error Error "Server Error")
2026/02/03 06:43:10 DEBUG : pacer: low level retry 4/10 (error Error "Server Error")
2026/02/03 06:43:30 DEBUG : pacer: low level retry 5/10 (error Error "Server Error")
2026/02/03 06:43:50 DEBUG : pacer: low level retry 6/10 (error Error "Server Error")
2026/02/03 06:44:10 DEBUG : pacer: low level retry 7/10 (error Error "Server Error")
2026/02/03 06:44:30 DEBUG : pacer: low level retry 8/10 (error Error "Server Error")
2026/02/03 06:44:50 DEBUG : pacer: low level retry 9/10 (error Error "Server Error")
2026/02/03 06:45:10 DEBUG : pacer: low level retry 10/10 (error Error "Server Error")
run.go:130: removing file "medium" failed - try 2/3: failed to delete item: Error "Server Error"
2026/02/03 06:45:30 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/02/03 06:45:50 DEBUG : pacer: low level retry 2/10 (error Error "Server Error")
2026/02/03 06:46:10 DEBUG : pacer: low level retry 3/10 (error Error "Server Error")
2026/02/03 06:46:30 DEBUG : pacer: low level retry 4/10 (error Error "Server Error")
2026/02/03 06:46:50 DEBUG : pacer: low level retry 5/10 (error Error "Server Error")
2026/02/03 06:47:09 DEBUG : pacer: low level retry 6/10 (error Error "Server Error")
2026/02/03 06:47:30 DEBUG : pacer: low level retry 7/10 (error Error "Server Error")
2026/02/03 06:47:50 DEBUG : pacer: low level retry 8/10 (error Error "Server Error")
2026/02/03 06:48:10 DEBUG : pacer: low level retry 9/10 (error Error "Server Error")
2026/02/03 06:48:30 DEBUG : pacer: low level retry 10/10 (error Error "Server Error")
run.go:130: removing file "medium" failed - try 3/3: failed to delete item: Error "Server Error"
run.go:133: removing file "medium" failed: failed to delete item: Error "Server Error"
2026/02/03 06:48:50 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/02/03 06:49:10 DEBUG : pacer: low level retry 2/10 (error Error "Server Error")
2026/02/03 06:49:30 DEBUG : pacer: low level retry 3/10 (error Error "Server Error")
2026/02/03 06:49:50 DEBUG : pacer: low level retry 4/10 (error Error "Server Error")
2026/02/03 06:50:10 DEBUG : pacer: low level retry 5/10 (error Error "Server Error")
2026/02/03 06:50:30 DEBUG : pacer: low level retry 6/10 (error Error "Server Error")
2026/02/03 06:50:50 DEBUG : pacer: low level retry 7/10 (error Error "Server Error")
2026/02/03 06:51:10 DEBUG : pacer: low level retry 8/10 (error Error "Server Error")
2026/02/03 06:51:30 DEBUG : pacer: low level retry 9/10 (error Error "Server Error")
2026/02/03 06:51:50 DEBUG : pacer: low level retry 10/10 (error Error "Server Error")
run.go:130: removing file "small" failed - try 1/3: failed to delete item: Error "Server Error"
2026/02/03 06:52:09 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/02/03 06:52:09 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/03 06:52:29 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/02/03 06:52:29 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/03 06:52:50 DEBUG : pacer: low level retry 3/10 (error Error "Server Error")
2026/02/03 06:53:10 DEBUG : pacer: low level retry 4/10 (error Error "Server Error")
2026/02/03 06:53:30 DEBUG : pacer: low level retry 5/10 (error Error "Server Error")
2026/02/03 06:53:50 DEBUG : pacer: low level retry 6/10 (error Error "Server Error")
2026/02/03 06:54:09 DEBUG : pacer: low level retry 7/10 (error Error "Server Error")
2026/02/03 06:54:30 DEBUG : pacer: low level retry 8/10 (error Error "Server Error")
2026/02/03 06:54:49 DEBUG : pacer: low level retry 9/10 (error Error "Server Error")
2026/02/03 06:55:10 DEBUG : pacer: low level retry 10/10 (error Error "Server Error")
run.go:130: removing file "small" failed - try 2/3: failed to delete item: Error "Server Error"
2026/02/03 06:55:30 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/02/03 06:55:50 DEBUG : pacer: low level retry 2/10 (error Error "Server Error")
2026/02/03 06:56:10 DEBUG : pacer: low level retry 3/10 (error Error "Server Error")
2026/02/03 06:56:30 DEBUG : pacer: low level retry 4/10 (error Error "Server Error")
2026/02/03 06:56:50 DEBUG : pacer: low level retry 5/10 (error Error "Server Error")
2026/02/03 06:57:09 DEBUG : pacer: low level retry 6/10 (error Error "Server Error")
2026/02/03 06:57:30 DEBUG : pacer: low level retry 7/10 (error Error "Server Error")
2026/02/03 06:57:50 DEBUG : pacer: low level retry 8/10 (error Error "Server Error")
2026/02/03 06:58:10 DEBUG : pacer: low level retry 9/10 (error Error "Server Error")
2026/02/03 06:58:30 DEBUG : pacer: low level retry 10/10 (error Error "Server Error")
run.go:130: removing file "small" failed - try 3/3: failed to delete item: Error "Server Error"
run.go:133: removing file "small" failed: failed to delete item: Error "Server Error"
2026/02/03 06:58:50 DEBUG : pacer: Reducing sleep to 10s
2026/02/03 06:59:10 DEBUG : pacer: Reducing sleep to 5s
2026/02/03 06:59:19 DEBUG : pacer: Reducing sleep to 2.5s
--- FAIL: TestMaxDeleteSizeLargeFile (1059.25s)
=== RUN TestMaxDeleteSize
run.go:185: Remote "drime root 'rclone-test-kibuwoh5suma'", Local "Local file system at /tmp/rclone3672422180", Modify Window "876000h0m0s"
2026/02/03 06:59:25 DEBUG : pacer: Reducing sleep to 1.25s
2026/02/03 06:59:29 DEBUG : pacer: Reducing sleep to 625ms
2026/02/03 06:59:29 DEBUG : pacer: Reducing sleep to 312.5ms
2026/02/03 06:59:33 DEBUG : pacer: Reducing sleep to 156.25ms
2026/02/03 06:59:33 DEBUG : pacer: Reducing sleep to 78.125ms
2026/02/03 06:59:35 DEBUG : pacer: Reducing sleep to 39.0625ms
2026/02/03 06:59:35 DEBUG : pacer: Reducing sleep to 19.53125ms
2026/02/03 06:59:35 DEBUG : Waiting for deletions to finish
2026/02/03 06:59:36 DEBUG : pacer: Reducing sleep to 10ms
2026/02/03 06:59:36 ERROR : small: Got fatal error on delete: --max-delete-size threshold reached
2026/02/03 06:59:36 INFO : large: Deleted
2026/02/03 06:59:36 INFO : medium: Deleted
operations_test.go:492:
Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:492
Error: Not equal:
expected: 1
actual : 3
Test: TestMaxDeleteSize
2026/02/03 06:59:37 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/02/03 06:59:37 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/02/03 06:59:38 DEBUG : pacer: low level retry 2/10 (error Error "Server Error")
2026/02/03 06:59:38 DEBUG : pacer: Rate limited, increasing sleep to 40ms
2026/02/03 06:59:38 DEBUG : pacer: low level retry 3/10 (error Error "Server Error")
2026/02/03 06:59:38 DEBUG : pacer: Rate limited, increasing sleep to 80ms
2026/02/03 06:59:38 DEBUG : pacer: low level retry 4/10 (error Error "Server Error")
2026/02/03 06:59:38 DEBUG : pacer: Rate limited, increasing sleep to 160ms
2026/02/03 06:59:39 DEBUG : pacer: low level retry 5/10 (error Error "Server Error")
2026/02/03 06:59:39 DEBUG : pacer: Rate limited, increasing sleep to 320ms
2026/02/03 06:59:39 DEBUG : pacer: low level retry 6/10 (error Error "Server Error")
2026/02/03 06:59:39 DEBUG : pacer: Rate limited, increasing sleep to 640ms
2026/02/03 06:59:39 DEBUG : pacer: low level retry 7/10 (error Error "Server Error")
2026/02/03 06:59:39 DEBUG : pacer: Rate limited, increasing sleep to 1.28s
2026/02/03 06:59:40 DEBUG : pacer: low level retry 8/10 (error Error "Server Error")
2026/02/03 06:59:40 DEBUG : pacer: Rate limited, increasing sleep to 2.56s
2026/02/03 06:59:41 DEBUG : pacer: low level retry 9/10 (error Error "Server Error")
2026/02/03 06:59:41 DEBUG : pacer: Rate limited, increasing sleep to 5.12s
2026/02/03 06:59:44 DEBUG : pacer: low level retry 10/10 (error Error "Server Error")
2026/02/03 06:59:44 DEBUG : pacer: Rate limited, increasing sleep to 10.24s
run.go:130: removing file "large" failed - try 1/3: failed to delete item: Error "Server Error"
2026/02/03 06:59:49 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/02/03 06:59:49 DEBUG : pacer: Rate limited, increasing sleep to 20s
2026/02/03 06:59:59 DEBUG : pacer: low level retry 2/10 (error Error "Server Error")
2026/02/03 07:00:19 DEBUG : pacer: low level retry 3/10 (error Error "Server Error")
2026/02/03 07:00:39 DEBUG : pacer: low level retry 4/10 (error Error "Server Error")
2026/02/03 07:00:59 DEBUG : pacer: low level retry 5/10 (error Error "Server Error")
2026/02/03 07:01:19 DEBUG : pacer: low level retry 6/10 (error Error "Server Error")
2026/02/03 07:01:39 DEBUG : pacer: low level retry 7/10 (error Error "Server Error")
2026/02/03 07:01:59 DEBUG : pacer: low level retry 8/10 (error Error "Server Error")
2026/02/03 07:02:19 DEBUG : pacer: low level retry 9/10 (error Error "Server Error")
2026/02/03 07:02:39 DEBUG : pacer: low level retry 10/10 (error Error "Server Error")
run.go:130: removing file "large" failed - try 2/3: failed to delete item: Error "Server Error"
2026/02/03 07:02:59 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/02/03 07:03:19 DEBUG : pacer: low level retry 2/10 (error Error "Server Error")
2026/02/03 07:03:39 DEBUG : pacer: low level retry 3/10 (error Error "Server Error")
2026/02/03 07:03:59 DEBUG : pacer: low level retry 4/10 (error Error "Server Error")
2026/02/03 07:04:19 DEBUG : pacer: low level retry 5/10 (error Error "Server Error")
2026/02/03 07:04:39 DEBUG : pacer: low level retry 6/10 (error Error "Server Error")
2026/02/03 07:05:00 DEBUG : pacer: low level retry 7/10 (error Error "Server Error")
2026/02/03 07:05:19 DEBUG : pacer: low level retry 8/10 (error Error "Server Error")
2026/02/03 07:05:39 DEBUG : pacer: low level retry 9/10 (error Error "Server Error")
2026/02/03 07:05:59 DEBUG : pacer: low level retry 10/10 (error Error "Server Error")
run.go:130: removing file "large" failed - try 3/3: failed to delete item: Error "Server Error"
run.go:133: removing file "large" failed: failed to delete item: Error "Server Error"
2026/02/03 07:06:19 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/02/03 07:06:39 DEBUG : pacer: low level retry 2/10 (error Error "Server Error")
2026/02/03 07:06:59 DEBUG : pacer: low level retry 3/10 (error Error "Server Error")
2026/02/03 07:07:19 DEBUG : pacer: low level retry 4/10 (error Error "Server Error")
2026/02/03 07:07:39 DEBUG : pacer: low level retry 5/10 (error Error "Server Error")
2026/02/03 07:07:59 DEBUG : pacer: low level retry 6/10 (error Error "Server Error")
2026/02/03 07:08:19 DEBUG : pacer: low level retry 7/10 (error Error "Server Error")
2026/02/03 07:08:39 DEBUG : pacer: low level retry 8/10 (error Error "Server Error")
2026/02/03 07:08:59 DEBUG : pacer: low level retry 9/10 (error Error "Server Error")
2026/02/03 07:09:19 DEBUG : pacer: low level retry 10/10 (error Error "Server Error")
run.go:130: removing file "medium" failed - try 1/3: failed to delete item: Error "Server Error"
2026/02/03 07:09:39 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/02/03 07:09:59 DEBUG : pacer: low level retry 2/10 (error Error "Server Error")
2026/02/03 07:10:19 DEBUG : pacer: low level retry 3/10 (error Error "Server Error")
2026/02/03 07:10:39 DEBUG : pacer: low level retry 4/10 (error Error "Server Error")
2026/02/03 07:11:00 DEBUG : pacer: low level retry 5/10 (error Error "Server Error")
2026/02/03 07:11:19 DEBUG : pacer: low level retry 6/10 (error Error "Server Error")
2026/02/03 07:11:39 DEBUG : pacer: low level retry 7/10 (error Error "Server Error")
2026/02/03 07:11:59 DEBUG : pacer: low level retry 8/10 (error Error "Server Error")
2026/02/03 07:12:19 DEBUG : pacer: low level retry 9/10 (error Error "Server Error")
2026/02/03 07:12:39 DEBUG : pacer: low level retry 10/10 (error Error "Server Error")
run.go:130: removing file "medium" failed - try 2/3: failed to delete item: Error "Server Error"
2026/02/03 07:12:59 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/02/03 07:13:19 DEBUG : pacer: low level retry 2/10 (error Error "Server Error")
2026/02/03 07:13:39 DEBUG : pacer: low level retry 3/10 (error Error "Server Error")
2026/02/03 07:13:59 DEBUG : pacer: low level retry 4/10 (error Error "Server Error")
2026/02/03 07:14:19 DEBUG : pacer: low level retry 5/10 (error Error "Server Error")
2026/02/03 07:14:39 DEBUG : pacer: low level retry 6/10 (error Error "Server Error")
2026/02/03 07:14:59 DEBUG : pacer: low level retry 7/10 (error Error "Server Error")
2026/02/03 07:15:19 DEBUG : pacer: low level retry 8/10 (error Error "Server Error")
2026/02/03 07:15:39 DEBUG : pacer: low level retry 9/10 (error Error "Server Error")
2026/02/03 07:15:59 DEBUG : pacer: low level retry 10/10 (error Error "Server Error")
run.go:130: removing file "medium" failed - try 3/3: failed to delete item: Error "Server Error"
run.go:133: removing file "medium" failed: failed to delete item: Error "Server Error"
2026/02/03 07:16:19 DEBUG : pacer: Reducing sleep to 10s
2026/02/03 07:16:39 DEBUG : pacer: Reducing sleep to 5s
2026/02/03 07:16:49 DEBUG : pacer: Reducing sleep to 2.5s
2026/02/03 07:16:54 DEBUG : pacer: Reducing sleep to 1.25s
--- FAIL: TestMaxDeleteSize (1054.93s)
=== RUN TestReadFile
run.go:185: Remote "drime root 'rclone-test-kibuwoh5suma'", Local "Local file system at /tmp/rclone3672422180", Modify Window "876000h0m0s"
2026/02/03 07:16:57 DEBUG : pacer: Reducing sleep to 625ms
2026/02/03 07:17:00 DEBUG : pacer: Reducing sleep to 312.5ms
2026/02/03 07:17:01 DEBUG : pacer: Reducing sleep to 156.25ms
2026/02/03 07:17:01 DEBUG : pacer: Reducing sleep to 78.125ms
2026/02/03 07:17:01 DEBUG : pacer: Reducing sleep to 39.0625ms
2026/02/03 07:17:02 DEBUG : pacer: Reducing sleep to 19.53125ms
2026/02/03 07:17:02 DEBUG : pacer: Reducing sleep to 10ms
fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3
fstest.go:301: Flushing the directory cache
fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3
fstest.go:301: Flushing the directory cache
fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3
fstest.go:301: Flushing the directory cache
fstest.go:305:
Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338
/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178
/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393
/home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:510
Error: Should be true
Test: TestReadFile
Messages: listing wrong, want
got
ReadFile (28)
fstest.go:191:
Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338
/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178
/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393
/home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:510
/usr/local/go/src/runtime/asm_amd64.s:1700
Error: Should be true
Test: TestReadFile
Messages: Unexpected file "ReadFile"
--- FAIL: TestReadFile (18.27s)
=== RUN TestCat
run.go:185: Remote "drime root 'rclone-test-kibuwoh5suma'", Local "Local file system at /tmp/rclone3672422180", Modify Window "876000h0m0s"
fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3
fstest.go:301: Flushing the directory cache
fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3
fstest.go:301: Flushing the directory cache
fstest.go:292: Sleeping for 7s just to make sure
fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3
fstest.go:301: Flushing the directory cache
fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3
fstest.go:301: Flushing the directory cache
fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3
fstest.go:301: Flushing the directory cache
fstest.go:305:
Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338
/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178
/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393
/usr/local/go/src/testing/testing.go:1211
/usr/local/go/src/testing/testing.go:1445
/usr/local/go/src/testing/testing.go:1786
Error: Should be true
Test: TestCat
Messages: listing wrong, want
got
file1 (10), file2 (9)
fstest.go:191:
Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338
/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178
/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393
/usr/local/go/src/testing/testing.go:1211
/usr/local/go/src/testing/testing.go:1445
/usr/local/go/src/testing/testing.go:1786
Error: Should be true
Test: TestCat
Messages: Unexpected file "file1"
fstest.go:191:
Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338
/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178
/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393
/usr/local/go/src/testing/testing.go:1211
/usr/local/go/src/testing/testing.go:1445
/usr/local/go/src/testing/testing.go:1786
Error: Should be true
Test: TestCat
Messages: Unexpected file "file2"
--- FAIL: TestCat (36.40s)
FAIL
2026/02/03 07:17:49 DEBUG : drime root 'rclone-test-kibuwoh5suma': Purge remote
"./operations.test -test.v -test.timeout 2h0m0s -remote TestDrime: -verbose -test.run '^(TestApplyTransforms|TestCat|TestCheckSumDownload|TestCopyFile|TestCopyFileCopyDest|TestCopyFileMaxTransfer|TestCount|TestDelete|TestListDirSorted|TestListDirSortedFn|TestLs|TestLsLong|TestLsd|TestMaxDelete|TestMaxDeleteSize|TestMaxDeleteSizeLargeFile|TestMultithreadCopyAbort|TestParseSumFile|TestReadFile)$|^TestListJSON$/^(Default|DirsOnly|Metadata|Recurse)$|^TestMultithreadCopy$/^(upload=false,size=10485759,streams=2|upload=true,size=10485759,streams=2|upload=true,size=10485760,streams=2|upload=true,size=10485761,streams=2)$'" - Finished ERROR in 1h29m2.410606855s (try 3/5): exit status 1: Failed [TestMultithreadCopy/upload=false,size=10485759,streams=2 TestMultithreadCopy/upload=true,size=10485759,streams=2 TestMultithreadCopy/upload=true,size=10485760,streams=2 TestMultithreadCopy/upload=true,size=10485761,streams=2 TestMultithreadCopyAbort TestParseSumFile TestCheckSumDownload TestApplyTransforms TestCopyFile TestCopyFileCopyDest TestListDirSorted TestListDirSortedFn TestListJSON/Default TestListJSON/DirsOnly TestListJSON/Recurse TestListJSON/Metadata TestLsd TestLs TestCount TestMaxDelete TestMaxDeleteSizeLargeFile TestMaxDeleteSize TestReadFile TestCat]