"./operations.test -test.v -test.timeout 2h0m0s -remote TestDrime: -verbose -test.run '^(TestApplyTransforms|TestCat|TestCheckDownload|TestCheckSum|TestCheckSumDownload|TestCopyFile|TestCopyFileCompareDest|TestCopyFileCopyDest|TestHashSums|TestListDirSortedFn|TestMaxDelete|TestMaxDeleteSize|TestParseSumFile|TestReadFile|TestStatJSON)$|^TestCheckSizeOnly$/^(1|2)$|^TestListJSON$/^(Default|DirsOnly|Metadata|Recurse)$|^TestMultithreadCopy$/^(upload=false,size=10485759,streams=2|upload=true,size=10485759,streams=2|upload=true,size=10485760,streams=2|upload=true,size=10485761,streams=2)$'" - Starting (try 4/5) 2026/02/05 05:48:31 DEBUG : Creating backend with remote "TestDrime:rclone-test-govener2vida" 2026/02/05 05:48:31 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/02/05 05:48:32 DEBUG : Creating backend with remote "/tmp/rclone819171941" === RUN TestMultithreadCopy run.go:185: Remote "drime root 'rclone-test-govener2vida'", Local "Local file system at /tmp/rclone819171941", 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:292: Sleeping for 6s just to make sure 2026/02/05 05:48:52 DEBUG : test-multithread-copy-false-10485759-2: multi-thread copy: disabling buffering because destination uses OpenWriterAt 2026/02/05 05:48:52 DEBUG : test-multithread-copy-false-10485759-2: multi-thread copy: write buffer set to 131072 2026/02/05 05:48:52 DEBUG : test-multithread-copy-false-10485759-2: multi-thread copy: chunk size 64Mi was bigger than source file size 10.000Mi 2026/02/05 05:48:52 DEBUG : test-multithread-copy-false-10485759-2: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 2 2026/02/05 05:48:52 DEBUG : test-multithread-copy-false-10485759-2: multi-thread copy: number of streams 4 was bigger than number of chunks 1 2026/02/05 05:48:52 DEBUG : test-multithread-copy-false-10485759-2: Starting multi-thread copy with 1 chunks of size 10.000Mi with 1 parallel streams 2026/02/05 05:48:52 DEBUG : test-multithread-copy-false-10485759-2: multi-thread copy: chunk 1/1 (0-10485759) size 10.000Mi starting 2026/02/05 05:48:52 DEBUG : test-multithread-copy-false-10485759-2: writing chunk 0 2026/02/05 05:48:52 DEBUG : test-multithread-copy-false-10485759-2: multi-thread copy: chunk 1/1 (0-10485759) size 10.000Mi finished 2026/02/05 05:48:52 DEBUG : test-multithread-copy-false-10485759-2: Finished multi-thread copy with 1 parts of size 10.000Mi === RUN TestMultithreadCopy/upload=true,size=10485759,streams=2 fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:292: Sleeping for 6s just to make sure 2026/02/05 05:49:02 DEBUG : test-multithread-copy-true-10485759-2: multi-thread copy: disabling buffering because source is local disk 2026/02/05 05:49:03 DEBUG : test-multithread-copy-true-10485759-2: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 2 2026/02/05 05:49:03 DEBUG : test-multithread-copy-true-10485759-2: multi-thread copy: number of streams 4 was bigger than number of chunks 2 2026/02/05 05:49:03 DEBUG : test-multithread-copy-true-10485759-2: Starting multi-thread copy with 2 chunks of size 5Mi with 2 parallel streams 2026/02/05 05:49:03 DEBUG : test-multithread-copy-true-10485759-2: multi-thread copy: chunk 2/2 (5242880-10485759) size 5.000Mi starting 2026/02/05 05:49:03 DEBUG : test-multithread-copy-true-10485759-2: multi-thread copy: chunk 1/2 (0-5242880) size 5Mi starting 2026/02/05 05:49:03 DEBUG : test-multithread-copy-true-10485759-2: multi-thread copy: chunk 2/2 (5242880-10485759) size 5.000Mi finished 2026/02/05 05:49:04 DEBUG : test-multithread-copy-true-10485759-2: multi-thread copy: chunk 1/2 (0-5242880) size 5Mi finished 2026/02/05 05:49:10 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/05 05:49:21 DEBUG : test-multithread-copy-true-10485760-2: multi-thread copy: disabling buffering because source is local disk 2026/02/05 05:49:21 DEBUG : test-multithread-copy-true-10485760-2: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 2 2026/02/05 05:49:21 DEBUG : test-multithread-copy-true-10485760-2: multi-thread copy: number of streams 4 was bigger than number of chunks 2 2026/02/05 05:49:21 DEBUG : test-multithread-copy-true-10485760-2: Starting multi-thread copy with 2 chunks of size 5Mi with 2 parallel streams 2026/02/05 05:49:21 DEBUG : test-multithread-copy-true-10485760-2: multi-thread copy: chunk 2/2 (5242880-10485760) size 5Mi starting 2026/02/05 05:49:21 DEBUG : test-multithread-copy-true-10485760-2: multi-thread copy: chunk 1/2 (0-5242880) size 5Mi starting 2026/02/05 05:49:22 DEBUG : test-multithread-copy-true-10485760-2: multi-thread copy: chunk 2/2 (5242880-10485760) size 5Mi finished 2026/02/05 05:49:22 DEBUG : test-multithread-copy-true-10485760-2: multi-thread copy: chunk 1/2 (0-5242880) size 5Mi finished 2026/02/05 05:49:27 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/05 05:49:38 DEBUG : test-multithread-copy-true-10485761-2: multi-thread copy: disabling buffering because source is local disk 2026/02/05 05:49:39 DEBUG : test-multithread-copy-true-10485761-2: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 2 2026/02/05 05:49:39 DEBUG : test-multithread-copy-true-10485761-2: multi-thread copy: number of streams 4 was bigger than number of chunks 3 2026/02/05 05:49:39 DEBUG : test-multithread-copy-true-10485761-2: Starting multi-thread copy with 3 chunks of size 5Mi with 3 parallel streams 2026/02/05 05:49:39 DEBUG : test-multithread-copy-true-10485761-2: multi-thread copy: chunk 3/3 (10485760-10485761) size 1 starting 2026/02/05 05:49:39 DEBUG : test-multithread-copy-true-10485761-2: multi-thread copy: chunk 2/3 (5242880-10485760) size 5Mi starting 2026/02/05 05:49:39 DEBUG : test-multithread-copy-true-10485761-2: multi-thread copy: chunk 1/3 (0-5242880) size 5Mi starting 2026/02/05 05:49:39 DEBUG : test-multithread-copy-true-10485761-2: multi-thread copy: chunk 3/3 (10485760-10485761) size 1 finished 2026/02/05 05:49:40 DEBUG : test-multithread-copy-true-10485761-2: multi-thread copy: chunk 1/3 (0-5242880) size 5Mi finished 2026/02/05 05:49:40 DEBUG : test-multithread-copy-true-10485761-2: multi-thread copy: chunk 2/3 (5242880-10485760) size 5Mi finished 2026/02/05 05:49:45 DEBUG : test-multithread-copy-true-10485761-2: Finished multi-thread copy with 3 parts of size 5Mi 2026/02/05 05:49:47 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/05 05:49:47 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2026/02/05 05:49:48 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/05 05:49:48 DEBUG : pacer: Rate limited, increasing sleep to 40ms 2026/02/05 05:49:48 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/05 05:49:48 DEBUG : pacer: Rate limited, increasing sleep to 80ms 2026/02/05 05:49:48 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/05 05:49:48 DEBUG : pacer: Rate limited, increasing sleep to 160ms 2026/02/05 05:49:49 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/05 05:49:49 DEBUG : pacer: Rate limited, increasing sleep to 320ms 2026/02/05 05:49:49 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/05 05:49:49 DEBUG : pacer: Rate limited, increasing sleep to 640ms 2026/02/05 05:49:49 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/05 05:49:49 DEBUG : pacer: Rate limited, increasing sleep to 1.28s 2026/02/05 05:49:50 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/05 05:49:50 DEBUG : pacer: Rate limited, increasing sleep to 2.56s 2026/02/05 05:49:51 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/05 05:49:51 DEBUG : pacer: Rate limited, increasing sleep to 5.12s 2026/02/05 05:49:54 DEBUG : pacer: low level retry 10/10 (error Error "Server Error") 2026/02/05 05:49:54 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/05 05:49:59 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/05 05:49:59 DEBUG : pacer: Rate limited, increasing sleep to 20s 2026/02/05 05:50:09 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/05 05:50:29 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/05 05:50:49 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/05 05:51:09 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/05 05:51:29 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/05 05:51:49 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/05 05:52:09 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/05 05:52:29 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/05 05:52:49 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/05 05:53:09 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/05 05:53:29 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/05 05:53:50 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/05 05:54:09 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/05 05:54:29 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/05 05:54:49 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/05 05:55:10 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/05 05:55:29 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/05 05:55:49 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/05 05:56:09 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/05 05:56:29 DEBUG : pacer: Reducing sleep to 10s --- FAIL: TestMultithreadCopy (477.31s) --- PASS: TestMultithreadCopy/upload=false,size=10485759,streams=2 (19.10s) --- PASS: TestMultithreadCopy/upload=true,size=10485759,streams=2 (17.65s) --- FAIL: TestMultithreadCopy/upload=true,size=10485760,streams=2 (17.67s) --- FAIL: TestMultithreadCopy/upload=true,size=10485761,streams=2 (18.06s) === RUN TestCheckDownload run.go:185: Remote "drime root 'rclone-test-govener2vida'", Local "Local file system at /tmp/rclone819171941", Modify Window "876000h0m0s" 2026/02/05 05:56:49 DEBUG : pacer: Reducing sleep to 5s 2026/02/05 05:57:06 DEBUG : pacer: Reducing sleep to 2.5s 2026/02/05 05:57:06 DEBUG : pacer: Reducing sleep to 1.25s === RUN TestCheckDownload/1 === RUN TestCheckDownload/2 2026/02/05 05:57:12 DEBUG : pacer: Reducing sleep to 39.0625ms 2026/02/05 05:57:20 DEBUG : pacer: Reducing sleep to 19.53125ms 2026/02/05 05:57:20 DEBUG : pacer: Reducing sleep to 10ms === RUN TestCheckDownload/3 === NAME TestCheckDownload fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:138 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:204 /usr/local/go/src/runtime/asm_amd64.s:1700 Error: Should be true Test: TestCheckDownload Messages: listing wrong, want empty space (1), potato2 (60), rutabaga (8) got empty space (1), rutabaga (8) fstest.go:203: Not found "potato2" fstest.go:206: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:206 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:310 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:138 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:204 Error: Not equal: expected: 0 actual : 1 Test: TestCheckDownload Messages: 1 objects not found === RUN TestCheckDownload/4 === RUN TestCheckDownload/5 === NAME TestCheckDownload fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:161 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:204 /usr/local/go/src/runtime/asm_amd64.s:1700 Error: Should be true Test: TestCheckDownload Messages: listing wrong, want empty space (1), potato2 (60), remotepotato (60), rutabaga (8) got empty space (1), potato2 (60), rutabaga (8) fstest.go:203: Not found "remotepotato" fstest.go:206: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:206 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:310 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:161 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:204 Error: Not equal: expected: 0 actual : 1 Test: TestCheckDownload Messages: 1 objects not found === RUN TestCheckDownload/6 === RUN TestCheckDownload/7 === NAME TestCheckDownload fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393 /usr/local/go/src/testing/testing.go:1211 /usr/local/go/src/testing/testing.go:1445 /usr/local/go/src/testing/testing.go:1786 Error: Should be true Test: TestCheckDownload Messages: listing wrong, want got empty space (1), potato2 (60), remotepotato (60), rutabaga (8) fstest.go:191: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393 /usr/local/go/src/testing/testing.go:1211 /usr/local/go/src/testing/testing.go:1445 /usr/local/go/src/testing/testing.go:1786 Error: Should be true Test: TestCheckDownload Messages: Unexpected file "empty space" fstest.go:191: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393 /usr/local/go/src/testing/testing.go:1211 /usr/local/go/src/testing/testing.go:1445 /usr/local/go/src/testing/testing.go:1786 Error: Should be true Test: TestCheckDownload Messages: Unexpected file "potato2" fstest.go:191: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393 /usr/local/go/src/testing/testing.go:1211 /usr/local/go/src/testing/testing.go:1445 /usr/local/go/src/testing/testing.go:1786 Error: Should be true Test: TestCheckDownload Messages: Unexpected file "remotepotato" fstest.go:191: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393 /usr/local/go/src/testing/testing.go:1211 /usr/local/go/src/testing/testing.go:1445 /usr/local/go/src/testing/testing.go:1786 Error: Should be true Test: TestCheckDownload Messages: Unexpected file "rutabaga" --- FAIL: TestCheckDownload (89.79s) --- PASS: TestCheckDownload/1 (3.93s) --- PASS: TestCheckDownload/2 (1.22s) --- PASS: TestCheckDownload/3 (0.64s) --- PASS: TestCheckDownload/4 (1.43s) --- PASS: TestCheckDownload/5 (0.83s) --- PASS: TestCheckDownload/6 (1.09s) --- PASS: TestCheckDownload/7 (0.53s) === RUN TestCheckSizeOnly run.go:185: Remote "drime root 'rclone-test-govener2vida'", Local "Local file system at /tmp/rclone819171941", Modify Window "876000h0m0s" fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:99 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:181 Error: Should be true Test: TestCheckSizeOnly Messages: listing wrong, want rutabaga (8) got fstest.go:203: Not found "rutabaga" fstest.go:206: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:206 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:310 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:99 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:181 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:212 Error: Not equal: expected: 0 actual : 1 Test: TestCheckSizeOnly Messages: 1 objects not found === RUN TestCheckSizeOnly/1 === RUN TestCheckSizeOnly/2 === NAME TestCheckSizeOnly fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:122 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:181 Error: Should be true Test: TestCheckSizeOnly Messages: listing wrong, want empty space (1), rutabaga (8) got rutabaga (8) fstest.go:203: Not found "empty space" fstest.go:206: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:206 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:310 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:122 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:181 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:212 Error: Not equal: expected: 0 actual : 1 Test: TestCheckSizeOnly Messages: 1 objects not found fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:138 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:181 Error: Should be true Test: TestCheckSizeOnly Messages: listing wrong, want empty space (1), potato2 (60), rutabaga (8) got empty space (1), rutabaga (8) fstest.go:203: Not found "potato2" fstest.go:206: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:206 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:310 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:138 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:181 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:212 Error: Not equal: expected: 0 actual : 1 Test: TestCheckSizeOnly Messages: 1 objects not found fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:161 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:181 Error: Should be true Test: TestCheckSizeOnly Messages: listing wrong, want empty space (1), potato2 (60), remotepotato (60), rutabaga (8) got empty space (1), potato2 (60), rutabaga (8) fstest.go:203: Not found "remotepotato" fstest.go:206: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:206 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:310 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:161 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:181 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:212 Error: Not equal: expected: 0 actual : 1 Test: TestCheckSizeOnly Messages: 1 objects not found fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393 /usr/local/go/src/testing/testing.go:1211 /usr/local/go/src/testing/testing.go:1445 /usr/local/go/src/testing/testing.go:1786 Error: Should be true Test: TestCheckSizeOnly Messages: listing wrong, want got empty space (1), potato2 (60), remotepotato (60), rutabaga (8) fstest.go:191: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393 /usr/local/go/src/testing/testing.go:1211 /usr/local/go/src/testing/testing.go:1445 /usr/local/go/src/testing/testing.go:1786 Error: Should be true Test: TestCheckSizeOnly Messages: Unexpected file "empty space" fstest.go:191: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393 /usr/local/go/src/testing/testing.go:1211 /usr/local/go/src/testing/testing.go:1445 /usr/local/go/src/testing/testing.go:1786 Error: Should be true Test: TestCheckSizeOnly Messages: Unexpected file "potato2" fstest.go:191: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393 /usr/local/go/src/testing/testing.go:1211 /usr/local/go/src/testing/testing.go:1445 /usr/local/go/src/testing/testing.go:1786 Error: Should be true Test: TestCheckSizeOnly Messages: Unexpected file "remotepotato" fstest.go:191: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393 /usr/local/go/src/testing/testing.go:1211 /usr/local/go/src/testing/testing.go:1445 /usr/local/go/src/testing/testing.go:1786 Error: Should be true Test: TestCheckSizeOnly Messages: Unexpected file "rutabaga" --- FAIL: TestCheckSizeOnly (60.51s) --- PASS: TestCheckSizeOnly/1 (0.51s) --- PASS: TestCheckSizeOnly/2 (0.25s) === RUN TestParseSumFile run.go:185: Remote "drime root 'rclone-test-govener2vida'", Local "Local file system at /tmp/rclone819171941", Modify Window "876000h0m0s" fstest.go:542: Sleeping for 1s for findObject eventual consistency: 1/3 (object not found) fstest.go:542: Sleeping for 1.5s for findObject eventual consistency: 2/3 (object not found) fstest.go:542: Sleeping for 2.25s for findObject eventual consistency: 3/3 (object not found) fstest.go:546: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:546 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:309 Error: Received unexpected error: object not found Test: TestParseSumFile --- FAIL: TestParseSumFile (9.45s) === RUN TestCheckSum run.go:185: Remote "drime root 'rclone-test-govener2vida'", Local "Local file system at /tmp/rclone819171941", Modify Window "876000h0m0s" 2026/02/05 05:59:09 DEBUG : Creating backend with remote "TestDrime:rclone-test-govener2vida/data" check_test.go:350: drime root 'rclone-test-govener2vida/data' lacks md5, 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/run.go:178 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393 /usr/local/go/src/testing/testing.go:1211 /usr/local/go/src/testing/testing.go:1445 /usr/local/go/src/testing/testing.go:1786 /usr/local/go/src/runtime/panic.go:636 /usr/local/go/src/testing/testing.go:1156 /usr/local/go/src/testing/testing.go:1139 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:350 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:537 Error: Should be true Test: TestCheckSum Messages: listing wrong, want got test.sum (81) 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 /usr/local/go/src/runtime/panic.go:636 /usr/local/go/src/testing/testing.go:1156 /usr/local/go/src/testing/testing.go:1139 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:350 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:537 Error: Should be true Test: TestCheckSum Messages: Unexpected file "test.sum" --- FAIL: TestCheckSum (10.60s) === RUN TestCheckSumDownload run.go:185: Remote "drime root 'rclone-test-govener2vida'", Local "Local file system at /tmp/rclone819171941", Modify Window "876000h0m0s" 2026/02/05 05:59:20 DEBUG : Creating backend with remote "TestDrime:rclone-test-govener2vida/data" fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:292: Sleeping for 7s just to make sure === RUN TestCheckSumDownload/subtest1 2026/02/05 05:59:56 DEBUG : test.sum: Removing old object on successful upload === RUN TestCheckSumDownload/subtest2 2026/02/05 06:00:02 DEBUG : test.sum: Removing old object on successful upload === NAME TestCheckSumDownload fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:292: Sleeping for 7s just to make sure === RUN TestCheckSumDownload/subtest3 2026/02/05 06:00:22 DEBUG : test.sum: Removing old object on successful upload === RUN TestCheckSumDownload/subtest4 2026/02/05 06:00:35 DEBUG : test.sum: Removing old object on successful upload === RUN TestCheckSumDownload/subtest5 2026/02/05 06:00:40 DEBUG : test.sum: Removing old object on successful upload === RUN TestCheckSumDownload/subtest6 === NAME TestCheckSumDownload check_test.go:409: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:409 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:428 Error: Not equal: expected: 2 actual : 1 Test: TestCheckSumDownload Messages: wrong error count in run 6 check_test.go:384: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:384 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:417 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:428 Error: Not equal: expected: []string{"", "* potato", "+ orange", "= banana"} actual : []string{"", "+ orange", "= banana", "= potato"} Diff: --- Expected +++ Actual @@ -2,5 +2,5 @@ (string) "", - (string) (len=8) "* potato", (string) (len=8) "+ orange", - (string) (len=8) "= banana" + (string) (len=8) "= banana", + (string) (len=8) "= potato" } Test: TestCheckSumDownload Messages: wrong combined result in run 6 check_test.go:384: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:384 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:420 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:428 Error: Not equal: expected: []string{"", "banana"} actual : []string{"", "banana", "potato"} Diff: --- Expected +++ Actual @@ -1,4 +1,5 @@ -([]string) (len=2) { +([]string) (len=3) { (string) "", - (string) (len=6) "banana" + (string) (len=6) "banana", + (string) (len=6) "potato" } Test: TestCheckSumDownload Messages: wrong match result in run 6 check_test.go:384: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:384 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:421 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:428 Error: Not equal: expected: []string{"", "potato"} actual : []string{} Diff: --- Expected +++ Actual @@ -1,4 +1,2 @@ -([]string) (len=2) { - (string) "", - (string) (len=6) "potato" +([]string) { } Test: TestCheckSumDownload Messages: wrong differ result in run 6 2026/02/05 06:00:46 DEBUG : data/banana: Removing old object on successful upload 2026/02/05 06:00:49 DEBUG : data/potato: Removing old object on successful upload 2026/02/05 06:00:53 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:525 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:541 /usr/local/go/src/runtime/asm_amd64.s:1700 Error: Should be true Test: TestCheckSumDownload Messages: listing wrong, want data/banana (13), data/potato (15), test.sum (82) got data/banana (13), data/potato (15), test.sum (123) fstest.go:150: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:150 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:195 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:525 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:541 Error: Not equal: expected: 82 actual : 123 Test: TestCheckSumDownload Messages: test.sum: size incorrect file=82 vs obj=123 === RUN TestCheckSumDownload/subtest7 === NAME TestCheckSumDownload run.go:130: removing dir "data" failed - try 1/3: directory not empty run.go:130: removing dir "data" failed - try 2/3: directory not empty fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:292: Sleeping for 7s just to make sure --- FAIL: TestCheckSumDownload (121.01s) --- PASS: TestCheckSumDownload/subtest1 (2.02s) --- PASS: TestCheckSumDownload/subtest2 (1.44s) --- PASS: TestCheckSumDownload/subtest3 (2.02s) --- PASS: TestCheckSumDownload/subtest4 (1.58s) --- PASS: TestCheckSumDownload/subtest5 (1.43s) --- PASS: TestCheckSumDownload/subtest6 (1.13s) --- PASS: TestCheckSumDownload/subtest7 (1.77s) === RUN TestApplyTransforms 2026/02/05 06:01:21 DEBUG : Creating backend with remote "TestDrime:rclone-test-hejawis4yawe" 2026/02/05 06:01:21 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/02/05 06:01:22 DEBUG : Creating backend with remote "/tmp/rclone1022277170" run.go:185: Remote "drime root 'rclone-test-hejawis4yawe'", Local "Local file system at /tmp/rclone1022277170", Modify Window "876000h0m0s" upper checkfile vs. lower remote (without normalization) 2026/02/05 06:01:27 ERROR : HELLO, WORLD!: file not in drime root 'rclone-test-hejawis4yawe' 2026/02/05 06:01:27 NOTICE: drime root 'rclone-test-hejawis4yawe': 1 files missing 2026/02/05 06:01:27 NOTICE: drime root 'rclone-test-hejawis4yawe': 0 differences found 2026/02/05 06:01:27 NOTICE: drime root 'rclone-test-hejawis4yawe': 1 errors while checking upper checkfile vs. lower remote (with normalization) 2026/02/05 06:01:27 ERROR : hello, world!: file not in drime root 'rclone-test-hejawis4yawe' 2026/02/05 06:01:27 NOTICE: drime root 'rclone-test-hejawis4yawe': 1 files missing 2026/02/05 06:01:27 NOTICE: drime root 'rclone-test-hejawis4yawe': 0 differences found 2026/02/05 06:01:27 NOTICE: drime root 'rclone-test-hejawis4yawe': 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-hejawis4yawe' Test: TestApplyTransforms Messages: unexpected error for upper checkfile vs. lower remote (with normalization) HELLO, WORLD! hello, world! 2026/02/05 06:01:27 DEBUG : Creating backend with remote "TestDrime:rclone-test-xedepoy0tute" 2026/02/05 06:01:27 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/02/05 06:01:28 DEBUG : Creating backend with remote "/tmp/rclone4030961685" run.go:185: Remote "drime root 'rclone-test-xedepoy0tute'", Local "Local file system at /tmp/rclone4030961685", Modify Window "876000h0m0s" lower checkfile vs. upper remote (without normalization) 2026/02/05 06:01:38 ERROR : hello, world!: file not in drime root 'rclone-test-xedepoy0tute' 2026/02/05 06:01:38 NOTICE: drime root 'rclone-test-xedepoy0tute': 1 files missing 2026/02/05 06:01:38 NOTICE: drime root 'rclone-test-xedepoy0tute': 0 differences found 2026/02/05 06:01:38 NOTICE: drime root 'rclone-test-xedepoy0tute': 1 errors while checking lower checkfile vs. upper remote (with normalization) 2026/02/05 06:01:38 ERROR : hello, world!: file not in drime root 'rclone-test-xedepoy0tute' 2026/02/05 06:01:38 NOTICE: drime root 'rclone-test-xedepoy0tute': 1 files missing 2026/02/05 06:01:38 NOTICE: drime root 'rclone-test-xedepoy0tute': 0 differences found 2026/02/05 06:01:38 NOTICE: drime root 'rclone-test-xedepoy0tute': 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-xedepoy0tute' Test: TestApplyTransforms Messages: unexpected error for lower checkfile vs. upper remote (with normalization) hello, world! HELLO, WORLD! 2026/02/05 06:01:38 DEBUG : Creating backend with remote "TestDrime:rclone-test-hacokiy0woje" 2026/02/05 06:01:38 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/02/05 06:01:39 DEBUG : Creating backend with remote "/tmp/rclone2882709118" run.go:185: Remote "drime root 'rclone-test-hacokiy0woje'", Local "Local file system at /tmp/rclone2882709118", Modify Window "876000h0m0s" lower checkfile vs. upperlowermixed remote (without normalization) 2026/02/05 06:01:42 ERROR : hello, world!: file not in drime root 'rclone-test-hacokiy0woje' 2026/02/05 06:01:42 NOTICE: drime root 'rclone-test-hacokiy0woje': 1 files missing 2026/02/05 06:01:42 NOTICE: drime root 'rclone-test-hacokiy0woje': 0 differences found 2026/02/05 06:01:42 NOTICE: drime root 'rclone-test-hacokiy0woje': 1 errors while checking lower checkfile vs. upperlowermixed remote (with normalization) 2026/02/05 06:01:43 ERROR : hello, world!: file not in drime root 'rclone-test-hacokiy0woje' 2026/02/05 06:01:43 NOTICE: drime root 'rclone-test-hacokiy0woje': 1 files missing 2026/02/05 06:01:43 NOTICE: drime root 'rclone-test-hacokiy0woje': 0 differences found 2026/02/05 06:01:43 NOTICE: drime root 'rclone-test-hacokiy0woje': 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-hacokiy0woje' Test: TestApplyTransforms Messages: unexpected error for lower checkfile vs. upperlowermixed remote (with normalization) hello, world! HeLlO, wOrLd! 2026/02/05 06:01:43 DEBUG : Creating backend with remote "TestDrime:rclone-test-xacedem6duru" 2026/02/05 06:01:43 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/02/05 06:01:43 DEBUG : Creating backend with remote "/tmp/rclone1891048642" run.go:185: Remote "drime root 'rclone-test-xacedem6duru'", Local "Local file system at /tmp/rclone1891048642", Modify Window "876000h0m0s" upperlowermixed checkfile vs. upper remote (without normalization) 2026/02/05 06:01:48 ERROR : HeLlO, wOrLd!: file not in drime root 'rclone-test-xacedem6duru' 2026/02/05 06:01:48 NOTICE: drime root 'rclone-test-xacedem6duru': 1 files missing 2026/02/05 06:01:48 NOTICE: drime root 'rclone-test-xacedem6duru': 0 differences found 2026/02/05 06:01:48 NOTICE: drime root 'rclone-test-xacedem6duru': 1 errors while checking upperlowermixed checkfile vs. upper remote (with normalization) 2026/02/05 06:01:48 ERROR : hello, world!: file not in drime root 'rclone-test-xacedem6duru' 2026/02/05 06:01:48 NOTICE: drime root 'rclone-test-xacedem6duru': 1 files missing 2026/02/05 06:01:48 NOTICE: drime root 'rclone-test-xacedem6duru': 0 differences found 2026/02/05 06:01:48 NOTICE: drime root 'rclone-test-xacedem6duru': 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-xacedem6duru' Test: TestApplyTransforms Messages: unexpected error for upperlowermixed checkfile vs. upper remote (with normalization) HeLlO, wOrLd! HELLO, WORLD! 2026/02/05 06:01:48 DEBUG : Creating backend with remote "TestDrime:rclone-test-bukudud1nofi" 2026/02/05 06:01:48 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/02/05 06:01:49 DEBUG : Creating backend with remote "/tmp/rclone3174841747" run.go:185: Remote "drime root 'rclone-test-bukudud1nofi'", Local "Local file system at /tmp/rclone3174841747", Modify Window "876000h0m0s" NFD checkfile vs. NFC remote (without normalization) 2026/02/05 06:01:57 ERROR : 測試_Русский___ě_áñ: file not in drime root 'rclone-test-bukudud1nofi' 2026/02/05 06:01:57 NOTICE: drime root 'rclone-test-bukudud1nofi': 1 files missing 2026/02/05 06:01:57 NOTICE: drime root 'rclone-test-bukudud1nofi': 0 differences found 2026/02/05 06:01:57 NOTICE: drime root 'rclone-test-bukudud1nofi': 1 errors while checking NFD checkfile vs. NFC remote (with normalization) 2026/02/05 06:01:57 ERROR : 測試_русский___ě_áñ: file not in drime root 'rclone-test-bukudud1nofi' 2026/02/05 06:01:57 NOTICE: drime root 'rclone-test-bukudud1nofi': 1 files missing 2026/02/05 06:01:57 NOTICE: drime root 'rclone-test-bukudud1nofi': 0 differences found 2026/02/05 06:01:57 NOTICE: drime root 'rclone-test-bukudud1nofi': 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-bukudud1nofi' Test: TestApplyTransforms Messages: unexpected error for NFD checkfile vs. NFC remote (with normalization) 測試_Русский___ě_áñ 測試_Русский___ě_áñ 2026/02/05 06:01:57 DEBUG : Creating backend with remote "TestDrime:rclone-test-xagoqit0givu" 2026/02/05 06:01:57 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/02/05 06:01:58 DEBUG : Creating backend with remote "/tmp/rclone1077253796" run.go:185: Remote "drime root 'rclone-test-xagoqit0givu'", Local "Local file system at /tmp/rclone1077253796", Modify Window "876000h0m0s" NFC checkfile vs. NFD remote (without normalization) 2026/02/05 06:02:03 ERROR : 測試_Русский___ě_áñ: file not in drime root 'rclone-test-xagoqit0givu' 2026/02/05 06:02:03 NOTICE: drime root 'rclone-test-xagoqit0givu': 1 files missing 2026/02/05 06:02:03 NOTICE: drime root 'rclone-test-xagoqit0givu': 0 differences found 2026/02/05 06:02:03 NOTICE: drime root 'rclone-test-xagoqit0givu': 1 errors while checking NFC checkfile vs. NFD remote (with normalization) 2026/02/05 06:02:03 ERROR : 測試_русский___ě_áñ: file not in drime root 'rclone-test-xagoqit0givu' 2026/02/05 06:02:03 NOTICE: drime root 'rclone-test-xagoqit0givu': 1 files missing 2026/02/05 06:02:03 NOTICE: drime root 'rclone-test-xagoqit0givu': 0 differences found 2026/02/05 06:02:03 NOTICE: drime root 'rclone-test-xagoqit0givu': 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-xagoqit0givu' Test: TestApplyTransforms Messages: unexpected error for NFC checkfile vs. NFD remote (with normalization) 測試_Русский___ě_áñ 測試_Русский___ě_áñ 2026/02/05 06:02:03 DEBUG : Creating backend with remote "TestDrime:rclone-test-lilidar1ceya" 2026/02/05 06:02:03 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/02/05 06:02:04 DEBUG : Creating backend with remote "/tmp/rclone2532268225" run.go:185: Remote "drime root 'rclone-test-lilidar1ceya'", Local "Local file system at /tmp/rclone2532268225", Modify Window "876000h0m0s" NFDx2 checkfile vs. both remote (without normalization) 2026/02/05 06:02:10 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in drime root 'rclone-test-lilidar1ceya' 2026/02/05 06:02:10 NOTICE: drime root 'rclone-test-lilidar1ceya': 1 files missing 2026/02/05 06:02:10 NOTICE: drime root 'rclone-test-lilidar1ceya': 0 differences found 2026/02/05 06:02:10 NOTICE: drime root 'rclone-test-lilidar1ceya': 1 errors while checking NFDx2 checkfile vs. both remote (with normalization) 2026/02/05 06:02:10 ERROR : 測試_русский___ě_áñ測試_русский___ě_áñ: file not in drime root 'rclone-test-lilidar1ceya' 2026/02/05 06:02:10 NOTICE: drime root 'rclone-test-lilidar1ceya': 1 files missing 2026/02/05 06:02:10 NOTICE: drime root 'rclone-test-lilidar1ceya': 0 differences found 2026/02/05 06:02:10 NOTICE: drime root 'rclone-test-lilidar1ceya': 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-lilidar1ceya' Test: TestApplyTransforms Messages: unexpected error for NFDx2 checkfile vs. both remote (with normalization) 測試_Русский___ě_áñ測試_Русский___ě_áñ 測試_Русский___ě_áñ測試_Русский___ě_áñ 2026/02/05 06:02:10 DEBUG : Creating backend with remote "TestDrime:rclone-test-risisaj8pihu" 2026/02/05 06:02:10 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/02/05 06:02:11 DEBUG : Creating backend with remote "/tmp/rclone1315488999" run.go:185: Remote "drime root 'rclone-test-risisaj8pihu'", Local "Local file system at /tmp/rclone1315488999", Modify Window "876000h0m0s" NFCx2 checkfile vs. both remote (without normalization) 2026/02/05 06:02:18 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in drime root 'rclone-test-risisaj8pihu' 2026/02/05 06:02:18 NOTICE: drime root 'rclone-test-risisaj8pihu': 1 files missing 2026/02/05 06:02:18 NOTICE: drime root 'rclone-test-risisaj8pihu': 0 differences found 2026/02/05 06:02:18 NOTICE: drime root 'rclone-test-risisaj8pihu': 1 errors while checking NFCx2 checkfile vs. both remote (with normalization) 2026/02/05 06:02:18 ERROR : 測試_русский___ě_áñ測試_русский___ě_áñ: file not in drime root 'rclone-test-risisaj8pihu' 2026/02/05 06:02:18 NOTICE: drime root 'rclone-test-risisaj8pihu': 1 files missing 2026/02/05 06:02:18 NOTICE: drime root 'rclone-test-risisaj8pihu': 0 differences found 2026/02/05 06:02:18 NOTICE: drime root 'rclone-test-risisaj8pihu': 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-risisaj8pihu' Test: TestApplyTransforms Messages: unexpected error for NFCx2 checkfile vs. both remote (with normalization) 測試_Русский___ě_áñ測試_Русский___ě_áñ 測試_Русский___ě_áñ測試_Русский___ě_áñ 2026/02/05 06:02:18 DEBUG : Creating backend with remote "TestDrime:rclone-test-kujipos4ponu" 2026/02/05 06:02:18 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/02/05 06:02:19 DEBUG : Creating backend with remote "/tmp/rclone2402225655" run.go:185: Remote "drime root 'rclone-test-kujipos4ponu'", Local "Local file system at /tmp/rclone2402225655", Modify Window "876000h0m0s" both checkfile vs. NFDx2 remote (without normalization) 2026/02/05 06:02:24 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in drime root 'rclone-test-kujipos4ponu' 2026/02/05 06:02:24 NOTICE: drime root 'rclone-test-kujipos4ponu': 1 files missing 2026/02/05 06:02:24 NOTICE: drime root 'rclone-test-kujipos4ponu': 0 differences found 2026/02/05 06:02:24 NOTICE: drime root 'rclone-test-kujipos4ponu': 1 errors while checking both checkfile vs. NFDx2 remote (with normalization) 2026/02/05 06:02:24 ERROR : 測試_русский___ě_áñ測試_русский___ě_áñ: file not in drime root 'rclone-test-kujipos4ponu' 2026/02/05 06:02:24 NOTICE: drime root 'rclone-test-kujipos4ponu': 1 files missing 2026/02/05 06:02:24 NOTICE: drime root 'rclone-test-kujipos4ponu': 0 differences found 2026/02/05 06:02:24 NOTICE: drime root 'rclone-test-kujipos4ponu': 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-kujipos4ponu' Test: TestApplyTransforms Messages: unexpected error for both checkfile vs. NFDx2 remote (with normalization) 測試_Русский___ě_áñ測試_Русский___ě_áñ 測試_Русский___ě_áñ測試_Русский___ě_áñ 2026/02/05 06:02:24 DEBUG : Creating backend with remote "TestDrime:rclone-test-vifumic2yape" 2026/02/05 06:02:24 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/02/05 06:02:25 DEBUG : Creating backend with remote "/tmp/rclone1590858237" run.go:185: Remote "drime root 'rclone-test-vifumic2yape'", Local "Local file system at /tmp/rclone1590858237", Modify Window "876000h0m0s" both checkfile vs. NFCx2 remote (without normalization) 2026/02/05 06:02:33 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in drime root 'rclone-test-vifumic2yape' 2026/02/05 06:02:33 NOTICE: drime root 'rclone-test-vifumic2yape': 1 files missing 2026/02/05 06:02:33 NOTICE: drime root 'rclone-test-vifumic2yape': 0 differences found 2026/02/05 06:02:33 NOTICE: drime root 'rclone-test-vifumic2yape': 1 errors while checking both checkfile vs. NFCx2 remote (with normalization) 2026/02/05 06:02:33 ERROR : 測試_русский___ě_áñ測試_русский___ě_áñ: file not in drime root 'rclone-test-vifumic2yape' 2026/02/05 06:02:33 NOTICE: drime root 'rclone-test-vifumic2yape': 1 files missing 2026/02/05 06:02:33 NOTICE: drime root 'rclone-test-vifumic2yape': 0 differences found 2026/02/05 06:02:33 NOTICE: drime root 'rclone-test-vifumic2yape': 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-vifumic2yape' Test: TestApplyTransforms Messages: unexpected error for both checkfile vs. NFCx2 remote (with normalization) 測試_Русский___ě_áñ測試_Русский___ě_áñ 測試_Русский___ě_áñ測試_Русский___ě_áñ 2026/02/05 06:02:33 DEBUG : drime root 'rclone-test-vifumic2yape': Purge remote 2026/02/05 06:02:33 DEBUG : drime root 'rclone-test-kujipos4ponu': Purge remote 2026/02/05 06:02:34 DEBUG : drime root 'rclone-test-risisaj8pihu': Purge remote 2026/02/05 06:02:34 DEBUG : drime root 'rclone-test-lilidar1ceya': Purge remote 2026/02/05 06:02:34 DEBUG : drime root 'rclone-test-xagoqit0givu': Purge remote 2026/02/05 06:02:35 DEBUG : drime root 'rclone-test-bukudud1nofi': Purge remote 2026/02/05 06:02:35 DEBUG : drime root 'rclone-test-xacedem6duru': Purge remote 2026/02/05 06:02:35 DEBUG : drime root 'rclone-test-hacokiy0woje': Purge remote 2026/02/05 06:02:36 DEBUG : drime root 'rclone-test-xedepoy0tute': Purge remote 2026/02/05 06:02:36 DEBUG : drime root 'rclone-test-hejawis4yawe': Purge remote --- FAIL: TestApplyTransforms (75.47s) === RUN TestCopyFile run.go:185: Remote "drime root 'rclone-test-govener2vida'", Local "Local file system at /tmp/rclone819171941", Modify Window "876000h0m0s" 2026/02/05 06:02:37 DEBUG : file1: Need to transfer - File not found at Destination 2026/02/05 06:02:44 DEBUG : sub/file2: size = 14 OK 2026/02/05 06:02:44 INFO : file1: Copied (new) to: sub/file2 2026/02/05 06:02:44 DEBUG : sub/file2: size = 14 OK 2026/02/05 06:02:44 DEBUG : file1: Sizes identical 2026/02/05 06:02:44 DEBUG : file1: Unchanged skipping 2026/02/05 06:02:45 DEBUG : drime root 'rclone-test-govener2vida': 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 (23.42s) === RUN TestCopyFileCompareDest run.go:185: Remote "drime root 'rclone-test-govener2vida'", Local "Local file system at /tmp/rclone819171941", Modify Window "876000h0m0s" 2026/02/05 06:03:00 DEBUG : Creating backend with remote "TestDrime:rclone-test-govener2vida/dst" 2026/02/05 06:03:01 DEBUG : Creating backend with remote "TestDrime:rclone-test-govener2vida/CompareDest" 2026/02/05 06:03:02 DEBUG : one: Need to transfer - File not found at Destination 2026/02/05 06:03:05 DEBUG : one: size = 3 OK 2026/02/05 06:03:05 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/05 06:03:14 DEBUG : one: size = 5 (Local file system at /tmp/rclone819171941) 2026/02/05 06:03:14 DEBUG : one: size = 3 (drime root 'rclone-test-govener2vida/dst') 2026/02/05 06:03:14 DEBUG : one: Sizes differ 2026/02/05 06:03:17 DEBUG : one: Removing old object on successful upload 2026/02/05 06:03:17 DEBUG : one: size = 5 OK 2026/02/05 06:03:17 INFO : one: Copied (replaced existing) 2026/02/05 06:03: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:292: Sleeping for 6s just to make sure 2026/02/05 06:03:36 DEBUG : one: size = 5 (Local file system at /tmp/rclone819171941) 2026/02/05 06:03:36 DEBUG : one: size = 3 (drime root 'rclone-test-govener2vida/dst') 2026/02/05 06:03:36 DEBUG : one: Sizes differ 2026/02/05 06:03:36 DEBUG : one: size = 5 OK 2026/02/05 06:03:36 DEBUG : one: Sizes identical 2026/02/05 06:03:36 DEBUG : one: Destination found in --compare-dest, skipping 2026/02/05 06:03:40 DEBUG : two: Need to transfer - File not found at Destination 2026/02/05 06:03:41 DEBUG : two: size = 3 OK 2026/02/05 06:03:41 DEBUG : two: Sizes identical 2026/02/05 06:03:41 DEBUG : two: Destination found in --compare-dest, skipping 2026/02/05 06:03:42 DEBUG : two: Need to transfer - File not found at Destination 2026/02/05 06:03:42 DEBUG : two: size = 3 OK 2026/02/05 06:03:42 DEBUG : two: Sizes identical 2026/02/05 06:03:42 DEBUG : two: Destination found in --compare-dest, skipping 2026/02/05 06:03:44 DEBUG : two: Need to transfer - File not found at Destination 2026/02/05 06:03:44 DEBUG : two: size = 5 (Local file system at /tmp/rclone819171941) 2026/02/05 06:03:44 DEBUG : two: size = 3 (drime root 'rclone-test-govener2vida/CompareDest') 2026/02/05 06:03:44 DEBUG : two: Sizes differ 2026/02/05 06:03:49 DEBUG : two: size = 5 OK 2026/02/05 06:03:49 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 (69.99s) === RUN TestCopyFileCopyDest run.go:185: Remote "drime root 'rclone-test-govener2vida'", Local "Local file system at /tmp/rclone819171941", Modify Window "876000h0m0s" 2026/02/05 06:04:10 DEBUG : Creating backend with remote "TestDrime:rclone-test-govener2vida/dst" 2026/02/05 06:04:11 DEBUG : Creating backend with remote "TestDrime:rclone-test-govener2vida/CopyDest" 2026/02/05 06:04:12 DEBUG : one: Need to transfer - File not found at Destination 2026/02/05 06:04:17 DEBUG : one: size = 3 OK 2026/02/05 06:04:17 INFO : one: Copied (new) fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:292: Sleeping for 7s just to make sure 2026/02/05 06:04:32 DEBUG : one: size = 5 (Local file system at /tmp/rclone819171941) 2026/02/05 06:04:32 DEBUG : one: size = 3 (drime root 'rclone-test-govener2vida/dst') 2026/02/05 06:04:32 DEBUG : one: Sizes differ 2026/02/05 06:04:35 DEBUG : one: Removing old object on successful upload 2026/02/05 06:04:36 DEBUG : one: size = 5 OK 2026/02/05 06:04:36 INFO : one: Copied (replaced existing) 2026/02/05 06:04:45 DEBUG : dst/one: Removing old object on successful upload 2026/02/05 06:04:51 DEBUG : Creating backend with remote "TestDrime:rclone-test-govener2vida/BackupDir" 2026/02/05 06:04:52 DEBUG : one: size = 5 (Local file system at /tmp/rclone819171941) 2026/02/05 06:04:52 DEBUG : one: size = 3 (drime root 'rclone-test-govener2vida/dst') 2026/02/05 06:04:52 DEBUG : one: Sizes differ 2026/02/05 06:04:54 INFO : one: Moved (server-side) 2026/02/05 06:04:57 DEBUG : one: Removing old object on successful upload 2026/02/05 06:04:58 DEBUG : one: size = 5 OK 2026/02/05 06:04:58 INFO : one: Copied (new) fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:292: Sleeping for 7s just to make sure 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), CopyDest/two (3), 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:333 /usr/local/go/src/runtime/asm_amd64.s:1700 Error: Not equal: expected: 0 actual : 1 Test: TestCopyFileCopyDest Messages: 1 objects not found 2026/02/05 06:05:25 DEBUG : two: Need to transfer - File not found at Destination 2026/02/05 06:05:25 DEBUG : two: size = 3 OK 2026/02/05 06:05:25 DEBUG : two: Sizes identical 2026/02/05 06:05:26 DEBUG : two: size = 3 OK 2026/02/05 06:05:26 INFO : two: Copied (server-side copy) 2026/02/05 06:05:26 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) fstest.go:203: Not found "BackupDir/one" fstest.go:203: Not found "dst/two" fstest.go:206: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:206 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:310 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/copy_test.go:342 /usr/local/go/src/runtime/asm_amd64.s:1700 Error: Not equal: expected: 0 actual : 2 Test: TestCopyFileCopyDest Messages: 2 objects not found 2026/02/05 06:05:37 DEBUG : two: size = 3 OK 2026/02/05 06:05:37 DEBUG : two: Sizes identical 2026/02/05 06:05:37 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/05 06:05:59 DEBUG : three: Need to transfer - File not found at Destination 2026/02/05 06:05:59 DEBUG : three: size = 7 (Local file system at /tmp/rclone819171941) 2026/02/05 06:05:59 DEBUG : three: size = 5 (drime root 'rclone-test-govener2vida/CopyDest') 2026/02/05 06:05:59 DEBUG : three: Sizes differ 2026/02/05 06:05:59 DEBUG : three: Destination not found in --copy-dest 2026/02/05 06:06:02 DEBUG : three: size = 7 OK 2026/02/05 06:06:02 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/two (3) fstest.go:203: Not found "BackupDir/one" fstest.go:203: Not found "dst/three" fstest.go:206: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:206 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:310 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/copy_test.go:362 /usr/local/go/src/runtime/asm_amd64.s:1700 Error: Not equal: expected: 0 actual : 2 Test: TestCopyFileCopyDest Messages: 2 objects not found run.go:130: removing dir "dst" failed - try 1/3: directory not empty run.go:130: removing dir "dst" failed - try 2/3: directory not empty run.go:130: removing dir "dst" failed - try 3/3: directory not empty run.go:133: removing dir "dst" failed: directory not empty 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{"dst"} Diff: --- Expected +++ Actual @@ -1,2 +1,3 @@ -([]string) { +([]string) (len=1) { + (string) (len=3) "dst" } Test: TestCopyFileCopyDest Messages: directories --- FAIL: TestCopyFileCopyDest (139.41s) === RUN TestListDirSortedFn run.go:185: Remote "drime root 'rclone-test-govener2vida'", Local "Local file system at /tmp/rclone819171941", 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 listdirsorted_test.go:57: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/listdirsorted_test.go:57 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/listdirsorted_test.go:125 Error: "[a.txt dst sub dir zend.txt]" should have 3 item(s), but has 4 Test: TestListDirSortedFn 2026/02/05 06:07:13 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/05 06:07:13 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2026/02/05 06:07:13 DEBUG : pacer: Reducing sleep to 10ms 2026/02/05 06:07:14 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/05 06:07:14 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2026/02/05 06:07:14 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/05 06:07:14 DEBUG : pacer: Rate limited, increasing sleep to 40ms 2026/02/05 06:07:14 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/05 06:07:14 DEBUG : pacer: Rate limited, increasing sleep to 80ms 2026/02/05 06:07:14 DEBUG : pacer: Reducing sleep to 40ms 2026/02/05 06:07:14 DEBUG : pacer: Reducing sleep to 20ms 2026/02/05 06:07:15 DEBUG : pacer: Reducing sleep to 10ms 2026/02/05 06:07:15 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/05 06:07:15 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2026/02/05 06:07:15 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/05 06:07:15 DEBUG : pacer: Rate limited, increasing sleep to 40ms 2026/02/05 06:07:15 DEBUG : pacer: Reducing sleep to 20ms 2026/02/05 06:07:15 DEBUG : pacer: Reducing sleep to 10ms 2026/02/05 06:07:15 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/05 06:07:15 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2026/02/05 06:07:16 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/05 06:07:16 DEBUG : pacer: Rate limited, increasing sleep to 40ms 2026/02/05 06:07:16 DEBUG : pacer: Reducing sleep to 20ms 2026/02/05 06:07:16 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/05 06:07:16 DEBUG : pacer: Rate limited, increasing sleep to 40ms 2026/02/05 06:07:17 DEBUG : pacer: Reducing sleep to 20ms 2026/02/05 06:07:17 DEBUG : pacer: Reducing sleep to 10ms 2026/02/05 06:07:17 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/05 06:07:17 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2026/02/05 06:07:18 DEBUG : pacer: Reducing sleep to 10ms run.go:130: removing dir "sub dir/sub sub dir" failed - try 1/3: directory not empty run.go:130: removing dir "sub dir/sub sub dir" failed - try 2/3: directory not empty run.go:130: removing dir "sub dir/sub sub dir" failed - try 3/3: directory not empty run.go:133: removing dir "sub dir/sub sub dir" failed: directory not empty 2026/02/05 06:07:22 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/05 06:07:22 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2026/02/05 06:07:22 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/05 06:07:22 DEBUG : pacer: Rate limited, increasing sleep to 40ms 2026/02/05 06:07:22 DEBUG : pacer: Reducing sleep to 20ms run.go:130: removing dir "sub dir/ignore dir" failed - try 1/3: directory not empty 2026/02/05 06:07:23 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/05 06:07:23 DEBUG : pacer: Rate limited, increasing sleep to 40ms 2026/02/05 06:07:24 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/05 06:07:24 DEBUG : pacer: Rate limited, increasing sleep to 80ms 2026/02/05 06:07:24 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/05 06:07:24 DEBUG : pacer: Rate limited, increasing sleep to 160ms 2026/02/05 06:07:24 DEBUG : pacer: Reducing sleep to 80ms run.go:130: removing dir "sub dir/ignore dir" failed - try 2/3: directory not empty 2026/02/05 06:07:25 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/05 06:07:25 DEBUG : pacer: Rate limited, increasing sleep to 160ms 2026/02/05 06:07:26 DEBUG : pacer: Reducing sleep to 80ms 2026/02/05 06:07:26 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/05 06:07:26 DEBUG : pacer: Rate limited, increasing sleep to 160ms 2026/02/05 06:07:26 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/05 06:07:26 DEBUG : pacer: Rate limited, increasing sleep to 320ms 2026/02/05 06:07:26 DEBUG : pacer: Reducing sleep to 160ms 2026/02/05 06:07:27 DEBUG : pacer: Reducing sleep to 80ms run.go:130: removing dir "sub dir" failed - try 1/3: directory not empty 2026/02/05 06:07:28 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/05 06:07:28 DEBUG : pacer: Rate limited, increasing sleep to 160ms 2026/02/05 06:07:28 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/05 06:07:28 DEBUG : pacer: Rate limited, increasing sleep to 320ms 2026/02/05 06:07:28 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/05 06:07:28 DEBUG : pacer: Rate limited, increasing sleep to 640ms 2026/02/05 06:07:29 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/05 06:07:29 DEBUG : pacer: Rate limited, increasing sleep to 1.28s 2026/02/05 06:07:29 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/05 06:07:29 DEBUG : pacer: Rate limited, increasing sleep to 2.56s 2026/02/05 06:07:31 DEBUG : pacer: Reducing sleep to 1.28s run.go:130: removing dir "sub dir" failed - try 2/3: directory not empty 2026/02/05 06:07:33 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/05 06:07:33 DEBUG : pacer: Rate limited, increasing sleep to 2.56s 2026/02/05 06:07:34 DEBUG : pacer: Reducing sleep to 1.28s 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 2026/02/05 06:07:37 DEBUG : pacer: Reducing sleep to 640ms 2026/02/05 06:07:38 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/05 06:07:38 DEBUG : pacer: Rate limited, increasing sleep to 1.28s 2026/02/05 06:07:39 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/05 06:07:39 DEBUG : pacer: Rate limited, increasing sleep to 2.56s 2026/02/05 06:07:40 DEBUG : pacer: Reducing sleep to 1.28s 2026/02/05 06:07:43 DEBUG : pacer: Reducing sleep to 640ms 2026/02/05 06:07:44 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/05 06:07:44 DEBUG : pacer: Rate limited, increasing sleep to 1.28s 2026/02/05 06:07:45 DEBUG : pacer: Reducing sleep to 640ms 2026/02/05 06:07:46 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/05 06:07:47 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/05 06:07:47 DEBUG : pacer: Rate limited, increasing sleep to 640ms 2026/02/05 06:07:48 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/05 06:07:48 DEBUG : pacer: Rate limited, increasing sleep to 1.28s 2026/02/05 06:07:48 DEBUG : pacer: Reducing sleep to 640ms 2026/02/05 06:07:50 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/05 06:07:50 DEBUG : pacer: Rate limited, increasing sleep to 1.28s 2026/02/05 06:07:50 DEBUG : pacer: Reducing sleep to 640ms 2026/02/05 06:07:52 DEBUG : pacer: Reducing sleep to 320ms 2026/02/05 06:07:52 DEBUG : pacer: Reducing sleep to 160ms fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache 2026/02/05 06:07:54 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/05 06:07:54 DEBUG : pacer: Rate limited, increasing sleep to 320ms 2026/02/05 06:07:55 DEBUG : pacer: Reducing sleep to 160ms 2026/02/05 06:07:55 DEBUG : pacer: Reducing sleep to 80ms 2026/02/05 06:07:55 DEBUG : pacer: Reducing sleep to 40ms 2026/02/05 06:07:56 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/05 06:07:56 DEBUG : pacer: Rate limited, increasing sleep to 80ms 2026/02/05 06:07:56 DEBUG : pacer: Reducing sleep to 40ms fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:327: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:327 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393 /usr/local/go/src/testing/testing.go:1211 /usr/local/go/src/testing/testing.go:1445 /usr/local/go/src/testing/testing.go:1786 /usr/local/go/src/runtime/panic.go:636 /usr/local/go/src/testing/testing.go:1041 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/listdirsorted_test.go:57 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/listdirsorted_test.go:125 Error: Not equal: expected: []string{} actual : []string{"sub dir", "sub dir/sub sub dir"} Diff: --- Expected +++ Actual @@ -1,2 +1,4 @@ -([]string) { +([]string) (len=2) { + (string) (len=7) "sub dir", + (string) (len=19) "sub dir/sub sub dir" } Test: TestListDirSortedFn Messages: directories --- FAIL: TestListDirSortedFn (90.72s) === RUN TestListJSON run.go:185: Remote "drime root 'rclone-test-govener2vida'", Local "Local file system at /tmp/rclone819171941", Modify Window "876000h0m0s" 2026/02/05 06:08:00 DEBUG : pacer: Reducing sleep to 20ms 2026/02/05 06:08:00 DEBUG : pacer: Reducing sleep to 10ms 2026/02/05 06:08:05 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/05 06:08:05 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2026/02/05 06:08:05 DEBUG : pacer: Reducing sleep to 10ms === 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 2026/02/05 06:08:15 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/05 06:08:15 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2026/02/05 06:08:15 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/05 06:08:15 DEBUG : pacer: Rate limited, increasing sleep to 40ms 2026/02/05 06:08:15 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/05 06:08:15 DEBUG : pacer: Rate limited, increasing sleep to 80ms 2026/02/05 06:08:15 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/05 06:08:15 DEBUG : pacer: Rate limited, increasing sleep to 160ms 2026/02/05 06:08:15 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/05 06:08:15 DEBUG : pacer: Rate limited, increasing sleep to 320ms 2026/02/05 06:08:16 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/05 06:08:16 DEBUG : pacer: Rate limited, increasing sleep to 640ms 2026/02/05 06:08:16 DEBUG : pacer: Reducing sleep to 320ms 2026/02/05 06:08:16 DEBUG : pacer: Reducing sleep to 160ms 2026/02/05 06:08:16 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/05 06:08:16 DEBUG : pacer: Rate limited, increasing sleep to 320ms 2026/02/05 06:08:17 DEBUG : pacer: Reducing sleep to 160ms 2026/02/05 06:08:17 DEBUG : pacer: Reducing sleep to 80ms 2026/02/05 06:08:17 DEBUG : pacer: Reducing sleep to 40ms 2026/02/05 06:08:18 DEBUG : pacer: Reducing sleep to 20ms 2026/02/05 06:08:18 DEBUG : pacer: Reducing sleep to 10ms === NAME TestListJSON run.go:130: removing dir "sub dir" failed - try 1/3: directory not empty 2026/02/05 06:08:19 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/05 06:08:19 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2026/02/05 06:08:20 DEBUG : pacer: Reducing sleep to 10ms 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 fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:327: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:327 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393 /usr/local/go/src/testing/testing.go:1211 /usr/local/go/src/testing/testing.go:1445 /usr/local/go/src/testing/testing.go:1786 Error: Not equal: expected: []string{} actual : []string{"sub dir"} Diff: --- Expected +++ Actual @@ -1,2 +1,3 @@ -([]string) { +([]string) (len=1) { + (string) (len=7) "sub dir" } Test: TestListJSON Messages: directories --- FAIL: TestListJSON (34.00s) --- FAIL: TestListJSON/Default (0.22s) --- FAIL: TestListJSON/DirsOnly (0.31s) --- FAIL: TestListJSON/Recurse (1.15s) --- FAIL: TestListJSON/Metadata (0.21s) === RUN TestStatJSON run.go:185: Remote "drime root 'rclone-test-govener2vida'", Local "Local file system at /tmp/rclone819171941", Modify Window "876000h0m0s" fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/lsjson_test.go:263 Error: Should be true Test: TestStatJSON Messages: listing wrong, want file1 (5), sub/file2 (9) got fstest.go:203: Not found "file1" fstest.go:203: Not found "sub/file2" fstest.go:206: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:206 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:310 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/lsjson_test.go:263 /usr/local/go/src/runtime/asm_amd64.s:1700 Error: Not equal: expected: 0 actual : 2 Test: TestStatJSON Messages: 2 objects not found === RUN TestStatJSON/Root === RUN TestStatJSON/RootFilesOnly === RUN TestStatJSON/RootDirsOnly === RUN TestStatJSON/Dir === RUN TestStatJSON/DirWithTrailingSlash === RUN TestStatJSON/File === RUN TestStatJSON/NotFound === RUN TestStatJSON/DirFilesOnly === RUN TestStatJSON/FileFilesOnly === RUN TestStatJSON/NotFoundFilesOnly === RUN TestStatJSON/DirDirsOnly === RUN TestStatJSON/FileDirsOnly === RUN TestStatJSON/NotFoundDirsOnly === RUN TestStatJSON/RootNotFound 2026/02/05 06:08:52 DEBUG : Creating backend with remote "TestDrime:rclone-test-govener2vida/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 (34.11s) --- PASS: TestStatJSON/Root (0.64s) --- PASS: TestStatJSON/RootFilesOnly (0.00s) --- PASS: TestStatJSON/RootDirsOnly (0.25s) --- PASS: TestStatJSON/Dir (0.47s) --- PASS: TestStatJSON/DirWithTrailingSlash (0.16s) --- PASS: TestStatJSON/File (0.28s) --- PASS: TestStatJSON/NotFound (0.33s) --- PASS: TestStatJSON/DirFilesOnly (0.20s) --- PASS: TestStatJSON/FileFilesOnly (0.35s) --- PASS: TestStatJSON/NotFoundFilesOnly (0.23s) --- PASS: TestStatJSON/DirDirsOnly (0.20s) --- PASS: TestStatJSON/FileDirsOnly (0.14s) --- PASS: TestStatJSON/NotFoundDirsOnly (0.35s) --- PASS: TestStatJSON/RootNotFound (1.24s) === RUN TestHashSums run.go:185: Remote "drime root 'rclone-test-govener2vida'", Local "Local file system at /tmp/rclone819171941", Modify Window "876000h0m0s" fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:186 Error: Should be true Test: TestHashSums Messages: listing wrong, want empty space (1), potato2 (60) got 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" 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: TestHashSums Messages: directories --- FAIL: TestHashSums (27.79s) === RUN TestMaxDelete run.go:185: Remote "drime root 'rclone-test-govener2vida'", Local "Local file system at /tmp/rclone819171941", Modify Window "876000h0m0s" fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:447 Error: Should be true Test: TestMaxDelete Messages: listing wrong, want large (100), medium (60), small (10) got medium (60), small (10) fstest.go:203: Not found "large" fstest.go:206: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:206 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:310 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:447 /usr/local/go/src/runtime/asm_amd64.s:1700 Error: Not equal: expected: 0 actual : 1 Test: TestMaxDelete Messages: 1 objects not found 2026/02/05 06:09:58 DEBUG : Waiting for deletions to finish 2026/02/05 06:09:58 ERROR : small: Got fatal error on delete: --max-delete threshold reached 2026/02/05 06:09:59 INFO : large: Deleted 2026/02/05 06:09:59 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/05 06:09:59 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/05 06:09:59 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2026/02/05 06:09:59 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/05 06:09:59 DEBUG : pacer: Rate limited, increasing sleep to 40ms 2026/02/05 06:10:00 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/05 06:10:00 DEBUG : pacer: Rate limited, increasing sleep to 80ms 2026/02/05 06:10:00 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/05 06:10:00 DEBUG : pacer: Rate limited, increasing sleep to 160ms 2026/02/05 06:10:00 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/05 06:10:00 DEBUG : pacer: Rate limited, increasing sleep to 320ms 2026/02/05 06:10:00 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/05 06:10:00 DEBUG : pacer: Rate limited, increasing sleep to 640ms 2026/02/05 06:10:01 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/05 06:10:01 DEBUG : pacer: Rate limited, increasing sleep to 1.28s 2026/02/05 06:10:01 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/05 06:10:01 DEBUG : pacer: Rate limited, increasing sleep to 2.56s 2026/02/05 06:10:03 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/05 06:10:03 DEBUG : pacer: Rate limited, increasing sleep to 5.12s 2026/02/05 06:10:05 DEBUG : pacer: low level retry 10/10 (error Error "Server Error") 2026/02/05 06:10:05 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/05 06:10:10 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/05 06:10:10 DEBUG : pacer: Rate limited, increasing sleep to 20s 2026/02/05 06:10:20 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/05 06:10:40 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/05 06:11:00 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/05 06:11:21 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/05 06:11:40 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/05 06:12:00 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/05 06:12:20 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/05 06:12:40 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/05 06:13:01 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/05 06:13:21 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/05 06:13:41 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/05 06:14:00 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/05 06:14:21 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/05 06:14:40 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/05 06:15:00 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/05 06:15:20 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/05 06:15:40 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/05 06:16:00 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/05 06:16:21 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/05 06:16:41 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/05 06:17:05 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/05 06:17:33 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/05 06:17:52 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/05 06:18:13 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/05 06:18:36 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/05 06:18:55 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/05 06:19:16 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/05 06:19:36 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/05 06:19:53 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/05 06:20:13 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/05 06:20:34 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/05 06:20:59 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/05 06:21:41 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value 2026/02/05 06:21:41 DEBUG : pacer: Reducing sleep to 10s run.go:130: removing file "medium" failed - try 2/3: failed to delete item: Error "520 (520): \n\n\n\n \n\n\ndrime.cloud | 520: Web server is returning an unknown error\n\n\n\n\n\n\n\n\n
\n
\n
\n

\n Web server is returning an unknown error\n Error code 520\n

\n
\n Visit cloudflare.com for more information.\n
\n
2026-02-05 06:21:41 UTC
\n
\n
\n
\n
\n
\n
\n \n \n \n \n
\n You\n

\n \n Browser\n \n

\n \n Working\n \n
\n
\n
\n \n \n \n \n
\n Frankfurt\n

\n \n Cloudflare\n \n

\n \n Working\n \n
\n
\n
\n \n \n \n \n
\n app.drime.cloud\n

\n \n Host\n \n

\n \n Error\n \n
\n
\n
\n
\n\n
\n
\n
\n

What happened?

\n

There is an unknown connection issue between Cloudflare and the origin web server. As a result, the web page can not be displayed.

\n
\n
\n

What can I do?

\n \n

If you are a visitor of this website:

\n

Please try again in a few minutes.

\n\n

If you are the owner of this website:

\n

There is an issue between Cloudflare's cache and your origin web server. Cloudflare monitors for these errors and automatically investigates the cause. To help support the investigation, you can pull the corresponding error log from your web server and submit it our support team. Please include the Ray ID (which is at the bottom of this error page). Additional troubleshooting resources.

\n
\n
\n
\n\n
\n

\n Cloudflare Ray ID: 9c904e1fd87c2baf\n •\n \n Performance & security by Cloudflare\n \n

\n \n
\n\n
\n
\n\n" 2026/02/05 06:22:06 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/05 06:22:06 DEBUG : pacer: Rate limited, increasing sleep to 20s 2026/02/05 06:22:28 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/05 06:22:42 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/05 06:22:59 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/05 06:23:19 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/05 06:23:38 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/05 06:23:59 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/05 06:24:19 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/05 06:24:39 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/05 06:25:00 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/05 06:25:21 DEBUG : pacer: Reducing sleep to 10s 2026/02/05 06:25:40 DEBUG : pacer: Reducing sleep to 5s 2026/02/05 06:25:52 DEBUG : pacer: Reducing sleep to 2.5s 2026/02/05 06:26:04 DEBUG : pacer: Reducing sleep to 1.25s --- FAIL: TestMaxDelete (988.36s) === RUN TestMaxDeleteSize run.go:185: Remote "drime root 'rclone-test-govener2vida'", Local "Local file system at /tmp/rclone819171941", Modify Window "876000h0m0s" 2026/02/05 06:26:16 DEBUG : pacer: Reducing sleep to 625ms 2026/02/05 06:26:30 DEBUG : pacer: Reducing sleep to 312.5ms 2026/02/05 06:26:43 DEBUG : pacer: Reducing sleep to 156.25ms 2026/02/05 06:26:55 DEBUG : pacer: low level retry 1/1 (error Error "Server Error") 2026/02/05 06:26:55 DEBUG : pacer: Rate limited, increasing sleep to 312.5ms run.go:286: Retry Put of "medium" to drime root 'rclone-test-govener2vida': 1/10 (failed to upload file: Error "Server Error") 2026/02/05 06:27:09 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/05 06:27:09 DEBUG : pacer: Rate limited, increasing sleep to 625ms 2026/02/05 06:27:21 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/05 06:27:21 DEBUG : pacer: Rate limited, increasing sleep to 1.25s 2026/02/05 06:27:33 DEBUG : pacer: Reducing sleep to 625ms 2026/02/05 06:27:45 DEBUG : pacer: Reducing sleep to 312.5ms 2026/02/05 06:27:58 DEBUG : pacer: Reducing sleep to 156.25ms 2026/02/05 06:28:12 DEBUG : pacer: low level retry 1/1 (error Error "Server Error") 2026/02/05 06:28:12 DEBUG : pacer: Rate limited, increasing sleep to 312.5ms run.go:286: Retry Put of "large" to drime root 'rclone-test-govener2vida': 1/10 (failed to upload file: Error "Server Error") 2026/02/05 06:28:28 DEBUG : pacer: Reducing sleep to 156.25ms 2026/02/05 06:28:44 DEBUG : pacer: Reducing sleep to 78.125ms 2026/02/05 06:28:59 DEBUG : pacer: Reducing sleep to 39.0625ms 2026/02/05 06:28:59 DEBUG : Waiting for deletions to finish 2026/02/05 06:29:15 DEBUG : pacer: Reducing sleep to 19.53125ms 2026/02/05 06:29:15 ERROR : small: Got fatal error on delete: --max-delete-size threshold reached 2026/02/05 06:29:31 DEBUG : pacer: Reducing sleep to 10ms 2026/02/05 06:29:31 INFO : large: Deleted 2026/02/05 06:29:31 INFO : medium: Deleted 2026/02/05 06:30:19 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/05 06:30:19 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2026/02/05 06:30:34 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/05 06:30:34 DEBUG : pacer: Rate limited, increasing sleep to 40ms 2026/02/05 06:30:48 DEBUG : pacer: Reducing sleep to 20ms 2026/02/05 06:31:03 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/05 06:31:03 DEBUG : pacer: Rate limited, increasing sleep to 40ms 2026/02/05 06:31:18 DEBUG : pacer: Reducing sleep to 20ms 2026/02/05 06:31:34 DEBUG : pacer: Reducing sleep to 10ms 2026/02/05 06:31:49 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/05 06:31:49 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2026/02/05 06:31:57 DEBUG : pacer: Reducing sleep to 10ms --- PASS: TestMaxDeleteSize (353.30s) === RUN TestReadFile run.go:185: Remote "drime root 'rclone-test-govener2vida'", Local "Local file system at /tmp/rclone819171941", Modify Window "876000h0m0s" fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:292: Sleeping for 7s just to make sure fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:292: Sleeping for 7s just to make sure --- PASS: TestReadFile (32.20s) === RUN TestCat run.go:185: Remote "drime root 'rclone-test-govener2vida'", Local "Local file system at /tmp/rclone819171941", Modify Window "876000h0m0s" 2026/02/05 06:32:35 DEBUG : pacer: low level retry 1/1 (error Error "Server Error") 2026/02/05 06:32:35 DEBUG : pacer: Rate limited, increasing sleep to 20ms run.go:286: Retry Put of "file2" to drime root 'rclone-test-govener2vida': 1/10 (failed to upload file: Error "Server Error") 2026/02/05 06:32:37 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 (33.19s) FAIL 2026/02/05 06:33:03 DEBUG : drime root 'rclone-test-govener2vida': Purge remote "./operations.test -test.v -test.timeout 2h0m0s -remote TestDrime: -verbose -test.run '^(TestApplyTransforms|TestCat|TestCheckDownload|TestCheckSum|TestCheckSumDownload|TestCopyFile|TestCopyFileCompareDest|TestCopyFileCopyDest|TestHashSums|TestListDirSortedFn|TestMaxDelete|TestMaxDeleteSize|TestParseSumFile|TestReadFile|TestStatJSON)$|^TestCheckSizeOnly$/^(1|2)$|^TestListJSON$/^(Default|DirsOnly|Metadata|Recurse)$|^TestMultithreadCopy$/^(upload=false,size=10485759,streams=2|upload=true,size=10485759,streams=2|upload=true,size=10485760,streams=2|upload=true,size=10485761,streams=2)$'" - Finished ERROR in 44m31.963870363s (try 4/5): exit status 1: Failed [TestMultithreadCopy/upload=true,size=10485760,streams=2 TestMultithreadCopy/upload=true,size=10485761,streams=2 TestCheckDownload TestCheckSizeOnly TestParseSumFile TestCheckSum TestCheckSumDownload TestApplyTransforms TestCopyFile TestCopyFileCompareDest TestCopyFileCopyDest TestListDirSortedFn TestListJSON/Default TestListJSON/DirsOnly TestListJSON/Recurse TestListJSON/Metadata TestStatJSON TestHashSums TestMaxDelete]