"./operations.test -test.v -test.timeout 1h0m0s -remote TestCryptDrive: -verbose -fast-list" - Starting (try 1/5) 2026/05/09 04:33:19 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-koxahix1nazu" 2026/05/09 04:33:19 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/05/09 04:33:19 DEBUG : Creating backend with remote "TestDrive:crypt/q5irbcg74s47er306k0erq792vnc8h60c1le4mietu585gn3ua7g" 2026/05/09 04:33:20 DEBUG : Creating backend with remote "/tmp/rclone1125200144" === 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:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-koxahix1nazu'", Local "Local file system at /tmp/rclone1125200144", Modify Window "1ms" multithread_test.go:119: multithread writing not supported --- SKIP: TestMultithreadCopy (0.24s) === RUN TestMultithreadCopyAbort run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-koxahix1nazu'", Local "Local file system at /tmp/rclone1125200144", Modify Window "1ms" multithread_test.go:119: multithread writing not supported --- SKIP: TestMultithreadCopyAbort (0.24s) === RUN TestSizeDiffers 2026/05/09 04:33:21 DEBUG : a: size = 0 OK 2026/05/09 04:33:21 DEBUG : a: size = 1 (memory) 2026/05/09 04:33:21 DEBUG : a: size = 2 (memory) --- PASS: TestSizeDiffers (0.00s) === RUN TestReOpen === RUN TestReOpen/Normal === RUN TestReOpen/Normal/Basics 2026/05/09 04:33:21 DEBUG : potato: Seek from 10 to 0 === RUN TestReOpen/Normal/ErrorAtStart === RUN TestReOpen/Normal/WithErrors 2026/05/09 04:33:21 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error 2026/05/09 04:33:21 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error 2026/05/09 04:33:21 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error === RUN TestReOpen/Normal/TooManyErrors 2026/05/09 04:33:21 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error 2026/05/09 04:33:21 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error 2026/05/09 04:33:21 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error 2026/05/09 04:33:21 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries === RUN TestReOpen/Normal/ReadAt 2026/05/09 04:33:21 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2026/05/09 04:33:21 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2026/05/09 04:33:21 DEBUG : potato: Seek from 5 to 1 === RUN TestReOpen/Normal/Seek 2026/05/09 04:33:21 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2026/05/09 04:33:21 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2026/05/09 04:33:21 DEBUG : potato: Seek from 5 to 2 === RUN TestReOpen/Normal/AccountRead === RUN TestReOpen/Normal/AccountReadDelay 2026/05/09 04:33:21 DEBUG : potato: Seek from 10 to 0 2026/05/09 04:33:21 DEBUG : potato: Seek from 10 to 0 2026/05/09 04:33:21 DEBUG : potato: Seek from 10 to 0 === RUN TestReOpen/Normal/AccountReadError === RUN TestReOpen/WithRangeOption === RUN TestReOpen/WithRangeOption/Basics 2026/05/09 04:33:21 DEBUG : potato: Seek from 7 to 0 === RUN TestReOpen/WithRangeOption/ErrorAtStart === RUN TestReOpen/WithRangeOption/WithErrors 2026/05/09 04:33:21 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error 2026/05/09 04:33:21 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error 2026/05/09 04:33:21 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error === RUN TestReOpen/WithRangeOption/TooManyErrors 2026/05/09 04:33:21 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error 2026/05/09 04:33:21 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error 2026/05/09 04:33:21 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error 2026/05/09 04:33:21 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries === RUN TestReOpen/WithRangeOption/ReadAt 2026/05/09 04:33:21 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2026/05/09 04:33:21 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2026/05/09 04:33:21 DEBUG : potato: Seek from 5 to 1 === RUN TestReOpen/WithRangeOption/Seek 2026/05/09 04:33:21 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2026/05/09 04:33:21 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2026/05/09 04:33:21 DEBUG : potato: Seek from 5 to 2 2026/05/09 04:33:21 DEBUG : potato: Seek from 7 to 4 === RUN TestReOpen/WithRangeOption/AccountRead === RUN TestReOpen/WithRangeOption/AccountReadDelay 2026/05/09 04:33:21 DEBUG : potato: Seek from 7 to 0 2026/05/09 04:33:21 DEBUG : potato: Seek from 7 to 0 2026/05/09 04:33:21 DEBUG : potato: Seek from 7 to 0 === RUN TestReOpen/WithRangeOption/AccountReadError === RUN TestReOpen/WithSeekOption === RUN TestReOpen/WithSeekOption/Basics 2026/05/09 04:33:21 DEBUG : potato: Seek from 8 to 0 === RUN TestReOpen/WithSeekOption/ErrorAtStart === RUN TestReOpen/WithSeekOption/WithErrors 2026/05/09 04:33:21 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error 2026/05/09 04:33:21 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error 2026/05/09 04:33:21 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error === RUN TestReOpen/WithSeekOption/TooManyErrors 2026/05/09 04:33:21 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error 2026/05/09 04:33:21 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error 2026/05/09 04:33:21 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error 2026/05/09 04:33:21 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries === RUN TestReOpen/WithSeekOption/ReadAt 2026/05/09 04:33:21 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2026/05/09 04:33:21 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2026/05/09 04:33:21 DEBUG : potato: Seek from 5 to 1 === RUN TestReOpen/WithSeekOption/Seek 2026/05/09 04:33:21 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2026/05/09 04:33:21 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2026/05/09 04:33:21 DEBUG : potato: Seek from 5 to 2 2026/05/09 04:33:21 DEBUG : potato: Seek from 7 to 5 === RUN TestReOpen/WithSeekOption/AccountRead === RUN TestReOpen/WithSeekOption/AccountReadDelay 2026/05/09 04:33:21 DEBUG : potato: Seek from 8 to 0 2026/05/09 04:33:21 DEBUG : potato: Seek from 8 to 0 2026/05/09 04:33:21 DEBUG : potato: Seek from 8 to 0 === RUN TestReOpen/WithSeekOption/AccountReadError === RUN TestReOpen/UnknownSize === RUN TestReOpen/UnknownSize/Basics 2026/05/09 04:33:21 DEBUG : potato: Seek from 9 to 0 === RUN TestReOpen/UnknownSize/ErrorAtStart === RUN TestReOpen/UnknownSize/WithErrors 2026/05/09 04:33:21 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error 2026/05/09 04:33:21 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error 2026/05/09 04:33:21 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error === RUN TestReOpen/UnknownSize/TooManyErrors 2026/05/09 04:33:21 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error 2026/05/09 04:33:21 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error 2026/05/09 04:33:21 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error 2026/05/09 04:33:21 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries === RUN TestReOpen/UnknownSize/ReadAt 2026/05/09 04:33:21 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2026/05/09 04:33:21 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2026/05/09 04:33:21 DEBUG : potato: Seek from 5 to 1 === RUN TestReOpen/UnknownSize/Seek 2026/05/09 04:33:21 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2026/05/09 04:33:21 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2026/05/09 04:33:21 DEBUG : potato: Seek from 5 to 2 2026/05/09 04:33:21 DEBUG : potato: Seek from 7 to 6 === RUN TestReOpen/UnknownSize/AccountRead === RUN TestReOpen/UnknownSize/AccountReadDelay 2026/05/09 04:33:21 DEBUG : potato: Seek from 9 to 0 2026/05/09 04:33:21 DEBUG : potato: Seek from 9 to 0 2026/05/09 04:33: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:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-koxahix1nazu'", Local "Local file system at /tmp/rclone1125200144", Modify Window "1ms" 2026/05/09 04:33:23 DEBUG : rutabaga: md5 = 8442e82c45b265504deca8c7f803a86d OK === RUN TestCheck/1 === RUN TestCheck/2 2026/05/09 04:33:25 DEBUG : empty space: md5 = c0fc7614147bc02103aa3ba7f82bd589 OK === RUN TestCheck/3 2026/05/09 04:33:27 DEBUG : potato2: md5 = c02278c14762e08eae8ec1608b78205a OK === RUN TestCheck/4 === RUN TestCheck/5 2026/05/09 04:33:29 DEBUG : remotepotato: md5 = 8d5d03977cc2f3c709a6fbf5365cea99 OK === RUN TestCheck/6 === RUN TestCheck/7 --- PASS: TestCheck (11.83s) --- PASS: TestCheck/1 (0.25s) --- PASS: TestCheck/2 (0.26s) --- PASS: TestCheck/3 (0.27s) --- PASS: TestCheck/4 (0.26s) --- PASS: TestCheck/5 (0.25s) --- PASS: TestCheck/6 (0.27s) --- PASS: TestCheck/7 (0.29s) === RUN TestCheckFsError 2026/05/09 04:33:33 DEBUG : Creating backend with remote "nonexistent" 2026/05/09 04:33:33 DEBUG : Creating backend with remote "nonexistent" 2026/05/09 04:33:33 DEBUG : Local file system at /home/rclone/go/src/github.com/rclone/rclone/fs/operations/nonexistent: Waiting for checks to finish 2026/05/09 04:33:33 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/05/09 04:33:33 NOTICE: Local file system at /home/rclone/go/src/github.com/rclone/rclone/fs/operations/nonexistent: 0 differences found 2026/05/09 04:33:33 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:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-koxahix1nazu'", Local "Local file system at /tmp/rclone1125200144", Modify Window "1ms" 2026/05/09 04:33:34 DEBUG : rutabaga: md5 = 53abbba4ea0c7386e15f4092fb049361 OK === RUN TestCheckDownload/1 === RUN TestCheckDownload/2 2026/05/09 04:33:38 DEBUG : empty space: md5 = 3df6622c301aa23ff9c901ffc6d3a442 OK === RUN TestCheckDownload/3 2026/05/09 04:33:40 DEBUG : potato2: md5 = 6f76674b4ae59ecc9122209da769676c OK === RUN TestCheckDownload/4 === RUN TestCheckDownload/5 2026/05/09 04:33:43 DEBUG : remotepotato: md5 = 7fab653e58ff020f051902242a050294 OK === RUN TestCheckDownload/6 === RUN TestCheckDownload/7 --- PASS: TestCheckDownload (15.05s) --- PASS: TestCheckDownload/1 (0.96s) --- PASS: TestCheckDownload/2 (0.78s) --- PASS: TestCheckDownload/3 (0.78s) --- PASS: TestCheckDownload/4 (0.94s) --- PASS: TestCheckDownload/5 (0.81s) --- PASS: TestCheckDownload/6 (0.79s) --- PASS: TestCheckDownload/7 (0.88s) === RUN TestCheckSizeOnly run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-koxahix1nazu'", Local "Local file system at /tmp/rclone1125200144", Modify Window "1ms" 2026/05/09 04:33:49 DEBUG : rutabaga: md5 = 8c2f6bea47c60fdc7eed67fa7b2ef57c OK === RUN TestCheckSizeOnly/1 === RUN TestCheckSizeOnly/2 2026/05/09 04:33:51 DEBUG : empty space: md5 = b60b0d7be1e00d4f4b09f922c6f65d1c OK === RUN TestCheckSizeOnly/3 2026/05/09 04:33:53 DEBUG : potato2: md5 = c49088dd37348e230eeab6a5abc3cde7 OK === RUN TestCheckSizeOnly/4 === RUN TestCheckSizeOnly/5 2026/05/09 04:33:55 DEBUG : remotepotato: md5 = ac9358d4b6f57c779f843560b3d4e0a0 OK === RUN TestCheckSizeOnly/6 === RUN TestCheckSizeOnly/7 --- PASS: TestCheckSizeOnly (10.89s) --- PASS: TestCheckSizeOnly/1 (0.27s) --- PASS: TestCheckSizeOnly/2 (0.26s) --- PASS: TestCheckSizeOnly/3 (0.26s) --- PASS: TestCheckSizeOnly/4 (0.25s) --- PASS: TestCheckSizeOnly/5 (0.26s) --- PASS: TestCheckSizeOnly/6 (0.25s) --- PASS: TestCheckSizeOnly/7 (0.28s) === RUN TestCheckEqualReaders --- PASS: TestCheckEqualReaders (0.00s) === RUN TestParseSumFile run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-koxahix1nazu'", Local "Local file system at /tmp/rclone1125200144", Modify Window "1ms" 2026/05/09 04:34:00 DEBUG : test.sum: md5 = 42aa4fb9b1265563a6e387070e9c256a OK 2026/05/09 04:34:01 NOTICE: test.sum: improperly formatted checksum line 4 2026/05/09 04:34:01 NOTICE: test.sum: improperly formatted checksum line 5 2026/05/09 04:34:01 NOTICE: test.sum: improperly formatted checksum line 6 2026/05/09 04:34:01 NOTICE: test.sum: 2 warning(s) suppressed... 2026/05/09 04:34:02 DEBUG : test.sum: md5 = 18f18e7e7f1a5027505cbf1264c1a09f OK 2026/05/09 04:34:03 NOTICE: test.sum: improperly formatted checksum line 4 2026/05/09 04:34:03 NOTICE: test.sum: improperly formatted checksum line 5 2026/05/09 04:34:03 NOTICE: test.sum: improperly formatted checksum line 6 2026/05/09 04:34:03 NOTICE: test.sum: 2 warning(s) suppressed... --- PASS: TestParseSumFile (6.01s) === RUN TestCheckSum run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-koxahix1nazu'", Local "Local file system at /tmp/rclone1125200144", Modify Window "1ms" 2026/05/09 04:34:04 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-koxahix1nazu/data" 2026/05/09 04:34:05 DEBUG : Creating backend with remote "TestDrive:crypt/q5irbcg74s47er306k0erq792vnc8h60c1le4mietu585gn3ua7g/vjrnln8ratgmqakfosrqe8espk" check_test.go:350: Encrypted drive 'TestCryptDrive:rclone-test-koxahix1nazu/data' lacks md5, skipping --- SKIP: TestCheckSum (1.94s) === RUN TestCheckSumDownload run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-koxahix1nazu'", Local "Local file system at /tmp/rclone1125200144", Modify Window "1ms" 2026/05/09 04:34:06 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-koxahix1nazu/data" 2026/05/09 04:34:06 DEBUG : Creating backend with remote "TestDrive:crypt/q5irbcg74s47er306k0erq792vnc8h60c1le4mietu585gn3ua7g/vjrnln8ratgmqakfosrqe8espk" 2026/05/09 04:34:10 DEBUG : data/banana: md5 = 77e6784b58665cac8efa247d8796d71d OK 2026/05/09 04:34:12 DEBUG : test.sum: md5 = 0adfe6d802e9c67bc2c1155f4581a579 OK === RUN TestCheckSumDownload/subtest1 2026/05/09 04:34:16 DEBUG : data/potato: md5 = 29e18bdb279a59cc258c24bb487d3ce4 OK 2026/05/09 04:34:18 DEBUG : test.sum: md5 = 4e4b41b7799c924fff023bc57a4d30a2 OK === RUN TestCheckSumDownload/subtest2 2026/05/09 04:34:22 DEBUG : test.sum: md5 = 748e9bfbafa785360fca61096a437655 OK === RUN TestCheckSumDownload/subtest3 2026/05/09 04:34:26 DEBUG : test.sum: md5 = 2b5037d87ebeb34ef4917ef0f57a78d3 OK === RUN TestCheckSumDownload/subtest4 2026/05/09 04:34:31 DEBUG : test.sum: md5 = fae0f4eecbfdef9beef208fa46d90c8c OK === RUN TestCheckSumDownload/subtest5 2026/05/09 04:34:35 DEBUG : test.sum: md5 = 543af85adb1989f642e2d4fd5af4fe7e OK === RUN TestCheckSumDownload/subtest6 2026/05/09 04:34:39 DEBUG : data/banana: md5 = c85f5c56512f8cfdcbdb4cf14cf34d47 OK 2026/05/09 04:34:40 DEBUG : data/potato: md5 = 34ad620cc97a9f511b1bc2dea9ea3365 OK 2026/05/09 04:34:42 DEBUG : test.sum: md5 = dd7bda3d9ac9983d6f66b8cbed15e1b4 OK === RUN TestCheckSumDownload/subtest7 2026/05/09 04:34:46 DEBUG : vjrnln8ratgmqakfosrqe8espk: Rmdir: contains trashed file: "2m507n64kafsi59v9q71r281a4" 2026/05/09 04:34:46 DEBUG : vjrnln8ratgmqakfosrqe8espk: Rmdir: contains trashed file: "igjr4utqjj43i7bn8okrqetjm0" --- PASS: TestCheckSumDownload (40.79s) --- PASS: TestCheckSumDownload/subtest1 (2.34s) --- PASS: TestCheckSumDownload/subtest2 (1.97s) --- PASS: TestCheckSumDownload/subtest3 (1.88s) --- PASS: TestCheckSumDownload/subtest4 (1.93s) --- PASS: TestCheckSumDownload/subtest5 (1.75s) --- PASS: TestCheckSumDownload/subtest6 (1.81s) --- PASS: TestCheckSumDownload/subtest7 (1.88s) === RUN TestApplyTransforms 2026/05/09 04:34:47 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-yiwokof9kiza" 2026/05/09 04:34:47 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/05/09 04:34:47 DEBUG : Creating backend with remote "TestDrive:crypt/rp30g12enhen45h1vaa5um2h39lt6gb2rad8aopnel5sq4tsps2g" 2026/05/09 04:34:48 DEBUG : Creating backend with remote "/tmp/rclone1637826572" run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-yiwokof9kiza'", Local "Local file system at /tmp/rclone1637826572", Modify Window "1ms" 2026/05/09 04:34:51 DEBUG : hello, world!: md5 = b58c8a99facc27a5cff6e22cf7e53c7e OK upper checkfile vs. lower remote (without normalization) 2026/05/09 04:34:51 ERROR : hello, world!: sum not found 2026/05/09 04:34:51 ERROR : HELLO, WORLD!: file not in Encrypted drive 'TestCryptDrive:rclone-test-yiwokof9kiza' 2026/05/09 04:34:51 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-yiwokof9kiza': 1 files missing 2026/05/09 04:34:51 NOTICE: 1 hashes missing 2026/05/09 04:34:51 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-yiwokof9kiza': 1 differences found 2026/05/09 04:34:51 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-yiwokof9kiza': 2 errors while checking upper checkfile vs. lower remote (with normalization) 2026/05/09 04:34:52 DEBUG : hello, world!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/05/09 04:34:52 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-yiwokof9kiza': 0 differences found 2026/05/09 04:34:52 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-yiwokof9kiza': 1 matching files 2026/05/09 04:34:52 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-quwilad0qina" 2026/05/09 04:34:52 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/05/09 04:34:52 DEBUG : Creating backend with remote "TestDrive:crypt/h627ch8js4nj515img9v1etbtl5an6egin398eurcq0inq5r4bf0" 2026/05/09 04:34:53 DEBUG : Creating backend with remote "/tmp/rclone2302652098" run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-quwilad0qina'", Local "Local file system at /tmp/rclone2302652098", Modify Window "1ms" 2026/05/09 04:34:55 DEBUG : HELLO, WORLD!: md5 = 6b9e6ec46fabc8c36e6b8efcb88029d4 OK lower checkfile vs. upper remote (without normalization) 2026/05/09 04:34:56 ERROR : HELLO, WORLD!: sum not found 2026/05/09 04:34:56 ERROR : hello, world!: file not in Encrypted drive 'TestCryptDrive:rclone-test-quwilad0qina' 2026/05/09 04:34:56 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-quwilad0qina': 1 files missing 2026/05/09 04:34:56 NOTICE: 1 hashes missing 2026/05/09 04:34:56 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-quwilad0qina': 1 differences found 2026/05/09 04:34:56 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-quwilad0qina': 2 errors while checking lower checkfile vs. upper remote (with normalization) 2026/05/09 04:34:57 DEBUG : HELLO, WORLD!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/05/09 04:34:57 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-quwilad0qina': 0 differences found 2026/05/09 04:34:57 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-quwilad0qina': 1 matching files 2026/05/09 04:34:57 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-hupagic4fize" 2026/05/09 04:34:57 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/05/09 04:34:57 DEBUG : Creating backend with remote "TestDrive:crypt/rpctkjsoalq0lf3mao6p0c5f2i5b9le7j63a7lq9a0dv8ppl6b2g" 2026/05/09 04:34:58 DEBUG : Creating backend with remote "/tmp/rclone4274689063" run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-hupagic4fize'", Local "Local file system at /tmp/rclone4274689063", Modify Window "1ms" 2026/05/09 04:35:00 DEBUG : HeLlO, wOrLd!: md5 = 3fe6769ca1df19cbfc862b76ea661e12 OK lower checkfile vs. upperlowermixed remote (without normalization) 2026/05/09 04:35:01 ERROR : HeLlO, wOrLd!: sum not found 2026/05/09 04:35:01 ERROR : hello, world!: file not in Encrypted drive 'TestCryptDrive:rclone-test-hupagic4fize' 2026/05/09 04:35:01 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-hupagic4fize': 1 files missing 2026/05/09 04:35:01 NOTICE: 1 hashes missing 2026/05/09 04:35:01 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-hupagic4fize': 1 differences found 2026/05/09 04:35:01 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-hupagic4fize': 2 errors while checking lower checkfile vs. upperlowermixed remote (with normalization) 2026/05/09 04:35:01 DEBUG : HeLlO, wOrLd!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/05/09 04:35:01 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-hupagic4fize': 0 differences found 2026/05/09 04:35:01 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-hupagic4fize': 1 matching files 2026/05/09 04:35:01 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-xarukaj1hace" 2026/05/09 04:35:01 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/05/09 04:35:02 DEBUG : Creating backend with remote "TestDrive:crypt/r21b9ivi3dm7vuvpp83lfkh1imb8ettd71niuoafq51ts4e51e10" 2026/05/09 04:35:02 DEBUG : Creating backend with remote "/tmp/rclone3509470873" run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-xarukaj1hace'", Local "Local file system at /tmp/rclone3509470873", Modify Window "1ms" 2026/05/09 04:35:05 DEBUG : HELLO, WORLD!: md5 = 8ad9f446e5b5a864222cb6ce5a0d8170 OK upperlowermixed checkfile vs. upper remote (without normalization) 2026/05/09 04:35:05 ERROR : HELLO, WORLD!: sum not found 2026/05/09 04:35:05 ERROR : HeLlO, wOrLd!: file not in Encrypted drive 'TestCryptDrive:rclone-test-xarukaj1hace' 2026/05/09 04:35:05 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-xarukaj1hace': 1 files missing 2026/05/09 04:35:05 NOTICE: 1 hashes missing 2026/05/09 04:35:05 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-xarukaj1hace': 1 differences found 2026/05/09 04:35:05 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-xarukaj1hace': 2 errors while checking upperlowermixed checkfile vs. upper remote (with normalization) 2026/05/09 04:35:06 DEBUG : HELLO, WORLD!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/05/09 04:35:06 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-xarukaj1hace': 0 differences found 2026/05/09 04:35:06 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-xarukaj1hace': 1 matching files 2026/05/09 04:35:06 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-cinolol1cujo" 2026/05/09 04:35:06 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/05/09 04:35:06 DEBUG : Creating backend with remote "TestDrive:crypt/0i1omdqfa5a90egi5t9qapgpn3jmnb5n2p72oiad2dauk4k8hhc0" 2026/05/09 04:35:07 DEBUG : Creating backend with remote "/tmp/rclone4229037584" run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-cinolol1cujo'", Local "Local file system at /tmp/rclone4229037584", Modify Window "1ms" 2026/05/09 04:35:09 DEBUG : 測試_Русский___ě_áñ: md5 = c43df59924374ba230c72507ea8e37b4 OK NFD checkfile vs. NFC remote (without normalization) 2026/05/09 04:35:10 ERROR : 測試_Русский___ě_áñ: sum not found 2026/05/09 04:35:10 ERROR : 測試_Русский___ě_áñ: file not in Encrypted drive 'TestCryptDrive:rclone-test-cinolol1cujo' 2026/05/09 04:35:10 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-cinolol1cujo': 1 files missing 2026/05/09 04:35:10 NOTICE: 1 hashes missing 2026/05/09 04:35:10 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-cinolol1cujo': 1 differences found 2026/05/09 04:35:10 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-cinolol1cujo': 2 errors while checking NFD checkfile vs. NFC remote (with normalization) 2026/05/09 04:35:11 DEBUG : 測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/05/09 04:35:11 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-cinolol1cujo': 0 differences found 2026/05/09 04:35:11 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-cinolol1cujo': 1 matching files 2026/05/09 04:35:11 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-weruyak9huto" 2026/05/09 04:35:11 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/05/09 04:35:11 DEBUG : Creating backend with remote "TestDrive:crypt/fbjd04875g75peb5svu490ail3u228r8mq2n8480c3mk7okmgb9g" 2026/05/09 04:35:12 DEBUG : Creating backend with remote "/tmp/rclone3999468908" run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-weruyak9huto'", Local "Local file system at /tmp/rclone3999468908", Modify Window "1ms" 2026/05/09 04:35:14 DEBUG : 測試_Русский___ě_áñ: md5 = 5d1ac256069fc3c606ca199248a5455d OK NFC checkfile vs. NFD remote (without normalization) 2026/05/09 04:35:15 ERROR : 測試_Русский___ě_áñ: sum not found 2026/05/09 04:35:15 ERROR : 測試_Русский___ě_áñ: file not in Encrypted drive 'TestCryptDrive:rclone-test-weruyak9huto' 2026/05/09 04:35:15 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-weruyak9huto': 1 files missing 2026/05/09 04:35:15 NOTICE: 1 hashes missing 2026/05/09 04:35:15 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-weruyak9huto': 1 differences found 2026/05/09 04:35:15 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-weruyak9huto': 2 errors while checking NFC checkfile vs. NFD remote (with normalization) 2026/05/09 04:35:16 DEBUG : 測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/05/09 04:35:16 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-weruyak9huto': 0 differences found 2026/05/09 04:35:16 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-weruyak9huto': 1 matching files 2026/05/09 04:35:16 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-jufocam2kohu" 2026/05/09 04:35:16 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/05/09 04:35:16 DEBUG : Creating backend with remote "TestDrive:crypt/951l96e1tjjgpqtlkr14ur0rl7j97739jlo6l18sr2erngtcia9g" 2026/05/09 04:35:17 DEBUG : Creating backend with remote "/tmp/rclone373872109" run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jufocam2kohu'", Local "Local file system at /tmp/rclone373872109", Modify Window "1ms" 2026/05/09 04:35:19 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 064b104c022f89a96aed87bbd3c1b5b6 OK NFDx2 checkfile vs. both remote (without normalization) 2026/05/09 04:35:20 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found 2026/05/09 04:35:20 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in Encrypted drive 'TestCryptDrive:rclone-test-jufocam2kohu' 2026/05/09 04:35:20 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-jufocam2kohu': 1 files missing 2026/05/09 04:35:20 NOTICE: 1 hashes missing 2026/05/09 04:35:20 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-jufocam2kohu': 1 differences found 2026/05/09 04:35:20 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-jufocam2kohu': 2 errors while checking NFDx2 checkfile vs. both remote (with normalization) 2026/05/09 04:35:21 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/05/09 04:35:21 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-jufocam2kohu': 0 differences found 2026/05/09 04:35:21 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-jufocam2kohu': 1 matching files 2026/05/09 04:35:21 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-baqebut9mila" 2026/05/09 04:35:21 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/05/09 04:35:21 DEBUG : Creating backend with remote "TestDrive:crypt/plp4691r6vcll6g5dldnmk4dfa8q2u1nora2b3d4lktbrlu1pj90" 2026/05/09 04:35:22 DEBUG : Creating backend with remote "/tmp/rclone259190050" run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-baqebut9mila'", Local "Local file system at /tmp/rclone259190050", Modify Window "1ms" 2026/05/09 04:35:24 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 60adcbf38496a2464336252d4ba49217 OK NFCx2 checkfile vs. both remote (without normalization) 2026/05/09 04:35:24 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found 2026/05/09 04:35:24 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in Encrypted drive 'TestCryptDrive:rclone-test-baqebut9mila' 2026/05/09 04:35:24 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-baqebut9mila': 1 files missing 2026/05/09 04:35:24 NOTICE: 1 hashes missing 2026/05/09 04:35:24 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-baqebut9mila': 1 differences found 2026/05/09 04:35:24 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-baqebut9mila': 2 errors while checking NFCx2 checkfile vs. both remote (with normalization) 2026/05/09 04:35:25 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/05/09 04:35:25 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-baqebut9mila': 0 differences found 2026/05/09 04:35:25 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-baqebut9mila': 1 matching files 2026/05/09 04:35:25 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-bokunih7tegu" 2026/05/09 04:35:25 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/05/09 04:35:25 DEBUG : Creating backend with remote "TestDrive:crypt/i2jlt1b8pivtkeiojaich6iav1tnt98sab0dnm08qgc2rnnrs66g" 2026/05/09 04:35:26 DEBUG : Creating backend with remote "/tmp/rclone1631049129" run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-bokunih7tegu'", Local "Local file system at /tmp/rclone1631049129", Modify Window "1ms" 2026/05/09 04:35:29 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = eacae6db627c9f7bfda19417b0c6964c OK both checkfile vs. NFDx2 remote (without normalization) 2026/05/09 04:35:29 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found 2026/05/09 04:35:29 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in Encrypted drive 'TestCryptDrive:rclone-test-bokunih7tegu' 2026/05/09 04:35:29 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-bokunih7tegu': 1 files missing 2026/05/09 04:35:29 NOTICE: 1 hashes missing 2026/05/09 04:35:29 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-bokunih7tegu': 1 differences found 2026/05/09 04:35:29 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-bokunih7tegu': 2 errors while checking both checkfile vs. NFDx2 remote (with normalization) 2026/05/09 04:35:30 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/05/09 04:35:30 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-bokunih7tegu': 0 differences found 2026/05/09 04:35:30 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-bokunih7tegu': 1 matching files 2026/05/09 04:35:30 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-yenofak9pima" 2026/05/09 04:35:30 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/05/09 04:35:30 DEBUG : Creating backend with remote "TestDrive:crypt/oo0j4b4ohj9j0pie9uragvfspngj6m6sevo654qpsu60ielri21g" 2026/05/09 04:35:31 DEBUG : Creating backend with remote "/tmp/rclone3795243854" run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-yenofak9pima'", Local "Local file system at /tmp/rclone3795243854", Modify Window "1ms" 2026/05/09 04:35:33 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 9081628b353cc7b307b0a092661edec0 OK both checkfile vs. NFCx2 remote (without normalization) 2026/05/09 04:35:34 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found 2026/05/09 04:35:34 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in Encrypted drive 'TestCryptDrive:rclone-test-yenofak9pima' 2026/05/09 04:35:34 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-yenofak9pima': 1 files missing 2026/05/09 04:35:34 NOTICE: 1 hashes missing 2026/05/09 04:35:34 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-yenofak9pima': 1 differences found 2026/05/09 04:35:34 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-yenofak9pima': 2 errors while checking both checkfile vs. NFCx2 remote (with normalization) 2026/05/09 04:35:35 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/05/09 04:35:35 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-yenofak9pima': 0 differences found 2026/05/09 04:35:35 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-yenofak9pima': 1 matching files 2026/05/09 04:35:35 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-yenofak9pima': Purge remote 2026/05/09 04:35:35 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-bokunih7tegu': Purge remote 2026/05/09 04:35:36 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-baqebut9mila': Purge remote 2026/05/09 04:35:36 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-jufocam2kohu': Purge remote 2026/05/09 04:35:37 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-weruyak9huto': Purge remote 2026/05/09 04:35:37 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-cinolol1cujo': Purge remote 2026/05/09 04:35:38 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-xarukaj1hace': Purge remote 2026/05/09 04:35:38 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-hupagic4fize': Purge remote 2026/05/09 04:35:39 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-quwilad0qina': Purge remote 2026/05/09 04:35:39 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-yiwokof9kiza': Purge remote --- PASS: TestApplyTransforms (52.56s) === RUN TestTruncateString --- PASS: TestTruncateString (0.00s) === RUN TestCopyFile run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-koxahix1nazu'", Local "Local file system at /tmp/rclone1125200144", Modify Window "1ms" 2026/05/09 04:35:40 DEBUG : file1: Need to transfer - File not found at Destination 2026/05/09 04:35:43 DEBUG : sub/file2: md5 = b9c9e2d1f7b0d51ea8cc6c62d2dc8128 OK 2026/05/09 04:35:43 DEBUG : sub/file2: size = 14 OK 2026/05/09 04:35:43 INFO : file1: Copied (new) to: sub/file2 2026/05/09 04:35:43 DEBUG : sub/file2: size = 14 OK 2026/05/09 04:35:43 DEBUG : file1: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2026/05/09 04:35:43 DEBUG : file1: Unchanged skipping 2026/05/09 04:35:44 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-koxahix1nazu': don't need to copy/move sub/file2, it is already at target location 2026/05/09 04:35:46 DEBUG : 150fuo3cn4j1uenq6r4g4qk6t4: Rmdir: contains trashed file: "d26uovf0miqhd1mu3c9jbseq00" --- PASS: TestCopyFile (6.59s) === RUN TestCopyLongFile run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-koxahix1nazu'", Local "Local file system at /tmp/rclone1125200144", Modify Window "1ms" copy_test.go:154: Test only runs on local --- SKIP: TestCopyLongFile (0.44s) === RUN TestCopyFileBackupDir run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-koxahix1nazu'", Local "Local file system at /tmp/rclone1125200144", Modify Window "1ms" 2026/05/09 04:35:49 DEBUG : dst/file1: md5 = cb018f0dd988addd2c144dc8e22987ad OK 2026/05/09 04:35:50 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-koxahix1nazu/backup" 2026/05/09 04:35:50 DEBUG : Creating backend with remote "TestDrive:crypt/q5irbcg74s47er306k0erq792vnc8h60c1le4mietu585gn3ua7g/1nrff024r7pq65ecp72fc28jb0" 2026/05/09 04:35:52 DEBUG : dst/file1: size = 14 (Local file system at /tmp/rclone1125200144) 2026/05/09 04:35:52 DEBUG : dst/file1: size = 18 (Encrypted drive 'TestCryptDrive:rclone-test-koxahix1nazu') 2026/05/09 04:35:52 DEBUG : dst/file1: Sizes differ 2026/05/09 04:35:55 INFO : dst/file1: Moved (server-side) 2026/05/09 04:35:56 DEBUG : dst/file1: md5 = c3cda2e0b3ef3fc1b6346dcec63321b7 OK 2026/05/09 04:35:56 DEBUG : dst/file1: size = 14 OK 2026/05/09 04:35:56 INFO : dst/file1: Copied (new) 2026/05/09 04:35:59 DEBUG : 31u3jie661vd5p8j7rtc3hgbh0: Rmdir: contains trashed file: "eer8kka55qnghc34cq76ca668g" 2026/05/09 04:35:59 DEBUG : 1nrff024r7pq65ecp72fc28jb0/31u3jie661vd5p8j7rtc3hgbh0: Rmdir: contains trashed file: "eer8kka55qnghc34cq76ca668g" 2026/05/09 04:36:00 DEBUG : 1nrff024r7pq65ecp72fc28jb0: Rmdir: contains trashed file: "31u3jie661vd5p8j7rtc3hgbh0" --- PASS: TestCopyFileBackupDir (14.15s) === RUN TestCopyFileCompareDest run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-koxahix1nazu'", Local "Local file system at /tmp/rclone1125200144", Modify Window "1ms" 2026/05/09 04:36:01 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-koxahix1nazu/dst" 2026/05/09 04:36:01 DEBUG : Config file has changed externally - reloading 2026/05/09 04:36:01 DEBUG : Creating backend with remote "TestDrive:crypt/q5irbcg74s47er306k0erq792vnc8h60c1le4mietu585gn3ua7g/31u3jie661vd5p8j7rtc3hgbh0" 2026/05/09 04:36:03 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-koxahix1nazu/CompareDest" 2026/05/09 04:36:03 DEBUG : Creating backend with remote "TestDrive:crypt/q5irbcg74s47er306k0erq792vnc8h60c1le4mietu585gn3ua7g/gveqi14airsml4bgu7krj116o8" 2026/05/09 04:36:04 DEBUG : one: Need to transfer - File not found at Destination 2026/05/09 04:36:07 DEBUG : one: md5 = 649f8bf72e36b255357e64c335992bdf OK 2026/05/09 04:36:07 DEBUG : one: size = 3 OK 2026/05/09 04:36:07 INFO : one: Copied (new) 2026/05/09 04:36:08 DEBUG : one: size = 5 (Local file system at /tmp/rclone1125200144) 2026/05/09 04:36:08 DEBUG : one: size = 3 (Encrypted drive 'TestCryptDrive:rclone-test-koxahix1nazu/dst') 2026/05/09 04:36:08 DEBUG : one: Sizes differ 2026/05/09 04:36:09 DEBUG : one: md5 = d7fb84ce132c7c680e0c86f8e7f59837 OK 2026/05/09 04:36:09 DEBUG : one: size = 5 OK 2026/05/09 04:36:09 INFO : one: Copied (replaced existing) 2026/05/09 04:36:12 DEBUG : dst/one: md5 = e41b8531dc01d956c780982fc58dc003 OK 2026/05/09 04:36:14 DEBUG : CompareDest/one: md5 = ebc2466ff299cccdf8db0d85bb973fb1 OK 2026/05/09 04:36:15 DEBUG : one: size = 5 (Local file system at /tmp/rclone1125200144) 2026/05/09 04:36:15 DEBUG : one: size = 3 (Encrypted drive 'TestCryptDrive:rclone-test-koxahix1nazu/dst') 2026/05/09 04:36:15 DEBUG : one: Sizes differ 2026/05/09 04:36:15 DEBUG : one: size = 5 OK 2026/05/09 04:36:15 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2026/05/09 04:36:15 DEBUG : one: Destination found in --compare-dest, skipping 2026/05/09 04:36:17 DEBUG : CompareDest/two: md5 = ab849361f5a3ae9cca9a9e1db41b7368 OK 2026/05/09 04:36:18 DEBUG : two: Need to transfer - File not found at Destination 2026/05/09 04:36:18 DEBUG : two: size = 3 OK 2026/05/09 04:36:18 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2026/05/09 04:36:18 DEBUG : two: Destination found in --compare-dest, skipping 2026/05/09 04:36:19 DEBUG : two: Need to transfer - File not found at Destination 2026/05/09 04:36:19 DEBUG : two: size = 3 OK 2026/05/09 04:36:19 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2026/05/09 04:36:19 DEBUG : two: Destination found in --compare-dest, skipping 2026/05/09 04:36:20 DEBUG : two: Need to transfer - File not found at Destination 2026/05/09 04:36:21 DEBUG : two: size = 5 (Local file system at /tmp/rclone1125200144) 2026/05/09 04:36:21 DEBUG : two: size = 3 (Encrypted drive 'TestCryptDrive:rclone-test-koxahix1nazu/CompareDest') 2026/05/09 04:36:21 DEBUG : two: Sizes differ 2026/05/09 04:36:22 DEBUG : two: md5 = 0ed2ba63190db41e5cbd8e3bc583baa5 OK 2026/05/09 04:36:22 DEBUG : two: size = 5 OK 2026/05/09 04:36:22 INFO : two: Copied (new) 2026/05/09 04:36:25 DEBUG : 31u3jie661vd5p8j7rtc3hgbh0: Rmdir: contains trashed file: "keafetfvtbmgo6tsdvcbj32i0k" 2026/05/09 04:36:25 DEBUG : 31u3jie661vd5p8j7rtc3hgbh0: Rmdir: contains trashed file: "0828u6q2p8d62qeklrkkuf10uc" 2026/05/09 04:36:26 DEBUG : gveqi14airsml4bgu7krj116o8: Rmdir: contains trashed file: "keafetfvtbmgo6tsdvcbj32i0k" 2026/05/09 04:36:26 DEBUG : gveqi14airsml4bgu7krj116o8: Rmdir: contains trashed file: "0828u6q2p8d62qeklrkkuf10uc" --- PASS: TestCopyFileCompareDest (25.83s) === RUN TestCopyFileCopyDest run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-koxahix1nazu'", Local "Local file system at /tmp/rclone1125200144", Modify Window "1ms" 2026/05/09 04:36:27 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-koxahix1nazu/dst" 2026/05/09 04:36:27 DEBUG : Creating backend with remote "TestDrive:crypt/q5irbcg74s47er306k0erq792vnc8h60c1le4mietu585gn3ua7g/31u3jie661vd5p8j7rtc3hgbh0" 2026/05/09 04:36:29 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-koxahix1nazu/CopyDest" 2026/05/09 04:36:29 DEBUG : Creating backend with remote "TestDrive:crypt/q5irbcg74s47er306k0erq792vnc8h60c1le4mietu585gn3ua7g/d09o6po3f7bm6ce32vdgs8h9ls" 2026/05/09 04:36:30 DEBUG : one: Need to transfer - File not found at Destination 2026/05/09 04:36:33 DEBUG : one: md5 = ea68b8717d83f31db46935dc691ade54 OK 2026/05/09 04:36:33 DEBUG : one: size = 3 OK 2026/05/09 04:36:33 INFO : one: Copied (new) 2026/05/09 04:36:34 DEBUG : one: size = 5 (Local file system at /tmp/rclone1125200144) 2026/05/09 04:36:34 DEBUG : one: size = 3 (Encrypted drive 'TestCryptDrive:rclone-test-koxahix1nazu/dst') 2026/05/09 04:36:34 DEBUG : one: Sizes differ 2026/05/09 04:36:35 DEBUG : one: md5 = 3eafd204242f503869196a1d45ea84ba OK 2026/05/09 04:36:35 DEBUG : one: size = 5 OK 2026/05/09 04:36:35 INFO : one: Copied (replaced existing) 2026/05/09 04:36:37 DEBUG : dst/one: md5 = 8d80a3e2a6f427819f492af8b7832fd8 OK 2026/05/09 04:36:40 DEBUG : CopyDest/one: md5 = de760e75480d03d331a8c3ce23c91b1e OK 2026/05/09 04:36:41 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-koxahix1nazu/BackupDir" 2026/05/09 04:36:41 DEBUG : Creating backend with remote "TestDrive:crypt/q5irbcg74s47er306k0erq792vnc8h60c1le4mietu585gn3ua7g/s6dbk3lfi7c9kfvo6j7bla9m0g" 2026/05/09 04:36:42 DEBUG : one: size = 5 (Local file system at /tmp/rclone1125200144) 2026/05/09 04:36:42 DEBUG : one: size = 3 (Encrypted drive 'TestCryptDrive:rclone-test-koxahix1nazu/dst') 2026/05/09 04:36:42 DEBUG : one: Sizes differ 2026/05/09 04:36:43 DEBUG : one: size = 5 OK 2026/05/09 04:36:43 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2026/05/09 04:36:43 DEBUG : one: size = 5 (Local file system at /tmp/rclone1125200144) 2026/05/09 04:36:43 DEBUG : one: size = 3 (Encrypted drive 'TestCryptDrive:rclone-test-koxahix1nazu/dst') 2026/05/09 04:36:43 DEBUG : one: Sizes differ 2026/05/09 04:36:44 INFO : one: Moved (server-side) 2026/05/09 04:36:45 DEBUG : one: size = 5 OK 2026/05/09 04:36:45 INFO : one: Copied (server-side copy) 2026/05/09 04:36:45 DEBUG : one: Destination found in --copy-dest, using server-side copy 2026/05/09 04:36:47 DEBUG : CopyDest/two: md5 = 888ccede0f99af47c6facd207b774968 OK 2026/05/09 04:36:48 DEBUG : two: Need to transfer - File not found at Destination 2026/05/09 04:36:48 DEBUG : two: size = 3 OK 2026/05/09 04:36:48 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2026/05/09 04:36:50 DEBUG : two: size = 3 OK 2026/05/09 04:36:50 INFO : two: Copied (server-side copy) 2026/05/09 04:36:50 DEBUG : two: Destination found in --copy-dest, using server-side copy 2026/05/09 04:36:50 DEBUG : two: size = 3 OK 2026/05/09 04:36:50 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2026/05/09 04:36:50 DEBUG : two: Unchanged skipping 2026/05/09 04:36:52 DEBUG : CopyDest/three: md5 = 95a429a544d88d621164e04b9906b77e OK 2026/05/09 04:36:53 DEBUG : three: Need to transfer - File not found at Destination 2026/05/09 04:36:53 DEBUG : three: size = 7 (Local file system at /tmp/rclone1125200144) 2026/05/09 04:36:53 DEBUG : three: size = 5 (Encrypted drive 'TestCryptDrive:rclone-test-koxahix1nazu/CopyDest') 2026/05/09 04:36:53 DEBUG : three: Sizes differ 2026/05/09 04:36:53 DEBUG : three: Destination not found in --copy-dest 2026/05/09 04:36:55 DEBUG : three: md5 = c8c1ee5f7ac1165f27726a218995c5f8 OK 2026/05/09 04:36:55 DEBUG : three: size = 7 OK 2026/05/09 04:36:55 INFO : three: Copied (new) 2026/05/09 04:36:59 DEBUG : 31u3jie661vd5p8j7rtc3hgbh0: Rmdir: contains trashed file: "b6jdmn2b1adrhc8vikh7edr9os" 2026/05/09 04:36:59 DEBUG : 31u3jie661vd5p8j7rtc3hgbh0: Rmdir: contains trashed file: "0828u6q2p8d62qeklrkkuf10uc" 2026/05/09 04:36:59 DEBUG : 31u3jie661vd5p8j7rtc3hgbh0: Rmdir: contains trashed file: "keafetfvtbmgo6tsdvcbj32i0k" 2026/05/09 04:37:00 DEBUG : d09o6po3f7bm6ce32vdgs8h9ls: Rmdir: contains trashed file: "b6jdmn2b1adrhc8vikh7edr9os" 2026/05/09 04:37:00 DEBUG : d09o6po3f7bm6ce32vdgs8h9ls: Rmdir: contains trashed file: "0828u6q2p8d62qeklrkkuf10uc" 2026/05/09 04:37:00 DEBUG : d09o6po3f7bm6ce32vdgs8h9ls: Rmdir: contains trashed file: "keafetfvtbmgo6tsdvcbj32i0k" 2026/05/09 04:37:01 DEBUG : s6dbk3lfi7c9kfvo6j7bla9m0g: Rmdir: contains trashed file: "0828u6q2p8d62qeklrkkuf10uc" --- PASS: TestCopyFileCopyDest (34.53s) === RUN TestCopyInplace run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-koxahix1nazu'", Local "Local file system at /tmp/rclone1125200144", Modify Window "1ms" copy_test.go:371: Partial uploads not supported --- SKIP: TestCopyInplace (0.44s) === RUN TestCopyLongFileName run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-koxahix1nazu'", Local "Local file system at /tmp/rclone1125200144", Modify Window "1ms" copy_test.go:404: Partial uploads not supported --- SKIP: TestCopyLongFileName (0.47s) === RUN TestCopyLongFileNameCollision run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-koxahix1nazu'", Local "Local file system at /tmp/rclone1125200144", Modify Window "1ms" copy_test.go:437: Partial uploads not supported --- SKIP: TestCopyLongFileNameCollision (0.41s) === RUN TestCopyFileMaxTransfer run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-koxahix1nazu'", Local "Local file system at /tmp/rclone1125200144", Modify Window "1ms" 2026/05/09 04:37:03 DEBUG : TestCopyFileMaxTransfer/file1: Need to transfer - File not found at Destination 2026/05/09 04:37:05 DEBUG : TestCopyFileMaxTransfer/file1: md5 = de54b81a6283131a2c10461ed9b3dd26 OK 2026/05/09 04:37:05 DEBUG : TestCopyFileMaxTransfer/file1: size = 14 OK 2026/05/09 04:37:05 INFO : TestCopyFileMaxTransfer/file1: Copied (new) 2026/05/09 04:37:06 DEBUG : TestCopyFileMaxTransfer/file2: Need to transfer - File not found at Destination 2026/05/09 04:37:06 ERROR : TestCopyFileMaxTransfer/file2: Failed to copy: Post "https://www.googleapis.com/upload/drive/v3/files?alt=json&fields=id%2Cname%2Csize%2Cmd5Checksum%2Csha1Checksum%2Csha256Checksum%2Ctrashed%2CexplicitlyTrashed%2CmodifiedTime%2CcreatedTime%2CmimeType%2Cparents%2CwebViewLink%2CshortcutDetails%2CexportLinks%2CresourceKey&keepRevisionForever=false&prettyPrint=false&supportsAllDrives=true&uploadType=multipart": googleapi: Copy failed: max transfer limit reached as set by --max-transfer copy_test.go:500: Expecting error to contain accounting.ErrorMaxTransferLimitReachedFatal 2026/05/09 04:37:07 DEBUG : TestCopyFileMaxTransfer/file3: Need to transfer - File not found at Destination 2026/05/09 04:37:08 DEBUG : TestCopyFileMaxTransfer/file4: Need to transfer - File not found at Destination 2026/05/09 04:37:09 DEBUG : TestCopyFileMaxTransfer/file4: md5 = 60db02e164f25babf68a9e8f2247dc56 OK 2026/05/09 04:37:09 DEBUG : TestCopyFileMaxTransfer/file4: size = 2062 OK 2026/05/09 04:37:09 INFO : TestCopyFileMaxTransfer/file4: Copied (new) 2026/05/09 04:37:12 DEBUG : bl9cnu966tarhhpg22ft7c4qm1863k22qdncucii8kn8c3k4aut0: Rmdir: contains trashed file: "amq3bkuqh8mg4ohnbs2i3185uk" 2026/05/09 04:37:12 DEBUG : bl9cnu966tarhhpg22ft7c4qm1863k22qdncucii8kn8c3k4aut0: Rmdir: contains trashed file: "eer8kka55qnghc34cq76ca668g" --- PASS: TestCopyFileMaxTransfer (9.63s) === RUN TestDeduplicateInteractive run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-koxahix1nazu'", Local "Local file system at /tmp/rclone1125200144", Modify Window "1ms" dedupe_test.go:36: Can't run this test without a hash --- SKIP: TestDeduplicateInteractive (0.43s) === RUN TestDeduplicateSkip run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-koxahix1nazu'", Local "Local file system at /tmp/rclone1125200144", Modify Window "1ms" 2026/05/09 04:37:15 INFO : Encrypted drive 'TestCryptDrive:rclone-test-koxahix1nazu': Looking for duplicate names using skip mode. 2026/05/09 04:37:16 NOTICE: one: Found 2 files with duplicate names 2026/05/09 04:37:16 NOTICE: one: Skipping 2 files with duplicate names --- PASS: TestDeduplicateSkip (4.86s) === RUN TestDeduplicateSizeOnly run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-koxahix1nazu'", Local "Local file system at /tmp/rclone1125200144", Modify Window "1ms" 2026/05/09 04:37:21 INFO : Encrypted drive 'TestCryptDrive:rclone-test-koxahix1nazu': Looking for duplicate names using skip mode. 2026/05/09 04:37:22 NOTICE: one: Found 3 files with duplicate names 2026/05/09 04:37:22 NOTICE: one: Deleting 1/2 identical duplicates (size 11) 2026/05/09 04:37:22 INFO : one: Deleted 2026/05/09 04:37:22 NOTICE: one: Skipping 2 files with duplicate names --- PASS: TestDeduplicateSizeOnly (6.63s) === RUN TestDeduplicateFirst run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-koxahix1nazu'", Local "Local file system at /tmp/rclone1125200144", Modify Window "1ms" 2026/05/09 04:37:28 INFO : Encrypted drive 'TestCryptDrive:rclone-test-koxahix1nazu': Looking for duplicate names using first mode. 2026/05/09 04:37:29 NOTICE: one: Found 3 files with duplicate names 2026/05/09 04:37:29 INFO : one: Deleted 2026/05/09 04:37:30 INFO : one: Deleted 2026/05/09 04:37:30 NOTICE: one: Deleted 2 extra copies --- PASS: TestDeduplicateFirst (6.57s) === RUN TestDeduplicateNewest run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-koxahix1nazu'", Local "Local file system at /tmp/rclone1125200144", Modify Window "1ms" 2026/05/09 04:37:35 INFO : Encrypted drive 'TestCryptDrive:rclone-test-koxahix1nazu': Looking for duplicate names using newest mode. 2026/05/09 04:37:35 NOTICE: one: Found 3 files with duplicate names 2026/05/09 04:37:36 INFO : one: Deleted 2026/05/09 04:37:36 INFO : one: Deleted 2026/05/09 04:37:36 NOTICE: one: Deleted 2 extra copies --- PASS: TestDeduplicateNewest (6.64s) === RUN TestDeduplicateNewestByHash run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-koxahix1nazu'", Local "Local file system at /tmp/rclone1125200144", Modify Window "1ms" dedupe_test.go:36: Can't run this test without a hash --- SKIP: TestDeduplicateNewestByHash (0.59s) === RUN TestDeduplicateOldest run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-koxahix1nazu'", Local "Local file system at /tmp/rclone1125200144", Modify Window "1ms" 2026/05/09 04:37:42 INFO : Encrypted drive 'TestCryptDrive:rclone-test-koxahix1nazu': Looking for duplicate names using oldest mode. 2026/05/09 04:37:42 NOTICE: one: Found 3 files with duplicate names 2026/05/09 04:37:43 INFO : one: Deleted 2026/05/09 04:37:43 INFO : one: Deleted 2026/05/09 04:37:43 NOTICE: one: Deleted 2 extra copies --- PASS: TestDeduplicateOldest (6.63s) === RUN TestDeduplicateLargest run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-koxahix1nazu'", Local "Local file system at /tmp/rclone1125200144", Modify Window "1ms" 2026/05/09 04:37:48 INFO : Encrypted drive 'TestCryptDrive:rclone-test-koxahix1nazu': Looking for duplicate names using largest mode. 2026/05/09 04:37:49 NOTICE: one: Found 3 files with duplicate names 2026/05/09 04:37:49 INFO : one: Deleted 2026/05/09 04:37:50 INFO : one: Deleted 2026/05/09 04:37:50 NOTICE: one: Deleted 2 extra copies --- PASS: TestDeduplicateLargest (6.37s) === RUN TestDeduplicateSmallest run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-koxahix1nazu'", Local "Local file system at /tmp/rclone1125200144", Modify Window "1ms" 2026/05/09 04:37:55 INFO : Encrypted drive 'TestCryptDrive:rclone-test-koxahix1nazu': Looking for duplicate names using smallest mode. 2026/05/09 04:37:55 NOTICE: one: Found 3 files with duplicate names 2026/05/09 04:37:56 INFO : one: Deleted 2026/05/09 04:37:56 INFO : one: Deleted 2026/05/09 04:37:56 NOTICE: one: Deleted 2 extra copies --- PASS: TestDeduplicateSmallest (6.55s) === RUN TestDeduplicateRename run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-koxahix1nazu'", Local "Local file system at /tmp/rclone1125200144", Modify Window "1ms" 2026/05/09 04:38:03 INFO : Encrypted drive 'TestCryptDrive:rclone-test-koxahix1nazu': Looking for duplicate names using rename mode. 2026/05/09 04:38:04 NOTICE: one.txt: Found 3 files with duplicate names 2026/05/09 04:38:05 INFO : one-2.txt: renamed from: one.txt 2026/05/09 04:38:06 INFO : one-3.txt: renamed from: one.txt 2026/05/09 04:38:07 INFO : one-4.txt: renamed from: one.txt --- PASS: TestDeduplicateRename (12.38s) === RUN TestMergeDirs run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-koxahix1nazu'", Local "Local file system at /tmp/rclone1125200144", Modify Window "1ms" 2026/05/09 04:38:12 DEBUG : dupe1/one.txt: md5 = eb49bfce709e73fdb6f509e893cf2df2 OK 2026/05/09 04:38:14 DEBUG : dupe2/two.txt: md5 = 29415203f055aeac5cff3d06d222d930 OK 2026/05/09 04:38:17 DEBUG : dupe3/three.txt: md5 = 58fd1dc31ee60d00e1ade5e5b1a6a25c OK 2026/05/09 04:38:17 INFO : urj8gsducqbtvekeq181ntd2u8: merging "sfrom47189m9qr3mt4qonu281c" 2026/05/09 04:38:18 INFO : urj8gsducqbtvekeq181ntd2u8: removing empty directory 2026/05/09 04:38:19 INFO : 1u1r4ei7c628fnjg9blqt3j60o: merging "tr2hj63d80ftlmvm6a952snjcc" 2026/05/09 04:38:19 INFO : 1u1r4ei7c628fnjg9blqt3j60o: removing empty directory 2026/05/09 04:38:23 DEBUG : 6kqidisnml4ml642h4iorebdng: Rmdir: contains trashed file: "tr2hj63d80ftlmvm6a952snjcc" 2026/05/09 04:38:23 DEBUG : 6kqidisnml4ml642h4iorebdng: Rmdir: contains trashed file: "sfrom47189m9qr3mt4qonu281c" 2026/05/09 04:38:23 DEBUG : 6kqidisnml4ml642h4iorebdng: Rmdir: contains trashed file: "o9f79a47tdo934l5lfe4jpsms8" --- PASS: TestMergeDirs (13.70s) === RUN TestListDirSorted run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-koxahix1nazu'", Local "Local file system at /tmp/rclone1125200144", Modify Window "1ms" 2026/05/09 04:38:25 DEBUG : a.txt: md5 = 3b50dd4f4e6d5e8ce4ca625825f7d282 OK 2026/05/09 04:38:27 DEBUG : zend.txt: md5 = e5fdba29ad174f60a823a6d37b052afa OK 2026/05/09 04:38:29 DEBUG : sub dir/hello world: md5 = 32dd8881945af6ccac0fc52abf84545c OK 2026/05/09 04:38:31 DEBUG : sub dir/hello world2: md5 = 35a4cedb57b253b0d4cf2c3a471406a1 OK 2026/05/09 04:38:33 DEBUG : sub dir/ignore dir/.ignore: md5 = 04d5c1f44012362ddb1f832aecf85fbf OK 2026/05/09 04:38:34 DEBUG : sub dir/ignore dir/should be ignored: md5 = 235cf0fe5889e148bcda931c66109178 OK 2026/05/09 04:38:37 DEBUG : sub dir/sub sub dir/hello world3: md5 = 8c3d24f7c4efe8637315e4d003be0e39 OK 2026/05/09 04:38:38 DEBUG : a.txt: Excluded (Size Filter) 2026/05/09 04:38:38 DEBUG : a.txt: Excluded 2026/05/09 04:38:39 DEBUG : sub dir/hello world2: Excluded (Size Filter) 2026/05/09 04:38:39 DEBUG : sub dir/hello world2: Excluded 2026/05/09 04:38:39 DEBUG : sub dir/hello world: Excluded (Size Filter) 2026/05/09 04:38:39 DEBUG : sub dir/hello world: Excluded 2026/05/09 04:38:39 DEBUG : sub dir/ignore dir: Excluded 2026/05/09 04:38:39 DEBUG : sub dir/hello world2: Excluded (Size Filter) 2026/05/09 04:38:39 DEBUG : sub dir/hello world2: Excluded 2026/05/09 04:38:39 DEBUG : sub dir/hello world: Excluded (Size Filter) 2026/05/09 04:38:39 DEBUG : sub dir/hello world: Excluded 2026/05/09 04:38:40 DEBUG : sub dir/ignore dir: Excluded 2026/05/09 04:38:44 DEBUG : 14begaa36ih249fgrnfk6nulg0/u72nb76vlceqmgie4q57rr656s: Rmdir: contains trashed file: "o5airla31uiui01blt74of9ves" 2026/05/09 04:38:45 DEBUG : 14begaa36ih249fgrnfk6nulg0/3fslb0r9seuggqgrrqv2gjhfj8: Rmdir: contains trashed file: "i0t74httmdlljg425j6tg570d8" 2026/05/09 04:38:45 DEBUG : 14begaa36ih249fgrnfk6nulg0/3fslb0r9seuggqgrrqv2gjhfj8: Rmdir: contains trashed file: "8qak2o3rl7h348s0lh02ghr71aajis56gocdjjbjfkc2j4vakju0" 2026/05/09 04:38:46 DEBUG : 14begaa36ih249fgrnfk6nulg0: Rmdir: contains trashed file: "u72nb76vlceqmgie4q57rr656s" 2026/05/09 04:38:46 DEBUG : 14begaa36ih249fgrnfk6nulg0: Rmdir: contains trashed file: "3fslb0r9seuggqgrrqv2gjhfj8" 2026/05/09 04:38:46 DEBUG : 14begaa36ih249fgrnfk6nulg0: Rmdir: contains trashed file: "mp1g12tj43cv7goud5b95fgju8" 2026/05/09 04:38:46 DEBUG : 14begaa36ih249fgrnfk6nulg0: Rmdir: contains trashed file: "8j9gk4b744l35einofkqsr2mvo" --- PASS: TestListDirSorted (22.99s) === RUN TestListDirSortedFn run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-koxahix1nazu'", Local "Local file system at /tmp/rclone1125200144", Modify Window "1ms" 2026/05/09 04:38:48 DEBUG : a.txt: md5 = 0b9bc21e7ee0d264132f217b326c473f OK 2026/05/09 04:38:49 DEBUG : zend.txt: md5 = 165c37b1647f412f5454645047db9128 OK 2026/05/09 04:38:52 DEBUG : sub dir/hello world: md5 = 7aa9c687d6a4b73798128de4281de1f3 OK 2026/05/09 04:38:53 DEBUG : sub dir/hello world2: md5 = a7f2f0961c8c64694212659fba2e4e9d OK 2026/05/09 04:38:56 DEBUG : sub dir/ignore dir/.ignore: md5 = 55d0c892cb3390045f6c9fe871deeaa4 OK 2026/05/09 04:38:57 DEBUG : sub dir/ignore dir/should be ignored: md5 = 1736d82511053167893f8b30d7705aba OK 2026/05/09 04:39:00 DEBUG : sub dir/sub sub dir/hello world3: md5 = b89623a5f4ae90d5fc37c9eb0e3ce6be OK 2026/05/09 04:39:01 DEBUG : a.txt: Excluded (Size Filter) 2026/05/09 04:39:01 DEBUG : a.txt: Excluded 2026/05/09 04:39:02 DEBUG : sub dir/hello world2: Excluded (Size Filter) 2026/05/09 04:39:02 DEBUG : sub dir/hello world2: Excluded 2026/05/09 04:39:02 DEBUG : sub dir/hello world: Excluded (Size Filter) 2026/05/09 04:39:02 DEBUG : sub dir/hello world: Excluded 2026/05/09 04:39:02 DEBUG : sub dir/ignore dir: Excluded 2026/05/09 04:39:02 DEBUG : sub dir/hello world2: Excluded (Size Filter) 2026/05/09 04:39:02 DEBUG : sub dir/hello world2: Excluded 2026/05/09 04:39:02 DEBUG : sub dir/hello world: Excluded (Size Filter) 2026/05/09 04:39:02 DEBUG : sub dir/hello world: Excluded 2026/05/09 04:39:03 DEBUG : sub dir/ignore dir: Excluded 2026/05/09 04:39:07 DEBUG : 14begaa36ih249fgrnfk6nulg0/u72nb76vlceqmgie4q57rr656s: Rmdir: contains trashed file: "o5airla31uiui01blt74of9ves" 2026/05/09 04:39:08 DEBUG : 14begaa36ih249fgrnfk6nulg0/3fslb0r9seuggqgrrqv2gjhfj8: Rmdir: contains trashed file: "i0t74httmdlljg425j6tg570d8" 2026/05/09 04:39:08 DEBUG : 14begaa36ih249fgrnfk6nulg0/3fslb0r9seuggqgrrqv2gjhfj8: Rmdir: contains trashed file: "8qak2o3rl7h348s0lh02ghr71aajis56gocdjjbjfkc2j4vakju0" 2026/05/09 04:39:09 DEBUG : 14begaa36ih249fgrnfk6nulg0: Rmdir: contains trashed file: "u72nb76vlceqmgie4q57rr656s" 2026/05/09 04:39:09 DEBUG : 14begaa36ih249fgrnfk6nulg0: Rmdir: contains trashed file: "3fslb0r9seuggqgrrqv2gjhfj8" 2026/05/09 04:39:09 DEBUG : 14begaa36ih249fgrnfk6nulg0: Rmdir: contains trashed file: "mp1g12tj43cv7goud5b95fgju8" 2026/05/09 04:39:09 DEBUG : 14begaa36ih249fgrnfk6nulg0: Rmdir: contains trashed file: "8j9gk4b744l35einofkqsr2mvo" --- PASS: TestListDirSortedFn (22.97s) === RUN TestListJSON run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-koxahix1nazu'", Local "Local file system at /tmp/rclone1125200144", Modify Window "1ms" 2026/05/09 04:39:11 DEBUG : file1: md5 = c4b56fc0d3ae78e8ce3b19b90132d36d OK 2026/05/09 04:39:13 DEBUG : sub/file2: md5 = f1738bbebbe666cf95d20428dc406dfc OK === 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/05/09 04:39:16 ERROR : file1: Failed to read hash: hash type not supported === RUN TestListJSON/Metadata 2026/05/09 04:39:17 DEBUG : eer8kka55qnghc34cq76ca668g: Fetching metadata 2026/05/09 04:39:17 DEBUG : 150fuo3cn4j1uenq6r4g4qk6t4: Fetching metadata 2026/05/09 04:39:19 DEBUG : 150fuo3cn4j1uenq6r4g4qk6t4: Rmdir: contains trashed file: "d26uovf0miqhd1mu3c9jbseq00" --- PASS: TestListJSON (9.96s) --- PASS: TestListJSON/Default (0.26s) --- PASS: TestListJSON/FilesOnly (0.24s) --- PASS: TestListJSON/DirsOnly (0.26s) --- PASS: TestListJSON/Recurse (0.47s) --- PASS: TestListJSON/SubDir (0.27s) --- PASS: TestListJSON/NoModTime (0.30s) --- PASS: TestListJSON/NoMimeType (0.29s) --- PASS: TestListJSON/ShowHash (0.23s) --- PASS: TestListJSON/HashTypes (0.28s) --- PASS: TestListJSON/Metadata (0.74s) === RUN TestStatJSON run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-koxahix1nazu'", Local "Local file system at /tmp/rclone1125200144", Modify Window "1ms" 2026/05/09 04:39:21 DEBUG : file1: md5 = 1cf1af4e949c1800c236f21496db3041 OK 2026/05/09 04:39:24 DEBUG : sub/file2: md5 = 9040dc851bf33f1f9019abf100269762 OK === RUN TestStatJSON/Root === RUN TestStatJSON/RootFilesOnly === RUN TestStatJSON/RootDirsOnly === RUN TestStatJSON/Dir === RUN TestStatJSON/DirWithTrailingSlash === RUN TestStatJSON/File === RUN TestStatJSON/NotFound === RUN TestStatJSON/DirFilesOnly === RUN TestStatJSON/FileFilesOnly === RUN TestStatJSON/NotFoundFilesOnly === RUN TestStatJSON/DirDirsOnly === RUN TestStatJSON/FileDirsOnly === RUN TestStatJSON/NotFoundDirsOnly === RUN TestStatJSON/RootNotFound 2026/05/09 04:39:28 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-koxahix1nazu/notfound" 2026/05/09 04:39:28 DEBUG : Creating backend with remote "TestDrive:crypt/q5irbcg74s47er306k0erq792vnc8h60c1le4mietu585gn3ua7g/qe0i31qdkoejk60elps0ni5sqk" 2026/05/09 04:39:31 DEBUG : 150fuo3cn4j1uenq6r4g4qk6t4: Rmdir: contains trashed file: "d26uovf0miqhd1mu3c9jbseq00" --- PASS: TestStatJSON (12.22s) --- PASS: TestStatJSON/Root (0.26s) --- PASS: TestStatJSON/RootFilesOnly (0.00s) --- PASS: TestStatJSON/RootDirsOnly (0.28s) --- PASS: TestStatJSON/Dir (0.51s) --- PASS: TestStatJSON/DirWithTrailingSlash (0.25s) --- PASS: TestStatJSON/File (0.22s) --- PASS: TestStatJSON/NotFound (0.56s) --- PASS: TestStatJSON/DirFilesOnly (0.26s) --- PASS: TestStatJSON/FileFilesOnly (0.24s) --- PASS: TestStatJSON/NotFoundFilesOnly (0.22s) --- PASS: TestStatJSON/DirDirsOnly (0.25s) --- PASS: TestStatJSON/FileDirsOnly (0.26s) --- PASS: TestStatJSON/NotFoundDirsOnly (0.28s) --- PASS: TestStatJSON/RootNotFound (1.68s) === RUN TestMkdir run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-koxahix1nazu'", Local "Local file system at /tmp/rclone1125200144", Modify Window "1ms" 2026/05/09 04:39:32 INFO : Encrypted drive 'TestCryptDrive:rclone-test-koxahix1nazu': Making directory 2026/05/09 04:39:32 INFO : Encrypted drive 'TestCryptDrive:rclone-test-koxahix1nazu': Making directory --- PASS: TestMkdir (0.68s) === RUN TestLsd run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-koxahix1nazu'", Local "Local file system at /tmp/rclone1125200144", Modify Window "1ms" 2026/05/09 04:39:35 DEBUG : sub dir/hello world: md5 = 980d73df20f97b7c120583e0fc1514ca OK 2026/05/09 04:39:37 DEBUG : 14begaa36ih249fgrnfk6nulg0: Rmdir: contains trashed file: "8j9gk4b744l35einofkqsr2mvo" --- PASS: TestLsd (5.14s) === RUN TestLs run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-koxahix1nazu'", Local "Local file system at /tmp/rclone1125200144", Modify Window "1ms" 2026/05/09 04:39:39 DEBUG : potato2: md5 = f47b7e2381e52c816045079e4ef3a868 OK 2026/05/09 04:39:40 DEBUG : empty space: md5 = a010a8bad4fa520a1c5b7c99c72865dd OK --- PASS: TestLs (4.83s) === RUN TestLsWithFilesFrom run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-koxahix1nazu'", Local "Local file system at /tmp/rclone1125200144", Modify Window "1ms" 2026/05/09 04:39:44 DEBUG : potato2: md5 = 814a103a9fb1952de5de7980b9d22387 OK 2026/05/09 04:39:45 DEBUG : empty space: md5 = a04fe8e2fb107bddadef39d3032e5945 OK 2026/05/09 04:39:46 DEBUG : empty space: Excluded (FilesFrom Filter) --- PASS: TestLsWithFilesFrom (5.02s) === RUN TestLsLong run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-koxahix1nazu'", Local "Local file system at /tmp/rclone1125200144", Modify Window "1ms" 2026/05/09 04:39:49 DEBUG : potato2: md5 = 605c05c219fa96956b629df70d57e597 OK 2026/05/09 04:39:50 DEBUG : empty space: md5 = ea96154da6cbb4cd58f3fba9fba77881 OK --- PASS: TestLsLong (4.84s) === RUN TestHashSums run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-koxahix1nazu'", Local "Local file system at /tmp/rclone1125200144", Modify Window "1ms" 2026/05/09 04:39:54 DEBUG : potato2: md5 = 29875485f20cd1db863bd46805f32b59 OK 2026/05/09 04:39:55 DEBUG : empty space: md5 = 0576ac68a41550958fab38301850c1e5 OK --- PASS: TestHashSums (4.84s) === RUN TestHashSumsWithErrors 2026/05/09 04:39:57 DEBUG : Creating backend with remote ":memory:" 2026/05/09 04:39:57 ERROR : file1: hash unsupported: hash type not supported --- PASS: TestHashSumsWithErrors (0.00s) === RUN TestHashStream 2026/05/09 04:39:57 DEBUG : Creating md5 hash of 0 bytes read from input stream 2026/05/09 04:39:57 DEBUG : Creating md5 hash of 0 bytes read from input stream 2026/05/09 04:39:57 DEBUG : Creating sha1 hash of 0 bytes read from input stream 2026/05/09 04:39:57 DEBUG : Creating sha1 hash of 0 bytes read from input stream 2026/05/09 04:39:57 DEBUG : Creating md5 hash of 12 bytes read from input stream 2026/05/09 04:39:57 DEBUG : Creating md5 hash of 12 bytes read from input stream 2026/05/09 04:39:57 DEBUG : Creating sha1 hash of 12 bytes read from input stream 2026/05/09 04:39:57 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:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-koxahix1nazu'", Local "Local file system at /tmp/rclone1125200144", Modify Window "1ms" 2026/05/09 04:39:58 DEBUG : potato2: md5 = 8e7a25c2734f29b6468daac92d902423 OK 2026/05/09 04:40:00 DEBUG : empty space: md5 = cad906d1d1d371b89cfa07e5129a9c43 OK 2026/05/09 04:40:02 DEBUG : sub dir/potato3: md5 = e84d09008b01b12f42161ff540fff444 OK 2026/05/09 04:40:05 DEBUG : 14begaa36ih249fgrnfk6nulg0: Rmdir: contains trashed file: "9atai6og4mljg3r5gjeh27k0q8" --- PASS: TestCount (9.01s) === RUN TestDelete run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-koxahix1nazu'", Local "Local file system at /tmp/rclone1125200144", Modify Window "1ms" 2026/05/09 04:40:08 DEBUG : small: md5 = 7688da52b8f5355e076d3d875e63fd41 OK 2026/05/09 04:40:09 DEBUG : medium: md5 = c48e5a1fcebb12d95306c191233438e7 OK 2026/05/09 04:40:10 DEBUG : large: md5 = 3460781e5636cb18dfd88fe62c926ad0 OK 2026/05/09 04:40:11 DEBUG : Waiting for deletions to finish 2026/05/09 04:40:11 DEBUG : large: Excluded (Size Filter) 2026/05/09 04:40:11 INFO : medium: Deleted 2026/05/09 04:40:11 INFO : small: Deleted --- PASS: TestDelete (6.54s) === RUN TestMaxDelete run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-koxahix1nazu'", Local "Local file system at /tmp/rclone1125200144", Modify Window "1ms" 2026/05/09 04:40:14 DEBUG : small: md5 = 13c0e0396ffdc9ba92f64402c8859571 OK 2026/05/09 04:40:15 DEBUG : medium: md5 = 237c5028899081cfe8ca355a1da7213d OK 2026/05/09 04:40:17 DEBUG : large: md5 = 1792a8c51e960f83e24b95a1cf1bf966 OK 2026/05/09 04:40:17 DEBUG : Waiting for deletions to finish 2026/05/09 04:40:17 ERROR : large: Got fatal error on delete: --max-delete threshold reached 2026/05/09 04:40:18 INFO : small: Deleted 2026/05/09 04:40:18 INFO : medium: Deleted --- PASS: TestMaxDelete (6.89s) === RUN TestMaxDeleteSizeLargeFile run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-koxahix1nazu'", Local "Local file system at /tmp/rclone1125200144", Modify Window "1ms" 2026/05/09 04:40:21 DEBUG : small: md5 = 16516de01ac82ce422b7d20a77d3b157 OK 2026/05/09 04:40:22 DEBUG : medium: md5 = 645576c0b2a9c8650dac2f2fc3bfcb8e OK 2026/05/09 04:40:24 DEBUG : large: md5 = 7a583e80f91a5488bddcc24d72bd2a60 OK 2026/05/09 04:40:24 DEBUG : Waiting for deletions to finish 2026/05/09 04:40:24 ERROR : large: Got fatal error on delete: --max-delete-size threshold reached 2026/05/09 04:40:25 INFO : small: Deleted 2026/05/09 04:40:25 INFO : medium: Deleted --- PASS: TestMaxDeleteSizeLargeFile (6.93s) === RUN TestMaxDeleteSize run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-koxahix1nazu'", Local "Local file system at /tmp/rclone1125200144", Modify Window "1ms" 2026/05/09 04:40:28 DEBUG : small: md5 = 9f1638f283eb77d8aac4de1bb4c2577d OK 2026/05/09 04:40:29 DEBUG : medium: md5 = 1ed1e402e8f973c4ee1145fc0e84e41a OK 2026/05/09 04:40:31 DEBUG : large: md5 = 573592e2778414fbe68a42729d5fc4e9 OK 2026/05/09 04:40:31 DEBUG : Waiting for deletions to finish 2026/05/09 04:40:31 ERROR : large: Got fatal error on delete: --max-delete-size threshold reached 2026/05/09 04:40:32 INFO : medium: Deleted 2026/05/09 04:40:32 INFO : small: Deleted --- PASS: TestMaxDeleteSize (6.94s) === RUN TestReadFile run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-koxahix1nazu'", Local "Local file system at /tmp/rclone1125200144", Modify Window "1ms" 2026/05/09 04:40:35 DEBUG : ReadFile: md5 = 7bfc62e1adcbd6c34fde033f06f8dccb OK --- PASS: TestReadFile (4.18s) === RUN TestRetry 2026/05/09 04:40:38 DEBUG : Received error: Wrapped EOF is retriable: EOF - low level retry 1/5 2026/05/09 04:40:38 DEBUG : Received error: Wrapped EOF is retriable: EOF - low level retry 2/5 2026/05/09 04:40:38 DEBUG : Sleeping for 10ms (as indicated by the server) to obey Retry-After error: BANG: trying again in 10ms 2026/05/09 04:40:38 DEBUG : Sleeping for 10ms (as indicated by the server) to obey Retry-After error: BANG: trying again in 10ms 2026/05/09 04:40:38 DEBUG : Sleeping for 10ms (as indicated by the server) to obey Retry-After error: BANG: trying again in 10ms 2026/05/09 04:40:38 DEBUG : Sleeping for 10ms (as indicated by the server) to obey Retry-After error: BANG: trying again in 10ms 2026/05/09 04:40:38 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:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-koxahix1nazu'", Local "Local file system at /tmp/rclone1125200144", Modify Window "1ms" 2026/05/09 04:40:39 DEBUG : file1: md5 = 58997a9bc3e1905834384b80789c5d13 OK 2026/05/09 04:40:40 DEBUG : file2: md5 = 9e08083558934e56de72a6f7fb150da7 OK --- PASS: TestCat (13.51s) === RUN TestPurge 2026/05/09 04:40:51 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-poyadux3gije" 2026/05/09 04:40:51 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/05/09 04:40:51 DEBUG : Creating backend with remote "TestDrive:crypt/4shvgsruj12lq86tmuacelre8kbtjb19lar6d8u3c716hasit89g" 2026/05/09 04:40:52 DEBUG : Creating backend with remote "/tmp/rclone4178181934" run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-poyadux3gije'", Local "Local file system at /tmp/rclone4178181934", Modify Window "1ms" 2026/05/09 04:40:57 DEBUG : A1/B1/C1/one: md5 = d95975a3d06e2cb8e09ef5c56d8f9317 OK 2026/05/09 04:40:57 INFO : A2: Making directory 2026/05/09 04:40:58 INFO : A1/B2: Making directory 2026/05/09 04:40:59 INFO : A1/B2/C2: Making directory 2026/05/09 04:41:00 INFO : A1/B1/C3: Making directory 2026/05/09 04:41:00 INFO : A3: Making directory 2026/05/09 04:41:01 INFO : A3/B3: Making directory 2026/05/09 04:41:02 INFO : A3/B3/C4: Making directory 2026/05/09 04:41:04 DEBUG : A1/two: md5 = abbc2f3e07caf46e3df004b512ac36d6 OK 2026/05/09 04:41:08 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-poyadux3gije': Purge remote 2026/05/09 04:41:08 NOTICE: purge failed: directory not found --- PASS: TestPurge (16.82s) === RUN TestRmdirsNoLeaveRoot run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-koxahix1nazu'", Local "Local file system at /tmp/rclone1125200144", Modify Window "1ms" 2026/05/09 04:41:12 DEBUG : A1/B1/C1/one: md5 = c56264fed66f980330b1a110f670cdee OK 2026/05/09 04:41:12 INFO : A2: Making directory 2026/05/09 04:41:13 INFO : A1/B2: Making directory 2026/05/09 04:41:14 INFO : A1/B2/C2: Making directory 2026/05/09 04:41:14 INFO : A1/B1/C3: Making directory 2026/05/09 04:41:15 INFO : A3: Making directory 2026/05/09 04:41:16 INFO : A3/B3: Making directory 2026/05/09 04:41:17 INFO : A3/B3/C4: Making directory 2026/05/09 04:41:19 DEBUG : A1/two: md5 = b00fd5505e4edc9488f546ddc5683fdf OK 2026/05/09 04:41:20 DEBUG : removing 1 level 3 directories 2026/05/09 04:41:20 INFO : A3/B3/C4: Removing directory 2026/05/09 04:41:23 DEBUG : removing 2 level 3 directories 2026/05/09 04:41:23 INFO : A1/B2/C2: Removing directory 2026/05/09 04:41:23 INFO : A1/B1/C3: Removing directory 2026/05/09 04:41:24 DEBUG : removing 2 level 2 directories 2026/05/09 04:41:24 INFO : A3/B3: Removing directory 2026/05/09 04:41:24 INFO : A1/B2: Removing directory 2026/05/09 04:41:24 DEBUG : h0fgn6vkka25jj9fhu5roml6pg/avn2ljdtrs3fgdela1haatj3go: Rmdir: contains trashed file: "k43jqb5391s8afh9eje7baui0g" 2026/05/09 04:41:24 DEBUG : fppqbfrji50odnh2br069japlo/8j6vge4hggs2biai52vpe0kpik: Rmdir: contains trashed file: "ope427f0bb0h1ebd1s1fm4022k" 2026/05/09 04:41:25 DEBUG : removing 2 level 1 directories 2026/05/09 04:41:25 INFO : A3: Removing directory 2026/05/09 04:41:25 INFO : A2: Removing directory 2026/05/09 04:41:25 DEBUG : fppqbfrji50odnh2br069japlo: Rmdir: contains trashed file: "8j6vge4hggs2biai52vpe0kpik" 2026/05/09 04:41:29 DEBUG : removing 1 level 3 directories 2026/05/09 04:41:29 INFO : A1/B1/C1: Removing directory 2026/05/09 04:41:29 DEBUG : h0fgn6vkka25jj9fhu5roml6pg/rv5l12j0nanu5af9ivc97ofdk8/gtu1leijh9rlm05pcjtusqop2g: Rmdir: contains trashed file: "0828u6q2p8d62qeklrkkuf10uc" 2026/05/09 04:41:30 DEBUG : removing 1 level 2 directories 2026/05/09 04:41:30 INFO : A1/B1: Removing directory 2026/05/09 04:41:30 DEBUG : h0fgn6vkka25jj9fhu5roml6pg/rv5l12j0nanu5af9ivc97ofdk8: Rmdir: contains trashed file: "ui2vk9n52l82tdno99vhkfi87g" 2026/05/09 04:41:30 DEBUG : h0fgn6vkka25jj9fhu5roml6pg/rv5l12j0nanu5af9ivc97ofdk8: Rmdir: contains trashed file: "gtu1leijh9rlm05pcjtusqop2g" 2026/05/09 04:41:30 DEBUG : removing 1 level 1 directories 2026/05/09 04:41:30 INFO : A1: Removing directory 2026/05/09 04:41:31 DEBUG : h0fgn6vkka25jj9fhu5roml6pg: Rmdir: contains trashed file: "avn2ljdtrs3fgdela1haatj3go" 2026/05/09 04:41:31 DEBUG : h0fgn6vkka25jj9fhu5roml6pg: Rmdir: contains trashed file: "rv5l12j0nanu5af9ivc97ofdk8" 2026/05/09 04:41:31 DEBUG : h0fgn6vkka25jj9fhu5roml6pg: Rmdir: contains trashed file: "keafetfvtbmgo6tsdvcbj32i0k" 2026/05/09 04:41:31 DEBUG : removing 1 level 0 directories 2026/05/09 04:41:31 INFO : Encrypted drive 'TestCryptDrive:rclone-test-koxahix1nazu': Removing directory 2026/05/09 04:41:32 DEBUG : Rmdir: contains trashed file: "fppqbfrji50odnh2br069japlo" 2026/05/09 04:41:32 DEBUG : Rmdir: contains trashed file: "c3k31g9ua29pvlul21td3bpldo" 2026/05/09 04:41:32 DEBUG : Rmdir: contains trashed file: "h0fgn6vkka25jj9fhu5roml6pg" 2026/05/09 04:41:32 DEBUG : Rmdir: contains trashed file: "14begaa36ih249fgrnfk6nulg0" 2026/05/09 04:41:32 DEBUG : Rmdir: contains trashed file: "14begaa36ih249fgrnfk6nulg0" 2026/05/09 04:41:32 DEBUG : Rmdir: contains trashed file: "150fuo3cn4j1uenq6r4g4qk6t4" 2026/05/09 04:41:32 DEBUG : Rmdir: contains trashed file: "150fuo3cn4j1uenq6r4g4qk6t4" 2026/05/09 04:41:32 DEBUG : Rmdir: contains trashed file: "14begaa36ih249fgrnfk6nulg0" 2026/05/09 04:41:32 DEBUG : Rmdir: contains trashed file: "14begaa36ih249fgrnfk6nulg0" 2026/05/09 04:41:32 DEBUG : Rmdir: contains trashed file: "1u1r4ei7c628fnjg9blqt3j60o" 2026/05/09 04:41:32 DEBUG : Rmdir: contains trashed file: "urj8gsducqbtvekeq181ntd2u8" 2026/05/09 04:41:32 DEBUG : Rmdir: contains trashed file: "6kqidisnml4ml642h4iorebdng" 2026/05/09 04:41:32 DEBUG : Rmdir: contains trashed file: "bl9cnu966tarhhpg22ft7c4qm1863k22qdncucii8kn8c3k4aut0" 2026/05/09 04:41:32 DEBUG : Rmdir: contains trashed file: "s6dbk3lfi7c9kfvo6j7bla9m0g" 2026/05/09 04:41:32 DEBUG : Rmdir: contains trashed file: "d09o6po3f7bm6ce32vdgs8h9ls" 2026/05/09 04:41:32 DEBUG : Rmdir: contains trashed file: "31u3jie661vd5p8j7rtc3hgbh0" 2026/05/09 04:41:32 DEBUG : Rmdir: contains trashed file: "gveqi14airsml4bgu7krj116o8" 2026/05/09 04:41:32 DEBUG : Rmdir: contains trashed file: "31u3jie661vd5p8j7rtc3hgbh0" 2026/05/09 04:41:32 DEBUG : Rmdir: contains trashed file: "1nrff024r7pq65ecp72fc28jb0" 2026/05/09 04:41:32 DEBUG : Rmdir: contains trashed file: "31u3jie661vd5p8j7rtc3hgbh0" 2026/05/09 04:41:32 DEBUG : Rmdir: contains trashed file: "150fuo3cn4j1uenq6r4g4qk6t4" 2026/05/09 04:41:32 DEBUG : Rmdir: contains trashed file: "vjrnln8ratgmqakfosrqe8espk" 2026/05/09 04:41:32 DEBUG : Rmdir: contains trashed file: "0828u6q2p8d62qeklrkkuf10uc" 2026/05/09 04:41:32 DEBUG : Rmdir: contains trashed file: "to8qa1vn2r6dftei4e0knjo6o4" 2026/05/09 04:41:32 DEBUG : Rmdir: contains trashed file: "to8qa1vn2r6dftei4e0knjo6o4" 2026/05/09 04:41:32 DEBUG : Rmdir: contains trashed file: "c4shrkuhic7ob3njfv3g2s2vik" 2026/05/09 04:41:32 DEBUG : Rmdir: contains trashed file: "to8qa1vn2r6dftei4e0knjo6o4" 2026/05/09 04:41:32 DEBUG : Rmdir: contains trashed file: "0828u6q2p8d62qeklrkkuf10uc" 2026/05/09 04:41:32 DEBUG : Rmdir: contains trashed file: "0828u6q2p8d62qeklrkkuf10uc" 2026/05/09 04:41:32 DEBUG : Rmdir: contains trashed file: "0828u6q2p8d62qeklrkkuf10uc" 2026/05/09 04:41:32 DEBUG : Rmdir: contains trashed file: "0828u6q2p8d62qeklrkkuf10uc" 2026/05/09 04:41:32 DEBUG : Rmdir: contains trashed file: "tg4p894oov5pg84onakp5iir70" 2026/05/09 04:41:32 DEBUG : Rmdir: contains trashed file: "1atl8vl4e0t9vhahglkub15g9k" 2026/05/09 04:41:32 DEBUG : Rmdir: contains trashed file: "1atl8vl4e0t9vhahglkub15g9k" 2026/05/09 04:41:32 DEBUG : Rmdir: contains trashed file: "1atl8vl4e0t9vhahglkub15g9k" 2026/05/09 04:41:32 DEBUG : Rmdir: contains trashed file: "0828u6q2p8d62qeklrkkuf10uc" 2026/05/09 04:41:32 DEBUG : Rmdir: contains trashed file: "0828u6q2p8d62qeklrkkuf10uc" 2026/05/09 04:41:32 DEBUG : Rmdir: contains trashed file: "1atl8vl4e0t9vhahglkub15g9k" 2026/05/09 04:41:32 DEBUG : Rmdir: contains trashed file: "0828u6q2p8d62qeklrkkuf10uc" 2026/05/09 04:41:32 DEBUG : Rmdir: contains trashed file: "1atl8vl4e0t9vhahglkub15g9k" 2026/05/09 04:41:32 DEBUG : Rmdir: contains trashed file: "tg4p894oov5pg84onakp5iir70" 2026/05/09 04:41:32 DEBUG : Rmdir: contains trashed file: "1atl8vl4e0t9vhahglkub15g9k" 2026/05/09 04:41:32 DEBUG : Rmdir: contains trashed file: "1atl8vl4e0t9vhahglkub15g9k" 2026/05/09 04:41:32 DEBUG : Rmdir: contains trashed file: "tg4p894oov5pg84onakp5iir70" 2026/05/09 04:41:32 DEBUG : Rmdir: contains trashed file: "tg4p894oov5pg84onakp5iir70" 2026/05/09 04:41:32 DEBUG : Rmdir: contains trashed file: "1atl8vl4e0t9vhahglkub15g9k" 2026/05/09 04:41:32 DEBUG : Rmdir: contains trashed file: "d26uovf0miqhd1mu3c9jbseq00" 2026/05/09 04:41:32 DEBUG : Rmdir: contains trashed file: "bdfh2jnp94io268p9p6mr133n4" 2026/05/09 04:41:32 DEBUG : Rmdir: contains trashed file: "160p8jokk6e0sqr9khoaqluoic" 2026/05/09 04:41:32 DEBUG : Rmdir: contains trashed file: "89l8renevh3mji1c8r1p7qn6tg" 2026/05/09 04:41:32 DEBUG : Rmdir: contains trashed file: "89l8renevh3mji1c8r1p7qn6tg" 2026/05/09 04:41:32 DEBUG : Rmdir: contains trashed file: "q75kr6k6e4u4qcpfacnefr56vg" 2026/05/09 04:41:32 DEBUG : Rmdir: contains trashed file: "2a4ubhneaj47tjcufnjilco248" 2026/05/09 04:41:32 DEBUG : Rmdir: contains trashed file: "1hetg8lme4rcdmnfbr0koj9m74" 2026/05/09 04:41:32 DEBUG : Rmdir: contains trashed file: "09ptdmd3vn9ng72gtig19k3tqg" 2026/05/09 04:41:32 DEBUG : Rmdir: contains trashed file: "q5ep28d6fiuvnifhronkrc1h8c" 2026/05/09 04:41:32 DEBUG : Rmdir: contains trashed file: "89l8renevh3mji1c8r1p7qn6tg" 2026/05/09 04:41:32 DEBUG : Rmdir: contains trashed file: "89l8renevh3mji1c8r1p7qn6tg" 2026/05/09 04:41:32 DEBUG : Rmdir: contains trashed file: "0828u6q2p8d62qeklrkkuf10uc" 2026/05/09 04:41:32 DEBUG : Rmdir: contains trashed file: "m5t7ukfh84vcomkjqcneu2d7uo" 2026/05/09 04:41:32 DEBUG : Rmdir: contains trashed file: "0828u6q2p8d62qeklrkkuf10uc" 2026/05/09 04:41:32 DEBUG : Rmdir: contains trashed file: "09ptdmd3vn9ng72gtig19k3tqg" 2026/05/09 04:41:32 DEBUG : Rmdir: contains trashed file: "eer8kka55qnghc34cq76ca668g" 2026/05/09 04:41:32 DEBUG : Rmdir: contains trashed file: "eer8kka55qnghc34cq76ca668g" 2026/05/09 04:41:32 DEBUG : Rmdir: contains trashed file: "0828u6q2p8d62qeklrkkuf10uc" 2026/05/09 04:41:32 DEBUG : Rmdir: contains trashed file: "89l8renevh3mji1c8r1p7qn6tg" 2026/05/09 04:41:32 DEBUG : Rmdir: contains trashed file: "q5ep28d6fiuvnifhronkrc1h8c" 2026/05/09 04:41:32 DEBUG : Rmdir: contains trashed file: "0828u6q2p8d62qeklrkkuf10uc" 2026/05/09 04:41:32 DEBUG : Rmdir: contains trashed file: "e9p3gp5bt94lpg91gpij093918" 2026/05/09 04:41:32 DEBUG : Rmdir: contains trashed file: "0828u6q2p8d62qeklrkkuf10uc" 2026/05/09 04:41:32 DEBUG : Rmdir: contains trashed file: "eer8kka55qnghc34cq76ca668g" 2026/05/09 04:41:32 DEBUG : Rmdir: contains trashed file: "q5ep28d6fiuvnifhronkrc1h8c" 2026/05/09 04:41:32 DEBUG : Rmdir: contains trashed file: "160p8jokk6e0sqr9khoaqluoic" 2026/05/09 04:41:32 DEBUG : Rmdir: contains trashed file: "0828u6q2p8d62qeklrkkuf10uc" 2026/05/09 04:41:32 DEBUG : Rmdir: contains trashed file: "4u4fjlma3lemqjvkevbirqdmms" 2026/05/09 04:41:32 DEBUG : Rmdir: contains trashed file: "09ptdmd3vn9ng72gtig19k3tqg" 2026/05/09 04:41:32 DEBUG : Rmdir: contains trashed file: "0828u6q2p8d62qeklrkkuf10uc" 2026/05/09 04:41:32 DEBUG : Rmdir: contains trashed file: "160p8jokk6e0sqr9khoaqluoic" 2026/05/09 04:41:32 DEBUG : Rmdir: contains trashed file: "0828u6q2p8d62qeklrkkuf10uc" 2026/05/09 04:41:32 DEBUG : Rmdir: contains trashed file: "89l8renevh3mji1c8r1p7qn6tg" 2026/05/09 04:41:32 DEBUG : Rmdir: contains trashed file: "160p8jokk6e0sqr9khoaqluoic" 2026/05/09 04:41:32 DEBUG : Rmdir: contains trashed file: "0828u6q2p8d62qeklrkkuf10uc" 2026/05/09 04:41:32 DEBUG : Rmdir: contains trashed file: "4u4fjlma3lemqjvkevbirqdmms" 2026/05/09 04:41:32 DEBUG : Rmdir: contains trashed file: "89l8renevh3mji1c8r1p7qn6tg" 2026/05/09 04:41:32 DEBUG : Rmdir: contains trashed file: "89l8renevh3mji1c8r1p7qn6tg" 2026/05/09 04:41:32 DEBUG : Rmdir: contains trashed file: "m5t7ukfh84vcomkjqcneu2d7uo" 2026/05/09 04:41:32 DEBUG : Rmdir: contains trashed file: "0828u6q2p8d62qeklrkkuf10uc" 2026/05/09 04:41:32 DEBUG : Rmdir: contains trashed file: "0828u6q2p8d62qeklrkkuf10uc" 2026/05/09 04:41:32 DEBUG : Rmdir: contains trashed file: "09ptdmd3vn9ng72gtig19k3tqg" 2026/05/09 04:41:32 DEBUG : Rmdir: contains trashed file: "0828u6q2p8d62qeklrkkuf10uc" 2026/05/09 04:41:32 DEBUG : Rmdir: contains trashed file: "1hetg8lme4rcdmnfbr0koj9m74" --- PASS: TestRmdirsNoLeaveRoot (24.95s) === RUN TestRmdirsLeaveRoot run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-koxahix1nazu'", Local "Local file system at /tmp/rclone1125200144", Modify Window "1ms" 2026/05/09 04:41:34 INFO : A1: Making directory 2026/05/09 04:41:34 INFO : A1/B1: Making directory 2026/05/09 04:41:35 INFO : A1/B1/C1: Making directory 2026/05/09 04:41:38 DEBUG : removing 1 level 3 directories 2026/05/09 04:41:38 INFO : A1/B1/C1: Removing directory 2026/05/09 04:41:39 DEBUG : removing 1 level 2 directories 2026/05/09 04:41:39 INFO : A1/B1: Removing directory 2026/05/09 04:41:39 DEBUG : h0fgn6vkka25jj9fhu5roml6pg/rv5l12j0nanu5af9ivc97ofdk8: Rmdir: contains trashed file: "gtu1leijh9rlm05pcjtusqop2g" 2026/05/09 04:41:40 DEBUG : h0fgn6vkka25jj9fhu5roml6pg: Rmdir: contains trashed file: "rv5l12j0nanu5af9ivc97ofdk8" --- PASS: TestRmdirsLeaveRoot (8.30s) === RUN TestRmdirsWithFilter run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-koxahix1nazu'", Local "Local file system at /tmp/rclone1125200144", Modify Window "1ms" 2026/05/09 04:41:41 INFO : A1: Making directory 2026/05/09 04:41:42 INFO : A1/B1: Making directory 2026/05/09 04:41:43 INFO : A1/B1/C1: Making directory 2026/05/09 04:41:46 DEBUG : removing 1 level 3 directories 2026/05/09 04:41:46 INFO : A1/B1/C1: Removing directory 2026/05/09 04:41:46 DEBUG : removing 1 level 2 directories 2026/05/09 04:41:46 INFO : A1/B1: Removing directory 2026/05/09 04:41:47 DEBUG : h0fgn6vkka25jj9fhu5roml6pg/rv5l12j0nanu5af9ivc97ofdk8: Rmdir: contains trashed file: "gtu1leijh9rlm05pcjtusqop2g" 2026/05/09 04:41:48 DEBUG : h0fgn6vkka25jj9fhu5roml6pg: Rmdir: contains trashed file: "rv5l12j0nanu5af9ivc97ofdk8" --- PASS: TestRmdirsWithFilter (7.86s) === RUN TestCopyURL run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-koxahix1nazu'", Local "Local file system at /tmp/rclone1125200144", Modify Window "1ms" 2026/05/09 04:41:51 DEBUG : file1: md5 = a6c7f6a2f927f22be8f67c94b7a95570 OK 2026/05/09 04:41:51 DEBUG : filename.txt: File name found in url 2026/05/09 04:41:53 DEBUG : filename.txt: md5 = 2d29e4c065cae39545c891354b0c3ecd OK 2026/05/09 04:41:53 DEBUG : headerfilename.txt: filename found in Content-Disposition header. 2026/05/09 04:41:54 DEBUG : headerfilename.txt: md5 = 41f7454c566252ad5ac194b8962597b2 OK 2026/05/09 04:41:55 DEBUG : file2: md5 = 9a8095aee98fd26e8e625a3ad7676361 OK --- PASS: TestCopyURL (9.00s) === RUN TestCopyURLDownloadHeaders run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-koxahix1nazu'", Local "Local file system at /tmp/rclone1125200144", Modify Window "1ms" 2026/05/09 04:41:59 DEBUG : file1: md5 = a244ca88e0cf71b0a677fdd1faf437d8 OK --- PASS: TestCopyURLDownloadHeaders (2.30s) === RUN TestCopyURLToWriter --- PASS: TestCopyURLToWriter (0.00s) === RUN TestMoveFile run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-koxahix1nazu'", Local "Local file system at /tmp/rclone1125200144", Modify Window "1ms" 2026/05/09 04:42:01 DEBUG : file1: Need to transfer - File not found at Destination 2026/05/09 04:42:03 DEBUG : sub/file2: md5 = 35a4dcec1f6666c50be8a3e5b4064748 OK 2026/05/09 04:42:03 DEBUG : sub/file2: size = 14 OK 2026/05/09 04:42:03 INFO : file1: Copied (new) to: sub/file2 2026/05/09 04:42:03 INFO : file1: Deleted 2026/05/09 04:42:03 DEBUG : sub/file2: size = 14 OK 2026/05/09 04:42:03 DEBUG : file1: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2026/05/09 04:42:03 DEBUG : file1: Unchanged skipping 2026/05/09 04:42:03 INFO : file1: Deleted 2026/05/09 04:42:04 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-koxahix1nazu': don't need to copy/move sub/file2, it is already at target location 2026/05/09 04:42:06 DEBUG : 150fuo3cn4j1uenq6r4g4qk6t4: Rmdir: contains trashed file: "d26uovf0miqhd1mu3c9jbseq00" --- PASS: TestMoveFile (6.11s) === RUN TestMoveFileWithIgnoreExisting run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-koxahix1nazu'", Local "Local file system at /tmp/rclone1125200144", Modify Window "1ms" 2026/05/09 04:42:07 DEBUG : file1: Need to transfer - File not found at Destination 2026/05/09 04:42:08 DEBUG : file1: md5 = 4e5971b3a27987844f468697a666ea60 OK 2026/05/09 04:42:08 DEBUG : file1: size = 14 OK 2026/05/09 04:42:08 INFO : file1: Copied (new) 2026/05/09 04:42:08 INFO : file1: Deleted 2026/05/09 04:42:09 DEBUG : file1: Destination exists, skipping 2026/05/09 04:42:09 DEBUG : file1: Not removing source file as destination file exists and --ignore-existing is set --- PASS: TestMoveFileWithIgnoreExisting (3.34s) === RUN TestCaseInsensitiveMoveFile run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-koxahix1nazu'", Local "Local file system at /tmp/rclone1125200144", Modify Window "1ms" --- PASS: TestCaseInsensitiveMoveFile (0.47s) === RUN TestCaseInsensitiveMoveFileDryRun run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-koxahix1nazu'", Local "Local file system at /tmp/rclone1125200144", Modify Window "1ms" --- PASS: TestCaseInsensitiveMoveFileDryRun (0.43s) === RUN TestMoveFileBackupDir run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-koxahix1nazu'", Local "Local file system at /tmp/rclone1125200144", Modify Window "1ms" 2026/05/09 04:42:13 DEBUG : dst/file1: md5 = 30eaf947db396c209e44315533238b70 OK 2026/05/09 04:42:14 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-koxahix1nazu/backup" 2026/05/09 04:42:14 DEBUG : Creating backend with remote "TestDrive:crypt/q5irbcg74s47er306k0erq792vnc8h60c1le4mietu585gn3ua7g/1nrff024r7pq65ecp72fc28jb0" 2026/05/09 04:42:16 DEBUG : dst/file1: size = 14 (Local file system at /tmp/rclone1125200144) 2026/05/09 04:42:16 DEBUG : dst/file1: size = 18 (Encrypted drive 'TestCryptDrive:rclone-test-koxahix1nazu') 2026/05/09 04:42:16 DEBUG : dst/file1: Sizes differ 2026/05/09 04:42:18 INFO : dst/file1: Moved (server-side) 2026/05/09 04:42:19 DEBUG : dst/file1: md5 = 291f2a42e4003ab11534ab981ae051a0 OK 2026/05/09 04:42:19 DEBUG : dst/file1: size = 14 OK 2026/05/09 04:42:19 INFO : dst/file1: Copied (new) 2026/05/09 04:42:19 INFO : dst/file1: Deleted 2026/05/09 04:42:22 DEBUG : 31u3jie661vd5p8j7rtc3hgbh0: Rmdir: contains trashed file: "eer8kka55qnghc34cq76ca668g" 2026/05/09 04:42:23 DEBUG : 1nrff024r7pq65ecp72fc28jb0/31u3jie661vd5p8j7rtc3hgbh0: Rmdir: contains trashed file: "eer8kka55qnghc34cq76ca668g" 2026/05/09 04:42:24 DEBUG : 1nrff024r7pq65ecp72fc28jb0: Rmdir: contains trashed file: "31u3jie661vd5p8j7rtc3hgbh0" --- PASS: TestMoveFileBackupDir (13.70s) === 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:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-koxahix1nazu'", Local "Local file system at /tmp/rclone1125200144", Modify Window "1ms" 2026/05/09 04:42:27 DEBUG : A1/one: md5 = 14ede11fa90babdab053c0041adc13dc OK 2026/05/09 04:42:28 DEBUG : A1/two: md5 = 25acea21750439aa061b955327028a9c OK 2026/05/09 04:42:31 DEBUG : A1/B1/three: md5 = 18f8a8da672427b453ff33fea58d8973 OK 2026/05/09 04:42:33 DEBUG : A1/B1/C1/four: md5 = 002b5aa6ba356928cfb7348c0d1c22a0 OK 2026/05/09 04:42:35 DEBUG : A1/B1/C2/five: md5 = c0577431c80b404aa44b01e75d4d88bb OK 2026/05/09 04:42:35 INFO : A1/B2: Making directory 2026/05/09 04:42:36 INFO : A1/B1/C3: Making directory 2026/05/09 04:42:47 INFO : A2/two: Moved (server-side) to: A3/two 2026/05/09 04:42:47 INFO : A2/B1/C2/five: Moved (server-side) to: A3/B1/C2/five 2026/05/09 04:42:47 INFO : A2/B1/three: Moved (server-side) to: A3/B1/three 2026/05/09 04:42:47 INFO : A2/B1/C1/four: Moved (server-side) to: A3/B1/C1/four 2026/05/09 04:42:47 INFO : A2/one: Moved (server-side) to: A3/one 2026/05/09 04:42:50 DEBUG : c3k31g9ua29pvlul21td3bpldo/rv5l12j0nanu5af9ivc97ofdk8: Rmdir: contains trashed file: "ui2vk9n52l82tdno99vhkfi87g" 2026/05/09 04:42:50 DEBUG : c3k31g9ua29pvlul21td3bpldo/rv5l12j0nanu5af9ivc97ofdk8: Rmdir: contains trashed file: "k43jqb5391s8afh9eje7baui0g" 2026/05/09 04:42:50 DEBUG : c3k31g9ua29pvlul21td3bpldo/rv5l12j0nanu5af9ivc97ofdk8: Rmdir: contains trashed file: "gtu1leijh9rlm05pcjtusqop2g" 2026/05/09 04:42:51 DEBUG : c3k31g9ua29pvlul21td3bpldo: Rmdir: contains trashed file: "avn2ljdtrs3fgdela1haatj3go" 2026/05/09 04:42:51 DEBUG : c3k31g9ua29pvlul21td3bpldo: Rmdir: contains trashed file: "rv5l12j0nanu5af9ivc97ofdk8" 2026/05/09 04:42:52 INFO : Encrypted drive 'TestCryptDrive:rclone-test-koxahix1nazu': Can't DirMove - falling back to file moves: can't move directory - incompatible remotes 2026/05/09 04:42:59 INFO : A3/two: Moved (server-side) to: A4/two 2026/05/09 04:42:59 INFO : A3/B1/C2/five: Moved (server-side) to: A4/B1/C2/five 2026/05/09 04:42:59 INFO : A3/B1/C1/four: Moved (server-side) to: A4/B1/C1/four 2026/05/09 04:42:59 INFO : A3/B1/three: Moved (server-side) to: A4/B1/three 2026/05/09 04:42:59 INFO : A3/one: Moved (server-side) to: A4/one 2026/05/09 04:43:02 DEBUG : fppqbfrji50odnh2br069japlo/rv5l12j0nanu5af9ivc97ofdk8: Rmdir: contains trashed file: "ui2vk9n52l82tdno99vhkfi87g" 2026/05/09 04:43:02 DEBUG : fppqbfrji50odnh2br069japlo/rv5l12j0nanu5af9ivc97ofdk8: Rmdir: contains trashed file: "k43jqb5391s8afh9eje7baui0g" 2026/05/09 04:43:02 DEBUG : fppqbfrji50odnh2br069japlo/rv5l12j0nanu5af9ivc97ofdk8: Rmdir: contains trashed file: "gtu1leijh9rlm05pcjtusqop2g" 2026/05/09 04:43:03 DEBUG : fppqbfrji50odnh2br069japlo: Rmdir: contains trashed file: "avn2ljdtrs3fgdela1haatj3go" 2026/05/09 04:43:03 DEBUG : fppqbfrji50odnh2br069japlo: Rmdir: contains trashed file: "rv5l12j0nanu5af9ivc97ofdk8" 2026/05/09 04:43:09 DEBUG : kafked7s86nmf4k5e2iil1i668/rv5l12j0nanu5af9ivc97ofdk8/k43jqb5391s8afh9eje7baui0g: Rmdir: contains trashed file: "67t67jgahg44dflpavut88pang" 2026/05/09 04:43:10 DEBUG : kafked7s86nmf4k5e2iil1i668/rv5l12j0nanu5af9ivc97ofdk8/gtu1leijh9rlm05pcjtusqop2g: Rmdir: contains trashed file: "r2oabfeuilgbi5ic6kevi8chd0" 2026/05/09 04:43:11 DEBUG : kafked7s86nmf4k5e2iil1i668/rv5l12j0nanu5af9ivc97ofdk8: Rmdir: contains trashed file: "ui2vk9n52l82tdno99vhkfi87g" 2026/05/09 04:43:11 DEBUG : kafked7s86nmf4k5e2iil1i668/rv5l12j0nanu5af9ivc97ofdk8: Rmdir: contains trashed file: "k43jqb5391s8afh9eje7baui0g" 2026/05/09 04:43:11 DEBUG : kafked7s86nmf4k5e2iil1i668/rv5l12j0nanu5af9ivc97ofdk8: Rmdir: contains trashed file: "gtu1leijh9rlm05pcjtusqop2g" 2026/05/09 04:43:11 DEBUG : kafked7s86nmf4k5e2iil1i668/rv5l12j0nanu5af9ivc97ofdk8: Rmdir: contains trashed file: "b6jdmn2b1adrhc8vikh7edr9os" 2026/05/09 04:43:11 DEBUG : kafked7s86nmf4k5e2iil1i668: Rmdir: contains trashed file: "avn2ljdtrs3fgdela1haatj3go" 2026/05/09 04:43:11 DEBUG : kafked7s86nmf4k5e2iil1i668: Rmdir: contains trashed file: "rv5l12j0nanu5af9ivc97ofdk8" 2026/05/09 04:43:11 DEBUG : kafked7s86nmf4k5e2iil1i668: Rmdir: contains trashed file: "keafetfvtbmgo6tsdvcbj32i0k" 2026/05/09 04:43:11 DEBUG : kafked7s86nmf4k5e2iil1i668: Rmdir: contains trashed file: "0828u6q2p8d62qeklrkkuf10uc" --- PASS: TestDirMove (47.61s) === RUN TestGetFsInfo run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-koxahix1nazu'", Local "Local file system at /tmp/rclone1125200144", Modify Window "1ms" --- PASS: TestGetFsInfo (0.45s) === RUN TestRcat === RUN TestRcat/withChecksum=false,ignoreChecksum=false run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-koxahix1nazu'", Local "Local file system at /tmp/rclone1125200144", Modify Window "1ms" 2026/05/09 04:43:13 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-koxahix1nazu': File to upload is small (34 bytes), uploading instead of streaming 2026/05/09 04:43:14 DEBUG : no_checksum_small_file_from_pipe: md5 = 2f5c2ac87a2411149b7b8d6cced418ca OK 2026/05/09 04:43:14 DEBUG : no_checksum_small_file_from_pipe: size = 34 OK 2026/05/09 04:43:14 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-koxahix1nazu': --checksum is in use but the source and destination have no hashes in common; falling back to --size-only 2026/05/09 04:43:14 DEBUG : no_checksum_small_file_from_pipe: Size of src and dst objects identical 2026/05/09 04:43:15 DEBUG : qc9k4uauqa9ls6a2r6lo8jf4b1m7r0510pkurois1cc70egbs4h0: Sending chunk 0 length 102465 2026/05/09 04:43:16 DEBUG : no_checksum_big_file_from_pipe: md5 = 8dd7059b6b46502c3d9be4c4d35cbb7b OK 2026/05/09 04:43:16 DEBUG : no_checksum_big_file_from_pipe: size = 102401 OK 2026/05/09 04:43:16 DEBUG : no_checksum_big_file_from_pipe: Size of src and dst objects identical === RUN TestRcat/withChecksum=true,ignoreChecksum=false run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-koxahix1nazu'", Local "Local file system at /tmp/rclone1125200144", Modify Window "1ms" 2026/05/09 04:43:18 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-koxahix1nazu': File to upload is small (34 bytes), uploading instead of streaming 2026/05/09 04:43:19 DEBUG : with_checksum_small_file_from_pipe: md5 = f6f1364125f4d2144e202c2493081e2c OK 2026/05/09 04:43:19 DEBUG : with_checksum_small_file_from_pipe: size = 34 OK 2026/05/09 04:43:19 DEBUG : with_checksum_small_file_from_pipe: Size of src and dst objects identical 2026/05/09 04:43:20 DEBUG : e74jo8a7lr1aimg2u78fct67gnuetc1qujiofaci0c8fnq7rjt1d4i296pcklqhgdihs03etvgdi4: Sending chunk 0 length 102465 2026/05/09 04:43:21 DEBUG : with_checksum_big_file_from_pipe: md5 = e2646a13c1f1d34ecf658467fa99cb1c OK 2026/05/09 04:43:21 DEBUG : with_checksum_big_file_from_pipe: size = 102401 OK 2026/05/09 04:43:21 DEBUG : with_checksum_big_file_from_pipe: Size of src and dst objects identical === RUN TestRcat/withChecksum=false,ignoreChecksum=true run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-koxahix1nazu'", Local "Local file system at /tmp/rclone1125200144", Modify Window "1ms" 2026/05/09 04:43:23 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-koxahix1nazu': File to upload is small (34 bytes), uploading instead of streaming 2026/05/09 04:43:25 DEBUG : ignore_checksum_small_file_from_pipe: size = 34 OK 2026/05/09 04:43:25 DEBUG : ignore_checksum_small_file_from_pipe: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2026/05/09 04:43:25 DEBUG : 1963rn6olajfbjn4nbqi9nacu7bk879vpf0d2bg8r0ndcqqsfntob0urt056p0qujf574rqnb5f62: Sending chunk 0 length 102465 2026/05/09 04:43:27 DEBUG : ignore_checksum_big_file_from_pipe: size = 102401 OK 2026/05/09 04:43:27 DEBUG : ignore_checksum_big_file_from_pipe: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) === RUN TestRcat/withChecksum=true,ignoreChecksum=true run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-koxahix1nazu'", Local "Local file system at /tmp/rclone1125200144", Modify Window "1ms" 2026/05/09 04:43:29 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-koxahix1nazu': File to upload is small (34 bytes), uploading instead of streaming 2026/05/09 04:43:30 DEBUG : ignore_checksum_small_file_from_pipe: size = 34 OK 2026/05/09 04:43:30 DEBUG : ignore_checksum_small_file_from_pipe: Size of src and dst objects identical 2026/05/09 04:43:31 DEBUG : 1963rn6olajfbjn4nbqi9nacu7bk879vpf0d2bg8r0ndcqqsfntob0urt056p0qujf574rqnb5f62: Sending chunk 0 length 102465 2026/05/09 04:43:32 DEBUG : ignore_checksum_big_file_from_pipe: size = 102401 OK 2026/05/09 04:43:32 DEBUG : ignore_checksum_big_file_from_pipe: Size of src and dst objects identical --- PASS: TestRcat (21.32s) --- PASS: TestRcat/withChecksum=false,ignoreChecksum=false (5.14s) --- PASS: TestRcat/withChecksum=true,ignoreChecksum=false (5.32s) --- PASS: TestRcat/withChecksum=false,ignoreChecksum=true (5.41s) --- PASS: TestRcat/withChecksum=true,ignoreChecksum=true (5.44s) === RUN TestRcatMetadata run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-koxahix1nazu'", Local "Local file system at /tmp/rclone1125200144", Modify Window "1ms" === RUN TestRcatMetadata/Normal 2026/05/09 04:43:34 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-koxahix1nazu': File to upload is small (48 bytes), uploading instead of streaming 2026/05/09 04:43:35 DEBUG : rcat_metadata: md5 = 930d46832c1a49fde18ee54be217e4ff OK 2026/05/09 04:43:35 DEBUG : rcat_metadata: size = 48 OK 2026/05/09 04:43:35 DEBUG : rcat_metadata: Size of src and dst objects identical === RUN TestRcatMetadata/ViaDisk 2026/05/09 04:43:37 DEBUG : rijssoobhbn85c5p1imhg55m6frank1nlplla6hkdjlqmqb2471g: Sending chunk 0 length 111 2026/05/09 04:43:38 DEBUG : rcat_metadata_uploadcutoff0: md5 = c8478e46dd0548196ae572e438b62015 OK 2026/05/09 04:43:38 DEBUG : rcat_metadata_uploadcutoff0: size = 63 OK 2026/05/09 04:43:38 DEBUG : rcat_metadata_uploadcutoff0: Size of src and dst objects identical --- PASS: TestRcatMetadata (6.32s) --- PASS: TestRcatMetadata/Normal (2.76s) --- PASS: TestRcatMetadata/ViaDisk (3.12s) === RUN TestRcatSize run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-koxahix1nazu'", Local "Local file system at /tmp/rclone1125200144", Modify Window "1ms" 2026/05/09 04:43:42 DEBUG : potato1: md5 = cf6ddc630202e266ebb3357b4d61fd13 OK 2026/05/09 04:43:42 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-koxahix1nazu': File to upload is small (60 bytes), uploading instead of streaming 2026/05/09 04:43:43 DEBUG : potato2: md5 = 2c8bde6fe0a337b116889434f61eda82 OK 2026/05/09 04:43:43 DEBUG : potato2: size = 60 OK 2026/05/09 04:43:43 DEBUG : potato2: Size of src and dst objects identical --- PASS: TestRcatSize (4.96s) === RUN TestRcatSizeMetadata run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-koxahix1nazu'", Local "Local file system at /tmp/rclone1125200144", Modify Window "1ms" 2026/05/09 04:43:47 DEBUG : potato1: md5 = 970110aa4ed282dfb2ad8c35bf202901 OK 2026/05/09 04:43:47 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-koxahix1nazu': File to upload is small (60 bytes), uploading instead of streaming 2026/05/09 04:43:48 DEBUG : potato2: md5 = 21ef11dce6ab1e141fa2e2c31106a2cd OK 2026/05/09 04:43:48 DEBUG : potato2: size = 60 OK 2026/05/09 04:43:48 DEBUG : potato2: Size of src and dst objects identical --- PASS: TestRcatSizeMetadata (5.14s) === RUN TestRcatSizeUploadHeaders run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-koxahix1nazu'", Local "Local file system at /tmp/rclone1125200144", Modify Window "1ms" 2026/05/09 04:43:52 DEBUG : potato1: md5 = 95e03bc9a3810649e6583324416677a8 OK --- PASS: TestRcatSizeUploadHeaders (2.33s) === RUN TestTouchDir run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-koxahix1nazu'", Local "Local file system at /tmp/rclone1125200144", Modify Window "1ms" 2026/05/09 04:43:54 DEBUG : potato2: md5 = 187632a20d53a0b66492f32a53627a09 OK 2026/05/09 04:43:55 DEBUG : empty space: md5 = 60898e608e0d3790066a319991322edf OK 2026/05/09 04:43:58 DEBUG : sub dir/potato3: md5 = 9ef17dc5ef507f2c70d317a30530273c OK 2026/05/09 04:43:59 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-koxahix1nazu': Touching "sub dir/potato3" 2026/05/09 04:43:59 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-koxahix1nazu': Touching "empty space" 2026/05/09 04:43:59 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-koxahix1nazu': Touching "potato2" 2026/05/09 04:44:02 DEBUG : 14begaa36ih249fgrnfk6nulg0: Rmdir: contains trashed file: "9atai6og4mljg3r5gjeh27k0q8" --- PASS: TestTouchDir (10.28s) === RUN TestMkdirMetadata run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-koxahix1nazu'", Local "Local file system at /tmp/rclone1125200144", Modify Window "1ms" 2026/05/09 04:44:03 DEBUG : dir with metadata: Making directory with metadata 2026/05/09 04:44:04 INFO : dir with metadata: Made directory with metadata (mtime=2001-02-03T04:05:06.499999999Z) --- PASS: TestMkdirMetadata (2.43s) === RUN TestMkdirModTime run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-koxahix1nazu'", Local "Local file system at /tmp/rclone1125200144", Modify Window "1ms" 2026/05/09 04:44:05 DEBUG : directory with modtime: Making directory with metadata 2026/05/09 04:44:06 INFO : directory with modtime: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) --- PASS: TestMkdirModTime (2.44s) === RUN TestCopyDirMetadata run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-koxahix1nazu'", Local "Local file system at /tmp/rclone1125200144", Modify Window "1ms" 2026/05/09 04:44:08 DEBUG : dir with metadata to be copied: Making directory with metadata 2026/05/09 04:44:08 INFO : dir with metadata to be copied: Made directory with metadata (mtime=2001-02-03T04:05:06.499999999Z) 2026/05/09 04:44:08 DEBUG : Google drive root 'crypt/q5irbcg74s47er306k0erq792vnc8h60c1le4mietu585gn3ua7g': Skipping btime metadata as can't update it on an existing file: 2026-05-09T04:44:08.167239405Z 2026/05/09 04:44:08 INFO : non existent directory: Updated directory metadata 2026/05/09 04:44:10 DEBUG : Google drive root 'crypt/q5irbcg74s47er306k0erq792vnc8h60c1le4mietu585gn3ua7g': Skipping btime metadata as can't update it on an existing file: 2026-05-09T04:44:08.167239405Z 2026/05/09 04:44:11 INFO : existing directory: Updated directory metadata --- PASS: TestCopyDirMetadata (4.97s) === RUN TestSetDirModTime run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-koxahix1nazu'", Local "Local file system at /tmp/rclone1125200144", Modify Window "1ms" 2026/05/09 04:44:13 DEBUG : set modtime on non existent directory: Skipping set directory modification time as --no-update-dir-modtime is set 2026/05/09 04:44:14 INFO : set modtime on existing directory: Set directory modification time (using SetModTime) 2026/05/09 04:44:15 INFO : set modtime on existing directory: Set directory modification time (using DirSetModTime) --- PASS: TestSetDirModTime (4.16s) === RUN TestDirsEqual run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-koxahix1nazu'", Local "Local file system at /tmp/rclone1125200144", Modify Window "1ms" 2026/05/09 04:44:17 DEBUG : dir with metadata to be copied: Making directory with metadata 2026/05/09 04:44:17 INFO : dir with metadata to be copied: Made directory with metadata (mtime=2001-02-03T04:05:06.499999999Z) 2026/05/09 04:44:17 DEBUG : dst: Making directory with metadata 2026/05/09 04:44:18 INFO : dst: Made directory with metadata (mtime=2001-02-03T04:05:06.499999999Z) 2026/05/09 04:44:18 DEBUG : dst: Directory modification time the same (differ by -999.999µs, within tolerance 1ms) 2026/05/09 04:44:18 INFO : dst: Set directory modification time (using SetModTime) 2026/05/09 04:44:19 INFO : dst: Set directory modification time (using SetModTime) 2026/05/09 04:44:19 DEBUG : dst: Directory modification time the same (differ by 1ns, within tolerance 1ms) 2026/05/09 04:44:19 INFO : dst: Set directory modification time (using SetModTime) 2026/05/09 04:44:19 DEBUG : dst: Destination directory is newer than source, skipping --- PASS: TestDirsEqual (3.64s) === RUN TestRemoveExisting run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-koxahix1nazu'", Local "Local file system at /tmp/rclone1125200144", Modify Window "1ms" 2026/05/09 04:44:23 DEBUG : sub dir/test remove existing: md5 = b3d0676263aed2e64cb98a9d483c4ac7 OK 2026/05/09 04:44:24 DEBUG : sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890123456789: md5 = 20941afb69b8f0b4188f0911c681196d OK 2026/05/09 04:44:26 DEBUG : sub dir/test remove existing: TEST: renaming existing object to "sub dir/test remove existing.qapovid9" before starting 2026/05/09 04:44:27 DEBUG : sub dir/test remove existing.qapovid9: TEST: removing renamed existing file after operation 2026/05/09 04:44:28 DEBUG : sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890123456789: TEST: renaming existing object to "sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890.dinepem0" before starting 2026/05/09 04:44:29 DEBUG : sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890.dinepem0: TEST: renaming existing back after failed operation 2026/05/09 04:44:31 DEBUG : sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890123456789: TEST: renaming existing object to "sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890.lefabuj4" before starting 2026/05/09 04:44:32 DEBUG : sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890.lefabuj4: TEST: removing renamed existing file after operation 2026/05/09 04:44:33 DEBUG : 14begaa36ih249fgrnfk6nulg0: Rmdir: contains trashed file: "2d3v8figf4gmn9jm5odptn5gt4f357pmj6co6cn7hdloprv4j7gg" 2026/05/09 04:44:33 DEBUG : 14begaa36ih249fgrnfk6nulg0: Rmdir: contains trashed file: "3lqg2hap2945ej2345kpq5h2o6fu79g558rnqqitqgissau5soqrma4sndo5q3j827qqasss8np7ub742lpo67et28r3nkjupcqs3quj17b89jeudhmn51hdhq3tlo6u7kd15gqggcmrvt23j8lmrh4tcid04d2egst3fv4nu5dht3vukq90" --- PASS: TestRemoveExisting (13.55s) === RUN TestRcAbout rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcAbout (0.00s) === RUN TestRcCleanup rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcCleanup (0.00s) === RUN TestRcCopyfile rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcCopyfile (0.00s) === RUN TestRcCopyurl rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcCopyurl (0.00s) === RUN TestRcDelete rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcDelete (0.00s) === RUN TestRcDeletefile rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcDeletefile (0.00s) === RUN TestRcList rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcList (0.00s) === RUN TestRcStat rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcStat (0.00s) === RUN TestRcSetTier rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcSetTier (0.00s) === RUN TestRcSetTierFile rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcSetTierFile (0.00s) === RUN TestRcMkdir rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcMkdir (0.00s) === RUN TestRcMovefile rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcMovefile (0.00s) === RUN TestRcPurge rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcPurge (0.00s) === RUN TestRcRmdir rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcRmdir (0.00s) === RUN TestRcRmdirs rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcRmdirs (0.00s) === RUN TestRcSize rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcSize (0.00s) === RUN TestRcPublicLink rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcPublicLink (0.00s) === RUN TestRcFsInfo rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcFsInfo (0.00s) === RUN TestUploadFile rc_test.go:30: Skipping test on non local remote --- SKIP: TestUploadFile (0.00s) === RUN TestRcCommand rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcCommand (0.00s) === RUN TestRcDu rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcDu (0.00s) === RUN TestRcCheck rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcCheck (0.00s) === RUN TestRcHashsum rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcHashsum (0.00s) === RUN TestRcHashsumSingleFile rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcHashsumSingleFile (0.00s) === RUN TestRcHashsumFile rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcHashsumFile (0.00s) PASS 2026/05/09 04:44:34 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-koxahix1nazu': Purge remote "./operations.test -test.v -test.timeout 1h0m0s -remote TestCryptDrive: -verbose -fast-list" - Finished OK in 11m15.355317331s (try 1/5)