"./operations.test -test.v -test.timeout 2h0m0s -remote TestDrime: -verbose -test.run '^(TestApplyTransforms|TestCat|TestCheckSumDownload|TestCopyFile|TestCopyFileCompareDest|TestCopyFileCopyDest|TestCopyInplace|TestHashSums|TestListDirSortedFn|TestLsLong|TestLsd|TestMaxDelete|TestMaxDeleteSize|TestMkdir|TestParseSumFile|TestPurge|TestRmdirsNoLeaveRoot|TestStatJSON)$|^TestListJSON$/^(Default|DirsOnly|Metadata|Recurse)$|^TestMultithreadCopy$/^(upload=false,size=10485759,streams=2|upload=false,size=10485760,streams=2|upload=false,size=10485761,streams=2|upload=true,size=10485759,streams=2|upload=true,size=10485760,streams=2|upload=true,size=10485761,streams=2)$'" - Starting (try 3/5) 2026/02/02 04:57:34 DEBUG : Creating backend with remote "TestDrime:rclone-test-mopoweh7toso" 2026/02/02 04:57:34 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/02/02 04:57:34 DEBUG : Creating backend with remote "/tmp/rclone477205313" === RUN TestMultithreadCopy run.go:185: Remote "drime root 'rclone-test-mopoweh7toso'", Local "Local file system at /tmp/rclone477205313", Modify Window "876000h0m0s" === RUN TestMultithreadCopy/upload=false,size=10485759,streams=2 fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/multithread_test.go:191 Error: Should be true Test: TestMultithreadCopy/upload=false,size=10485759,streams=2 Messages: listing wrong, want test-multithread-copy-false-10485759-2 (10485759) got fstest.go:203: Not found "test-multithread-copy-false-10485759-2" fstest.go:206: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:206 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:310 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/multithread_test.go:191 /usr/local/go/src/runtime/asm_amd64.s:1700 Error: Not equal: expected: 0 actual : 1 Test: TestMultithreadCopy/upload=false,size=10485759,streams=2 Messages: 1 objects not found 2026/02/02 04:57:49 DEBUG : test-multithread-copy-false-10485759-2: multi-thread copy: disabling buffering because destination uses OpenWriterAt 2026/02/02 04:57:49 DEBUG : test-multithread-copy-false-10485759-2: multi-thread copy: write buffer set to 131072 2026/02/02 04:57:49 DEBUG : test-multithread-copy-false-10485759-2: multi-thread copy: chunk size 64Mi was bigger than source file size 10.000Mi 2026/02/02 04:57:49 DEBUG : test-multithread-copy-false-10485759-2: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 2 2026/02/02 04:57:49 DEBUG : test-multithread-copy-false-10485759-2: multi-thread copy: number of streams 4 was bigger than number of chunks 1 2026/02/02 04:57:49 DEBUG : test-multithread-copy-false-10485759-2: Starting multi-thread copy with 1 chunks of size 10.000Mi with 1 parallel streams 2026/02/02 04:57:49 DEBUG : test-multithread-copy-false-10485759-2: multi-thread copy: chunk 1/1 (0-10485759) size 10.000Mi starting 2026/02/02 04:57:49 DEBUG : test-multithread-copy-false-10485759-2: writing chunk 0 2026/02/02 04:57:49 DEBUG : test-multithread-copy-false-10485759-2: multi-thread copy: chunk 1/1 (0-10485759) size 10.000Mi finished 2026/02/02 04:57:49 DEBUG : test-multithread-copy-false-10485759-2: Finished multi-thread copy with 1 parts of size 10.000Mi === RUN TestMultithreadCopy/upload=false,size=10485760,streams=2 fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:292: Sleeping for 7s just to make sure 2026/02/02 04:58:06 DEBUG : test-multithread-copy-false-10485760-2: multi-thread copy: disabling buffering because destination uses OpenWriterAt 2026/02/02 04:58:06 DEBUG : test-multithread-copy-false-10485760-2: multi-thread copy: write buffer set to 131072 2026/02/02 04:58:06 DEBUG : test-multithread-copy-false-10485760-2: multi-thread copy: chunk size 64Mi was bigger than source file size 10Mi 2026/02/02 04:58:06 DEBUG : test-multithread-copy-false-10485760-2: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 2 2026/02/02 04:58:06 DEBUG : test-multithread-copy-false-10485760-2: multi-thread copy: number of streams 4 was bigger than number of chunks 1 2026/02/02 04:58:06 DEBUG : test-multithread-copy-false-10485760-2: Starting multi-thread copy with 1 chunks of size 10Mi with 1 parallel streams 2026/02/02 04:58:06 DEBUG : test-multithread-copy-false-10485760-2: multi-thread copy: chunk 1/1 (0-10485760) size 10Mi starting 2026/02/02 04:58:07 DEBUG : test-multithread-copy-false-10485760-2: writing chunk 0 2026/02/02 04:58:07 DEBUG : test-multithread-copy-false-10485760-2: multi-thread copy: chunk 1/1 (0-10485760) size 10Mi finished 2026/02/02 04:58:07 DEBUG : test-multithread-copy-false-10485760-2: Finished multi-thread copy with 1 parts of size 10Mi === RUN TestMultithreadCopy/upload=false,size=10485761,streams=2 2026/02/02 04:58:11 DEBUG : test-multithread-copy-false-10485761-2: multi-thread copy: disabling buffering because destination uses OpenWriterAt 2026/02/02 04:58:11 DEBUG : test-multithread-copy-false-10485761-2: multi-thread copy: write buffer set to 131072 2026/02/02 04:58:11 DEBUG : test-multithread-copy-false-10485761-2: multi-thread copy: chunk size 64Mi was bigger than source file size 10.000Mi 2026/02/02 04:58:11 DEBUG : test-multithread-copy-false-10485761-2: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 2 2026/02/02 04:58:11 DEBUG : test-multithread-copy-false-10485761-2: multi-thread copy: number of streams 4 was bigger than number of chunks 1 2026/02/02 04:58:11 DEBUG : test-multithread-copy-false-10485761-2: Starting multi-thread copy with 1 chunks of size 10.000Mi with 1 parallel streams 2026/02/02 04:58:11 DEBUG : test-multithread-copy-false-10485761-2: multi-thread copy: chunk 1/1 (0-10485761) size 10.000Mi starting 2026/02/02 04:58:12 DEBUG : test-multithread-copy-false-10485761-2: writing chunk 0 2026/02/02 04:58:12 DEBUG : test-multithread-copy-false-10485761-2: multi-thread copy: chunk 1/1 (0-10485761) size 10.000Mi finished 2026/02/02 04:58:12 DEBUG : test-multithread-copy-false-10485761-2: Finished multi-thread copy with 1 parts of size 10.000Mi === RUN TestMultithreadCopy/upload=true,size=10485759,streams=2 fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/multithread_test.go:186 Error: Should be true Test: TestMultithreadCopy/upload=true,size=10485759,streams=2 Messages: listing wrong, want got test-multithread-copy-false-10485761-2 (10485761) fstest.go:191: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/multithread_test.go:186 /usr/local/go/src/runtime/asm_amd64.s:1700 Error: Should be true Test: TestMultithreadCopy/upload=true,size=10485759,streams=2 Messages: Unexpected file "test-multithread-copy-false-10485761-2" 2026/02/02 04:58:22 DEBUG : test-multithread-copy-true-10485759-2: multi-thread copy: disabling buffering because source is local disk 2026/02/02 04:58:23 DEBUG : test-multithread-copy-true-10485759-2: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 2 2026/02/02 04:58:23 DEBUG : test-multithread-copy-true-10485759-2: multi-thread copy: number of streams 4 was bigger than number of chunks 2 2026/02/02 04:58:23 DEBUG : test-multithread-copy-true-10485759-2: Starting multi-thread copy with 2 chunks of size 5Mi with 2 parallel streams 2026/02/02 04:58:23 DEBUG : test-multithread-copy-true-10485759-2: multi-thread copy: chunk 2/2 (5242880-10485759) size 5.000Mi starting 2026/02/02 04:58:23 DEBUG : test-multithread-copy-true-10485759-2: multi-thread copy: chunk 1/2 (0-5242880) size 5Mi starting 2026/02/02 04:58:24 DEBUG : test-multithread-copy-true-10485759-2: multi-thread copy: chunk 2/2 (5242880-10485759) size 5.000Mi finished 2026/02/02 04:58:24 DEBUG : test-multithread-copy-true-10485759-2: multi-thread copy: chunk 1/2 (0-5242880) size 5Mi finished 2026/02/02 04:58:26 DEBUG : test-multithread-copy-true-10485759-2: Finished multi-thread copy with 2 parts of size 5Mi === RUN TestMultithreadCopy/upload=true,size=10485760,streams=2 fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/multithread_test.go:186 Error: Should be true Test: TestMultithreadCopy/upload=true,size=10485760,streams=2 Messages: listing wrong, want got test-multithread-copy-true-10485759-2 (10485759) fstest.go:191: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/multithread_test.go:186 /usr/local/go/src/runtime/asm_amd64.s:1700 Error: Should be true Test: TestMultithreadCopy/upload=true,size=10485760,streams=2 Messages: Unexpected file "test-multithread-copy-true-10485759-2" 2026/02/02 04:58:37 DEBUG : test-multithread-copy-true-10485760-2: multi-thread copy: disabling buffering because source is local disk 2026/02/02 04:58:38 DEBUG : test-multithread-copy-true-10485760-2: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 2 2026/02/02 04:58:38 DEBUG : test-multithread-copy-true-10485760-2: multi-thread copy: number of streams 4 was bigger than number of chunks 2 2026/02/02 04:58:38 DEBUG : test-multithread-copy-true-10485760-2: Starting multi-thread copy with 2 chunks of size 5Mi with 2 parallel streams 2026/02/02 04:58:38 DEBUG : test-multithread-copy-true-10485760-2: multi-thread copy: chunk 2/2 (5242880-10485760) size 5Mi starting 2026/02/02 04:58:38 DEBUG : test-multithread-copy-true-10485760-2: multi-thread copy: chunk 1/2 (0-5242880) size 5Mi starting 2026/02/02 04:58:38 DEBUG : test-multithread-copy-true-10485760-2: multi-thread copy: chunk 1/2 (0-5242880) size 5Mi finished 2026/02/02 04:58:39 DEBUG : test-multithread-copy-true-10485760-2: multi-thread copy: chunk 2/2 (5242880-10485760) size 5Mi finished 2026/02/02 04:58:40 DEBUG : test-multithread-copy-true-10485760-2: Finished multi-thread copy with 2 parts of size 5Mi === RUN TestMultithreadCopy/upload=true,size=10485761,streams=2 fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/multithread_test.go:186 Error: Should be true Test: TestMultithreadCopy/upload=true,size=10485761,streams=2 Messages: listing wrong, want got test-multithread-copy-true-10485760-2 (10485760) fstest.go:191: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/multithread_test.go:186 /usr/local/go/src/runtime/asm_amd64.s:1700 Error: Should be true Test: TestMultithreadCopy/upload=true,size=10485761,streams=2 Messages: Unexpected file "test-multithread-copy-true-10485760-2" 2026/02/02 04:58:51 DEBUG : test-multithread-copy-true-10485761-2: multi-thread copy: disabling buffering because source is local disk 2026/02/02 04:58:52 DEBUG : test-multithread-copy-true-10485761-2: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 2 2026/02/02 04:58:52 DEBUG : test-multithread-copy-true-10485761-2: multi-thread copy: number of streams 4 was bigger than number of chunks 3 2026/02/02 04:58:52 DEBUG : test-multithread-copy-true-10485761-2: Starting multi-thread copy with 3 chunks of size 5Mi with 3 parallel streams 2026/02/02 04:58:52 DEBUG : test-multithread-copy-true-10485761-2: multi-thread copy: chunk 3/3 (10485760-10485761) size 1 starting 2026/02/02 04:58:52 DEBUG : test-multithread-copy-true-10485761-2: multi-thread copy: chunk 1/3 (0-5242880) size 5Mi starting 2026/02/02 04:58:52 DEBUG : test-multithread-copy-true-10485761-2: multi-thread copy: chunk 2/3 (5242880-10485760) size 5Mi starting 2026/02/02 04:58:52 DEBUG : test-multithread-copy-true-10485761-2: multi-thread copy: chunk 3/3 (10485760-10485761) size 1 finished 2026/02/02 04:58:52 DEBUG : test-multithread-copy-true-10485761-2: multi-thread copy: chunk 2/3 (5242880-10485760) size 5Mi finished 2026/02/02 04:58:53 DEBUG : test-multithread-copy-true-10485761-2: multi-thread copy: chunk 1/3 (0-5242880) size 5Mi finished 2026/02/02 04:58:54 DEBUG : test-multithread-copy-true-10485761-2: Finished multi-thread copy with 3 parts of size 5Mi 2026/02/02 04:58:55 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/02 04:58:55 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2026/02/02 04:58:56 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/02 04:58:56 DEBUG : pacer: Rate limited, increasing sleep to 40ms 2026/02/02 04:58:56 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/02 04:58:56 DEBUG : pacer: Rate limited, increasing sleep to 80ms 2026/02/02 04:58:57 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/02 04:58:57 DEBUG : pacer: Rate limited, increasing sleep to 160ms 2026/02/02 04:58:57 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/02 04:58:57 DEBUG : pacer: Rate limited, increasing sleep to 320ms 2026/02/02 04:58:57 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/02 04:58:57 DEBUG : pacer: Rate limited, increasing sleep to 640ms 2026/02/02 04:58:58 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/02 04:58:58 DEBUG : pacer: Rate limited, increasing sleep to 1.28s 2026/02/02 04:58:58 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/02 04:58:58 DEBUG : pacer: Rate limited, increasing sleep to 2.56s 2026/02/02 04:59:00 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/02 04:59:00 DEBUG : pacer: Rate limited, increasing sleep to 5.12s 2026/02/02 04:59:02 DEBUG : pacer: low level retry 10/10 (error Error "Server Error") 2026/02/02 04:59:02 DEBUG : pacer: Rate limited, increasing sleep to 10.24s === NAME TestMultithreadCopy run.go:130: removing file "test-multithread-copy-true-10485761-2" failed - try 1/3: failed to delete item: Error "Server Error" 2026/02/02 04:59:07 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/02 04:59:07 DEBUG : pacer: Rate limited, increasing sleep to 20s 2026/02/02 04:59:17 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/02 04:59:38 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/02 04:59:57 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/02 05:00:18 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/02 05:00:37 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/02 05:00:57 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/02 05:01:18 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/02 05:01:38 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/02 05:01:58 DEBUG : pacer: low level retry 10/10 (error Error "Server Error") run.go:130: removing file "test-multithread-copy-true-10485761-2" failed - try 2/3: failed to delete item: Error "Server Error" 2026/02/02 05:02:18 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/02 05:02:38 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/02 05:02:58 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/02 05:03:17 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/02 05:03:38 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/02 05:03:58 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/02 05:04:18 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/02 05:04:38 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/02 05:04:58 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/02 05:05:18 DEBUG : pacer: low level retry 10/10 (error Error "Server Error") run.go:130: removing file "test-multithread-copy-true-10485761-2" failed - try 3/3: failed to delete item: Error "Server Error" run.go:133: removing file "test-multithread-copy-true-10485761-2" failed: failed to delete item: Error "Server Error" 2026/02/02 05:05:38 DEBUG : pacer: Reducing sleep to 10s --- FAIL: TestMultithreadCopy (483.26s) --- FAIL: TestMultithreadCopy/upload=false,size=10485759,streams=2 (14.85s) --- PASS: TestMultithreadCopy/upload=false,size=10485760,streams=2 (17.45s) --- PASS: TestMultithreadCopy/upload=false,size=10485761,streams=2 (4.81s) --- FAIL: TestMultithreadCopy/upload=true,size=10485759,streams=2 (14.51s) --- FAIL: TestMultithreadCopy/upload=true,size=10485760,streams=2 (13.97s) --- FAIL: TestMultithreadCopy/upload=true,size=10485761,streams=2 (13.33s) === RUN TestParseSumFile run.go:185: Remote "drime root 'rclone-test-mopoweh7toso'", Local "Local file system at /tmp/rclone477205313", Modify Window "876000h0m0s" 2026/02/02 05:05:58 DEBUG : pacer: Reducing sleep to 5s 2026/02/02 05:06:08 DEBUG : pacer: Reducing sleep to 2.5s 2026/02/02 05:06:13 DEBUG : pacer: Reducing sleep to 1.25s 2026/02/02 05:06:16 DEBUG : pacer: Reducing sleep to 625ms 2026/02/02 05:06:16 NOTICE: test.sum: improperly formatted checksum line 4 2026/02/02 05:06:16 NOTICE: test.sum: improperly formatted checksum line 5 2026/02/02 05:06:16 NOTICE: test.sum: improperly formatted checksum line 6 2026/02/02 05:06:16 NOTICE: test.sum: 2 warning(s) suppressed... 2026/02/02 05:06:17 DEBUG : pacer: Reducing sleep to 312.5ms 2026/02/02 05:06:18 DEBUG : pacer: Reducing sleep to 156.25ms 2026/02/02 05:06:18 DEBUG : test.sum: Removing old object on successful upload 2026/02/02 05:06:18 DEBUG : pacer: Reducing sleep to 78.125ms 2026/02/02 05:06:19 DEBUG : pacer: Reducing sleep to 39.0625ms 2026/02/02 05:06:19 DEBUG : pacer: Reducing sleep to 19.53125ms 2026/02/02 05:06:19 NOTICE: test.sum: improperly formatted checksum line 4 2026/02/02 05:06:19 NOTICE: test.sum: improperly formatted checksum line 5 2026/02/02 05:06:19 NOTICE: test.sum: improperly formatted checksum line 6 2026/02/02 05:06:19 NOTICE: test.sum: 2 warning(s) suppressed... 2026/02/02 05:06:19 DEBUG : pacer: Reducing sleep to 10ms 2026/02/02 05:06:20 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/02 05:06:20 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2026/02/02 05:06:20 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/02 05:06:20 DEBUG : pacer: Rate limited, increasing sleep to 40ms 2026/02/02 05:06:20 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/02 05:06:20 DEBUG : pacer: Rate limited, increasing sleep to 80ms 2026/02/02 05:06:20 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/02 05:06:20 DEBUG : pacer: Rate limited, increasing sleep to 160ms 2026/02/02 05:06:20 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/02 05:06:20 DEBUG : pacer: Rate limited, increasing sleep to 320ms 2026/02/02 05:06:21 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/02 05:06:21 DEBUG : pacer: Rate limited, increasing sleep to 640ms 2026/02/02 05:06:21 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/02 05:06:21 DEBUG : pacer: Rate limited, increasing sleep to 1.28s 2026/02/02 05:06:22 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/02 05:06:22 DEBUG : pacer: Rate limited, increasing sleep to 2.56s 2026/02/02 05:06:24 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/02 05:06:24 DEBUG : pacer: Rate limited, increasing sleep to 5.12s 2026/02/02 05:06:26 DEBUG : pacer: low level retry 10/10 (error Error "Server Error") 2026/02/02 05:06:26 DEBUG : pacer: Rate limited, increasing sleep to 10.24s run.go:130: removing file "test.sum" failed - try 1/3: failed to delete item: Error "Server Error" 2026/02/02 05:06:31 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/02 05:06:31 DEBUG : pacer: Rate limited, increasing sleep to 20s 2026/02/02 05:06:41 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/02 05:07:01 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/02 05:07:21 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/02 05:07:41 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/02 05:08:01 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/02 05:08:21 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/02 05:08:41 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/02 05:09:01 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/02 05:09:21 DEBUG : pacer: low level retry 10/10 (error Error "Server Error") run.go:130: removing file "test.sum" failed - try 2/3: failed to delete item: Error "Server Error" 2026/02/02 05:09:41 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/02 05:10:01 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/02 05:10:22 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/02 05:10:41 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/02 05:11:01 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/02 05:11:21 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/02 05:11:41 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/02 05:12:01 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/02 05:12:21 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/02 05:12:41 DEBUG : pacer: low level retry 10/10 (error Error "Server Error") run.go:130: removing file "test.sum" failed - try 3/3: failed to delete item: Error "Server Error" run.go:133: removing file "test.sum" failed: failed to delete item: Error "Server Error" 2026/02/02 05:13:01 DEBUG : pacer: Reducing sleep to 10s fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache 2026/02/02 05:13:21 DEBUG : pacer: Reducing sleep to 5s 2026/02/02 05:13:32 DEBUG : pacer: Reducing sleep to 2.5s fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache 2026/02/02 05:13:37 DEBUG : pacer: Reducing sleep to 1.25s 2026/02/02 05:13:39 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 (485.19s) === RUN TestCheckSumDownload run.go:185: Remote "drime root 'rclone-test-mopoweh7toso'", Local "Local file system at /tmp/rclone477205313", Modify Window "876000h0m0s" 2026/02/02 05:13:43 DEBUG : Creating backend with remote "TestDrime:rclone-test-mopoweh7toso/data" 2026/02/02 05:13:43 DEBUG : Config file has changed externally - reloading 2026/02/02 05:13:44 DEBUG : pacer: Reducing sleep to 312.5ms 2026/02/02 05:13:45 DEBUG : pacer: Reducing sleep to 156.25ms 2026/02/02 05:13:45 DEBUG : pacer: Reducing sleep to 78.125ms 2026/02/02 05:13:46 DEBUG : pacer: Reducing sleep to 39.0625ms 2026/02/02 05:13:47 DEBUG : pacer: Reducing sleep to 19.53125ms 2026/02/02 05:13:47 DEBUG : pacer: Reducing sleep to 10ms 2026/02/02 05:13:48 DEBUG : test.sum: Removing old object on successful upload fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:436 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:541 /usr/local/go/src/runtime/asm_amd64.s:1700 Error: Should be true Test: TestCheckSumDownload Messages: listing wrong, want data/banana (13), test.sum (41) got test.sum (90) fstest.go:150: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:150 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:195 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:436 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:541 Error: Not equal: expected: 41 actual : 90 Test: TestCheckSumDownload Messages: test.sum: size incorrect file=41 vs obj=90 fstest.go:203: Not found "data/banana" fstest.go:206: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:206 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:310 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:436 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:541 Error: Not equal: expected: 0 actual : 1 Test: TestCheckSumDownload Messages: 1 objects not found === RUN TestCheckSumDownload/subtest1 2026/02/02 05:14:01 DEBUG : test.sum: Removing old object on successful upload === NAME TestCheckSumDownload fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:450 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:541 /usr/local/go/src/runtime/asm_amd64.s:1700 Error: Should be true Test: TestCheckSumDownload Messages: listing wrong, want data/banana (13), data/potato (15), test.sum (41) got data/banana (13), test.sum (41) fstest.go:203: Not found "data/potato" fstest.go:206: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:206 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:310 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:450 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:541 Error: Not equal: expected: 0 actual : 1 Test: TestCheckSumDownload Messages: 1 objects not found === RUN TestCheckSumDownload/subtest2 2026/02/02 05:14:13 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: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) 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:464 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:541 Error: Not equal: expected: 82 actual : 41 Test: TestCheckSumDownload Messages: test.sum: size incorrect file=82 vs obj=41 === RUN TestCheckSumDownload/subtest3 2026/02/02 05:14:26 DEBUG : test.sum: Removing old object on successful upload === RUN TestCheckSumDownload/subtest4 === NAME TestCheckSumDownload check_test.go:407: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:407 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:428 Error: An error is expected but got nil. Test: TestCheckSumDownload Messages: no expected error in run 4 check_test.go:409: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:409 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:428 Error: Not equal: expected: 1 actual : 0 Test: TestCheckSumDownload Messages: wrong error count in run 4 check_test.go:384: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:384 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:417 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:428 Error: Not equal: expected: []string{"", "* banana", "= potato"} actual : []string{"", "= banana", "= potato"} Diff: --- Expected +++ Actual @@ -2,3 +2,3 @@ (string) "", - (string) (len=8) "* banana", + (string) (len=8) "= banana", (string) (len=8) "= potato" Test: TestCheckSumDownload Messages: wrong combined result in run 4 check_test.go:384: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:384 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:420 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:428 Error: Not equal: expected: []string{"", "potato"} actual : []string{"", "banana", "potato"} Diff: --- Expected +++ Actual @@ -1,3 +1,4 @@ -([]string) (len=2) { +([]string) (len=3) { (string) "", + (string) (len=6) "banana", (string) (len=6) "potato" Test: TestCheckSumDownload Messages: wrong match result in run 4 check_test.go:384: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:384 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:421 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:428 Error: Not equal: expected: []string{"", "banana"} actual : []string{} Diff: --- Expected +++ Actual @@ -1,4 +1,2 @@ -([]string) (len=2) { - (string) "", - (string) (len=6) "banana" +([]string) { } Test: TestCheckSumDownload Messages: wrong differ result in run 4 2026/02/02 05:14:28 DEBUG : test.sum: Removing old object on successful upload 2026/02/02 05:14:29 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/02 05:14:29 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2026/02/02 05:14:29 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/02 05:14:29 DEBUG : pacer: Rate limited, increasing sleep to 40ms 2026/02/02 05:14:29 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/02 05:14:29 DEBUG : pacer: Rate limited, increasing sleep to 80ms 2026/02/02 05:14:29 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/02 05:14:29 DEBUG : pacer: Rate limited, increasing sleep to 160ms 2026/02/02 05:14:30 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/02 05:14:30 DEBUG : pacer: Rate limited, increasing sleep to 320ms 2026/02/02 05:14:30 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/02 05:14:30 DEBUG : pacer: Rate limited, increasing sleep to 640ms 2026/02/02 05:14:31 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/02 05:14:31 DEBUG : pacer: Rate limited, increasing sleep to 1.28s 2026/02/02 05:14:31 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/02 05:14:31 DEBUG : pacer: Rate limited, increasing sleep to 2.56s 2026/02/02 05:14:32 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/02 05:14:32 DEBUG : pacer: Rate limited, increasing sleep to 5.12s 2026/02/02 05:14:35 DEBUG : pacer: low level retry 10/10 (error Error "Server Error") 2026/02/02 05:14:35 DEBUG : pacer: Rate limited, increasing sleep to 10.24s run.go:286: Retry Put of "test.sum" to drime root 'rclone-test-mopoweh7toso': 1/10 (failed to delete existing object: failed to delete item: Error "Server Error") 2026/02/02 05:14:40 DEBUG : pacer: Reducing sleep to 5.12s 2026/02/02 05:14:51 DEBUG : pacer: Reducing sleep to 2.56s 2026/02/02 05:14:51 DEBUG : test.sum: Removing old object on successful upload 2026/02/02 05:14:55 DEBUG : pacer: Reducing sleep to 1.28s 2026/02/02 05:14:58 DEBUG : pacer: Reducing sleep to 640ms 2026/02/02 05:14:59 DEBUG : pacer: Reducing sleep to 320ms fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache 2026/02/02 05:15:01 DEBUG : pacer: Reducing sleep to 160ms 2026/02/02 05:15:01 DEBUG : pacer: Reducing sleep to 80ms 2026/02/02 05:15:01 DEBUG : pacer: Reducing sleep to 40ms fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache 2026/02/02 05:15:03 DEBUG : pacer: Reducing sleep to 20ms 2026/02/02 05:15:03 DEBUG : pacer: Reducing sleep to 10ms fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:493 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:541 /usr/local/go/src/runtime/asm_amd64.s:1700 Error: Should be true Test: TestCheckSumDownload Messages: listing wrong, want data/banana (13), data/potato (15), test.sum (123) got data/banana (13), data/potato (15), test.sum (123), test.sum (82) fstest.go:191: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:493 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:541 Error: Should be true Test: TestCheckSumDownload Messages: Unexpected file "test.sum" === RUN TestCheckSumDownload/subtest5 2026/02/02 05:15:11 DEBUG : test.sum: Removing old object on successful upload === NAME TestCheckSumDownload fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:508 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:541 /usr/local/go/src/runtime/asm_amd64.s:1700 Error: Should be true Test: TestCheckSumDownload Messages: listing wrong, want data/banana (13), data/potato (15), test.sum (123) got data/banana (13), data/potato (15), test.sum (123), test.sum (82) fstest.go:191: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:508 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:541 Error: Should be true Test: TestCheckSumDownload Messages: Unexpected file "test.sum" === RUN TestCheckSumDownload/subtest6 2026/02/02 05:15:23 DEBUG : data/banana: Removing old object on successful upload 2026/02/02 05:15:25 DEBUG : data/potato: Removing old object on successful upload 2026/02/02 05:15:26 DEBUG : test.sum: Removing old object on successful upload === NAME TestCheckSumDownload fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:525 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:541 /usr/local/go/src/runtime/asm_amd64.s:1700 Error: Should be true Test: TestCheckSumDownload Messages: listing wrong, want data/banana (13), data/potato (15), test.sum (82) got data/banana (13), data/potato (15), test.sum (82), test.sum (82) fstest.go:191: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:525 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:541 Error: Should be true Test: TestCheckSumDownload Messages: Unexpected file "test.sum" === RUN TestCheckSumDownload/subtest7 2026/02/02 05:15:38 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/02 05:15:38 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2026/02/02 05:15:39 DEBUG : pacer: Reducing sleep to 10ms === NAME TestCheckSumDownload run.go:130: removing dir "data" failed - try 1/3: directory not empty run.go:130: removing dir "data" failed - try 2/3: directory not empty run.go:130: removing dir "data" failed - try 3/3: directory not empty run.go:133: removing dir "data" failed: directory not empty fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:327: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:327 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393 /usr/local/go/src/testing/testing.go:1211 /usr/local/go/src/testing/testing.go:1445 /usr/local/go/src/testing/testing.go:1786 Error: Not equal: expected: []string{} actual : []string{"data"} Diff: --- Expected +++ Actual @@ -1,2 +1,3 @@ -([]string) { +([]string) (len=1) { + (string) (len=4) "data" } Test: TestCheckSumDownload Messages: directories --- FAIL: TestCheckSumDownload (129.22s) --- PASS: TestCheckSumDownload/subtest1 (2.68s) --- PASS: TestCheckSumDownload/subtest2 (1.55s) --- PASS: TestCheckSumDownload/subtest3 (2.00s) --- PASS: TestCheckSumDownload/subtest4 (1.31s) --- PASS: TestCheckSumDownload/subtest5 (1.81s) --- PASS: TestCheckSumDownload/subtest6 (2.25s) --- PASS: TestCheckSumDownload/subtest7 (1.69s) === RUN TestApplyTransforms 2026/02/02 05:15:52 DEBUG : Creating backend with remote "TestDrime:rclone-test-nukadix1foju" 2026/02/02 05:15:52 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/02/02 05:15:53 DEBUG : Creating backend with remote "/tmp/rclone1256251578" run.go:185: Remote "drime root 'rclone-test-nukadix1foju'", Local "Local file system at /tmp/rclone1256251578", Modify Window "876000h0m0s" upper checkfile vs. lower remote (without normalization) 2026/02/02 05:15:55 ERROR : HELLO, WORLD!: file not in drime root 'rclone-test-nukadix1foju' 2026/02/02 05:15:55 NOTICE: drime root 'rclone-test-nukadix1foju': 1 files missing 2026/02/02 05:15:55 NOTICE: drime root 'rclone-test-nukadix1foju': 0 differences found 2026/02/02 05:15:55 NOTICE: drime root 'rclone-test-nukadix1foju': 1 errors while checking upper checkfile vs. lower remote (with normalization) 2026/02/02 05:15:56 ERROR : hello, world!: file not in drime root 'rclone-test-nukadix1foju' 2026/02/02 05:15:56 NOTICE: drime root 'rclone-test-nukadix1foju': 1 files missing 2026/02/02 05:15:56 NOTICE: drime root 'rclone-test-nukadix1foju': 0 differences found 2026/02/02 05:15:56 NOTICE: drime root 'rclone-test-nukadix1foju': 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-nukadix1foju' Test: TestApplyTransforms Messages: unexpected error for upper checkfile vs. lower remote (with normalization) HELLO, WORLD! hello, world! 2026/02/02 05:15:56 DEBUG : Creating backend with remote "TestDrime:rclone-test-mexoqor1xezo" 2026/02/02 05:15:56 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/02/02 05:15:56 DEBUG : Creating backend with remote "/tmp/rclone1347501648" run.go:185: Remote "drime root 'rclone-test-mexoqor1xezo'", Local "Local file system at /tmp/rclone1347501648", Modify Window "876000h0m0s" lower checkfile vs. upper remote (without normalization) 2026/02/02 05:15:58 ERROR : hello, world!: file not in drime root 'rclone-test-mexoqor1xezo' 2026/02/02 05:15:58 NOTICE: drime root 'rclone-test-mexoqor1xezo': 1 files missing 2026/02/02 05:15:58 NOTICE: drime root 'rclone-test-mexoqor1xezo': 0 differences found 2026/02/02 05:15:58 NOTICE: drime root 'rclone-test-mexoqor1xezo': 1 errors while checking lower checkfile vs. upper remote (with normalization) 2026/02/02 05:15:58 ERROR : hello, world!: file not in drime root 'rclone-test-mexoqor1xezo' 2026/02/02 05:15:58 NOTICE: drime root 'rclone-test-mexoqor1xezo': 1 files missing 2026/02/02 05:15:58 NOTICE: drime root 'rclone-test-mexoqor1xezo': 0 differences found 2026/02/02 05:15:58 NOTICE: drime root 'rclone-test-mexoqor1xezo': 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-mexoqor1xezo' Test: TestApplyTransforms Messages: unexpected error for lower checkfile vs. upper remote (with normalization) hello, world! HELLO, WORLD! 2026/02/02 05:15:58 DEBUG : Creating backend with remote "TestDrime:rclone-test-mukupuv9zari" 2026/02/02 05:15:58 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/02/02 05:15:59 DEBUG : Creating backend with remote "/tmp/rclone3406191331" run.go:185: Remote "drime root 'rclone-test-mukupuv9zari'", Local "Local file system at /tmp/rclone3406191331", Modify Window "876000h0m0s" lower checkfile vs. upperlowermixed remote (without normalization) 2026/02/02 05:16:01 ERROR : hello, world!: file not in drime root 'rclone-test-mukupuv9zari' 2026/02/02 05:16:01 NOTICE: drime root 'rclone-test-mukupuv9zari': 1 files missing 2026/02/02 05:16:01 NOTICE: drime root 'rclone-test-mukupuv9zari': 0 differences found 2026/02/02 05:16:01 NOTICE: drime root 'rclone-test-mukupuv9zari': 1 errors while checking lower checkfile vs. upperlowermixed remote (with normalization) 2026/02/02 05:16:01 ERROR : hello, world!: file not in drime root 'rclone-test-mukupuv9zari' 2026/02/02 05:16:01 NOTICE: drime root 'rclone-test-mukupuv9zari': 1 files missing 2026/02/02 05:16:01 NOTICE: drime root 'rclone-test-mukupuv9zari': 0 differences found 2026/02/02 05:16:01 NOTICE: drime root 'rclone-test-mukupuv9zari': 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-mukupuv9zari' Test: TestApplyTransforms Messages: unexpected error for lower checkfile vs. upperlowermixed remote (with normalization) hello, world! HeLlO, wOrLd! 2026/02/02 05:16:01 DEBUG : Creating backend with remote "TestDrime:rclone-test-zugixuj1qexa" 2026/02/02 05:16:01 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/02/02 05:16:01 DEBUG : Creating backend with remote "/tmp/rclone3113049229" run.go:185: Remote "drime root 'rclone-test-zugixuj1qexa'", Local "Local file system at /tmp/rclone3113049229", Modify Window "876000h0m0s" upperlowermixed checkfile vs. upper remote (without normalization) 2026/02/02 05:16:04 ERROR : HeLlO, wOrLd!: file not in drime root 'rclone-test-zugixuj1qexa' 2026/02/02 05:16:04 NOTICE: drime root 'rclone-test-zugixuj1qexa': 1 files missing 2026/02/02 05:16:04 NOTICE: drime root 'rclone-test-zugixuj1qexa': 0 differences found 2026/02/02 05:16:04 NOTICE: drime root 'rclone-test-zugixuj1qexa': 1 errors while checking upperlowermixed checkfile vs. upper remote (with normalization) 2026/02/02 05:16:04 ERROR : hello, world!: file not in drime root 'rclone-test-zugixuj1qexa' 2026/02/02 05:16:04 NOTICE: drime root 'rclone-test-zugixuj1qexa': 1 files missing 2026/02/02 05:16:04 NOTICE: drime root 'rclone-test-zugixuj1qexa': 0 differences found 2026/02/02 05:16:04 NOTICE: drime root 'rclone-test-zugixuj1qexa': 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-zugixuj1qexa' Test: TestApplyTransforms Messages: unexpected error for upperlowermixed checkfile vs. upper remote (with normalization) HeLlO, wOrLd! HELLO, WORLD! 2026/02/02 05:16:04 DEBUG : Creating backend with remote "TestDrime:rclone-test-nafejij0mize" 2026/02/02 05:16:04 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/02/02 05:16:05 DEBUG : Creating backend with remote "/tmp/rclone1643634500" run.go:185: Remote "drime root 'rclone-test-nafejij0mize'", Local "Local file system at /tmp/rclone1643634500", Modify Window "876000h0m0s" NFD checkfile vs. NFC remote (without normalization) 2026/02/02 05:16:08 ERROR : 測試_Русский___ě_áñ: file not in drime root 'rclone-test-nafejij0mize' 2026/02/02 05:16:08 NOTICE: drime root 'rclone-test-nafejij0mize': 1 files missing 2026/02/02 05:16:08 NOTICE: drime root 'rclone-test-nafejij0mize': 0 differences found 2026/02/02 05:16:08 NOTICE: drime root 'rclone-test-nafejij0mize': 1 errors while checking NFD checkfile vs. NFC remote (with normalization) 2026/02/02 05:16:08 ERROR : 測試_русский___ě_áñ: file not in drime root 'rclone-test-nafejij0mize' 2026/02/02 05:16:08 NOTICE: drime root 'rclone-test-nafejij0mize': 1 files missing 2026/02/02 05:16:08 NOTICE: drime root 'rclone-test-nafejij0mize': 0 differences found 2026/02/02 05:16:08 NOTICE: drime root 'rclone-test-nafejij0mize': 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-nafejij0mize' Test: TestApplyTransforms Messages: unexpected error for NFD checkfile vs. NFC remote (with normalization) 測試_Русский___ě_áñ 測試_Русский___ě_áñ 2026/02/02 05:16:08 DEBUG : Creating backend with remote "TestDrime:rclone-test-gazeheh8caja" 2026/02/02 05:16:08 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/02/02 05:16:08 DEBUG : Creating backend with remote "/tmp/rclone878870383" run.go:185: Remote "drime root 'rclone-test-gazeheh8caja'", Local "Local file system at /tmp/rclone878870383", Modify Window "876000h0m0s" NFC checkfile vs. NFD remote (without normalization) 2026/02/02 05:16:11 ERROR : 測試_Русский___ě_áñ: file not in drime root 'rclone-test-gazeheh8caja' 2026/02/02 05:16:11 NOTICE: drime root 'rclone-test-gazeheh8caja': 1 files missing 2026/02/02 05:16:11 NOTICE: drime root 'rclone-test-gazeheh8caja': 0 differences found 2026/02/02 05:16:11 NOTICE: drime root 'rclone-test-gazeheh8caja': 1 errors while checking NFC checkfile vs. NFD remote (with normalization) 2026/02/02 05:16:11 ERROR : 測試_русский___ě_áñ: file not in drime root 'rclone-test-gazeheh8caja' 2026/02/02 05:16:11 NOTICE: drime root 'rclone-test-gazeheh8caja': 1 files missing 2026/02/02 05:16:11 NOTICE: drime root 'rclone-test-gazeheh8caja': 0 differences found 2026/02/02 05:16:11 NOTICE: drime root 'rclone-test-gazeheh8caja': 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-gazeheh8caja' Test: TestApplyTransforms Messages: unexpected error for NFC checkfile vs. NFD remote (with normalization) 測試_Русский___ě_áñ 測試_Русский___ě_áñ 2026/02/02 05:16:11 DEBUG : Creating backend with remote "TestDrime:rclone-test-niwojac0gugi" 2026/02/02 05:16:11 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/02/02 05:16:11 DEBUG : Creating backend with remote "/tmp/rclone140951162" run.go:185: Remote "drime root 'rclone-test-niwojac0gugi'", Local "Local file system at /tmp/rclone140951162", Modify Window "876000h0m0s" NFDx2 checkfile vs. both remote (without normalization) 2026/02/02 05:16:14 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in drime root 'rclone-test-niwojac0gugi' 2026/02/02 05:16:14 NOTICE: drime root 'rclone-test-niwojac0gugi': 1 files missing 2026/02/02 05:16:14 NOTICE: drime root 'rclone-test-niwojac0gugi': 0 differences found 2026/02/02 05:16:14 NOTICE: drime root 'rclone-test-niwojac0gugi': 1 errors while checking NFDx2 checkfile vs. both remote (with normalization) 2026/02/02 05:16:14 ERROR : 測試_русский___ě_áñ測試_русский___ě_áñ: file not in drime root 'rclone-test-niwojac0gugi' 2026/02/02 05:16:14 NOTICE: drime root 'rclone-test-niwojac0gugi': 1 files missing 2026/02/02 05:16:14 NOTICE: drime root 'rclone-test-niwojac0gugi': 0 differences found 2026/02/02 05:16:14 NOTICE: drime root 'rclone-test-niwojac0gugi': 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-niwojac0gugi' Test: TestApplyTransforms Messages: unexpected error for NFDx2 checkfile vs. both remote (with normalization) 測試_Русский___ě_áñ測試_Русский___ě_áñ 測試_Русский___ě_áñ測試_Русский___ě_áñ 2026/02/02 05:16:14 DEBUG : Creating backend with remote "TestDrime:rclone-test-nafuwiq0foda" 2026/02/02 05:16:14 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/02/02 05:16:14 DEBUG : Creating backend with remote "/tmp/rclone3754053292" run.go:185: Remote "drime root 'rclone-test-nafuwiq0foda'", Local "Local file system at /tmp/rclone3754053292", Modify Window "876000h0m0s" NFCx2 checkfile vs. both remote (without normalization) 2026/02/02 05:16:16 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in drime root 'rclone-test-nafuwiq0foda' 2026/02/02 05:16:16 NOTICE: drime root 'rclone-test-nafuwiq0foda': 1 files missing 2026/02/02 05:16:16 NOTICE: drime root 'rclone-test-nafuwiq0foda': 0 differences found 2026/02/02 05:16:16 NOTICE: drime root 'rclone-test-nafuwiq0foda': 1 errors while checking NFCx2 checkfile vs. both remote (with normalization) 2026/02/02 05:16:17 ERROR : 測試_русский___ě_áñ測試_русский___ě_áñ: file not in drime root 'rclone-test-nafuwiq0foda' 2026/02/02 05:16:17 NOTICE: drime root 'rclone-test-nafuwiq0foda': 1 files missing 2026/02/02 05:16:17 NOTICE: drime root 'rclone-test-nafuwiq0foda': 0 differences found 2026/02/02 05:16:17 NOTICE: drime root 'rclone-test-nafuwiq0foda': 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-nafuwiq0foda' Test: TestApplyTransforms Messages: unexpected error for NFCx2 checkfile vs. both remote (with normalization) 測試_Русский___ě_áñ測試_Русский___ě_áñ 測試_Русский___ě_áñ測試_Русский___ě_áñ 2026/02/02 05:16:17 DEBUG : Creating backend with remote "TestDrime:rclone-test-gepudiz6qoko" 2026/02/02 05:16:17 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/02/02 05:16:17 DEBUG : Creating backend with remote "/tmp/rclone2505449170" run.go:185: Remote "drime root 'rclone-test-gepudiz6qoko'", Local "Local file system at /tmp/rclone2505449170", Modify Window "876000h0m0s" both checkfile vs. NFDx2 remote (without normalization) 2026/02/02 05:16:19 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in drime root 'rclone-test-gepudiz6qoko' 2026/02/02 05:16:19 NOTICE: drime root 'rclone-test-gepudiz6qoko': 1 files missing 2026/02/02 05:16:19 NOTICE: drime root 'rclone-test-gepudiz6qoko': 0 differences found 2026/02/02 05:16:19 NOTICE: drime root 'rclone-test-gepudiz6qoko': 1 errors while checking both checkfile vs. NFDx2 remote (with normalization) 2026/02/02 05:16:19 ERROR : 測試_русский___ě_áñ測試_русский___ě_áñ: file not in drime root 'rclone-test-gepudiz6qoko' 2026/02/02 05:16:19 NOTICE: drime root 'rclone-test-gepudiz6qoko': 1 files missing 2026/02/02 05:16:19 NOTICE: drime root 'rclone-test-gepudiz6qoko': 0 differences found 2026/02/02 05:16:19 NOTICE: drime root 'rclone-test-gepudiz6qoko': 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-gepudiz6qoko' Test: TestApplyTransforms Messages: unexpected error for both checkfile vs. NFDx2 remote (with normalization) 測試_Русский___ě_áñ測試_Русский___ě_áñ 測試_Русский___ě_áñ測試_Русский___ě_áñ 2026/02/02 05:16:19 DEBUG : Creating backend with remote "TestDrime:rclone-test-jupoqan4wipa" 2026/02/02 05:16:19 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/02/02 05:16:20 DEBUG : Creating backend with remote "/tmp/rclone3119594801" run.go:185: Remote "drime root 'rclone-test-jupoqan4wipa'", Local "Local file system at /tmp/rclone3119594801", Modify Window "876000h0m0s" both checkfile vs. NFCx2 remote (without normalization) 2026/02/02 05:16:23 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in drime root 'rclone-test-jupoqan4wipa' 2026/02/02 05:16:23 NOTICE: drime root 'rclone-test-jupoqan4wipa': 1 files missing 2026/02/02 05:16:23 NOTICE: drime root 'rclone-test-jupoqan4wipa': 0 differences found 2026/02/02 05:16:23 NOTICE: drime root 'rclone-test-jupoqan4wipa': 1 errors while checking both checkfile vs. NFCx2 remote (with normalization) 2026/02/02 05:16:23 ERROR : 測試_русский___ě_áñ測試_русский___ě_áñ: file not in drime root 'rclone-test-jupoqan4wipa' 2026/02/02 05:16:23 NOTICE: drime root 'rclone-test-jupoqan4wipa': 1 files missing 2026/02/02 05:16:23 NOTICE: drime root 'rclone-test-jupoqan4wipa': 0 differences found 2026/02/02 05:16:23 NOTICE: drime root 'rclone-test-jupoqan4wipa': 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-jupoqan4wipa' Test: TestApplyTransforms Messages: unexpected error for both checkfile vs. NFCx2 remote (with normalization) 測試_Русский___ě_áñ測試_Русский___ě_áñ 測試_Русский___ě_áñ測試_Русский___ě_áñ 2026/02/02 05:16:23 DEBUG : drime root 'rclone-test-jupoqan4wipa': Purge remote 2026/02/02 05:16:23 DEBUG : drime root 'rclone-test-gepudiz6qoko': Purge remote 2026/02/02 05:16:24 DEBUG : drime root 'rclone-test-nafuwiq0foda': Purge remote 2026/02/02 05:16:24 DEBUG : drime root 'rclone-test-niwojac0gugi': Purge remote 2026/02/02 05:16:24 DEBUG : drime root 'rclone-test-gazeheh8caja': Purge remote 2026/02/02 05:16:24 DEBUG : drime root 'rclone-test-nafejij0mize': Purge remote 2026/02/02 05:16:25 DEBUG : drime root 'rclone-test-zugixuj1qexa': Purge remote 2026/02/02 05:16:25 DEBUG : drime root 'rclone-test-mukupuv9zari': Purge remote 2026/02/02 05:16:25 DEBUG : drime root 'rclone-test-mexoqor1xezo': Purge remote 2026/02/02 05:16:25 DEBUG : drime root 'rclone-test-nukadix1foju': Purge remote --- FAIL: TestApplyTransforms (33.31s) === RUN TestCopyFile run.go:185: Remote "drime root 'rclone-test-mopoweh7toso'", Local "Local file system at /tmp/rclone477205313", Modify Window "876000h0m0s" 2026/02/02 05:16:26 DEBUG : file1: Need to transfer - File not found at Destination 2026/02/02 05:16:28 DEBUG : sub/file2: size = 14 OK 2026/02/02 05:16:28 INFO : file1: Copied (new) to: sub/file2 2026/02/02 05:16:29 DEBUG : sub/file2: size = 14 OK 2026/02/02 05:16:29 DEBUG : file1: Sizes identical 2026/02/02 05:16:29 DEBUG : file1: Unchanged skipping 2026/02/02 05:16:29 DEBUG : drime root 'rclone-test-mopoweh7toso': don't need to copy/move sub/file2, it is already at target location run.go:130: removing dir "sub" failed - try 1/3: directory not empty run.go:130: removing dir "sub" failed - try 2/3: directory not empty run.go:130: removing dir "sub" failed - try 3/3: directory not empty run.go:133: removing dir "sub" failed: directory not empty fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:327: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:327 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393 /usr/local/go/src/testing/testing.go:1211 /usr/local/go/src/testing/testing.go:1445 /usr/local/go/src/testing/testing.go:1786 Error: Not equal: expected: []string{} actual : []string{"sub"} Diff: --- Expected +++ Actual @@ -1,2 +1,3 @@ -([]string) { +([]string) (len=1) { + (string) (len=3) "sub" } Test: TestCopyFile Messages: directories --- FAIL: TestCopyFile (19.18s) === RUN TestCopyFileCompareDest run.go:185: Remote "drime root 'rclone-test-mopoweh7toso'", Local "Local file system at /tmp/rclone477205313", Modify Window "876000h0m0s" 2026/02/02 05:16:45 DEBUG : Creating backend with remote "TestDrime:rclone-test-mopoweh7toso/dst" 2026/02/02 05:16:46 DEBUG : Creating backend with remote "TestDrime:rclone-test-mopoweh7toso/CompareDest" 2026/02/02 05:16:47 DEBUG : one: Need to transfer - File not found at Destination 2026/02/02 05:16:49 DEBUG : one: size = 3 OK 2026/02/02 05:16:49 INFO : one: Copied (new) fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:292: Sleeping for 6s just to make sure 2026/02/02 05:16:59 DEBUG : one: size = 5 (Local file system at /tmp/rclone477205313) 2026/02/02 05:16:59 DEBUG : one: size = 3 (drime root 'rclone-test-mopoweh7toso/dst') 2026/02/02 05:16:59 DEBUG : one: Sizes differ 2026/02/02 05:17:00 DEBUG : one: Removing old object on successful upload 2026/02/02 05:17:00 DEBUG : one: size = 5 OK 2026/02/02 05:17:00 INFO : one: Copied (replaced existing) fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:292: Sleeping for 6s just to make sure 2026/02/02 05:17:09 DEBUG : dst/one: Removing old object on successful upload fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:292: Sleeping for 6s just to make sure 2026/02/02 05:17:20 DEBUG : one: size = 5 (Local file system at /tmp/rclone477205313) 2026/02/02 05:17:20 DEBUG : one: size = 3 (drime root 'rclone-test-mopoweh7toso/dst') 2026/02/02 05:17:20 DEBUG : one: Sizes differ 2026/02/02 05:17:20 DEBUG : one: size = 5 OK 2026/02/02 05:17:20 DEBUG : one: Sizes identical 2026/02/02 05:17:20 DEBUG : one: Destination found in --compare-dest, skipping fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:292: Sleeping for 6s just to make sure 2026/02/02 05:17:31 DEBUG : two: Need to transfer - File not found at Destination 2026/02/02 05:17:31 DEBUG : two: size = 3 OK 2026/02/02 05:17:31 DEBUG : two: Sizes identical 2026/02/02 05:17:31 DEBUG : two: Destination found in --compare-dest, skipping 2026/02/02 05:17:32 DEBUG : two: Need to transfer - File not found at Destination 2026/02/02 05:17:33 DEBUG : two: size = 3 OK 2026/02/02 05:17:33 DEBUG : two: Sizes identical 2026/02/02 05:17:33 DEBUG : two: Destination found in --compare-dest, skipping 2026/02/02 05:17:34 DEBUG : two: Need to transfer - File not found at Destination 2026/02/02 05:17:34 DEBUG : two: size = 5 (Local file system at /tmp/rclone477205313) 2026/02/02 05:17:34 DEBUG : two: size = 3 (drime root 'rclone-test-mopoweh7toso/CompareDest') 2026/02/02 05:17:34 DEBUG : two: Sizes differ 2026/02/02 05:17:35 DEBUG : two: size = 5 OK 2026/02/02 05:17:35 INFO : two: Copied (new) run.go:130: removing dir "dst" failed - try 1/3: directory not empty run.go:130: removing dir "dst" failed - try 2/3: directory not empty run.go:130: removing dir "dst" failed - try 3/3: directory not empty run.go:133: removing dir "dst" failed: directory not empty run.go:130: removing dir "CompareDest" failed - try 1/3: directory not empty run.go:130: removing dir "CompareDest" failed - try 2/3: directory not empty run.go:130: removing dir "CompareDest" failed - try 3/3: directory not empty run.go:133: removing dir "CompareDest" failed: directory not empty fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:327: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:327 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393 /usr/local/go/src/testing/testing.go:1211 /usr/local/go/src/testing/testing.go:1445 /usr/local/go/src/testing/testing.go:1786 Error: Not equal: expected: []string{} actual : []string{"CompareDest", "dst"} Diff: --- Expected +++ Actual @@ -1,2 +1,4 @@ -([]string) { +([]string) (len=2) { + (string) (len=11) "CompareDest", + (string) (len=3) "dst" } Test: TestCopyFileCompareDest Messages: directories --- FAIL: TestCopyFileCompareDest (71.49s) === RUN TestCopyFileCopyDest run.go:185: Remote "drime root 'rclone-test-mopoweh7toso'", Local "Local file system at /tmp/rclone477205313", Modify Window "876000h0m0s" 2026/02/02 05:17:56 DEBUG : Creating backend with remote "TestDrime:rclone-test-mopoweh7toso/dst" 2026/02/02 05:17:56 DEBUG : Config file has changed externally - reloading 2026/02/02 05:17:57 DEBUG : Creating backend with remote "TestDrime:rclone-test-mopoweh7toso/CopyDest" 2026/02/02 05:17:58 DEBUG : one: Need to transfer - File not found at Destination 2026/02/02 05:17:59 DEBUG : one: size = 3 OK 2026/02/02 05:17:59 INFO : one: Copied (new) fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/copy_test.go:295 Error: Should be true Test: TestCopyFileCopyDest Messages: listing wrong, want dst/one (3) got fstest.go:203: Not found "dst/one" fstest.go:206: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:206 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:310 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/copy_test.go:295 /usr/local/go/src/runtime/asm_amd64.s:1700 Error: Not equal: expected: 0 actual : 1 Test: TestCopyFileCopyDest Messages: 1 objects not found 2026/02/02 05:18:09 DEBUG : one: size = 5 (Local file system at /tmp/rclone477205313) 2026/02/02 05:18:09 DEBUG : one: size = 3 (drime root 'rclone-test-mopoweh7toso/dst') 2026/02/02 05:18:09 DEBUG : one: Sizes differ 2026/02/02 05:18:10 DEBUG : one: Removing old object on successful upload 2026/02/02 05:18:10 DEBUG : one: size = 5 OK 2026/02/02 05:18:10 INFO : one: Copied (replaced existing) fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/copy_test.go:308 Error: Should be true Test: TestCopyFileCopyDest Messages: listing wrong, want dst/one (5) got dst/one (3) fstest.go:150: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:150 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:195 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/copy_test.go:308 Error: Not equal: expected: 5 actual : 3 Test: TestCopyFileCopyDest Messages: dst/one: size incorrect file=5 vs obj=3 2026/02/02 05:18:21 DEBUG : dst/one: Removing old object on successful upload fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go: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:317 Error: Should be true Test: TestCopyFileCopyDest Messages: listing wrong, want CopyDest/one (5), dst/one (3) got CopyDest/one (5), dst/one (5) fstest.go:150: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:150 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:195 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/copy_test.go:317 Error: Not equal: expected: 3 actual : 5 Test: TestCopyFileCopyDest Messages: dst/one: size incorrect file=3 vs obj=5 2026/02/02 05:18:33 DEBUG : Creating backend with remote "TestDrime:rclone-test-mopoweh7toso/BackupDir" 2026/02/02 05:18:34 DEBUG : one: size = 5 (Local file system at /tmp/rclone477205313) 2026/02/02 05:18:34 DEBUG : one: size = 3 (drime root 'rclone-test-mopoweh7toso/dst') 2026/02/02 05:18:34 DEBUG : one: Sizes differ 2026/02/02 05:18:34 DEBUG : one: size = 5 OK 2026/02/02 05:18:34 DEBUG : one: Sizes identical 2026/02/02 05:18:34 DEBUG : one: size = 5 (Local file system at /tmp/rclone477205313) 2026/02/02 05:18:34 DEBUG : one: size = 3 (drime root 'rclone-test-mopoweh7toso/dst') 2026/02/02 05:18:34 DEBUG : one: Sizes differ 2026/02/02 05:18:36 INFO : one: Moved (server-side) 2026/02/02 05:18:37 DEBUG : one: Server side copy: removing existing object after successful copy 2026/02/02 05:18:37 DEBUG : one: size = 5 OK 2026/02/02 05:18:37 INFO : one: Copied (server-side copy) 2026/02/02 05:18:37 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), dst/one (5) 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 : 1 Test: TestCopyFileCopyDest Messages: 1 objects not found fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go: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 CopyDest/one (5), dst/one (5) fstest.go:203: Not found "BackupDir/one" 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 : 2 Test: TestCopyFileCopyDest Messages: 2 objects not found 2026/02/02 05:18:57 DEBUG : two: Need to transfer - File not found at Destination 2026/02/02 05:18:57 DEBUG : two: size = 3 OK 2026/02/02 05:18:57 DEBUG : two: Sizes identical 2026/02/02 05:18:58 DEBUG : two: size = 3 OK 2026/02/02 05:18:58 INFO : two: Copied (server-side copy) 2026/02/02 05:18:58 DEBUG : two: Destination found in --copy-dest, using server-side copy fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/copy_test.go:342 Error: Should be true Test: TestCopyFileCopyDest Messages: listing wrong, want BackupDir/one (3), CopyDest/one (5), CopyDest/two (3), dst/one (5), dst/two (3) got CopyDest/one (5), CopyDest/two (3), dst/one (5), dst/two (3) 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:342 /usr/local/go/src/runtime/asm_amd64.s:1700 Error: Not equal: expected: 0 actual : 1 Test: TestCopyFileCopyDest Messages: 1 objects not found 2026/02/02 05:19:08 DEBUG : two: size = 3 OK 2026/02/02 05:19:08 DEBUG : two: Sizes identical 2026/02/02 05:19:08 DEBUG : two: Unchanged skipping fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/copy_test.go:348 Error: Should be true Test: TestCopyFileCopyDest Messages: listing wrong, want BackupDir/one (3), CopyDest/one (5), CopyDest/two (3), dst/one (5), dst/two (3) got CopyDest/one (5), CopyDest/two (3), dst/one (5), dst/two (3) 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:348 /usr/local/go/src/runtime/asm_amd64.s:1700 Error: Not equal: expected: 0 actual : 1 Test: TestCopyFileCopyDest Messages: 1 objects not found fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/copy_test.go:353 Error: Should be true Test: TestCopyFileCopyDest Messages: listing wrong, want BackupDir/one (3), CopyDest/one (5), CopyDest/three (5), CopyDest/two (3), dst/one (5), dst/two (3) got CopyDest/one (5), CopyDest/three (5), CopyDest/two (3), dst/one (5), dst/two (3) 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:353 /usr/local/go/src/runtime/asm_amd64.s:1700 Error: Not equal: expected: 0 actual : 1 Test: TestCopyFileCopyDest Messages: 1 objects not found 2026/02/02 05:19:27 DEBUG : three: Need to transfer - File not found at Destination 2026/02/02 05:19:28 DEBUG : three: size = 7 (Local file system at /tmp/rclone477205313) 2026/02/02 05:19:28 DEBUG : three: size = 5 (drime root 'rclone-test-mopoweh7toso/CopyDest') 2026/02/02 05:19:28 DEBUG : three: Sizes differ 2026/02/02 05:19:28 DEBUG : three: Destination not found in --copy-dest 2026/02/02 05:19:29 DEBUG : three: size = 7 OK 2026/02/02 05:19:29 INFO : three: Copied (new) fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/copy_test.go:362 Error: Should be true Test: TestCopyFileCopyDest Messages: listing wrong, want BackupDir/one (3), CopyDest/one (5), CopyDest/three (5), CopyDest/two (3), dst/one (5), dst/three (7), dst/two (3) got CopyDest/one (5), CopyDest/three (5), CopyDest/two (3), dst/one (5), dst/three (7), dst/two (3) 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:362 /usr/local/go/src/runtime/asm_amd64.s:1700 Error: Not equal: expected: 0 actual : 1 Test: TestCopyFileCopyDest Messages: 1 objects not found --- FAIL: TestCopyFileCopyDest (107.06s) === RUN TestCopyInplace run.go:185: Remote "drime root 'rclone-test-mopoweh7toso'", Local "Local file system at /tmp/rclone477205313", Modify Window "876000h0m0s" copy_test.go:371: Partial uploads not supported --- SKIP: TestCopyInplace (0.57s) === RUN TestListDirSortedFn run.go:185: Remote "drime root 'rclone-test-mopoweh7toso'", Local "Local file system at /tmp/rclone477205313", Modify Window "876000h0m0s" fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/listdirsorted_test.go:36 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/listdirsorted_test.go:125 /usr/local/go/src/runtime/asm_amd64.s:1700 Error: Should be true Test: TestListDirSortedFn Messages: listing wrong, want a.txt (11), sub dir/hello world (11), sub dir/hello world2 (11), sub dir/ignore dir/.ignore (1), sub dir/ignore dir/should be ignored (9), sub dir/sub sub dir/hello world3 (11), zend.txt (5) got a.txt (11), sub dir/hello world (11), sub dir/hello world2 (11), sub dir/ignore dir/.ignore (1), sub dir/sub sub dir/hello world3 (11), zend.txt (5) fstest.go:203: Not found "sub dir/ignore dir/should be ignored" fstest.go:206: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:206 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:310 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/listdirsorted_test.go:36 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/listdirsorted_test.go:125 Error: Not equal: expected: 0 actual : 1 Test: TestListDirSortedFn Messages: 1 objects not found 2026/02/02 05:20:02 DEBUG : a.txt: Excluded (Size Filter) 2026/02/02 05:20:02 DEBUG : a.txt: Excluded 2026/02/02 05:20:02 DEBUG : sub dir/hello world2: Excluded (Size Filter) 2026/02/02 05:20:02 DEBUG : sub dir/hello world2: Excluded 2026/02/02 05:20:02 DEBUG : sub dir/hello world: Excluded (Size Filter) 2026/02/02 05:20:02 DEBUG : sub dir/hello world: Excluded 2026/02/02 05:20:03 DEBUG : sub dir/ignore dir: Excluded 2026/02/02 05:20:03 DEBUG : sub dir/hello world2: Excluded (Size Filter) 2026/02/02 05:20:03 DEBUG : sub dir/hello world2: Excluded 2026/02/02 05:20:03 DEBUG : sub dir/hello world: Excluded (Size Filter) 2026/02/02 05:20:03 DEBUG : sub dir/hello world: Excluded 2026/02/02 05:20:03 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/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"} Diff: --- Expected +++ Actual @@ -1,2 +1,4 @@ -([]string) { +([]string) (len=2) { + (string) (len=7) "sub dir", + (string) (len=18) "sub dir/ignore dir" } Test: TestListDirSortedFn Messages: directories --- FAIL: TestListDirSortedFn (40.32s) === RUN TestListJSON run.go:185: Remote "drime root 'rclone-test-mopoweh7toso'", Local "Local file system at /tmp/rclone477205313", Modify Window "876000h0m0s" === 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 (23.19s) --- FAIL: TestListJSON/Default (0.23s) --- FAIL: TestListJSON/DirsOnly (0.17s) --- FAIL: TestListJSON/Recurse (0.79s) --- FAIL: TestListJSON/Metadata (0.21s) === RUN TestStatJSON run.go:185: Remote "drime root 'rclone-test-mopoweh7toso'", Local "Local file system at /tmp/rclone477205313", Modify Window "876000h0m0s" fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/lsjson_test.go:263 Error: Should be true Test: TestStatJSON Messages: listing wrong, want file1 (5), sub/file2 (9) got file1 (5) fstest.go:203: Not found "sub/file2" fstest.go:206: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:206 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:310 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/lsjson_test.go:263 /usr/local/go/src/runtime/asm_amd64.s:1700 Error: Not equal: expected: 0 actual : 1 Test: TestStatJSON Messages: 1 objects not found === RUN TestStatJSON/Root === RUN TestStatJSON/RootFilesOnly === RUN TestStatJSON/RootDirsOnly === RUN TestStatJSON/Dir === RUN TestStatJSON/DirWithTrailingSlash === RUN TestStatJSON/File === RUN TestStatJSON/NotFound === RUN TestStatJSON/DirFilesOnly === RUN TestStatJSON/FileFilesOnly === RUN TestStatJSON/NotFoundFilesOnly === RUN TestStatJSON/DirDirsOnly === RUN TestStatJSON/FileDirsOnly === RUN TestStatJSON/NotFoundDirsOnly === RUN TestStatJSON/RootNotFound 2026/02/02 05:21:02 DEBUG : Creating backend with remote "TestDrime:rclone-test-mopoweh7toso/notfound" === NAME TestStatJSON run.go:130: removing dir "sub" failed - try 1/3: directory not empty run.go:130: removing dir "sub" failed - try 2/3: directory not empty run.go:130: removing dir "sub" failed - try 3/3: directory not empty run.go:133: removing dir "sub" failed: directory not empty fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go: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: TestStatJSON Messages: listing wrong, want got sub/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: TestStatJSON Messages: Unexpected file "sub/file2" fstest.go:327: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:327 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393 /usr/local/go/src/testing/testing.go:1211 /usr/local/go/src/testing/testing.go:1445 /usr/local/go/src/testing/testing.go:1786 Error: Not equal: expected: []string{} actual : []string{"sub"} Diff: --- Expected +++ Actual @@ -1,2 +1,3 @@ -([]string) { +([]string) (len=1) { + (string) (len=3) "sub" } Test: TestStatJSON Messages: directories --- FAIL: TestStatJSON (30.45s) --- PASS: TestStatJSON/Root (0.36s) --- PASS: TestStatJSON/RootFilesOnly (0.00s) --- PASS: TestStatJSON/RootDirsOnly (0.27s) --- PASS: TestStatJSON/Dir (0.50s) --- PASS: TestStatJSON/DirWithTrailingSlash (0.16s) --- PASS: TestStatJSON/File (0.17s) --- PASS: TestStatJSON/NotFound (0.42s) --- PASS: TestStatJSON/DirFilesOnly (0.16s) --- PASS: TestStatJSON/FileFilesOnly (0.22s) --- PASS: TestStatJSON/NotFoundFilesOnly (0.16s) --- PASS: TestStatJSON/DirDirsOnly (0.15s) --- PASS: TestStatJSON/FileDirsOnly (0.18s) --- PASS: TestStatJSON/NotFoundDirsOnly (0.20s) --- PASS: TestStatJSON/RootNotFound (1.30s) === RUN TestMkdir run.go:185: Remote "drime root 'rclone-test-mopoweh7toso'", Local "Local file system at /tmp/rclone477205313", Modify Window "876000h0m0s" 2026/02/02 05:21:18 INFO : drime root 'rclone-test-mopoweh7toso': Making directory 2026/02/02 05:21:18 INFO : drime root 'rclone-test-mopoweh7toso': Making directory 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: TestMkdir (10.20s) === RUN TestLsd run.go:185: Remote "drime root 'rclone-test-mopoweh7toso'", Local "Local file system at /tmp/rclone477205313", 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.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 --- PASS: TestLsd (17.61s) === RUN TestLsLong run.go:185: Remote "drime root 'rclone-test-mopoweh7toso'", Local "Local file system at /tmp/rclone477205313", Modify Window "876000h0m0s" fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:144 Error: Should be true Test: TestLsLong Messages: listing wrong, want empty space (1), potato2 (60) got fstest.go:203: Not found "potato2" fstest.go:203: Not found "empty space" fstest.go:206: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:206 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:310 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:144 /usr/local/go/src/runtime/asm_amd64.s:1700 Error: Not equal: expected: 0 actual : 2 Test: TestLsLong Messages: 2 objects not found fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393 /usr/local/go/src/testing/testing.go:1211 /usr/local/go/src/testing/testing.go:1445 /usr/local/go/src/testing/testing.go:1786 Error: Should be true Test: TestLsLong Messages: listing wrong, want got empty space (1), potato2 (60) fstest.go:191: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393 /usr/local/go/src/testing/testing.go:1211 /usr/local/go/src/testing/testing.go:1445 /usr/local/go/src/testing/testing.go:1786 Error: Should be true Test: TestLsLong Messages: Unexpected file "empty space" fstest.go:191: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393 /usr/local/go/src/testing/testing.go:1211 /usr/local/go/src/testing/testing.go:1445 /usr/local/go/src/testing/testing.go:1786 Error: Should be true Test: TestLsLong Messages: Unexpected file "potato2" --- FAIL: TestLsLong (19.51s) === RUN TestHashSums run.go:185: Remote "drime root 'rclone-test-mopoweh7toso'", Local "Local file system at /tmp/rclone477205313", Modify Window "876000h0m0s" 2026/02/02 05:22:07 DEBUG : potato2: Removing old object on successful upload 2026/02/02 05:22:07 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/02 05:22:07 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2026/02/02 05:22:07 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/02 05:22:07 DEBUG : pacer: Rate limited, increasing sleep to 40ms 2026/02/02 05:22:08 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/02 05:22:08 DEBUG : pacer: Rate limited, increasing sleep to 80ms 2026/02/02 05:22:08 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/02 05:22:08 DEBUG : pacer: Rate limited, increasing sleep to 160ms 2026/02/02 05:22:08 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/02 05:22:08 DEBUG : pacer: Rate limited, increasing sleep to 320ms 2026/02/02 05:22:08 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/02 05:22:08 DEBUG : pacer: Rate limited, increasing sleep to 640ms 2026/02/02 05:22:09 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/02 05:22:09 DEBUG : pacer: Rate limited, increasing sleep to 1.28s 2026/02/02 05:22:09 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/02 05:22:09 DEBUG : pacer: Rate limited, increasing sleep to 2.56s 2026/02/02 05:22:11 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/02 05:22:11 DEBUG : pacer: Rate limited, increasing sleep to 5.12s 2026/02/02 05:22:13 DEBUG : pacer: low level retry 10/10 (error Error "Server Error") 2026/02/02 05:22:13 DEBUG : pacer: Rate limited, increasing sleep to 10.24s run.go:286: Retry Put of "potato2" to drime root 'rclone-test-mopoweh7toso': 1/10 (failed to delete existing object: failed to delete item: Error "Server Error") 2026/02/02 05:22:18 DEBUG : pacer: Reducing sleep to 5.12s 2026/02/02 05:22:29 DEBUG : pacer: Reducing sleep to 2.56s 2026/02/02 05:22:29 DEBUG : potato2: Removing old object on successful upload 2026/02/02 05:22:34 DEBUG : pacer: Reducing sleep to 1.28s 2026/02/02 05:22:36 DEBUG : pacer: Reducing sleep to 640ms 2026/02/02 05:22:38 DEBUG : pacer: Reducing sleep to 320ms 2026/02/02 05:22:38 DEBUG : pacer: Reducing sleep to 160ms fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache 2026/02/02 05:22:40 DEBUG : pacer: Reducing sleep to 80ms 2026/02/02 05:22:40 DEBUG : pacer: Reducing sleep to 40ms fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache 2026/02/02 05:22:43 DEBUG : pacer: Reducing sleep to 20ms 2026/02/02 05:22:43 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/operations_test.go:186 Error: Should be true Test: TestHashSums 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:186 /usr/local/go/src/runtime/asm_amd64.s:1700 Error: Not equal: expected: 0 actual : 1 Test: TestHashSums 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: TestHashSums Messages: listing wrong, want got empty space (1), potato2 (60) fstest.go:191: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393 /usr/local/go/src/testing/testing.go:1211 /usr/local/go/src/testing/testing.go:1445 /usr/local/go/src/testing/testing.go:1786 Error: Should be true Test: TestHashSums Messages: Unexpected file "empty space" fstest.go:191: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393 /usr/local/go/src/testing/testing.go:1211 /usr/local/go/src/testing/testing.go:1445 /usr/local/go/src/testing/testing.go:1786 Error: Should be true Test: TestHashSums Messages: Unexpected file "potato2" --- FAIL: TestHashSums (51.71s) === RUN TestMaxDelete run.go:185: Remote "drime root 'rclone-test-mopoweh7toso'", Local "Local file system at /tmp/rclone477205313", Modify Window "876000h0m0s" fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:447 Error: Should be true Test: TestMaxDelete Messages: listing wrong, want large (100), medium (60), small (10) got small (10) fstest.go:203: Not found "medium" fstest.go:203: Not found "large" fstest.go:206: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:206 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:310 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:447 /usr/local/go/src/runtime/asm_amd64.s:1700 Error: Not equal: expected: 0 actual : 2 Test: TestMaxDelete Messages: 2 objects not found 2026/02/02 05:23:08 DEBUG : Waiting for deletions to finish 2026/02/02 05:23:09 ERROR : small: Got fatal error on delete: --max-delete threshold reached 2026/02/02 05:23:09 INFO : large: Deleted 2026/02/02 05:23:09 INFO : medium: Deleted operations_test.go:453: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:453 Error: Not equal: expected: 1 actual : 3 Test: TestMaxDelete 2026/02/02 05:23:10 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/02 05:23:10 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2026/02/02 05:23:10 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/02 05:23:10 DEBUG : pacer: Rate limited, increasing sleep to 40ms 2026/02/02 05:23:10 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/02 05:23:10 DEBUG : pacer: Rate limited, increasing sleep to 80ms 2026/02/02 05:23:10 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/02 05:23:10 DEBUG : pacer: Rate limited, increasing sleep to 160ms 2026/02/02 05:23:10 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/02 05:23:10 DEBUG : pacer: Rate limited, increasing sleep to 320ms 2026/02/02 05:23:11 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/02 05:23:11 DEBUG : pacer: Rate limited, increasing sleep to 640ms 2026/02/02 05:23:11 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/02 05:23:11 DEBUG : pacer: Rate limited, increasing sleep to 1.28s 2026/02/02 05:23:12 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/02 05:23:12 DEBUG : pacer: Rate limited, increasing sleep to 2.56s 2026/02/02 05:23:13 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/02 05:23:13 DEBUG : pacer: Rate limited, increasing sleep to 5.12s 2026/02/02 05:23:15 DEBUG : pacer: low level retry 10/10 (error Error "Server Error") 2026/02/02 05:23:15 DEBUG : pacer: Rate limited, increasing sleep to 10.24s run.go:130: removing file "large" failed - try 1/3: failed to delete item: Error "Server Error" 2026/02/02 05:23:21 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/02 05:23:21 DEBUG : pacer: Rate limited, increasing sleep to 20s 2026/02/02 05:23:31 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/02 05:23:51 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/02 05:24:11 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/02 05:24:31 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/02 05:24:51 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/02 05:25:11 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/02 05:25:31 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/02 05:25:51 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/02 05:26:11 DEBUG : pacer: low level retry 10/10 (error Error "Server Error") run.go:130: removing file "large" failed - try 2/3: failed to delete item: Error "Server Error" 2026/02/02 05:26:31 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/02 05:26:51 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/02 05:27:11 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/02 05:27:31 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/02 05:27:51 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/02 05:28:11 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/02 05:28:31 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/02 05:28:51 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/02 05:29:11 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/02 05:29:31 DEBUG : pacer: low level retry 10/10 (error Error "Server Error") run.go:130: removing file "large" failed - try 3/3: failed to delete item: Error "Server Error" run.go:133: removing file "large" failed: failed to delete item: Error "Server Error" 2026/02/02 05:29:51 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/02 05:30:11 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/02 05:30:31 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/02 05:30:51 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/02 05:31:11 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/02 05:31:31 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/02 05:31:51 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/02 05:32:11 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/02 05:32:31 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/02 05:32:51 DEBUG : pacer: low level retry 10/10 (error Error "Server Error") run.go:130: removing file "medium" failed - try 1/3: failed to delete item: Error "Server Error" 2026/02/02 05:33:11 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/02 05:33:31 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/02 05:33:51 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/02 05:34:11 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/02 05:34:31 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/02 05:34:51 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/02 05:35:11 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/02 05:35:31 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/02 05:35:51 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/02 05:36:11 DEBUG : pacer: low level retry 10/10 (error Error "Server Error") run.go:130: removing file "medium" failed - try 2/3: failed to delete item: Error "Server Error" 2026/02/02 05:36:31 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/02 05:36:51 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/02 05:37:11 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/02 05:37:31 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/02 05:37:51 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/02 05:38:11 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/02 05:38:31 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/02 05:38:51 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/02 05:39:11 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/02 05:39:31 DEBUG : pacer: low level retry 10/10 (error Error "Server Error") run.go:130: removing file "medium" failed - try 3/3: failed to delete item: Error "Server Error" run.go:133: removing file "medium" failed: failed to delete item: Error "Server Error" 2026/02/02 05:39:51 DEBUG : pacer: Reducing sleep to 10s 2026/02/02 05:40:11 DEBUG : pacer: Reducing sleep to 5s 2026/02/02 05:40:21 DEBUG : pacer: Reducing sleep to 2.5s 2026/02/02 05:40:26 DEBUG : pacer: Reducing sleep to 1.25s --- FAIL: TestMaxDelete (1049.19s) === RUN TestMaxDeleteSize run.go:185: Remote "drime root 'rclone-test-mopoweh7toso'", Local "Local file system at /tmp/rclone477205313", Modify Window "876000h0m0s" 2026/02/02 05:40:28 DEBUG : pacer: Reducing sleep to 625ms 2026/02/02 05:40:30 DEBUG : pacer: Reducing sleep to 312.5ms 2026/02/02 05:40:31 DEBUG : pacer: Reducing sleep to 156.25ms 2026/02/02 05:40:32 DEBUG : pacer: Reducing sleep to 78.125ms 2026/02/02 05:40:32 DEBUG : pacer: Reducing sleep to 39.0625ms 2026/02/02 05:40:33 DEBUG : pacer: Reducing sleep to 19.53125ms 2026/02/02 05:40:34 DEBUG : pacer: Reducing sleep to 10ms fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:292: Sleeping for 7s just to make sure 2026/02/02 05:40:46 DEBUG : Waiting for deletions to finish 2026/02/02 05:40:46 ERROR : small: Got fatal error on delete: --max-delete-size threshold reached 2026/02/02 05:40:46 INFO : medium: Deleted 2026/02/02 05:40:46 INFO : large: Deleted operations_test.go:492: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:492 Error: Not equal: expected: 1 actual : 3 Test: TestMaxDeleteSize 2026/02/02 05:40:47 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/02 05:40:47 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2026/02/02 05:40:48 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/02 05:40:48 DEBUG : pacer: Rate limited, increasing sleep to 40ms 2026/02/02 05:40:48 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/02 05:40:48 DEBUG : pacer: Rate limited, increasing sleep to 80ms 2026/02/02 05:40:48 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/02 05:40:48 DEBUG : pacer: Rate limited, increasing sleep to 160ms 2026/02/02 05:40:48 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/02 05:40:48 DEBUG : pacer: Rate limited, increasing sleep to 320ms 2026/02/02 05:40:49 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/02 05:40:49 DEBUG : pacer: Rate limited, increasing sleep to 640ms 2026/02/02 05:40:49 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/02 05:40:49 DEBUG : pacer: Rate limited, increasing sleep to 1.28s 2026/02/02 05:40:50 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/02 05:40:50 DEBUG : pacer: Rate limited, increasing sleep to 2.56s 2026/02/02 05:40:51 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/02 05:40:51 DEBUG : pacer: Rate limited, increasing sleep to 5.12s 2026/02/02 05:40:54 DEBUG : pacer: low level retry 10/10 (error Error "Server Error") 2026/02/02 05:40:54 DEBUG : pacer: Rate limited, increasing sleep to 10.24s run.go:130: removing file "large" failed - try 1/3: failed to delete item: Error "Server Error" 2026/02/02 05:40:59 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/02 05:40:59 DEBUG : pacer: Rate limited, increasing sleep to 20s 2026/02/02 05:41:09 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/02 05:41:29 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/02 05:41:49 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/02 05:42:09 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/02 05:42:29 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/02 05:42:49 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/02 05:43:09 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/02 05:43:29 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/02 05:43:49 DEBUG : pacer: low level retry 10/10 (error Error "Server Error") run.go:130: removing file "large" failed - try 2/3: failed to delete item: Error "Server Error" 2026/02/02 05:44:09 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/02 05:44:29 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/02 05:44:49 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/02 05:45:09 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/02 05:45:29 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/02 05:45:49 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/02 05:46:09 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/02 05:46:29 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/02 05:46:49 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/02 05:47:09 DEBUG : pacer: low level retry 10/10 (error Error "Server Error") run.go:130: removing file "large" failed - try 3/3: failed to delete item: Error "Server Error" run.go:133: removing file "large" failed: failed to delete item: Error "Server Error" 2026/02/02 05:47:29 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/02 05:47:49 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/02 05:48:09 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/02 05:48:29 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/02 05:48:49 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/02 05:49:09 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/02 05:49:29 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/02 05:49:49 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/02 05:50:09 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/02 05:50:29 DEBUG : pacer: low level retry 10/10 (error Error "Server Error") run.go:130: removing file "medium" failed - try 1/3: failed to delete item: Error "Server Error" 2026/02/02 05:50:49 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/02 05:51:09 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/02 05:51:29 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/02 05:51:49 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/02 05:52:09 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/02 05:52:29 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/02 05:52:49 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/02 05:53:09 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/02 05:53:29 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/02 05:53:49 DEBUG : pacer: low level retry 10/10 (error Error "Server Error") run.go:130: removing file "medium" failed - try 2/3: failed to delete item: Error "Server Error" 2026/02/02 05:54:09 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/02 05:54:29 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/02 05:54:49 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/02 05:55:09 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/02 05:55:29 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/02 05:55:49 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/02 05:56:09 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/02 05:56:29 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/02 05:56:49 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/02 05:57:09 DEBUG : pacer: low level retry 10/10 (error Error "Server Error") run.go:130: removing file "medium" failed - try 3/3: failed to delete item: Error "Server Error" run.go:133: removing file "medium" failed: failed to delete item: Error "Server Error" 2026/02/02 05:57:29 DEBUG : pacer: Reducing sleep to 10s 2026/02/02 05:57:49 DEBUG : pacer: Reducing sleep to 5s 2026/02/02 05:57:59 DEBUG : pacer: Reducing sleep to 2.5s 2026/02/02 05:58:04 DEBUG : pacer: Reducing sleep to 1.25s --- FAIL: TestMaxDeleteSize (1057.88s) === RUN TestCat run.go:185: Remote "drime root 'rclone-test-mopoweh7toso'", Local "Local file system at /tmp/rclone477205313", Modify Window "876000h0m0s" 2026/02/02 05:58:06 DEBUG : pacer: Reducing sleep to 625ms 2026/02/02 05:58:08 DEBUG : pacer: Reducing sleep to 312.5ms 2026/02/02 05:58:08 DEBUG : pacer: Reducing sleep to 156.25ms 2026/02/02 05:58:10 DEBUG : pacer: Reducing sleep to 78.125ms 2026/02/02 05:58:10 DEBUG : pacer: Reducing sleep to 39.0625ms 2026/02/02 05:58:10 DEBUG : pacer: Reducing sleep to 19.53125ms 2026/02/02 05:58:11 DEBUG : pacer: Reducing sleep to 10ms fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:292: Sleeping for 7s just to make sure --- PASS: TestCat (23.53s) === RUN TestPurge 2026/02/02 05:58:27 DEBUG : Creating backend with remote "TestDrime:rclone-test-tuqucod7fimo" 2026/02/02 05:58:27 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/02/02 05:58:28 DEBUG : Creating backend with remote "/tmp/rclone201677219" run.go:185: Remote "drime root 'rclone-test-tuqucod7fimo'", Local "Local file system at /tmp/rclone201677219", Modify Window "876000h0m0s" 2026/02/02 05:58:31 INFO : A2: Making directory 2026/02/02 05:58:32 INFO : A1/B2: Making directory 2026/02/02 05:58:32 INFO : A1/B2/C2: Making directory 2026/02/02 05:58:33 INFO : A1/B1/C3: Making directory 2026/02/02 05:58:33 INFO : A3: Making directory 2026/02/02 05:58:33 INFO : A3/B3: Making directory 2026/02/02 05:58:34 INFO : A3/B3/C4: Making directory fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache 2026/02/02 05:58:38 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/fs/operations/operations_test.go:590 Error: Should be true Test: TestPurge Messages: listing wrong, want A1/B1/C1/one (3), A1/two (3) got A1/B1/C1/one (3) fstest.go:203: Not found "A1/two" fstest.go:206: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:206 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:310 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:590 Error: Not equal: expected: 0 actual : 1 Test: TestPurge Messages: 1 objects not found fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache 2026/02/02 05:58:51 ERROR : error listing: directory not found fstest.go:292: Sleeping for 6s just to make sure 2026/02/02 05:58:57 DEBUG : drime root 'rclone-test-tuqucod7fimo': Purge remote 2026/02/02 05:58:57 NOTICE: purge failed: directory not found --- FAIL: TestPurge (30.01s) === RUN TestRmdirsNoLeaveRoot run.go:185: Remote "drime root 'rclone-test-mopoweh7toso'", Local "Local file system at /tmp/rclone477205313", Modify Window "876000h0m0s" 2026/02/02 05:59:00 INFO : A2: Making directory 2026/02/02 05:59:00 INFO : A1/B2: Making directory 2026/02/02 05:59:01 INFO : A1/B2/C2: Making directory 2026/02/02 05:59:01 INFO : A1/B1/C3: Making directory 2026/02/02 05:59:01 INFO : A3: Making directory 2026/02/02 05:59:02 INFO : A3/B3: Making directory 2026/02/02 05:59:03 INFO : A3/B3/C4: Making directory fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:662 Error: Should be true Test: TestRmdirsNoLeaveRoot Messages: listing wrong, want A1/B1/C1/one (3), A1/two (3) got A1/B1/C1/one (3) fstest.go:203: Not found "A1/two" fstest.go:206: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:206 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:310 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:662 Error: Not equal: expected: 0 actual : 1 Test: TestRmdirsNoLeaveRoot Messages: 1 objects not found 2026/02/02 05:59:17 DEBUG : removing 1 level 3 directories 2026/02/02 05:59:17 INFO : A3/B3/C4: Removing directory 2026/02/02 05:59:19 DEBUG : removing 2 level 3 directories 2026/02/02 05:59:19 INFO : A1/B1/C3: Removing directory 2026/02/02 05:59:19 INFO : A1/B2/C2: Removing directory 2026/02/02 05:59:20 DEBUG : removing 2 level 2 directories 2026/02/02 05:59:20 INFO : A3/B3: Removing directory 2026/02/02 05:59:20 INFO : A1/B2: Removing directory 2026/02/02 05:59:20 ERROR : A1/B2: Failed to rmdir: directory not empty 2026/02/02 05:59:20 DEBUG : removing 2 level 1 directories 2026/02/02 05:59:20 INFO : A3: Removing directory 2026/02/02 05:59:20 INFO : A2: Removing directory 2026/02/02 05:59:21 ERROR : A3: Failed to rmdir: directory not empty operations_test.go:705: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:705 Error: Received unexpected error: failed to remove directories: 2 errors: last error: directory not empty Test: TestRmdirsNoLeaveRoot 2026/02/02 05:59:24 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/02 05:59:24 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2026/02/02 05:59:24 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/02 05:59:24 DEBUG : pacer: Rate limited, increasing sleep to 40ms 2026/02/02 05:59:24 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/02 05:59:24 DEBUG : pacer: Rate limited, increasing sleep to 80ms 2026/02/02 05:59:25 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/02 05:59:25 DEBUG : pacer: Rate limited, increasing sleep to 160ms 2026/02/02 05:59:25 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/02 05:59:25 DEBUG : pacer: Rate limited, increasing sleep to 320ms 2026/02/02 05:59:25 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/02 05:59:25 DEBUG : pacer: Rate limited, increasing sleep to 640ms 2026/02/02 05:59:25 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/02 05:59:25 DEBUG : pacer: Rate limited, increasing sleep to 1.28s 2026/02/02 05:59:26 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/02 05:59:26 DEBUG : pacer: Rate limited, increasing sleep to 2.56s 2026/02/02 05:59:27 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/02 05:59:27 DEBUG : pacer: Rate limited, increasing sleep to 5.12s 2026/02/02 05:59:30 DEBUG : pacer: low level retry 10/10 (error Error "Server Error") 2026/02/02 05:59:30 DEBUG : pacer: Rate limited, increasing sleep to 10.24s run.go:130: removing dir "A3/B3" failed - try 1/3: failed to delete item: Error "Server Error" 2026/02/02 05:59:35 DEBUG : pacer: Reducing sleep to 5.12s 2026/02/02 05:59:46 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/02 05:59:46 DEBUG : pacer: Rate limited, increasing sleep to 10.24s 2026/02/02 05:59:50 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/02 05:59:50 DEBUG : pacer: Rate limited, increasing sleep to 20s 2026/02/02 06:00:01 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/02 06:00:21 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/02 06:00:41 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/02 06:01:01 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/02 06:01:21 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/02 06:01:41 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/02 06:02:01 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/02 06:02:21 DEBUG : pacer: low level retry 10/10 (error Error "Server Error") run.go:130: removing dir "A3/B3" failed - try 2/3: failed to delete item: Error "Server Error" 2026/02/02 06:02:41 DEBUG : pacer: Reducing sleep to 10s 2026/02/02 06:03:01 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/02 06:03:01 DEBUG : pacer: Rate limited, increasing sleep to 20s 2026/02/02 06:03:11 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/02 06:03:31 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/02 06:03:51 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/02 06:04:11 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/02 06:04:31 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/02 06:04:51 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/02 06:05:11 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/02 06:05:31 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/02 06:05:51 DEBUG : pacer: low level retry 10/10 (error Error "Server Error") run.go:130: removing dir "A3/B3" failed - try 3/3: failed to delete item: Error "Server Error" run.go:133: removing dir "A3/B3" failed: failed to delete item: Error "Server Error" 2026/02/02 06:06:11 DEBUG : pacer: Reducing sleep to 10s 2026/02/02 06:06:31 DEBUG : pacer: Reducing sleep to 5s 2026/02/02 06:06:41 DEBUG : pacer: Reducing sleep to 2.5s 2026/02/02 06:06:46 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/02 06:06:46 DEBUG : pacer: Rate limited, increasing sleep to 5s 2026/02/02 06:06:49 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/02 06:06:49 DEBUG : pacer: Rate limited, increasing sleep to 10s 2026/02/02 06:06:54 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/02 06:06:54 DEBUG : pacer: Rate limited, increasing sleep to 20s 2026/02/02 06:07:04 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/02 06:07:23 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/02 06:07:44 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/02 06:08:03 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/02 06:08:24 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/02 06:08:44 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/02 06:09:04 DEBUG : pacer: low level retry 10/10 (error Error "Server Error") run.go:130: removing dir "A2" failed - try 1/3: failed to delete item: Error "Server Error" 2026/02/02 06:09:24 DEBUG : pacer: Reducing sleep to 10s 2026/02/02 06:09:44 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/02 06:09:44 DEBUG : pacer: Rate limited, increasing sleep to 20s 2026/02/02 06:09:54 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/02 06:10:14 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/02 06:10:34 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/02 06:10:54 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/02 06:11:14 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/02 06:11:34 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/02 06:11:55 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/02 06:12:14 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/02 06:12:34 DEBUG : pacer: low level retry 10/10 (error Error "Server Error") run.go:130: removing dir "A2" failed - try 2/3: failed to delete item: Error "Server Error" 2026/02/02 06:12:54 DEBUG : pacer: Reducing sleep to 10s 2026/02/02 06:13:14 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/02 06:13:14 DEBUG : pacer: Rate limited, increasing sleep to 20s 2026/02/02 06:13:24 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/02 06:13:44 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/02 06:14:04 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/02 06:14:24 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/02 06:14:44 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/02 06:15:04 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/02 06:15:24 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/02 06:15:44 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/02 06:16:04 DEBUG : pacer: low level retry 10/10 (error Error "Server Error") run.go:130: removing dir "A2" failed - try 3/3: failed to delete item: Error "Server Error" run.go:133: removing dir "A2" failed: failed to delete item: Error "Server Error" 2026/02/02 06:16:24 DEBUG : pacer: Reducing sleep to 10s 2026/02/02 06:16:44 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/02 06:16:44 DEBUG : pacer: Rate limited, increasing sleep to 20s 2026/02/02 06:16:54 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/02 06:17:14 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/02 06:17:34 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/02 06:17:54 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/02 06:18:14 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/02 06:18:34 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/02 06:18:54 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/02 06:19:14 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/02 06:19:34 DEBUG : pacer: low level retry 10/10 (error Error "Server Error") run.go:130: removing dir "A1/B2/C2" failed - try 1/3: failed to delete item: Error "Server Error" 2026/02/02 06:19:54 DEBUG : pacer: Reducing sleep to 10s 2026/02/02 06:20:14 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/02 06:20:14 DEBUG : pacer: Rate limited, increasing sleep to 20s 2026/02/02 06:20:24 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/02 06:20:44 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/02 06:21:04 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/02 06:21:24 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/02 06:21:44 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/02 06:22:04 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/02 06:22:24 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/02 06:22:44 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/02 06:23:04 DEBUG : pacer: low level retry 10/10 (error Error "Server Error") run.go:130: removing dir "A1/B2/C2" failed - try 2/3: failed to delete item: Error "Server Error" 2026/02/02 06:23:24 DEBUG : pacer: Reducing sleep to 10s 2026/02/02 06:23:45 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/02 06:23:45 DEBUG : pacer: Rate limited, increasing sleep to 20s 2026/02/02 06:23:55 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/02 06:24:14 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/02 06:24:35 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/02 06:24:55 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/02 06:25:15 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/02 06:25:35 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/02 06:25:54 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/02 06:26:14 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/02 06:26:35 DEBUG : pacer: low level retry 10/10 (error Error "Server Error") run.go:130: removing dir "A1/B2/C2" failed - try 3/3: failed to delete item: Error "Server Error" run.go:133: removing dir "A1/B2/C2" failed: failed to delete item: Error "Server Error" 2026/02/02 06:26:54 DEBUG : pacer: Reducing sleep to 10s 2026/02/02 06:27:14 DEBUG : pacer: Reducing sleep to 5s 2026/02/02 06:27:25 DEBUG : pacer: Reducing sleep to 2.5s 2026/02/02 06:27:30 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/02 06:27:30 DEBUG : pacer: Rate limited, increasing sleep to 5s 2026/02/02 06:27:32 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/02 06:27:32 DEBUG : pacer: Rate limited, increasing sleep to 10s 2026/02/02 06:27:37 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/02 06:27:37 DEBUG : pacer: Rate limited, increasing sleep to 20s 2026/02/02 06:27:47 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/02 06:28:07 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/02 06:28:27 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/02 06:28:47 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/02 06:29:07 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/02 06:29:27 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/02 06:29:47 DEBUG : pacer: low level retry 10/10 (error Error "Server Error") run.go:130: removing dir "A1/B1/C3" failed - try 1/3: failed to delete item: Error "Server Error" 2026/02/02 06:30:07 DEBUG : pacer: Reducing sleep to 10s 2026/02/02 06:30:27 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/02 06:30:27 DEBUG : pacer: Rate limited, increasing sleep to 20s 2026/02/02 06:30:37 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/02 06:30:57 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/02 06:31:17 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/02 06:31:37 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/02 06:31:57 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/02 06:32:17 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/02 06:32:37 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/02 06:32:57 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/02 06:33:17 DEBUG : pacer: low level retry 10/10 (error Error "Server Error") run.go:130: removing dir "A1/B1/C3" failed - try 2/3: failed to delete item: Error "Server Error" 2026/02/02 06:33:37 DEBUG : pacer: Reducing sleep to 10s 2026/02/02 06:33:58 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/02 06:33:58 DEBUG : pacer: Rate limited, increasing sleep to 20s 2026/02/02 06:34:08 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/02 06:34:28 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/02 06:34:48 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/02 06:35:07 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/02 06:35:27 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/02 06:35:47 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/02 06:36:08 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/02 06:36:28 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/02 06:36:48 DEBUG : pacer: low level retry 10/10 (error Error "Server Error") run.go:130: removing dir "A1/B1/C3" failed - try 3/3: failed to delete item: Error "Server Error" run.go:133: removing dir "A1/B1/C3" failed: failed to delete item: Error "Server Error" 2026/02/02 06:37:07 DEBUG : pacer: Reducing sleep to 10s 2026/02/02 06:37:27 DEBUG : pacer: Reducing sleep to 5s 2026/02/02 06:37:37 DEBUG : pacer: Reducing sleep to 2.5s 2026/02/02 06:37:43 DEBUG : pacer: Reducing sleep to 1.25s 2026/02/02 06:37:45 DEBUG : pacer: Reducing sleep to 625ms 2026/02/02 06:37:46 DEBUG : pacer: Reducing sleep to 312.5ms 2026/02/02 06:37:47 DEBUG : pacer: Reducing sleep to 156.25ms --- FAIL: TestRmdirsNoLeaveRoot (2329.42s) FAIL 2026/02/02 06:37:47 DEBUG : drime root 'rclone-test-mopoweh7toso': Purge remote 2026/02/02 06:37:47 DEBUG : pacer: Reducing sleep to 78.125ms "./operations.test -test.v -test.timeout 2h0m0s -remote TestDrime: -verbose -test.run '^(TestApplyTransforms|TestCat|TestCheckSumDownload|TestCopyFile|TestCopyFileCompareDest|TestCopyFileCopyDest|TestCopyInplace|TestHashSums|TestListDirSortedFn|TestLsLong|TestLsd|TestMaxDelete|TestMaxDeleteSize|TestMkdir|TestParseSumFile|TestPurge|TestRmdirsNoLeaveRoot|TestStatJSON)$|^TestListJSON$/^(Default|DirsOnly|Metadata|Recurse)$|^TestMultithreadCopy$/^(upload=false,size=10485759,streams=2|upload=false,size=10485760,streams=2|upload=false,size=10485761,streams=2|upload=true,size=10485759,streams=2|upload=true,size=10485760,streams=2|upload=true,size=10485761,streams=2)$'" - Finished ERROR in 1h40m13.337975022s (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 TestParseSumFile TestCheckSumDownload TestApplyTransforms TestCopyFile TestCopyFileCompareDest TestCopyFileCopyDest TestListDirSortedFn TestListJSON/Default TestListJSON/DirsOnly TestListJSON/Recurse TestListJSON/Metadata TestStatJSON TestLsLong TestHashSums TestMaxDelete TestMaxDeleteSize TestPurge TestRmdirsNoLeaveRoot]