"./operations.test -test.v -test.timeout 2h0m0s -remote TestDrime: -verbose -test.run '^(TestApplyTransforms|TestCheckDownload|TestCheckSizeOnly|TestCheckSumDownload|TestCopyFile|TestCopyFileCompareDest|TestCopyFileCopyDest|TestCopyURL|TestDirMove|TestHashSums|TestListDirSorted|TestListDirSortedFn|TestLsLong|TestLsd|TestMaxDelete|TestMaxDeleteSize|TestMoveFile|TestMoveFileBackupDir|TestRmdirsLeaveRoot|TestRmdirsNoLeaveRoot|TestRmdirsWithFilter|TestStatJSON)$|^TestListJSON$/^(Default|DirsOnly|Metadata|Recurse)$|^TestMultithreadCopy$/^(upload=true,size=10485759,streams=2|upload=true,size=10485760,streams=2|upload=true,size=10485761,streams=2)$|^TestRcat$/^(withChecksum=false,ignoreChecksum=false|withChecksum=true,ignoreChecksum=false|withChecksum=true,ignoreChecksum=true)$'" - Starting (try 2/5) 2026/02/06 04:52:27 DEBUG : Creating backend with remote "TestDrime:rclone-test-wamayod7cole" 2026/02/06 04:52:27 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/02/06 04:52:28 DEBUG : Creating backend with remote "/tmp/rclone637323739" === RUN TestMultithreadCopy run.go:185: Remote "drime root 'rclone-test-wamayod7cole'", Local "Local file system at /tmp/rclone637323739", Modify Window "876000h0m0s" === RUN TestMultithreadCopy/upload=true,size=10485759,streams=2 2026/02/06 04:52:30 DEBUG : test-multithread-copy-true-10485759-2: multi-thread copy: disabling buffering because source is local disk 2026/02/06 04:52:31 DEBUG : test-multithread-copy-true-10485759-2: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 2 2026/02/06 04:52:31 DEBUG : test-multithread-copy-true-10485759-2: multi-thread copy: number of streams 4 was bigger than number of chunks 2 2026/02/06 04:52:31 DEBUG : test-multithread-copy-true-10485759-2: Starting multi-thread copy with 2 chunks of size 5Mi with 2 parallel streams 2026/02/06 04:52:31 DEBUG : test-multithread-copy-true-10485759-2: multi-thread copy: chunk 2/2 (5242880-10485759) size 5.000Mi starting 2026/02/06 04:52:31 DEBUG : test-multithread-copy-true-10485759-2: multi-thread copy: chunk 1/2 (0-5242880) size 5Mi starting 2026/02/06 04:52:32 DEBUG : test-multithread-copy-true-10485759-2: multi-thread copy: chunk 2/2 (5242880-10485759) size 5.000Mi finished 2026/02/06 04:52:32 DEBUG : test-multithread-copy-true-10485759-2: multi-thread copy: chunk 1/2 (0-5242880) size 5Mi finished multithread_test.go:218: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/multithread_test.go:218 Error: Received unexpected error: multi-thread copy: failed to find object after copy: object not found Test: TestMultithreadCopy/upload=true,size=10485759,streams=2 === RUN TestMultithreadCopy/upload=true,size=10485760,streams=2 fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 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:339 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/multithread_test.go:187 Error: Should be true Test: TestMultithreadCopy/upload=true,size=10485760,streams=2 Messages: listing wrong, want test-multithread-copy-true-10485760-2 (10485760) got test-multithread-copy-true-10485759-2 (10485759), 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:339 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/multithread_test.go:187 /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/06 04:52:44 DEBUG : test-multithread-copy-true-10485760-2: multi-thread copy: disabling buffering because source is local disk 2026/02/06 04:52:44 DEBUG : test-multithread-copy-true-10485760-2: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 2 2026/02/06 04:52:44 DEBUG : test-multithread-copy-true-10485760-2: multi-thread copy: number of streams 4 was bigger than number of chunks 2 2026/02/06 04:52:44 DEBUG : test-multithread-copy-true-10485760-2: Starting multi-thread copy with 2 chunks of size 5Mi with 2 parallel streams 2026/02/06 04:52:44 DEBUG : test-multithread-copy-true-10485760-2: multi-thread copy: chunk 2/2 (5242880-10485760) size 5Mi starting 2026/02/06 04:52:44 DEBUG : test-multithread-copy-true-10485760-2: multi-thread copy: chunk 1/2 (0-5242880) size 5Mi starting 2026/02/06 04:52:45 DEBUG : test-multithread-copy-true-10485760-2: multi-thread copy: chunk 2/2 (5242880-10485760) size 5Mi finished 2026/02/06 04:52:46 DEBUG : test-multithread-copy-true-10485760-2: multi-thread copy: chunk 1/2 (0-5242880) size 5Mi finished 2026/02/06 04:52:48 DEBUG : test-multithread-copy-true-10485760-2: Finished multi-thread copy with 2 parts of size 5Mi fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/multithread_test.go:222 Error: Should be true Test: TestMultithreadCopy/upload=true,size=10485760,streams=2 Messages: listing wrong, want test-multithread-copy-true-10485760-2 (10485760) got test-multithread-copy-true-10485759-2 (10485759), 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/fs/operations/multithread_test.go:222 Error: Should be true Test: TestMultithreadCopy/upload=true,size=10485760,streams=2 Messages: Unexpected file "test-multithread-copy-true-10485759-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/fs/operations/multithread_test.go:223 Error: Should be true Test: TestMultithreadCopy/upload=true,size=10485760,streams=2 Messages: listing wrong, want test-multithread-copy-true-10485760-2 (10485760) got test-multithread-copy-true-10485759-2 (10485759), 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/fs/operations/multithread_test.go:223 Error: Should be true Test: TestMultithreadCopy/upload=true,size=10485760,streams=2 Messages: Unexpected file "test-multithread-copy-true-10485759-2" === 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-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=10485761,streams=2 Messages: Unexpected file "test-multithread-copy-true-10485759-2" fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 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:339 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/multithread_test.go:187 Error: Should be true Test: TestMultithreadCopy/upload=true,size=10485761,streams=2 Messages: listing wrong, want test-multithread-copy-true-10485761-2 (10485761) got test-multithread-copy-true-10485759-2 (10485759), test-multithread-copy-true-10485761-2 (10485761) fstest.go:191: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:339 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/multithread_test.go:187 /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-10485759-2" 2026/02/06 04:53:22 DEBUG : test-multithread-copy-true-10485761-2: multi-thread copy: disabling buffering because source is local disk 2026/02/06 04:53:23 DEBUG : test-multithread-copy-true-10485761-2: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 2 2026/02/06 04:53:23 DEBUG : test-multithread-copy-true-10485761-2: multi-thread copy: number of streams 4 was bigger than number of chunks 3 2026/02/06 04:53:23 DEBUG : test-multithread-copy-true-10485761-2: Starting multi-thread copy with 3 chunks of size 5Mi with 3 parallel streams 2026/02/06 04:53:23 DEBUG : test-multithread-copy-true-10485761-2: multi-thread copy: chunk 3/3 (10485760-10485761) size 1 starting 2026/02/06 04:53:23 DEBUG : test-multithread-copy-true-10485761-2: multi-thread copy: chunk 1/3 (0-5242880) size 5Mi starting 2026/02/06 04:53:23 DEBUG : test-multithread-copy-true-10485761-2: multi-thread copy: chunk 2/3 (5242880-10485760) size 5Mi starting 2026/02/06 04:53:23 DEBUG : test-multithread-copy-true-10485761-2: multi-thread copy: chunk 3/3 (10485760-10485761) size 1 finished 2026/02/06 04:53:24 DEBUG : test-multithread-copy-true-10485761-2: multi-thread copy: chunk 2/3 (5242880-10485760) size 5Mi finished 2026/02/06 04:53:24 DEBUG : test-multithread-copy-true-10485761-2: multi-thread copy: chunk 1/3 (0-5242880) size 5Mi finished 2026/02/06 04:53:35 DEBUG : test-multithread-copy-true-10485761-2: Finished multi-thread copy with 3 parts of size 5Mi fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/multithread_test.go:222 Error: Should be true Test: TestMultithreadCopy/upload=true,size=10485761,streams=2 Messages: listing wrong, want test-multithread-copy-true-10485761-2 (10485761) got test-multithread-copy-true-10485759-2 (10485759), test-multithread-copy-true-10485761-2 (10485761) fstest.go:191: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/multithread_test.go:222 Error: Should be true Test: TestMultithreadCopy/upload=true,size=10485761,streams=2 Messages: Unexpected file "test-multithread-copy-true-10485759-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/fs/operations/multithread_test.go:223 Error: Should be true Test: TestMultithreadCopy/upload=true,size=10485761,streams=2 Messages: listing wrong, want test-multithread-copy-true-10485761-2 (10485761) got test-multithread-copy-true-10485759-2 (10485759), test-multithread-copy-true-10485761-2 (10485761) fstest.go:191: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/multithread_test.go:223 Error: Should be true Test: TestMultithreadCopy/upload=true,size=10485761,streams=2 Messages: Unexpected file "test-multithread-copy-true-10485759-2" 2026/02/06 04:53:52 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/06 04:53:52 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2026/02/06 04:53:53 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/06 04:53:53 DEBUG : pacer: Rate limited, increasing sleep to 40ms 2026/02/06 04:53:53 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/06 04:53:53 DEBUG : pacer: Rate limited, increasing sleep to 80ms 2026/02/06 04:53:53 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/06 04:53:53 DEBUG : pacer: Rate limited, increasing sleep to 160ms 2026/02/06 04:53:53 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/06 04:53:53 DEBUG : pacer: Rate limited, increasing sleep to 320ms 2026/02/06 04:53:53 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/06 04:53:53 DEBUG : pacer: Rate limited, increasing sleep to 640ms 2026/02/06 04:53:54 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/06 04:53:54 DEBUG : pacer: Rate limited, increasing sleep to 1.28s 2026/02/06 04:53:54 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/06 04:53:54 DEBUG : pacer: Rate limited, increasing sleep to 2.56s 2026/02/06 04:53:56 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/06 04:53:56 DEBUG : pacer: Rate limited, increasing sleep to 5.12s 2026/02/06 04:53:58 DEBUG : pacer: low level retry 10/10 (error Error "Server Error") 2026/02/06 04:53:58 DEBUG : pacer: Rate limited, increasing sleep to 10.24s === NAME TestMultithreadCopy run.go:130: removing file "test-multithread-copy-true-10485761-2" failed - try 1/3: failed to delete item: Error "Server Error" 2026/02/06 04:54:04 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/06 04:54:04 DEBUG : pacer: Rate limited, increasing sleep to 20s 2026/02/06 04:54:14 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/06 04:54:34 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/06 04:54:54 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/06 04:55:14 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/06 04:55:34 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/06 04:55:54 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/06 04:56:14 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/06 04:56:34 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/06 04:56:54 DEBUG : pacer: low level retry 10/10 (error Error "Server Error") run.go:130: removing file "test-multithread-copy-true-10485761-2" failed - try 2/3: failed to delete item: Error "Server Error" 2026/02/06 04:57:14 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/06 04:57:34 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/06 04:57:54 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/06 04:58:14 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/06 04:58:34 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/06 04:58:56 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/06 04:59:16 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/06 04:59:36 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/06 04:59:56 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/06 05:00:15 DEBUG : pacer: low level retry 10/10 (error Error "Server Error") run.go:130: removing file "test-multithread-copy-true-10485761-2" failed - try 3/3: failed to delete item: Error "Server Error" run.go:133: removing file "test-multithread-copy-true-10485761-2" failed: failed to delete item: Error "Server Error" 2026/02/06 05:00:36 DEBUG : pacer: Reducing sleep to 10s --- FAIL: TestMultithreadCopy (487.86s) --- FAIL: TestMultithreadCopy/upload=true,size=10485759,streams=2 (6.31s) --- FAIL: TestMultithreadCopy/upload=true,size=10485760,streams=2 (29.27s) --- FAIL: TestMultithreadCopy/upload=true,size=10485761,streams=2 (46.41s) === RUN TestCheckDownload run.go:185: Remote "drime root 'rclone-test-wamayod7cole'", Local "Local file system at /tmp/rclone637323739", Modify Window "876000h0m0s" 2026/02/06 05:00:56 DEBUG : pacer: Reducing sleep to 5s 2026/02/06 05:01:16 DEBUG : pacer: Reducing sleep to 2.5s 2026/02/06 05:01:22 DEBUG : pacer: Reducing sleep to 1.25s === RUN TestCheckDownload/1 === RUN TestCheckDownload/2 2026/02/06 05:01:40 DEBUG : pacer: Reducing sleep to 39.0625ms 2026/02/06 05:01:46 DEBUG : pacer: Reducing sleep to 19.53125ms 2026/02/06 05:01:50 DEBUG : pacer: Reducing sleep to 10ms === RUN TestCheckDownload/3 === RUN TestCheckDownload/4 === RUN TestCheckDownload/5 === NAME TestCheckDownload fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:292: Sleeping for 6s just to make sure === RUN TestCheckDownload/6 === RUN TestCheckDownload/7 --- PASS: TestCheckDownload (155.34s) --- PASS: TestCheckDownload/1 (7.73s) --- PASS: TestCheckDownload/2 (7.12s) --- PASS: TestCheckDownload/3 (4.42s) --- PASS: TestCheckDownload/4 (6.12s) --- PASS: TestCheckDownload/5 (4.94s) --- PASS: TestCheckDownload/6 (3.53s) --- PASS: TestCheckDownload/7 (4.32s) === RUN TestCheckSizeOnly run.go:185: Remote "drime root 'rclone-test-wamayod7cole'", Local "Local file system at /tmp/rclone637323739", Modify Window "876000h0m0s" === RUN TestCheckSizeOnly/1 === RUN TestCheckSizeOnly/2 === RUN TestCheckSizeOnly/3 === NAME TestCheckSizeOnly fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:292: Sleeping for 6s just to make sure === RUN TestCheckSizeOnly/4 === RUN TestCheckSizeOnly/5 === RUN TestCheckSizeOnly/6 === RUN TestCheckSizeOnly/7 --- PASS: TestCheckSizeOnly (85.82s) --- PASS: TestCheckSizeOnly/1 (1.52s) --- PASS: TestCheckSizeOnly/2 (1.40s) --- PASS: TestCheckSizeOnly/3 (1.55s) --- PASS: TestCheckSizeOnly/4 (2.07s) --- PASS: TestCheckSizeOnly/5 (2.13s) --- PASS: TestCheckSizeOnly/6 (1.18s) --- PASS: TestCheckSizeOnly/7 (1.60s) === RUN TestCheckSumDownload run.go:185: Remote "drime root 'rclone-test-wamayod7cole'", Local "Local file system at /tmp/rclone637323739", Modify Window "876000h0m0s" 2026/02/06 05:04:37 DEBUG : Creating backend with remote "TestDrime:rclone-test-wamayod7cole/data" 2026/02/06 05:04:37 DEBUG : Config file has changed externally - reloading fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:292: Sleeping for 6s just to make sure === RUN TestCheckSumDownload/subtest1 2026/02/06 05:05:43 DEBUG : test.sum: Removing old object on successful upload === RUN TestCheckSumDownload/subtest2 2026/02/06 05:06:11 DEBUG : test.sum: Removing old object on successful upload === RUN TestCheckSumDownload/subtest3 2026/02/06 05:06:28 DEBUG : test.sum: Removing old object on successful upload === RUN TestCheckSumDownload/subtest4 2026/02/06 05:06:45 DEBUG : test.sum: Removing old object on successful upload === RUN TestCheckSumDownload/subtest5 2026/02/06 05:07:06 DEBUG : test.sum: Removing old object on successful upload === RUN TestCheckSumDownload/subtest6 2026/02/06 05:07:38 DEBUG : data/banana: Removing old object on successful upload 2026/02/06 05:07:46 DEBUG : data/potato: Removing old object on successful upload 2026/02/06 05:07:54 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:292: Sleeping for 6s just to make sure === RUN TestCheckSumDownload/subtest7 --- PASS: TestCheckSumDownload (235.77s) --- PASS: TestCheckSumDownload/subtest1 (7.98s) --- PASS: TestCheckSumDownload/subtest2 (9.29s) --- PASS: TestCheckSumDownload/subtest3 (4.79s) --- PASS: TestCheckSumDownload/subtest4 (4.87s) --- PASS: TestCheckSumDownload/subtest5 (5.99s) --- PASS: TestCheckSumDownload/subtest6 (7.42s) --- PASS: TestCheckSumDownload/subtest7 (7.19s) === RUN TestApplyTransforms 2026/02/06 05:08:33 DEBUG : Creating backend with remote "TestDrime:rclone-test-dutunoj1tede" 2026/02/06 05:08:33 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/02/06 05:08:36 DEBUG : Creating backend with remote "/tmp/rclone3000378303" run.go:185: Remote "drime root 'rclone-test-dutunoj1tede'", Local "Local file system at /tmp/rclone3000378303", Modify Window "876000h0m0s" upper checkfile vs. lower remote (without normalization) 2026/02/06 05:08:48 ERROR : HELLO, WORLD!: file not in drime root 'rclone-test-dutunoj1tede' 2026/02/06 05:08:48 NOTICE: drime root 'rclone-test-dutunoj1tede': 1 files missing 2026/02/06 05:08:48 NOTICE: drime root 'rclone-test-dutunoj1tede': 0 differences found 2026/02/06 05:08:48 NOTICE: drime root 'rclone-test-dutunoj1tede': 1 errors while checking upper checkfile vs. lower remote (with normalization) 2026/02/06 05:08:49 ERROR : hello, world!: file not in drime root 'rclone-test-dutunoj1tede' 2026/02/06 05:08:49 NOTICE: drime root 'rclone-test-dutunoj1tede': 1 files missing 2026/02/06 05:08:49 NOTICE: drime root 'rclone-test-dutunoj1tede': 0 differences found 2026/02/06 05:08:49 NOTICE: drime root 'rclone-test-dutunoj1tede': 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-dutunoj1tede' Test: TestApplyTransforms Messages: unexpected error for upper checkfile vs. lower remote (with normalization) HELLO, WORLD! hello, world! 2026/02/06 05:08:49 DEBUG : Creating backend with remote "TestDrime:rclone-test-qujeked9wofo" 2026/02/06 05:08:49 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/02/06 05:08:53 DEBUG : Creating backend with remote "/tmp/rclone1874176260" run.go:185: Remote "drime root 'rclone-test-qujeked9wofo'", Local "Local file system at /tmp/rclone1874176260", Modify Window "876000h0m0s" lower checkfile vs. upper remote (without normalization) 2026/02/06 05:09:06 ERROR : hello, world!: file not in drime root 'rclone-test-qujeked9wofo' 2026/02/06 05:09:06 NOTICE: drime root 'rclone-test-qujeked9wofo': 1 files missing 2026/02/06 05:09:06 NOTICE: drime root 'rclone-test-qujeked9wofo': 0 differences found 2026/02/06 05:09:06 NOTICE: drime root 'rclone-test-qujeked9wofo': 1 errors while checking lower checkfile vs. upper remote (with normalization) 2026/02/06 05:09:07 ERROR : hello, world!: file not in drime root 'rclone-test-qujeked9wofo' 2026/02/06 05:09:07 NOTICE: drime root 'rclone-test-qujeked9wofo': 1 files missing 2026/02/06 05:09:07 NOTICE: drime root 'rclone-test-qujeked9wofo': 0 differences found 2026/02/06 05:09:07 NOTICE: drime root 'rclone-test-qujeked9wofo': 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-qujeked9wofo' Test: TestApplyTransforms Messages: unexpected error for lower checkfile vs. upper remote (with normalization) hello, world! HELLO, WORLD! 2026/02/06 05:09:07 DEBUG : Creating backend with remote "TestDrime:rclone-test-vacefat4karo" 2026/02/06 05:09:07 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/02/06 05:09:09 DEBUG : Creating backend with remote "/tmp/rclone3949874245" run.go:185: Remote "drime root 'rclone-test-vacefat4karo'", Local "Local file system at /tmp/rclone3949874245", Modify Window "876000h0m0s" lower checkfile vs. upperlowermixed remote (without normalization) 2026/02/06 05:09:19 ERROR : hello, world!: file not in drime root 'rclone-test-vacefat4karo' 2026/02/06 05:09:19 NOTICE: drime root 'rclone-test-vacefat4karo': 1 files missing 2026/02/06 05:09:19 NOTICE: drime root 'rclone-test-vacefat4karo': 0 differences found 2026/02/06 05:09:19 NOTICE: drime root 'rclone-test-vacefat4karo': 1 errors while checking lower checkfile vs. upperlowermixed remote (with normalization) 2026/02/06 05:09:19 ERROR : hello, world!: file not in drime root 'rclone-test-vacefat4karo' 2026/02/06 05:09:19 NOTICE: drime root 'rclone-test-vacefat4karo': 1 files missing 2026/02/06 05:09:19 NOTICE: drime root 'rclone-test-vacefat4karo': 0 differences found 2026/02/06 05:09:19 NOTICE: drime root 'rclone-test-vacefat4karo': 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-vacefat4karo' Test: TestApplyTransforms Messages: unexpected error for lower checkfile vs. upperlowermixed remote (with normalization) hello, world! HeLlO, wOrLd! 2026/02/06 05:09:19 DEBUG : Creating backend with remote "TestDrime:rclone-test-veditov8kecu" 2026/02/06 05:09:19 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/02/06 05:09:20 DEBUG : Creating backend with remote "/tmp/rclone3371315017" run.go:185: Remote "drime root 'rclone-test-veditov8kecu'", Local "Local file system at /tmp/rclone3371315017", Modify Window "876000h0m0s" upperlowermixed checkfile vs. upper remote (without normalization) 2026/02/06 05:09:27 ERROR : HeLlO, wOrLd!: file not in drime root 'rclone-test-veditov8kecu' 2026/02/06 05:09:27 NOTICE: drime root 'rclone-test-veditov8kecu': 1 files missing 2026/02/06 05:09:27 NOTICE: drime root 'rclone-test-veditov8kecu': 0 differences found 2026/02/06 05:09:27 NOTICE: drime root 'rclone-test-veditov8kecu': 1 errors while checking upperlowermixed checkfile vs. upper remote (with normalization) 2026/02/06 05:09:27 ERROR : hello, world!: file not in drime root 'rclone-test-veditov8kecu' 2026/02/06 05:09:27 NOTICE: drime root 'rclone-test-veditov8kecu': 1 files missing 2026/02/06 05:09:27 NOTICE: drime root 'rclone-test-veditov8kecu': 0 differences found 2026/02/06 05:09:27 NOTICE: drime root 'rclone-test-veditov8kecu': 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-veditov8kecu' Test: TestApplyTransforms Messages: unexpected error for upperlowermixed checkfile vs. upper remote (with normalization) HeLlO, wOrLd! HELLO, WORLD! 2026/02/06 05:09:27 DEBUG : Creating backend with remote "TestDrime:rclone-test-kaxahob2wiqe" 2026/02/06 05:09:27 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/02/06 05:09:28 DEBUG : Creating backend with remote "/tmp/rclone2236447885" run.go:185: Remote "drime root 'rclone-test-kaxahob2wiqe'", Local "Local file system at /tmp/rclone2236447885", Modify Window "876000h0m0s" NFD checkfile vs. NFC remote (without normalization) 2026/02/06 05:09:34 ERROR : 測試_Русский___ě_áñ: file not in drime root 'rclone-test-kaxahob2wiqe' 2026/02/06 05:09:34 NOTICE: drime root 'rclone-test-kaxahob2wiqe': 1 files missing 2026/02/06 05:09:34 NOTICE: drime root 'rclone-test-kaxahob2wiqe': 0 differences found 2026/02/06 05:09:34 NOTICE: drime root 'rclone-test-kaxahob2wiqe': 1 errors while checking NFD checkfile vs. NFC remote (with normalization) 2026/02/06 05:09:34 ERROR : 測試_русский___ě_áñ: file not in drime root 'rclone-test-kaxahob2wiqe' 2026/02/06 05:09:34 NOTICE: drime root 'rclone-test-kaxahob2wiqe': 1 files missing 2026/02/06 05:09:34 NOTICE: drime root 'rclone-test-kaxahob2wiqe': 0 differences found 2026/02/06 05:09:34 NOTICE: drime root 'rclone-test-kaxahob2wiqe': 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-kaxahob2wiqe' Test: TestApplyTransforms Messages: unexpected error for NFD checkfile vs. NFC remote (with normalization) 測試_Русский___ě_áñ 測試_Русский___ě_áñ 2026/02/06 05:09:34 DEBUG : Creating backend with remote "TestDrime:rclone-test-masimog9xeye" 2026/02/06 05:09:34 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/02/06 05:09:35 DEBUG : Creating backend with remote "/tmp/rclone1118412594" run.go:185: Remote "drime root 'rclone-test-masimog9xeye'", Local "Local file system at /tmp/rclone1118412594", Modify Window "876000h0m0s" NFC checkfile vs. NFD remote (without normalization) 2026/02/06 05:09:46 ERROR : 測試_Русский___ě_áñ: file not in drime root 'rclone-test-masimog9xeye' 2026/02/06 05:09:46 NOTICE: drime root 'rclone-test-masimog9xeye': 1 files missing 2026/02/06 05:09:46 NOTICE: drime root 'rclone-test-masimog9xeye': 0 differences found 2026/02/06 05:09:46 NOTICE: drime root 'rclone-test-masimog9xeye': 1 errors while checking NFC checkfile vs. NFD remote (with normalization) 2026/02/06 05:09:47 DEBUG : 測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/02/06 05:09:47 NOTICE: drime root 'rclone-test-masimog9xeye': 0 differences found 2026/02/06 05:09:47 NOTICE: drime root 'rclone-test-masimog9xeye': 1 matching files 2026/02/06 05:09:47 DEBUG : Creating backend with remote "TestDrime:rclone-test-tedojoc6jozu" 2026/02/06 05:09:47 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/02/06 05:09:48 DEBUG : Creating backend with remote "/tmp/rclone3091593354" run.go:185: Remote "drime root 'rclone-test-tedojoc6jozu'", Local "Local file system at /tmp/rclone3091593354", Modify Window "876000h0m0s" NFDx2 checkfile vs. both remote (without normalization) 2026/02/06 05:09:54 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in drime root 'rclone-test-tedojoc6jozu' 2026/02/06 05:09:54 NOTICE: drime root 'rclone-test-tedojoc6jozu': 1 files missing 2026/02/06 05:09:54 NOTICE: drime root 'rclone-test-tedojoc6jozu': 0 differences found 2026/02/06 05:09:54 NOTICE: drime root 'rclone-test-tedojoc6jozu': 1 errors while checking NFDx2 checkfile vs. both remote (with normalization) 2026/02/06 05:09:54 ERROR : 測試_русский___ě_áñ測試_русский___ě_áñ: file not in drime root 'rclone-test-tedojoc6jozu' 2026/02/06 05:09:54 NOTICE: drime root 'rclone-test-tedojoc6jozu': 1 files missing 2026/02/06 05:09:54 NOTICE: drime root 'rclone-test-tedojoc6jozu': 0 differences found 2026/02/06 05:09:54 NOTICE: drime root 'rclone-test-tedojoc6jozu': 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-tedojoc6jozu' Test: TestApplyTransforms Messages: unexpected error for NFDx2 checkfile vs. both remote (with normalization) 測試_Русский___ě_áñ測試_Русский___ě_áñ 測試_Русский___ě_áñ測試_Русский___ě_áñ 2026/02/06 05:09:54 DEBUG : Creating backend with remote "TestDrime:rclone-test-napupuw4qevi" 2026/02/06 05:09:54 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/02/06 05:09:54 DEBUG : Creating backend with remote "/tmp/rclone2563857287" run.go:185: Remote "drime root 'rclone-test-napupuw4qevi'", Local "Local file system at /tmp/rclone2563857287", Modify Window "876000h0m0s" NFCx2 checkfile vs. both remote (without normalization) 2026/02/06 05:09:58 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in drime root 'rclone-test-napupuw4qevi' 2026/02/06 05:09:58 NOTICE: drime root 'rclone-test-napupuw4qevi': 1 files missing 2026/02/06 05:09:58 NOTICE: drime root 'rclone-test-napupuw4qevi': 0 differences found 2026/02/06 05:09:58 NOTICE: drime root 'rclone-test-napupuw4qevi': 1 errors while checking NFCx2 checkfile vs. both remote (with normalization) 2026/02/06 05:09:58 ERROR : 測試_русский___ě_áñ測試_русский___ě_áñ: file not in drime root 'rclone-test-napupuw4qevi' 2026/02/06 05:09:58 NOTICE: drime root 'rclone-test-napupuw4qevi': 1 files missing 2026/02/06 05:09:58 NOTICE: drime root 'rclone-test-napupuw4qevi': 0 differences found 2026/02/06 05:09:58 NOTICE: drime root 'rclone-test-napupuw4qevi': 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-napupuw4qevi' Test: TestApplyTransforms Messages: unexpected error for NFCx2 checkfile vs. both remote (with normalization) 測試_Русский___ě_áñ測試_Русский___ě_áñ 測試_Русский___ě_áñ測試_Русский___ě_áñ 2026/02/06 05:09:58 DEBUG : Creating backend with remote "TestDrime:rclone-test-boziyuf1wure" 2026/02/06 05:09:58 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/02/06 05:09:59 DEBUG : Creating backend with remote "/tmp/rclone895271229" run.go:185: Remote "drime root 'rclone-test-boziyuf1wure'", Local "Local file system at /tmp/rclone895271229", Modify Window "876000h0m0s" both checkfile vs. NFDx2 remote (without normalization) 2026/02/06 05:10:02 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in drime root 'rclone-test-boziyuf1wure' 2026/02/06 05:10:02 NOTICE: drime root 'rclone-test-boziyuf1wure': 1 files missing 2026/02/06 05:10:02 NOTICE: drime root 'rclone-test-boziyuf1wure': 0 differences found 2026/02/06 05:10:02 NOTICE: drime root 'rclone-test-boziyuf1wure': 1 errors while checking both checkfile vs. NFDx2 remote (with normalization) 2026/02/06 05:10:03 ERROR : 測試_русский___ě_áñ測試_русский___ě_áñ: file not in drime root 'rclone-test-boziyuf1wure' 2026/02/06 05:10:03 NOTICE: drime root 'rclone-test-boziyuf1wure': 1 files missing 2026/02/06 05:10:03 NOTICE: drime root 'rclone-test-boziyuf1wure': 0 differences found 2026/02/06 05:10:03 NOTICE: drime root 'rclone-test-boziyuf1wure': 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-boziyuf1wure' Test: TestApplyTransforms Messages: unexpected error for both checkfile vs. NFDx2 remote (with normalization) 測試_Русский___ě_áñ測試_Русский___ě_áñ 測試_Русский___ě_áñ測試_Русский___ě_áñ 2026/02/06 05:10:03 DEBUG : Creating backend with remote "TestDrime:rclone-test-woyefoc9fezu" 2026/02/06 05:10:03 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/02/06 05:10:03 DEBUG : Creating backend with remote "/tmp/rclone1791677107" run.go:185: Remote "drime root 'rclone-test-woyefoc9fezu'", Local "Local file system at /tmp/rclone1791677107", Modify Window "876000h0m0s" both checkfile vs. NFCx2 remote (without normalization) 2026/02/06 05:10:07 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in drime root 'rclone-test-woyefoc9fezu' 2026/02/06 05:10:07 NOTICE: drime root 'rclone-test-woyefoc9fezu': 1 files missing 2026/02/06 05:10:07 NOTICE: drime root 'rclone-test-woyefoc9fezu': 0 differences found 2026/02/06 05:10:07 NOTICE: drime root 'rclone-test-woyefoc9fezu': 1 errors while checking both checkfile vs. NFCx2 remote (with normalization) 2026/02/06 05:10:07 ERROR : 測試_русский___ě_áñ測試_русский___ě_áñ: file not in drime root 'rclone-test-woyefoc9fezu' 2026/02/06 05:10:07 NOTICE: drime root 'rclone-test-woyefoc9fezu': 1 files missing 2026/02/06 05:10:07 NOTICE: drime root 'rclone-test-woyefoc9fezu': 0 differences found 2026/02/06 05:10:07 NOTICE: drime root 'rclone-test-woyefoc9fezu': 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-woyefoc9fezu' Test: TestApplyTransforms Messages: unexpected error for both checkfile vs. NFCx2 remote (with normalization) 測試_Русский___ě_áñ測試_Русский___ě_áñ 測試_Русский___ě_áñ測試_Русский___ě_áñ 2026/02/06 05:10:07 DEBUG : drime root 'rclone-test-woyefoc9fezu': Purge remote 2026/02/06 05:10:07 DEBUG : drime root 'rclone-test-boziyuf1wure': Purge remote 2026/02/06 05:10:07 DEBUG : drime root 'rclone-test-napupuw4qevi': Purge remote 2026/02/06 05:10:08 DEBUG : drime root 'rclone-test-tedojoc6jozu': Purge remote 2026/02/06 05:10:08 DEBUG : drime root 'rclone-test-masimog9xeye': Purge remote 2026/02/06 05:10:09 DEBUG : drime root 'rclone-test-kaxahob2wiqe': Purge remote 2026/02/06 05:10:09 DEBUG : drime root 'rclone-test-veditov8kecu': Purge remote 2026/02/06 05:10:09 DEBUG : drime root 'rclone-test-vacefat4karo': Purge remote 2026/02/06 05:10:10 DEBUG : drime root 'rclone-test-qujeked9wofo': Purge remote 2026/02/06 05:10:10 DEBUG : drime root 'rclone-test-dutunoj1tede': Purge remote --- FAIL: TestApplyTransforms (97.98s) === RUN TestCopyFile run.go:185: Remote "drime root 'rclone-test-wamayod7cole'", Local "Local file system at /tmp/rclone637323739", Modify Window "876000h0m0s" 2026/02/06 05:10:11 DEBUG : file1: Need to transfer - File not found at Destination 2026/02/06 05:10:25 DEBUG : sub/file2: size = 14 OK 2026/02/06 05:10:25 INFO : file1: Copied (new) to: sub/file2 2026/02/06 05:10:26 DEBUG : sub/file2: size = 14 OK 2026/02/06 05:10:26 DEBUG : file1: Sizes identical 2026/02/06 05:10:26 DEBUG : file1: Unchanged skipping 2026/02/06 05:10:26 DEBUG : drime root 'rclone-test-wamayod7cole': 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 (29.73s) === RUN TestCopyFileCompareDest run.go:185: Remote "drime root 'rclone-test-wamayod7cole'", Local "Local file system at /tmp/rclone637323739", Modify Window "876000h0m0s" 2026/02/06 05:10:40 DEBUG : Creating backend with remote "TestDrime:rclone-test-wamayod7cole/dst" 2026/02/06 05:10:40 DEBUG : Config file has changed externally - reloading 2026/02/06 05:10:42 DEBUG : Creating backend with remote "TestDrime:rclone-test-wamayod7cole/CompareDest" 2026/02/06 05:10:43 DEBUG : one: Need to transfer - File not found at Destination 2026/02/06 05:10:53 DEBUG : one: size = 3 OK 2026/02/06 05:10:53 INFO : one: Copied (new) 2026/02/06 05:10:55 DEBUG : one: size = 5 (Local file system at /tmp/rclone637323739) 2026/02/06 05:10:55 DEBUG : one: size = 3 (drime root 'rclone-test-wamayod7cole/dst') 2026/02/06 05:10:55 DEBUG : one: Sizes differ 2026/02/06 05:10:59 DEBUG : one: Removing old object on successful upload 2026/02/06 05:10:59 DEBUG : one: size = 5 OK 2026/02/06 05:10:59 INFO : one: Copied (replaced existing) fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/copy_test.go:225 Error: Should be true Test: TestCopyFileCompareDest Messages: listing wrong, want dst/one (5) got dst/one (3) fstest.go:150: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:150 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:195 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/copy_test.go:225 Error: Not equal: expected: 5 actual : 3 Test: TestCopyFileCompareDest Messages: dst/one: size incorrect file=5 vs obj=3 2026/02/06 05:11:11 DEBUG : dst/one: Removing old object on successful upload fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:292: Sleeping for 7s just to make sure 2026/02/06 05:11:28 DEBUG : one: size = 5 (Local file system at /tmp/rclone637323739) 2026/02/06 05:11:28 DEBUG : one: size = 3 (drime root 'rclone-test-wamayod7cole/dst') 2026/02/06 05:11:28 DEBUG : one: Sizes differ 2026/02/06 05:11:29 DEBUG : one: size = 5 OK 2026/02/06 05:11:29 DEBUG : one: Sizes identical 2026/02/06 05:11:29 DEBUG : one: Destination found in --compare-dest, skipping fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/copy_test.go:242 Error: Should be true Test: TestCopyFileCompareDest Messages: listing wrong, want CompareDest/one (5), CompareDest/two (3), dst/one (3) got CompareDest/one (5), dst/one (3) fstest.go:203: Not found "CompareDest/two" fstest.go:206: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:206 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:310 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/copy_test.go:242 /usr/local/go/src/runtime/asm_amd64.s:1700 Error: Not equal: expected: 0 actual : 1 Test: TestCopyFileCompareDest Messages: 1 objects not found 2026/02/06 05:11:41 DEBUG : two: Need to transfer - File not found at Destination 2026/02/06 05:11:41 DEBUG : two: size = 3 OK 2026/02/06 05:11:41 DEBUG : two: Sizes identical 2026/02/06 05:11:41 DEBUG : two: Destination found in --compare-dest, skipping 2026/02/06 05:11:42 DEBUG : two: Need to transfer - File not found at Destination 2026/02/06 05:11:42 DEBUG : two: size = 3 OK 2026/02/06 05:11:42 DEBUG : two: Sizes identical 2026/02/06 05:11:42 DEBUG : two: Destination found in --compare-dest, skipping 2026/02/06 05:11:44 DEBUG : two: Need to transfer - File not found at Destination 2026/02/06 05:11:44 DEBUG : two: size = 5 (Local file system at /tmp/rclone637323739) 2026/02/06 05:11:44 DEBUG : two: size = 3 (drime root 'rclone-test-wamayod7cole/CompareDest') 2026/02/06 05:11:44 DEBUG : two: Sizes differ 2026/02/06 05:11:53 DEBUG : two: size = 5 OK 2026/02/06 05:11:53 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 (92.14s) === RUN TestCopyFileCopyDest run.go:185: Remote "drime root 'rclone-test-wamayod7cole'", Local "Local file system at /tmp/rclone637323739", Modify Window "876000h0m0s" 2026/02/06 05:12:12 DEBUG : Creating backend with remote "TestDrime:rclone-test-wamayod7cole/dst" 2026/02/06 05:12:13 DEBUG : Creating backend with remote "TestDrime:rclone-test-wamayod7cole/CopyDest" 2026/02/06 05:12:14 DEBUG : one: Need to transfer - File not found at Destination 2026/02/06 05:12:21 DEBUG : one: size = 3 OK 2026/02/06 05:12:21 INFO : one: Copied (new) 2026/02/06 05:12:22 DEBUG : one: size = 5 (Local file system at /tmp/rclone637323739) 2026/02/06 05:12:22 DEBUG : one: size = 3 (drime root 'rclone-test-wamayod7cole/dst') 2026/02/06 05:12:22 DEBUG : one: Sizes differ 2026/02/06 05:12:27 DEBUG : one: Removing old object on successful upload 2026/02/06 05:12:27 DEBUG : one: size = 5 OK 2026/02/06 05:12:27 INFO : one: Copied (replaced existing) fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:292: Sleeping for 7s just to make sure 2026/02/06 05:12:43 DEBUG : dst/one: Removing old object on successful upload 2026/02/06 05:12:47 DEBUG : Creating backend with remote "TestDrime:rclone-test-wamayod7cole/BackupDir" 2026/02/06 05:12:49 DEBUG : one: size = 5 (Local file system at /tmp/rclone637323739) 2026/02/06 05:12:49 DEBUG : one: size = 3 (drime root 'rclone-test-wamayod7cole/dst') 2026/02/06 05:12:49 DEBUG : one: Sizes differ 2026/02/06 05:12:50 INFO : one: Moved (server-side) 2026/02/06 05:12:52 DEBUG : one: Removing old object on successful upload 2026/02/06 05:12:52 DEBUG : one: size = 5 OK 2026/02/06 05:12:52 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/06 05:13:18 DEBUG : two: Need to transfer - File not found at Destination 2026/02/06 05:13:18 DEBUG : two: size = 3 OK 2026/02/06 05:13:18 DEBUG : two: Sizes identical 2026/02/06 05:13:19 DEBUG : two: size = 3 OK 2026/02/06 05:13:19 INFO : two: Copied (server-side copy) 2026/02/06 05:13:19 DEBUG : two: Destination found in --copy-dest, using server-side copy fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/copy_test.go:342 Error: Should be true Test: TestCopyFileCopyDest Messages: listing wrong, want BackupDir/one (3), CopyDest/one (5), CopyDest/two (3), dst/one (5), dst/two (3) got CopyDest/one (5), CopyDest/two (3), dst/one (5), dst/two (3) fstest.go:203: Not found "BackupDir/one" fstest.go:206: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:206 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:310 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/copy_test.go:342 /usr/local/go/src/runtime/asm_amd64.s:1700 Error: Not equal: expected: 0 actual : 1 Test: TestCopyFileCopyDest Messages: 1 objects not found 2026/02/06 05:13:29 DEBUG : two: size = 3 OK 2026/02/06 05:13:29 DEBUG : two: Sizes identical 2026/02/06 05:13:29 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/06 05:13:57 DEBUG : three: Need to transfer - File not found at Destination 2026/02/06 05:13:57 DEBUG : three: size = 7 (Local file system at /tmp/rclone637323739) 2026/02/06 05:13:57 DEBUG : three: size = 5 (drime root 'rclone-test-wamayod7cole/CopyDest') 2026/02/06 05:13:57 DEBUG : three: Sizes differ 2026/02/06 05:13:57 DEBUG : three: Destination not found in --copy-dest 2026/02/06 05:13:59 DEBUG : three: size = 7 OK 2026/02/06 05:13:59 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 run.go:130: removing dir "CopyDest" failed - try 1/3: directory not empty run.go:130: removing dir "CopyDest" failed - try 2/3: directory not empty run.go:130: removing dir "CopyDest" failed - try 3/3: directory not empty run.go:133: removing dir "CopyDest" failed: directory not empty fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:327: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:327 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393 /usr/local/go/src/testing/testing.go:1211 /usr/local/go/src/testing/testing.go:1445 /usr/local/go/src/testing/testing.go:1786 Error: Not equal: expected: []string{} actual : []string{"CopyDest", "dst"} Diff: --- Expected +++ Actual @@ -1,2 +1,4 @@ -([]string) { +([]string) (len=2) { + (string) (len=8) "CopyDest", + (string) (len=3) "dst" } Test: TestCopyFileCopyDest Messages: directories --- FAIL: TestCopyFileCopyDest (137.72s) === RUN TestListDirSorted run.go:185: Remote "drime root 'rclone-test-wamayod7cole'", Local "Local file system at /tmp/rclone637323739", Modify Window "876000h0m0s" 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:111 Error: "[CopyDest a.txt dst sub dir zend.txt]" should have 3 item(s), but has 5 Test: TestListDirSorted 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 --- FAIL: TestListDirSorted (81.33s) === RUN TestListDirSortedFn run.go:185: Remote "drime root 'rclone-test-wamayod7cole'", Local "Local file system at /tmp/rclone637323739", Modify Window "876000h0m0s" 2026/02/06 05:16:43 DEBUG : a.txt: Excluded (Size Filter) 2026/02/06 05:16:43 DEBUG : a.txt: Excluded 2026/02/06 05:16:44 DEBUG : sub dir/hello world2: Excluded (Size Filter) 2026/02/06 05:16:44 DEBUG : sub dir/hello world2: Excluded 2026/02/06 05:16:44 DEBUG : sub dir/hello world: Excluded (Size Filter) 2026/02/06 05:16:44 DEBUG : sub dir/hello world: Excluded 2026/02/06 05:16:45 DEBUG : sub dir/ignore dir: Excluded 2026/02/06 05:16:45 DEBUG : sub dir/hello world2: Excluded (Size Filter) 2026/02/06 05:16:45 DEBUG : sub dir/hello world2: Excluded 2026/02/06 05:16:45 DEBUG : sub dir/hello world: Excluded (Size Filter) 2026/02/06 05:16:45 DEBUG : sub dir/hello world: Excluded 2026/02/06 05:16:46 DEBUG : sub dir/ignore dir: Excluded run.go:130: removing dir "sub dir/sub sub dir" failed - try 1/3: directory not empty run.go:130: removing dir "sub dir/sub sub dir" failed - try 2/3: directory not empty run.go:130: removing dir "sub dir/sub sub dir" failed - try 3/3: directory not empty run.go:133: removing dir "sub dir/sub sub dir" failed: directory not empty run.go:130: removing dir "sub dir" failed - try 1/3: directory not empty run.go:130: removing dir "sub dir" failed - try 2/3: directory not empty run.go:130: removing dir "sub dir" failed - try 3/3: directory not empty run.go:133: removing dir "sub dir" failed: directory not empty fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:327: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:327 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393 /usr/local/go/src/testing/testing.go:1211 /usr/local/go/src/testing/testing.go:1445 /usr/local/go/src/testing/testing.go:1786 Error: Not equal: expected: []string{} actual : []string{"sub dir", "sub dir/sub sub dir"} Diff: --- Expected +++ Actual @@ -1,2 +1,4 @@ -([]string) { +([]string) (len=2) { + (string) (len=7) "sub dir", + (string) (len=19) "sub dir/sub sub dir" } Test: TestListDirSortedFn Messages: directories --- FAIL: TestListDirSortedFn (77.52s) === RUN TestListJSON run.go:185: Remote "drime root 'rclone-test-wamayod7cole'", Local "Local file system at /tmp/rclone637323739", Modify Window "876000h0m0s" === RUN TestListJSON/Default lsjson_test.go:202: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/lsjson_test.go:202 Error: Not equal: expected: 2 actual : 3 Test: TestListJSON/Default Messages: Wrong number of results === RUN TestListJSON/DirsOnly lsjson_test.go:202: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/lsjson_test.go:202 Error: Not equal: expected: 1 actual : 2 Test: TestListJSON/DirsOnly Messages: Wrong number of results === RUN TestListJSON/Recurse lsjson_test.go:202: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/lsjson_test.go:202 Error: Not equal: expected: 3 actual : 5 Test: TestListJSON/Recurse Messages: Wrong number of results === RUN TestListJSON/Metadata lsjson_test.go:202: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/lsjson_test.go:202 Error: Not equal: expected: 2 actual : 3 Test: TestListJSON/Metadata Messages: Wrong number of results === NAME TestListJSON run.go:130: removing dir "sub dir" failed - try 1/3: directory not empty run.go:130: removing dir "sub dir" failed - try 2/3: directory not empty run.go:130: removing dir "sub dir" failed - try 3/3: directory not empty run.go:133: removing dir "sub dir" failed: directory not empty run.go:130: removing dir "sub" failed - try 1/3: directory not empty run.go:130: removing dir "sub" failed - try 2/3: directory not empty 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 (51.68s) --- FAIL: TestListJSON/Default (0.22s) --- FAIL: TestListJSON/DirsOnly (0.29s) --- FAIL: TestListJSON/Recurse (0.93s) --- FAIL: TestListJSON/Metadata (0.44s) === RUN TestStatJSON run.go:185: Remote "drime root 'rclone-test-wamayod7cole'", Local "Local file system at /tmp/rclone637323739", Modify Window "876000h0m0s" === RUN TestStatJSON/Root === RUN TestStatJSON/RootFilesOnly === RUN TestStatJSON/RootDirsOnly === RUN TestStatJSON/Dir === RUN TestStatJSON/DirWithTrailingSlash === RUN TestStatJSON/File === RUN TestStatJSON/NotFound === RUN TestStatJSON/DirFilesOnly === RUN TestStatJSON/FileFilesOnly === RUN TestStatJSON/NotFoundFilesOnly === RUN TestStatJSON/DirDirsOnly === RUN TestStatJSON/FileDirsOnly === RUN TestStatJSON/NotFoundDirsOnly === RUN TestStatJSON/RootNotFound 2026/02/06 05:19:02 DEBUG : Creating backend with remote "TestDrime:rclone-test-wamayod7cole/notfound" === NAME TestStatJSON run.go:130: removing dir "sub" failed - try 1/3: directory not empty run.go:130: removing dir "sub" failed - try 2/3: directory not empty run.go:130: removing dir "sub" failed - try 3/3: directory not empty run.go:133: removing dir "sub" failed: directory not empty fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:327: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:327 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393 /usr/local/go/src/testing/testing.go:1211 /usr/local/go/src/testing/testing.go:1445 /usr/local/go/src/testing/testing.go:1786 Error: Not equal: expected: []string{} actual : []string{"sub"} Diff: --- Expected +++ Actual @@ -1,2 +1,3 @@ -([]string) { +([]string) (len=1) { + (string) (len=3) "sub" } Test: TestStatJSON Messages: directories --- FAIL: TestStatJSON (106.83s) --- PASS: TestStatJSON/Root (2.18s) --- PASS: TestStatJSON/RootFilesOnly (0.00s) --- PASS: TestStatJSON/RootDirsOnly (1.71s) --- PASS: TestStatJSON/Dir (3.68s) --- PASS: TestStatJSON/DirWithTrailingSlash (2.25s) --- PASS: TestStatJSON/File (1.70s) --- PASS: TestStatJSON/NotFound (3.10s) --- PASS: TestStatJSON/DirFilesOnly (1.23s) --- PASS: TestStatJSON/FileFilesOnly (1.67s) --- PASS: TestStatJSON/NotFoundFilesOnly (2.14s) --- PASS: TestStatJSON/DirDirsOnly (1.96s) --- PASS: TestStatJSON/FileDirsOnly (2.23s) --- PASS: TestStatJSON/NotFoundDirsOnly (1.97s) --- PASS: TestStatJSON/RootNotFound (10.94s) === RUN TestLsd run.go:185: Remote "drime root 'rclone-test-wamayod7cole'", Local "Local file system at /tmp/rclone637323739", Modify Window "876000h0m0s" run.go:130: removing dir "sub dir" failed - try 1/3: directory not empty run.go:130: removing dir "sub dir" failed - try 2/3: directory not empty --- PASS: TestLsd (37.13s) === RUN TestLsLong run.go:185: Remote "drime root 'rclone-test-wamayod7cole'", Local "Local file system at /tmp/rclone637323739", Modify Window "876000h0m0s" fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:292: Sleeping for 6s just to make sure --- PASS: TestLsLong (32.38s) === RUN TestHashSums run.go:185: Remote "drime root 'rclone-test-wamayod7cole'", Local "Local file system at /tmp/rclone637323739", Modify Window "876000h0m0s" fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:292: Sleeping for 6s just to make sure --- PASS: TestHashSums (44.59s) === RUN TestMaxDelete run.go:185: Remote "drime root 'rclone-test-wamayod7cole'", Local "Local file system at /tmp/rclone637323739", Modify Window "876000h0m0s" fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:292: Sleeping for 7s just to make sure 2026/02/06 05:22:30 DEBUG : Waiting for deletions to finish 2026/02/06 05:22:30 ERROR : small: Got fatal error on delete: --max-delete threshold reached 2026/02/06 05:22:30 INFO : medium: Deleted 2026/02/06 05:22:30 INFO : large: 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/06 05:22:31 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/06 05:22:31 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2026/02/06 05:22:32 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/06 05:22:32 DEBUG : pacer: Rate limited, increasing sleep to 40ms 2026/02/06 05:22:32 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/06 05:22:32 DEBUG : pacer: Rate limited, increasing sleep to 80ms 2026/02/06 05:22:32 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/06 05:22:32 DEBUG : pacer: Rate limited, increasing sleep to 160ms 2026/02/06 05:22:32 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/06 05:22:32 DEBUG : pacer: Rate limited, increasing sleep to 320ms 2026/02/06 05:22:33 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/06 05:22:33 DEBUG : pacer: Rate limited, increasing sleep to 640ms 2026/02/06 05:22:33 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/06 05:22:33 DEBUG : pacer: Rate limited, increasing sleep to 1.28s 2026/02/06 05:22:34 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/06 05:22:34 DEBUG : pacer: Rate limited, increasing sleep to 2.56s 2026/02/06 05:22:35 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/06 05:22:35 DEBUG : pacer: Rate limited, increasing sleep to 5.12s 2026/02/06 05:22:38 DEBUG : pacer: low level retry 10/10 (error Error "Server Error") 2026/02/06 05:22:38 DEBUG : pacer: Rate limited, increasing sleep to 10.24s run.go:130: removing file "large" failed - try 1/3: failed to delete item: Error "Server Error" 2026/02/06 05:22:43 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/06 05:22:43 DEBUG : pacer: Rate limited, increasing sleep to 20s 2026/02/06 05:22:53 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/06 05:23:13 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/06 05:23:33 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/06 05:23:53 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/06 05:24:13 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/06 05:24:33 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/06 05:24:53 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/06 05:25:13 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/06 05:25:33 DEBUG : pacer: low level retry 10/10 (error Error "Server Error") run.go:130: removing file "large" failed - try 2/3: failed to delete item: Error "Server Error" 2026/02/06 05:25:53 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/06 05:26:13 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/06 05:26:33 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/06 05:26:53 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/06 05:27:13 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/06 05:27:33 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/06 05:27:53 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/06 05:28:13 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/06 05:28:33 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/06 05:28:53 DEBUG : pacer: low level retry 10/10 (error Error "Server Error") run.go:130: removing file "large" failed - try 3/3: failed to delete item: Error "Server Error" run.go:133: removing file "large" failed: failed to delete item: Error "Server Error" 2026/02/06 05:29:13 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/06 05:29:33 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/06 05:29:53 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/06 05:30:13 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/06 05:30:33 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/06 05:30:53 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/06 05:31:13 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/06 05:31:33 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/06 05:31:53 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/06 05:32:13 DEBUG : pacer: low level retry 10/10 (error Error "Server Error") run.go:130: removing file "medium" failed - try 1/3: failed to delete item: Error "Server Error" 2026/02/06 05:32:33 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/06 05:32:53 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/06 05:33:13 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/06 05:33:33 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/06 05:33:54 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/06 05:34:15 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/06 05:34:34 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/06 05:34:54 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/06 05:35:14 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/06 05:35:34 DEBUG : pacer: low level retry 10/10 (error Error "Server Error") run.go:130: removing file "medium" failed - try 2/3: failed to delete item: Error "Server Error" 2026/02/06 05:35:54 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/06 05:36:14 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/06 05:36:34 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/06 05:36:54 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/06 05:37:14 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/06 05:37:34 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/06 05:37:55 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/06 05:38:13 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/06 05:38:33 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/06 05:38:53 DEBUG : pacer: low level retry 10/10 (error Error "Server Error") run.go:130: removing file "medium" failed - try 3/3: failed to delete item: Error "Server Error" run.go:133: removing file "medium" failed: failed to delete item: Error "Server Error" 2026/02/06 05:39:13 DEBUG : pacer: Reducing sleep to 10s 2026/02/06 05:39:33 DEBUG : pacer: Reducing sleep to 5s 2026/02/06 05:39:43 DEBUG : pacer: Reducing sleep to 2.5s 2026/02/06 05:39:48 DEBUG : pacer: Reducing sleep to 1.25s --- FAIL: TestMaxDelete (1086.38s) === RUN TestMaxDeleteSize run.go:185: Remote "drime root 'rclone-test-wamayod7cole'", Local "Local file system at /tmp/rclone637323739", Modify Window "876000h0m0s" 2026/02/06 05:39:50 DEBUG : pacer: Reducing sleep to 625ms 2026/02/06 05:39:54 DEBUG : pacer: Reducing sleep to 312.5ms 2026/02/06 05:39:54 DEBUG : pacer: Reducing sleep to 156.25ms 2026/02/06 05:40:00 DEBUG : pacer: Reducing sleep to 78.125ms 2026/02/06 05:40:00 DEBUG : pacer: Reducing sleep to 39.0625ms 2026/02/06 05:40:06 DEBUG : pacer: Reducing sleep to 19.53125ms 2026/02/06 05:40:07 DEBUG : pacer: Reducing sleep to 10ms 2026/02/06 05:40:07 DEBUG : Waiting for deletions to finish 2026/02/06 05:40:07 ERROR : small: Got fatal error on delete: --max-delete-size threshold reached 2026/02/06 05:40:07 INFO : large: Deleted 2026/02/06 05:40:07 INFO : medium: Deleted operations_test.go:492: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:492 Error: Not equal: expected: 1 actual : 3 Test: TestMaxDeleteSize 2026/02/06 05:40:08 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/06 05:40:08 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2026/02/06 05:40:08 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/06 05:40:08 DEBUG : pacer: Rate limited, increasing sleep to 40ms 2026/02/06 05:40:08 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/06 05:40:08 DEBUG : pacer: Rate limited, increasing sleep to 80ms 2026/02/06 05:40:09 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/06 05:40:09 DEBUG : pacer: Rate limited, increasing sleep to 160ms 2026/02/06 05:40:09 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/06 05:40:09 DEBUG : pacer: Rate limited, increasing sleep to 320ms 2026/02/06 05:40:09 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/06 05:40:09 DEBUG : pacer: Rate limited, increasing sleep to 640ms 2026/02/06 05:40:10 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/06 05:40:10 DEBUG : pacer: Rate limited, increasing sleep to 1.28s 2026/02/06 05:40:10 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/06 05:40:10 DEBUG : pacer: Rate limited, increasing sleep to 2.56s 2026/02/06 05:40:12 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/06 05:40:12 DEBUG : pacer: Rate limited, increasing sleep to 5.12s 2026/02/06 05:40:14 DEBUG : pacer: low level retry 10/10 (error Error "Server Error") 2026/02/06 05:40:14 DEBUG : pacer: Rate limited, increasing sleep to 10.24s run.go:130: removing file "large" failed - try 1/3: failed to delete item: Error "Server Error" 2026/02/06 05:40:19 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/06 05:40:19 DEBUG : pacer: Rate limited, increasing sleep to 20s 2026/02/06 05:40:30 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/06 05:40:50 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/06 05:41:11 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/06 05:41:32 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/06 05:41:51 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/06 05:42:11 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/06 05:42:31 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/06 05:42:51 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/06 05:43:11 DEBUG : pacer: low level retry 10/10 (error Error "Server Error") run.go:130: removing file "large" failed - try 2/3: failed to delete item: Error "Server Error" 2026/02/06 05:43:31 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/06 05:43:51 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/06 05:44:11 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/06 05:44:31 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/06 05:44:51 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/06 05:45:11 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/06 05:45:32 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/06 05:45:51 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/06 05:46:12 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/06 05:46:31 DEBUG : pacer: low level retry 10/10 (error Error "Server Error") run.go:130: removing file "large" failed - try 3/3: failed to delete item: Error "Server Error" run.go:133: removing file "large" failed: failed to delete item: Error "Server Error" 2026/02/06 05:46:51 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/06 05:47:11 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/06 05:47:31 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/06 05:47:51 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/06 05:48:11 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/06 05:48:32 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/06 05:48:51 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/06 05:49:11 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/06 05:49:30 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/06 05:49:51 DEBUG : pacer: low level retry 10/10 (error Error "Server Error") run.go:130: removing file "medium" failed - try 1/3: failed to delete item: Error "Server Error" 2026/02/06 05:50:11 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/06 05:50:31 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/06 05:50:51 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/06 05:51:11 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/06 05:51:31 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/06 05:51:51 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/06 05:52:11 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/06 05:52:32 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/06 05:52:52 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/06 05:53:11 DEBUG : pacer: low level retry 10/10 (error Error "Server Error") run.go:130: removing file "medium" failed - try 2/3: failed to delete item: Error "Server Error" 2026/02/06 05:53:30 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/06 05:53:50 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/06 05:54:10 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/06 05:54:30 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/06 05:54:50 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/06 05:55:10 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/06 05:55:30 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/06 05:55:50 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/06 05:56:10 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/06 05:56:30 DEBUG : pacer: low level retry 10/10 (error Error "Server Error") run.go:130: removing file "medium" failed - try 3/3: failed to delete item: Error "Server Error" run.go:133: removing file "medium" failed: failed to delete item: Error "Server Error" 2026/02/06 05:56:50 DEBUG : pacer: Reducing sleep to 10s 2026/02/06 05:57:10 DEBUG : pacer: Reducing sleep to 5s 2026/02/06 05:57:20 DEBUG : pacer: Reducing sleep to 2.5s 2026/02/06 05:57:25 DEBUG : pacer: Reducing sleep to 1.25s --- FAIL: TestMaxDeleteSize (1056.56s) === RUN TestRmdirsNoLeaveRoot run.go:185: Remote "drime root 'rclone-test-wamayod7cole'", Local "Local file system at /tmp/rclone637323739", Modify Window "876000h0m0s" 2026/02/06 05:57:27 DEBUG : pacer: Reducing sleep to 625ms 2026/02/06 05:57:28 DEBUG : pacer: Reducing sleep to 312.5ms 2026/02/06 05:57:29 DEBUG : pacer: Reducing sleep to 156.25ms 2026/02/06 05:57:29 DEBUG : pacer: Reducing sleep to 78.125ms 2026/02/06 05:57:30 DEBUG : pacer: Reducing sleep to 39.0625ms 2026/02/06 05:57:30 DEBUG : pacer: Reducing sleep to 19.53125ms 2026/02/06 05:57:30 DEBUG : pacer: Reducing sleep to 10ms 2026/02/06 05:57:33 INFO : A2: Making directory 2026/02/06 05:57:34 INFO : A1/B2: Making directory 2026/02/06 05:57:34 INFO : A1/B2/C2: Making directory 2026/02/06 05:57:35 INFO : A1/B1/C3: Making directory 2026/02/06 05:57:36 INFO : A3: Making directory 2026/02/06 05:57:36 INFO : A3/B3: Making directory 2026/02/06 05:57:37 INFO : A3/B3/C4: Making directory fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:662 Error: Should be true Test: TestRmdirsNoLeaveRoot Messages: listing wrong, want A1/B1/C1/one (3), A1/two (3) got A1/B1/C1/one (3) fstest.go:203: Not found "A1/two" fstest.go:206: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:206 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:310 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:662 Error: Not equal: expected: 0 actual : 1 Test: TestRmdirsNoLeaveRoot Messages: 1 objects not found 2026/02/06 05:57:51 DEBUG : removing 1 level 3 directories 2026/02/06 05:57:51 INFO : A3/B3/C4: Removing directory 2026/02/06 05:57:54 DEBUG : removing 2 level 3 directories 2026/02/06 05:57:54 INFO : A1/B2/C2: Removing directory 2026/02/06 05:57:54 INFO : A1/B1/C3: Removing directory 2026/02/06 05:57:55 DEBUG : removing 2 level 2 directories 2026/02/06 05:57:55 INFO : A3/B3: Removing directory 2026/02/06 05:57:55 INFO : A1/B2: Removing directory 2026/02/06 05:57:55 ERROR : A1/B2: Failed to rmdir: directory not empty 2026/02/06 05:57:55 DEBUG : removing 2 level 1 directories 2026/02/06 05:57:55 INFO : A2: Removing directory 2026/02/06 05:57:55 INFO : A3: Removing directory 2026/02/06 05:57:55 ERROR : A3: Failed to rmdir: directory not empty operations_test.go:705: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:705 Error: Received unexpected error: failed to remove directories: 2 errors: last error: directory not empty Test: TestRmdirsNoLeaveRoot 2026/02/06 05:57:58 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/06 05:57:58 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2026/02/06 05:57:58 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/06 05:57:58 DEBUG : pacer: Rate limited, increasing sleep to 40ms 2026/02/06 05:57:59 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/06 05:57:59 DEBUG : pacer: Rate limited, increasing sleep to 80ms 2026/02/06 05:57:59 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/06 05:57:59 DEBUG : pacer: Rate limited, increasing sleep to 160ms 2026/02/06 05:57:59 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/06 05:57:59 DEBUG : pacer: Rate limited, increasing sleep to 320ms 2026/02/06 05:57:59 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/06 05:57:59 DEBUG : pacer: Rate limited, increasing sleep to 640ms 2026/02/06 05:58:00 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/06 05:58:00 DEBUG : pacer: Rate limited, increasing sleep to 1.28s 2026/02/06 05:58:00 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/06 05:58:00 DEBUG : pacer: Rate limited, increasing sleep to 2.56s 2026/02/06 05:58:02 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/06 05:58:02 DEBUG : pacer: Rate limited, increasing sleep to 5.12s 2026/02/06 05:58:04 DEBUG : pacer: low level retry 10/10 (error Error "Server Error") 2026/02/06 05:58:04 DEBUG : pacer: Rate limited, increasing sleep to 10.24s run.go:130: removing dir "A3/B3" failed - try 1/3: failed to delete item: Error "Server Error" 2026/02/06 05:58:09 DEBUG : pacer: Reducing sleep to 5.12s 2026/02/06 05:58:20 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/06 05:58:20 DEBUG : pacer: Rate limited, increasing sleep to 10.24s 2026/02/06 05:58:25 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/06 05:58:25 DEBUG : pacer: Rate limited, increasing sleep to 20s 2026/02/06 05:58:35 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/06 05:58:55 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/06 05:59:15 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/06 05:59:35 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/06 05:59:55 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/06 06:00:15 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/06 06:00:35 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/06 06:00:55 DEBUG : pacer: low level retry 10/10 (error Error "Server Error") run.go:130: removing dir "A3/B3" failed - try 2/3: failed to delete item: Error "Server Error" 2026/02/06 06:01:15 DEBUG : pacer: Reducing sleep to 10s 2026/02/06 06:01:35 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/06 06:01:35 DEBUG : pacer: Rate limited, increasing sleep to 20s 2026/02/06 06:01:45 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/06 06:02:05 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/06 06:02:25 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/06 06:02:45 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/06 06:03:05 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/06 06:03:25 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/06 06:03:45 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/06 06:04:05 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/06 06:04:25 DEBUG : pacer: low level retry 10/10 (error Error "Server Error") run.go:130: removing dir "A3/B3" failed - try 3/3: failed to delete item: Error "Server Error" run.go:133: removing dir "A3/B3" failed: failed to delete item: Error "Server Error" 2026/02/06 06:04:45 DEBUG : pacer: Reducing sleep to 10s 2026/02/06 06:05:05 DEBUG : pacer: Reducing sleep to 5s 2026/02/06 06:05:15 DEBUG : pacer: Reducing sleep to 2.5s 2026/02/06 06:05:20 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/06 06:05:20 DEBUG : pacer: Rate limited, increasing sleep to 5s 2026/02/06 06:05:22 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/06 06:05:22 DEBUG : pacer: Rate limited, increasing sleep to 10s 2026/02/06 06:05:27 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/06 06:05:27 DEBUG : pacer: Rate limited, increasing sleep to 20s 2026/02/06 06:05:38 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/06 06:05:57 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/06 06:06:18 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/06 06:06:37 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/06 06:06:57 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/06 06:07:17 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/06 06:07:38 DEBUG : pacer: low level retry 10/10 (error Error "Server Error") run.go:130: removing dir "A2" failed - try 1/3: failed to delete item: Error "Server Error" 2026/02/06 06:07:58 DEBUG : pacer: Reducing sleep to 10s 2026/02/06 06:08:18 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/06 06:08:18 DEBUG : pacer: Rate limited, increasing sleep to 20s 2026/02/06 06:08:28 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/06 06:08:48 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/06 06:09:08 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/06 06:09:27 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/06 06:09:48 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/06 06:10:08 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/06 06:10:27 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/06 06:10:48 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/06 06:11:08 DEBUG : pacer: low level retry 10/10 (error Error "Server Error") run.go:130: removing dir "A2" failed - try 2/3: failed to delete item: Error "Server Error" 2026/02/06 06:11:28 DEBUG : pacer: Reducing sleep to 10s 2026/02/06 06:11:48 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/06 06:11:48 DEBUG : pacer: Rate limited, increasing sleep to 20s 2026/02/06 06:11:58 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/06 06:12:18 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/06 06:12:37 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/06 06:12:58 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/06 06:13:17 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/06 06:13:37 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/06 06:13:58 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/06 06:14:18 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/06 06:14:38 DEBUG : pacer: low level retry 10/10 (error Error "Server Error") run.go:130: removing dir "A2" failed - try 3/3: failed to delete item: Error "Server Error" run.go:133: removing dir "A2" failed: failed to delete item: Error "Server Error" 2026/02/06 06:14:58 DEBUG : pacer: Reducing sleep to 10s 2026/02/06 06:15:18 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/06 06:15:18 DEBUG : pacer: Rate limited, increasing sleep to 20s 2026/02/06 06:15:28 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/06 06:15:48 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/06 06:16:08 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/06 06:16:28 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/06 06:16:48 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/06 06:17:08 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/06 06:17:28 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/06 06:17:48 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/06 06:18:08 DEBUG : pacer: low level retry 10/10 (error Error "Server Error") run.go:130: removing dir "A1/B2/C2" failed - try 1/3: failed to delete item: Error "Server Error" 2026/02/06 06:18:28 DEBUG : pacer: Reducing sleep to 10s 2026/02/06 06:18:48 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/06 06:18:48 DEBUG : pacer: Rate limited, increasing sleep to 20s 2026/02/06 06:18:57 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/06 06:19:18 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/06 06:19:38 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/06 06:19:58 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/06 06:20:18 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/06 06:20:38 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/06 06:20:58 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/06 06:21:18 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/06 06:21:38 DEBUG : pacer: low level retry 10/10 (error Error "Server Error") run.go:130: removing dir "A1/B2/C2" failed - try 2/3: failed to delete item: Error "Server Error" 2026/02/06 06:21:58 DEBUG : pacer: Reducing sleep to 10s 2026/02/06 06:22:18 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/06 06:22:18 DEBUG : pacer: Rate limited, increasing sleep to 20s 2026/02/06 06:22:28 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/06 06:22:48 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/06 06:23:08 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/06 06:23:28 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/06 06:23:48 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/06 06:24:08 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/06 06:24:28 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/06 06:24:48 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/06 06:25:08 DEBUG : pacer: low level retry 10/10 (error Error "Server Error") run.go:130: removing dir "A1/B2/C2" failed - try 3/3: failed to delete item: Error "Server Error" run.go:133: removing dir "A1/B2/C2" failed: failed to delete item: Error "Server Error" 2026/02/06 06:25:28 DEBUG : pacer: Reducing sleep to 10s 2026/02/06 06:25:48 DEBUG : pacer: Reducing sleep to 5s 2026/02/06 06:25:58 DEBUG : pacer: Reducing sleep to 2.5s 2026/02/06 06:26:03 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/06 06:26:03 DEBUG : pacer: Rate limited, increasing sleep to 5s 2026/02/06 06:26:05 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/06 06:26:05 DEBUG : pacer: Rate limited, increasing sleep to 10s 2026/02/06 06:26:10 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/06 06:26:10 DEBUG : pacer: Rate limited, increasing sleep to 20s 2026/02/06 06:26:20 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/06 06:26:40 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/06 06:27:00 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/06 06:27:20 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/06 06:27:40 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/06 06:28:00 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/06 06:28:20 DEBUG : pacer: low level retry 10/10 (error Error "Server Error") run.go:130: removing dir "A1/B1/C3" failed - try 1/3: failed to delete item: Error "Server Error" 2026/02/06 06:28:40 DEBUG : pacer: Reducing sleep to 10s 2026/02/06 06:29:00 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/06 06:29:00 DEBUG : pacer: Rate limited, increasing sleep to 20s 2026/02/06 06:29:10 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/06 06:29:30 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/06 06:29:50 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/06 06:30:10 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/06 06:30:30 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/06 06:30:50 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/06 06:31:10 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/06 06:31:30 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/06 06:31:50 DEBUG : pacer: low level retry 10/10 (error Error "Server Error") run.go:130: removing dir "A1/B1/C3" failed - try 2/3: failed to delete item: Error "Server Error" 2026/02/06 06:32:10 DEBUG : pacer: Reducing sleep to 10s 2026/02/06 06:32:30 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/06 06:32:30 DEBUG : pacer: Rate limited, increasing sleep to 20s 2026/02/06 06:32:40 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/06 06:33:00 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/06 06:33:20 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/06 06:33:40 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/06 06:34:00 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/06 06:34:20 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/06 06:34:40 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/06 06:35:00 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/06 06:35:20 DEBUG : pacer: low level retry 10/10 (error Error "Server Error") run.go:130: removing dir "A1/B1/C3" failed - try 3/3: failed to delete item: Error "Server Error" run.go:133: removing dir "A1/B1/C3" failed: failed to delete item: Error "Server Error" 2026/02/06 06:35:40 DEBUG : pacer: Reducing sleep to 10s 2026/02/06 06:36:00 DEBUG : pacer: Reducing sleep to 5s 2026/02/06 06:36:10 DEBUG : pacer: Reducing sleep to 2.5s 2026/02/06 06:36:15 DEBUG : pacer: Reducing sleep to 1.25s 2026/02/06 06:36:18 DEBUG : pacer: Reducing sleep to 625ms 2026/02/06 06:36:19 DEBUG : pacer: Reducing sleep to 312.5ms 2026/02/06 06:36:20 DEBUG : pacer: Reducing sleep to 156.25ms --- FAIL: TestRmdirsNoLeaveRoot (2334.95s) === RUN TestRmdirsLeaveRoot run.go:185: Remote "drime root 'rclone-test-wamayod7cole'", Local "Local file system at /tmp/rclone637323739", Modify Window "876000h0m0s" 2026/02/06 06:36:20 INFO : A1: Making directory 2026/02/06 06:36:20 DEBUG : pacer: Reducing sleep to 78.125ms 2026/02/06 06:36:20 DEBUG : pacer: Reducing sleep to 39.0625ms 2026/02/06 06:36:20 INFO : A1/B1: Making directory 2026/02/06 06:36:21 DEBUG : pacer: Reducing sleep to 19.53125ms 2026/02/06 06:36:21 DEBUG : pacer: Reducing sleep to 10ms 2026/02/06 06:36:21 INFO : A1/B1/C1: Making directory fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:327: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:327 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:750 Error: Not equal: expected: []string{"A1", "A1/B1", "A1/B1/C1"} actual : []string{} Diff: --- Expected +++ Actual @@ -1,5 +1,2 @@ -([]string) (len=3) { - (string) (len=2) "A1", - (string) (len=5) "A1/B1", - (string) (len=8) "A1/B1/C1" +([]string) { } Test: TestRmdirsLeaveRoot Messages: directories 2026/02/06 06:36:31 DEBUG : removing 1 level 3 directories 2026/02/06 06:36:31 INFO : A1/B1/C1: Removing directory 2026/02/06 06:36:32 DEBUG : removing 1 level 2 directories 2026/02/06 06:36:32 INFO : A1/B1: Removing directory 2026/02/06 06:36:32 ERROR : A1/B1: Failed to rmdir: directory not empty operations_test.go:762: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:762 Error: Received unexpected error: failed to remove directories: directory not empty Test: TestRmdirsLeaveRoot 2026/02/06 06:36:34 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/06 06:36:34 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2026/02/06 06:36:34 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/06 06:36:34 DEBUG : pacer: Rate limited, increasing sleep to 40ms 2026/02/06 06:36:35 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/06 06:36:35 DEBUG : pacer: Rate limited, increasing sleep to 80ms 2026/02/06 06:36:35 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/06 06:36:35 DEBUG : pacer: Rate limited, increasing sleep to 160ms 2026/02/06 06:36:35 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/06 06:36:35 DEBUG : pacer: Rate limited, increasing sleep to 320ms 2026/02/06 06:36:36 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/06 06:36:36 DEBUG : pacer: Rate limited, increasing sleep to 640ms 2026/02/06 06:36:36 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/06 06:36:36 DEBUG : pacer: Rate limited, increasing sleep to 1.28s 2026/02/06 06:36:37 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/06 06:36:37 DEBUG : pacer: Rate limited, increasing sleep to 2.56s 2026/02/06 06:36:38 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/06 06:36:38 DEBUG : pacer: Rate limited, increasing sleep to 5.12s 2026/02/06 06:36:41 DEBUG : pacer: low level retry 10/10 (error Error "Server Error") 2026/02/06 06:36:41 DEBUG : pacer: Rate limited, increasing sleep to 10.24s run.go:130: removing dir "A1/B1/C1" failed - try 1/3: failed to delete item: Error "Server Error" 2026/02/06 06:36:46 DEBUG : pacer: Reducing sleep to 5.12s 2026/02/06 06:36:56 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/06 06:36:56 DEBUG : pacer: Rate limited, increasing sleep to 10.24s 2026/02/06 06:37:01 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/06 06:37:01 DEBUG : pacer: Rate limited, increasing sleep to 20s 2026/02/06 06:37:12 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/06 06:37:31 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/06 06:37:51 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/06 06:38:11 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/06 06:38:31 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/06 06:38:51 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/06 06:39:11 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/06 06:39:31 DEBUG : pacer: low level retry 10/10 (error Error "Server Error") run.go:130: removing dir "A1/B1/C1" failed - try 2/3: failed to delete item: Error "Server Error" 2026/02/06 06:39:51 DEBUG : pacer: Reducing sleep to 10s 2026/02/06 06:40:11 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/06 06:40:11 DEBUG : pacer: Rate limited, increasing sleep to 20s 2026/02/06 06:40:21 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/06 06:40:41 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/06 06:41:01 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/06 06:41:21 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/06 06:41:41 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/06 06:42:03 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/06 06:42:21 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/06 06:42:41 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/06 06:43:01 DEBUG : pacer: low level retry 10/10 (error Error "Server Error") run.go:130: removing dir "A1/B1/C1" failed - try 3/3: failed to delete item: Error "Server Error" run.go:133: removing dir "A1/B1/C1" failed: failed to delete item: Error "Server Error" 2026/02/06 06:43:21 DEBUG : pacer: Reducing sleep to 10s 2026/02/06 06:43:41 DEBUG : pacer: Reducing sleep to 5s 2026/02/06 06:43:51 DEBUG : pacer: Reducing sleep to 2.5s 2026/02/06 06:43:56 DEBUG : pacer: Reducing sleep to 1.25s 2026/02/06 06:43:59 DEBUG : pacer: Reducing sleep to 625ms --- FAIL: TestRmdirsLeaveRoot (459.16s) === RUN TestRmdirsWithFilter run.go:185: Remote "drime root 'rclone-test-wamayod7cole'", Local "Local file system at /tmp/rclone637323739", Modify Window "876000h0m0s" 2026/02/06 06:43:59 INFO : A1: Making directory 2026/02/06 06:44:00 DEBUG : pacer: Reducing sleep to 312.5ms 2026/02/06 06:44:00 DEBUG : pacer: Reducing sleep to 156.25ms 2026/02/06 06:44:00 INFO : A1/B1: Making directory 2026/02/06 06:44:01 DEBUG : pacer: Reducing sleep to 78.125ms 2026/02/06 06:44:01 DEBUG : pacer: Reducing sleep to 39.0625ms 2026/02/06 06:44:01 INFO : A1/B1/C1: Making directory 2026/02/06 06:44:01 DEBUG : pacer: Reducing sleep to 19.53125ms 2026/02/06 06:44:02 DEBUG : pacer: Reducing sleep to 10ms fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:327: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:327 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:789 Error: Not equal: expected: []string{"A1", "A1/B1", "A1/B1/C1"} actual : []string{} Diff: --- Expected +++ Actual @@ -1,5 +1,2 @@ -([]string) (len=3) { - (string) (len=2) "A1", - (string) (len=5) "A1/B1", - (string) (len=8) "A1/B1/C1" +([]string) { } Test: TestRmdirsWithFilter Messages: directories 2026/02/06 06:44:11 DEBUG : removing 1 level 3 directories 2026/02/06 06:44:11 INFO : A1/B1/C1: Removing directory 2026/02/06 06:44:12 DEBUG : removing 1 level 2 directories 2026/02/06 06:44:12 INFO : A1/B1: Removing directory 2026/02/06 06:44:12 ERROR : A1/B1: Failed to rmdir: directory not empty operations_test.go:801: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:801 Error: Received unexpected error: failed to remove directories: directory not empty Test: TestRmdirsWithFilter 2026/02/06 06:44:14 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/06 06:44:14 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2026/02/06 06:44:14 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/06 06:44:14 DEBUG : pacer: Rate limited, increasing sleep to 40ms 2026/02/06 06:44:14 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/06 06:44:14 DEBUG : pacer: Rate limited, increasing sleep to 80ms 2026/02/06 06:44:14 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/06 06:44:14 DEBUG : pacer: Rate limited, increasing sleep to 160ms 2026/02/06 06:44:15 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/06 06:44:15 DEBUG : pacer: Rate limited, increasing sleep to 320ms 2026/02/06 06:44:15 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/06 06:44:15 DEBUG : pacer: Rate limited, increasing sleep to 640ms 2026/02/06 06:44:15 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/06 06:44:15 DEBUG : pacer: Rate limited, increasing sleep to 1.28s 2026/02/06 06:44:16 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/06 06:44:16 DEBUG : pacer: Rate limited, increasing sleep to 2.56s 2026/02/06 06:44:17 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/06 06:44:17 DEBUG : pacer: Rate limited, increasing sleep to 5.12s 2026/02/06 06:44:20 DEBUG : pacer: low level retry 10/10 (error Error "Server Error") 2026/02/06 06:44:20 DEBUG : pacer: Rate limited, increasing sleep to 10.24s run.go:130: removing dir "A1/B1/C1" failed - try 1/3: failed to delete item: Error "Server Error" 2026/02/06 06:44:25 DEBUG : pacer: Reducing sleep to 5.12s 2026/02/06 06:44:35 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/06 06:44:35 DEBUG : pacer: Rate limited, increasing sleep to 10.24s 2026/02/06 06:44:40 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/06 06:44:40 DEBUG : pacer: Rate limited, increasing sleep to 20s 2026/02/06 06:44:51 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/06 06:45:11 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/06 06:45:31 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/06 06:45:51 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/06 06:46:11 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/06 06:46:31 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/06 06:46:51 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/06 06:47:11 DEBUG : pacer: low level retry 10/10 (error Error "Server Error") run.go:130: removing dir "A1/B1/C1" failed - try 2/3: failed to delete item: Error "Server Error" 2026/02/06 06:47:31 DEBUG : pacer: Reducing sleep to 10s 2026/02/06 06:47:51 DEBUG : pacer: low level retry 1/10 (error Error "Server Error") 2026/02/06 06:47:51 DEBUG : pacer: Rate limited, increasing sleep to 20s 2026/02/06 06:48:01 DEBUG : pacer: low level retry 2/10 (error Error "Server Error") 2026/02/06 06:48:21 DEBUG : pacer: low level retry 3/10 (error Error "Server Error") 2026/02/06 06:48:41 DEBUG : pacer: low level retry 4/10 (error Error "Server Error") 2026/02/06 06:49:01 DEBUG : pacer: low level retry 5/10 (error Error "Server Error") 2026/02/06 06:49:21 DEBUG : pacer: low level retry 6/10 (error Error "Server Error") 2026/02/06 06:49:41 DEBUG : pacer: low level retry 7/10 (error Error "Server Error") 2026/02/06 06:50:00 DEBUG : pacer: low level retry 8/10 (error Error "Server Error") 2026/02/06 06:50:21 DEBUG : pacer: low level retry 9/10 (error Error "Server Error") 2026/02/06 06:50:41 DEBUG : pacer: low level retry 10/10 (error Error "Server Error") run.go:130: removing dir "A1/B1/C1" failed - try 3/3: failed to delete item: Error "Server Error" run.go:133: removing dir "A1/B1/C1" failed: failed to delete item: Error "Server Error" 2026/02/06 06:51:01 DEBUG : pacer: Reducing sleep to 10s 2026/02/06 06:51:21 DEBUG : pacer: Reducing sleep to 5s 2026/02/06 06:51:31 DEBUG : pacer: Reducing sleep to 2.5s 2026/02/06 06:51:36 DEBUG : pacer: Reducing sleep to 1.25s 2026/02/06 06:51:38 DEBUG : pacer: Reducing sleep to 625ms --- FAIL: TestRmdirsWithFilter (459.45s) === RUN TestCopyURL run.go:185: Remote "drime root 'rclone-test-wamayod7cole'", Local "Local file system at /tmp/rclone637323739", Modify Window "876000h0m0s" 2026/02/06 06:51:39 DEBUG : pacer: Reducing sleep to 312.5ms 2026/02/06 06:51:40 DEBUG : pacer: Reducing sleep to 156.25ms 2026/02/06 06:51:48 DEBUG : pacer: Reducing sleep to 78.125ms 2026/02/06 06:51:48 DEBUG : pacer: Reducing sleep to 39.0625ms fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache 2026/02/06 06:51:49 DEBUG : pacer: Reducing sleep to 19.53125ms 2026/02/06 06:51:50 DEBUG : pacer: Reducing sleep to 10ms fstest.go:292: Sleeping for 6s just to make sure 2026/02/06 06:51:56 DEBUG : filename.txt: File name found in url 2026/02/06 06:52:06 DEBUG : headerfilename.txt: filename found in Content-Disposition header. 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 panic: test timed out after 2h0m0s running tests: TestCopyURL (50s) goroutine 7202 [running]: testing.(*M).startAlarm.func1() /usr/local/go/src/testing/testing.go:2484 +0x394 created by time.goFunc /usr/local/go/src/time/sleep.go:215 +0x2d goroutine 1 [chan receive, 2 minutes]: testing.(*T).Run(0xc0004948c0, {0x296a921?, 0xc000787ad8?}, 0x2adad18) /usr/local/go/src/testing/testing.go:1859 +0x431 testing.runTests.func1(0xc0004948c0) /usr/local/go/src/testing/testing.go:2279 +0x37 testing.tRunner(0xc0004948c0, 0xc000787c18) /usr/local/go/src/testing/testing.go:1792 +0xf4 testing.runTests(0xc00085ff38, {0x44a6dc0, 0x6f, 0x6f}, {0x849500?, 0x44e2400?, 0x44be640?}) /usr/local/go/src/testing/testing.go:2277 +0x4b4 testing.(*M).Run(0xc0003a81e0) /usr/local/go/src/testing/testing.go:2142 +0x64a github.com/rclone/rclone/fstest.TestMain(0xc0003a81e0) /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:74 +0xa6 github.com/rclone/rclone/fs/operations_test.TestMain(...) /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:61 main.main() _testmain.go:269 +0xa9 goroutine 687 [chan receive (nil chan), 111 minutes]: github.com/rclone/rclone/fs/march.(*March).Run.func2() /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:257 +0x3c created by github.com/rclone/rclone/fs/march.(*March).Run in goroutine 677 /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:255 +0x378 goroutine 19 [syscall, 120 minutes]: os/signal.signal_recv() /usr/local/go/src/runtime/sigqueue.go:152 +0x29 os/signal.loop() /usr/local/go/src/os/signal/signal_unix.go:23 +0x13 created by os/signal.Notify.func1.1 in goroutine 1 /usr/local/go/src/os/signal/signal.go:152 +0x1f goroutine 20 [chan receive, 120 minutes]: github.com/rclone/rclone/fs/accounting.(*tokenBucket).startSignalHandler.func1() /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:24 +0x27 created by github.com/rclone/rclone/fs/accounting.(*tokenBucket).startSignalHandler in goroutine 1 /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:21 +0xa7 goroutine 2166 [chan receive, 103 minutes]: github.com/rclone/rclone/fs/accounting.(*tokenBucket).startSignalHandler.func1() /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:24 +0x27 created by github.com/rclone/rclone/fs/accounting.(*tokenBucket).startSignalHandler in goroutine 1798 /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:21 +0xa7 goroutine 32 [chan receive, 119 minutes]: github.com/rclone/rclone/lib/atexit.Register.func1.1() /home/rclone/go/src/github.com/rclone/rclone/lib/atexit/atexit.go:45 +0x29 created by github.com/rclone/rclone/lib/atexit.Register.func1 in goroutine 68 /home/rclone/go/src/github.com/rclone/rclone/lib/atexit/atexit.go:44 +0x68 goroutine 1162 [chan receive (nil chan), 108 minutes]: github.com/rclone/rclone/fs/march.(*March).Run.func2() /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:257 +0x3c created by github.com/rclone/rclone/fs/march.(*March).Run in goroutine 1024 /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:255 +0x378 goroutine 633 [chan receive (nil chan), 111 minutes]: github.com/rclone/rclone/fs/march.(*March).Run.func2() /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:257 +0x3c created by github.com/rclone/rclone/fs/march.(*March).Run in goroutine 623 /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:255 +0x378 goroutine 2249 [chan receive, 102 minutes]: github.com/rclone/rclone/fs/accounting.(*tokenBucket).startSignalHandler.func1() /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:24 +0x27 created by github.com/rclone/rclone/fs/accounting.(*tokenBucket).startSignalHandler in goroutine 1798 /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:21 +0xa7 goroutine 1118 [chan receive (nil chan), 110 minutes]: github.com/rclone/rclone/fs/march.(*March).Run.func2() /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:257 +0x3c created by github.com/rclone/rclone/fs/march.(*March).Run in goroutine 1108 /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:255 +0x378 goroutine 721 [chan receive (nil chan), 111 minutes]: github.com/rclone/rclone/fs/march.(*March).Run.func2() /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:257 +0x3c created by github.com/rclone/rclone/fs/march.(*March).Run in goroutine 711 /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:255 +0x378 goroutine 1870 [chan receive, 103 minutes]: github.com/rclone/rclone/fs/accounting.(*tokenBucket).startSignalHandler.func1() /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:24 +0x27 created by github.com/rclone/rclone/fs/accounting.(*tokenBucket).startSignalHandler in goroutine 1798 /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:21 +0xa7 goroutine 773 [chan receive (nil chan), 111 minutes]: github.com/rclone/rclone/fs/march.(*March).Run.func2() /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:257 +0x3c created by github.com/rclone/rclone/fs/march.(*March).Run in goroutine 731 /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:255 +0x378 goroutine 1271 [chan receive (nil chan), 108 minutes]: github.com/rclone/rclone/fs/march.(*March).Run.func2() /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:257 +0x3c created by github.com/rclone/rclone/fs/march.(*March).Run in goroutine 1149 /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:255 +0x378 goroutine 944 [chan receive (nil chan), 110 minutes]: github.com/rclone/rclone/fs/march.(*March).Run.func2() /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:257 +0x3c created by github.com/rclone/rclone/fs/march.(*March).Run in goroutine 934 /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:255 +0x378 goroutine 918 [chan receive (nil chan), 110 minutes]: github.com/rclone/rclone/fs/march.(*March).Run.func2() /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:257 +0x3c created by github.com/rclone/rclone/fs/march.(*March).Run in goroutine 796 /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:255 +0x378 goroutine 2371 [chan receive, 102 minutes]: github.com/rclone/rclone/fs/accounting.(*tokenBucket).startSignalHandler.func1() /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:24 +0x27 created by github.com/rclone/rclone/fs/accounting.(*tokenBucket).startSignalHandler in goroutine 1798 /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:21 +0xa7 goroutine 1799 [chan receive, 105 minutes]: github.com/rclone/rclone/fs/accounting.(*tokenBucket).startSignalHandler.func1() /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:24 +0x27 created by github.com/rclone/rclone/fs/accounting.(*tokenBucket).startSignalHandler in goroutine 1798 /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:21 +0xa7 goroutine 1094 [chan receive (nil chan), 110 minutes]: github.com/rclone/rclone/fs/march.(*March).Run.func2() /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:257 +0x3c created by github.com/rclone/rclone/fs/march.(*March).Run in goroutine 1084 /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:255 +0x378 goroutine 859 [chan receive (nil chan), 110 minutes]: github.com/rclone/rclone/fs/march.(*March).Run.func2() /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:257 +0x3c created by github.com/rclone/rclone/fs/march.(*March).Run in goroutine 769 /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:255 +0x378 goroutine 2623 [IO wait]: internal/poll.runtime_pollWait(0x70c49e088ad0, 0x72) /usr/local/go/src/runtime/netpoll.go:351 +0x85 internal/poll.(*pollDesc).wait(0xc000e84780?, 0xc000035300?, 0x0) /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x27 internal/poll.(*pollDesc).waitRead(...) /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 internal/poll.(*FD).Read(0xc000e84780, {0xc000035300, 0x1300, 0x1300}) /usr/local/go/src/internal/poll/fd_unix.go:165 +0x27a net.(*netFD).Read(0xc000e84780, {0xc000035300?, 0xf49?, 0xc0004497a0?}) /usr/local/go/src/net/fd_posix.go:55 +0x25 net.(*conn).Read(0xc0004a0660, {0xc000035300?, 0xc0000356b2?, 0x5?}) /usr/local/go/src/net/net.go:194 +0x45 github.com/rclone/rclone/fs/fshttp.(*timeoutConn).Read(0xc00085f9c8, {0xc000035300?, 0x70c49e432170?, 0x70c49e4295c0?}) /home/rclone/go/src/github.com/rclone/rclone/fs/fshttp/dialer.go:111 +0x29 crypto/tls.(*atLeastReader).Read(0xc000011a58, {0xc000035300?, 0xf49?, 0xc0008ef608?}) /usr/local/go/src/crypto/tls/conn.go:809 +0x3b bytes.(*Buffer).ReadFrom(0xc0004bd7b8, {0x2e54de0, 0xc000011a58}) /usr/local/go/src/bytes/buffer.go:211 +0x98 crypto/tls.(*Conn).readFromUntil(0xc0004bd508, {0x70c454438380, 0xc00085f9c8}, 0x4426d4?) /usr/local/go/src/crypto/tls/conn.go:831 +0xde crypto/tls.(*Conn).readRecordOrCCS(0xc0004bd508, 0x0) /usr/local/go/src/crypto/tls/conn.go:629 +0x3cf crypto/tls.(*Conn).readRecord(...) /usr/local/go/src/crypto/tls/conn.go:591 crypto/tls.(*Conn).Read(0xc0004bd508, {0xc00097f000, 0x1000, 0xc000619ca0?}) /usr/local/go/src/crypto/tls/conn.go:1385 +0x145 bufio.(*Reader).Read(0xc0000b2f60, {0xc000966740, 0x9, 0x4426d50?}) /usr/local/go/src/bufio/bufio.go:245 +0x197 io.ReadAtLeast({0x2e53180, 0xc0000b2f60}, {0xc000966740, 0x9, 0x9}, 0x9) /usr/local/go/src/io/io.go:335 +0x91 io.ReadFull(...) /usr/local/go/src/io/io.go:354 net/http.http2readFrameHeader({0xc000966740, 0x9, 0xc0003bda70?}, {0x2e53180?, 0xc0000b2f60?}) /usr/local/go/src/net/http/h2_bundle.go:1805 +0x65 net/http.(*http2Framer).ReadFrame(0xc000966700) /usr/local/go/src/net/http/h2_bundle.go:2072 +0x7d net/http.(*http2clientConnReadLoop).run(0xc000619fa8) /usr/local/go/src/net/http/h2_bundle.go:9933 +0xda net/http.(*http2ClientConn).readLoop(0xc000bbb6c0) /usr/local/go/src/net/http/h2_bundle.go:9812 +0x79 created by net/http.(*http2Transport).newClientConn in goroutine 2622 /usr/local/go/src/net/http/h2_bundle.go:8334 +0xde5 goroutine 1224 [chan receive (nil chan), 108 minutes]: github.com/rclone/rclone/fs/march.(*March).Run.func2() /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:257 +0x3c created by github.com/rclone/rclone/fs/march.(*March).Run in goroutine 1214 /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:255 +0x378 goroutine 2109 [chan receive, 103 minutes]: github.com/rclone/rclone/fs/accounting.(*tokenBucket).startSignalHandler.func1() /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:24 +0x27 created by github.com/rclone/rclone/fs/accounting.(*tokenBucket).startSignalHandler in goroutine 1798 /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:21 +0xa7 goroutine 1043 [chan receive (nil chan), 110 minutes]: github.com/rclone/rclone/fs/march.(*March).Run.func2() /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:257 +0x3c created by github.com/rclone/rclone/fs/march.(*March).Run in goroutine 1033 /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:255 +0x378 goroutine 1251 [chan receive (nil chan), 108 minutes]: github.com/rclone/rclone/fs/march.(*March).Run.func2() /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:257 +0x3c created by github.com/rclone/rclone/fs/march.(*March).Run in goroutine 1241 /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:255 +0x378 goroutine 1984 [chan receive, 103 minutes]: github.com/rclone/rclone/fs/accounting.(*tokenBucket).startSignalHandler.func1() /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:24 +0x27 created by github.com/rclone/rclone/fs/accounting.(*tokenBucket).startSignalHandler in goroutine 1798 /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:21 +0xa7 goroutine 7016 [sleep]: time.Sleep(0xee6b2800) /usr/local/go/src/runtime/time.go:338 +0x165 github.com/rclone/rclone/fstest.CheckListingWithRoot(0xc000bba000, {0x2e947f0, 0xc0005d2140}, {0x0, 0x0}, {0x44e1e60, 0x0, 0x0}, {0xc00006bda8, 0x0, ...}, ...) /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:299 +0x647 github.com/rclone/rclone/fstest.CheckListingWithPrecision(...) /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 github.com/rclone/rclone/fstest.newRunIndividual.func1() /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178 +0x2f1 github.com/rclone/rclone/fstest.(*Run).Finalise(0xc00076c2a0) /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393 +0x1c testing.(*common).Cleanup.func1() /usr/local/go/src/testing/testing.go:1211 +0x106 testing.(*common).runCleanup(0xc000bba000, 0xc00006bf28?) /usr/local/go/src/testing/testing.go:1445 +0xea testing.tRunner.func2() /usr/local/go/src/testing/testing.go:1786 +0x25 testing.tRunner(0xc000bba000, 0x2adad18) /usr/local/go/src/testing/testing.go:1798 +0x11e created by testing.(*T).Run in goroutine 1 /usr/local/go/src/testing/testing.go:1851 +0x413 goroutine 1956 [chan receive, 103 minutes]: github.com/rclone/rclone/fs/accounting.(*tokenBucket).startSignalHandler.func1() /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:24 +0x27 created by github.com/rclone/rclone/fs/accounting.(*tokenBucket).startSignalHandler in goroutine 1798 /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:21 +0xa7 goroutine 2282 [chan receive, 102 minutes]: github.com/rclone/rclone/fs/accounting.(*tokenBucket).startSignalHandler.func1() /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:24 +0x27 created by github.com/rclone/rclone/fs/accounting.(*tokenBucket).startSignalHandler in goroutine 1798 /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:21 +0xa7 goroutine 2428 [chan receive, 102 minutes]: github.com/rclone/rclone/fs/accounting.(*tokenBucket).startSignalHandler.func1() /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:24 +0x27 created by github.com/rclone/rclone/fs/accounting.(*tokenBucket).startSignalHandler in goroutine 1798 /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:21 +0xa7 "./operations.test -test.v -test.timeout 2h0m0s -remote TestDrime: -verbose -test.run '^(TestApplyTransforms|TestCheckDownload|TestCheckSizeOnly|TestCheckSumDownload|TestCopyFile|TestCopyFileCompareDest|TestCopyFileCopyDest|TestCopyURL|TestDirMove|TestHashSums|TestListDirSorted|TestListDirSortedFn|TestLsLong|TestLsd|TestMaxDelete|TestMaxDeleteSize|TestMoveFile|TestMoveFileBackupDir|TestRmdirsLeaveRoot|TestRmdirsNoLeaveRoot|TestRmdirsWithFilter|TestStatJSON)$|^TestListJSON$/^(Default|DirsOnly|Metadata|Recurse)$|^TestMultithreadCopy$/^(upload=true,size=10485759,streams=2|upload=true,size=10485760,streams=2|upload=true,size=10485761,streams=2)$|^TestRcat$/^(withChecksum=false,ignoreChecksum=false|withChecksum=true,ignoreChecksum=false|withChecksum=true,ignoreChecksum=true)$'" - Finished ERROR in 2h0m1.249466252s (try 2/5): exit status 2: Failed [TestMultithreadCopy/upload=true,size=10485759,streams=2 TestMultithreadCopy/upload=true,size=10485760,streams=2 TestMultithreadCopy/upload=true,size=10485761,streams=2 TestApplyTransforms TestCopyFile TestCopyFileCompareDest TestCopyFileCopyDest TestListDirSorted TestListDirSortedFn TestListJSON/Default TestListJSON/DirsOnly TestListJSON/Recurse TestListJSON/Metadata TestStatJSON TestMaxDelete TestMaxDeleteSize TestRmdirsNoLeaveRoot TestRmdirsLeaveRoot TestRmdirsWithFilter]