"./operations.test -test.v -test.timeout 2h0m0s -remote TestDrime: -verbose -test.run '^(TestApplyTransforms|TestCat|TestCheckSumDownload|TestCopyFile|TestCopyFileCopyDest|TestCount|TestListDirSorted|TestListDirSortedFn|TestLs|TestMaxDelete|TestMaxDeleteSize|TestMaxDeleteSizeLargeFile|TestParseSumFile|TestReadFile)$|^TestListJSON$/^(Default|DirsOnly|Metadata|Recurse)$|^TestMultithreadCopy$/^(upload=false,size=10485759,streams=2|upload=true,size=10485759,streams=2|upload=true,size=10485760,streams=2|upload=true,size=10485761,streams=2)$'" - Starting (try 5/5)
2026/02/03 08:47:23 DEBUG : Creating backend with remote "TestDrime:rclone-test-coxaqej4vimo"
2026/02/03 08:47:23 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/02/03 08:47:24 DEBUG : Creating backend with remote "/tmp/rclone4087212002"
=== RUN TestMultithreadCopy
run.go:185: Remote "drime root 'rclone-test-coxaqej4vimo'", Local "Local file system at /tmp/rclone4087212002", Modify Window "876000h0m0s"
=== RUN TestMultithreadCopy/upload=false,size=10485759,streams=2
fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3
fstest.go:301: Flushing the directory cache
fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3
fstest.go:301: Flushing the directory cache
fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3
fstest.go:301: Flushing the directory cache
fstest.go:305:
Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350
/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345
/home/rclone/go/src/github.com/rclone/rclone/fs/operations/multithread_test.go:191
Error: Should be true
Test: TestMultithreadCopy/upload=false,size=10485759,streams=2
Messages: listing wrong, want
test-multithread-copy-false-10485759-2 (10485759) got
fstest.go:203: Not found "test-multithread-copy-false-10485759-2"
fstest.go:206:
Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:206
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:310
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350
/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345
/home/rclone/go/src/github.com/rclone/rclone/fs/operations/multithread_test.go:191
/usr/local/go/src/runtime/asm_amd64.s:1700
Error: Not equal:
expected: 0
actual : 1
Test: TestMultithreadCopy/upload=false,size=10485759,streams=2
Messages: 1 objects not found
2026/02/03 08:47:39 DEBUG : test-multithread-copy-false-10485759-2: multi-thread copy: disabling buffering because destination uses OpenWriterAt
2026/02/03 08:47:39 DEBUG : test-multithread-copy-false-10485759-2: multi-thread copy: write buffer set to 131072
2026/02/03 08:47:39 DEBUG : test-multithread-copy-false-10485759-2: multi-thread copy: chunk size 64Mi was bigger than source file size 10.000Mi
2026/02/03 08:47:39 DEBUG : test-multithread-copy-false-10485759-2: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 2
2026/02/03 08:47:39 DEBUG : test-multithread-copy-false-10485759-2: multi-thread copy: number of streams 4 was bigger than number of chunks 1
2026/02/03 08:47:39 DEBUG : test-multithread-copy-false-10485759-2: Starting multi-thread copy with 1 chunks of size 10.000Mi with 1 parallel streams
2026/02/03 08:47:39 DEBUG : test-multithread-copy-false-10485759-2: multi-thread copy: chunk 1/1 (0-10485759) size 10.000Mi starting
2026/02/03 08:47:39 DEBUG : test-multithread-copy-false-10485759-2: writing chunk 0
2026/02/03 08:47:39 DEBUG : test-multithread-copy-false-10485759-2: multi-thread copy: chunk 1/1 (0-10485759) size 10.000Mi finished
2026/02/03 08:47:39 DEBUG : test-multithread-copy-false-10485759-2: Finished multi-thread copy with 1 parts of size 10.000Mi
=== RUN TestMultithreadCopy/upload=true,size=10485759,streams=2
fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3
fstest.go:301: Flushing the directory cache
fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3
fstest.go:301: Flushing the directory cache
fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3
fstest.go:301: Flushing the directory cache
fstest.go:305:
Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350
/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345
/home/rclone/go/src/github.com/rclone/rclone/fs/operations/multithread_test.go:186
Error: Should be true
Test: TestMultithreadCopy/upload=true,size=10485759,streams=2
Messages: listing wrong, want
got
test-multithread-copy-false-10485759-2 (10485759)
fstest.go:191:
Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350
/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345
/home/rclone/go/src/github.com/rclone/rclone/fs/operations/multithread_test.go:186
/usr/local/go/src/runtime/asm_amd64.s:1700
Error: Should be true
Test: TestMultithreadCopy/upload=true,size=10485759,streams=2
Messages: Unexpected file "test-multithread-copy-false-10485759-2"
2026/02/03 08:47:49 DEBUG : test-multithread-copy-true-10485759-2: multi-thread copy: disabling buffering because source is local disk
2026/02/03 08:47:50 DEBUG : test-multithread-copy-true-10485759-2: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 2
2026/02/03 08:47:50 DEBUG : test-multithread-copy-true-10485759-2: multi-thread copy: number of streams 4 was bigger than number of chunks 2
2026/02/03 08:47:50 DEBUG : test-multithread-copy-true-10485759-2: Starting multi-thread copy with 2 chunks of size 5Mi with 2 parallel streams
2026/02/03 08:47:50 DEBUG : test-multithread-copy-true-10485759-2: multi-thread copy: chunk 2/2 (5242880-10485759) size 5.000Mi starting
2026/02/03 08:47:50 DEBUG : test-multithread-copy-true-10485759-2: multi-thread copy: chunk 1/2 (0-5242880) size 5Mi starting
2026/02/03 08:47:51 DEBUG : test-multithread-copy-true-10485759-2: multi-thread copy: chunk 1/2 (0-5242880) size 5Mi finished
2026/02/03 08:47:51 DEBUG : test-multithread-copy-true-10485759-2: multi-thread copy: chunk 2/2 (5242880-10485759) size 5.000Mi finished
2026/02/03 08:47:54 DEBUG : test-multithread-copy-true-10485759-2: Finished multi-thread copy with 2 parts of size 5Mi
=== RUN TestMultithreadCopy/upload=true,size=10485760,streams=2
fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3
fstest.go:301: Flushing the directory cache
fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3
fstest.go:301: Flushing the directory cache
fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3
fstest.go:301: Flushing the directory cache
fstest.go:305:
Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350
/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345
/home/rclone/go/src/github.com/rclone/rclone/fs/operations/multithread_test.go:186
Error: Should be true
Test: TestMultithreadCopy/upload=true,size=10485760,streams=2
Messages: listing wrong, want
got
test-multithread-copy-true-10485759-2 (10485759)
fstest.go:191:
Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350
/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345
/home/rclone/go/src/github.com/rclone/rclone/fs/operations/multithread_test.go:186
/usr/local/go/src/runtime/asm_amd64.s:1700
Error: Should be true
Test: TestMultithreadCopy/upload=true,size=10485760,streams=2
Messages: Unexpected file "test-multithread-copy-true-10485759-2"
2026/02/03 08:48:05 DEBUG : test-multithread-copy-true-10485760-2: multi-thread copy: disabling buffering because source is local disk
2026/02/03 08:48:05 DEBUG : test-multithread-copy-true-10485760-2: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 2
2026/02/03 08:48:05 DEBUG : test-multithread-copy-true-10485760-2: multi-thread copy: number of streams 4 was bigger than number of chunks 2
2026/02/03 08:48:05 DEBUG : test-multithread-copy-true-10485760-2: Starting multi-thread copy with 2 chunks of size 5Mi with 2 parallel streams
2026/02/03 08:48:05 DEBUG : test-multithread-copy-true-10485760-2: multi-thread copy: chunk 2/2 (5242880-10485760) size 5Mi starting
2026/02/03 08:48:05 DEBUG : test-multithread-copy-true-10485760-2: multi-thread copy: chunk 1/2 (0-5242880) size 5Mi starting
2026/02/03 08:48:06 DEBUG : test-multithread-copy-true-10485760-2: multi-thread copy: chunk 1/2 (0-5242880) size 5Mi finished
2026/02/03 08:48:06 DEBUG : test-multithread-copy-true-10485760-2: multi-thread copy: chunk 2/2 (5242880-10485760) size 5Mi finished
2026/02/03 08:48:09 DEBUG : test-multithread-copy-true-10485760-2: Finished multi-thread copy with 2 parts of size 5Mi
=== RUN TestMultithreadCopy/upload=true,size=10485761,streams=2
fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3
fstest.go:301: Flushing the directory cache
fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3
fstest.go:301: Flushing the directory cache
fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3
fstest.go:301: Flushing the directory cache
fstest.go:305:
Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350
/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345
/home/rclone/go/src/github.com/rclone/rclone/fs/operations/multithread_test.go:186
Error: Should be true
Test: TestMultithreadCopy/upload=true,size=10485761,streams=2
Messages: listing wrong, want
got
test-multithread-copy-true-10485760-2 (10485760)
fstest.go:191:
Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350
/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345
/home/rclone/go/src/github.com/rclone/rclone/fs/operations/multithread_test.go:186
/usr/local/go/src/runtime/asm_amd64.s:1700
Error: Should be true
Test: TestMultithreadCopy/upload=true,size=10485761,streams=2
Messages: Unexpected file "test-multithread-copy-true-10485760-2"
2026/02/03 08:48:20 DEBUG : test-multithread-copy-true-10485761-2: multi-thread copy: disabling buffering because source is local disk
2026/02/03 08:48:20 DEBUG : test-multithread-copy-true-10485761-2: multi-thread copy: using backend concurrency of 4 instead of --multi-thread-streams 2
2026/02/03 08:48:20 DEBUG : test-multithread-copy-true-10485761-2: multi-thread copy: number of streams 4 was bigger than number of chunks 3
2026/02/03 08:48:20 DEBUG : test-multithread-copy-true-10485761-2: Starting multi-thread copy with 3 chunks of size 5Mi with 3 parallel streams
2026/02/03 08:48:20 DEBUG : test-multithread-copy-true-10485761-2: multi-thread copy: chunk 3/3 (10485760-10485761) size 1 starting
2026/02/03 08:48:20 DEBUG : test-multithread-copy-true-10485761-2: multi-thread copy: chunk 1/3 (0-5242880) size 5Mi starting
2026/02/03 08:48:20 DEBUG : test-multithread-copy-true-10485761-2: multi-thread copy: chunk 2/3 (5242880-10485760) size 5Mi starting
2026/02/03 08:48:21 DEBUG : test-multithread-copy-true-10485761-2: multi-thread copy: chunk 3/3 (10485760-10485761) size 1 finished
2026/02/03 08:48:21 DEBUG : test-multithread-copy-true-10485761-2: multi-thread copy: chunk 2/3 (5242880-10485760) size 5Mi finished
2026/02/03 08:48:21 DEBUG : test-multithread-copy-true-10485761-2: multi-thread copy: chunk 1/3 (0-5242880) size 5Mi finished
2026/02/03 08:48:24 DEBUG : test-multithread-copy-true-10485761-2: Finished multi-thread copy with 3 parts of size 5Mi
2026/02/03 08:48:26 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/02/03 08:48:26 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/02/03 08:48:26 DEBUG : pacer: low level retry 2/10 (error Error "Server Error")
2026/02/03 08:48:26 DEBUG : pacer: Rate limited, increasing sleep to 40ms
2026/02/03 08:48:26 DEBUG : pacer: low level retry 3/10 (error Error "Server Error")
2026/02/03 08:48:26 DEBUG : pacer: Rate limited, increasing sleep to 80ms
2026/02/03 08:48:26 DEBUG : pacer: low level retry 4/10 (error Error "Server Error")
2026/02/03 08:48:26 DEBUG : pacer: Rate limited, increasing sleep to 160ms
2026/02/03 08:48:27 DEBUG : pacer: low level retry 5/10 (error Error "Server Error")
2026/02/03 08:48:27 DEBUG : pacer: Rate limited, increasing sleep to 320ms
2026/02/03 08:48:27 DEBUG : pacer: low level retry 6/10 (error Error "Server Error")
2026/02/03 08:48:27 DEBUG : pacer: Rate limited, increasing sleep to 640ms
2026/02/03 08:48:28 DEBUG : pacer: low level retry 7/10 (error Error "Server Error")
2026/02/03 08:48:28 DEBUG : pacer: Rate limited, increasing sleep to 1.28s
2026/02/03 08:48:29 DEBUG : pacer: low level retry 8/10 (error Error "Server Error")
2026/02/03 08:48:29 DEBUG : pacer: Rate limited, increasing sleep to 2.56s
2026/02/03 08:48:30 DEBUG : pacer: low level retry 9/10 (error Error "Server Error")
2026/02/03 08:48:30 DEBUG : pacer: Rate limited, increasing sleep to 5.12s
2026/02/03 08:48:33 DEBUG : pacer: low level retry 10/10 (error Error "Server Error")
2026/02/03 08:48:33 DEBUG : pacer: Rate limited, increasing sleep to 10.24s
=== NAME TestMultithreadCopy
run.go:130: removing file "test-multithread-copy-true-10485761-2" failed - try 1/3: failed to delete item: Error "Server Error"
2026/02/03 08:48:38 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/02/03 08:48:38 DEBUG : pacer: Rate limited, increasing sleep to 20s
2026/02/03 08:48:48 DEBUG : pacer: low level retry 2/10 (error Error "Server Error")
2026/02/03 08:49:08 DEBUG : pacer: low level retry 3/10 (error Error "Server Error")
2026/02/03 08:49:28 DEBUG : pacer: low level retry 4/10 (error Error "Server Error")
2026/02/03 08:49:48 DEBUG : pacer: low level retry 5/10 (error Error "Server Error")
2026/02/03 08:50:08 DEBUG : pacer: low level retry 6/10 (error Error "Server Error")
2026/02/03 08:50:28 DEBUG : pacer: low level retry 7/10 (error Error "Server Error")
2026/02/03 08:50:48 DEBUG : pacer: low level retry 8/10 (error Error "Server Error")
2026/02/03 08:51:08 DEBUG : pacer: low level retry 9/10 (error Error "Server Error")
2026/02/03 08:51:28 DEBUG : pacer: low level retry 10/10 (error Error "Server Error")
run.go:130: removing file "test-multithread-copy-true-10485761-2" failed - try 2/3: failed to delete item: Error "Server Error"
2026/02/03 08:51:48 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/02/03 08:52:08 DEBUG : pacer: low level retry 2/10 (error Error "Server Error")
2026/02/03 08:52:29 DEBUG : pacer: low level retry 3/10 (error Error "Server Error")
2026/02/03 08:52:48 DEBUG : pacer: low level retry 4/10 (error Error "Server Error")
2026/02/03 08:53:08 DEBUG : pacer: low level retry 5/10 (error Error "Server Error")
2026/02/03 08:53:28 DEBUG : pacer: low level retry 6/10 (error Error "Server Error")
2026/02/03 08:53:48 DEBUG : pacer: low level retry 7/10 (error Error "Server Error")
2026/02/03 08:54:08 DEBUG : pacer: low level retry 8/10 (error Error "Server Error")
2026/02/03 08:54:29 DEBUG : pacer: low level retry 9/10 (error Error "Server Error")
2026/02/03 08:54:48 DEBUG : pacer: low level retry 10/10 (error Error "Server Error")
run.go:130: removing file "test-multithread-copy-true-10485761-2" failed - try 3/3: failed to delete item: Error "Server Error"
run.go:133: removing file "test-multithread-copy-true-10485761-2" failed: failed to delete item: Error "Server Error"
2026/02/03 08:55:08 DEBUG : pacer: Reducing sleep to 10s
--- FAIL: TestMultithreadCopy (464.34s)
--- FAIL: TestMultithreadCopy/upload=false,size=10485759,streams=2 (14.57s)
--- FAIL: TestMultithreadCopy/upload=true,size=10485759,streams=2 (15.24s)
--- FAIL: TestMultithreadCopy/upload=true,size=10485760,streams=2 (14.47s)
--- FAIL: TestMultithreadCopy/upload=true,size=10485761,streams=2 (15.14s)
=== RUN TestParseSumFile
run.go:185: Remote "drime root 'rclone-test-coxaqej4vimo'", Local "Local file system at /tmp/rclone4087212002", Modify Window "876000h0m0s"
2026/02/03 08:55:29 DEBUG : pacer: Reducing sleep to 5s
2026/02/03 08:55:41 DEBUG : pacer: Reducing sleep to 2.5s
2026/02/03 08:55:43 DEBUG : pacer: Reducing sleep to 1.25s
2026/02/03 08:55:46 DEBUG : pacer: Reducing sleep to 625ms
2026/02/03 08:55:46 NOTICE: test.sum: improperly formatted checksum line 4
2026/02/03 08:55:46 NOTICE: test.sum: improperly formatted checksum line 5
2026/02/03 08:55:46 NOTICE: test.sum: improperly formatted checksum line 6
2026/02/03 08:55:46 NOTICE: test.sum: 2 warning(s) suppressed...
2026/02/03 08:55:47 DEBUG : pacer: Reducing sleep to 312.5ms
2026/02/03 08:56:04 DEBUG : pacer: Reducing sleep to 156.25ms
2026/02/03 08:56:04 DEBUG : test.sum: Removing old object on successful upload
2026/02/03 08:56:05 DEBUG : pacer: Reducing sleep to 78.125ms
2026/02/03 08:56:05 DEBUG : pacer: Reducing sleep to 39.0625ms
2026/02/03 08:56:06 DEBUG : pacer: Reducing sleep to 19.53125ms
2026/02/03 08:56:06 NOTICE: test.sum: improperly formatted checksum line 4
2026/02/03 08:56:06 NOTICE: test.sum: improperly formatted checksum line 5
2026/02/03 08:56:06 NOTICE: test.sum: improperly formatted checksum line 6
2026/02/03 08:56:06 NOTICE: test.sum: 2 warning(s) suppressed...
2026/02/03 08:56:06 DEBUG : pacer: Reducing sleep to 10ms
fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3
fstest.go:301: Flushing the directory cache
fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3
fstest.go:301: Flushing the directory cache
fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3
fstest.go:301: Flushing the directory cache
fstest.go:305:
Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338
/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178
/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393
/usr/local/go/src/testing/testing.go:1211
/usr/local/go/src/testing/testing.go:1445
/usr/local/go/src/testing/testing.go:1786
Error: Should be true
Test: TestParseSumFile
Messages: listing wrong, want
got
test.sum (90)
fstest.go:191:
Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338
/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178
/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393
/usr/local/go/src/testing/testing.go:1211
/usr/local/go/src/testing/testing.go:1445
/usr/local/go/src/testing/testing.go:1786
Error: Should be true
Test: TestParseSumFile
Messages: Unexpected file "test.sum"
--- FAIL: TestParseSumFile (66.62s)
=== RUN TestCheckSumDownload
run.go:185: Remote "drime root 'rclone-test-coxaqej4vimo'", Local "Local file system at /tmp/rclone4087212002", Modify Window "876000h0m0s"
2026/02/03 08:56:15 DEBUG : Creating backend with remote "TestDrime:rclone-test-coxaqej4vimo/data"
=== RUN TestCheckSumDownload/subtest1
2026/02/03 08:56:45 DEBUG : test.sum: Removing old object on successful upload
=== RUN TestCheckSumDownload/subtest2
2026/02/03 08:56:56 DEBUG : test.sum: Removing old object on successful upload
2026/02/03 08:56:56 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/02/03 08:56:56 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/02/03 08:56:57 DEBUG : pacer: low level retry 2/10 (error Error "Server Error")
2026/02/03 08:56:57 DEBUG : pacer: Rate limited, increasing sleep to 40ms
2026/02/03 08:56:57 DEBUG : pacer: low level retry 3/10 (error Error "Server Error")
2026/02/03 08:56:57 DEBUG : pacer: Rate limited, increasing sleep to 80ms
2026/02/03 08:56:57 DEBUG : pacer: low level retry 4/10 (error Error "Server Error")
2026/02/03 08:56:57 DEBUG : pacer: Rate limited, increasing sleep to 160ms
2026/02/03 08:56:58 DEBUG : pacer: low level retry 5/10 (error Error "Server Error")
2026/02/03 08:56:58 DEBUG : pacer: Rate limited, increasing sleep to 320ms
2026/02/03 08:56:58 DEBUG : pacer: low level retry 6/10 (error Error "Server Error")
2026/02/03 08:56:58 DEBUG : pacer: Rate limited, increasing sleep to 640ms
2026/02/03 08:56:58 DEBUG : pacer: low level retry 7/10 (error Error "Server Error")
2026/02/03 08:56:58 DEBUG : pacer: Rate limited, increasing sleep to 1.28s
2026/02/03 08:56:59 DEBUG : pacer: low level retry 8/10 (error Error "Server Error")
2026/02/03 08:56:59 DEBUG : pacer: Rate limited, increasing sleep to 2.56s
2026/02/03 08:57:00 DEBUG : pacer: low level retry 9/10 (error Error "Server Error")
2026/02/03 08:57:00 DEBUG : pacer: Rate limited, increasing sleep to 5.12s
2026/02/03 08:57:03 DEBUG : pacer: low level retry 10/10 (error Error "Server Error")
2026/02/03 08:57:03 DEBUG : pacer: Rate limited, increasing sleep to 10.24s
=== NAME TestCheckSumDownload
run.go:286: Retry Put of "test.sum" to drime root 'rclone-test-coxaqej4vimo': 1/10 (failed to delete existing object: failed to delete item: Error "Server Error")
2026/02/03 08:57:08 DEBUG : pacer: Reducing sleep to 5.12s
2026/02/03 08:57:22 DEBUG : pacer: Reducing sleep to 2.56s
2026/02/03 08:57:22 DEBUG : test.sum: Removing old object on successful upload
2026/02/03 08:57:23 DEBUG : pacer: Reducing sleep to 1.28s
2026/02/03 08:57:26 DEBUG : pacer: Reducing sleep to 640ms
2026/02/03 08:57:27 DEBUG : pacer: Reducing sleep to 320ms
fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3
fstest.go:301: Flushing the directory cache
2026/02/03 08:57:29 DEBUG : pacer: Reducing sleep to 160ms
2026/02/03 08:57:29 DEBUG : pacer: Reducing sleep to 80ms
2026/02/03 08:57:30 DEBUG : pacer: Reducing sleep to 40ms
fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3
fstest.go:301: Flushing the directory cache
2026/02/03 08:57:32 DEBUG : pacer: Reducing sleep to 20ms
2026/02/03 08:57:33 DEBUG : pacer: Reducing sleep to 10ms
fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3
fstest.go:301: Flushing the directory cache
fstest.go:305:
Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350
/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345
/home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:464
/home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:541
/usr/local/go/src/runtime/asm_amd64.s:1700
Error: Should be true
Test: TestCheckSumDownload
Messages: listing wrong, want
data/banana (13), data/potato (15), test.sum (82) got
data/banana (13), data/potato (15), test.sum (41), test.sum (82)
fstest.go:191:
Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350
/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345
/home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:464
/home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:541
Error: Should be true
Test: TestCheckSumDownload
Messages: Unexpected file "test.sum"
=== RUN TestCheckSumDownload/subtest3
2026/02/03 08:57:47 DEBUG : test.sum: Removing old object on successful upload
=== NAME TestCheckSumDownload
fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3
fstest.go:301: Flushing the directory cache
fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3
fstest.go:301: Flushing the directory cache
fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3
fstest.go:301: Flushing the directory cache
fstest.go:305:
Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350
/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345
/home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:478
/home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:541
/usr/local/go/src/runtime/asm_amd64.s:1700
Error: Should be true
Test: TestCheckSumDownload
Messages: listing wrong, want
data/banana (13), data/potato (15), test.sum (82) got
data/banana (13), data/potato (15), test.sum (41), test.sum (82)
fstest.go:191:
Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350
/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345
/home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:478
/home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:541
Error: Should be true
Test: TestCheckSumDownload
Messages: Unexpected file "test.sum"
=== RUN TestCheckSumDownload/subtest4
2026/02/03 08:58:02 DEBUG : test.sum: Removing old object on successful upload
=== NAME TestCheckSumDownload
fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3
fstest.go:301: Flushing the directory cache
fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3
fstest.go:301: Flushing the directory cache
fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3
fstest.go:301: Flushing the directory cache
fstest.go:305:
Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350
/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345
/home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:493
/home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:541
/usr/local/go/src/runtime/asm_amd64.s:1700
Error: Should be true
Test: TestCheckSumDownload
Messages: listing wrong, want
data/banana (13), data/potato (15), test.sum (123) got
data/banana (13), data/potato (15), test.sum (123), test.sum (41)
fstest.go:191:
Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350
/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345
/home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:493
/home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:541
Error: Should be true
Test: TestCheckSumDownload
Messages: Unexpected file "test.sum"
=== RUN TestCheckSumDownload/subtest5
2026/02/03 08:58:17 DEBUG : test.sum: Removing old object on successful upload
=== NAME TestCheckSumDownload
fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3
fstest.go:301: Flushing the directory cache
fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3
fstest.go:301: Flushing the directory cache
fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3
fstest.go:301: Flushing the directory cache
fstest.go:305:
Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350
/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345
/home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:508
/home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:541
/usr/local/go/src/runtime/asm_amd64.s:1700
Error: Should be true
Test: TestCheckSumDownload
Messages: listing wrong, want
data/banana (13), data/potato (15), test.sum (123) got
data/banana (13), data/potato (15), test.sum (123), test.sum (41)
fstest.go:191:
Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350
/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345
/home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:508
/home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:541
Error: Should be true
Test: TestCheckSumDownload
Messages: Unexpected file "test.sum"
=== RUN TestCheckSumDownload/subtest6
2026/02/03 08:58:34 DEBUG : data/banana: Removing old object on successful upload
2026/02/03 08:58:39 DEBUG : data/potato: Removing old object on successful upload
2026/02/03 08:58:43 DEBUG : test.sum: Removing old object on successful upload
=== NAME TestCheckSumDownload
fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3
fstest.go:301: Flushing the directory cache
fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3
fstest.go:301: Flushing the directory cache
fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3
fstest.go:301: Flushing the directory cache
fstest.go:305:
Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350
/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345
/home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:525
/home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:541
/usr/local/go/src/runtime/asm_amd64.s:1700
Error: Should be true
Test: TestCheckSumDownload
Messages: listing wrong, want
data/banana (13), data/potato (15), test.sum (82) got
data/banana (13), data/potato (15), test.sum (123), test.sum (41)
fstest.go:150:
Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:150
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:195
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350
/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345
/home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:525
/home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:541
Error: Not equal:
expected: 82
actual : 123
Test: TestCheckSumDownload
Messages: test.sum: size incorrect file=82 vs obj=123
fstest.go:191:
Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350
/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345
/home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:525
/home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:541
Error: Should be true
Test: TestCheckSumDownload
Messages: Unexpected file "test.sum"
=== RUN TestCheckSumDownload/subtest7
2026/02/03 08:58:55 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/02/03 08:58:55 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/02/03 08:58:56 DEBUG : pacer: Reducing sleep to 10ms
2026/02/03 08:58:56 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/02/03 08:58:56 DEBUG : pacer: low level retry 1/10 (error Error "500 Internal Server Error (500): \r\n500 Internal Server Error\r\n\r\n500 Internal Server Error
\r\n
nginx\r\n\r\n\r\n")
2026/02/03 08:58:56 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/02/03 08:58:56 DEBUG : pacer: Reducing sleep to 10ms
2026/02/03 08:58:56 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/02/03 08:58:56 DEBUG : pacer: low level retry 1/10 (error Error "500 Internal Server Error (500): \r\n500 Internal Server Error\r\n\r\n500 Internal Server Error
\r\n
nginx\r\n\r\n\r\n")
2026/02/03 08:58:56 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/02/03 08:58:57 DEBUG : pacer: Reducing sleep to 10ms
2026/02/03 08:58:57 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/02/03 08:58:57 DEBUG : pacer: low level retry 1/10 (error Error "500 Internal Server Error (500): \r\n500 Internal Server Error\r\n\r\n500 Internal Server Error
\r\n
nginx\r\n\r\n\r\n")
2026/02/03 08:58:57 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/02/03 08:58:57 DEBUG : pacer: Reducing sleep to 10ms
=== NAME TestCheckSumDownload
run.go:130: removing dir "data" failed - try 1/3: directory not empty
2026/02/03 08:58:58 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/02/03 08:58:58 DEBUG : pacer: low level retry 1/10 (error Error "500 Internal Server Error (500): \r\n500 Internal Server Error\r\n\r\n500 Internal Server Error
\r\n
nginx\r\n\r\n\r\n")
2026/02/03 08:58:58 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/02/03 08:58:58 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/02/03 08:58:58 DEBUG : pacer: low level retry 2/10 (error Error "500 Internal Server Error (500): \r\n500 Internal Server Error\r\n\r\n500 Internal Server Error
\r\n
nginx\r\n\r\n\r\n")
2026/02/03 08:58:58 DEBUG : pacer: Rate limited, increasing sleep to 40ms
2026/02/03 08:58:58 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/02/03 08:58:58 DEBUG : pacer: low level retry 3/10 (error Error "500 Internal Server Error (500): \r\n500 Internal Server Error\r\n\r\n500 Internal Server Error
\r\n
nginx\r\n\r\n\r\n")
2026/02/03 08:58:58 DEBUG : pacer: Rate limited, increasing sleep to 80ms
2026/02/03 08:58:58 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/02/03 08:58:58 DEBUG : pacer: low level retry 4/10 (error Error "500 Internal Server Error (500): \r\n500 Internal Server Error\r\n\r\n500 Internal Server Error
\r\n
nginx\r\n\r\n\r\n")
2026/02/03 08:58:58 DEBUG : pacer: Rate limited, increasing sleep to 160ms
2026/02/03 08:58:58 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/02/03 08:58:58 DEBUG : pacer: low level retry 5/10 (error Error "500 Internal Server Error (500): \r\n500 Internal Server Error\r\n\r\n500 Internal Server Error
\r\n
nginx\r\n\r\n\r\n")
2026/02/03 08:58:58 DEBUG : pacer: Rate limited, increasing sleep to 320ms
2026/02/03 08:58:59 DEBUG : pacer: Reducing sleep to 160ms
run.go:130: removing dir "data" failed - try 2/3: directory not empty
2026/02/03 08:59:00 DEBUG : pacer: Reducing sleep to 80ms
run.go:130: removing dir "data" failed - try 3/3: directory not empty
run.go:133: removing dir "data" failed: directory not empty
2026/02/03 08:59:01 DEBUG : pacer: Reducing sleep to 40ms
2026/02/03 08:59:01 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/02/03 08:59:01 DEBUG : pacer: low level retry 1/10 (error Error "500 Internal Server Error (500): \r\n500 Internal Server Error\r\n\r\n500 Internal Server Error
\r\n
nginx\r\n\r\n\r\n")
2026/02/03 08:59:01 DEBUG : pacer: Rate limited, increasing sleep to 80ms
2026/02/03 08:59:01 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/02/03 08:59:01 DEBUG : pacer: low level retry 2/10 (error Error "500 Internal Server Error (500): \r\n500 Internal Server Error\r\n\r\n500 Internal Server Error
\r\n
nginx\r\n\r\n\r\n")
2026/02/03 08:59:01 DEBUG : pacer: Rate limited, increasing sleep to 160ms
2026/02/03 08:59:01 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/02/03 08:59:01 DEBUG : pacer: low level retry 3/10 (error Error "500 Internal Server Error (500): \r\n500 Internal Server Error\r\n\r\n500 Internal Server Error
\r\n
nginx\r\n\r\n\r\n")
2026/02/03 08:59:01 DEBUG : pacer: Rate limited, increasing sleep to 320ms
2026/02/03 08:59:02 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/02/03 08:59:02 DEBUG : pacer: low level retry 4/10 (error Error "500 Internal Server Error (500): \r\n500 Internal Server Error\r\n\r\n500 Internal Server Error
\r\n
nginx\r\n\r\n\r\n")
2026/02/03 08:59:02 DEBUG : pacer: Rate limited, increasing sleep to 640ms
2026/02/03 08:59:02 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/02/03 08:59:02 DEBUG : pacer: low level retry 5/10 (error Error "500 Internal Server Error (500): \r\n500 Internal Server Error\r\n\r\n500 Internal Server Error
\r\n
nginx\r\n\r\n\r\n")
2026/02/03 08:59:02 DEBUG : pacer: Rate limited, increasing sleep to 1.28s
2026/02/03 08:59:03 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/02/03 08:59:03 DEBUG : pacer: low level retry 6/10 (error Error "500 Internal Server Error (500): \r\n500 Internal Server Error\r\n\r\n500 Internal Server Error
\r\n
nginx\r\n\r\n\r\n")
2026/02/03 08:59:03 DEBUG : pacer: Rate limited, increasing sleep to 2.56s
2026/02/03 08:59:04 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/02/03 08:59:04 DEBUG : pacer: low level retry 7/10 (error Error "500 Internal Server Error (500): \r\n500 Internal Server Error\r\n\r\n500 Internal Server Error
\r\n
nginx\r\n\r\n\r\n")
2026/02/03 08:59:04 DEBUG : pacer: Rate limited, increasing sleep to 5.12s
2026/02/03 08:59:06 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/02/03 08:59:06 DEBUG : pacer: low level retry 8/10 (error Error "500 Internal Server Error (500): \r\n500 Internal Server Error\r\n\r\n500 Internal Server Error
\r\n
nginx\r\n\r\n\r\n")
2026/02/03 08:59:06 DEBUG : pacer: Rate limited, increasing sleep to 10.24s
2026/02/03 08:59:11 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/02/03 08:59:11 DEBUG : pacer: low level retry 9/10 (error Error "500 Internal Server Error (500): \r\n500 Internal Server Error\r\n\r\n500 Internal Server Error
\r\n
nginx\r\n\r\n\r\n")
2026/02/03 08:59:11 DEBUG : pacer: Rate limited, increasing sleep to 20s
2026/02/03 08:59:22 DEBUG : pacer: Reducing sleep to 10s
fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3
fstest.go:301: Flushing the directory cache
2026/02/03 08:59:42 DEBUG : pacer: Reducing sleep to 5s
2026/02/03 08:59:52 DEBUG : pacer: Reducing sleep to 2.5s
2026/02/03 08:59:57 DEBUG : pacer: Reducing sleep to 1.25s
fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3
fstest.go:301: Flushing the directory cache
2026/02/03 08:59:59 DEBUG : pacer: Reducing sleep to 625ms
2026/02/03 09:00:01 DEBUG : pacer: Reducing sleep to 312.5ms
2026/02/03 09:00:01 DEBUG : pacer: Reducing sleep to 156.25ms
fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3
fstest.go:301: Flushing the directory cache
fstest.go:327:
Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:327
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338
/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178
/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393
/usr/local/go/src/testing/testing.go:1211
/usr/local/go/src/testing/testing.go:1445
/usr/local/go/src/testing/testing.go:1786
Error: Not equal:
expected: []string{}
actual : []string{"data"}
Diff:
--- Expected
+++ Actual
@@ -1,2 +1,3 @@
-([]string) {
+([]string) (len=1) {
+ (string) (len=4) "data"
}
Test: TestCheckSumDownload
Messages: directories
--- FAIL: TestCheckSumDownload (230.47s)
--- PASS: TestCheckSumDownload/subtest1 (1.91s)
--- PASS: TestCheckSumDownload/subtest2 (1.33s)
--- PASS: TestCheckSumDownload/subtest3 (2.40s)
--- PASS: TestCheckSumDownload/subtest4 (1.92s)
--- PASS: TestCheckSumDownload/subtest5 (1.77s)
--- PASS: TestCheckSumDownload/subtest6 (2.24s)
--- PASS: TestCheckSumDownload/subtest7 (2.37s)
=== RUN TestApplyTransforms
2026/02/03 09:00:05 DEBUG : Creating backend with remote "TestDrime:rclone-test-lozovev4fejo"
2026/02/03 09:00:05 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/02/03 09:00:06 DEBUG : Creating backend with remote "/tmp/rclone2718229756"
run.go:185: Remote "drime root 'rclone-test-lozovev4fejo'", Local "Local file system at /tmp/rclone2718229756", Modify Window "876000h0m0s"
upper checkfile vs. lower remote (without normalization)
2026/02/03 09:00:11 ERROR : HELLO, WORLD!: file not in drime root 'rclone-test-lozovev4fejo'
2026/02/03 09:00:11 NOTICE: drime root 'rclone-test-lozovev4fejo': 1 files missing
2026/02/03 09:00:11 NOTICE: drime root 'rclone-test-lozovev4fejo': 0 differences found
2026/02/03 09:00:11 NOTICE: drime root 'rclone-test-lozovev4fejo': 1 errors while checking
upper checkfile vs. lower remote (with normalization)
2026/02/03 09:00:12 ERROR : hello, world!: file not in drime root 'rclone-test-lozovev4fejo'
2026/02/03 09:00:12 NOTICE: drime root 'rclone-test-lozovev4fejo': 1 files missing
2026/02/03 09:00:12 NOTICE: drime root 'rclone-test-lozovev4fejo': 0 differences found
2026/02/03 09:00:12 NOTICE: drime root 'rclone-test-lozovev4fejo': 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-lozovev4fejo'
Test: TestApplyTransforms
Messages: unexpected error for upper checkfile vs. lower remote (with normalization) HELLO, WORLD! hello, world!
2026/02/03 09:00:12 DEBUG : Creating backend with remote "TestDrime:rclone-test-bamiqit7meze"
2026/02/03 09:00:12 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/02/03 09:00:13 DEBUG : Creating backend with remote "/tmp/rclone3610912962"
run.go:185: Remote "drime root 'rclone-test-bamiqit7meze'", Local "Local file system at /tmp/rclone3610912962", Modify Window "876000h0m0s"
lower checkfile vs. upper remote (without normalization)
2026/02/03 09:00:18 ERROR : hello, world!: file not in drime root 'rclone-test-bamiqit7meze'
2026/02/03 09:00:18 NOTICE: drime root 'rclone-test-bamiqit7meze': 1 files missing
2026/02/03 09:00:18 NOTICE: drime root 'rclone-test-bamiqit7meze': 0 differences found
2026/02/03 09:00:18 NOTICE: drime root 'rclone-test-bamiqit7meze': 1 errors while checking
lower checkfile vs. upper remote (with normalization)
2026/02/03 09:00:18 ERROR : hello, world!: file not in drime root 'rclone-test-bamiqit7meze'
2026/02/03 09:00:18 NOTICE: drime root 'rclone-test-bamiqit7meze': 1 files missing
2026/02/03 09:00:18 NOTICE: drime root 'rclone-test-bamiqit7meze': 0 differences found
2026/02/03 09:00:18 NOTICE: drime root 'rclone-test-bamiqit7meze': 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-bamiqit7meze'
Test: TestApplyTransforms
Messages: unexpected error for lower checkfile vs. upper remote (with normalization) hello, world! HELLO, WORLD!
2026/02/03 09:00:18 DEBUG : Creating backend with remote "TestDrime:rclone-test-qicanej3yile"
2026/02/03 09:00:18 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/02/03 09:00:19 DEBUG : Creating backend with remote "/tmp/rclone189860691"
run.go:185: Remote "drime root 'rclone-test-qicanej3yile'", Local "Local file system at /tmp/rclone189860691", Modify Window "876000h0m0s"
lower checkfile vs. upperlowermixed remote (without normalization)
2026/02/03 09:00:29 ERROR : hello, world!: file not in drime root 'rclone-test-qicanej3yile'
2026/02/03 09:00:29 NOTICE: drime root 'rclone-test-qicanej3yile': 1 files missing
2026/02/03 09:00:29 NOTICE: drime root 'rclone-test-qicanej3yile': 0 differences found
2026/02/03 09:00:29 NOTICE: drime root 'rclone-test-qicanej3yile': 1 errors while checking
lower checkfile vs. upperlowermixed remote (with normalization)
2026/02/03 09:00:29 ERROR : hello, world!: file not in drime root 'rclone-test-qicanej3yile'
2026/02/03 09:00:29 NOTICE: drime root 'rclone-test-qicanej3yile': 1 files missing
2026/02/03 09:00:29 NOTICE: drime root 'rclone-test-qicanej3yile': 0 differences found
2026/02/03 09:00:29 NOTICE: drime root 'rclone-test-qicanej3yile': 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-qicanej3yile'
Test: TestApplyTransforms
Messages: unexpected error for lower checkfile vs. upperlowermixed remote (with normalization) hello, world! HeLlO, wOrLd!
2026/02/03 09:00:29 DEBUG : Creating backend with remote "TestDrime:rclone-test-pusofor3bule"
2026/02/03 09:00:29 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/02/03 09:00:30 DEBUG : Creating backend with remote "/tmp/rclone2785600914"
run.go:185: Remote "drime root 'rclone-test-pusofor3bule'", Local "Local file system at /tmp/rclone2785600914", Modify Window "876000h0m0s"
upperlowermixed checkfile vs. upper remote (without normalization)
2026/02/03 09:00:33 ERROR : HeLlO, wOrLd!: file not in drime root 'rclone-test-pusofor3bule'
2026/02/03 09:00:33 NOTICE: drime root 'rclone-test-pusofor3bule': 1 files missing
2026/02/03 09:00:33 NOTICE: drime root 'rclone-test-pusofor3bule': 0 differences found
2026/02/03 09:00:33 NOTICE: drime root 'rclone-test-pusofor3bule': 1 errors while checking
upperlowermixed checkfile vs. upper remote (with normalization)
2026/02/03 09:00:33 ERROR : hello, world!: file not in drime root 'rclone-test-pusofor3bule'
2026/02/03 09:00:33 NOTICE: drime root 'rclone-test-pusofor3bule': 1 files missing
2026/02/03 09:00:33 NOTICE: drime root 'rclone-test-pusofor3bule': 0 differences found
2026/02/03 09:00:33 NOTICE: drime root 'rclone-test-pusofor3bule': 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-pusofor3bule'
Test: TestApplyTransforms
Messages: unexpected error for upperlowermixed checkfile vs. upper remote (with normalization) HeLlO, wOrLd! HELLO, WORLD!
2026/02/03 09:00:33 DEBUG : Creating backend with remote "TestDrime:rclone-test-pepasij0dida"
2026/02/03 09:00:33 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/02/03 09:00:34 DEBUG : Creating backend with remote "/tmp/rclone1286495645"
run.go:185: Remote "drime root 'rclone-test-pepasij0dida'", Local "Local file system at /tmp/rclone1286495645", Modify Window "876000h0m0s"
NFD checkfile vs. NFC remote (without normalization)
2026/02/03 09:00:38 ERROR : 測試_Русский___ě_áñ: file not in drime root 'rclone-test-pepasij0dida'
2026/02/03 09:00:38 NOTICE: drime root 'rclone-test-pepasij0dida': 1 files missing
2026/02/03 09:00:38 NOTICE: drime root 'rclone-test-pepasij0dida': 0 differences found
2026/02/03 09:00:38 NOTICE: drime root 'rclone-test-pepasij0dida': 1 errors while checking
NFD checkfile vs. NFC remote (with normalization)
2026/02/03 09:00:38 ERROR : 測試_русский___ě_áñ: file not in drime root 'rclone-test-pepasij0dida'
2026/02/03 09:00:38 NOTICE: drime root 'rclone-test-pepasij0dida': 1 files missing
2026/02/03 09:00:38 NOTICE: drime root 'rclone-test-pepasij0dida': 0 differences found
2026/02/03 09:00:38 NOTICE: drime root 'rclone-test-pepasij0dida': 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-pepasij0dida'
Test: TestApplyTransforms
Messages: unexpected error for NFD checkfile vs. NFC remote (with normalization) 測試_Русский___ě_áñ 測試_Русский___ě_áñ
2026/02/03 09:00:38 DEBUG : Creating backend with remote "TestDrime:rclone-test-tepahel5xiva"
2026/02/03 09:00:38 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/02/03 09:00:39 DEBUG : Creating backend with remote "/tmp/rclone2757414302"
run.go:185: Remote "drime root 'rclone-test-tepahel5xiva'", Local "Local file system at /tmp/rclone2757414302", Modify Window "876000h0m0s"
NFC checkfile vs. NFD remote (without normalization)
2026/02/03 09:00:44 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/02/03 09:00:44 DEBUG : pacer: low level retry 1/10 (error Error "500 Internal Server Error (500): \r\n500 Internal Server Error\r\n\r\n500 Internal Server Error
\r\n
nginx\r\n\r\n\r\n")
2026/02/03 09:00:44 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/02/03 09:00:44 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/02/03 09:00:44 DEBUG : pacer: low level retry 2/10 (error Error "500 Internal Server Error (500): \r\n500 Internal Server Error\r\n\r\n500 Internal Server Error
\r\n
nginx\r\n\r\n\r\n")
2026/02/03 09:00:44 DEBUG : pacer: Rate limited, increasing sleep to 40ms
2026/02/03 09:00:44 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/02/03 09:00:44 DEBUG : pacer: low level retry 3/10 (error Error "500 Internal Server Error (500): \r\n500 Internal Server Error\r\n\r\n500 Internal Server Error
\r\n
nginx\r\n\r\n\r\n")
2026/02/03 09:00:44 DEBUG : pacer: Rate limited, increasing sleep to 80ms
2026/02/03 09:00:45 DEBUG : pacer: Reducing sleep to 40ms
2026/02/03 09:00:45 ERROR : 測試_Русский___ě_áñ: file not in drime root 'rclone-test-tepahel5xiva'
2026/02/03 09:00:45 NOTICE: drime root 'rclone-test-tepahel5xiva': 1 files missing
2026/02/03 09:00:45 NOTICE: drime root 'rclone-test-tepahel5xiva': 0 differences found
2026/02/03 09:00:45 NOTICE: drime root 'rclone-test-tepahel5xiva': 1 errors while checking
NFC checkfile vs. NFD remote (with normalization)
2026/02/03 09:00:45 DEBUG : pacer: Reducing sleep to 20ms
2026/02/03 09:00:45 ERROR : 測試_русский___ě_áñ: file not in drime root 'rclone-test-tepahel5xiva'
2026/02/03 09:00:45 NOTICE: drime root 'rclone-test-tepahel5xiva': 1 files missing
2026/02/03 09:00:45 NOTICE: drime root 'rclone-test-tepahel5xiva': 0 differences found
2026/02/03 09:00:45 NOTICE: drime root 'rclone-test-tepahel5xiva': 1 errors while checking
check_test.go:591:
Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:591
/home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:599
Error: Received unexpected error:
file not in drime root 'rclone-test-tepahel5xiva'
Test: TestApplyTransforms
Messages: unexpected error for NFC checkfile vs. NFD remote (with normalization) 測試_Русский___ě_áñ 測試_Русский___ě_áñ
2026/02/03 09:00:45 DEBUG : Creating backend with remote "TestDrime:rclone-test-yaxizol9xabe"
2026/02/03 09:00:45 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/02/03 09:00:46 DEBUG : Creating backend with remote "/tmp/rclone3031759601"
run.go:185: Remote "drime root 'rclone-test-yaxizol9xabe'", Local "Local file system at /tmp/rclone3031759601", Modify Window "876000h0m0s"
2026/02/03 09:00:46 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/02/03 09:00:46 DEBUG : pacer: low level retry 1/10 (error Error "500 Internal Server Error (500): \r\n500 Internal Server Error\r\n\r\n500 Internal Server Error
\r\n
nginx\r\n\r\n\r\n")
2026/02/03 09:00:46 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/02/03 09:00:46 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/02/03 09:00:46 DEBUG : pacer: low level retry 2/10 (error Error "500 Internal Server Error (500): \r\n500 Internal Server Error\r\n\r\n500 Internal Server Error
\r\n
nginx\r\n\r\n\r\n")
2026/02/03 09:00:46 DEBUG : pacer: Rate limited, increasing sleep to 40ms
2026/02/03 09:00:47 DEBUG : pacer: Reducing sleep to 20ms
2026/02/03 09:00:47 DEBUG : pacer: Reducing sleep to 10ms
NFDx2 checkfile vs. both remote (without normalization)
2026/02/03 09:00:52 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in drime root 'rclone-test-yaxizol9xabe'
2026/02/03 09:00:52 NOTICE: drime root 'rclone-test-yaxizol9xabe': 1 files missing
2026/02/03 09:00:52 NOTICE: drime root 'rclone-test-yaxizol9xabe': 0 differences found
2026/02/03 09:00:52 NOTICE: drime root 'rclone-test-yaxizol9xabe': 1 errors while checking
NFDx2 checkfile vs. both remote (with normalization)
2026/02/03 09:00:52 ERROR : 測試_русский___ě_áñ測試_русский___ě_áñ: file not in drime root 'rclone-test-yaxizol9xabe'
2026/02/03 09:00:52 NOTICE: drime root 'rclone-test-yaxizol9xabe': 1 files missing
2026/02/03 09:00:52 NOTICE: drime root 'rclone-test-yaxizol9xabe': 0 differences found
2026/02/03 09:00:52 NOTICE: drime root 'rclone-test-yaxizol9xabe': 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-yaxizol9xabe'
Test: TestApplyTransforms
Messages: unexpected error for NFDx2 checkfile vs. both remote (with normalization) 測試_Русский___ě_áñ測試_Русский___ě_áñ 測試_Русский___ě_áñ測試_Русский___ě_áñ
2026/02/03 09:00:52 DEBUG : Creating backend with remote "TestDrime:rclone-test-ceqosuv7naci"
2026/02/03 09:00:52 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/02/03 09:00:52 DEBUG : Creating backend with remote "/tmp/rclone3172719652"
run.go:185: Remote "drime root 'rclone-test-ceqosuv7naci'", Local "Local file system at /tmp/rclone3172719652", Modify Window "876000h0m0s"
2026/02/03 09:00:52 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/02/03 09:00:52 DEBUG : pacer: low level retry 1/10 (error Error "500 Internal Server Error (500): \r\n500 Internal Server Error\r\n\r\n500 Internal Server Error
\r\n
nginx\r\n\r\n\r\n")
2026/02/03 09:00:52 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/02/03 09:00:53 DEBUG : pacer: Reducing sleep to 10ms
2026/02/03 09:00:56 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/02/03 09:00:56 DEBUG : pacer: low level retry 1/10 (error Error "500 Internal Server Error (500): \r\n500 Internal Server Error\r\n\r\n500 Internal Server Error
\r\n
nginx\r\n\r\n\r\n")
2026/02/03 09:00:56 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/02/03 09:00:56 DEBUG : pacer: Reducing sleep to 10ms
NFCx2 checkfile vs. both remote (without normalization)
2026/02/03 09:00:57 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in drime root 'rclone-test-ceqosuv7naci'
2026/02/03 09:00:57 NOTICE: drime root 'rclone-test-ceqosuv7naci': 1 files missing
2026/02/03 09:00:57 NOTICE: drime root 'rclone-test-ceqosuv7naci': 0 differences found
2026/02/03 09:00:57 NOTICE: drime root 'rclone-test-ceqosuv7naci': 1 errors while checking
NFCx2 checkfile vs. both remote (with normalization)
2026/02/03 09:00:57 ERROR : 測試_русский___ě_áñ測試_русский___ě_áñ: file not in drime root 'rclone-test-ceqosuv7naci'
2026/02/03 09:00:57 NOTICE: drime root 'rclone-test-ceqosuv7naci': 1 files missing
2026/02/03 09:00:57 NOTICE: drime root 'rclone-test-ceqosuv7naci': 0 differences found
2026/02/03 09:00:57 NOTICE: drime root 'rclone-test-ceqosuv7naci': 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-ceqosuv7naci'
Test: TestApplyTransforms
Messages: unexpected error for NFCx2 checkfile vs. both remote (with normalization) 測試_Русский___ě_áñ測試_Русский___ě_áñ 測試_Русский___ě_áñ測試_Русский___ě_áñ
2026/02/03 09:00:57 DEBUG : Creating backend with remote "TestDrime:rclone-test-joraxid9ceti"
2026/02/03 09:00:57 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/02/03 09:00:58 DEBUG : Creating backend with remote "/tmp/rclone3921549417"
run.go:185: Remote "drime root 'rclone-test-joraxid9ceti'", Local "Local file system at /tmp/rclone3921549417", Modify Window "876000h0m0s"
both checkfile vs. NFDx2 remote (without normalization)
2026/02/03 09:01:06 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in drime root 'rclone-test-joraxid9ceti'
2026/02/03 09:01:06 NOTICE: drime root 'rclone-test-joraxid9ceti': 1 files missing
2026/02/03 09:01:06 NOTICE: drime root 'rclone-test-joraxid9ceti': 0 differences found
2026/02/03 09:01:06 NOTICE: drime root 'rclone-test-joraxid9ceti': 1 errors while checking
both checkfile vs. NFDx2 remote (with normalization)
2026/02/03 09:01:06 ERROR : 測試_русский___ě_áñ測試_русский___ě_áñ: file not in drime root 'rclone-test-joraxid9ceti'
2026/02/03 09:01:06 NOTICE: drime root 'rclone-test-joraxid9ceti': 1 files missing
2026/02/03 09:01:06 NOTICE: drime root 'rclone-test-joraxid9ceti': 0 differences found
2026/02/03 09:01:06 NOTICE: drime root 'rclone-test-joraxid9ceti': 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-joraxid9ceti'
Test: TestApplyTransforms
Messages: unexpected error for both checkfile vs. NFDx2 remote (with normalization) 測試_Русский___ě_áñ測試_Русский___ě_áñ 測試_Русский___ě_áñ測試_Русский___ě_áñ
2026/02/03 09:01:06 DEBUG : Creating backend with remote "TestDrime:rclone-test-sofaqiq5niko"
2026/02/03 09:01:06 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/02/03 09:01:07 DEBUG : Creating backend with remote "/tmp/rclone260041562"
run.go:185: Remote "drime root 'rclone-test-sofaqiq5niko'", Local "Local file system at /tmp/rclone260041562", Modify Window "876000h0m0s"
both checkfile vs. NFCx2 remote (without normalization)
2026/02/03 09:01:14 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in drime root 'rclone-test-sofaqiq5niko'
2026/02/03 09:01:14 NOTICE: drime root 'rclone-test-sofaqiq5niko': 1 files missing
2026/02/03 09:01:14 NOTICE: drime root 'rclone-test-sofaqiq5niko': 0 differences found
2026/02/03 09:01:14 NOTICE: drime root 'rclone-test-sofaqiq5niko': 1 errors while checking
both checkfile vs. NFCx2 remote (with normalization)
2026/02/03 09:01:15 ERROR : 測試_русский___ě_áñ測試_русский___ě_áñ: file not in drime root 'rclone-test-sofaqiq5niko'
2026/02/03 09:01:15 NOTICE: drime root 'rclone-test-sofaqiq5niko': 1 files missing
2026/02/03 09:01:15 NOTICE: drime root 'rclone-test-sofaqiq5niko': 0 differences found
2026/02/03 09:01:15 NOTICE: drime root 'rclone-test-sofaqiq5niko': 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-sofaqiq5niko'
Test: TestApplyTransforms
Messages: unexpected error for both checkfile vs. NFCx2 remote (with normalization) 測試_Русский___ě_áñ測試_Русский___ě_áñ 測試_Русский___ě_áñ測試_Русский___ě_áñ
2026/02/03 09:01:15 DEBUG : drime root 'rclone-test-sofaqiq5niko': Purge remote
2026/02/03 09:01:15 DEBUG : drime root 'rclone-test-joraxid9ceti': Purge remote
2026/02/03 09:01:16 DEBUG : drime root 'rclone-test-ceqosuv7naci': Purge remote
2026/02/03 09:01:16 DEBUG : drime root 'rclone-test-yaxizol9xabe': Purge remote
2026/02/03 09:01:17 DEBUG : drime root 'rclone-test-tepahel5xiva': Purge remote
2026/02/03 09:01:17 DEBUG : pacer: Reducing sleep to 10ms
2026/02/03 09:01:17 DEBUG : drime root 'rclone-test-pepasij0dida': Purge remote
2026/02/03 09:01:17 DEBUG : drime root 'rclone-test-pusofor3bule': Purge remote
2026/02/03 09:01:18 DEBUG : drime root 'rclone-test-qicanej3yile': Purge remote
2026/02/03 09:01:18 DEBUG : drime root 'rclone-test-bamiqit7meze': Purge remote
2026/02/03 09:01:19 DEBUG : drime root 'rclone-test-lozovev4fejo': Purge remote
--- FAIL: TestApplyTransforms (74.14s)
=== RUN TestCopyFile
run.go:185: Remote "drime root 'rclone-test-coxaqej4vimo'", Local "Local file system at /tmp/rclone4087212002", Modify Window "876000h0m0s"
2026/02/03 09:01:20 DEBUG : pacer: Reducing sleep to 78.125ms
2026/02/03 09:01:21 DEBUG : pacer: Reducing sleep to 39.0625ms
2026/02/03 09:01:21 DEBUG : file1: Need to transfer - File not found at Destination
2026/02/03 09:01:21 DEBUG : pacer: Reducing sleep to 19.53125ms
2026/02/03 09:01:22 DEBUG : pacer: Reducing sleep to 10ms
2026/02/03 09:01:28 DEBUG : sub/file2: size = 14 OK
2026/02/03 09:01:28 INFO : file1: Copied (new) to: sub/file2
2026/02/03 09:01:29 DEBUG : sub/file2: size = 14 OK
2026/02/03 09:01:29 DEBUG : file1: Sizes identical
2026/02/03 09:01:29 DEBUG : file1: Unchanged skipping
2026/02/03 09:01:30 DEBUG : drime root 'rclone-test-coxaqej4vimo': 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 (28.99s)
=== RUN TestCopyFileCopyDest
run.go:185: Remote "drime root 'rclone-test-coxaqej4vimo'", Local "Local file system at /tmp/rclone4087212002", Modify Window "876000h0m0s"
2026/02/03 09:01:48 DEBUG : Creating backend with remote "TestDrime:rclone-test-coxaqej4vimo/dst"
2026/02/03 09:01:50 DEBUG : Creating backend with remote "TestDrime:rclone-test-coxaqej4vimo/CopyDest"
2026/02/03 09:01:51 DEBUG : one: Need to transfer - File not found at Destination
2026/02/03 09:02:01 DEBUG : one: size = 3 OK
2026/02/03 09:02:01 INFO : one: Copied (new)
fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3
fstest.go:301: Flushing the directory cache
fstest.go:292: Sleeping for 6s just to make sure
2026/02/03 09:02:11 DEBUG : one: size = 5 (Local file system at /tmp/rclone4087212002)
2026/02/03 09:02:11 DEBUG : one: size = 3 (drime root 'rclone-test-coxaqej4vimo/dst')
2026/02/03 09:02:11 DEBUG : one: Sizes differ
2026/02/03 09:02:16 DEBUG : one: Removing old object on successful upload
2026/02/03 09:02:16 DEBUG : one: size = 5 OK
2026/02/03 09:02:16 INFO : one: Copied (replaced existing)
2026/02/03 09:02:25 DEBUG : dst/one: Removing old object on successful upload
2026/02/03 09:02:32 DEBUG : Creating backend with remote "TestDrime:rclone-test-coxaqej4vimo/BackupDir"
2026/02/03 09:02:33 DEBUG : one: size = 5 (Local file system at /tmp/rclone4087212002)
2026/02/03 09:02:33 DEBUG : one: size = 3 (drime root 'rclone-test-coxaqej4vimo/dst')
2026/02/03 09:02:33 DEBUG : one: Sizes differ
2026/02/03 09:02:34 INFO : one: Moved (server-side)
2026/02/03 09:02:37 DEBUG : one: Removing old object on successful upload
2026/02/03 09:02:37 DEBUG : one: size = 5 OK
2026/02/03 09:02:37 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/03 09:03:03 DEBUG : two: Need to transfer - File not found at Destination
2026/02/03 09:03:04 DEBUG : two: size = 3 OK
2026/02/03 09:03:04 DEBUG : two: Sizes identical
2026/02/03 09:03:05 DEBUG : two: size = 3 OK
2026/02/03 09:03:05 INFO : two: Copied (server-side copy)
2026/02/03 09:03:05 DEBUG : two: Destination found in --copy-dest, using server-side copy
fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3
fstest.go:301: Flushing the directory cache
fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3
fstest.go:301: Flushing the directory cache
fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3
fstest.go:301: Flushing the directory cache
fstest.go:305:
Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350
/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345
/home/rclone/go/src/github.com/rclone/rclone/fs/operations/copy_test.go:342
Error: Should be true
Test: TestCopyFileCopyDest
Messages: listing wrong, want
BackupDir/one (3), CopyDest/one (5), CopyDest/two (3), dst/one (5), dst/two (3) got
CopyDest/one (5), CopyDest/two (3), dst/one (5)
fstest.go:203: Not found "BackupDir/one"
fstest.go:203: Not found "dst/two"
fstest.go:206:
Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:206
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:310
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350
/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345
/home/rclone/go/src/github.com/rclone/rclone/fs/operations/copy_test.go:342
/usr/local/go/src/runtime/asm_amd64.s:1700
Error: Not equal:
expected: 0
actual : 2
Test: TestCopyFileCopyDest
Messages: 2 objects not found
2026/02/03 09:03:15 DEBUG : two: size = 3 OK
2026/02/03 09:03:15 DEBUG : two: Sizes identical
2026/02/03 09:03:15 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/03 09:03:42 DEBUG : three: Need to transfer - File not found at Destination
2026/02/03 09:03:43 DEBUG : three: size = 7 (Local file system at /tmp/rclone4087212002)
2026/02/03 09:03:43 DEBUG : three: size = 5 (drime root 'rclone-test-coxaqej4vimo/CopyDest')
2026/02/03 09:03:43 DEBUG : three: Sizes differ
2026/02/03 09:03:43 DEBUG : three: Destination not found in --copy-dest
2026/02/03 09:03:47 DEBUG : three: size = 7 OK
2026/02/03 09:03:47 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 (148.81s)
=== RUN TestListDirSorted
run.go:185: Remote "drime root 'rclone-test-coxaqej4vimo'", Local "Local file system at /tmp/rclone4087212002", Modify Window "876000h0m0s"
fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3
fstest.go:301: Flushing the directory cache
fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3
fstest.go:301: Flushing the directory cache
fstest.go:292: Sleeping for 7s just to make sure
listdirsorted_test.go:57:
Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/listdirsorted_test.go:57
/home/rclone/go/src/github.com/rclone/rclone/fs/operations/listdirsorted_test.go: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
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
/usr/local/go/src/runtime/panic.go:636
/usr/local/go/src/testing/testing.go:1041
/home/rclone/go/src/github.com/rclone/rclone/fs/operations/listdirsorted_test.go:57
/home/rclone/go/src/github.com/rclone/rclone/fs/operations/listdirsorted_test.go:111
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: TestListDirSorted
Messages: directories
--- FAIL: TestListDirSorted (67.16s)
=== RUN TestListDirSortedFn
run.go:185: Remote "drime root 'rclone-test-coxaqej4vimo'", Local "Local file system at /tmp/rclone4087212002", Modify Window "876000h0m0s"
fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3
fstest.go:301: Flushing the directory cache
fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3
fstest.go:301: Flushing the directory cache
fstest.go:292: Sleeping for 7s just to make sure
2026/02/03 09:06:03 DEBUG : a.txt: Excluded (Size Filter)
2026/02/03 09:06:03 DEBUG : a.txt: Excluded
2026/02/03 09:06:03 DEBUG : sub dir/hello world2: Excluded (Size Filter)
2026/02/03 09:06:03 DEBUG : sub dir/hello world2: Excluded
2026/02/03 09:06:03 DEBUG : sub dir/hello world: Excluded (Size Filter)
2026/02/03 09:06:03 DEBUG : sub dir/hello world: Excluded
2026/02/03 09:06:04 DEBUG : sub dir/ignore dir: Excluded
2026/02/03 09:06:04 DEBUG : sub dir/hello world2: Excluded (Size Filter)
2026/02/03 09:06:04 DEBUG : sub dir/hello world2: Excluded
2026/02/03 09:06:04 DEBUG : sub dir/hello world: Excluded (Size Filter)
2026/02/03 09:06:04 DEBUG : sub dir/hello world: Excluded
2026/02/03 09:06:04 DEBUG : sub dir/ignore dir: Excluded
run.go:130: removing dir "sub dir/sub sub dir" failed - try 1/3: directory not empty
run.go:130: removing dir "sub dir/sub sub dir" failed - try 2/3: directory not empty
run.go:130: removing dir "sub dir/sub sub dir" failed - try 3/3: directory not empty
run.go:133: removing dir "sub dir/sub sub dir" failed: directory not empty
run.go:130: removing dir "sub dir/ignore dir" failed - try 1/3: directory not empty
run.go:130: removing dir "sub dir/ignore dir" failed - try 2/3: directory not empty
run.go:130: removing dir "sub dir/ignore dir" failed - try 3/3: directory not empty
run.go:133: removing dir "sub dir/ignore dir" failed: directory not empty
run.go:130: removing dir "sub dir" failed - try 1/3: directory not empty
run.go:130: removing dir "sub dir" failed - try 2/3: directory not empty
run.go:130: removing dir "sub dir" failed - try 3/3: directory not empty
run.go:133: removing dir "sub dir" failed: directory not empty
fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3
fstest.go:301: Flushing the directory cache
fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3
fstest.go:301: Flushing the directory cache
fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3
fstest.go:301: Flushing the directory cache
fstest.go:327:
Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:327
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338
/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178
/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393
/usr/local/go/src/testing/testing.go:1211
/usr/local/go/src/testing/testing.go:1445
/usr/local/go/src/testing/testing.go:1786
Error: Not equal:
expected: []string{}
actual : []string{"sub dir", "sub dir/ignore dir", "sub dir/sub sub dir"}
Diff:
--- Expected
+++ Actual
@@ -1,2 +1,5 @@
-([]string) {
+([]string) (len=3) {
+ (string) (len=7) "sub dir",
+ (string) (len=18) "sub dir/ignore dir",
+ (string) (len=19) "sub dir/sub sub dir"
}
Test: TestListDirSortedFn
Messages: directories
--- FAIL: TestListDirSortedFn (63.93s)
=== RUN TestListJSON
run.go:185: Remote "drime root 'rclone-test-coxaqej4vimo'", Local "Local file system at /tmp/rclone4087212002", 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
=== 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 : 6
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
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 (40.76s)
--- FAIL: TestListJSON/Default (0.30s)
--- FAIL: TestListJSON/DirsOnly (0.23s)
--- FAIL: TestListJSON/Recurse (0.89s)
--- FAIL: TestListJSON/Metadata (0.24s)
=== RUN TestLs
run.go:185: Remote "drime root 'rclone-test-coxaqej4vimo'", Local "Local file system at /tmp/rclone4087212002", 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: TestLs (20.97s)
=== RUN TestCount
run.go:185: Remote "drime root 'rclone-test-coxaqej4vimo'", Local "Local file system at /tmp/rclone4087212002", Modify Window "876000h0m0s"
run.go:130: removing dir "sub dir" failed - try 1/3: directory not empty
run.go:130: removing dir "sub dir" failed - try 2/3: directory not empty
run.go:130: removing dir "sub dir" failed - try 3/3: directory not empty
run.go:133: removing dir "sub dir" failed: directory not empty
fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3
fstest.go:301: Flushing the directory cache
fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3
fstest.go:301: Flushing the directory cache
fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3
fstest.go:301: Flushing the directory cache
fstest.go:327:
Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:327
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338
/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178
/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393
/usr/local/go/src/testing/testing.go:1211
/usr/local/go/src/testing/testing.go:1445
/usr/local/go/src/testing/testing.go:1786
Error: Not equal:
expected: []string{}
actual : []string{"sub dir"}
Diff:
--- Expected
+++ Actual
@@ -1,2 +1,3 @@
-([]string) {
+([]string) (len=1) {
+ (string) (len=7) "sub dir"
}
Test: TestCount
Messages: directories
--- FAIL: TestCount (34.36s)
=== RUN TestMaxDelete
run.go:185: Remote "drime root 'rclone-test-coxaqej4vimo'", Local "Local file system at /tmp/rclone4087212002", Modify Window "876000h0m0s"
2026/02/03 09:08:38 DEBUG : Waiting for deletions to finish
2026/02/03 09:08:38 ERROR : small: Got fatal error on delete: --max-delete threshold reached
2026/02/03 09:08:39 INFO : medium: Deleted
2026/02/03 09:08:39 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/03 09:08:40 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/02/03 09:08:40 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/02/03 09:08:40 DEBUG : pacer: low level retry 2/10 (error Error "Server Error")
2026/02/03 09:08:40 DEBUG : pacer: Rate limited, increasing sleep to 40ms
2026/02/03 09:08:40 DEBUG : pacer: low level retry 3/10 (error Error "Server Error")
2026/02/03 09:08:40 DEBUG : pacer: Rate limited, increasing sleep to 80ms
2026/02/03 09:08:40 DEBUG : pacer: low level retry 4/10 (error Error "Server Error")
2026/02/03 09:08:40 DEBUG : pacer: Rate limited, increasing sleep to 160ms
2026/02/03 09:08:41 DEBUG : pacer: low level retry 5/10 (error Error "Server Error")
2026/02/03 09:08:41 DEBUG : pacer: Rate limited, increasing sleep to 320ms
2026/02/03 09:08:41 DEBUG : pacer: low level retry 6/10 (error Error "Server Error")
2026/02/03 09:08:41 DEBUG : pacer: Rate limited, increasing sleep to 640ms
2026/02/03 09:08:41 DEBUG : pacer: low level retry 7/10 (error Error "Server Error")
2026/02/03 09:08:41 DEBUG : pacer: Rate limited, increasing sleep to 1.28s
2026/02/03 09:08:42 DEBUG : pacer: low level retry 8/10 (error Error "Server Error")
2026/02/03 09:08:42 DEBUG : pacer: Rate limited, increasing sleep to 2.56s
2026/02/03 09:08:43 DEBUG : pacer: low level retry 9/10 (error Error "Server Error")
2026/02/03 09:08:43 DEBUG : pacer: Rate limited, increasing sleep to 5.12s
2026/02/03 09:08:46 DEBUG : pacer: low level retry 10/10 (error Error "Server Error")
2026/02/03 09:08:46 DEBUG : pacer: Rate limited, increasing sleep to 10.24s
run.go:130: removing file "large" failed - try 1/3: failed to delete item: Error "Server Error"
2026/02/03 09:08:51 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/02/03 09:08:51 DEBUG : pacer: Rate limited, increasing sleep to 20s
2026/02/03 09:09:01 DEBUG : pacer: low level retry 2/10 (error Error "Server Error")
2026/02/03 09:09:21 DEBUG : pacer: low level retry 3/10 (error Error "Server Error")
2026/02/03 09:09:41 DEBUG : pacer: low level retry 4/10 (error Error "Server Error")
2026/02/03 09:10:01 DEBUG : pacer: low level retry 5/10 (error Error "Server Error")
2026/02/03 09:10:21 DEBUG : pacer: low level retry 6/10 (error Error "Server Error")
2026/02/03 09:10:41 DEBUG : pacer: low level retry 7/10 (error Error "Server Error")
2026/02/03 09:11:01 DEBUG : pacer: low level retry 8/10 (error Error "Server Error")
2026/02/03 09:11:21 DEBUG : pacer: low level retry 9/10 (error Error "Server Error")
2026/02/03 09:11:41 DEBUG : pacer: low level retry 10/10 (error Error "Server Error")
run.go:130: removing file "large" failed - try 2/3: failed to delete item: Error "Server Error"
2026/02/03 09:12:01 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/02/03 09:12:21 DEBUG : pacer: low level retry 2/10 (error Error "Server Error")
2026/02/03 09:12:41 DEBUG : pacer: low level retry 3/10 (error Error "Server Error")
2026/02/03 09:13:01 DEBUG : pacer: low level retry 4/10 (error Error "Server Error")
2026/02/03 09:13:21 DEBUG : pacer: low level retry 5/10 (error Error "Server Error")
2026/02/03 09:13:41 DEBUG : pacer: low level retry 6/10 (error Error "Server Error")
2026/02/03 09:14:01 DEBUG : pacer: low level retry 7/10 (error Error "Server Error")
2026/02/03 09:14:21 DEBUG : pacer: low level retry 8/10 (error Error "Server Error")
2026/02/03 09:14:41 DEBUG : pacer: low level retry 9/10 (error Error "Server Error")
2026/02/03 09:15:01 DEBUG : pacer: low level retry 10/10 (error Error "Server Error")
run.go:130: removing file "large" failed - try 3/3: failed to delete item: Error "Server Error"
run.go:133: removing file "large" failed: failed to delete item: Error "Server Error"
2026/02/03 09:15:21 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/02/03 09:15:41 DEBUG : pacer: low level retry 2/10 (error Error "Server Error")
2026/02/03 09:16:01 DEBUG : pacer: low level retry 3/10 (error Error "Server Error")
2026/02/03 09:16:21 DEBUG : pacer: low level retry 4/10 (error Error "Server Error")
2026/02/03 09:16:41 DEBUG : pacer: low level retry 5/10 (error Error "Server Error")
2026/02/03 09:17:01 DEBUG : pacer: low level retry 6/10 (error Error "Server Error")
2026/02/03 09:17:21 DEBUG : pacer: low level retry 7/10 (error Error "Server Error")
2026/02/03 09:17:42 DEBUG : pacer: low level retry 8/10 (error Error "Server Error")
2026/02/03 09:18:01 DEBUG : pacer: low level retry 9/10 (error Error "Server Error")
2026/02/03 09:18:21 DEBUG : pacer: low level retry 10/10 (error Error "Server Error")
run.go:130: removing file "medium" failed - try 1/3: failed to delete item: Error "Server Error"
2026/02/03 09:18:41 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/02/03 09:19:01 DEBUG : pacer: low level retry 2/10 (error Error "Server Error")
2026/02/03 09:19:21 DEBUG : pacer: low level retry 3/10 (error Post "https://app.drime.cloud/api/v1/file-entries/delete": read tcp [2a01:4f9:c011:405e::1]:58414->[2606:4700::6812:cb0]:443: read: connection reset by peer)
2026/02/03 09:19:41 DEBUG : pacer: low level retry 4/10 (error Error "Server Error")
2026/02/03 09:20:01 DEBUG : pacer: low level retry 5/10 (error Error "Server Error")
2026/02/03 09:20:21 DEBUG : pacer: low level retry 6/10 (error Error "Server Error")
2026/02/03 09:20:41 DEBUG : pacer: low level retry 7/10 (error Error "Server Error")
2026/02/03 09:21:01 DEBUG : pacer: low level retry 8/10 (error Error "Server Error")
2026/02/03 09:21:21 DEBUG : pacer: low level retry 9/10 (error Error "Server Error")
2026/02/03 09:21:41 DEBUG : pacer: low level retry 10/10 (error Error "Server Error")
run.go:130: removing file "medium" failed - try 2/3: failed to delete item: Error "Server Error"
2026/02/03 09:22:01 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/02/03 09:22:21 DEBUG : pacer: low level retry 2/10 (error Error "Server Error")
2026/02/03 09:22:41 DEBUG : pacer: low level retry 3/10 (error Error "Server Error")
2026/02/03 09:23:01 DEBUG : pacer: low level retry 4/10 (error Error "Server Error")
2026/02/03 09:23:21 DEBUG : pacer: low level retry 5/10 (error Error "Server Error")
2026/02/03 09:23:41 DEBUG : pacer: low level retry 6/10 (error Error "Server Error")
2026/02/03 09:24:01 DEBUG : pacer: low level retry 7/10 (error Error "Server Error")
2026/02/03 09:24:21 DEBUG : pacer: low level retry 8/10 (error Error "Server Error")
2026/02/03 09:24:41 DEBUG : pacer: low level retry 9/10 (error Error "Server Error")
2026/02/03 09:25:01 DEBUG : pacer: low level retry 10/10 (error Error "Server Error")
run.go:130: removing file "medium" failed - try 3/3: failed to delete item: Error "Server Error"
run.go:133: removing file "medium" failed: failed to delete item: Error "Server Error"
2026/02/03 09:25:21 DEBUG : pacer: Reducing sleep to 10s
2026/02/03 09:25:41 DEBUG : pacer: Reducing sleep to 5s
2026/02/03 09:25:51 DEBUG : pacer: Reducing sleep to 2.5s
2026/02/03 09:25:56 DEBUG : pacer: Reducing sleep to 1.25s
2026/02/03 09:25:59 DEBUG : pacer: Reducing sleep to 625ms
2026/02/03 09:26:00 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/02/03 09:26:00 DEBUG : pacer: low level retry 1/10 (error Error "500 Internal Server Error (500): \r\n500 Internal Server Error\r\n\r\n500 Internal Server Error
\r\n
nginx\r\n\r\n\r\n")
2026/02/03 09:26:00 DEBUG : pacer: Rate limited, increasing sleep to 1.25s
2026/02/03 09:26:00 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/02/03 09:26:00 DEBUG : pacer: low level retry 2/10 (error Error "500 Internal Server Error (500): \r\n500 Internal Server Error\r\n\r\n500 Internal Server Error
\r\n
nginx\r\n\r\n\r\n")
2026/02/03 09:26:00 DEBUG : pacer: Rate limited, increasing sleep to 2.5s
2026/02/03 09:26:02 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/02/03 09:26:02 DEBUG : pacer: low level retry 3/10 (error Error "500 Internal Server Error (500): \r\n500 Internal Server Error\r\n\r\n500 Internal Server Error
\r\n
nginx\r\n\r\n\r\n")
2026/02/03 09:26:02 DEBUG : pacer: Rate limited, increasing sleep to 5s
2026/02/03 09:26:04 DEBUG : pacer: Reducing sleep to 2.5s
2026/02/03 09:26:09 DEBUG : pacer: Reducing sleep to 1.25s
--- FAIL: TestMaxDelete (1084.88s)
=== RUN TestMaxDeleteSizeLargeFile
run.go:185: Remote "drime root 'rclone-test-coxaqej4vimo'", Local "Local file system at /tmp/rclone4087212002", Modify Window "876000h0m0s"
2026/02/03 09:26:12 DEBUG : pacer: Reducing sleep to 625ms
2026/02/03 09:26:15 DEBUG : pacer: Reducing sleep to 312.5ms
2026/02/03 09:26:15 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/02/03 09:26:15 DEBUG : pacer: low level retry 1/10 (error Error "500 Internal Server Error (500): \r\n500 Internal Server Error\r\n\r\n500 Internal Server Error
\r\n
nginx\r\n\r\n\r\n")
2026/02/03 09:26:15 DEBUG : pacer: Rate limited, increasing sleep to 625ms
2026/02/03 09:26:16 DEBUG : pacer: Reducing sleep to 312.5ms
2026/02/03 09:26:19 DEBUG : pacer: Reducing sleep to 156.25ms
2026/02/03 09:26:19 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/02/03 09:26:19 DEBUG : pacer: low level retry 1/10 (error Error "500 Internal Server Error (500): \r\n500 Internal Server Error\r\n\r\n500 Internal Server Error
\r\n
nginx\r\n\r\n\r\n")
2026/02/03 09:26:19 DEBUG : pacer: Rate limited, increasing sleep to 312.5ms
2026/02/03 09:26:19 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/02/03 09:26:19 DEBUG : pacer: low level retry 2/10 (error Error "500 Internal Server Error (500): \r\n500 Internal Server Error\r\n\r\n500 Internal Server Error
\r\n
nginx\r\n\r\n\r\n")
2026/02/03 09:26:19 DEBUG : pacer: Rate limited, increasing sleep to 625ms
2026/02/03 09:26:19 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/02/03 09:26:19 DEBUG : pacer: low level retry 3/10 (error Error "500 Internal Server Error (500): \r\n500 Internal Server Error\r\n\r\n500 Internal Server Error
\r\n
nginx\r\n\r\n\r\n")
2026/02/03 09:26:19 DEBUG : pacer: Rate limited, increasing sleep to 1.25s
2026/02/03 09:26:20 DEBUG : pacer: Reducing sleep to 625ms
2026/02/03 09:26:21 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/02/03 09:26:21 DEBUG : pacer: low level retry 1/1 (error Error "500 Internal Server Error (500): \r\n500 Internal Server Error\r\n\r\n500 Internal Server Error
\r\n
nginx\r\n\r\n\r\n")
2026/02/03 09:26:21 DEBUG : pacer: Rate limited, increasing sleep to 1.25s
run.go:286: Retry Put of "large" to drime root 'rclone-test-coxaqej4vimo': 1/10 (failed to upload file: Error "500 Internal Server Error (500): \r\n500 Internal Server Error\r\n\r\n500 Internal Server Error
\r\n
nginx\r\n\r\n\r\n")
2026/02/03 09:26:23 DEBUG : pacer: Reducing sleep to 625ms
2026/02/03 09:26:26 DEBUG : pacer: Reducing sleep to 312.5ms
2026/02/03 09:26:26 DEBUG : pacer: Reducing sleep to 156.25ms
fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3
fstest.go:301: Flushing the directory cache
2026/02/03 09:26:28 DEBUG : pacer: Reducing sleep to 78.125ms
2026/02/03 09:26:29 DEBUG : pacer: Reducing sleep to 39.0625ms
fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3
fstest.go:301: Flushing the directory cache
2026/02/03 09:26:31 DEBUG : pacer: Reducing sleep to 19.53125ms
2026/02/03 09:26:31 DEBUG : pacer: Reducing sleep to 10ms
fstest.go:292: Sleeping for 7s just to make sure
2026/02/03 09:26:38 DEBUG : Waiting for deletions to finish
2026/02/03 09:26:39 ERROR : large: Got fatal error on delete: --max-delete-size threshold reached
2026/02/03 09:26:39 INFO : small: Deleted
2026/02/03 09:26:39 INFO : medium: Deleted
fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3
fstest.go:301: Flushing the directory cache
fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3
fstest.go:301: Flushing the directory cache
fstest.go:292: Sleeping for 7s just to make sure
fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3
fstest.go:301: Flushing the directory cache
fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3
fstest.go:301: Flushing the directory cache
fstest.go:292: Sleeping for 7s just to make sure
--- PASS: TestMaxDeleteSizeLargeFile (53.07s)
=== RUN TestMaxDeleteSize
run.go:185: Remote "drime root 'rclone-test-coxaqej4vimo'", Local "Local file system at /tmp/rclone4087212002", Modify Window "876000h0m0s"
fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3
fstest.go:301: Flushing the directory cache
fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3
fstest.go:301: Flushing the directory cache
fstest.go:292: Sleeping for 7s just to make sure
2026/02/03 09:27:25 DEBUG : Waiting for deletions to finish
2026/02/03 09:27:25 ERROR : small: Got fatal error on delete: --max-delete-size threshold reached
2026/02/03 09:27:25 INFO : large: Deleted
2026/02/03 09:27:25 INFO : medium: Deleted
operations_test.go:492:
Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:492
Error: Not equal:
expected: 1
actual : 3
Test: TestMaxDeleteSize
2026/02/03 09:27:26 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/02/03 09:27:26 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/02/03 09:27:26 DEBUG : pacer: low level retry 2/10 (error Error "Server Error")
2026/02/03 09:27:26 DEBUG : pacer: Rate limited, increasing sleep to 40ms
2026/02/03 09:27:26 DEBUG : pacer: low level retry 3/10 (error Error "Server Error")
2026/02/03 09:27:26 DEBUG : pacer: Rate limited, increasing sleep to 80ms
2026/02/03 09:27:27 DEBUG : pacer: low level retry 4/10 (error Error "Server Error")
2026/02/03 09:27:27 DEBUG : pacer: Rate limited, increasing sleep to 160ms
2026/02/03 09:27:27 DEBUG : pacer: low level retry 5/10 (error Error "Server Error")
2026/02/03 09:27:27 DEBUG : pacer: Rate limited, increasing sleep to 320ms
2026/02/03 09:27:27 DEBUG : pacer: low level retry 6/10 (error Error "Server Error")
2026/02/03 09:27:27 DEBUG : pacer: Rate limited, increasing sleep to 640ms
2026/02/03 09:27:28 DEBUG : pacer: low level retry 7/10 (error Error "Server Error")
2026/02/03 09:27:28 DEBUG : pacer: Rate limited, increasing sleep to 1.28s
2026/02/03 09:27:29 DEBUG : pacer: low level retry 8/10 (error Error "Server Error")
2026/02/03 09:27:29 DEBUG : pacer: Rate limited, increasing sleep to 2.56s
2026/02/03 09:27:29 DEBUG : pacer: low level retry 9/10 (error Error "Server Error")
2026/02/03 09:27:29 DEBUG : pacer: Rate limited, increasing sleep to 5.12s
2026/02/03 09:27:32 DEBUG : pacer: low level retry 10/10 (error Error "Server Error")
2026/02/03 09:27:32 DEBUG : pacer: Rate limited, increasing sleep to 10.24s
run.go:130: removing file "large" failed - try 1/3: failed to delete item: Error "Server Error"
2026/02/03 09:27:37 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/02/03 09:27:37 DEBUG : pacer: Rate limited, increasing sleep to 20s
2026/02/03 09:27:47 DEBUG : pacer: low level retry 2/10 (error Error "Server Error")
2026/02/03 09:28:08 DEBUG : pacer: low level retry 3/10 (error Error "Server Error")
2026/02/03 09:28:27 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/02/03 09:28:27 DEBUG : pacer: low level retry 4/10 (error Error "500 Internal Server Error (500): \r\n500 Internal Server Error\r\n\r\n500 Internal Server Error
\r\n
nginx\r\n\r\n\r\n")
2026/02/03 09:28:47 DEBUG : pacer: low level retry 5/10 (error Error "Server Error")
2026/02/03 09:29:07 DEBUG : pacer: low level retry 6/10 (error Error "Server Error")
2026/02/03 09:29:29 DEBUG : pacer: low level retry 7/10 (error Error "Server Error")
2026/02/03 09:29:47 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/02/03 09:29:47 DEBUG : pacer: low level retry 8/10 (error Error "500 Internal Server Error (500): \r\n500 Internal Server Error\r\n\r\n500 Internal Server Error
\r\n
nginx\r\n\r\n\r\n")
2026/02/03 09:30:07 DEBUG : pacer: low level retry 9/10 (error Error "Server Error")
2026/02/03 09:30:28 DEBUG : pacer: low level retry 10/10 (error Error "Server Error")
run.go:130: removing file "large" failed - try 2/3: failed to delete item: Error "Server Error"
2026/02/03 09:30:47 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/02/03 09:31:07 DEBUG : pacer: low level retry 2/10 (error Error "Server Error")
2026/02/03 09:31:28 DEBUG : pacer: low level retry 3/10 (error Error "Server Error")
2026/02/03 09:31:47 DEBUG : pacer: low level retry 4/10 (error Error "Server Error")
2026/02/03 09:32:07 DEBUG : pacer: low level retry 5/10 (error Error "Server Error")
2026/02/03 09:32:27 DEBUG : pacer: low level retry 6/10 (error Error "Server Error")
2026/02/03 09:32:47 DEBUG : pacer: low level retry 7/10 (error Error "Server Error")
2026/02/03 09:33:07 DEBUG : pacer: low level retry 8/10 (error Error "Server Error")
2026/02/03 09:33:28 DEBUG : pacer: low level retry 9/10 (error Error "Server Error")
2026/02/03 09:33:47 DEBUG : pacer: low level retry 10/10 (error Error "Server Error")
run.go:130: removing file "large" failed - try 3/3: failed to delete item: Error "Server Error"
run.go:133: removing file "large" failed: failed to delete item: Error "Server Error"
2026/02/03 09:34:07 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/02/03 09:34:28 DEBUG : pacer: low level retry 2/10 (error Error "Server Error")
2026/02/03 09:34:48 DEBUG : pacer: low level retry 3/10 (error Error "Server Error")
2026/02/03 09:35:07 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/02/03 09:35:07 DEBUG : pacer: low level retry 4/10 (error Error "500 Internal Server Error (500): \r\n500 Internal Server Error\r\n\r\n500 Internal Server Error
\r\n
nginx\r\n\r\n\r\n")
2026/02/03 09:35:30 DEBUG : pacer: low level retry 5/10 (error Error "Server Error")
2026/02/03 09:35:47 DEBUG : pacer: low level retry 6/10 (error Error "Server Error")
2026/02/03 09:36:07 DEBUG : pacer: low level retry 7/10 (error Error "Server Error")
2026/02/03 09:36:28 DEBUG : pacer: low level retry 8/10 (error Error "Server Error")
2026/02/03 09:36:47 DEBUG : pacer: low level retry 9/10 (error Error "Server Error")
2026/02/03 09:37:07 DEBUG : pacer: low level retry 10/10 (error Error "Server Error")
run.go:130: removing file "medium" failed - try 1/3: failed to delete item: Error "Server Error"
2026/02/03 09:37:28 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/02/03 09:37:47 DEBUG : pacer: low level retry 2/10 (error Error "Server Error")
2026/02/03 09:38:08 DEBUG : pacer: low level retry 3/10 (error Error "Server Error")
2026/02/03 09:38:27 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/02/03 09:38:27 DEBUG : pacer: low level retry 4/10 (error Error "500 Internal Server Error (500): \r\n500 Internal Server Error\r\n\r\n500 Internal Server Error
\r\n
nginx\r\n\r\n\r\n")
2026/02/03 09:38:47 DEBUG : pacer: low level retry 5/10 (error Error "Server Error")
2026/02/03 09:39:07 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/02/03 09:39:07 DEBUG : pacer: low level retry 6/10 (error Error "500 Internal Server Error (500): \r\n500 Internal Server Error\r\n\r\n500 Internal Server Error
\r\n
nginx\r\n\r\n\r\n")
2026/02/03 09:39:28 DEBUG : pacer: low level retry 7/10 (error Error "Server Error")
2026/02/03 09:39:47 DEBUG : pacer: low level retry 8/10 (error Error "Server Error")
2026/02/03 09:40:07 DEBUG : pacer: low level retry 9/10 (error Error "Server Error")
2026/02/03 09:40:27 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/02/03 09:40:27 DEBUG : pacer: low level retry 10/10 (error Error "500 Internal Server Error (500): \r\n500 Internal Server Error\r\n\r\n500 Internal Server Error
\r\n
nginx\r\n\r\n\r\n")
run.go:130: removing file "medium" failed - try 2/3: failed to delete item: Error "500 Internal Server Error (500): \r\n500 Internal Server Error\r\n\r\n500 Internal Server Error
\r\n
nginx\r\n\r\n\r\n"
2026/02/03 09:40:47 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/02/03 09:41:07 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/02/03 09:41:07 DEBUG : pacer: low level retry 2/10 (error Error "500 Internal Server Error (500): \r\n500 Internal Server Error\r\n\r\n500 Internal Server Error
\r\n
nginx\r\n\r\n\r\n")
2026/02/03 09:41:28 DEBUG : pacer: low level retry 3/10 (error Error "Server Error")
2026/02/03 09:41:47 DEBUG : pacer: low level retry 4/10 (error Error "Server Error")
2026/02/03 09:42:08 DEBUG : pacer: low level retry 5/10 (error Error "Server Error")
2026/02/03 09:42:27 DEBUG : pacer: low level retry 6/10 (error Error "Server Error")
2026/02/03 09:42:47 DEBUG : pacer: low level retry 7/10 (error Error "Server Error")
2026/02/03 09:43:07 DEBUG : pacer: low level retry 8/10 (error Error "Server Error")
2026/02/03 09:43:28 DEBUG : pacer: low level retry 9/10 (error Error "Server Error")
2026/02/03 09:43:47 DEBUG : pacer: low level retry 10/10 (error Error "Server Error")
run.go:130: removing file "medium" failed - try 3/3: failed to delete item: Error "Server Error"
run.go:133: removing file "medium" failed: failed to delete item: Error "Server Error"
2026/02/03 09:44:07 DEBUG : pacer: Reducing sleep to 10s
2026/02/03 09:44:28 DEBUG : pacer: Reducing sleep to 5s
2026/02/03 09:44:37 DEBUG : pacer: Reducing sleep to 2.5s
2026/02/03 09:44:43 DEBUG : pacer: Reducing sleep to 1.25s
--- FAIL: TestMaxDeleteSize (1060.17s)
=== RUN TestReadFile
run.go:185: Remote "drime root 'rclone-test-coxaqej4vimo'", Local "Local file system at /tmp/rclone4087212002", Modify Window "876000h0m0s"
2026/02/03 09:44:45 DEBUG : pacer: Reducing sleep to 625ms
2026/02/03 09:44:47 DEBUG : pacer: Reducing sleep to 312.5ms
2026/02/03 09:44:48 DEBUG : pacer: Reducing sleep to 156.25ms
fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3
fstest.go:301: Flushing the directory cache
2026/02/03 09:44:49 DEBUG : pacer: Reducing sleep to 78.125ms
2026/02/03 09:44:49 DEBUG : pacer: Reducing sleep to 39.0625ms
fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3
fstest.go:301: Flushing the directory cache
2026/02/03 09:44:51 DEBUG : pacer: Reducing sleep to 19.53125ms
2026/02/03 09:44:52 DEBUG : pacer: Reducing sleep to 10ms
fstest.go:292: Sleeping for 7s just to make sure
fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3
fstest.go:301: Flushing the directory cache
fstest.go:292: Sleeping for 6s just to make sure
--- PASS: TestReadFile (25.61s)
=== RUN TestCat
run.go:185: Remote "drime root 'rclone-test-coxaqej4vimo'", Local "Local file system at /tmp/rclone4087212002", Modify Window "876000h0m0s"
fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3
fstest.go:301: Flushing the directory cache
fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3
fstest.go:301: Flushing the directory cache
fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3
fstest.go:301: Flushing the directory cache
fstest.go:305:
Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350
/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345
/home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:545
Error: Should be true
Test: TestCat
Messages: listing wrong, want
file1 (10), file2 (9) got
file1 (10)
fstest.go:203: Not found "file2"
fstest.go:206:
Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:206
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:310
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338
/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350
/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345
/home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:545
/usr/local/go/src/runtime/asm_amd64.s:1700
Error: Not equal:
expected: 0
actual : 1
Test: TestCat
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:292: Sleeping for 7s just to make sure
--- FAIL: TestCat (35.44s)
FAIL
2026/02/03 09:45:44 DEBUG : drime root 'rclone-test-coxaqej4vimo': Purge remote
"./operations.test -test.v -test.timeout 2h0m0s -remote TestDrime: -verbose -test.run '^(TestApplyTransforms|TestCat|TestCheckSumDownload|TestCopyFile|TestCopyFileCopyDest|TestCount|TestListDirSorted|TestListDirSortedFn|TestLs|TestMaxDelete|TestMaxDeleteSize|TestMaxDeleteSizeLargeFile|TestParseSumFile|TestReadFile)$|^TestListJSON$/^(Default|DirsOnly|Metadata|Recurse)$|^TestMultithreadCopy$/^(upload=false,size=10485759,streams=2|upload=true,size=10485759,streams=2|upload=true,size=10485760,streams=2|upload=true,size=10485761,streams=2)$'" - Finished ERROR in 58m21.013858501s (try 5/5): exit status 1: Failed [TestMultithreadCopy/upload=false,size=10485759,streams=2 TestMultithreadCopy/upload=true,size=10485759,streams=2 TestMultithreadCopy/upload=true,size=10485760,streams=2 TestMultithreadCopy/upload=true,size=10485761,streams=2 TestParseSumFile TestCheckSumDownload TestApplyTransforms TestCopyFile TestCopyFileCopyDest TestListDirSorted TestListDirSortedFn TestListJSON/Default TestListJSON/DirsOnly TestListJSON/Recurse TestListJSON/Metadata TestCount TestMaxDelete TestMaxDeleteSize TestCat]