"./operations.test -test.v -test.timeout 2h0m0s -remote TestDrime: -verbose -test.run '^(TestApplyTransforms|TestCopyFile|TestCopyFileCompareDest|TestCopyFileCopyDest|TestListDirSorted|TestListDirSortedFn|TestMaxDelete|TestMaxDeleteSize|TestRmdirsNoLeaveRoot|TestStatJSON)$|^TestListJSON$/^(Default|DirsOnly|Metadata|Recurse)$|^TestMultithreadCopy$/^(upload=true,size=10485760,streams=2|upload=true,size=10485761,streams=2)$'" - Starting (try 4/5) 2026/02/06 08:28:37 DEBUG : Creating backend with remote "TestDrime:rclone-test-hifalix2cuyu" 2026/02/06 08:28:37 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/02/06 08:28:41 DEBUG : Creating backend with remote "/tmp/rclone1953119286" === RUN TestMultithreadCopy run.go:185: Remote "drime root 'rclone-test-hifalix2cuyu'", Local "Local file system at /tmp/rclone1953119286", Modify Window "876000h0m0s" === RUN TestMultithreadCopy/upload=true,size=10485760,streams=2 2026/02/06 08:28:50 DEBUG : test-multithread-copy-true-10485760-2: multi-thread copy: disabling buffering because source is local disk 2026/02/06 08:28:52 DEBUG : test-multithread-copy-true-10485760-2: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 2 2026/02/06 08:28:52 DEBUG : test-multithread-copy-true-10485760-2: multi-thread copy: number of streams 4 was bigger than number of chunks 2 2026/02/06 08:28:52 DEBUG : test-multithread-copy-true-10485760-2: Starting multi-thread copy with 2 chunks of size 5Mi with 2 parallel streams 2026/02/06 08:28:52 DEBUG : test-multithread-copy-true-10485760-2: multi-thread copy: chunk 2/2 (5242880-10485760) size 5Mi starting 2026/02/06 08:28:52 DEBUG : test-multithread-copy-true-10485760-2: multi-thread copy: chunk 1/2 (0-5242880) size 5Mi starting 2026/02/06 08:28:54 DEBUG : test-multithread-copy-true-10485760-2: multi-thread copy: chunk 1/2 (0-5242880) size 5Mi finished 2026/02/06 08:28:54 DEBUG : test-multithread-copy-true-10485760-2: multi-thread copy: chunk 2/2 (5242880-10485760) size 5Mi finished 2026/02/06 08:29:02 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/06 08:29:16 DEBUG : test-multithread-copy-true-10485761-2: multi-thread copy: disabling buffering because source is local disk 2026/02/06 08:29:17 DEBUG : test-multithread-copy-true-10485761-2: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 2 2026/02/06 08:29:17 DEBUG : test-multithread-copy-true-10485761-2: multi-thread copy: number of streams 4 was bigger than number of chunks 3 2026/02/06 08:29:17 DEBUG : test-multithread-copy-true-10485761-2: Starting multi-thread copy with 3 chunks of size 5Mi with 3 parallel streams 2026/02/06 08:29:17 DEBUG : test-multithread-copy-true-10485761-2: multi-thread copy: chunk 3/3 (10485760-10485761) size 1 starting 2026/02/06 08:29:17 DEBUG : test-multithread-copy-true-10485761-2: multi-thread copy: chunk 2/3 (5242880-10485760) size 5Mi starting 2026/02/06 08:29:17 DEBUG : test-multithread-copy-true-10485761-2: multi-thread copy: chunk 1/3 (0-5242880) size 5Mi starting 2026/02/06 08:29:18 DEBUG : test-multithread-copy-true-10485761-2: multi-thread copy: chunk 3/3 (10485760-10485761) size 1 finished 2026/02/06 08:29:18 DEBUG : test-multithread-copy-true-10485761-2: multi-thread copy: chunk 1/3 (0-5242880) size 5Mi finished 2026/02/06 08:29:18 DEBUG : test-multithread-copy-true-10485761-2: multi-thread copy: chunk 2/3 (5242880-10485760) size 5Mi finished 2026/02/06 08:29:22 DEBUG : test-multithread-copy-true-10485761-2: Finished multi-thread copy with 3 parts of size 5Mi 2026/02/06 08:29:24 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/06 08:29:24 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2026/02/06 08:29:24 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/06 08:29:24 DEBUG : pacer: Rate limited, increasing sleep to 40ms 2026/02/06 08:29:25 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/06 08:29:25 DEBUG : pacer: Rate limited, increasing sleep to 80ms 2026/02/06 08:29:25 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/06 08:29:25 DEBUG : pacer: Rate limited, increasing sleep to 160ms 2026/02/06 08:29:26 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/06 08:29:26 DEBUG : pacer: Rate limited, increasing sleep to 320ms 2026/02/06 08:29:26 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/06 08:29:26 DEBUG : pacer: Rate limited, increasing sleep to 640ms 2026/02/06 08:29:26 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/06 08:29:26 DEBUG : pacer: Rate limited, increasing sleep to 1.28s 2026/02/06 08:29:27 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/06 08:29:27 DEBUG : pacer: Rate limited, increasing sleep to 2.56s 2026/02/06 08:29:28 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/06 08:29:28 DEBUG : pacer: Rate limited, increasing sleep to 5.12s 2026/02/06 08:29:31 DEBUG : pacer: low level retry 10/10 (error Error "Server Error") 2026/02/06 08:29:31 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/06 08:29:36 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/06 08:29:36 DEBUG : pacer: Rate limited, increasing sleep to 20s 2026/02/06 08:29:46 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/06 08:30:06 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/06 08:30:26 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/06 08:30:46 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/06 08:31:06 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/06 08:31:27 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/06 08:31:47 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/06 08:32:06 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/06 08:32:26 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/06 08:32:46 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/06 08:33:06 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/06 08:33:26 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/06 08:33:46 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/06 08:34:06 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/06 08:34:27 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/06 08:34:46 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/06 08:35:06 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/06 08:35:26 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/06 08:35:46 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/06 08:36:07 DEBUG : pacer: Reducing sleep to 10s --- FAIL: TestMultithreadCopy (446.15s) --- PASS: TestMultithreadCopy/upload=true,size=10485760,streams=2 (16.31s) --- FAIL: TestMultithreadCopy/upload=true,size=10485761,streams=2 (19.24s) === RUN TestApplyTransforms 2026/02/06 08:36:07 DEBUG : Creating backend with remote "TestDrime:rclone-test-dureyal6luwe" 2026/02/06 08:36:07 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/02/06 08:36:09 DEBUG : Creating backend with remote "/tmp/rclone388417713" run.go:185: Remote "drime root 'rclone-test-dureyal6luwe'", Local "Local file system at /tmp/rclone388417713", Modify Window "876000h0m0s" upper checkfile vs. lower remote (without normalization) 2026/02/06 08:36:15 ERROR : HELLO, WORLD!: file not in drime root 'rclone-test-dureyal6luwe' 2026/02/06 08:36:15 NOTICE: drime root 'rclone-test-dureyal6luwe': 1 files missing 2026/02/06 08:36:15 NOTICE: drime root 'rclone-test-dureyal6luwe': 0 differences found 2026/02/06 08:36:15 NOTICE: drime root 'rclone-test-dureyal6luwe': 1 errors while checking upper checkfile vs. lower remote (with normalization) 2026/02/06 08:36:15 ERROR : hello, world!: file not in drime root 'rclone-test-dureyal6luwe' 2026/02/06 08:36:15 NOTICE: drime root 'rclone-test-dureyal6luwe': 1 files missing 2026/02/06 08:36:15 NOTICE: drime root 'rclone-test-dureyal6luwe': 0 differences found 2026/02/06 08:36:15 NOTICE: drime root 'rclone-test-dureyal6luwe': 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-dureyal6luwe' Test: TestApplyTransforms Messages: unexpected error for upper checkfile vs. lower remote (with normalization) HELLO, WORLD! hello, world! 2026/02/06 08:36:15 DEBUG : Creating backend with remote "TestDrime:rclone-test-kikafaw1lude" 2026/02/06 08:36:15 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/02/06 08:36:17 DEBUG : Creating backend with remote "/tmp/rclone176847700" run.go:185: Remote "drime root 'rclone-test-kikafaw1lude'", Local "Local file system at /tmp/rclone176847700", Modify Window "876000h0m0s" lower checkfile vs. upper remote (without normalization) 2026/02/06 08:36:35 ERROR : HELLO, WORLD!: sum not found 2026/02/06 08:36:35 ERROR : hello, world!: file not in drime root 'rclone-test-kikafaw1lude' 2026/02/06 08:36:35 NOTICE: drime root 'rclone-test-kikafaw1lude': 1 files missing 2026/02/06 08:36:35 NOTICE: 1 hashes missing 2026/02/06 08:36:35 NOTICE: drime root 'rclone-test-kikafaw1lude': 1 differences found 2026/02/06 08:36:35 NOTICE: drime root 'rclone-test-kikafaw1lude': 2 errors while checking lower checkfile vs. upper remote (with normalization) 2026/02/06 08:36:35 DEBUG : HELLO, WORLD!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/02/06 08:36:35 NOTICE: drime root 'rclone-test-kikafaw1lude': 0 differences found 2026/02/06 08:36:35 NOTICE: drime root 'rclone-test-kikafaw1lude': 1 matching files 2026/02/06 08:36:35 DEBUG : Creating backend with remote "TestDrime:rclone-test-yakajic9goma" 2026/02/06 08:36:35 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/02/06 08:36:37 DEBUG : Creating backend with remote "/tmp/rclone4080244778" run.go:185: Remote "drime root 'rclone-test-yakajic9goma'", Local "Local file system at /tmp/rclone4080244778", Modify Window "876000h0m0s" lower checkfile vs. upperlowermixed remote (without normalization) 2026/02/06 08:36:41 ERROR : hello, world!: file not in drime root 'rclone-test-yakajic9goma' 2026/02/06 08:36:41 NOTICE: drime root 'rclone-test-yakajic9goma': 1 files missing 2026/02/06 08:36:41 NOTICE: drime root 'rclone-test-yakajic9goma': 0 differences found 2026/02/06 08:36:41 NOTICE: drime root 'rclone-test-yakajic9goma': 1 errors while checking lower checkfile vs. upperlowermixed remote (with normalization) 2026/02/06 08:36:41 ERROR : hello, world!: file not in drime root 'rclone-test-yakajic9goma' 2026/02/06 08:36:41 NOTICE: drime root 'rclone-test-yakajic9goma': 1 files missing 2026/02/06 08:36:41 NOTICE: drime root 'rclone-test-yakajic9goma': 0 differences found 2026/02/06 08:36:41 NOTICE: drime root 'rclone-test-yakajic9goma': 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-yakajic9goma' Test: TestApplyTransforms Messages: unexpected error for lower checkfile vs. upperlowermixed remote (with normalization) hello, world! HeLlO, wOrLd! 2026/02/06 08:36:41 DEBUG : Creating backend with remote "TestDrime:rclone-test-meloxaq5dipe" 2026/02/06 08:36:41 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/02/06 08:36:42 DEBUG : Creating backend with remote "/tmp/rclone316704971" run.go:185: Remote "drime root 'rclone-test-meloxaq5dipe'", Local "Local file system at /tmp/rclone316704971", Modify Window "876000h0m0s" upperlowermixed checkfile vs. upper remote (without normalization) 2026/02/06 08:36:50 ERROR : HeLlO, wOrLd!: file not in drime root 'rclone-test-meloxaq5dipe' 2026/02/06 08:36:50 NOTICE: drime root 'rclone-test-meloxaq5dipe': 1 files missing 2026/02/06 08:36:50 NOTICE: drime root 'rclone-test-meloxaq5dipe': 0 differences found 2026/02/06 08:36:50 NOTICE: drime root 'rclone-test-meloxaq5dipe': 1 errors while checking upperlowermixed checkfile vs. upper remote (with normalization) 2026/02/06 08:36:50 ERROR : hello, world!: file not in drime root 'rclone-test-meloxaq5dipe' 2026/02/06 08:36:50 NOTICE: drime root 'rclone-test-meloxaq5dipe': 1 files missing 2026/02/06 08:36:50 NOTICE: drime root 'rclone-test-meloxaq5dipe': 0 differences found 2026/02/06 08:36:50 NOTICE: drime root 'rclone-test-meloxaq5dipe': 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-meloxaq5dipe' Test: TestApplyTransforms Messages: unexpected error for upperlowermixed checkfile vs. upper remote (with normalization) HeLlO, wOrLd! HELLO, WORLD! 2026/02/06 08:36:50 DEBUG : Creating backend with remote "TestDrime:rclone-test-nolupuf7xima" 2026/02/06 08:36:50 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/02/06 08:36:52 DEBUG : Creating backend with remote "/tmp/rclone3336927740" run.go:185: Remote "drime root 'rclone-test-nolupuf7xima'", Local "Local file system at /tmp/rclone3336927740", Modify Window "876000h0m0s" NFD checkfile vs. NFC remote (without normalization) 2026/02/06 08:36:59 ERROR : 測試_Русский___ě_áñ: file not in drime root 'rclone-test-nolupuf7xima' 2026/02/06 08:36:59 NOTICE: drime root 'rclone-test-nolupuf7xima': 1 files missing 2026/02/06 08:36:59 NOTICE: drime root 'rclone-test-nolupuf7xima': 0 differences found 2026/02/06 08:36:59 NOTICE: drime root 'rclone-test-nolupuf7xima': 1 errors while checking NFD checkfile vs. NFC remote (with normalization) 2026/02/06 08:36:59 ERROR : 測試_русский___ě_áñ: file not in drime root 'rclone-test-nolupuf7xima' 2026/02/06 08:36:59 NOTICE: drime root 'rclone-test-nolupuf7xima': 1 files missing 2026/02/06 08:36:59 NOTICE: drime root 'rclone-test-nolupuf7xima': 0 differences found 2026/02/06 08:36:59 NOTICE: drime root 'rclone-test-nolupuf7xima': 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-nolupuf7xima' Test: TestApplyTransforms Messages: unexpected error for NFD checkfile vs. NFC remote (with normalization) 測試_Русский___ě_áñ 測試_Русский___ě_áñ 2026/02/06 08:36:59 DEBUG : Creating backend with remote "TestDrime:rclone-test-gezocuj5jene" 2026/02/06 08:36:59 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/02/06 08:37:00 DEBUG : Creating backend with remote "/tmp/rclone2042010882" run.go:185: Remote "drime root 'rclone-test-gezocuj5jene'", Local "Local file system at /tmp/rclone2042010882", Modify Window "876000h0m0s" NFC checkfile vs. NFD remote (without normalization) 2026/02/06 08:37:09 ERROR : 測試_Русский___ě_áñ: file not in drime root 'rclone-test-gezocuj5jene' 2026/02/06 08:37:09 NOTICE: drime root 'rclone-test-gezocuj5jene': 1 files missing 2026/02/06 08:37:09 NOTICE: drime root 'rclone-test-gezocuj5jene': 0 differences found 2026/02/06 08:37:09 NOTICE: drime root 'rclone-test-gezocuj5jene': 1 errors while checking NFC checkfile vs. NFD remote (with normalization) 2026/02/06 08:37:10 ERROR : 測試_русский___ě_áñ: file not in drime root 'rclone-test-gezocuj5jene' 2026/02/06 08:37:10 NOTICE: drime root 'rclone-test-gezocuj5jene': 1 files missing 2026/02/06 08:37:10 NOTICE: drime root 'rclone-test-gezocuj5jene': 0 differences found 2026/02/06 08:37:10 NOTICE: drime root 'rclone-test-gezocuj5jene': 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-gezocuj5jene' Test: TestApplyTransforms Messages: unexpected error for NFC checkfile vs. NFD remote (with normalization) 測試_Русский___ě_áñ 測試_Русский___ě_áñ 2026/02/06 08:37:10 DEBUG : Creating backend with remote "TestDrime:rclone-test-wivojot6bawa" 2026/02/06 08:37:10 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/02/06 08:37:11 DEBUG : Creating backend with remote "/tmp/rclone1606276917" run.go:185: Remote "drime root 'rclone-test-wivojot6bawa'", Local "Local file system at /tmp/rclone1606276917", Modify Window "876000h0m0s" NFDx2 checkfile vs. both remote (without normalization) 2026/02/06 08:37:29 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found 2026/02/06 08:37:29 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in drime root 'rclone-test-wivojot6bawa' 2026/02/06 08:37:29 NOTICE: drime root 'rclone-test-wivojot6bawa': 1 files missing 2026/02/06 08:37:29 NOTICE: 1 hashes missing 2026/02/06 08:37:29 NOTICE: drime root 'rclone-test-wivojot6bawa': 1 differences found 2026/02/06 08:37:29 NOTICE: drime root 'rclone-test-wivojot6bawa': 2 errors while checking NFDx2 checkfile vs. both remote (with normalization) 2026/02/06 08:37:36 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/02/06 08:37:36 NOTICE: drime root 'rclone-test-wivojot6bawa': 0 differences found 2026/02/06 08:37:36 NOTICE: drime root 'rclone-test-wivojot6bawa': 1 matching files 2026/02/06 08:37:36 DEBUG : Creating backend with remote "TestDrime:rclone-test-qanuteg6duye" 2026/02/06 08:37:36 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/02/06 08:37:43 DEBUG : Creating backend with remote "/tmp/rclone3234460269" run.go:185: Remote "drime root 'rclone-test-qanuteg6duye'", Local "Local file system at /tmp/rclone3234460269", Modify Window "876000h0m0s" NFCx2 checkfile vs. both remote (without normalization) 2026/02/06 08:38:00 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found 2026/02/06 08:38:00 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in drime root 'rclone-test-qanuteg6duye' 2026/02/06 08:38:00 NOTICE: drime root 'rclone-test-qanuteg6duye': 1 files missing 2026/02/06 08:38:00 NOTICE: 1 hashes missing 2026/02/06 08:38:00 NOTICE: drime root 'rclone-test-qanuteg6duye': 1 differences found 2026/02/06 08:38:00 NOTICE: drime root 'rclone-test-qanuteg6duye': 2 errors while checking NFCx2 checkfile vs. both remote (with normalization) 2026/02/06 08:38:04 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/02/06 08:38:04 NOTICE: drime root 'rclone-test-qanuteg6duye': 0 differences found 2026/02/06 08:38:04 NOTICE: drime root 'rclone-test-qanuteg6duye': 1 matching files 2026/02/06 08:38:04 DEBUG : Creating backend with remote "TestDrime:rclone-test-cofusav9dacu" 2026/02/06 08:38:04 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/02/06 08:38:09 DEBUG : Creating backend with remote "/tmp/rclone1798122087" run.go:185: Remote "drime root 'rclone-test-cofusav9dacu'", Local "Local file system at /tmp/rclone1798122087", Modify Window "876000h0m0s" both checkfile vs. NFDx2 remote (without normalization) 2026/02/06 08:38:34 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found 2026/02/06 08:38:34 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in drime root 'rclone-test-cofusav9dacu' 2026/02/06 08:38:34 NOTICE: drime root 'rclone-test-cofusav9dacu': 1 files missing 2026/02/06 08:38:34 NOTICE: 1 hashes missing 2026/02/06 08:38:34 NOTICE: drime root 'rclone-test-cofusav9dacu': 1 differences found 2026/02/06 08:38:34 NOTICE: drime root 'rclone-test-cofusav9dacu': 2 errors while checking both checkfile vs. NFDx2 remote (with normalization) 2026/02/06 08:38:41 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/02/06 08:38:41 NOTICE: drime root 'rclone-test-cofusav9dacu': 0 differences found 2026/02/06 08:38:41 NOTICE: drime root 'rclone-test-cofusav9dacu': 1 matching files 2026/02/06 08:38:41 DEBUG : Creating backend with remote "TestDrime:rclone-test-bulumut0pona" 2026/02/06 08:38:41 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/02/06 08:38:49 DEBUG : Creating backend with remote "/tmp/rclone471273036" run.go:185: Remote "drime root 'rclone-test-bulumut0pona'", Local "Local file system at /tmp/rclone471273036", Modify Window "876000h0m0s" both checkfile vs. NFCx2 remote (without normalization) 2026/02/06 08:39:18 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found 2026/02/06 08:39:18 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in drime root 'rclone-test-bulumut0pona' 2026/02/06 08:39:18 NOTICE: drime root 'rclone-test-bulumut0pona': 1 files missing 2026/02/06 08:39:18 NOTICE: 1 hashes missing 2026/02/06 08:39:18 NOTICE: drime root 'rclone-test-bulumut0pona': 1 differences found 2026/02/06 08:39:18 NOTICE: drime root 'rclone-test-bulumut0pona': 2 errors while checking both checkfile vs. NFCx2 remote (with normalization) 2026/02/06 08:39:26 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/02/06 08:39:26 NOTICE: drime root 'rclone-test-bulumut0pona': 0 differences found 2026/02/06 08:39:26 NOTICE: drime root 'rclone-test-bulumut0pona': 1 matching files 2026/02/06 08:39:26 DEBUG : drime root 'rclone-test-bulumut0pona': Purge remote 2026/02/06 08:39:30 DEBUG : drime root 'rclone-test-cofusav9dacu': Purge remote 2026/02/06 08:39:33 DEBUG : drime root 'rclone-test-qanuteg6duye': Purge remote 2026/02/06 08:39:37 DEBUG : drime root 'rclone-test-wivojot6bawa': Purge remote 2026/02/06 08:39:40 DEBUG : drime root 'rclone-test-gezocuj5jene': Purge remote 2026/02/06 08:39:43 DEBUG : drime root 'rclone-test-nolupuf7xima': Purge remote 2026/02/06 08:39:47 DEBUG : drime root 'rclone-test-meloxaq5dipe': Purge remote 2026/02/06 08:39:49 DEBUG : drime root 'rclone-test-yakajic9goma': Purge remote 2026/02/06 08:39:51 DEBUG : drime root 'rclone-test-kikafaw1lude': Purge remote 2026/02/06 08:39:54 DEBUG : drime root 'rclone-test-dureyal6luwe': Purge remote --- FAIL: TestApplyTransforms (229.68s) === RUN TestCopyFile run.go:185: Remote "drime root 'rclone-test-hifalix2cuyu'", Local "Local file system at /tmp/rclone1953119286", Modify Window "876000h0m0s" 2026/02/06 08:39:59 DEBUG : pacer: Reducing sleep to 5s 2026/02/06 08:39:59 DEBUG : file1: Need to transfer - File not found at Destination 2026/02/06 08:40:10 DEBUG : pacer: Reducing sleep to 2.5s 2026/02/06 08:40:14 DEBUG : pacer: Reducing sleep to 1.25s 2026/02/06 08:40:18 DEBUG : pacer: Reducing sleep to 625ms 2026/02/06 08:40:25 DEBUG : pacer: Reducing sleep to 312.5ms 2026/02/06 08:40:25 DEBUG : sub/file2: size = 14 OK 2026/02/06 08:40:25 INFO : file1: Copied (new) to: sub/file2 2026/02/06 08:40:28 DEBUG : pacer: Reducing sleep to 156.25ms 2026/02/06 08:40:32 DEBUG : pacer: Reducing sleep to 78.125ms 2026/02/06 08:40:36 DEBUG : pacer: Reducing sleep to 39.0625ms 2026/02/06 08:40:36 DEBUG : sub/file2: size = 14 OK 2026/02/06 08:40:36 DEBUG : file1: Sizes identical 2026/02/06 08:40:36 DEBUG : file1: Unchanged skipping 2026/02/06 08:40:40 DEBUG : pacer: Reducing sleep to 19.53125ms 2026/02/06 08:40:44 DEBUG : pacer: Reducing sleep to 10ms 2026/02/06 08:40:44 DEBUG : drime root 'rclone-test-hifalix2cuyu': 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 --- PASS: TestCopyFile (79.75s) === RUN TestCopyFileCompareDest run.go:185: Remote "drime root 'rclone-test-hifalix2cuyu'", Local "Local file system at /tmp/rclone1953119286", Modify Window "876000h0m0s" 2026/02/06 08:41:16 DEBUG : Creating backend with remote "TestDrime:rclone-test-hifalix2cuyu/dst" 2026/02/06 08:41:33 DEBUG : Creating backend with remote "TestDrime:rclone-test-hifalix2cuyu/CompareDest" 2026/02/06 08:41:45 DEBUG : one: Need to transfer - File not found at Destination 2026/02/06 08:42:08 DEBUG : one: size = 3 OK 2026/02/06 08:42:08 INFO : one: Copied (new) 2026/02/06 08:42:23 DEBUG : one: size = 5 (Local file system at /tmp/rclone1953119286) 2026/02/06 08:42:23 DEBUG : one: size = 3 (drime root 'rclone-test-hifalix2cuyu/dst') 2026/02/06 08:42:23 DEBUG : one: Sizes differ 2026/02/06 08:42:43 DEBUG : one: Removing old object on successful upload 2026/02/06 08:42:46 DEBUG : one: size = 5 OK 2026/02/06 08:42:46 INFO : one: Copied (replaced existing) 2026/02/06 08:43:06 DEBUG : dst/one: Removing old object on successful upload 2026/02/06 08:43:57 DEBUG : one: size = 5 (Local file system at /tmp/rclone1953119286) 2026/02/06 08:43:57 DEBUG : one: size = 3 (drime root 'rclone-test-hifalix2cuyu/dst') 2026/02/06 08:43:57 DEBUG : one: Sizes differ 2026/02/06 08:44:05 DEBUG : one: size = 5 OK 2026/02/06 08:44:05 DEBUG : one: Sizes identical 2026/02/06 08:44:05 DEBUG : one: Destination found in --compare-dest, skipping 2026/02/06 08:44:39 DEBUG : two: Need to transfer - File not found at Destination 2026/02/06 08:44:44 DEBUG : two: size = 3 OK 2026/02/06 08:44:44 DEBUG : two: Sizes identical 2026/02/06 08:44:44 DEBUG : two: Destination found in --compare-dest, skipping 2026/02/06 08:44:53 DEBUG : two: Need to transfer - File not found at Destination 2026/02/06 08:44:56 DEBUG : two: size = 3 OK 2026/02/06 08:44:56 DEBUG : two: Sizes identical 2026/02/06 08:44:56 DEBUG : two: Destination found in --compare-dest, skipping 2026/02/06 08:45:13 DEBUG : two: Need to transfer - File not found at Destination 2026/02/06 08:45:17 DEBUG : two: size = 5 (Local file system at /tmp/rclone1953119286) 2026/02/06 08:45:17 DEBUG : two: size = 3 (drime root 'rclone-test-hifalix2cuyu/CompareDest') 2026/02/06 08:45:17 DEBUG : two: Sizes differ 2026/02/06 08:45:32 DEBUG : two: size = 5 OK 2026/02/06 08:45:32 INFO : two: Copied (new) --- PASS: TestCopyFileCompareDest (302.07s) === RUN TestCopyFileCopyDest run.go:185: Remote "drime root 'rclone-test-hifalix2cuyu'", Local "Local file system at /tmp/rclone1953119286", Modify Window "876000h0m0s" 2026/02/06 08:46:18 DEBUG : Creating backend with remote "TestDrime:rclone-test-hifalix2cuyu/dst" 2026/02/06 08:46:38 DEBUG : Creating backend with remote "TestDrime:rclone-test-hifalix2cuyu/CopyDest" 2026/02/06 08:46:53 DEBUG : one: Need to transfer - File not found at Destination 2026/02/06 08:47:22 DEBUG : one: size = 3 OK 2026/02/06 08:47:22 INFO : one: Copied (new) 2026/02/06 08:47:45 DEBUG : one: size = 5 (Local file system at /tmp/rclone1953119286) 2026/02/06 08:47:45 DEBUG : one: size = 3 (drime root 'rclone-test-hifalix2cuyu/dst') 2026/02/06 08:47:45 DEBUG : one: Sizes differ 2026/02/06 08:48:01 DEBUG : one: Removing old object on successful upload 2026/02/06 08:48:05 DEBUG : one: size = 5 OK 2026/02/06 08:48:05 INFO : one: Copied (replaced existing) 2026/02/06 08:48:31 DEBUG : dst/one: Removing old object on successful upload 2026/02/06 08:49:13 DEBUG : Creating backend with remote "TestDrime:rclone-test-hifalix2cuyu/BackupDir" 2026/02/06 08:49:31 DEBUG : one: size = 5 (Local file system at /tmp/rclone1953119286) 2026/02/06 08:49:31 DEBUG : one: size = 3 (drime root 'rclone-test-hifalix2cuyu/dst') 2026/02/06 08:49:31 DEBUG : one: Sizes differ 2026/02/06 08:49:39 DEBUG : one: size = 5 OK 2026/02/06 08:49:39 DEBUG : one: Sizes identical 2026/02/06 08:49:39 DEBUG : one: size = 5 (Local file system at /tmp/rclone1953119286) 2026/02/06 08:49:39 DEBUG : one: size = 3 (drime root 'rclone-test-hifalix2cuyu/dst') 2026/02/06 08:49:39 DEBUG : one: Sizes differ 2026/02/06 08:50:04 INFO : one: Moved (server-side) 2026/02/06 08:50:15 DEBUG : one: size = 5 OK 2026/02/06 08:50:15 INFO : one: Copied (server-side copy) 2026/02/06 08:50:15 DEBUG : one: Destination found in --copy-dest, using server-side copy 2026/02/06 08:51:13 DEBUG : two: Need to transfer - File not found at Destination 2026/02/06 08:51:18 DEBUG : two: size = 3 OK 2026/02/06 08:51:18 DEBUG : two: Sizes identical 2026/02/06 08:51:28 DEBUG : two: size = 3 OK 2026/02/06 08:51:28 INFO : two: Copied (server-side copy) 2026/02/06 08:51:28 DEBUG : two: Destination found in --copy-dest, using server-side copy 2026/02/06 08:51:43 DEBUG : two: size = 3 OK 2026/02/06 08:51:43 DEBUG : two: Sizes identical 2026/02/06 08:51:43 DEBUG : two: Unchanged skipping 2026/02/06 08:52:19 DEBUG : three: Need to transfer - File not found at Destination 2026/02/06 08:52:23 DEBUG : three: size = 7 (Local file system at /tmp/rclone1953119286) 2026/02/06 08:52:23 DEBUG : three: size = 5 (drime root 'rclone-test-hifalix2cuyu/CopyDest') 2026/02/06 08:52:23 DEBUG : three: Sizes differ 2026/02/06 08:52:23 DEBUG : three: Destination not found in --copy-dest 2026/02/06 08:52:43 DEBUG : three: size = 7 OK 2026/02/06 08:52:43 INFO : three: Copied (new) --- PASS: TestCopyFileCopyDest (461.36s) === RUN TestListDirSorted run.go:185: Remote "drime root 'rclone-test-hifalix2cuyu'", Local "Local file system at /tmp/rclone1953119286", Modify Window "876000h0m0s" 2026/02/06 08:57:34 DEBUG : a.txt: Excluded (Size Filter) 2026/02/06 08:57:34 DEBUG : a.txt: Excluded 2026/02/06 08:57:53 DEBUG : sub dir/hello world2: Excluded (Size Filter) 2026/02/06 08:57:53 DEBUG : sub dir/hello world2: Excluded 2026/02/06 08:57:53 DEBUG : sub dir/hello world: Excluded (Size Filter) 2026/02/06 08:57:53 DEBUG : sub dir/hello world: Excluded 2026/02/06 08:58:08 DEBUG : sub dir/ignore dir: Excluded 2026/02/06 08:58:08 DEBUG : sub dir/hello world2: Excluded (Size Filter) 2026/02/06 08:58:08 DEBUG : sub dir/hello world2: Excluded 2026/02/06 08:58:08 DEBUG : sub dir/hello world: Excluded (Size Filter) 2026/02/06 08:58:08 DEBUG : sub dir/hello world: Excluded 2026/02/06 08:58:13 DEBUG : sub dir/ignore dir: Excluded --- PASS: TestListDirSorted (345.92s) === RUN TestListDirSortedFn run.go:185: Remote "drime root 'rclone-test-hifalix2cuyu'", Local "Local file system at /tmp/rclone1953119286", Modify Window "876000h0m0s" 2026/02/06 09:02:57 DEBUG : a.txt: Excluded (Size Filter) 2026/02/06 09:02:57 DEBUG : a.txt: Excluded 2026/02/06 09:03:03 DEBUG : sub dir/hello world2: Excluded (Size Filter) 2026/02/06 09:03:03 DEBUG : sub dir/hello world2: Excluded 2026/02/06 09:03:03 DEBUG : sub dir/hello world: Excluded (Size Filter) 2026/02/06 09:03:03 DEBUG : sub dir/hello world: Excluded 2026/02/06 09:03:16 DEBUG : sub dir/ignore dir: Excluded 2026/02/06 09:03:16 DEBUG : sub dir/hello world2: Excluded (Size Filter) 2026/02/06 09:03:16 DEBUG : sub dir/hello world2: Excluded 2026/02/06 09:03:16 DEBUG : sub dir/hello world: Excluded (Size Filter) 2026/02/06 09:03:16 DEBUG : sub dir/hello world: Excluded 2026/02/06 09:03:22 DEBUG : sub dir/ignore dir: Excluded --- PASS: TestListDirSortedFn (294.56s) === RUN TestListJSON run.go:185: Remote "drime root 'rclone-test-hifalix2cuyu'", Local "Local file system at /tmp/rclone1953119286", Modify Window "876000h0m0s" === RUN TestListJSON/Default === RUN TestListJSON/DirsOnly === RUN TestListJSON/Recurse === RUN TestListJSON/Metadata --- PASS: TestListJSON (102.13s) --- PASS: TestListJSON/Default (4.16s) --- PASS: TestListJSON/DirsOnly (4.22s) --- PASS: TestListJSON/Recurse (8.14s) --- PASS: TestListJSON/Metadata (4.59s) === RUN TestStatJSON run.go:185: Remote "drime root 'rclone-test-hifalix2cuyu'", Local "Local file system at /tmp/rclone1953119286", Modify Window "876000h0m0s" === 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/06 09:07:57 DEBUG : Creating backend with remote "TestDrime:rclone-test-hifalix2cuyu/notfound" === NAME TestStatJSON run.go:130: removing dir "sub" failed - try 1/3: directory not empty run.go:130: removing dir "sub" failed - try 2/3: directory not empty run.go:130: removing dir "sub" failed - try 3/3: directory not empty run.go:133: removing dir "sub" failed: directory not empty fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:327: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:327 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393 /usr/local/go/src/testing/testing.go:1211 /usr/local/go/src/testing/testing.go:1445 /usr/local/go/src/testing/testing.go:1786 Error: Not equal: expected: []string{} actual : []string{"sub"} Diff: --- Expected +++ Actual @@ -1,2 +1,3 @@ -([]string) { +([]string) (len=1) { + (string) (len=3) "sub" } Test: TestStatJSON Messages: directories --- FAIL: TestStatJSON (129.61s) --- PASS: TestStatJSON/Root (3.90s) --- PASS: TestStatJSON/RootFilesOnly (0.00s) --- PASS: TestStatJSON/RootDirsOnly (3.70s) --- PASS: TestStatJSON/Dir (8.64s) --- PASS: TestStatJSON/DirWithTrailingSlash (3.55s) --- PASS: TestStatJSON/File (3.01s) --- PASS: TestStatJSON/NotFound (6.35s) --- PASS: TestStatJSON/DirFilesOnly (2.56s) --- PASS: TestStatJSON/FileFilesOnly (2.84s) --- PASS: TestStatJSON/NotFoundFilesOnly (2.93s) --- PASS: TestStatJSON/DirDirsOnly (2.66s) --- PASS: TestStatJSON/FileDirsOnly (3.30s) --- PASS: TestStatJSON/NotFoundDirsOnly (2.54s) --- PASS: TestStatJSON/RootNotFound (9.80s) === RUN TestMaxDelete run.go:185: Remote "drime root 'rclone-test-hifalix2cuyu'", Local "Local file system at /tmp/rclone1953119286", Modify Window "876000h0m0s" fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:292: Sleeping for 7s just to make sure 2026/02/06 09:09:05 DEBUG : Waiting for deletions to finish 2026/02/06 09:09:05 ERROR : small: Got fatal error on delete: --max-delete threshold reached 2026/02/06 09:09:05 INFO : large: Deleted 2026/02/06 09:09:06 INFO : medium: Deleted operations_test.go:453: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:453 Error: Not equal: expected: 1 actual : 3 Test: TestMaxDelete fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:454 Error: Should be true Test: TestMaxDelete Messages: listing wrong, want got small (10) fstest.go:191: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:454 /usr/local/go/src/runtime/asm_amd64.s:1700 Error: Should be true Test: TestMaxDelete Messages: Unexpected file "small" 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 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:454 Error: Not equal: expected: []string{} actual : []string{"sub"} Diff: --- Expected +++ Actual @@ -1,2 +1,3 @@ -([]string) { +([]string) (len=1) { + (string) (len=3) "sub" } Test: TestMaxDelete Messages: directories --- FAIL: TestMaxDelete (46.79s) === RUN TestMaxDeleteSize run.go:185: Remote "drime root 'rclone-test-hifalix2cuyu'", Local "Local file system at /tmp/rclone1953119286", Modify Window "876000h0m0s" fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:292: Sleeping for 6s just to make sure 2026/02/06 09:09:53 DEBUG : Waiting for deletions to finish 2026/02/06 09:09:54 ERROR : small: Got fatal error on delete: --max-delete-size threshold reached 2026/02/06 09:09:54 INFO : large: Deleted 2026/02/06 09:09:54 INFO : medium: Deleted operations_test.go:492: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:492 Error: Not equal: expected: 1 actual : 3 Test: TestMaxDeleteSize 2026/02/06 09:09:55 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/06 09:09:55 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2026/02/06 09:09:56 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/06 09:09:56 DEBUG : pacer: Rate limited, increasing sleep to 40ms 2026/02/06 09:09:56 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/06 09:09:56 DEBUG : pacer: Rate limited, increasing sleep to 80ms 2026/02/06 09:09:56 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/06 09:09:56 DEBUG : pacer: Rate limited, increasing sleep to 160ms 2026/02/06 09:09:56 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/06 09:09:56 DEBUG : pacer: Rate limited, increasing sleep to 320ms 2026/02/06 09:09:57 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/06 09:09:57 DEBUG : pacer: Rate limited, increasing sleep to 640ms 2026/02/06 09:09:57 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/06 09:09:57 DEBUG : pacer: Rate limited, increasing sleep to 1.28s 2026/02/06 09:09:58 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/06 09:09:58 DEBUG : pacer: Rate limited, increasing sleep to 2.56s 2026/02/06 09:09:59 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/06 09:09:59 DEBUG : pacer: Rate limited, increasing sleep to 5.12s 2026/02/06 09:10:01 DEBUG : pacer: low level retry 10/10 (error Error "Server Error") 2026/02/06 09:10:01 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/06 09:10:06 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/06 09:10:06 DEBUG : pacer: Rate limited, increasing sleep to 20s 2026/02/06 09:10:17 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/06 09:10:37 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/06 09:10:57 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/06 09:11:17 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/06 09:11:37 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/06 09:11:57 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/06 09:12:17 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/06 09:12:37 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/06 09:12:57 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/06 09:13:17 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/06 09:13:37 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/06 09:13:57 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/06 09:14:17 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/06 09:14:37 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/06 09:14:57 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/06 09:15:17 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/06 09:15:37 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/06 09:15:57 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/06 09:16:17 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/06 09:16:37 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/06 09:16:57 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/06 09:17:17 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/06 09:17:37 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/06 09:17:57 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/06 09:18:18 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/06 09:18:38 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/06 09:18:57 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/06 09:19:18 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/06 09:19:38 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/06 09:19:58 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/06 09:20:17 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/06 09:20:37 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/06 09:20:57 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/06 09:21:18 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/06 09:21:38 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/06 09:21:57 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/06 09:22:18 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/06 09:22:37 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/06 09:22:57 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/06 09:23:17 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/06 09:23:38 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/06 09:23:57 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/06 09:24:17 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/06 09:24:37 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/06 09:24:57 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/06 09:25:17 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/06 09:25:37 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/06 09:25:57 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/06 09:26:17 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/06 09:26:37 DEBUG : pacer: Reducing sleep to 10s 2026/02/06 09:26:57 DEBUG : pacer: Reducing sleep to 5s 2026/02/06 09:27:07 DEBUG : pacer: Reducing sleep to 2.5s 2026/02/06 09:27:12 DEBUG : pacer: Reducing sleep to 1.25s --- FAIL: TestMaxDeleteSize (1073.05s) === RUN TestRmdirsNoLeaveRoot run.go:185: Remote "drime root 'rclone-test-hifalix2cuyu'", Local "Local file system at /tmp/rclone1953119286", Modify Window "876000h0m0s" 2026/02/06 09:27:14 DEBUG : pacer: Reducing sleep to 625ms 2026/02/06 09:27:16 DEBUG : pacer: Reducing sleep to 312.5ms 2026/02/06 09:27:16 DEBUG : pacer: Reducing sleep to 156.25ms 2026/02/06 09:27:17 DEBUG : pacer: Reducing sleep to 78.125ms 2026/02/06 09:27:17 DEBUG : pacer: Reducing sleep to 39.0625ms 2026/02/06 09:27:17 DEBUG : pacer: Reducing sleep to 19.53125ms 2026/02/06 09:27:18 DEBUG : pacer: Reducing sleep to 10ms 2026/02/06 09:27:22 INFO : A2: Making directory 2026/02/06 09:27:22 INFO : A1/B2: Making directory 2026/02/06 09:27:23 INFO : A1/B2/C2: Making directory 2026/02/06 09:27:24 INFO : A1/B1/C3: Making directory 2026/02/06 09:27:25 INFO : A3: Making directory 2026/02/06 09:27:26 INFO : A3/B3: Making directory 2026/02/06 09:27:27 INFO : A3/B3/C4: Making directory fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:292: Sleeping for 7s just to make sure 2026/02/06 09:27:48 DEBUG : removing 1 level 3 directories 2026/02/06 09:27:48 INFO : A3/B3/C4: Removing directory 2026/02/06 09:27:52 DEBUG : removing 2 level 3 directories 2026/02/06 09:27:52 INFO : A1/B2/C2: Removing directory 2026/02/06 09:27:52 INFO : A1/B1/C3: Removing directory 2026/02/06 09:27:53 DEBUG : removing 2 level 2 directories 2026/02/06 09:27:53 INFO : A3/B3: Removing directory 2026/02/06 09:27:53 INFO : A1/B2: Removing directory 2026/02/06 09:27:53 ERROR : A1/B2: Failed to rmdir: directory not empty 2026/02/06 09:27:54 DEBUG : removing 2 level 1 directories 2026/02/06 09:27:54 INFO : A3: Removing directory 2026/02/06 09:27:54 INFO : A2: Removing directory 2026/02/06 09:27:54 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/06 09:27:58 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/06 09:27:58 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2026/02/06 09:27:59 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/06 09:27:59 DEBUG : pacer: Rate limited, increasing sleep to 40ms 2026/02/06 09:27:59 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/06 09:27:59 DEBUG : pacer: Rate limited, increasing sleep to 80ms 2026/02/06 09:27:59 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/06 09:27:59 DEBUG : pacer: Rate limited, increasing sleep to 160ms 2026/02/06 09:28:00 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/06 09:28:00 DEBUG : pacer: Rate limited, increasing sleep to 320ms 2026/02/06 09:28:00 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/06 09:28:00 DEBUG : pacer: Rate limited, increasing sleep to 640ms 2026/02/06 09:28:00 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/06 09:28:00 DEBUG : pacer: Rate limited, increasing sleep to 1.28s 2026/02/06 09:28:01 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/06 09:28:01 DEBUG : pacer: Rate limited, increasing sleep to 2.56s 2026/02/06 09:28:02 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/06 09:28:02 DEBUG : pacer: Rate limited, increasing sleep to 5.12s 2026/02/06 09:28:05 DEBUG : pacer: low level retry 10/10 (error Error "Server Error") 2026/02/06 09:28:05 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/06 09:28:10 DEBUG : pacer: Reducing sleep to 5.12s 2026/02/06 09:28:20 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/06 09:28:20 DEBUG : pacer: Rate limited, increasing sleep to 10.24s 2026/02/06 09:28:26 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/06 09:28:26 DEBUG : pacer: Rate limited, increasing sleep to 20s 2026/02/06 09:28:35 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/06 09:28:57 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/06 09:29:16 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/06 09:29:36 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/06 09:29:56 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/06 09:30:16 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/06 09:30:36 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/06 09:30:56 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/06 09:31:17 DEBUG : pacer: Reducing sleep to 10s 2026/02/06 09:31:36 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/06 09:31:36 DEBUG : pacer: Rate limited, increasing sleep to 20s 2026/02/06 09:31:46 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/06 09:32:06 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/06 09:32:27 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/06 09:32:50 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/06 09:33:08 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/06 09:33:29 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/06 09:33:49 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/06 09:34:07 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/06 09:34:27 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/06 09:34:49 DEBUG : pacer: Reducing sleep to 10s 2026/02/06 09:35:10 DEBUG : pacer: Reducing sleep to 5s 2026/02/06 09:35:20 DEBUG : pacer: Reducing sleep to 2.5s 2026/02/06 09:35:26 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/06 09:35:26 DEBUG : pacer: Rate limited, increasing sleep to 5s 2026/02/06 09:35:31 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/06 09:35:31 DEBUG : pacer: Rate limited, increasing sleep to 10s 2026/02/06 09:35:36 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/06 09:35:36 DEBUG : pacer: Rate limited, increasing sleep to 20s 2026/02/06 09:35:45 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/06 09:36:04 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/06 09:36:24 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/06 09:36:44 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/06 09:37:06 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/06 09:37:26 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/06 09:37:46 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/06 09:38:05 DEBUG : pacer: Reducing sleep to 10s 2026/02/06 09:38:24 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/06 09:38:24 DEBUG : pacer: Rate limited, increasing sleep to 20s 2026/02/06 09:38:36 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/06 09:38:56 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/06 09:39:15 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/06 09:39:34 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/06 09:39:55 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/06 09:40:14 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/06 09:40:35 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/06 09:40:55 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/06 09:41:15 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/06 09:41:35 DEBUG : pacer: Reducing sleep to 10s 2026/02/06 09:41:55 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/06 09:41:55 DEBUG : pacer: Rate limited, increasing sleep to 20s 2026/02/06 09:42:04 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/06 09:42:28 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/06 09:42:48 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/06 09:43:10 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/06 09:43:30 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/06 09:43:51 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/06 09:44:11 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/06 09:44:32 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/06 09:44:47 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/06 09:45:09 DEBUG : pacer: Reducing sleep to 10s 2026/02/06 09:45:28 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/06 09:45:28 DEBUG : pacer: Rate limited, increasing sleep to 20s 2026/02/06 09:45:40 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/06 09:45:57 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/06 09:46:18 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/06 09:46:39 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/06 09:46:59 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/06 09:47:15 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/06 09:47:35 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/06 09:47:56 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/06 09:48:14 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/06 09:48:34 DEBUG : pacer: Reducing sleep to 10s 2026/02/06 09:48:55 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/06 09:48:55 DEBUG : pacer: Rate limited, increasing sleep to 20s 2026/02/06 09:49:05 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/06 09:49:24 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/06 09:49:45 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/06 09:50:05 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/06 09:50:25 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/06 09:50:46 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/06 09:51:04 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/06 09:51:24 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/06 09:51:45 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/06 09:52:05 DEBUG : pacer: Reducing sleep to 10s 2026/02/06 09:52:27 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/06 09:52:27 DEBUG : pacer: Rate limited, increasing sleep to 20s 2026/02/06 09:52:38 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/06 09:52:59 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/06 09:53:19 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/06 09:53:38 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/06 09:54:00 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/06 09:54:18 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/06 09:54:36 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/06 09:54:56 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/06 09:55:16 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/06 09:55:35 DEBUG : pacer: Reducing sleep to 10s 2026/02/06 09:55:56 DEBUG : pacer: Reducing sleep to 5s 2026/02/06 09:56:05 DEBUG : pacer: Reducing sleep to 2.5s 2026/02/06 09:56:10 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/06 09:56:10 DEBUG : pacer: Rate limited, increasing sleep to 5s 2026/02/06 09:56:14 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/06 09:56:14 DEBUG : pacer: Rate limited, increasing sleep to 10s 2026/02/06 09:56:20 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/06 09:56:20 DEBUG : pacer: Rate limited, increasing sleep to 20s 2026/02/06 09:56:31 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/06 09:56:50 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/06 09:57:09 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/06 09:57:32 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/06 09:57:51 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/06 09:58:11 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/06 09:58:31 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/06 09:58:52 DEBUG : pacer: Reducing sleep to 10s 2026/02/06 09:59:14 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/06 09:59:14 DEBUG : pacer: Rate limited, increasing sleep to 20s 2026/02/06 09:59:26 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/06 09:59:43 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/06 10:00:04 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/06 10:00:24 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/06 10:00:43 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/06 10:01:01 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/06 10:01:21 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/06 10:01:42 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/06 10:02:02 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/06 10:02:25 DEBUG : pacer: Reducing sleep to 10s 2026/02/06 10:02:41 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/06 10:02:41 DEBUG : pacer: Rate limited, increasing sleep to 20s 2026/02/06 10:02:52 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/06 10:03:15 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/06 10:03:34 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/06 10:03:55 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/06 10:04:13 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/06 10:04:34 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/06 10:04:54 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/06 10:05:15 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/06 10:05:32 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/06 10:05:53 DEBUG : pacer: Reducing sleep to 10s 2026/02/06 10:06:12 DEBUG : pacer: Reducing sleep to 5s 2026/02/06 10:06:23 DEBUG : pacer: Reducing sleep to 2.5s 2026/02/06 10:06:31 DEBUG : pacer: Reducing sleep to 1.25s 2026/02/06 10:06:37 DEBUG : pacer: Reducing sleep to 625ms 2026/02/06 10:06:44 DEBUG : pacer: Reducing sleep to 312.5ms 2026/02/06 10:06:50 DEBUG : pacer: Reducing sleep to 156.25ms --- FAIL: TestRmdirsNoLeaveRoot (2378.57s) FAIL 2026/02/06 10:06:50 DEBUG : drime root 'rclone-test-hifalix2cuyu': Purge remote 2026/02/06 10:06:58 DEBUG : pacer: Reducing sleep to 78.125ms "./operations.test -test.v -test.timeout 2h0m0s -remote TestDrime: -verbose -test.run '^(TestApplyTransforms|TestCopyFile|TestCopyFileCompareDest|TestCopyFileCopyDest|TestListDirSorted|TestListDirSortedFn|TestMaxDelete|TestMaxDeleteSize|TestRmdirsNoLeaveRoot|TestStatJSON)$|^TestListJSON$/^(Default|DirsOnly|Metadata|Recurse)$|^TestMultithreadCopy$/^(upload=true,size=10485760,streams=2|upload=true,size=10485761,streams=2)$'" - Finished ERROR in 1h38m20.711505692s (try 4/5): exit status 1: Failed [TestMultithreadCopy/upload=true,size=10485761,streams=2 TestApplyTransforms TestStatJSON TestMaxDelete TestMaxDeleteSize TestRmdirsNoLeaveRoot]