"./operations.test -test.v -test.timeout 2h0m0s -remote TestDrime: -verbose" - Starting (try 1/5)
2026/03/24 04:57:27 DEBUG : Creating backend with remote "TestDrime:rclone-test-nezuzih4tupa"
2026/03/24 04:57:27 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/03/24 04:57:39 DEBUG : Creating backend with remote "/tmp/rclone819691579"
=== RUN TestDoMultiThreadCopy
--- PASS: TestDoMultiThreadCopy (0.00s)
=== RUN TestMultithreadCalculateNumChunks
=== RUN TestMultithreadCalculateNumChunks/{size:1_chunkSize:65536_wantNumChunks:1}
=== RUN TestMultithreadCalculateNumChunks/{size:1048576_chunkSize:1_wantNumChunks:1048576}
=== RUN TestMultithreadCalculateNumChunks/{size:1048576_chunkSize:2_wantNumChunks:524288}
=== RUN TestMultithreadCalculateNumChunks/{size:1048577_chunkSize:2_wantNumChunks:524289}
=== RUN TestMultithreadCalculateNumChunks/{size:1048575_chunkSize:2_wantNumChunks:524288}
--- PASS: TestMultithreadCalculateNumChunks (0.00s)
--- PASS: TestMultithreadCalculateNumChunks/{size:1_chunkSize:65536_wantNumChunks:1} (0.00s)
--- PASS: TestMultithreadCalculateNumChunks/{size:1048576_chunkSize:1_wantNumChunks:1048576} (0.00s)
--- PASS: TestMultithreadCalculateNumChunks/{size:1048576_chunkSize:2_wantNumChunks:524288} (0.00s)
--- PASS: TestMultithreadCalculateNumChunks/{size:1048577_chunkSize:2_wantNumChunks:524289} (0.00s)
--- PASS: TestMultithreadCalculateNumChunks/{size:1048575_chunkSize:2_wantNumChunks:524288} (0.00s)
=== RUN TestMultithreadCopy
run.go:185: Remote "drime root 'rclone-test-nezuzih4tupa'", Local "Local file system at /tmp/rclone819691579", Modify Window "876000h0m0s"
multithread_test.go:137:
Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/multithread_test.go:137
/home/rclone/go/src/github.com/rclone/rclone/fs/operations/multithread_test.go:148
Error: Received unexpected error:
failed to initiate multipart upload: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan."
Test: TestMultithreadCopy
--- FAIL: TestMultithreadCopy (20.72s)
=== RUN TestMultithreadCopyAbort
run.go:185: Remote "drime root 'rclone-test-nezuzih4tupa'", Local "Local file system at /tmp/rclone819691579", Modify Window "876000h0m0s"
multithread_test.go:137:
Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/multithread_test.go:137
/home/rclone/go/src/github.com/rclone/rclone/fs/operations/multithread_test.go:293
Error: Received unexpected error:
failed to initiate multipart upload: Error "You have exhausted your allowed space of 20,480.0MB. Delete some files or upgrade your plan."
Test: TestMultithreadCopyAbort
--- FAIL: TestMultithreadCopyAbort (21.16s)
=== RUN TestSizeDiffers
2026/03/24 04:58:21 DEBUG : a: size = 0 OK
2026/03/24 04:58:21 DEBUG : a: size = 1 (memory)
2026/03/24 04:58:21 DEBUG : a: size = 2 (memory)
--- PASS: TestSizeDiffers (0.00s)
=== RUN TestReOpen
=== RUN TestReOpen/Normal
=== RUN TestReOpen/Normal/Basics
2026/03/24 04:58:21 DEBUG : potato: Seek from 10 to 0
=== RUN TestReOpen/Normal/ErrorAtStart
=== RUN TestReOpen/Normal/WithErrors
2026/03/24 04:58:21 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error
2026/03/24 04:58:21 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error
2026/03/24 04:58:21 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error
=== RUN TestReOpen/Normal/TooManyErrors
2026/03/24 04:58:21 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error
2026/03/24 04:58:21 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error
2026/03/24 04:58:21 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error
2026/03/24 04:58:21 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries
=== RUN TestReOpen/Normal/ReadAt
2026/03/24 04:58:21 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error
2026/03/24 04:58:21 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error
2026/03/24 04:58:21 DEBUG : potato: Seek from 5 to 1
=== RUN TestReOpen/Normal/Seek
2026/03/24 04:58:21 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error
2026/03/24 04:58:21 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error
2026/03/24 04:58:21 DEBUG : potato: Seek from 5 to 2
=== RUN TestReOpen/Normal/AccountRead
=== RUN TestReOpen/Normal/AccountReadDelay
2026/03/24 04:58:21 DEBUG : potato: Seek from 10 to 0
2026/03/24 04:58:21 DEBUG : potato: Seek from 10 to 0
2026/03/24 04:58:21 DEBUG : potato: Seek from 10 to 0
=== RUN TestReOpen/Normal/AccountReadError
=== RUN TestReOpen/WithRangeOption
=== RUN TestReOpen/WithRangeOption/Basics
2026/03/24 04:58:21 DEBUG : potato: Seek from 7 to 0
=== RUN TestReOpen/WithRangeOption/ErrorAtStart
=== RUN TestReOpen/WithRangeOption/WithErrors
2026/03/24 04:58:21 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error
2026/03/24 04:58:21 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error
2026/03/24 04:58:21 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error
=== RUN TestReOpen/WithRangeOption/TooManyErrors
2026/03/24 04:58:21 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error
2026/03/24 04:58:21 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error
2026/03/24 04:58:21 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error
2026/03/24 04:58:21 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries
=== RUN TestReOpen/WithRangeOption/ReadAt
2026/03/24 04:58:21 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error
2026/03/24 04:58:21 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error
2026/03/24 04:58:21 DEBUG : potato: Seek from 5 to 1
=== RUN TestReOpen/WithRangeOption/Seek
2026/03/24 04:58:21 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error
2026/03/24 04:58:21 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error
2026/03/24 04:58:21 DEBUG : potato: Seek from 5 to 2
2026/03/24 04:58:21 DEBUG : potato: Seek from 7 to 4
=== RUN TestReOpen/WithRangeOption/AccountRead
=== RUN TestReOpen/WithRangeOption/AccountReadDelay
2026/03/24 04:58:21 DEBUG : potato: Seek from 7 to 0
2026/03/24 04:58:21 DEBUG : potato: Seek from 7 to 0
2026/03/24 04:58:21 DEBUG : potato: Seek from 7 to 0
=== RUN TestReOpen/WithRangeOption/AccountReadError
=== RUN TestReOpen/WithSeekOption
=== RUN TestReOpen/WithSeekOption/Basics
2026/03/24 04:58:21 DEBUG : potato: Seek from 8 to 0
=== RUN TestReOpen/WithSeekOption/ErrorAtStart
=== RUN TestReOpen/WithSeekOption/WithErrors
2026/03/24 04:58:21 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error
2026/03/24 04:58:21 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error
2026/03/24 04:58:21 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error
=== RUN TestReOpen/WithSeekOption/TooManyErrors
2026/03/24 04:58:21 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error
2026/03/24 04:58:21 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error
2026/03/24 04:58:21 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error
2026/03/24 04:58:21 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries
=== RUN TestReOpen/WithSeekOption/ReadAt
2026/03/24 04:58:21 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error
2026/03/24 04:58:21 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error
2026/03/24 04:58:21 DEBUG : potato: Seek from 5 to 1
=== RUN TestReOpen/WithSeekOption/Seek
2026/03/24 04:58:21 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error
2026/03/24 04:58:21 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error
2026/03/24 04:58:21 DEBUG : potato: Seek from 5 to 2
2026/03/24 04:58:21 DEBUG : potato: Seek from 7 to 5
=== RUN TestReOpen/WithSeekOption/AccountRead
=== RUN TestReOpen/WithSeekOption/AccountReadDelay
2026/03/24 04:58:21 DEBUG : potato: Seek from 8 to 0
2026/03/24 04:58:21 DEBUG : potato: Seek from 8 to 0
2026/03/24 04:58:21 DEBUG : potato: Seek from 8 to 0
=== RUN TestReOpen/WithSeekOption/AccountReadError
=== RUN TestReOpen/UnknownSize
=== RUN TestReOpen/UnknownSize/Basics
2026/03/24 04:58:21 DEBUG : potato: Seek from 9 to 0
=== RUN TestReOpen/UnknownSize/ErrorAtStart
=== RUN TestReOpen/UnknownSize/WithErrors
2026/03/24 04:58:21 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error
2026/03/24 04:58:21 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error
2026/03/24 04:58:21 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error
=== RUN TestReOpen/UnknownSize/TooManyErrors
2026/03/24 04:58:21 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error
2026/03/24 04:58:21 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error
2026/03/24 04:58:21 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error
2026/03/24 04:58:21 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries
=== RUN TestReOpen/UnknownSize/ReadAt
2026/03/24 04:58:21 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error
2026/03/24 04:58:21 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error
2026/03/24 04:58:21 DEBUG : potato: Seek from 5 to 1
=== RUN TestReOpen/UnknownSize/Seek
2026/03/24 04:58:21 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error
2026/03/24 04:58:21 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error
2026/03/24 04:58:21 DEBUG : potato: Seek from 5 to 2
2026/03/24 04:58:21 DEBUG : potato: Seek from 7 to 6
=== RUN TestReOpen/UnknownSize/AccountRead
=== RUN TestReOpen/UnknownSize/AccountReadDelay
2026/03/24 04:58:21 DEBUG : potato: Seek from 9 to 0
2026/03/24 04:58:21 DEBUG : potato: Seek from 9 to 0
2026/03/24 04:58:21 DEBUG : potato: Seek from 9 to 0
=== RUN TestReOpen/UnknownSize/AccountReadError
--- PASS: TestReOpen (0.00s)
--- PASS: TestReOpen/Normal (0.00s)
--- PASS: TestReOpen/Normal/Basics (0.00s)
--- PASS: TestReOpen/Normal/ErrorAtStart (0.00s)
--- PASS: TestReOpen/Normal/WithErrors (0.00s)
--- PASS: TestReOpen/Normal/TooManyErrors (0.00s)
--- PASS: TestReOpen/Normal/ReadAt (0.00s)
--- PASS: TestReOpen/Normal/Seek (0.00s)
--- PASS: TestReOpen/Normal/AccountRead (0.00s)
--- PASS: TestReOpen/Normal/AccountReadDelay (0.00s)
--- PASS: TestReOpen/Normal/AccountReadError (0.00s)
--- PASS: TestReOpen/WithRangeOption (0.00s)
--- PASS: TestReOpen/WithRangeOption/Basics (0.00s)
--- PASS: TestReOpen/WithRangeOption/ErrorAtStart (0.00s)
--- PASS: TestReOpen/WithRangeOption/WithErrors (0.00s)
--- PASS: TestReOpen/WithRangeOption/TooManyErrors (0.00s)
--- PASS: TestReOpen/WithRangeOption/ReadAt (0.00s)
--- PASS: TestReOpen/WithRangeOption/Seek (0.00s)
--- PASS: TestReOpen/WithRangeOption/AccountRead (0.00s)
--- PASS: TestReOpen/WithRangeOption/AccountReadDelay (0.00s)
--- PASS: TestReOpen/WithRangeOption/AccountReadError (0.00s)
--- PASS: TestReOpen/WithSeekOption (0.00s)
--- PASS: TestReOpen/WithSeekOption/Basics (0.00s)
--- PASS: TestReOpen/WithSeekOption/ErrorAtStart (0.00s)
--- PASS: TestReOpen/WithSeekOption/WithErrors (0.00s)
--- PASS: TestReOpen/WithSeekOption/TooManyErrors (0.00s)
--- PASS: TestReOpen/WithSeekOption/ReadAt (0.00s)
--- PASS: TestReOpen/WithSeekOption/Seek (0.00s)
--- PASS: TestReOpen/WithSeekOption/AccountRead (0.00s)
--- PASS: TestReOpen/WithSeekOption/AccountReadDelay (0.00s)
--- PASS: TestReOpen/WithSeekOption/AccountReadError (0.00s)
--- PASS: TestReOpen/UnknownSize (0.00s)
--- PASS: TestReOpen/UnknownSize/Basics (0.00s)
--- PASS: TestReOpen/UnknownSize/ErrorAtStart (0.00s)
--- PASS: TestReOpen/UnknownSize/WithErrors (0.00s)
--- PASS: TestReOpen/UnknownSize/TooManyErrors (0.00s)
--- PASS: TestReOpen/UnknownSize/ReadAt (0.00s)
--- PASS: TestReOpen/UnknownSize/Seek (0.00s)
--- PASS: TestReOpen/UnknownSize/AccountRead (0.00s)
--- PASS: TestReOpen/UnknownSize/AccountReadDelay (0.00s)
--- PASS: TestReOpen/UnknownSize/AccountReadError (0.00s)
=== RUN TestCheck
run.go:185: Remote "drime root 'rclone-test-nezuzih4tupa'", Local "Local file system at /tmp/rclone819691579", Modify Window "876000h0m0s"
=== RUN TestCheck/1
=== RUN TestCheck/2
=== RUN TestCheck/3
=== RUN TestCheck/4
=== RUN TestCheck/5
=== RUN TestCheck/6
=== RUN TestCheck/7
--- PASS: TestCheck (151.11s)
--- PASS: TestCheck/1 (1.70s)
--- PASS: TestCheck/2 (1.94s)
--- PASS: TestCheck/3 (15.16s)
--- PASS: TestCheck/4 (7.30s)
--- PASS: TestCheck/5 (8.26s)
--- PASS: TestCheck/6 (3.76s)
--- PASS: TestCheck/7 (5.24s)
=== RUN TestCheckFsError
2026/03/24 05:00:52 DEBUG : Creating backend with remote "nonexistent"
2026/03/24 05:00:52 DEBUG : Creating backend with remote "nonexistent"
2026/03/24 05:00:52 DEBUG : Local file system at /home/rclone/go/src/github.com/rclone/rclone/fs/operations/nonexistent: Waiting for checks to finish
2026/03/24 05:00:52 ERROR : Local file system at /home/rclone/go/src/github.com/rclone/rclone/fs/operations/nonexistent: error reading source root directory: directory not found
2026/03/24 05:00:52 NOTICE: Local file system at /home/rclone/go/src/github.com/rclone/rclone/fs/operations/nonexistent: 0 differences found
2026/03/24 05:00:52 NOTICE: Local file system at /home/rclone/go/src/github.com/rclone/rclone/fs/operations/nonexistent: 2 errors while checking
--- PASS: TestCheckFsError (0.00s)
=== RUN TestCheckDownload
run.go:185: Remote "drime root 'rclone-test-nezuzih4tupa'", Local "Local file system at /tmp/rclone819691579", Modify Window "876000h0m0s"
=== RUN TestCheckDownload/1
=== RUN TestCheckDownload/2
=== RUN TestCheckDownload/3
=== RUN TestCheckDownload/4
=== RUN TestCheckDownload/5
=== RUN TestCheckDownload/6
=== RUN TestCheckDownload/7
--- PASS: TestCheckDownload (149.50s)
--- PASS: TestCheckDownload/1 (12.03s)
--- PASS: TestCheckDownload/2 (8.45s)
--- PASS: TestCheckDownload/3 (10.94s)
--- PASS: TestCheckDownload/4 (9.02s)
--- PASS: TestCheckDownload/5 (6.89s)
--- PASS: TestCheckDownload/6 (7.60s)
--- PASS: TestCheckDownload/7 (13.45s)
=== RUN TestCheckSizeOnly
run.go:185: Remote "drime root 'rclone-test-nezuzih4tupa'", Local "Local file system at /tmp/rclone819691579", Modify Window "876000h0m0s"
=== RUN TestCheckSizeOnly/1
=== RUN TestCheckSizeOnly/2
=== RUN TestCheckSizeOnly/3
=== RUN TestCheckSizeOnly/4
=== RUN TestCheckSizeOnly/5
=== RUN TestCheckSizeOnly/6
=== RUN TestCheckSizeOnly/7
--- PASS: TestCheckSizeOnly (192.98s)
--- PASS: TestCheckSizeOnly/1 (8.16s)
--- PASS: TestCheckSizeOnly/2 (6.47s)
--- PASS: TestCheckSizeOnly/3 (9.22s)
--- PASS: TestCheckSizeOnly/4 (7.56s)
--- PASS: TestCheckSizeOnly/5 (4.93s)
--- PASS: TestCheckSizeOnly/6 (5.52s)
--- PASS: TestCheckSizeOnly/7 (4.77s)
=== RUN TestCheckEqualReaders
--- PASS: TestCheckEqualReaders (0.00s)
=== RUN TestParseSumFile
run.go:185: Remote "drime root 'rclone-test-nezuzih4tupa'", Local "Local file system at /tmp/rclone819691579", Modify Window "876000h0m0s"
2026/03/24 05:06:59 NOTICE: test.sum: improperly formatted checksum line 4
2026/03/24 05:06:59 NOTICE: test.sum: improperly formatted checksum line 5
2026/03/24 05:06:59 NOTICE: test.sum: improperly formatted checksum line 6
2026/03/24 05:06:59 NOTICE: test.sum: 2 warning(s) suppressed...
2026/03/24 05:07:09 DEBUG : test.sum: Removing old object on successful upload
run.go:290: Failed to put "test.sum" to "drime root 'rclone-test-nezuzih4tupa'": failed to delete existing object: failed to delete item: Error "The selected entry ids is invalid."
--- FAIL: TestParseSumFile (42.43s)
=== RUN TestCheckSum
run.go:185: Remote "drime root 'rclone-test-nezuzih4tupa'", Local "Local file system at /tmp/rclone819691579", Modify Window "876000h0m0s"
2026/03/24 05:07:17 DEBUG : Creating backend with remote "TestDrime:rclone-test-nezuzih4tupa/data"
check_test.go:350: drime root 'rclone-test-nezuzih4tupa/data' lacks md5, skipping
--- SKIP: TestCheckSum (17.28s)
=== RUN TestCheckSumDownload
run.go:185: Remote "drime root 'rclone-test-nezuzih4tupa'", Local "Local file system at /tmp/rclone819691579", Modify Window "876000h0m0s"
2026/03/24 05:07:34 DEBUG : Creating backend with remote "TestDrime:rclone-test-nezuzih4tupa/data"
=== RUN TestCheckSumDownload/subtest1
2026/03/24 05:09:31 DEBUG : test.sum: Removing old object on successful upload
=== NAME TestCheckSumDownload
run.go:290: Failed to put "test.sum" to "drime root 'rclone-test-nezuzih4tupa'": failed to delete existing object: failed to delete item: Error "The selected entry ids is invalid."
--- FAIL: TestCheckSumDownload (156.31s)
--- PASS: TestCheckSumDownload/subtest1 (18.96s)
=== RUN TestApplyTransforms
2026/03/24 05:10:10 DEBUG : Creating backend with remote "TestDrime:rclone-test-dofanid0gifi"
2026/03/24 05:10:10 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/03/24 05:10:18 DEBUG : Creating backend with remote "/tmp/rclone3190450467"
run.go:185: Remote "drime root 'rclone-test-dofanid0gifi'", Local "Local file system at /tmp/rclone3190450467", Modify Window "876000h0m0s"
upper checkfile vs. lower remote (without normalization)
2026/03/24 05:10:55 ERROR : hello, world!: sum not found
2026/03/24 05:10:55 ERROR : HELLO, WORLD!: file not in drime root 'rclone-test-dofanid0gifi'
2026/03/24 05:10:55 NOTICE: drime root 'rclone-test-dofanid0gifi': 1 files missing
2026/03/24 05:10:55 NOTICE: 1 hashes missing
2026/03/24 05:10:55 NOTICE: drime root 'rclone-test-dofanid0gifi': 1 differences found
2026/03/24 05:10:55 NOTICE: drime root 'rclone-test-dofanid0gifi': 2 errors while checking
upper checkfile vs. lower remote (with normalization)
2026/03/24 05:11:10 DEBUG : hello, world!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK
2026/03/24 05:11:10 NOTICE: drime root 'rclone-test-dofanid0gifi': 0 differences found
2026/03/24 05:11:10 NOTICE: drime root 'rclone-test-dofanid0gifi': 1 matching files
2026/03/24 05:11:10 DEBUG : Creating backend with remote "TestDrime:rclone-test-sesojem0kahi"
2026/03/24 05:11:10 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/03/24 05:11:23 DEBUG : Creating backend with remote "/tmp/rclone3777010799"
run.go:185: Remote "drime root 'rclone-test-sesojem0kahi'", Local "Local file system at /tmp/rclone3777010799", Modify Window "876000h0m0s"
lower checkfile vs. upper remote (without normalization)
2026/03/24 05:12:09 ERROR : HELLO, WORLD!: sum not found
2026/03/24 05:12:09 ERROR : hello, world!: file not in drime root 'rclone-test-sesojem0kahi'
2026/03/24 05:12:09 NOTICE: drime root 'rclone-test-sesojem0kahi': 1 files missing
2026/03/24 05:12:09 NOTICE: 1 hashes missing
2026/03/24 05:12:09 NOTICE: drime root 'rclone-test-sesojem0kahi': 1 differences found
2026/03/24 05:12:09 NOTICE: drime root 'rclone-test-sesojem0kahi': 2 errors while checking
lower checkfile vs. upper remote (with normalization)
2026/03/24 05:12:22 DEBUG : HELLO, WORLD!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK
2026/03/24 05:12:22 NOTICE: drime root 'rclone-test-sesojem0kahi': 0 differences found
2026/03/24 05:12:22 NOTICE: drime root 'rclone-test-sesojem0kahi': 1 matching files
2026/03/24 05:12:22 DEBUG : Creating backend with remote "TestDrime:rclone-test-hoyahez3voso"
2026/03/24 05:12:22 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/03/24 05:12:30 DEBUG : Creating backend with remote "/tmp/rclone151100905"
run.go:185: Remote "drime root 'rclone-test-hoyahez3voso'", Local "Local file system at /tmp/rclone151100905", Modify Window "876000h0m0s"
lower checkfile vs. upperlowermixed remote (without normalization)
2026/03/24 05:13:06 ERROR : HeLlO, wOrLd!: sum not found
2026/03/24 05:13:06 ERROR : hello, world!: file not in drime root 'rclone-test-hoyahez3voso'
2026/03/24 05:13:06 NOTICE: drime root 'rclone-test-hoyahez3voso': 1 files missing
2026/03/24 05:13:06 NOTICE: 1 hashes missing
2026/03/24 05:13:06 NOTICE: drime root 'rclone-test-hoyahez3voso': 1 differences found
2026/03/24 05:13:06 NOTICE: drime root 'rclone-test-hoyahez3voso': 2 errors while checking
lower checkfile vs. upperlowermixed remote (with normalization)
2026/03/24 05:13:20 DEBUG : HeLlO, wOrLd!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK
2026/03/24 05:13:20 NOTICE: drime root 'rclone-test-hoyahez3voso': 0 differences found
2026/03/24 05:13:20 NOTICE: drime root 'rclone-test-hoyahez3voso': 1 matching files
2026/03/24 05:13:20 DEBUG : Creating backend with remote "TestDrime:rclone-test-yawicir3xeca"
2026/03/24 05:13:20 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/03/24 05:13:27 DEBUG : Creating backend with remote "/tmp/rclone2151555149"
run.go:185: Remote "drime root 'rclone-test-yawicir3xeca'", Local "Local file system at /tmp/rclone2151555149", Modify Window "876000h0m0s"
upperlowermixed checkfile vs. upper remote (without normalization)
2026/03/24 05:13:59 ERROR : HELLO, WORLD!: sum not found
2026/03/24 05:13:59 ERROR : HeLlO, wOrLd!: file not in drime root 'rclone-test-yawicir3xeca'
2026/03/24 05:13:59 NOTICE: drime root 'rclone-test-yawicir3xeca': 1 files missing
2026/03/24 05:13:59 NOTICE: 1 hashes missing
2026/03/24 05:13:59 NOTICE: drime root 'rclone-test-yawicir3xeca': 1 differences found
2026/03/24 05:13:59 NOTICE: drime root 'rclone-test-yawicir3xeca': 2 errors while checking
upperlowermixed checkfile vs. upper remote (with normalization)
2026/03/24 05:14:03 DEBUG : HELLO, WORLD!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK
2026/03/24 05:14:03 NOTICE: drime root 'rclone-test-yawicir3xeca': 0 differences found
2026/03/24 05:14:03 NOTICE: drime root 'rclone-test-yawicir3xeca': 1 matching files
2026/03/24 05:14:03 DEBUG : Creating backend with remote "TestDrime:rclone-test-mitipos0gavo"
2026/03/24 05:14:03 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/03/24 05:14:08 DEBUG : Creating backend with remote "/tmp/rclone618775784"
run.go:185: Remote "drime root 'rclone-test-mitipos0gavo'", Local "Local file system at /tmp/rclone618775784", Modify Window "876000h0m0s"
NFD checkfile vs. NFC remote (without normalization)
2026/03/24 05:14:36 ERROR : 測試_Русский___ě_áñ: sum not found
2026/03/24 05:14:36 ERROR : 測試_Русский___ě_áñ: file not in drime root 'rclone-test-mitipos0gavo'
2026/03/24 05:14:36 NOTICE: drime root 'rclone-test-mitipos0gavo': 1 files missing
2026/03/24 05:14:36 NOTICE: 1 hashes missing
2026/03/24 05:14:36 NOTICE: drime root 'rclone-test-mitipos0gavo': 1 differences found
2026/03/24 05:14:36 NOTICE: drime root 'rclone-test-mitipos0gavo': 2 errors while checking
NFD checkfile vs. NFC remote (with normalization)
2026/03/24 05:14:40 DEBUG : 測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK
2026/03/24 05:14:40 NOTICE: drime root 'rclone-test-mitipos0gavo': 0 differences found
2026/03/24 05:14:40 NOTICE: drime root 'rclone-test-mitipos0gavo': 1 matching files
2026/03/24 05:14:40 DEBUG : Creating backend with remote "TestDrime:rclone-test-fuhuwom7mebi"
2026/03/24 05:14:40 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/03/24 05:14:45 DEBUG : Creating backend with remote "/tmp/rclone1939428720"
run.go:185: Remote "drime root 'rclone-test-fuhuwom7mebi'", Local "Local file system at /tmp/rclone1939428720", Modify Window "876000h0m0s"
NFC checkfile vs. NFD remote (without normalization)
2026/03/24 05:15:13 ERROR : 測試_Русский___ě_áñ: sum not found
2026/03/24 05:15:13 ERROR : 測試_Русский___ě_áñ: file not in drime root 'rclone-test-fuhuwom7mebi'
2026/03/24 05:15:13 NOTICE: drime root 'rclone-test-fuhuwom7mebi': 1 files missing
2026/03/24 05:15:13 NOTICE: 1 hashes missing
2026/03/24 05:15:13 NOTICE: drime root 'rclone-test-fuhuwom7mebi': 1 differences found
2026/03/24 05:15:13 NOTICE: drime root 'rclone-test-fuhuwom7mebi': 2 errors while checking
NFC checkfile vs. NFD remote (with normalization)
2026/03/24 05:15:22 DEBUG : 測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK
2026/03/24 05:15:22 NOTICE: drime root 'rclone-test-fuhuwom7mebi': 0 differences found
2026/03/24 05:15:22 NOTICE: drime root 'rclone-test-fuhuwom7mebi': 1 matching files
2026/03/24 05:15:22 DEBUG : Creating backend with remote "TestDrime:rclone-test-jupipoy9ruju"
2026/03/24 05:15:22 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/03/24 05:15:29 DEBUG : Creating backend with remote "/tmp/rclone4071197163"
run.go:185: Remote "drime root 'rclone-test-jupipoy9ruju'", Local "Local file system at /tmp/rclone4071197163", Modify Window "876000h0m0s"
NFDx2 checkfile vs. both remote (without normalization)
2026/03/24 05:16:17 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found
2026/03/24 05:16:17 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in drime root 'rclone-test-jupipoy9ruju'
2026/03/24 05:16:17 NOTICE: drime root 'rclone-test-jupipoy9ruju': 1 files missing
2026/03/24 05:16:17 NOTICE: 1 hashes missing
2026/03/24 05:16:17 NOTICE: drime root 'rclone-test-jupipoy9ruju': 1 differences found
2026/03/24 05:16:17 NOTICE: drime root 'rclone-test-jupipoy9ruju': 2 errors while checking
NFDx2 checkfile vs. both remote (with normalization)
2026/03/24 05:16:36 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK
2026/03/24 05:16:36 NOTICE: drime root 'rclone-test-jupipoy9ruju': 0 differences found
2026/03/24 05:16:36 NOTICE: drime root 'rclone-test-jupipoy9ruju': 1 matching files
2026/03/24 05:16:36 DEBUG : Creating backend with remote "TestDrime:rclone-test-nuzuzak9hawi"
2026/03/24 05:16:36 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/03/24 05:16:47 DEBUG : Creating backend with remote "/tmp/rclone1745264998"
run.go:185: Remote "drime root 'rclone-test-nuzuzak9hawi'", Local "Local file system at /tmp/rclone1745264998", Modify Window "876000h0m0s"
NFCx2 checkfile vs. both remote (without normalization)
2026/03/24 05:17:30 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found
2026/03/24 05:17:30 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in drime root 'rclone-test-nuzuzak9hawi'
2026/03/24 05:17:30 NOTICE: drime root 'rclone-test-nuzuzak9hawi': 1 files missing
2026/03/24 05:17:30 NOTICE: 1 hashes missing
2026/03/24 05:17:30 NOTICE: drime root 'rclone-test-nuzuzak9hawi': 1 differences found
2026/03/24 05:17:30 NOTICE: drime root 'rclone-test-nuzuzak9hawi': 2 errors while checking
NFCx2 checkfile vs. both remote (with normalization)
2026/03/24 05:17:39 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK
2026/03/24 05:17:39 NOTICE: drime root 'rclone-test-nuzuzak9hawi': 0 differences found
2026/03/24 05:17:39 NOTICE: drime root 'rclone-test-nuzuzak9hawi': 1 matching files
2026/03/24 05:17:39 DEBUG : Creating backend with remote "TestDrime:rclone-test-camemaf8kuta"
2026/03/24 05:17:39 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/03/24 05:17:42 DEBUG : Creating backend with remote "/tmp/rclone1773635717"
run.go:185: Remote "drime root 'rclone-test-camemaf8kuta'", Local "Local file system at /tmp/rclone1773635717", Modify Window "876000h0m0s"
both checkfile vs. NFDx2 remote (without normalization)
2026/03/24 05:18:07 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found
2026/03/24 05:18:07 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in drime root 'rclone-test-camemaf8kuta'
2026/03/24 05:18:07 NOTICE: drime root 'rclone-test-camemaf8kuta': 1 files missing
2026/03/24 05:18:07 NOTICE: 1 hashes missing
2026/03/24 05:18:07 NOTICE: drime root 'rclone-test-camemaf8kuta': 1 differences found
2026/03/24 05:18:07 NOTICE: drime root 'rclone-test-camemaf8kuta': 2 errors while checking
both checkfile vs. NFDx2 remote (with normalization)
2026/03/24 05:18:12 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK
2026/03/24 05:18:12 NOTICE: drime root 'rclone-test-camemaf8kuta': 0 differences found
2026/03/24 05:18:12 NOTICE: drime root 'rclone-test-camemaf8kuta': 1 matching files
2026/03/24 05:18:12 DEBUG : Creating backend with remote "TestDrime:rclone-test-quzisoh3guyi"
2026/03/24 05:18:12 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/03/24 05:18:23 DEBUG : Creating backend with remote "/tmp/rclone3869806295"
run.go:185: Remote "drime root 'rclone-test-quzisoh3guyi'", Local "Local file system at /tmp/rclone3869806295", Modify Window "876000h0m0s"
both checkfile vs. NFCx2 remote (without normalization)
2026/03/24 05:19:01 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found
2026/03/24 05:19:01 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in drime root 'rclone-test-quzisoh3guyi'
2026/03/24 05:19:01 NOTICE: drime root 'rclone-test-quzisoh3guyi': 1 files missing
2026/03/24 05:19:01 NOTICE: 1 hashes missing
2026/03/24 05:19:01 NOTICE: drime root 'rclone-test-quzisoh3guyi': 1 differences found
2026/03/24 05:19:01 NOTICE: drime root 'rclone-test-quzisoh3guyi': 2 errors while checking
both checkfile vs. NFCx2 remote (with normalization)
2026/03/24 05:19:13 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK
2026/03/24 05:19:13 NOTICE: drime root 'rclone-test-quzisoh3guyi': 0 differences found
2026/03/24 05:19:13 NOTICE: drime root 'rclone-test-quzisoh3guyi': 1 matching files
2026/03/24 05:19:13 DEBUG : drime root 'rclone-test-quzisoh3guyi': Purge remote
2026/03/24 05:19:19 DEBUG : drime root 'rclone-test-camemaf8kuta': Purge remote
2026/03/24 05:19:22 DEBUG : drime root 'rclone-test-nuzuzak9hawi': Purge remote
2026/03/24 05:19:25 DEBUG : drime root 'rclone-test-jupipoy9ruju': Purge remote
2026/03/24 05:19:27 DEBUG : drime root 'rclone-test-fuhuwom7mebi': Purge remote
2026/03/24 05:19:29 DEBUG : drime root 'rclone-test-mitipos0gavo': Purge remote
2026/03/24 05:19:35 DEBUG : drime root 'rclone-test-yawicir3xeca': Purge remote
2026/03/24 05:19:41 DEBUG : drime root 'rclone-test-hoyahez3voso': Purge remote
2026/03/24 05:19:46 DEBUG : drime root 'rclone-test-sesojem0kahi': Purge remote
2026/03/24 05:19:50 DEBUG : drime root 'rclone-test-dofanid0gifi': Purge remote
--- PASS: TestApplyTransforms (585.41s)
=== RUN TestTruncateString
--- PASS: TestTruncateString (0.00s)
=== RUN TestCopyFile
run.go:185: Remote "drime root 'rclone-test-nezuzih4tupa'", Local "Local file system at /tmp/rclone819691579", Modify Window "876000h0m0s"
2026/03/24 05:20:00 DEBUG : file1: Need to transfer - File not found at Destination
2026/03/24 05:20:25 DEBUG : sub/file2: size = 14 OK
2026/03/24 05:20:25 INFO : file1: Copied (new) to: sub/file2
2026/03/24 05:20:41 DEBUG : sub/file2: size = 14 OK
2026/03/24 05:20:41 DEBUG : file1: Sizes identical
2026/03/24 05:20:41 DEBUG : file1: Unchanged skipping
2026/03/24 05:20:51 DEBUG : drime root 'rclone-test-nezuzih4tupa': don't need to copy/move sub/file2, it is already at target location
--- PASS: TestCopyFile (81.98s)
=== RUN TestCopyLongFile
run.go:185: Remote "drime root 'rclone-test-nezuzih4tupa'", Local "Local file system at /tmp/rclone819691579", Modify Window "876000h0m0s"
copy_test.go:154: Test only runs on local
--- SKIP: TestCopyLongFile (13.57s)
=== RUN TestCopyFileBackupDir
run.go:185: Remote "drime root 'rclone-test-nezuzih4tupa'", Local "Local file system at /tmp/rclone819691579", Modify Window "876000h0m0s"
2026/03/24 05:22:13 DEBUG : Creating backend with remote "TestDrime:rclone-test-nezuzih4tupa/backup"
2026/03/24 05:22:13 DEBUG : Config file has changed externally - reloading
2026/03/24 05:22:36 DEBUG : dst/file1: size = 14 (Local file system at /tmp/rclone819691579)
2026/03/24 05:22:36 DEBUG : dst/file1: size = 18 (drime root 'rclone-test-nezuzih4tupa')
2026/03/24 05:22:36 DEBUG : dst/file1: Sizes differ
2026/03/24 05:23:22 INFO : dst/file1: Moved (server-side)
2026/03/24 05:24:29 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/03/24 05:24:29 DEBUG : pacer: low level retry 1/1 (error Error "504 Gateway Timeout (504): \r\n504 Gateway Time-out\r\n\r\n504 Gateway Time-out
\r\n
nginx\r\n\r\n\r\n")
2026/03/24 05:24:29 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/03/24 05:24:29 DEBUG : dst/file1: Received error: failed to upload file: Error "504 Gateway Timeout (504): \r\n504 Gateway Time-out\r\n\r\n504 Gateway Time-out
\r\n
nginx\r\n\r\n\r\n" - low level retry 0/10
2026/03/24 05:24:33 DEBUG : pacer: Reducing sleep to 10ms
2026/03/24 05:24:39 DEBUG : dst/file1: Removing old object on successful upload
2026/03/24 05:24:44 ERROR : dst/file1: Failed to copy: failed to delete existing object: failed to delete item: Error "The selected entry ids is invalid."
copy_test.go:186:
Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/copy_test.go:186
Error: Received unexpected error:
failed to delete existing object: failed to delete item: Error "The selected entry ids is invalid."
Test: TestCopyFileBackupDir
--- FAIL: TestCopyFileBackupDir (232.25s)
=== RUN TestCopyFileCompareDest
run.go:185: Remote "drime root 'rclone-test-nezuzih4tupa'", Local "Local file system at /tmp/rclone819691579", Modify Window "876000h0m0s"
2026/03/24 05:25:24 DEBUG : Creating backend with remote "TestDrime:rclone-test-nezuzih4tupa/dst"
2026/03/24 05:25:40 DEBUG : Creating backend with remote "TestDrime:rclone-test-nezuzih4tupa/CompareDest"
2026/03/24 05:25:40 DEBUG : Config file has changed externally - reloading
2026/03/24 05:25:58 DEBUG : one: Need to transfer - File not found at Destination
2026/03/24 05:26:24 DEBUG : one: size = 3 OK
2026/03/24 05:26:24 INFO : one: Copied (new)
2026/03/24 05:26:41 DEBUG : one: size = 5 (Local file system at /tmp/rclone819691579)
2026/03/24 05:26:41 DEBUG : one: size = 3 (drime root 'rclone-test-nezuzih4tupa/dst')
2026/03/24 05:26:41 DEBUG : one: Sizes differ
2026/03/24 05:27:01 DEBUG : one: Removing old object on successful upload
2026/03/24 05:27:08 ERROR : one: Failed to copy: failed to delete existing object: failed to delete item: Error "The selected entry ids is invalid."
copy_test.go:220:
Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/copy_test.go:220
Error: Received unexpected error:
failed to delete existing object: failed to delete item: Error "The selected entry ids is invalid."
Test: TestCopyFileCompareDest
--- FAIL: TestCopyFileCompareDest (131.71s)
=== RUN TestCopyFileCopyDest
run.go:185: Remote "drime root 'rclone-test-nezuzih4tupa'", Local "Local file system at /tmp/rclone819691579", Modify Window "876000h0m0s"
2026/03/24 05:27:35 DEBUG : Creating backend with remote "TestDrime:rclone-test-nezuzih4tupa/dst"
2026/03/24 05:27:59 DEBUG : Creating backend with remote "TestDrime:rclone-test-nezuzih4tupa/CopyDest"
2026/03/24 05:27:59 DEBUG : Config file has changed externally - reloading
2026/03/24 05:28:18 DEBUG : one: Need to transfer - File not found at Destination
2026/03/24 05:29:34 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/03/24 05:29:34 DEBUG : pacer: low level retry 1/1 (error Error "504 Gateway Timeout (504): \r\n504 Gateway Time-out\r\n\r\n504 Gateway Time-out
\r\n
nginx\r\n\r\n\r\n")
2026/03/24 05:29:34 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/03/24 05:29:34 DEBUG : one: Received error: failed to upload file: Error "504 Gateway Timeout (504): \r\n504 Gateway Time-out\r\n\r\n504 Gateway Time-out
\r\n
nginx\r\n\r\n\r\n" - low level retry 0/10
2026/03/24 05:29:39 DEBUG : pacer: Reducing sleep to 10ms
2026/03/24 05:29:45 DEBUG : one: Removing old object on successful upload
2026/03/24 05:29:50 ERROR : one: Failed to copy: failed to delete existing object: failed to delete item: Error "The selected entry ids is invalid."
copy_test.go:290:
Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/copy_test.go:290
Error: Received unexpected error:
failed to delete existing object: failed to delete item: Error "The selected entry ids is invalid."
Test: TestCopyFileCopyDest
--- FAIL: TestCopyFileCopyDest (162.91s)
=== RUN TestCopyInplace
run.go:185: Remote "drime root 'rclone-test-nezuzih4tupa'", Local "Local file system at /tmp/rclone819691579", Modify Window "876000h0m0s"
copy_test.go:371: Partial uploads not supported
--- SKIP: TestCopyInplace (5.92s)
=== RUN TestCopyLongFileName
run.go:185: Remote "drime root 'rclone-test-nezuzih4tupa'", Local "Local file system at /tmp/rclone819691579", Modify Window "876000h0m0s"
copy_test.go:404: Partial uploads not supported
--- SKIP: TestCopyLongFileName (5.30s)
=== RUN TestCopyLongFileNameCollision
run.go:185: Remote "drime root 'rclone-test-nezuzih4tupa'", Local "Local file system at /tmp/rclone819691579", Modify Window "876000h0m0s"
copy_test.go:437: Partial uploads not supported
--- SKIP: TestCopyLongFileNameCollision (9.55s)
=== RUN TestCopyFileMaxTransfer
run.go:185: Remote "drime root 'rclone-test-nezuzih4tupa'", Local "Local file system at /tmp/rclone819691579", Modify Window "876000h0m0s"
2026/03/24 05:30:48 DEBUG : TestCopyFileMaxTransfer/file1: Need to transfer - File not found at Destination
2026/03/24 05:31:18 DEBUG : TestCopyFileMaxTransfer/file1: size = 14 OK
2026/03/24 05:31:18 INFO : TestCopyFileMaxTransfer/file1: Copied (new)
2026/03/24 05:31:31 DEBUG : TestCopyFileMaxTransfer/file2: Need to transfer - File not found at Destination
2026/03/24 05:31:38 ERROR : TestCopyFileMaxTransfer/file2: Failed to copy: failed to upload file: Post "https://app.drime.cloud/api/v1/uploads": failed to copy data: max transfer limit reached as set by --max-transfer
2026/03/24 05:32:01 DEBUG : TestCopyFileMaxTransfer/file3: Need to transfer - File not found at Destination
2026/03/24 05:32:19 DEBUG : TestCopyFileMaxTransfer/file4: Need to transfer - File not found at Destination
2026/03/24 05:32:56 DEBUG : TestCopyFileMaxTransfer/file4: size = 2062 OK
2026/03/24 05:32:56 INFO : TestCopyFileMaxTransfer/file4: Copied (new)
--- PASS: TestCopyFileMaxTransfer (191.42s)
=== RUN TestDeduplicateInteractive
run.go:185: Remote "drime root 'rclone-test-nezuzih4tupa'", Local "Local file system at /tmp/rclone819691579", Modify Window "876000h0m0s"
dedupe_test.go:24: Can't test deduplicate - no duplicate files possible
--- SKIP: TestDeduplicateInteractive (13.96s)
=== RUN TestDeduplicateSkip
run.go:185: Remote "drime root 'rclone-test-nezuzih4tupa'", Local "Local file system at /tmp/rclone819691579", Modify Window "876000h0m0s"
dedupe_test.go:24: Can't test deduplicate - no duplicate files possible
--- SKIP: TestDeduplicateSkip (10.33s)
=== RUN TestDeduplicateSizeOnly
run.go:185: Remote "drime root 'rclone-test-nezuzih4tupa'", Local "Local file system at /tmp/rclone819691579", Modify Window "876000h0m0s"
dedupe_test.go:24: Can't test deduplicate - no duplicate files possible
--- SKIP: TestDeduplicateSizeOnly (11.87s)
=== RUN TestDeduplicateFirst
run.go:185: Remote "drime root 'rclone-test-nezuzih4tupa'", Local "Local file system at /tmp/rclone819691579", Modify Window "876000h0m0s"
dedupe_test.go:24: Can't test deduplicate - no duplicate files possible
--- SKIP: TestDeduplicateFirst (11.56s)
=== RUN TestDeduplicateNewest
run.go:185: Remote "drime root 'rclone-test-nezuzih4tupa'", Local "Local file system at /tmp/rclone819691579", Modify Window "876000h0m0s"
dedupe_test.go:24: Can't test deduplicate - no duplicate files possible
--- SKIP: TestDeduplicateNewest (11.51s)
=== RUN TestDeduplicateNewestByHash
run.go:185: Remote "drime root 'rclone-test-nezuzih4tupa'", Local "Local file system at /tmp/rclone819691579", Modify Window "876000h0m0s"
dedupe_test.go:36: Can't run this test without a hash
--- SKIP: TestDeduplicateNewestByHash (19.40s)
=== RUN TestDeduplicateOldest
run.go:185: Remote "drime root 'rclone-test-nezuzih4tupa'", Local "Local file system at /tmp/rclone819691579", Modify Window "876000h0m0s"
dedupe_test.go:24: Can't test deduplicate - no duplicate files possible
--- SKIP: TestDeduplicateOldest (15.43s)
=== RUN TestDeduplicateLargest
run.go:185: Remote "drime root 'rclone-test-nezuzih4tupa'", Local "Local file system at /tmp/rclone819691579", Modify Window "876000h0m0s"
dedupe_test.go:24: Can't test deduplicate - no duplicate files possible
--- SKIP: TestDeduplicateLargest (15.30s)
=== RUN TestDeduplicateSmallest
run.go:185: Remote "drime root 'rclone-test-nezuzih4tupa'", Local "Local file system at /tmp/rclone819691579", Modify Window "876000h0m0s"
dedupe_test.go:24: Can't test deduplicate - no duplicate files possible
--- SKIP: TestDeduplicateSmallest (12.06s)
=== RUN TestDeduplicateRename
run.go:185: Remote "drime root 'rclone-test-nezuzih4tupa'", Local "Local file system at /tmp/rclone819691579", Modify Window "876000h0m0s"
dedupe_test.go:24: Can't test deduplicate - no duplicate files possible
--- SKIP: TestDeduplicateRename (12.38s)
=== RUN TestMergeDirs
run.go:185: Remote "drime root 'rclone-test-nezuzih4tupa'", Local "Local file system at /tmp/rclone819691579", Modify Window "876000h0m0s"
dedupe_test.go:256: Can't merge directories
--- SKIP: TestMergeDirs (11.77s)
=== RUN TestListDirSorted
run.go:185: Remote "drime root 'rclone-test-nezuzih4tupa'", Local "Local file system at /tmp/rclone819691579", Modify Window "876000h0m0s"
2026/03/24 05:38:42 DEBUG : a.txt: Excluded (Size Filter)
2026/03/24 05:38:42 DEBUG : a.txt: Excluded
2026/03/24 05:38:47 DEBUG : sub dir/hello world2: Excluded (Size Filter)
2026/03/24 05:38:47 DEBUG : sub dir/hello world2: Excluded
2026/03/24 05:38:47 DEBUG : sub dir/hello world: Excluded (Size Filter)
2026/03/24 05:38:47 DEBUG : sub dir/hello world: Excluded
2026/03/24 05:39:06 DEBUG : sub dir/ignore dir: Excluded
2026/03/24 05:39:06 DEBUG : sub dir/hello world2: Excluded (Size Filter)
2026/03/24 05:39:06 DEBUG : sub dir/hello world2: Excluded
2026/03/24 05:39:06 DEBUG : sub dir/hello world: Excluded (Size Filter)
2026/03/24 05:39:06 DEBUG : sub dir/hello world: Excluded
2026/03/24 05:39:10 DEBUG : sub dir/ignore dir: Excluded
--- PASS: TestListDirSorted (224.76s)
=== RUN TestListDirSortedFn
run.go:185: Remote "drime root 'rclone-test-nezuzih4tupa'", Local "Local file system at /tmp/rclone819691579", Modify Window "876000h0m0s"
2026/03/24 05:42:45 DEBUG : a.txt: Excluded (Size Filter)
2026/03/24 05:42:45 DEBUG : a.txt: Excluded
2026/03/24 05:42:57 DEBUG : sub dir/hello world2: Excluded (Size Filter)
2026/03/24 05:42:57 DEBUG : sub dir/hello world2: Excluded
2026/03/24 05:42:57 DEBUG : sub dir/hello world: Excluded (Size Filter)
2026/03/24 05:42:57 DEBUG : sub dir/hello world: Excluded
2026/03/24 05:43:10 DEBUG : sub dir/ignore dir: Excluded
2026/03/24 05:43:10 DEBUG : sub dir/hello world2: Excluded (Size Filter)
2026/03/24 05:43:10 DEBUG : sub dir/hello world2: Excluded
2026/03/24 05:43:10 DEBUG : sub dir/hello world: Excluded (Size Filter)
2026/03/24 05:43:10 DEBUG : sub dir/hello world: Excluded
2026/03/24 05:43:17 DEBUG : sub dir/ignore dir: Excluded
--- PASS: TestListDirSortedFn (284.29s)
=== RUN TestListJSON
run.go:185: Remote "drime root 'rclone-test-nezuzih4tupa'", Local "Local file system at /tmp/rclone819691579", Modify Window "876000h0m0s"
=== RUN TestListJSON/Default
=== RUN TestListJSON/FilesOnly
=== RUN TestListJSON/DirsOnly
=== RUN TestListJSON/Recurse
=== RUN TestListJSON/SubDir
=== RUN TestListJSON/NoModTime
=== RUN TestListJSON/NoMimeType
=== RUN TestListJSON/ShowHash
=== RUN TestListJSON/HashTypes
2026/03/24 05:46:23 ERROR : file1: Failed to read hash: hash type not supported
=== RUN TestListJSON/Metadata
--- PASS: TestListJSON (152.36s)
--- PASS: TestListJSON/Default (4.42s)
--- PASS: TestListJSON/FilesOnly (5.01s)
--- PASS: TestListJSON/DirsOnly (3.07s)
--- PASS: TestListJSON/Recurse (6.72s)
--- PASS: TestListJSON/SubDir (4.17s)
--- PASS: TestListJSON/NoModTime (6.07s)
--- PASS: TestListJSON/NoMimeType (9.53s)
--- PASS: TestListJSON/ShowHash (7.22s)
--- PASS: TestListJSON/HashTypes (5.16s)
--- PASS: TestListJSON/Metadata (4.21s)
=== RUN TestStatJSON
run.go:185: Remote "drime root 'rclone-test-nezuzih4tupa'", Local "Local file system at /tmp/rclone819691579", Modify Window "876000h0m0s"
2026/03/24 05:49:07 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/03/24 05:49:07 DEBUG : pacer: low level retry 1/1 (error Error "504 Gateway Timeout (504): \r\n504 Gateway Time-out\r\n\r\n504 Gateway Time-out
\r\n
nginx\r\n\r\n\r\n")
2026/03/24 05:49:07 DEBUG : pacer: Rate limited, increasing sleep to 20ms
run.go:286: Retry Put of "sub/file2" to drime root 'rclone-test-nezuzih4tupa': 1/10 (failed to upload file: Error "504 Gateway Timeout (504): \r\n504 Gateway Time-out\r\n\r\n504 Gateway Time-out
\r\n
nginx\r\n\r\n\r\n")
2026/03/24 05:49:15 DEBUG : pacer: Reducing sleep to 10ms
2026/03/24 05:49:23 DEBUG : sub/file2: Removing old object on successful upload
run.go:290: Failed to put "sub/file2" to "drime root 'rclone-test-nezuzih4tupa'": failed to delete existing object: failed to delete item: Error "The selected entry ids is invalid."
--- FAIL: TestStatJSON (189.43s)
=== RUN TestMkdir
run.go:185: Remote "drime root 'rclone-test-nezuzih4tupa'", Local "Local file system at /tmp/rclone819691579", Modify Window "876000h0m0s"
2026/03/24 05:50:27 INFO : drime root 'rclone-test-nezuzih4tupa': Making directory
2026/03/24 05:50:31 INFO : drime root 'rclone-test-nezuzih4tupa': Making directory
--- PASS: TestMkdir (15.90s)
=== RUN TestLsd
run.go:185: Remote "drime root 'rclone-test-nezuzih4tupa'", Local "Local file system at /tmp/rclone819691579", Modify Window "876000h0m0s"
2026/03/24 05:52:00 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/03/24 05:52:00 DEBUG : pacer: low level retry 1/1 (error Error "504 Gateway Timeout (504): \r\n504 Gateway Time-out\r\n\r\n504 Gateway Time-out
\r\n
nginx\r\n\r\n\r\n")
2026/03/24 05:52:00 DEBUG : pacer: Rate limited, increasing sleep to 20ms
run.go:286: Retry Put of "sub dir/hello world" to drime root 'rclone-test-nezuzih4tupa': 1/10 (failed to upload file: Error "504 Gateway Timeout (504): \r\n504 Gateway Time-out\r\n\r\n504 Gateway Time-out
\r\n
nginx\r\n\r\n\r\n")
2026/03/24 05:52:08 DEBUG : pacer: Reducing sleep to 10ms
2026/03/24 05:52:15 DEBUG : sub dir/hello world: Removing old object on successful upload
run.go:290: Failed to put "sub dir/hello world" to "drime root 'rclone-test-nezuzih4tupa'": failed to delete existing object: failed to delete item: Error "The selected entry ids is invalid."
--- FAIL: TestLsd (119.39s)
=== RUN TestLs
run.go:185: Remote "drime root 'rclone-test-nezuzih4tupa'", Local "Local file system at /tmp/rclone819691579", Modify Window "876000h0m0s"
--- PASS: TestLs (55.40s)
=== RUN TestLsWithFilesFrom
run.go:185: Remote "drime root 'rclone-test-nezuzih4tupa'", Local "Local file system at /tmp/rclone819691579", Modify Window "876000h0m0s"
2026/03/24 05:54:13 DEBUG : empty space: Excluded (FilesFrom Filter)
2026/03/24 05:54:13 DEBUG : empty space: Excluded
--- PASS: TestLsWithFilesFrom (55.25s)
=== RUN TestLsLong
run.go:185: Remote "drime root 'rclone-test-nezuzih4tupa'", Local "Local file system at /tmp/rclone819691579", Modify Window "876000h0m0s"
--- PASS: TestLsLong (63.48s)
=== RUN TestHashSums
run.go:185: Remote "drime root 'rclone-test-nezuzih4tupa'", Local "Local file system at /tmp/rclone819691579", Modify Window "876000h0m0s"
--- PASS: TestHashSums (83.73s)
=== RUN TestHashSumsWithErrors
2026/03/24 05:57:00 DEBUG : Creating backend with remote ":memory:"
2026/03/24 05:57:00 DEBUG : Config file has changed externally - reloading
2026/03/24 05:57:00 ERROR : file1: hash unsupported: hash type not supported
--- PASS: TestHashSumsWithErrors (0.00s)
=== RUN TestHashStream
2026/03/24 05:57:00 DEBUG : Creating md5 hash of 0 bytes read from input stream
2026/03/24 05:57:00 DEBUG : Creating md5 hash of 0 bytes read from input stream
2026/03/24 05:57:00 DEBUG : Creating sha1 hash of 0 bytes read from input stream
2026/03/24 05:57:00 DEBUG : Creating sha1 hash of 0 bytes read from input stream
2026/03/24 05:57:00 DEBUG : Creating md5 hash of 12 bytes read from input stream
2026/03/24 05:57:00 DEBUG : Creating md5 hash of 12 bytes read from input stream
2026/03/24 05:57:00 DEBUG : Creating sha1 hash of 12 bytes read from input stream
2026/03/24 05:57:00 DEBUG : Creating sha1 hash of 12 bytes read from input stream
--- PASS: TestHashStream (0.00s)
=== RUN TestSuffixName
--- PASS: TestSuffixName (0.00s)
=== RUN TestCount
run.go:185: Remote "drime root 'rclone-test-nezuzih4tupa'", Local "Local file system at /tmp/rclone819691579", Modify Window "876000h0m0s"
--- PASS: TestCount (161.76s)
=== RUN TestDelete
run.go:185: Remote "drime root 'rclone-test-nezuzih4tupa'", Local "Local file system at /tmp/rclone819691579", Modify Window "876000h0m0s"
2026/03/24 06:00:54 DEBUG : Waiting for deletions to finish
2026/03/24 06:01:00 DEBUG : large: Excluded (Size Filter)
2026/03/24 06:01:00 DEBUG : large: Excluded
2026/03/24 06:01:09 INFO : medium: Deleted
2026/03/24 06:01:09 INFO : small: Deleted
--- PASS: TestDelete (114.78s)
=== RUN TestMaxDelete
run.go:185: Remote "drime root 'rclone-test-nezuzih4tupa'", Local "Local file system at /tmp/rclone819691579", Modify Window "876000h0m0s"
2026/03/24 06:03:07 DEBUG : Waiting for deletions to finish
2026/03/24 06:03:15 ERROR : small: Got fatal error on delete: --max-delete threshold reached
2026/03/24 06:03:20 INFO : large: Deleted
2026/03/24 06:03:20 INFO : medium: Deleted
--- PASS: TestMaxDelete (140.88s)
=== RUN TestMaxDeleteSizeLargeFile
run.go:185: Remote "drime root 'rclone-test-nezuzih4tupa'", Local "Local file system at /tmp/rclone819691579", Modify Window "876000h0m0s"
2026/03/24 06:05:10 DEBUG : Waiting for deletions to finish
2026/03/24 06:05:13 ERROR : large: Got fatal error on delete: --max-delete-size threshold reached
2026/03/24 06:05:18 INFO : medium: Deleted
2026/03/24 06:05:18 INFO : small: Deleted
--- PASS: TestMaxDeleteSizeLargeFile (102.98s)
=== RUN TestMaxDeleteSize
run.go:185: Remote "drime root 'rclone-test-nezuzih4tupa'", Local "Local file system at /tmp/rclone819691579", Modify Window "876000h0m0s"
2026/03/24 06:06:09 DEBUG : Waiting for deletions to finish
2026/03/24 06:06:11 ERROR : small: Got fatal error on delete: --max-delete-size threshold reached
2026/03/24 06:06:13 INFO : large: Deleted
2026/03/24 06:06:13 INFO : medium: Deleted
--- PASS: TestMaxDeleteSize (58.94s)
=== RUN TestReadFile
run.go:185: Remote "drime root 'rclone-test-nezuzih4tupa'", Local "Local file system at /tmp/rclone819691579", Modify Window "876000h0m0s"
--- PASS: TestReadFile (72.28s)
=== RUN TestRetry
2026/03/24 06:07:52 DEBUG : Received error: Wrapped EOF is retriable: EOF - low level retry 1/5
2026/03/24 06:07:52 DEBUG : Received error: Wrapped EOF is retriable: EOF - low level retry 2/5
2026/03/24 06:07:52 DEBUG : Sleeping for 10ms (as indicated by the server) to obey Retry-After error: BANG: trying again in 10ms
2026/03/24 06:07:52 DEBUG : Sleeping for 10ms (as indicated by the server) to obey Retry-After error: BANG: trying again in 10ms
2026/03/24 06:07:52 DEBUG : Sleeping for 10ms (as indicated by the server) to obey Retry-After error: BANG: trying again in 10ms
2026/03/24 06:07:52 DEBUG : Sleeping for 10ms (as indicated by the server) to obey Retry-After error: BANG: trying again in 10ms
2026/03/24 06:07:52 DEBUG : Sleeping for 10ms (as indicated by the server) to obey Retry-After error: BANG: trying again in 10ms
--- PASS: TestRetry (0.05s)
=== RUN TestCat
run.go:185: Remote "drime root 'rclone-test-nezuzih4tupa'", Local "Local file system at /tmp/rclone819691579", Modify Window "876000h0m0s"
--- PASS: TestCat (167.15s)
=== RUN TestPurge
2026/03/24 06:10:39 DEBUG : Creating backend with remote "TestDrime:rclone-test-sohanux8duza"
2026/03/24 06:10:39 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/03/24 06:10:51 DEBUG : Creating backend with remote "/tmp/rclone2432485717"
run.go:185: Remote "drime root 'rclone-test-sohanux8duza'", Local "Local file system at /tmp/rclone2432485717", Modify Window "876000h0m0s"
2026/03/24 06:12:13 INFO : A2: Making directory
2026/03/24 06:12:25 INFO : A1/B2: Making directory
2026/03/24 06:12:33 INFO : A1/B2/C2: Making directory
2026/03/24 06:12:47 INFO : A1/B1/C3: Making directory
2026/03/24 06:12:59 INFO : A3: Making directory
2026/03/24 06:13:15 INFO : A3/B3: Making directory
2026/03/24 06:13:30 INFO : A3/B3/C4: Making directory
2026/03/24 06:14:59 ERROR : error listing: directory not found
2026/03/24 06:14:59 DEBUG : drime root 'rclone-test-sohanux8duza': Purge remote
2026/03/24 06:15:02 NOTICE: purge failed: directory not found
--- PASS: TestPurge (263.53s)
=== RUN TestRmdirsNoLeaveRoot
run.go:185: Remote "drime root 'rclone-test-nezuzih4tupa'", Local "Local file system at /tmp/rclone819691579", Modify Window "876000h0m0s"
2026/03/24 06:16:01 INFO : A2: Making directory
2026/03/24 06:16:15 INFO : A1/B2: Making directory
2026/03/24 06:16:26 INFO : A1/B2/C2: Making directory
2026/03/24 06:16:38 INFO : A1/B1/C3: Making directory
2026/03/24 06:16:50 INFO : A3: Making directory
2026/03/24 06:17:05 INFO : A3/B3: Making directory
2026/03/24 06:17:18 INFO : A3/B3/C4: Making directory
2026/03/24 06:18:24 DEBUG : removing 1 level 3 directories
2026/03/24 06:18:24 INFO : A3/B3/C4: Removing directory
2026/03/24 06:19:20 DEBUG : removing 2 level 3 directories
2026/03/24 06:19:20 INFO : A1/B2/C2: Removing directory
2026/03/24 06:19:20 INFO : A1/B1/C3: Removing directory
2026/03/24 06:19:39 DEBUG : removing 2 level 2 directories
2026/03/24 06:19:39 INFO : A3/B3: Removing directory
2026/03/24 06:19:39 INFO : A1/B2: Removing directory
2026/03/24 06:19:53 DEBUG : removing 2 level 1 directories
2026/03/24 06:19:53 INFO : A3: Removing directory
2026/03/24 06:19:53 INFO : A2: Removing directory
2026/03/24 06:21:21 DEBUG : removing 1 level 3 directories
2026/03/24 06:21:21 INFO : A1/B1/C1: Removing directory
2026/03/24 06:21:35 DEBUG : removing 1 level 2 directories
2026/03/24 06:21:35 INFO : A1/B1: Removing directory
2026/03/24 06:21:55 DEBUG : removing 1 level 1 directories
2026/03/24 06:21:55 INFO : A1: Removing directory
2026/03/24 06:22:09 DEBUG : removing 1 level 0 directories
2026/03/24 06:22:09 INFO : drime root 'rclone-test-nezuzih4tupa': Removing directory
2026/03/24 06:22:38 ERROR : error listing: directory not found
2026/03/24 06:22:49 ERROR : error listing: directory not found
--- PASS: TestRmdirsNoLeaveRoot (466.74s)
=== RUN TestRmdirsLeaveRoot
run.go:185: Remote "drime root 'rclone-test-nezuzih4tupa'", Local "Local file system at /tmp/rclone819691579", Modify Window "876000h0m0s"
2026/03/24 06:23:13 INFO : A1: Making directory
2026/03/24 06:23:29 INFO : A1/B1: Making directory
2026/03/24 06:23:41 INFO : A1/B1/C1: Making directory
2026/03/24 06:24:22 DEBUG : removing 1 level 3 directories
2026/03/24 06:24:22 INFO : A1/B1/C1: Removing directory
2026/03/24 06:24:32 DEBUG : removing 1 level 2 directories
2026/03/24 06:24:32 INFO : A1/B1: Removing directory
--- PASS: TestRmdirsLeaveRoot (146.44s)
=== RUN TestRmdirsWithFilter
run.go:185: Remote "drime root 'rclone-test-nezuzih4tupa'", Local "Local file system at /tmp/rclone819691579", Modify Window "876000h0m0s"
2026/03/24 06:25:15 INFO : A1: Making directory
2026/03/24 06:25:20 INFO : A1/B1: Making directory
2026/03/24 06:25:28 INFO : A1/B1/C1: Making directory
2026/03/24 06:26:21 DEBUG : removing 1 level 3 directories
2026/03/24 06:26:21 INFO : A1/B1/C1: Removing directory
2026/03/24 06:26:30 DEBUG : removing 1 level 2 directories
2026/03/24 06:26:30 INFO : A1/B1: Removing directory
--- PASS: TestRmdirsWithFilter (116.06s)
=== RUN TestCopyURL
run.go:185: Remote "drime root 'rclone-test-nezuzih4tupa'", Local "Local file system at /tmp/rclone819691579", Modify Window "876000h0m0s"
2026/03/24 06:27:26 DEBUG : filename.txt: File name found in url
2026/03/24 06:27:32 DEBUG : headerfilename.txt: filename found in Content-Disposition header.
--- PASS: TestCopyURL (75.76s)
=== RUN TestCopyURLDownloadHeaders
run.go:185: Remote "drime root 'rclone-test-nezuzih4tupa'", Local "Local file system at /tmp/rclone819691579", Modify Window "876000h0m0s"
--- PASS: TestCopyURLDownloadHeaders (20.08s)
=== RUN TestCopyURLToWriter
--- PASS: TestCopyURLToWriter (0.00s)
=== RUN TestMoveFile
run.go:185: Remote "drime root 'rclone-test-nezuzih4tupa'", Local "Local file system at /tmp/rclone819691579", Modify Window "876000h0m0s"
2026/03/24 06:28:50 DEBUG : file1: Need to transfer - File not found at Destination
2026/03/24 06:29:00 DEBUG : sub/file2: size = 14 OK
2026/03/24 06:29:00 INFO : file1: Copied (new) to: sub/file2
2026/03/24 06:29:00 INFO : file1: Deleted
2026/03/24 06:29:10 DEBUG : sub/file2: size = 14 OK
2026/03/24 06:29:10 DEBUG : file1: Sizes identical
2026/03/24 06:29:10 DEBUG : file1: Unchanged skipping
2026/03/24 06:29:10 INFO : file1: Deleted
2026/03/24 06:29:18 DEBUG : drime root 'rclone-test-nezuzih4tupa': don't need to copy/move sub/file2, it is already at target location
--- PASS: TestMoveFile (69.07s)
=== RUN TestMoveFileWithIgnoreExisting
run.go:185: Remote "drime root 'rclone-test-nezuzih4tupa'", Local "Local file system at /tmp/rclone819691579", Modify Window "876000h0m0s"
2026/03/24 06:29:59 DEBUG : file1: Need to transfer - File not found at Destination
2026/03/24 06:30:19 DEBUG : file1: size = 14 OK
2026/03/24 06:30:19 INFO : file1: Copied (new)
2026/03/24 06:30:19 INFO : file1: Deleted
2026/03/24 06:30:27 DEBUG : file1: Destination exists, skipping
2026/03/24 06:30:27 DEBUG : file1: Not removing source file as destination file exists and --ignore-existing is set
--- PASS: TestMoveFileWithIgnoreExisting (52.31s)
=== RUN TestCaseInsensitiveMoveFile
run.go:185: Remote "drime root 'rclone-test-nezuzih4tupa'", Local "Local file system at /tmp/rclone819691579", Modify Window "876000h0m0s"
--- PASS: TestCaseInsensitiveMoveFile (7.51s)
=== RUN TestCaseInsensitiveMoveFileDryRun
run.go:185: Remote "drime root 'rclone-test-nezuzih4tupa'", Local "Local file system at /tmp/rclone819691579", Modify Window "876000h0m0s"
--- PASS: TestCaseInsensitiveMoveFileDryRun (6.61s)
=== RUN TestMoveFileBackupDir
run.go:185: Remote "drime root 'rclone-test-nezuzih4tupa'", Local "Local file system at /tmp/rclone819691579", Modify Window "876000h0m0s"
2026/03/24 06:31:28 DEBUG : Creating backend with remote "TestDrime:rclone-test-nezuzih4tupa/backup"
2026/03/24 06:31:48 DEBUG : dst/file1: size = 14 (Local file system at /tmp/rclone819691579)
2026/03/24 06:31:48 DEBUG : dst/file1: size = 18 (drime root 'rclone-test-nezuzih4tupa')
2026/03/24 06:31:48 DEBUG : dst/file1: Sizes differ
2026/03/24 06:32:14 INFO : dst/file1: Moved (server-side)
2026/03/24 06:32:25 DEBUG : dst/file1: size = 14 OK
2026/03/24 06:32:25 INFO : dst/file1: Copied (new)
2026/03/24 06:32:25 INFO : dst/file1: Deleted
--- PASS: TestMoveFileBackupDir (139.92s)
=== RUN TestSameConfig
--- PASS: TestSameConfig (0.00s)
=== RUN TestSame
--- PASS: TestSame (0.00s)
=== RUN TestOverlappingFilterCheckWithoutFilter
--- PASS: TestOverlappingFilterCheckWithoutFilter (0.00s)
=== RUN TestOverlappingFilterCheckWithFilter
--- PASS: TestOverlappingFilterCheckWithFilter (0.00s)
=== RUN TestListFormat
--- PASS: TestListFormat (0.00s)
=== RUN TestDirMove
run.go:185: Remote "drime root 'rclone-test-nezuzih4tupa'", Local "Local file system at /tmp/rclone819691579", Modify Window "876000h0m0s"
2026/03/24 06:35:14 INFO : A1/B2: Making directory
2026/03/24 06:35:21 INFO : A1/B1/C3: Making directory
2026/03/24 06:46:27 DEBUG : pacer: low level retry 1/10 (error Put "https://app.drime.cloud/api/v1/file-entries/526036875": read tcp 65.108.87.141:48106->57.130.54.115:443: read: connection reset by peer)
2026/03/24 06:46:27 DEBUG : pacer: Rate limited, increasing sleep to 20ms
panic: test timed out after 2h0m0s
running tests:
TestDirMove (24m16s)
goroutine 127339 [running]:
testing.(*M).startAlarm.func1()
/usr/local/go/src/testing/testing.go:2802 +0x34b
created by time.goFunc
/usr/local/go/src/time/sleep.go:215 +0x2d
goroutine 1 [chan receive, 24 minutes]:
testing.(*T).Run(0x2581edfd6fc8, {0x29e3854?, 0x2581ee2a9ae8?}, 0x2b58520)
/usr/local/go/src/testing/testing.go:2109 +0x4e5
testing.runTests.func1(0x2581edfd6fc8)
/usr/local/go/src/testing/testing.go:2585 +0x37
testing.tRunner(0x2581edfd6fc8, 0x2581ee2a9c10)
/usr/local/go/src/testing/testing.go:2036 +0xea
testing.runTests({0x2a0f78c, 0x18}, {0x2a491d6, 0x26}, 0x2581edb64618, {0x45ba5c0, 0x71, 0x71}, {0xc268ab58d7640e07, 0x68f29bbe2f0, ...})
/usr/local/go/src/testing/testing.go:2583 +0x505
testing.(*M).Run(0x2581ee06a780)
/usr/local/go/src/testing/testing.go:2443 +0x6ac
github.com/rclone/rclone/fstest.TestMain(0x2581ee06a780)
/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:74 +0xa6
github.com/rclone/rclone/fs/operations_test.TestMain(...)
/home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:61
main.main()
_testmain.go:274 +0xa6
goroutine 37 [syscall, 120 minutes]:
os/signal.signal_recv()
/usr/local/go/src/runtime/sigqueue.go:152 +0x98
os/signal.loop()
/usr/local/go/src/os/signal/signal_unix.go:23 +0x13
created by os/signal.Notify.func1.1 in goroutine 1
/usr/local/go/src/os/signal/signal.go:152 +0x1f
goroutine 38 [chan receive, 120 minutes]:
github.com/rclone/rclone/fs/accounting.(*tokenBucket).startSignalHandler.func1()
/home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:24 +0x27
created by github.com/rclone/rclone/fs/accounting.(*tokenBucket).startSignalHandler in goroutine 1
/home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:21 +0xa7
goroutine 1373 [chan receive, 108 minutes]:
github.com/rclone/rclone/fs/accounting.(*tokenBucket).startSignalHandler.func1()
/home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:24 +0x27
created by github.com/rclone/rclone/fs/accounting.(*tokenBucket).startSignalHandler in goroutine 1372
/home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:21 +0xa7
goroutine 1787 [chan receive, 104 minutes]:
github.com/rclone/rclone/fs/accounting.(*tokenBucket).startSignalHandler.func1()
/home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:24 +0x27
created by github.com/rclone/rclone/fs/accounting.(*tokenBucket).startSignalHandler in goroutine 1372
/home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:21 +0xa7
goroutine 127338 [IO wait]:
internal/poll.runtime_pollWait(0x7f922688ae00, 0x72)
/usr/local/go/src/runtime/netpoll.go:351 +0x85
internal/poll.(*pollDesc).wait(0x2581edf48a00?, 0x2581ee2d9900?, 0x0)
/usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x27
internal/poll.(*pollDesc).waitRead(...)
/usr/local/go/src/internal/poll/fd_poll_runtime.go:89
internal/poll.(*FD).Read(0x2581edf48a00, {0x2581ee2d9900, 0x4d0, 0x4d0})
/usr/local/go/src/internal/poll/fd_unix.go:165 +0x2ae
net.(*netFD).Read(0x2581edf48a00, {0x2581ee2d9900?, 0x2581ee190f88?, 0x493069?})
/usr/local/go/src/net/fd_posix.go:68 +0x25
net.(*conn).Read(0x2581edf50288, {0x2581ee2d9900?, 0x0?, 0x0?})
/usr/local/go/src/net/net.go:196 +0x45
net.dnsPacketRoundTrip({_, _}, _, {{{0x61, 0x70, 0x70, 0x2e, 0x64, 0x72, 0x69, ...}, ...}, ...}, ...)
/usr/local/go/src/net/dnsclient_unix.go:116 +0x11f
net.(*Resolver).exchange(_, {_, _}, {_, _}, {{{0x61, 0x70, 0x70, 0x2e, 0x64, ...}, ...}, ...}, ...)
/usr/local/go/src/net/dnsclient_unix.go:195 +0x4af
net.(*Resolver).tryOneName(0x45cfba0, {0x2b9e588, 0x2581edccbdb0}, 0x2581ee188280, {0x2581ee4eeb40, 0x10}, 0x1c)
/usr/local/go/src/net/dnsclient_unix.go:316 +0x545
net.(*Resolver).goLookupIPCNAMEOrder.func3.1(0xee00?)
/usr/local/go/src/net/dnsclient_unix.go:667 +0x6c
created by net.(*Resolver).goLookupIPCNAMEOrder.func3 in goroutine 127336
/usr/local/go/src/net/dnsclient_unix.go:666 +0x13f
goroutine 682 [chan receive (nil chan), 115 minutes]:
github.com/rclone/rclone/fs/march.(*March).Run.func2()
/home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:259 +0x3c
created by github.com/rclone/rclone/fs/march.(*March).Run in goroutine 672
/home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:257 +0x359
goroutine 1522 [chan receive, 108 minutes]:
github.com/rclone/rclone/fs/accounting.(*tokenBucket).startSignalHandler.func1()
/home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:24 +0x27
created by github.com/rclone/rclone/fs/accounting.(*tokenBucket).startSignalHandler in goroutine 1372
/home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:21 +0xa7
goroutine 199 [chan receive (nil chan), 120 minutes]:
github.com/rclone/rclone/fs/march.(*March).Run.func2()
/home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:259 +0x3c
created by github.com/rclone/rclone/fs/march.(*March).Run in goroutine 125
/home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:257 +0x359
goroutine 415 [chan receive (nil chan), 117 minutes]:
github.com/rclone/rclone/fs/march.(*March).Run.func2()
/home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:259 +0x3c
created by github.com/rclone/rclone/fs/march.(*March).Run in goroutine 405
/home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:257 +0x359
goroutine 800 [chan receive (nil chan), 115 minutes]:
github.com/rclone/rclone/fs/march.(*March).Run.func2()
/home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:259 +0x3c
created by github.com/rclone/rclone/fs/march.(*March).Run in goroutine 790
/home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:257 +0x359
goroutine 6614 [select]:
net/http.(*Transport).getConn(0x2581ee312820, 0x2581edccbd10, {{}, 0x0, {0x2581ee4a8f80, 0x5}, {0x2581ee4ff4d0, 0x13}, 0x0})
/usr/local/go/src/net/http/transport.go:1552 +0x4b0
net/http.(*Transport).roundTrip(0x2581ee312820, 0x2581ee22bcc0)
/usr/local/go/src/net/http/transport.go:693 +0xa7f
net/http.(*Transport).RoundTrip(...)
/usr/local/go/src/net/http/roundtrip.go:33
github.com/rclone/rclone/fs/fshttp.(*Transport).RoundTrip(0x2581edbfcd20, 0x2581ee22bcc0)
/home/rclone/go/src/github.com/rclone/rclone/fs/fshttp/http.go:496 +0x34a
net/http.send(0x2581ee22bcc0, {0x2b73d00, 0x2581edbfcd20}, {0x2581ee148b68?, 0x4936a6?, 0x0?})
/usr/local/go/src/net/http/client.go:264 +0x64b
net/http.(*Client).send(0x2581eded3560, 0x2581ee22bcc0, {0x2581ee49d101?, 0x2581ee148bf0?, 0x0?})
/usr/local/go/src/net/http/client.go:185 +0x258
net/http.(*Client).do(0x2581eded3560, 0x2581ee22bcc0)
/usr/local/go/src/net/http/client.go:733 +0x9d7
net/http.(*Client).Do(...)
/usr/local/go/src/net/http/client.go:592
github.com/rclone/rclone/lib/rest.(*Client).Call(0x2581edf8a000, {0x2b9e438, 0x45f6c60}, 0x2581ee149258)
/home/rclone/go/src/github.com/rclone/rclone/lib/rest/rest.go:371 +0xd85
github.com/rclone/rclone/lib/rest.(*Client).callCodec(0x2581edf8a000, {0x2b9e438, 0x45f6c60}, 0x2581ee523b80?, {0x2313c80?, 0x2581ee034e40?}, {0x2313cc0, 0x2581edb50e00}, 0x2581ee149400?, 0x2b59a48, ...)
/home/rclone/go/src/github.com/rclone/rclone/lib/rest/rest.go:579 +0x447
github.com/rclone/rclone/lib/rest.(*Client).CallJSON(...)
/home/rclone/go/src/github.com/rclone/rclone/lib/rest/rest.go:522
github.com/rclone/rclone/backend/drime.(*Fs).patch.func1()
/home/rclone/go/src/github.com/rclone/rclone/backend/drime/drime.go:794 +0x8d
github.com/rclone/rclone/fs.pacerInvoker(0x2, 0xa, 0x2b73000?)
/home/rclone/go/src/github.com/rclone/rclone/fs/pacer.go:86 +0x32
github.com/rclone/rclone/lib/pacer.(*Pacer).call(0x2581edbfcd80, 0x2581edf8a870, 0xa)
/home/rclone/go/src/github.com/rclone/rclone/lib/pacer/pacer.go:228 +0xd2
github.com/rclone/rclone/lib/pacer.(*Pacer).Call(0x2581edbfcd80, 0x2581edf8a870)
/home/rclone/go/src/github.com/rclone/rclone/lib/pacer/pacer.go:247 +0x92
github.com/rclone/rclone/backend/drime.(*Fs).patch(0x2581ee188000, {0x2b9e438, 0x45f6c60}, {0x2581edf3a240, 0x9}, {0x29cda41, 0x4}, {0x2b5f2c8, 0x2})
/home/rclone/go/src/github.com/rclone/rclone/backend/drime/drime.go:793 +0x3ae
github.com/rclone/rclone/backend/drime.(*Fs).rename(0x2581ee188000, {0x2b9e438, 0x45f6c60}, {0x2581edf3a240, 0x9}, {0x2b5f2c8, 0x2})
/home/rclone/go/src/github.com/rclone/rclone/backend/drime/drime.go:805 +0x96
github.com/rclone/rclone/backend/drime.(*Fs).moveTo(0x2581ee188000, {0x2b9e438, 0x45f6c60}, {0x2581edf3a240, 0x9}, {0x29cc344, 0x2}, {0x2b5f2c8, 0x2}, {0x2581ee980f90, ...}, ...)
/home/rclone/go/src/github.com/rclone/rclone/backend/drime/drime.go:865 +0x305
github.com/rclone/rclone/backend/drime.(*Fs).DirMove(0x2581ee188000, {0x2b9e438, 0x45f6c60}, {0x2bb9f80?, 0x2581ee188000}, {0x29cc344, 0x2}, {0x2b5f2c8?, 0x0?})
/home/rclone/go/src/github.com/rclone/rclone/backend/drime/drime.go:943 +0x125
github.com/rclone/rclone/fs/operations.DirMove({0x2b9e438, 0x45f6c60}, {0x2bb9f80, 0x2581ee188000}, {0x29cc344, 0x2}, {0x2b5f2c8, 0x2})
/home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations.go:2407 +0x123
github.com/rclone/rclone/fs/operations_test.TestDirMove(0x2581edf6c908)
/home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:1411 +0x8d7
testing.tRunner(0x2581edf6c908, 0x2b58520)
/usr/local/go/src/testing/testing.go:2036 +0xea
created by testing.(*T).Run in goroutine 1
/usr/local/go/src/testing/testing.go:2101 +0x4c5
goroutine 912 [chan receive (nil chan), 114 minutes]:
github.com/rclone/rclone/fs/march.(*March).Run.func2()
/home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:259 +0x3c
created by github.com/rclone/rclone/fs/march.(*March).Run in goroutine 902
/home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:257 +0x359
goroutine 249 [chan receive (nil chan), 118 minutes]:
github.com/rclone/rclone/fs/march.(*March).Run.func2()
/home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:259 +0x3c
created by github.com/rclone/rclone/fs/march.(*March).Run in goroutine 239
/home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:257 +0x359
goroutine 1403 [chan receive, 106 minutes]:
github.com/rclone/rclone/fs/accounting.(*tokenBucket).startSignalHandler.func1()
/home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:24 +0x27
created by github.com/rclone/rclone/fs/accounting.(*tokenBucket).startSignalHandler in goroutine 1372
/home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:21 +0xa7
goroutine 474 [chan receive (nil chan), 117 minutes]:
github.com/rclone/rclone/fs/march.(*March).Run.func2()
/home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:259 +0x3c
created by github.com/rclone/rclone/fs/march.(*March).Run in goroutine 433
/home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:257 +0x359
goroutine 1163 [chan receive (nil chan), 112 minutes]:
github.com/rclone/rclone/fs/march.(*March).Run.func2()
/home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:259 +0x3c
created by github.com/rclone/rclone/fs/march.(*March).Run in goroutine 977
/home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:257 +0x359
goroutine 821 [chan receive (nil chan), 115 minutes]:
github.com/rclone/rclone/fs/march.(*March).Run.func2()
/home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:259 +0x3c
created by github.com/rclone/rclone/fs/march.(*March).Run in goroutine 779
/home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:257 +0x359
goroutine 276 [chan receive (nil chan), 118 minutes]:
github.com/rclone/rclone/fs/march.(*March).Run.func2()
/home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:259 +0x3c
created by github.com/rclone/rclone/fs/march.(*March).Run in goroutine 266
/home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:257 +0x359
goroutine 1534 [chan receive, 106 minutes]:
github.com/rclone/rclone/fs/accounting.(*tokenBucket).startSignalHandler.func1()
/home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:24 +0x27
created by github.com/rclone/rclone/fs/accounting.(*tokenBucket).startSignalHandler in goroutine 1372
/home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:21 +0xa7
goroutine 1950 [chan receive, 102 minutes]:
github.com/rclone/rclone/fs/accounting.(*tokenBucket).startSignalHandler.func1()
/home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:24 +0x27
created by github.com/rclone/rclone/fs/accounting.(*tokenBucket).startSignalHandler in goroutine 1372
/home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:21 +0xa7
goroutine 2157 [chan receive, 100 minutes]:
github.com/rclone/rclone/fs/accounting.(*tokenBucket).startSignalHandler.func1()
/home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:24 +0x27
created by github.com/rclone/rclone/fs/accounting.(*tokenBucket).startSignalHandler in goroutine 1372
/home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:21 +0xa7
goroutine 127336 [runnable]:
net.(*Resolver).goLookupIPCNAMEOrder.func4({0x10?, 0x25ea200?}, 0xf?)
/usr/local/go/src/net/dnsclient_unix.go:673 +0x71
net.(*Resolver).goLookupIPCNAMEOrder(_, {_, _}, {_, _}, {_, _}, _, _)
/usr/local/go/src/net/dnsclient_unix.go:683 +0x832
net.(*Resolver).lookupIP(0x45cfba0, {0x2b9e588, 0x2581edccbdb0}, {0x29ccba0, 0x3}, {0x2581ee4ff4d0, 0xf})
/usr/local/go/src/net/lookup_unix.go:66 +0x131
net.init.func1({0x2b9e588?, 0x2581edccbdb0?}, 0x2581edccbd60?, {0x29ccba0?, 0x2581ee4a8f80?}, {0x2581ee4ff4d0?, 0x2581ee4ff4d0?})
/usr/local/go/src/net/hook.go:21 +0x37
net.(*Resolver).lookupIPAddr.func1()
/usr/local/go/src/net/lookup.go:335 +0x37
internal/singleflight.(*Group).doCall(0x45cfbb0, 0x2581edccbe00, {0x2581ee4ff4e8, 0x13}, 0x0?)
/usr/local/go/src/internal/singleflight/singleflight.go:93 +0x34
created by internal/singleflight.(*Group).DoChan in goroutine 127335
/usr/local/go/src/internal/singleflight/singleflight.go:86 +0x2ce
goroutine 399 [chan receive (nil chan), 117 minutes]:
github.com/rclone/rclone/fs/march.(*March).Run.func2()
/home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:259 +0x3c
created by github.com/rclone/rclone/fs/march.(*March).Run in goroutine 389
/home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:257 +0x359
goroutine 5290 [chan receive, 47 minutes]:
github.com/rclone/rclone/fs/accounting.(*tokenBucket).startSignalHandler.func1()
/home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:24 +0x27
created by github.com/rclone/rclone/fs/accounting.(*tokenBucket).startSignalHandler in goroutine 5289
/home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:21 +0xa7
goroutine 1093 [chan receive (nil chan), 114 minutes]:
github.com/rclone/rclone/fs/march.(*March).Run.func2()
/home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:259 +0x3c
created by github.com/rclone/rclone/fs/march.(*March).Run in goroutine 1083
/home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:257 +0x359
goroutine 305 [chan receive (nil chan), 118 minutes]:
github.com/rclone/rclone/fs/march.(*March).Run.func2()
/home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:259 +0x3c
created by github.com/rclone/rclone/fs/march.(*March).Run in goroutine 295
/home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:257 +0x359
goroutine 380 [chan receive (nil chan), 118 minutes]:
github.com/rclone/rclone/fs/march.(*March).Run.func2()
/home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:259 +0x3c
created by github.com/rclone/rclone/fs/march.(*March).Run in goroutine 370
/home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:257 +0x359
goroutine 1992 [chan receive, 100 minutes]:
github.com/rclone/rclone/fs/accounting.(*tokenBucket).startSignalHandler.func1()
/home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:24 +0x27
created by github.com/rclone/rclone/fs/accounting.(*tokenBucket).startSignalHandler in goroutine 1372
/home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:21 +0xa7
goroutine 954 [chan receive (nil chan), 114 minutes]:
github.com/rclone/rclone/fs/march.(*March).Run.func2()
/home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:259 +0x3c
created by github.com/rclone/rclone/fs/march.(*March).Run in goroutine 944
/home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:257 +0x359
goroutine 535 [chan receive (nil chan), 117 minutes]:
github.com/rclone/rclone/fs/march.(*March).Run.func2()
/home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:259 +0x3c
created by github.com/rclone/rclone/fs/march.(*March).Run in goroutine 525
/home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:257 +0x359
goroutine 999 [chan receive (nil chan), 114 minutes]:
github.com/rclone/rclone/fs/march.(*March).Run.func2()
/home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:259 +0x3c
created by github.com/rclone/rclone/fs/march.(*March).Run in goroutine 989
/home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:257 +0x359
goroutine 1140 [chan receive (nil chan), 112 minutes]:
github.com/rclone/rclone/fs/march.(*March).Run.func2()
/home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:259 +0x3c
created by github.com/rclone/rclone/fs/march.(*March).Run in goroutine 1130
/home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:257 +0x359
goroutine 610 [chan receive (nil chan), 117 minutes]:
github.com/rclone/rclone/fs/march.(*March).Run.func2()
/home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:259 +0x3c
created by github.com/rclone/rclone/fs/march.(*March).Run in goroutine 600
/home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:257 +0x359
goroutine 572 [chan receive (nil chan), 117 minutes]:
github.com/rclone/rclone/fs/march.(*March).Run.func2()
/home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:259 +0x3c
created by github.com/rclone/rclone/fs/march.(*March).Run in goroutine 628
/home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:257 +0x359
goroutine 1925 [chan receive, 102 minutes]:
github.com/rclone/rclone/fs/accounting.(*tokenBucket).startSignalHandler.func1()
/home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:24 +0x27
created by github.com/rclone/rclone/fs/accounting.(*tokenBucket).startSignalHandler in goroutine 1372
/home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:21 +0xa7
goroutine 1672 [chan receive, 104 minutes]:
github.com/rclone/rclone/fs/accounting.(*tokenBucket).startSignalHandler.func1()
/home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:24 +0x27
created by github.com/rclone/rclone/fs/accounting.(*tokenBucket).startSignalHandler in goroutine 1372
/home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:21 +0xa7
goroutine 762 [chan receive (nil chan), 115 minutes]:
github.com/rclone/rclone/fs/march.(*March).Run.func2()
/home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:259 +0x3c
created by github.com/rclone/rclone/fs/march.(*March).Run in goroutine 752
/home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:257 +0x359
goroutine 1034 [chan receive (nil chan), 114 minutes]:
github.com/rclone/rclone/fs/march.(*March).Run.func2()
/home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:259 +0x3c
created by github.com/rclone/rclone/fs/march.(*March).Run in goroutine 1008
/home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:257 +0x359
goroutine 127335 [select]:
net.(*Resolver).lookupIPAddr(0x45cfba0, {0x2b9e5f8, 0x2581ee0e5880}, {0x29ccba0, 0x3}, {0x2581ee4ff4d0, 0xf})
/usr/local/go/src/net/lookup.go:343 +0x3cb
net.(*Resolver).internetAddrList(0x45cfba0?, {0x2b9e5f8?, 0x2581ee0e5880?}, {0x29ccba0, 0x3}, {0x2581ee4ff4d0?, 0x2581ee1a0690?})
/usr/local/go/src/net/ipsock.go:289 +0x4b3
net.(*Resolver).resolveAddrList(0x45cfba0, {0x2b9e5f8, 0x2581ee0e5880}, {0x29cdd8d, 0x4}, {0x29ccba0?, 0x4dab52?}, {0x2581ee4ff4d0, 0x13}, {0x0, ...})
/usr/local/go/src/net/dial.go:354 +0x3e5
net.(*Dialer).DialContext(0x2581ee188b40, {0x2b9e588?, 0x2581edccbd60?}, {0x29ccba0, 0x3}, {0x2581ee4ff4d0, 0x13})
/usr/local/go/src/net/dial.go:539 +0x1c5
github.com/rclone/rclone/fs/fshttp.(*Dialer).DialContext(0x2581ee188b40, {0x2b9e588, 0x2581edccbd60}, {0x29ccba0?, 0x7f922687f6c8?}, {0x2581ee4ff4d0, 0x13})
/home/rclone/go/src/github.com/rclone/rclone/fs/fshttp/dialer.go:62 +0x125
github.com/rclone/rclone/fs/fshttp.NewTransportCustom.func2({0x2b9e588, 0x2581edccbd60}, {0x29ccba0, 0x3}, {0x2581ee4ff4d0, 0x13})
/home/rclone/go/src/github.com/rclone/rclone/fs/fshttp/http.go:269 +0x5c
net/http.(*Transport).dial(0x7f926ed6d2f8?, {0x2b9e588?, 0x2581edccbd60?}, {0x29ccba0?, 0x2581ee1a09f8?}, {0x2581ee4ff4d0?, 0x10?})
/usr/local/go/src/net/http/transport.go:1307 +0xd2
net/http.(*Transport).dialConn(0x2581ee312820, {0x2b9e588, 0x2581edccbd60}, {{}, 0x0, {0x2581ee4a8f80, 0x5}, {0x2581ee4ff4d0, 0x13}, 0x0}, ...)
/usr/local/go/src/net/http/transport.go:1815 +0x847
net/http.(*Transport).dialConnFor(0x2581ee312820, 0x2581edf1c840)
/usr/local/go/src/net/http/transport.go:1648 +0xd2
net/http.(*Transport).startDialConnForLocked.func1()
/usr/local/go/src/net/http/transport.go:1629 +0x35
created by net/http.(*Transport).startDialConnForLocked in goroutine 6614
/usr/local/go/src/net/http/transport.go:1628 +0x112
"./operations.test -test.v -test.timeout 2h0m0s -remote TestDrime: -verbose" - Finished ERROR in 2h0m11.969031983s (try 1/5): exit status 2: Failed [TestMultithreadCopy TestMultithreadCopyAbort TestParseSumFile TestCheckSumDownload TestCopyFileBackupDir TestCopyFileCompareDest TestCopyFileCopyDest TestStatJSON TestLsd]