"./operations.test -test.v -test.timeout 1h0m0s -remote TestCryptDrive: -verbose -fast-list" - Starting (try 1/5) 2026/01/29 05:26:58 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-fulajol8jiga" 2026/01/29 05:26:58 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/01/29 05:26:58 DEBUG : Creating backend with remote "TestDrive:crypt/hmt9nrq7jloakp17tgo4ehfhqkkos3f6kh7gg7vbnaj6l4scnjk0" 2026/01/29 05:27:01 DEBUG : Creating backend with remote "/tmp/rclone1201838359" === RUN TestDoMultiThreadCopy --- PASS: TestDoMultiThreadCopy (0.02s) === 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.01s) --- PASS: TestMultithreadCalculateNumChunks/{size:1_chunkSize:65536_wantNumChunks:1} (0.00s) --- PASS: TestMultithreadCalculateNumChunks/{size:1048576_chunkSize:1_wantNumChunks:1048576} (0.00s) --- PASS: TestMultithreadCalculateNumChunks/{size:1048576_chunkSize:2_wantNumChunks:524288} (0.00s) --- PASS: TestMultithreadCalculateNumChunks/{size:1048577_chunkSize:2_wantNumChunks:524289} (0.00s) --- PASS: TestMultithreadCalculateNumChunks/{size:1048575_chunkSize:2_wantNumChunks:524288} (0.00s) === RUN TestMultithreadCopy run.go:185: Remote "Encrypted drive 'TestCryptDrive:rclone-test-fulajol8jiga'", Local "Local file system at /tmp/rclone1201838359", Modify Window "1ms" multithread_test.go:119: multithread writing not supported --- SKIP: TestMultithreadCopy (0.74s) === RUN TestMultithreadCopyAbort run.go:185: Remote "Encrypted drive 'TestCryptDrive:rclone-test-fulajol8jiga'", Local "Local file system at /tmp/rclone1201838359", Modify Window "1ms" multithread_test.go:119: multithread writing not supported --- SKIP: TestMultithreadCopyAbort (0.83s) === RUN TestSizeDiffers 2026/01/29 05:27:03 DEBUG : a: size = 0 OK 2026/01/29 05:27:03 DEBUG : a: size = 1 (memory) 2026/01/29 05:27:03 DEBUG : a: size = 2 (memory) --- PASS: TestSizeDiffers (0.42s) === RUN TestReOpen === RUN TestReOpen/Normal === RUN TestReOpen/Normal/Basics 2026/01/29 05:27:03 DEBUG : potato: Seek from 10 to 0 === RUN TestReOpen/Normal/ErrorAtStart === RUN TestReOpen/Normal/WithErrors 2026/01/29 05:27:04 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error 2026/01/29 05:27:04 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error 2026/01/29 05:27:04 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error === RUN TestReOpen/Normal/TooManyErrors 2026/01/29 05:27:04 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error 2026/01/29 05:27:04 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error 2026/01/29 05:27:05 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error 2026/01/29 05:27:05 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries === RUN TestReOpen/Normal/ReadAt 2026/01/29 05:27:06 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2026/01/29 05:27:06 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2026/01/29 05:27:10 DEBUG : potato: Seek from 5 to 1 === RUN TestReOpen/Normal/Seek 2026/01/29 05:27:14 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2026/01/29 05:27:15 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2026/01/29 05:27:15 DEBUG : potato: Seek from 5 to 2 === RUN TestReOpen/Normal/AccountRead === RUN TestReOpen/Normal/AccountReadDelay 2026/01/29 05:27:16 DEBUG : potato: Seek from 10 to 0 2026/01/29 05:27:16 DEBUG : potato: Seek from 10 to 0 2026/01/29 05:27:16 DEBUG : potato: Seek from 10 to 0 === RUN TestReOpen/Normal/AccountReadError === RUN TestReOpen/WithRangeOption === RUN TestReOpen/WithRangeOption/Basics 2026/01/29 05:27:16 DEBUG : potato: Seek from 7 to 0 === RUN TestReOpen/WithRangeOption/ErrorAtStart === RUN TestReOpen/WithRangeOption/WithErrors 2026/01/29 05:27:16 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error 2026/01/29 05:27:16 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error 2026/01/29 05:27:16 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error === RUN TestReOpen/WithRangeOption/TooManyErrors 2026/01/29 05:27:16 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error 2026/01/29 05:27:16 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error 2026/01/29 05:27:16 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error 2026/01/29 05:27:16 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries === RUN TestReOpen/WithRangeOption/ReadAt 2026/01/29 05:27:16 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2026/01/29 05:27:16 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2026/01/29 05:27:16 DEBUG : potato: Seek from 5 to 1 === RUN TestReOpen/WithRangeOption/Seek 2026/01/29 05:27:16 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2026/01/29 05:27:16 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2026/01/29 05:27:16 DEBUG : potato: Seek from 5 to 2 2026/01/29 05:27:16 DEBUG : potato: Seek from 7 to 4 === RUN TestReOpen/WithRangeOption/AccountRead === RUN TestReOpen/WithRangeOption/AccountReadDelay 2026/01/29 05:27:16 DEBUG : potato: Seek from 7 to 0 2026/01/29 05:27:16 DEBUG : potato: Seek from 7 to 0 2026/01/29 05:27:16 DEBUG : potato: Seek from 7 to 0 === RUN TestReOpen/WithRangeOption/AccountReadError === RUN TestReOpen/WithSeekOption === RUN TestReOpen/WithSeekOption/Basics 2026/01/29 05:27:16 DEBUG : potato: Seek from 8 to 0 === RUN TestReOpen/WithSeekOption/ErrorAtStart === RUN TestReOpen/WithSeekOption/WithErrors 2026/01/29 05:27:16 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error 2026/01/29 05:27:16 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error 2026/01/29 05:27:16 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error === RUN TestReOpen/WithSeekOption/TooManyErrors 2026/01/29 05:27:16 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error 2026/01/29 05:27:16 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error 2026/01/29 05:27:16 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error 2026/01/29 05:27:16 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries === RUN TestReOpen/WithSeekOption/ReadAt 2026/01/29 05:27:16 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2026/01/29 05:27:16 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2026/01/29 05:27:16 DEBUG : potato: Seek from 5 to 1 === RUN TestReOpen/WithSeekOption/Seek 2026/01/29 05:27:16 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2026/01/29 05:27:16 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2026/01/29 05:27:16 DEBUG : potato: Seek from 5 to 2 2026/01/29 05:27:16 DEBUG : potato: Seek from 7 to 5 === RUN TestReOpen/WithSeekOption/AccountRead === RUN TestReOpen/WithSeekOption/AccountReadDelay 2026/01/29 05:27:16 DEBUG : potato: Seek from 8 to 0 2026/01/29 05:27:16 DEBUG : potato: Seek from 8 to 0 2026/01/29 05:27:16 DEBUG : potato: Seek from 8 to 0 === RUN TestReOpen/WithSeekOption/AccountReadError === RUN TestReOpen/UnknownSize === RUN TestReOpen/UnknownSize/Basics 2026/01/29 05:27:16 DEBUG : potato: Seek from 9 to 0 === RUN TestReOpen/UnknownSize/ErrorAtStart === RUN TestReOpen/UnknownSize/WithErrors 2026/01/29 05:27:16 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error 2026/01/29 05:27:16 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error 2026/01/29 05:27:16 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error === RUN TestReOpen/UnknownSize/TooManyErrors 2026/01/29 05:27:16 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error 2026/01/29 05:27:16 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error 2026/01/29 05:27:16 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error 2026/01/29 05:27:16 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries === RUN TestReOpen/UnknownSize/ReadAt 2026/01/29 05:27:16 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2026/01/29 05:27:16 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2026/01/29 05:27:16 DEBUG : potato: Seek from 5 to 1 === RUN TestReOpen/UnknownSize/Seek 2026/01/29 05:27:16 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2026/01/29 05:27:16 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2026/01/29 05:27:16 DEBUG : potato: Seek from 5 to 2 2026/01/29 05:27:16 DEBUG : potato: Seek from 7 to 6 === RUN TestReOpen/UnknownSize/AccountRead === RUN TestReOpen/UnknownSize/AccountReadDelay 2026/01/29 05:27:16 DEBUG : potato: Seek from 9 to 0 2026/01/29 05:27:16 DEBUG : potato: Seek from 9 to 0 2026/01/29 05:27:16 DEBUG : potato: Seek from 9 to 0 === RUN TestReOpen/UnknownSize/AccountReadError --- PASS: TestReOpen (12.66s) --- PASS: TestReOpen/Normal (12.52s) --- PASS: TestReOpen/Normal/Basics (0.28s) --- PASS: TestReOpen/Normal/ErrorAtStart (0.07s) --- PASS: TestReOpen/Normal/WithErrors (0.27s) --- PASS: TestReOpen/Normal/TooManyErrors (1.25s) --- PASS: TestReOpen/Normal/ReadAt (6.39s) --- PASS: TestReOpen/Normal/Seek (3.55s) --- PASS: TestReOpen/Normal/AccountRead (0.05s) --- PASS: TestReOpen/Normal/AccountReadDelay (0.08s) --- PASS: TestReOpen/Normal/AccountReadError (0.04s) --- PASS: TestReOpen/WithRangeOption (0.08s) --- PASS: TestReOpen/WithRangeOption/Basics (0.04s) --- PASS: TestReOpen/WithRangeOption/ErrorAtStart (0.00s) --- PASS: TestReOpen/WithRangeOption/WithErrors (0.01s) --- 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.01s) --- PASS: TestReOpen/WithSeekOption/Basics (0.00s) --- PASS: TestReOpen/WithSeekOption/ErrorAtStart (0.01s) --- PASS: TestReOpen/WithSeekOption/WithErrors (0.00s) --- PASS: TestReOpen/WithSeekOption/TooManyErrors (0.00s) --- PASS: TestReOpen/WithSeekOption/ReadAt (0.00s) --- PASS: TestReOpen/WithSeekOption/Seek (0.00s) --- PASS: TestReOpen/WithSeekOption/AccountRead (0.00s) --- PASS: TestReOpen/WithSeekOption/AccountReadDelay (0.00s) --- PASS: TestReOpen/WithSeekOption/AccountReadError (0.00s) --- PASS: TestReOpen/UnknownSize (0.00s) --- PASS: TestReOpen/UnknownSize/Basics (0.00s) --- PASS: TestReOpen/UnknownSize/ErrorAtStart (0.00s) --- PASS: TestReOpen/UnknownSize/WithErrors (0.00s) --- PASS: TestReOpen/UnknownSize/TooManyErrors (0.00s) --- PASS: TestReOpen/UnknownSize/ReadAt (0.00s) --- PASS: TestReOpen/UnknownSize/Seek (0.00s) --- PASS: TestReOpen/UnknownSize/AccountRead (0.00s) --- PASS: TestReOpen/UnknownSize/AccountReadDelay (0.00s) --- PASS: TestReOpen/UnknownSize/AccountReadError (0.00s) === RUN TestCheck run.go:185: Remote "Encrypted drive 'TestCryptDrive:rclone-test-fulajol8jiga'", Local "Local file system at /tmp/rclone1201838359", Modify Window "1ms" 2026/01/29 05:27:21 DEBUG : rutabaga: md5 = 7f46600461c68008f0532b98e7e9d825 OK === RUN TestCheck/1 === RUN TestCheck/2 2026/01/29 05:27:24 DEBUG : empty space: md5 = df3029a3b7c61199f3564362fcb70290 OK === RUN TestCheck/3 2026/01/29 05:27:27 DEBUG : potato2: md5 = ef51923a50281b31dd4e32acd02a797b OK === RUN TestCheck/4 === RUN TestCheck/5 2026/01/29 05:27:30 DEBUG : remotepotato: md5 = 90bdf7e4ef965f051a1d90355635e5a2 OK === RUN TestCheck/6 === RUN TestCheck/7 --- PASS: TestCheck (563.73s) --- PASS: TestCheck/1 (0.40s) --- PASS: TestCheck/2 (0.53s) --- PASS: TestCheck/3 (0.39s) --- PASS: TestCheck/4 (0.44s) --- PASS: TestCheck/5 (0.36s) --- PASS: TestCheck/6 (0.64s) --- PASS: TestCheck/7 (544.48s) === RUN TestCheckFsError 2026/01/29 05:36:40 DEBUG : Creating backend with remote "nonexistent" 2026/01/29 05:36:40 DEBUG : Config file has changed externally - reloading 2026/01/29 05:36:40 DEBUG : Creating backend with remote "nonexistent" 2026/01/29 05:36:40 DEBUG : Local file system at /home/rclone/go/src/github.com/rclone/rclone/fs/operations/nonexistent: Waiting for checks to finish 2026/01/29 05:36:40 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/01/29 05:36:40 NOTICE: Local file system at /home/rclone/go/src/github.com/rclone/rclone/fs/operations/nonexistent: 0 differences found 2026/01/29 05:36:40 NOTICE: Local file system at /home/rclone/go/src/github.com/rclone/rclone/fs/operations/nonexistent: 2 errors while checking --- PASS: TestCheckFsError (0.00s) === RUN TestCheckDownload run.go:185: Remote "Encrypted drive 'TestCryptDrive:rclone-test-fulajol8jiga'", Local "Local file system at /tmp/rclone1201838359", Modify Window "1ms" 2026/01/29 05:36:42 DEBUG : rutabaga: md5 = 1455727972fb74f703159e2ff7a09f22 OK === RUN TestCheckDownload/1 === RUN TestCheckDownload/2 2026/01/29 05:36:46 DEBUG : empty space: md5 = 8cfbc13a69d5048f07b692b75c677f0d OK === RUN TestCheckDownload/3 2026/01/29 05:36:49 DEBUG : potato2: md5 = 06f004ff33086a68f5b05cb863e529a9 OK === RUN TestCheckDownload/4 === RUN TestCheckDownload/5 2026/01/29 05:36:53 DEBUG : remotepotato: md5 = a8953a790671f6f99ff5086165ccc256 OK === RUN TestCheckDownload/6 === RUN TestCheckDownload/7 --- PASS: TestCheckDownload (19.49s) --- PASS: TestCheckDownload/1 (1.13s) --- PASS: TestCheckDownload/2 (0.86s) --- PASS: TestCheckDownload/3 (0.81s) --- PASS: TestCheckDownload/4 (1.03s) --- PASS: TestCheckDownload/5 (0.90s) --- PASS: TestCheckDownload/6 (1.24s) --- PASS: TestCheckDownload/7 (0.94s) === RUN TestCheckSizeOnly run.go:185: Remote "Encrypted drive 'TestCryptDrive:rclone-test-fulajol8jiga'", Local "Local file system at /tmp/rclone1201838359", Modify Window "1ms" 2026/01/29 05:37:01 DEBUG : rutabaga: md5 = 4ede5e5eb31876683404a624b732f306 OK === RUN TestCheckSizeOnly/1 === RUN TestCheckSizeOnly/2 2026/01/29 05:37:04 DEBUG : empty space: md5 = 6e41293fe0713ff910fe1d556919aec2 OK === RUN TestCheckSizeOnly/3 2026/01/29 05:37:07 DEBUG : potato2: md5 = b288d98b8e7dc6a218c9ca01ca630319 OK === RUN TestCheckSizeOnly/4 === RUN TestCheckSizeOnly/5 2026/01/29 05:37:10 DEBUG : remotepotato: md5 = 7393ca7e48c4b8cdebc447e928a31a3f OK === RUN TestCheckSizeOnly/6 === RUN TestCheckSizeOnly/7 --- PASS: TestCheckSizeOnly (14.70s) --- PASS: TestCheckSizeOnly/1 (0.37s) --- PASS: TestCheckSizeOnly/2 (0.36s) --- PASS: TestCheckSizeOnly/3 (0.37s) --- PASS: TestCheckSizeOnly/4 (0.39s) --- PASS: TestCheckSizeOnly/5 (0.37s) --- PASS: TestCheckSizeOnly/6 (0.31s) --- PASS: TestCheckSizeOnly/7 (0.32s) === RUN TestCheckEqualReaders --- PASS: TestCheckEqualReaders (0.00s) === RUN TestParseSumFile run.go:185: Remote "Encrypted drive 'TestCryptDrive:rclone-test-fulajol8jiga'", Local "Local file system at /tmp/rclone1201838359", Modify Window "1ms" 2026/01/29 05:37:16 DEBUG : test.sum: md5 = 3ce3b6935be4ce0411517fac427dc881 OK 2026/01/29 05:37:17 NOTICE: test.sum: improperly formatted checksum line 4 2026/01/29 05:37:17 NOTICE: test.sum: improperly formatted checksum line 5 2026/01/29 05:37:17 NOTICE: test.sum: improperly formatted checksum line 6 2026/01/29 05:37:17 NOTICE: test.sum: 2 warning(s) suppressed... 2026/01/29 05:37:19 DEBUG : test.sum: md5 = d51f04a044cd4c33fe462b3f6b4ae4ab OK 2026/01/29 05:37:21 NOTICE: test.sum: improperly formatted checksum line 4 2026/01/29 05:37:21 NOTICE: test.sum: improperly formatted checksum line 5 2026/01/29 05:37:21 NOTICE: test.sum: improperly formatted checksum line 6 2026/01/29 05:37:21 NOTICE: test.sum: 2 warning(s) suppressed... --- PASS: TestParseSumFile (8.25s) === RUN TestCheckSum run.go:185: Remote "Encrypted drive 'TestCryptDrive:rclone-test-fulajol8jiga'", Local "Local file system at /tmp/rclone1201838359", Modify Window "1ms" 2026/01/29 05:37:22 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-fulajol8jiga/data" 2026/01/29 05:37:22 DEBUG : Creating backend with remote "TestDrive:crypt/hmt9nrq7jloakp17tgo4ehfhqkkos3f6kh7gg7vbnaj6l4scnjk0/vjrnln8ratgmqakfosrqe8espk" 2026/01/29 05:37:22 DEBUG : Config file has changed externally - reloading check_test.go:350: Encrypted drive 'TestCryptDrive:rclone-test-fulajol8jiga/data' lacks md5, skipping --- SKIP: TestCheckSum (2.58s) === RUN TestCheckSumDownload run.go:185: Remote "Encrypted drive 'TestCryptDrive:rclone-test-fulajol8jiga'", Local "Local file system at /tmp/rclone1201838359", Modify Window "1ms" 2026/01/29 05:37:25 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-fulajol8jiga/data" 2026/01/29 05:37:25 DEBUG : Creating backend with remote "TestDrive:crypt/hmt9nrq7jloakp17tgo4ehfhqkkos3f6kh7gg7vbnaj6l4scnjk0/vjrnln8ratgmqakfosrqe8espk" 2026/01/29 05:37:29 DEBUG : data/banana: md5 = 5d10c57cef12f35aa808a0ebbb62b4b9 OK 2026/01/29 05:37:31 DEBUG : test.sum: md5 = ca3dfbb8f98ab0417cd85c2be01c9788 OK === RUN TestCheckSumDownload/subtest1 2026/01/29 05:37:36 DEBUG : data/potato: md5 = 319374ad855cfb9e5a89cccea40902ce OK 2026/01/29 05:37:38 DEBUG : test.sum: md5 = 052f9cd73adfc21a896dcaf34403c1b7 OK === RUN TestCheckSumDownload/subtest2 2026/01/29 05:37:43 DEBUG : test.sum: md5 = b951b4799a5e41198f7613d806f945d7 OK === RUN TestCheckSumDownload/subtest3 2026/01/29 05:37:47 DEBUG : test.sum: md5 = 05e77e71d8e404a45d843ff95260be2e OK === RUN TestCheckSumDownload/subtest4 2026/01/29 05:37:51 DEBUG : test.sum: md5 = e4f1cc3d17b9c029f4c5d8896b73dc24 OK === RUN TestCheckSumDownload/subtest5 2026/01/29 05:37:56 DEBUG : test.sum: md5 = 5155b43c12c9d4ee46dad57633410783 OK === RUN TestCheckSumDownload/subtest6 2026/01/29 05:38:00 DEBUG : data/banana: md5 = 1b65f5fabed3675497a90d4ae011a645 OK 2026/01/29 05:38:02 DEBUG : data/potato: md5 = eb75ae32045e5ed876d9a77d1fec7315 OK 2026/01/29 05:38:04 DEBUG : test.sum: md5 = 2a676429fa3195218ceac5d855b0b5e7 OK === RUN TestCheckSumDownload/subtest7 2026/01/29 05:38:09 DEBUG : vjrnln8ratgmqakfosrqe8espk: Rmdir: contains trashed file: "igjr4utqjj43i7bn8okrqetjm0" 2026/01/29 05:38:09 DEBUG : vjrnln8ratgmqakfosrqe8espk: Rmdir: contains trashed file: "2m507n64kafsi59v9q71r281a4" --- PASS: TestCheckSumDownload (45.61s) --- PASS: TestCheckSumDownload/subtest1 (2.34s) --- PASS: TestCheckSumDownload/subtest2 (1.86s) --- PASS: TestCheckSumDownload/subtest3 (1.97s) --- PASS: TestCheckSumDownload/subtest4 (1.83s) --- PASS: TestCheckSumDownload/subtest5 (1.82s) --- PASS: TestCheckSumDownload/subtest6 (1.84s) --- PASS: TestCheckSumDownload/subtest7 (2.10s) === RUN TestApplyTransforms 2026/01/29 05:38:10 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-deguhoc6biga" 2026/01/29 05:38:10 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/01/29 05:38:10 DEBUG : Creating backend with remote "TestDrive:crypt/0f4v1elm78k8fgi450f5krhqgpi5ef7trp6u3fcjcfsv126n39ng" 2026/01/29 05:38:11 DEBUG : Creating backend with remote "/tmp/rclone3814770499" run.go:185: Remote "Encrypted drive 'TestCryptDrive:rclone-test-deguhoc6biga'", Local "Local file system at /tmp/rclone3814770499", Modify Window "1ms" 2026/01/29 05:38:14 DEBUG : hello, world!: md5 = 0318fc9cb10b0d07ac6a4b4f94f6f884 OK upper checkfile vs. lower remote (without normalization) 2026/01/29 05:38:15 ERROR : hello, world!: sum not found 2026/01/29 05:38:15 ERROR : HELLO, WORLD!: file not in Encrypted drive 'TestCryptDrive:rclone-test-deguhoc6biga' 2026/01/29 05:38:15 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-deguhoc6biga': 1 files missing 2026/01/29 05:38:15 NOTICE: 1 hashes missing 2026/01/29 05:38:15 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-deguhoc6biga': 1 differences found 2026/01/29 05:38:15 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-deguhoc6biga': 2 errors while checking upper checkfile vs. lower remote (with normalization) 2026/01/29 05:38:16 DEBUG : hello, world!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/01/29 05:38:16 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-deguhoc6biga': 0 differences found 2026/01/29 05:38:16 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-deguhoc6biga': 1 matching files 2026/01/29 05:38:16 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-yoxuhas0ciji" 2026/01/29 05:38:16 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/01/29 05:38:16 DEBUG : Creating backend with remote "TestDrive:crypt/rd3r1invuq4ve2tjrctdnhf6d0n8c6u5ja3df0qh3hhlqhsnnm40" 2026/01/29 05:38:17 DEBUG : Creating backend with remote "/tmp/rclone2333697405" run.go:185: Remote "Encrypted drive 'TestCryptDrive:rclone-test-yoxuhas0ciji'", Local "Local file system at /tmp/rclone2333697405", Modify Window "1ms" 2026/01/29 05:38:20 DEBUG : HELLO, WORLD!: md5 = 45e5a824ad6836b8e4dd7e11875ccddb OK lower checkfile vs. upper remote (without normalization) 2026/01/29 05:38:21 ERROR : HELLO, WORLD!: sum not found 2026/01/29 05:38:21 ERROR : hello, world!: file not in Encrypted drive 'TestCryptDrive:rclone-test-yoxuhas0ciji' 2026/01/29 05:38:21 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-yoxuhas0ciji': 1 files missing 2026/01/29 05:38:21 NOTICE: 1 hashes missing 2026/01/29 05:38:21 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-yoxuhas0ciji': 1 differences found 2026/01/29 05:38:21 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-yoxuhas0ciji': 2 errors while checking lower checkfile vs. upper remote (with normalization) 2026/01/29 05:38:22 DEBUG : HELLO, WORLD!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/01/29 05:38:22 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-yoxuhas0ciji': 0 differences found 2026/01/29 05:38:22 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-yoxuhas0ciji': 1 matching files 2026/01/29 05:38:22 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-lasuqas0picu" 2026/01/29 05:38:22 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/01/29 05:38:22 DEBUG : Creating backend with remote "TestDrive:crypt/7hod3i47e9lsn0ekoeo2ipvjqiqt2nfkakremce85dc73et2bl70" 2026/01/29 05:38:23 DEBUG : Creating backend with remote "/tmp/rclone1597210446" run.go:185: Remote "Encrypted drive 'TestCryptDrive:rclone-test-lasuqas0picu'", Local "Local file system at /tmp/rclone1597210446", Modify Window "1ms" 2026/01/29 05:38:26 DEBUG : HeLlO, wOrLd!: md5 = 6fbc238134edcb8ae3fbe9fcf909c32a OK lower checkfile vs. upperlowermixed remote (without normalization) 2026/01/29 05:38:26 ERROR : HeLlO, wOrLd!: sum not found 2026/01/29 05:38:26 ERROR : hello, world!: file not in Encrypted drive 'TestCryptDrive:rclone-test-lasuqas0picu' 2026/01/29 05:38:26 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-lasuqas0picu': 1 files missing 2026/01/29 05:38:26 NOTICE: 1 hashes missing 2026/01/29 05:38:26 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-lasuqas0picu': 1 differences found 2026/01/29 05:38:26 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-lasuqas0picu': 2 errors while checking lower checkfile vs. upperlowermixed remote (with normalization) 2026/01/29 05:38:27 DEBUG : HeLlO, wOrLd!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/01/29 05:38:27 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-lasuqas0picu': 0 differences found 2026/01/29 05:38:27 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-lasuqas0picu': 1 matching files 2026/01/29 05:38:27 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-deleveb1giqi" 2026/01/29 05:38:27 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/01/29 05:38:27 DEBUG : Creating backend with remote "TestDrive:crypt/nijlbdjfcb44gjr6m98icf6ciecd9rkou9g67937rmikpp4db0tg" 2026/01/29 05:38:29 DEBUG : Creating backend with remote "/tmp/rclone4259811594" run.go:185: Remote "Encrypted drive 'TestCryptDrive:rclone-test-deleveb1giqi'", Local "Local file system at /tmp/rclone4259811594", Modify Window "1ms" 2026/01/29 05:38:32 DEBUG : HELLO, WORLD!: md5 = 6c4a0d805a842637a7c5f5faa0f1ff1b OK upperlowermixed checkfile vs. upper remote (without normalization) 2026/01/29 05:38:32 ERROR : HELLO, WORLD!: sum not found 2026/01/29 05:38:32 ERROR : HeLlO, wOrLd!: file not in Encrypted drive 'TestCryptDrive:rclone-test-deleveb1giqi' 2026/01/29 05:38:32 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-deleveb1giqi': 1 files missing 2026/01/29 05:38:32 NOTICE: 1 hashes missing 2026/01/29 05:38:32 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-deleveb1giqi': 1 differences found 2026/01/29 05:38:32 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-deleveb1giqi': 2 errors while checking upperlowermixed checkfile vs. upper remote (with normalization) 2026/01/29 05:38:33 DEBUG : HELLO, WORLD!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/01/29 05:38:33 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-deleveb1giqi': 0 differences found 2026/01/29 05:38:33 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-deleveb1giqi': 1 matching files 2026/01/29 05:38:33 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-nelunob3tega" 2026/01/29 05:38:33 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/01/29 05:38:33 DEBUG : Creating backend with remote "TestDrive:crypt/jlkgvakgoqtcmq1c9lp02qtf43hac2fuccndvna3vc3hms953fl0" 2026/01/29 05:38:35 DEBUG : Creating backend with remote "/tmp/rclone2684929180" run.go:185: Remote "Encrypted drive 'TestCryptDrive:rclone-test-nelunob3tega'", Local "Local file system at /tmp/rclone2684929180", Modify Window "1ms" 2026/01/29 05:38:37 DEBUG : 測試_Русский___ě_áñ: md5 = c42a35c88c860b4d8b7f2de85a6dc4a0 OK NFD checkfile vs. NFC remote (without normalization) 2026/01/29 05:38:38 ERROR : 測試_Русский___ě_áñ: sum not found 2026/01/29 05:38:38 ERROR : 測試_Русский___ě_áñ: file not in Encrypted drive 'TestCryptDrive:rclone-test-nelunob3tega' 2026/01/29 05:38:38 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-nelunob3tega': 1 files missing 2026/01/29 05:38:38 NOTICE: 1 hashes missing 2026/01/29 05:38:38 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-nelunob3tega': 1 differences found 2026/01/29 05:38:38 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-nelunob3tega': 2 errors while checking NFD checkfile vs. NFC remote (with normalization) 2026/01/29 05:38:39 DEBUG : 測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/01/29 05:38:39 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-nelunob3tega': 0 differences found 2026/01/29 05:38:39 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-nelunob3tega': 1 matching files 2026/01/29 05:38:39 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-tugalic1hifi" 2026/01/29 05:38:39 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/01/29 05:38:39 DEBUG : Creating backend with remote "TestDrive:crypt/5iqldlmot0iu95j47ra0ank3u122sovc110m79dsoboep2dgcbdg" 2026/01/29 05:38:40 DEBUG : Creating backend with remote "/tmp/rclone3586723734" run.go:185: Remote "Encrypted drive 'TestCryptDrive:rclone-test-tugalic1hifi'", Local "Local file system at /tmp/rclone3586723734", Modify Window "1ms" 2026/01/29 05:38:43 DEBUG : 測試_Русский___ě_áñ: md5 = 914a3efc57ec7291f8c1fde6259ef59d OK NFC checkfile vs. NFD remote (without normalization) 2026/01/29 05:38:43 ERROR : 測試_Русский___ě_áñ: sum not found 2026/01/29 05:38:43 ERROR : 測試_Русский___ě_áñ: file not in Encrypted drive 'TestCryptDrive:rclone-test-tugalic1hifi' 2026/01/29 05:38:43 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-tugalic1hifi': 1 files missing 2026/01/29 05:38:43 NOTICE: 1 hashes missing 2026/01/29 05:38:43 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-tugalic1hifi': 1 differences found 2026/01/29 05:38:43 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-tugalic1hifi': 2 errors while checking NFC checkfile vs. NFD remote (with normalization) 2026/01/29 05:38:44 DEBUG : 測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/01/29 05:38:44 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-tugalic1hifi': 0 differences found 2026/01/29 05:38:44 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-tugalic1hifi': 1 matching files 2026/01/29 05:38:44 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-seloxay1nequ" 2026/01/29 05:38:44 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/01/29 05:38:45 DEBUG : Creating backend with remote "TestDrive:crypt/ll33hp8c30th2fujhlhbjfm25b8goi6v60mekdr4artseqvrk3sg" 2026/01/29 05:38:46 DEBUG : Creating backend with remote "/tmp/rclone1265530139" run.go:185: Remote "Encrypted drive 'TestCryptDrive:rclone-test-seloxay1nequ'", Local "Local file system at /tmp/rclone1265530139", Modify Window "1ms" 2026/01/29 05:38:48 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = a9e3028e2f252e2e17cb7700accafe3b OK NFDx2 checkfile vs. both remote (without normalization) 2026/01/29 05:38:49 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found 2026/01/29 05:38:49 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in Encrypted drive 'TestCryptDrive:rclone-test-seloxay1nequ' 2026/01/29 05:38:49 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-seloxay1nequ': 1 files missing 2026/01/29 05:38:49 NOTICE: 1 hashes missing 2026/01/29 05:38:49 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-seloxay1nequ': 1 differences found 2026/01/29 05:38:49 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-seloxay1nequ': 2 errors while checking NFDx2 checkfile vs. both remote (with normalization) 2026/01/29 05:38:50 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/01/29 05:38:50 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-seloxay1nequ': 0 differences found 2026/01/29 05:38:50 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-seloxay1nequ': 1 matching files 2026/01/29 05:38:50 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-munusus2pemo" 2026/01/29 05:38:50 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/01/29 05:38:50 DEBUG : Creating backend with remote "TestDrive:crypt/mkfgad334a1h8s806jh7aavj3qfvpoqk19mcthucob2h0ub27d4g" 2026/01/29 05:38:51 DEBUG : Creating backend with remote "/tmp/rclone3285431781" run.go:185: Remote "Encrypted drive 'TestCryptDrive:rclone-test-munusus2pemo'", Local "Local file system at /tmp/rclone3285431781", Modify Window "1ms" 2026/01/29 05:38:54 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 5c1ea8ce9fc2c0805e074d9dc066b676 OK NFCx2 checkfile vs. both remote (without normalization) 2026/01/29 05:38:55 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found 2026/01/29 05:38:55 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in Encrypted drive 'TestCryptDrive:rclone-test-munusus2pemo' 2026/01/29 05:38:55 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-munusus2pemo': 1 files missing 2026/01/29 05:38:55 NOTICE: 1 hashes missing 2026/01/29 05:38:55 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-munusus2pemo': 1 differences found 2026/01/29 05:38:55 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-munusus2pemo': 2 errors while checking NFCx2 checkfile vs. both remote (with normalization) 2026/01/29 05:38:56 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/01/29 05:38:56 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-munusus2pemo': 0 differences found 2026/01/29 05:38:56 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-munusus2pemo': 1 matching files 2026/01/29 05:38:56 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-xupixut6xilu" 2026/01/29 05:38:56 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/01/29 05:38:56 DEBUG : Creating backend with remote "TestDrive:crypt/eg892eef98jiovkfe71aetpbupe7i24o814sklvkqdhtf31oi19g" 2026/01/29 05:38:57 DEBUG : Creating backend with remote "/tmp/rclone2063572136" run.go:185: Remote "Encrypted drive 'TestCryptDrive:rclone-test-xupixut6xilu'", Local "Local file system at /tmp/rclone2063572136", Modify Window "1ms" 2026/01/29 05:39:00 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = dbdf4410d48f724d1ed27c52271efabc OK both checkfile vs. NFDx2 remote (without normalization) 2026/01/29 05:39:00 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found 2026/01/29 05:39:00 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in Encrypted drive 'TestCryptDrive:rclone-test-xupixut6xilu' 2026/01/29 05:39:00 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-xupixut6xilu': 1 files missing 2026/01/29 05:39:00 NOTICE: 1 hashes missing 2026/01/29 05:39:00 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-xupixut6xilu': 1 differences found 2026/01/29 05:39:00 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-xupixut6xilu': 2 errors while checking both checkfile vs. NFDx2 remote (with normalization) 2026/01/29 05:39:01 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/01/29 05:39:01 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-xupixut6xilu': 0 differences found 2026/01/29 05:39:01 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-xupixut6xilu': 1 matching files 2026/01/29 05:39:01 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-saqezac1mezo" 2026/01/29 05:39:01 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/01/29 05:39:01 DEBUG : Creating backend with remote "TestDrive:crypt/iv2kiei1vsocos441701siaa5npal8f2onvbvpcpng8u944448i0" 2026/01/29 05:39:03 DEBUG : Creating backend with remote "/tmp/rclone1386762195" run.go:185: Remote "Encrypted drive 'TestCryptDrive:rclone-test-saqezac1mezo'", Local "Local file system at /tmp/rclone1386762195", Modify Window "1ms" 2026/01/29 05:39:05 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 216e4b7e321144787e845b701c81a3c6 OK both checkfile vs. NFCx2 remote (without normalization) 2026/01/29 05:39:06 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found 2026/01/29 05:39:06 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in Encrypted drive 'TestCryptDrive:rclone-test-saqezac1mezo' 2026/01/29 05:39:06 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-saqezac1mezo': 1 files missing 2026/01/29 05:39:06 NOTICE: 1 hashes missing 2026/01/29 05:39:06 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-saqezac1mezo': 1 differences found 2026/01/29 05:39:06 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-saqezac1mezo': 2 errors while checking both checkfile vs. NFCx2 remote (with normalization) 2026/01/29 05:39:07 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/01/29 05:39:07 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-saqezac1mezo': 0 differences found 2026/01/29 05:39:07 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-saqezac1mezo': 1 matching files 2026/01/29 05:39:07 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-saqezac1mezo': Purge remote 2026/01/29 05:39:08 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-xupixut6xilu': Purge remote 2026/01/29 05:39:08 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-munusus2pemo': Purge remote 2026/01/29 05:39:09 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-seloxay1nequ': Purge remote 2026/01/29 05:39:09 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-tugalic1hifi': Purge remote 2026/01/29 05:39:10 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-nelunob3tega': Purge remote 2026/01/29 05:39:11 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-deleveb1giqi': Purge remote 2026/01/29 05:39:11 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-lasuqas0picu': Purge remote 2026/01/29 05:39:12 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-yoxuhas0ciji': Purge remote 2026/01/29 05:39:13 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-deguhoc6biga': Purge remote --- PASS: TestApplyTransforms (62.86s) === RUN TestTruncateString --- PASS: TestTruncateString (0.00s) === RUN TestCopyFile run.go:185: Remote "Encrypted drive 'TestCryptDrive:rclone-test-fulajol8jiga'", Local "Local file system at /tmp/rclone1201838359", Modify Window "1ms" 2026/01/29 05:39:13 DEBUG : file1: Need to transfer - File not found at Destination 2026/01/29 05:39:16 DEBUG : sub/file2: md5 = 1748bbd8d531d70a2efc35a1ee4dc264 OK 2026/01/29 05:39:16 DEBUG : sub/file2: size = 14 OK 2026/01/29 05:39:16 INFO : file1: Copied (new) to: sub/file2 2026/01/29 05:39:17 DEBUG : sub/file2: size = 14 OK 2026/01/29 05:39:17 DEBUG : file1: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2026/01/29 05:39:17 DEBUG : file1: Unchanged skipping 2026/01/29 05:39:18 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-fulajol8jiga': don't need to copy/move sub/file2, it is already at target location 2026/01/29 05:39:20 DEBUG : 150fuo3cn4j1uenq6r4g4qk6t4: Rmdir: contains trashed file: "d26uovf0miqhd1mu3c9jbseq00" --- PASS: TestCopyFile (7.56s) === RUN TestCopyLongFile run.go:185: Remote "Encrypted drive 'TestCryptDrive:rclone-test-fulajol8jiga'", Local "Local file system at /tmp/rclone1201838359", Modify Window "1ms" copy_test.go:154: Test only runs on local --- SKIP: TestCopyLongFile (0.51s) === RUN TestCopyFileBackupDir run.go:185: Remote "Encrypted drive 'TestCryptDrive:rclone-test-fulajol8jiga'", Local "Local file system at /tmp/rclone1201838359", Modify Window "1ms" 2026/01/29 05:39:24 DEBUG : dst/file1: md5 = 2a2e5d4990bb06aed5b4663cc05fa120 OK 2026/01/29 05:39:25 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-fulajol8jiga/backup" 2026/01/29 05:39:25 DEBUG : Creating backend with remote "TestDrive:crypt/hmt9nrq7jloakp17tgo4ehfhqkkos3f6kh7gg7vbnaj6l4scnjk0/1nrff024r7pq65ecp72fc28jb0" 2026/01/29 05:39:26 DEBUG : dst/file1: size = 14 (Local file system at /tmp/rclone1201838359) 2026/01/29 05:39:26 DEBUG : dst/file1: size = 18 (Encrypted drive 'TestCryptDrive:rclone-test-fulajol8jiga') 2026/01/29 05:39:26 DEBUG : dst/file1: Sizes differ 2026/01/29 05:39:29 INFO : dst/file1: Moved (server-side) 2026/01/29 05:39:31 DEBUG : dst/file1: md5 = c27c911a53adc16a0d68ca484a1f6205 OK 2026/01/29 05:39:31 DEBUG : dst/file1: size = 14 OK 2026/01/29 05:39:31 INFO : dst/file1: Copied (new) 2026/01/29 05:39:35 DEBUG : 31u3jie661vd5p8j7rtc3hgbh0: Rmdir: contains trashed file: "eer8kka55qnghc34cq76ca668g" 2026/01/29 05:39:36 DEBUG : 1nrff024r7pq65ecp72fc28jb0/31u3jie661vd5p8j7rtc3hgbh0: Rmdir: contains trashed file: "eer8kka55qnghc34cq76ca668g" 2026/01/29 05:39:37 DEBUG : 1nrff024r7pq65ecp72fc28jb0: Rmdir: contains trashed file: "31u3jie661vd5p8j7rtc3hgbh0" --- PASS: TestCopyFileBackupDir (16.34s) === RUN TestCopyFileCompareDest run.go:185: Remote "Encrypted drive 'TestCryptDrive:rclone-test-fulajol8jiga'", Local "Local file system at /tmp/rclone1201838359", Modify Window "1ms" 2026/01/29 05:39:38 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-fulajol8jiga/dst" 2026/01/29 05:39:38 DEBUG : Creating backend with remote "TestDrive:crypt/hmt9nrq7jloakp17tgo4ehfhqkkos3f6kh7gg7vbnaj6l4scnjk0/31u3jie661vd5p8j7rtc3hgbh0" 2026/01/29 05:39:40 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-fulajol8jiga/CompareDest" 2026/01/29 05:39:40 DEBUG : Creating backend with remote "TestDrive:crypt/hmt9nrq7jloakp17tgo4ehfhqkkos3f6kh7gg7vbnaj6l4scnjk0/gveqi14airsml4bgu7krj116o8" 2026/01/29 05:39:41 DEBUG : one: Need to transfer - File not found at Destination 2026/01/29 05:39:44 DEBUG : one: md5 = a27263e0a5c5b4277465a5eb706707aa OK 2026/01/29 05:39:44 DEBUG : one: size = 3 OK 2026/01/29 05:39:44 INFO : one: Copied (new) 2026/01/29 05:39:46 DEBUG : one: size = 5 (Local file system at /tmp/rclone1201838359) 2026/01/29 05:39:46 DEBUG : one: size = 3 (Encrypted drive 'TestCryptDrive:rclone-test-fulajol8jiga/dst') 2026/01/29 05:39:46 DEBUG : one: Sizes differ 2026/01/29 05:39:48 DEBUG : one: md5 = b509d6093cc8bdbf9dae0bf5367962cc OK 2026/01/29 05:39:48 DEBUG : one: size = 5 OK 2026/01/29 05:39:48 INFO : one: Copied (replaced existing) 2026/01/29 05:39:50 DEBUG : dst/one: md5 = 7c076e06645275bc3045de625aaf5c4f OK 2026/01/29 05:39:53 DEBUG : CompareDest/one: md5 = d76d666796f8fe33d45b55986ed495e2 OK 2026/01/29 05:39:54 DEBUG : one: size = 5 (Local file system at /tmp/rclone1201838359) 2026/01/29 05:39:54 DEBUG : one: size = 3 (Encrypted drive 'TestCryptDrive:rclone-test-fulajol8jiga/dst') 2026/01/29 05:39:54 DEBUG : one: Sizes differ 2026/01/29 05:39:54 DEBUG : one: size = 5 OK 2026/01/29 05:39:54 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2026/01/29 05:39:54 DEBUG : one: Destination found in --compare-dest, skipping 2026/01/29 05:39:56 DEBUG : CompareDest/two: md5 = 38231a5d94e0fa64978dfe55a8534ad5 OK 2026/01/29 05:39:57 DEBUG : two: Need to transfer - File not found at Destination 2026/01/29 05:39:58 DEBUG : two: size = 3 OK 2026/01/29 05:39:58 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2026/01/29 05:39:58 DEBUG : two: Destination found in --compare-dest, skipping 2026/01/29 05:39:59 DEBUG : two: Need to transfer - File not found at Destination 2026/01/29 05:39:59 DEBUG : two: size = 3 OK 2026/01/29 05:39:59 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2026/01/29 05:39:59 DEBUG : two: Destination found in --compare-dest, skipping 2026/01/29 05:40:00 DEBUG : two: Need to transfer - File not found at Destination 2026/01/29 05:40:01 DEBUG : two: size = 5 (Local file system at /tmp/rclone1201838359) 2026/01/29 05:40:01 DEBUG : two: size = 3 (Encrypted drive 'TestCryptDrive:rclone-test-fulajol8jiga/CompareDest') 2026/01/29 05:40:01 DEBUG : two: Sizes differ 2026/01/29 05:40:02 DEBUG : two: md5 = 0889e738f9647172d358884bc3e8bc2a OK 2026/01/29 05:40:02 DEBUG : two: size = 5 OK 2026/01/29 05:40:02 INFO : two: Copied (new) 2026/01/29 05:40:06 DEBUG : 31u3jie661vd5p8j7rtc3hgbh0: Rmdir: contains trashed file: "keafetfvtbmgo6tsdvcbj32i0k" 2026/01/29 05:40:06 DEBUG : 31u3jie661vd5p8j7rtc3hgbh0: Rmdir: contains trashed file: "0828u6q2p8d62qeklrkkuf10uc" 2026/01/29 05:40:07 DEBUG : gveqi14airsml4bgu7krj116o8: Rmdir: contains trashed file: "keafetfvtbmgo6tsdvcbj32i0k" 2026/01/29 05:40:07 DEBUG : gveqi14airsml4bgu7krj116o8: Rmdir: contains trashed file: "0828u6q2p8d62qeklrkkuf10uc" --- PASS: TestCopyFileCompareDest (30.55s) === RUN TestCopyFileCopyDest run.go:185: Remote "Encrypted drive 'TestCryptDrive:rclone-test-fulajol8jiga'", Local "Local file system at /tmp/rclone1201838359", Modify Window "1ms" 2026/01/29 05:40:08 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-fulajol8jiga/dst" 2026/01/29 05:40:08 DEBUG : Creating backend with remote "TestDrive:crypt/hmt9nrq7jloakp17tgo4ehfhqkkos3f6kh7gg7vbnaj6l4scnjk0/31u3jie661vd5p8j7rtc3hgbh0" 2026/01/29 05:40:10 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-fulajol8jiga/CopyDest" 2026/01/29 05:40:10 DEBUG : Creating backend with remote "TestDrive:crypt/hmt9nrq7jloakp17tgo4ehfhqkkos3f6kh7gg7vbnaj6l4scnjk0/d09o6po3f7bm6ce32vdgs8h9ls" 2026/01/29 05:40:12 DEBUG : one: Need to transfer - File not found at Destination 2026/01/29 05:40:15 DEBUG : one: md5 = a2b6c13b4fff9f7ca3cf014d4cfda91e OK 2026/01/29 05:40:15 DEBUG : one: size = 3 OK 2026/01/29 05:40:15 INFO : one: Copied (new) 2026/01/29 05:40:17 DEBUG : one: size = 5 (Local file system at /tmp/rclone1201838359) 2026/01/29 05:40:17 DEBUG : one: size = 3 (Encrypted drive 'TestCryptDrive:rclone-test-fulajol8jiga/dst') 2026/01/29 05:40:17 DEBUG : one: Sizes differ 2026/01/29 05:40:21 DEBUG : one: md5 = 51ea13e680c68065ca0c76c3d6c83c91 OK 2026/01/29 05:40:21 DEBUG : one: size = 5 OK 2026/01/29 05:40:21 INFO : one: Copied (replaced existing) 2026/01/29 05:40:23 DEBUG : dst/one: md5 = 915632c1f7e0835737fb065893459ac6 OK 2026/01/29 05:40:26 DEBUG : CopyDest/one: md5 = 50fd768fe1c518509046094d0f58a618 OK 2026/01/29 05:40:27 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-fulajol8jiga/BackupDir" 2026/01/29 05:40:27 DEBUG : Creating backend with remote "TestDrive:crypt/hmt9nrq7jloakp17tgo4ehfhqkkos3f6kh7gg7vbnaj6l4scnjk0/s6dbk3lfi7c9kfvo6j7bla9m0g" 2026/01/29 05:40:30 DEBUG : one: size = 5 (Local file system at /tmp/rclone1201838359) 2026/01/29 05:40:30 DEBUG : one: size = 3 (Encrypted drive 'TestCryptDrive:rclone-test-fulajol8jiga/dst') 2026/01/29 05:40:30 DEBUG : one: Sizes differ 2026/01/29 05:40:31 DEBUG : one: size = 5 OK 2026/01/29 05:40:31 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2026/01/29 05:40:31 DEBUG : one: size = 5 (Local file system at /tmp/rclone1201838359) 2026/01/29 05:40:31 DEBUG : one: size = 3 (Encrypted drive 'TestCryptDrive:rclone-test-fulajol8jiga/dst') 2026/01/29 05:40:31 DEBUG : one: Sizes differ 2026/01/29 05:40:33 INFO : one: Moved (server-side) 2026/01/29 05:40:35 DEBUG : one: size = 5 OK 2026/01/29 05:40:35 INFO : one: Copied (server-side copy) 2026/01/29 05:40:36 DEBUG : one: Destination found in --copy-dest, using server-side copy 2026/01/29 05:46:55 DEBUG : CopyDest/two: md5 = 52c0ee787ad2866a42077e6c54fa5123 OK 2026/01/29 06:14:09 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-fulajol8jiga/CopyDest" 2026/01/29 06:14:09 DEBUG : Config file has changed externally - reloading 2026/01/29 06:14:10 DEBUG : two: Need to transfer - File not found at Destination 2026/01/29 06:14:11 DEBUG : two: size = 3 OK 2026/01/29 06:14:11 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2026/01/29 06:14:12 DEBUG : two: size = 3 OK 2026/01/29 06:14:12 INFO : two: Copied (server-side copy) 2026/01/29 06:14:12 DEBUG : two: Destination found in --copy-dest, using server-side copy 2026/01/29 06:14:14 DEBUG : two: size = 3 OK 2026/01/29 06:14:14 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2026/01/29 06:14:14 DEBUG : two: Unchanged skipping 2026/01/29 06:14:18 DEBUG : CopyDest/three: md5 = f1a56ee12963f998696db7b2cf382b5f OK 2026/01/29 06:14:21 DEBUG : three: Need to transfer - File not found at Destination 2026/01/29 06:14:21 DEBUG : three: size = 7 (Local file system at /tmp/rclone1201838359) 2026/01/29 06:14:21 DEBUG : three: size = 5 (Encrypted drive 'TestCryptDrive:rclone-test-fulajol8jiga/CopyDest') 2026/01/29 06:14:21 DEBUG : three: Sizes differ 2026/01/29 06:14:21 DEBUG : three: Destination not found in --copy-dest 2026/01/29 06:14:23 DEBUG : three: md5 = 291a4ccfbb7183793e99739ef45de1e1 OK 2026/01/29 06:14:23 DEBUG : three: size = 7 OK 2026/01/29 06:14:23 INFO : three: Copied (new) 2026/01/29 06:22:27 DEBUG : pacer: low level retry 1/10 (error Patch "https://www.googleapis.com/drive/v3/files/1mF3S4PwkdLlzs1CjAENFYS-KrSsidjWs?alt=json&fields=&prettyPrint=false&supportsAllDrives=true": EOF) 2026/01/29 06:22:27 DEBUG : pacer: Rate limited, increasing sleep to 1.505976288s 2026/01/29 06:22:27 DEBUG : TestDrive: Token expired 2026/01/29 06:22:27 DEBUG : Config file has changed externally - reloading 2026/01/29 06:22:27 DEBUG : TestDrive: No updated token found in the config file 2026/01/29 06:22:27 DEBUG : TestDrive: Token refresh successful 2026/01/29 06:22:27 DEBUG : Config file has changed externally - reloading 2026/01/29 06:22:27 DEBUG : Saving config "token" in section "TestDrive" of the config file 2026/01/29 06:22:27 DEBUG : TestDrive: Saved new token in config file 2026/01/29 06:22:28 DEBUG : pacer: Reducing sleep to 0s 2026/01/29 06:22:31 DEBUG : 31u3jie661vd5p8j7rtc3hgbh0: Rmdir: contains trashed file: "b6jdmn2b1adrhc8vikh7edr9os" 2026/01/29 06:22:31 DEBUG : 31u3jie661vd5p8j7rtc3hgbh0: Rmdir: contains trashed file: "keafetfvtbmgo6tsdvcbj32i0k" 2026/01/29 06:22:31 DEBUG : 31u3jie661vd5p8j7rtc3hgbh0: Rmdir: contains trashed file: "0828u6q2p8d62qeklrkkuf10uc" 2026/01/29 06:22:32 DEBUG : d09o6po3f7bm6ce32vdgs8h9ls: Rmdir: contains trashed file: "0828u6q2p8d62qeklrkkuf10uc" 2026/01/29 06:22:32 DEBUG : d09o6po3f7bm6ce32vdgs8h9ls: Rmdir: contains trashed file: "keafetfvtbmgo6tsdvcbj32i0k" 2026/01/29 06:22:32 DEBUG : d09o6po3f7bm6ce32vdgs8h9ls: Rmdir: contains trashed file: "b6jdmn2b1adrhc8vikh7edr9os" 2026/01/29 06:22:34 DEBUG : s6dbk3lfi7c9kfvo6j7bla9m0g: Rmdir: contains trashed file: "0828u6q2p8d62qeklrkkuf10uc" --- PASS: TestCopyFileCopyDest (2546.44s) === RUN TestCopyInplace run.go:185: Remote "Encrypted drive 'TestCryptDrive:rclone-test-fulajol8jiga'", Local "Local file system at /tmp/rclone1201838359", Modify Window "1ms" copy_test.go:371: Partial uploads not supported --- SKIP: TestCopyInplace (0.57s) === RUN TestCopyLongFileName run.go:185: Remote "Encrypted drive 'TestCryptDrive:rclone-test-fulajol8jiga'", Local "Local file system at /tmp/rclone1201838359", Modify Window "1ms" copy_test.go:404: Partial uploads not supported --- SKIP: TestCopyLongFileName (0.58s) === RUN TestCopyLongFileNameCollision run.go:185: Remote "Encrypted drive 'TestCryptDrive:rclone-test-fulajol8jiga'", Local "Local file system at /tmp/rclone1201838359", Modify Window "1ms" copy_test.go:437: Partial uploads not supported --- SKIP: TestCopyLongFileNameCollision (0.52s) === RUN TestCopyFileMaxTransfer run.go:185: Remote "Encrypted drive 'TestCryptDrive:rclone-test-fulajol8jiga'", Local "Local file system at /tmp/rclone1201838359", Modify Window "1ms" 2026/01/29 06:22:36 DEBUG : TestCopyFileMaxTransfer/file1: Need to transfer - File not found at Destination 2026/01/29 06:22:39 DEBUG : TestCopyFileMaxTransfer/file1: md5 = 9ea4470e66e035b72250289c0a1391cd OK 2026/01/29 06:22:39 DEBUG : TestCopyFileMaxTransfer/file1: size = 14 OK 2026/01/29 06:22:39 INFO : TestCopyFileMaxTransfer/file1: Copied (new) 2026/01/29 06:22:40 DEBUG : TestCopyFileMaxTransfer/file2: Need to transfer - File not found at Destination 2026/01/29 06:22:41 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/01/29 06:22:42 DEBUG : TestCopyFileMaxTransfer/file3: Need to transfer - File not found at Destination 2026/01/29 06:22:43 DEBUG : TestCopyFileMaxTransfer/file4: Need to transfer - File not found at Destination 2026/01/29 06:22:44 DEBUG : TestCopyFileMaxTransfer/file4: md5 = ebd056df6cb7bc43470fcd3aa281a670 OK 2026/01/29 06:22:44 DEBUG : TestCopyFileMaxTransfer/file4: size = 2062 OK 2026/01/29 06:22:44 INFO : TestCopyFileMaxTransfer/file4: Copied (new) 2026/01/29 06:22:47 DEBUG : bl9cnu966tarhhpg22ft7c4qm1863k22qdncucii8kn8c3k4aut0: Rmdir: contains trashed file: "amq3bkuqh8mg4ohnbs2i3185uk" 2026/01/29 06:22:47 DEBUG : bl9cnu966tarhhpg22ft7c4qm1863k22qdncucii8kn8c3k4aut0: Rmdir: contains trashed file: "eer8kka55qnghc34cq76ca668g" --- PASS: TestCopyFileMaxTransfer (12.21s) === RUN TestDeduplicateInteractive run.go:185: Remote "Encrypted drive 'TestCryptDrive:rclone-test-fulajol8jiga'", Local "Local file system at /tmp/rclone1201838359", Modify Window "1ms" dedupe_test.go:36: Can't run this test without a hash --- SKIP: TestDeduplicateInteractive (0.50s) === RUN TestDeduplicateSkip run.go:185: Remote "Encrypted drive 'TestCryptDrive:rclone-test-fulajol8jiga'", Local "Local file system at /tmp/rclone1201838359", Modify Window "1ms" 2026/01/29 06:22:52 INFO : Encrypted drive 'TestCryptDrive:rclone-test-fulajol8jiga': Looking for duplicate names using skip mode. 2026/01/29 06:22:53 NOTICE: one: Found 2 files with duplicate names 2026/01/29 06:22:53 NOTICE: one: Skipping 2 files with duplicate names --- PASS: TestDeduplicateSkip (6.16s) === RUN TestDeduplicateSizeOnly run.go:185: Remote "Encrypted drive 'TestCryptDrive:rclone-test-fulajol8jiga'", Local "Local file system at /tmp/rclone1201838359", Modify Window "1ms" 2026/01/29 06:23:00 INFO : Encrypted drive 'TestCryptDrive:rclone-test-fulajol8jiga': Looking for duplicate names using skip mode. 2026/01/29 06:23:01 NOTICE: one: Found 3 files with duplicate names 2026/01/29 06:23:01 NOTICE: one: Deleting 1/2 identical duplicates (size 11) 2026/01/29 06:23:02 INFO : one: Deleted 2026/01/29 06:23:02 NOTICE: one: Skipping 2 files with duplicate names --- PASS: TestDeduplicateSizeOnly (8.63s) === RUN TestDeduplicateFirst run.go:185: Remote "Encrypted drive 'TestCryptDrive:rclone-test-fulajol8jiga'", Local "Local file system at /tmp/rclone1201838359", Modify Window "1ms" 2026/01/29 06:23:08 INFO : Encrypted drive 'TestCryptDrive:rclone-test-fulajol8jiga': Looking for duplicate names using first mode. 2026/01/29 06:23:09 NOTICE: one: Found 3 files with duplicate names 2026/01/29 06:23:10 INFO : one: Deleted 2026/01/29 06:23:10 INFO : one: Deleted 2026/01/29 06:23:10 NOTICE: one: Deleted 2 extra copies --- PASS: TestDeduplicateFirst (8.31s) === RUN TestDeduplicateNewest run.go:185: Remote "Encrypted drive 'TestCryptDrive:rclone-test-fulajol8jiga'", Local "Local file system at /tmp/rclone1201838359", Modify Window "1ms" 2026/01/29 06:23:17 INFO : Encrypted drive 'TestCryptDrive:rclone-test-fulajol8jiga': Looking for duplicate names using newest mode. 2026/01/29 06:23:18 NOTICE: one: Found 3 files with duplicate names 2026/01/29 06:23:18 INFO : one: Deleted 2026/01/29 06:23:19 INFO : one: Deleted 2026/01/29 06:23:19 NOTICE: one: Deleted 2 extra copies --- PASS: TestDeduplicateNewest (8.39s) === RUN TestDeduplicateNewestByHash run.go:185: Remote "Encrypted drive 'TestCryptDrive:rclone-test-fulajol8jiga'", Local "Local file system at /tmp/rclone1201838359", Modify Window "1ms" dedupe_test.go:36: Can't run this test without a hash --- SKIP: TestDeduplicateNewestByHash (0.52s) === RUN TestDeduplicateOldest run.go:185: Remote "Encrypted drive 'TestCryptDrive:rclone-test-fulajol8jiga'", Local "Local file system at /tmp/rclone1201838359", Modify Window "1ms" 2026/01/29 06:23:26 INFO : Encrypted drive 'TestCryptDrive:rclone-test-fulajol8jiga': Looking for duplicate names using oldest mode. 2026/01/29 06:23:26 NOTICE: one: Found 3 files with duplicate names 2026/01/29 06:23:27 INFO : one: Deleted 2026/01/29 06:23:28 INFO : one: Deleted 2026/01/29 06:23:28 NOTICE: one: Deleted 2 extra copies --- PASS: TestDeduplicateOldest (8.17s) === RUN TestDeduplicateLargest run.go:185: Remote "Encrypted drive 'TestCryptDrive:rclone-test-fulajol8jiga'", Local "Local file system at /tmp/rclone1201838359", Modify Window "1ms" 2026/01/29 06:23:34 INFO : Encrypted drive 'TestCryptDrive:rclone-test-fulajol8jiga': Looking for duplicate names using largest mode. 2026/01/29 06:23:35 NOTICE: one: Found 3 files with duplicate names 2026/01/29 06:23:35 INFO : one: Deleted 2026/01/29 06:23:36 INFO : one: Deleted 2026/01/29 06:23:36 NOTICE: one: Deleted 2 extra copies --- PASS: TestDeduplicateLargest (8.43s) === RUN TestDeduplicateSmallest run.go:185: Remote "Encrypted drive 'TestCryptDrive:rclone-test-fulajol8jiga'", Local "Local file system at /tmp/rclone1201838359", Modify Window "1ms" 2026/01/29 06:23:42 INFO : Encrypted drive 'TestCryptDrive:rclone-test-fulajol8jiga': Looking for duplicate names using smallest mode. 2026/01/29 06:23:43 NOTICE: one: Found 3 files with duplicate names 2026/01/29 06:23:44 INFO : one: Deleted 2026/01/29 06:23:44 INFO : one: Deleted 2026/01/29 06:23:44 NOTICE: one: Deleted 2 extra copies --- PASS: TestDeduplicateSmallest (8.30s) === RUN TestDeduplicateRename run.go:185: Remote "Encrypted drive 'TestCryptDrive:rclone-test-fulajol8jiga'", Local "Local file system at /tmp/rclone1201838359", Modify Window "1ms" 2026/01/29 06:23:52 INFO : Encrypted drive 'TestCryptDrive:rclone-test-fulajol8jiga': Looking for duplicate names using rename mode. 2026/01/29 06:23:53 NOTICE: one.txt: Found 3 files with duplicate names 2026/01/29 06:23:54 INFO : one-2.txt: renamed from: one.txt 2026/01/29 06:23:56 INFO : one-3.txt: renamed from: one.txt 2026/01/29 06:23:57 INFO : one-4.txt: renamed from: one.txt --- PASS: TestDeduplicateRename (15.01s) === RUN TestMergeDirs run.go:185: Remote "Encrypted drive 'TestCryptDrive:rclone-test-fulajol8jiga'", Local "Local file system at /tmp/rclone1201838359", Modify Window "1ms" 2026/01/29 06:24:04 DEBUG : dupe1/one.txt: md5 = 4fd455e298948f50403274b3da46e4cb OK 2026/01/29 06:24:06 DEBUG : dupe2/two.txt: md5 = 76052d9cf3e7c3c6a6d0fd1f3d176293 OK 2026/01/29 06:24:09 DEBUG : dupe3/three.txt: md5 = 6775704beef737ad7fae4d2989c6b1ba OK 2026/01/29 06:24:10 INFO : urj8gsducqbtvekeq181ntd2u8: merging "sfrom47189m9qr3mt4qonu281c" 2026/01/29 06:24:11 INFO : urj8gsducqbtvekeq181ntd2u8: removing empty directory 2026/01/29 06:24:12 INFO : 1u1r4ei7c628fnjg9blqt3j60o: merging "tr2hj63d80ftlmvm6a952snjcc" 2026/01/29 06:24:12 INFO : 1u1r4ei7c628fnjg9blqt3j60o: removing empty directory 2026/01/29 06:24:17 DEBUG : 6kqidisnml4ml642h4iorebdng: Rmdir: contains trashed file: "tr2hj63d80ftlmvm6a952snjcc" 2026/01/29 06:24:17 DEBUG : 6kqidisnml4ml642h4iorebdng: Rmdir: contains trashed file: "sfrom47189m9qr3mt4qonu281c" 2026/01/29 06:24:17 DEBUG : 6kqidisnml4ml642h4iorebdng: Rmdir: contains trashed file: "o9f79a47tdo934l5lfe4jpsms8" --- PASS: TestMergeDirs (16.97s) === RUN TestListDirSorted run.go:185: Remote "Encrypted drive 'TestCryptDrive:rclone-test-fulajol8jiga'", Local "Local file system at /tmp/rclone1201838359", Modify Window "1ms" 2026/01/29 06:24:20 DEBUG : a.txt: md5 = 19b6daba876163b61491a8b7fe047ae2 OK 2026/01/29 06:24:21 DEBUG : zend.txt: md5 = bb00306f8d792c6aebaeb94d21a0b8d7 OK 2026/01/29 06:24:24 DEBUG : sub dir/hello world: md5 = d6ea8c296dc45f15b55dff28763975dc OK 2026/01/29 06:24:26 DEBUG : sub dir/hello world2: md5 = 3e6851064d89db1bd02f956a3688ac1f OK 2026/01/29 06:24:29 DEBUG : sub dir/ignore dir/.ignore: md5 = 0e1288ef8de2df3a21976770c9991125 OK 2026/01/29 06:24:30 DEBUG : sub dir/ignore dir/should be ignored: md5 = 0e9f87c0e95012c1af1352523d017da6 OK 2026/01/29 06:24:33 DEBUG : sub dir/sub sub dir/hello world3: md5 = 686fee4c10f50dcb392efc99d0b8b631 OK 2026/01/29 06:24:35 DEBUG : a.txt: Excluded (Size Filter) 2026/01/29 06:24:35 DEBUG : a.txt: Excluded 2026/01/29 06:24:35 DEBUG : sub dir/hello world2: Excluded (Size Filter) 2026/01/29 06:24:35 DEBUG : sub dir/hello world2: Excluded 2026/01/29 06:24:35 DEBUG : sub dir/hello world: Excluded (Size Filter) 2026/01/29 06:24:35 DEBUG : sub dir/hello world: Excluded 2026/01/29 06:24:36 DEBUG : sub dir/ignore dir: Excluded 2026/01/29 06:24:36 DEBUG : sub dir/hello world2: Excluded (Size Filter) 2026/01/29 06:24:36 DEBUG : sub dir/hello world2: Excluded 2026/01/29 06:24:36 DEBUG : sub dir/hello world: Excluded (Size Filter) 2026/01/29 06:24:36 DEBUG : sub dir/hello world: Excluded 2026/01/29 06:24:37 DEBUG : sub dir/ignore dir: Excluded 2026/01/29 06:24:43 DEBUG : 14begaa36ih249fgrnfk6nulg0/u72nb76vlceqmgie4q57rr656s: Rmdir: contains trashed file: "o5airla31uiui01blt74of9ves" 2026/01/29 06:24:44 DEBUG : 14begaa36ih249fgrnfk6nulg0/3fslb0r9seuggqgrrqv2gjhfj8: Rmdir: contains trashed file: "8qak2o3rl7h348s0lh02ghr71aajis56gocdjjbjfkc2j4vakju0" 2026/01/29 06:24:44 DEBUG : 14begaa36ih249fgrnfk6nulg0/3fslb0r9seuggqgrrqv2gjhfj8: Rmdir: contains trashed file: "i0t74httmdlljg425j6tg570d8" 2026/01/29 06:24:45 DEBUG : 14begaa36ih249fgrnfk6nulg0: Rmdir: contains trashed file: "u72nb76vlceqmgie4q57rr656s" 2026/01/29 06:24:45 DEBUG : 14begaa36ih249fgrnfk6nulg0: Rmdir: contains trashed file: "3fslb0r9seuggqgrrqv2gjhfj8" 2026/01/29 06:24:45 DEBUG : 14begaa36ih249fgrnfk6nulg0: Rmdir: contains trashed file: "mp1g12tj43cv7goud5b95fgju8" 2026/01/29 06:24:45 DEBUG : 14begaa36ih249fgrnfk6nulg0: Rmdir: contains trashed file: "8j9gk4b744l35einofkqsr2mvo" --- PASS: TestListDirSorted (27.70s) === RUN TestListDirSortedFn run.go:185: Remote "Encrypted drive 'TestCryptDrive:rclone-test-fulajol8jiga'", Local "Local file system at /tmp/rclone1201838359", Modify Window "1ms" 2026/01/29 06:24:47 DEBUG : a.txt: md5 = 30826748ced095e62b07570d3ac29685 OK 2026/01/29 06:24:49 DEBUG : zend.txt: md5 = e7cd9ccd6acdcd6456483f07740ad8d7 OK 2026/01/29 06:24:51 DEBUG : sub dir/hello world: md5 = 7e5509fe39946386c221f3b551a29aed OK 2026/01/29 06:24:53 DEBUG : sub dir/hello world2: md5 = c16370cd42c6f6f9133e632c1ea48a66 OK 2026/01/29 06:24:56 DEBUG : sub dir/ignore dir/.ignore: md5 = d90f4836648279725953d08fc2ae56c0 OK 2026/01/29 06:24:58 DEBUG : sub dir/ignore dir/should be ignored: md5 = 1f6de426add82b39463158816af9c480 OK 2026/01/29 06:25:00 DEBUG : sub dir/sub sub dir/hello world3: md5 = 83ca92d1b902320c52537c2cbc7950aa OK 2026/01/29 06:25:02 DEBUG : a.txt: Excluded (Size Filter) 2026/01/29 06:25:02 DEBUG : a.txt: Excluded 2026/01/29 06:25:03 DEBUG : sub dir/hello world: Excluded (Size Filter) 2026/01/29 06:25:03 DEBUG : sub dir/hello world: Excluded 2026/01/29 06:25:03 DEBUG : sub dir/hello world2: Excluded (Size Filter) 2026/01/29 06:25:03 DEBUG : sub dir/hello world2: Excluded 2026/01/29 06:25:04 DEBUG : sub dir/ignore dir: Excluded 2026/01/29 06:25:04 DEBUG : sub dir/hello world: Excluded (Size Filter) 2026/01/29 06:25:04 DEBUG : sub dir/hello world: Excluded 2026/01/29 06:25:04 DEBUG : sub dir/hello world2: Excluded (Size Filter) 2026/01/29 06:25:04 DEBUG : sub dir/hello world2: Excluded 2026/01/29 06:25:04 DEBUG : sub dir/ignore dir: Excluded 2026/01/29 06:25:10 DEBUG : 14begaa36ih249fgrnfk6nulg0/u72nb76vlceqmgie4q57rr656s: Rmdir: contains trashed file: "o5airla31uiui01blt74of9ves" 2026/01/29 06:25:11 DEBUG : 14begaa36ih249fgrnfk6nulg0/3fslb0r9seuggqgrrqv2gjhfj8: Rmdir: contains trashed file: "i0t74httmdlljg425j6tg570d8" 2026/01/29 06:25:11 DEBUG : 14begaa36ih249fgrnfk6nulg0/3fslb0r9seuggqgrrqv2gjhfj8: Rmdir: contains trashed file: "8qak2o3rl7h348s0lh02ghr71aajis56gocdjjbjfkc2j4vakju0" 2026/01/29 06:25:12 DEBUG : 14begaa36ih249fgrnfk6nulg0: Rmdir: contains trashed file: "u72nb76vlceqmgie4q57rr656s" 2026/01/29 06:25:12 DEBUG : 14begaa36ih249fgrnfk6nulg0: Rmdir: contains trashed file: "3fslb0r9seuggqgrrqv2gjhfj8" 2026/01/29 06:25:12 DEBUG : 14begaa36ih249fgrnfk6nulg0: Rmdir: contains trashed file: "8j9gk4b744l35einofkqsr2mvo" 2026/01/29 06:25:12 DEBUG : 14begaa36ih249fgrnfk6nulg0: Rmdir: contains trashed file: "mp1g12tj43cv7goud5b95fgju8" --- PASS: TestListDirSortedFn (27.24s) === RUN TestListJSON run.go:185: Remote "Encrypted drive 'TestCryptDrive:rclone-test-fulajol8jiga'", Local "Local file system at /tmp/rclone1201838359", Modify Window "1ms" 2026/01/29 06:25:14 DEBUG : file1: md5 = e2d50ad9f460cf96cd2eac9f4e28e877 OK 2026/01/29 06:25:17 DEBUG : sub/file2: md5 = 869ebb4b44470747cce8c57e16e3d727 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/01/29 06:25:22 ERROR : file1: Failed to read hash: hash type not supported === RUN TestListJSON/Metadata 2026/01/29 06:25:22 DEBUG : eer8kka55qnghc34cq76ca668g: Fetching metadata 2026/01/29 06:25:22 DEBUG : 150fuo3cn4j1uenq6r4g4qk6t4: Fetching metadata 2026/01/29 06:25:25 DEBUG : 150fuo3cn4j1uenq6r4g4qk6t4: Rmdir: contains trashed file: "d26uovf0miqhd1mu3c9jbseq00" --- PASS: TestListJSON (13.01s) --- PASS: TestListJSON/Default (0.33s) --- PASS: TestListJSON/FilesOnly (0.30s) --- PASS: TestListJSON/DirsOnly (0.96s) --- PASS: TestListJSON/Recurse (0.65s) --- PASS: TestListJSON/SubDir (0.29s) --- PASS: TestListJSON/NoModTime (0.37s) --- PASS: TestListJSON/NoMimeType (0.31s) --- PASS: TestListJSON/ShowHash (0.30s) --- PASS: TestListJSON/HashTypes (0.30s) --- PASS: TestListJSON/Metadata (0.81s) === RUN TestStatJSON run.go:185: Remote "Encrypted drive 'TestCryptDrive:rclone-test-fulajol8jiga'", Local "Local file system at /tmp/rclone1201838359", Modify Window "1ms" 2026/01/29 06:25:27 DEBUG : file1: md5 = e3d34a7d3aa784c4d5920068f28cf36e OK 2026/01/29 06:25:30 DEBUG : sub/file2: md5 = 057596d2938de3024d2a55ed5fe34d9d 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/01/29 06:25:35 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-fulajol8jiga/notfound" 2026/01/29 06:25:35 DEBUG : Config file has changed externally - reloading 2026/01/29 06:25:35 DEBUG : Creating backend with remote "TestDrive:crypt/hmt9nrq7jloakp17tgo4ehfhqkkos3f6kh7gg7vbnaj6l4scnjk0/qe0i31qdkoejk60elps0ni5sqk" 2026/01/29 06:25:40 DEBUG : 150fuo3cn4j1uenq6r4g4qk6t4: Rmdir: contains trashed file: "d26uovf0miqhd1mu3c9jbseq00" --- PASS: TestStatJSON (14.82s) --- PASS: TestStatJSON/Root (0.31s) --- PASS: TestStatJSON/RootFilesOnly (0.00s) --- PASS: TestStatJSON/RootDirsOnly (0.34s) --- PASS: TestStatJSON/Dir (0.67s) --- PASS: TestStatJSON/DirWithTrailingSlash (0.34s) --- PASS: TestStatJSON/File (0.30s) --- PASS: TestStatJSON/NotFound (0.60s) --- PASS: TestStatJSON/DirFilesOnly (0.28s) --- PASS: TestStatJSON/FileFilesOnly (0.33s) --- PASS: TestStatJSON/NotFoundFilesOnly (0.24s) --- PASS: TestStatJSON/DirDirsOnly (0.33s) --- PASS: TestStatJSON/FileDirsOnly (0.34s) --- PASS: TestStatJSON/NotFoundDirsOnly (0.35s) --- PASS: TestStatJSON/RootNotFound (2.24s) === RUN TestMkdir run.go:185: Remote "Encrypted drive 'TestCryptDrive:rclone-test-fulajol8jiga'", Local "Local file system at /tmp/rclone1201838359", Modify Window "1ms" 2026/01/29 06:25:41 INFO : Encrypted drive 'TestCryptDrive:rclone-test-fulajol8jiga': Making directory 2026/01/29 06:25:41 INFO : Encrypted drive 'TestCryptDrive:rclone-test-fulajol8jiga': Making directory --- PASS: TestMkdir (0.81s) === RUN TestLsd run.go:185: Remote "Encrypted drive 'TestCryptDrive:rclone-test-fulajol8jiga'", Local "Local file system at /tmp/rclone1201838359", Modify Window "1ms" 2026/01/29 06:25:44 DEBUG : sub dir/hello world: md5 = 628c4f2f476bf94a6376b67e832b1e77 OK 2026/01/29 06:25:47 DEBUG : 14begaa36ih249fgrnfk6nulg0: Rmdir: contains trashed file: "8j9gk4b744l35einofkqsr2mvo" --- PASS: TestLsd (6.29s) === RUN TestLs run.go:185: Remote "Encrypted drive 'TestCryptDrive:rclone-test-fulajol8jiga'", Local "Local file system at /tmp/rclone1201838359", Modify Window "1ms" 2026/01/29 06:25:49 DEBUG : potato2: md5 = 64eab93e4a4791ed63e98b3d5bea63da OK 2026/01/29 06:25:51 DEBUG : empty space: md5 = c2d99dc479ebc134d2b9a79ba19a2666 OK --- PASS: TestLs (5.66s) === RUN TestLsWithFilesFrom run.go:185: Remote "Encrypted drive 'TestCryptDrive:rclone-test-fulajol8jiga'", Local "Local file system at /tmp/rclone1201838359", Modify Window "1ms" 2026/01/29 06:25:56 DEBUG : potato2: md5 = 64f5554867c039584a8e031eea9e9455 OK 2026/01/29 06:25:58 DEBUG : empty space: md5 = a3b431c213cfc8bdeb92e53a2ca06ad9 OK 2026/01/29 06:25:58 DEBUG : empty space: Excluded (FilesFrom Filter) --- PASS: TestLsWithFilesFrom (7.11s) === RUN TestLsLong run.go:185: Remote "Encrypted drive 'TestCryptDrive:rclone-test-fulajol8jiga'", Local "Local file system at /tmp/rclone1201838359", Modify Window "1ms" 2026/01/29 06:26:02 DEBUG : potato2: md5 = 74e8abdf4c8e7976beddeddb3b757882 OK 2026/01/29 06:26:04 DEBUG : empty space: md5 = 70e010f74257009792fb44623615215a OK --- PASS: TestLsLong (5.96s) === RUN TestHashSums run.go:185: Remote "Encrypted drive 'TestCryptDrive:rclone-test-fulajol8jiga'", Local "Local file system at /tmp/rclone1201838359", Modify Window "1ms" 2026/01/29 06:26:08 DEBUG : potato2: md5 = 69d118721e12dd543c0f904fe7debac3 OK 2026/01/29 06:26:10 DEBUG : empty space: md5 = b1d835f0b6cf7dcb57c5bd2e52ef54b5 OK --- PASS: TestHashSums (5.87s) === RUN TestHashSumsWithErrors 2026/01/29 06:26:12 DEBUG : Creating backend with remote ":memory:" 2026/01/29 06:26:12 ERROR : file1: hash unsupported: hash type not supported --- PASS: TestHashSumsWithErrors (0.00s) === RUN TestHashStream 2026/01/29 06:26:12 DEBUG : Creating md5 hash of 0 bytes read from input stream 2026/01/29 06:26:12 DEBUG : Creating md5 hash of 0 bytes read from input stream 2026/01/29 06:26:12 DEBUG : Creating sha1 hash of 0 bytes read from input stream 2026/01/29 06:26:12 DEBUG : Creating sha1 hash of 0 bytes read from input stream 2026/01/29 06:26:12 DEBUG : Creating md5 hash of 12 bytes read from input stream 2026/01/29 06:26:12 DEBUG : Creating md5 hash of 12 bytes read from input stream 2026/01/29 06:26:12 DEBUG : Creating sha1 hash of 12 bytes read from input stream 2026/01/29 06:26:12 DEBUG : Creating sha1 hash of 12 bytes read from input stream --- PASS: TestHashStream (0.00s) === RUN TestSuffixName --- PASS: TestSuffixName (0.00s) === RUN TestCount run.go:185: Remote "Encrypted drive 'TestCryptDrive:rclone-test-fulajol8jiga'", Local "Local file system at /tmp/rclone1201838359", Modify Window "1ms" 2026/01/29 06:26:14 DEBUG : potato2: md5 = 723cc58b540d52f20acfb1f04a4e0753 OK 2026/01/29 06:26:16 DEBUG : empty space: md5 = 0014ea7311f81c81a8ccd00b4dcccb84 OK 2026/01/29 06:26:19 DEBUG : sub dir/potato3: md5 = 12c049a8efbf2b59c205b0739f299898 OK 2026/01/29 06:26:22 DEBUG : 14begaa36ih249fgrnfk6nulg0: Rmdir: contains trashed file: "9atai6og4mljg3r5gjeh27k0q8" --- PASS: TestCount (10.96s) === RUN TestDelete run.go:185: Remote "Encrypted drive 'TestCryptDrive:rclone-test-fulajol8jiga'", Local "Local file system at /tmp/rclone1201838359", Modify Window "1ms" 2026/01/29 06:26:25 DEBUG : small: md5 = e8979047224d272d4f8e3e6927b8cba6 OK 2026/01/29 06:26:27 DEBUG : medium: md5 = a6215dfe9b62f6d4e54ebe03569ade97 OK 2026/01/29 06:26:29 DEBUG : large: md5 = 7a27faaa8de7a738a9face6282ef6607 OK 2026/01/29 06:26:29 DEBUG : Waiting for deletions to finish 2026/01/29 06:26:29 DEBUG : large: Excluded (Size Filter) 2026/01/29 06:26:30 INFO : small: Deleted 2026/01/29 06:26:30 INFO : medium: Deleted --- PASS: TestDelete (8.22s) === RUN TestMaxDelete run.go:185: Remote "Encrypted drive 'TestCryptDrive:rclone-test-fulajol8jiga'", Local "Local file system at /tmp/rclone1201838359", Modify Window "1ms" 2026/01/29 06:26:33 DEBUG : small: md5 = 4ac3e3d899d6ceb60cd96c43191de6a4 OK 2026/01/29 06:26:35 DEBUG : medium: md5 = 5853b62d9e8dbc48b8a5fc0b5af18de2 OK 2026/01/29 06:26:36 DEBUG : large: md5 = 6515f59ac6dabb116bef1e82a1b03911 OK 2026/01/29 06:26:37 DEBUG : Waiting for deletions to finish 2026/01/29 06:26:37 ERROR : medium: Got fatal error on delete: --max-delete threshold reached 2026/01/29 06:26:38 INFO : large: Deleted 2026/01/29 06:26:38 INFO : small: Deleted --- PASS: TestMaxDelete (8.36s) === RUN TestMaxDeleteSizeLargeFile run.go:185: Remote "Encrypted drive 'TestCryptDrive:rclone-test-fulajol8jiga'", Local "Local file system at /tmp/rclone1201838359", Modify Window "1ms" 2026/01/29 06:26:41 DEBUG : small: md5 = c12a31ddb0459e742fc0ccfdf62c8e5a OK 2026/01/29 06:26:43 DEBUG : medium: md5 = 543e878cc61762430766d96308fe22dc OK 2026/01/29 06:26:45 DEBUG : large: md5 = f737dce72b306a8274f5852bfc53f1bc OK 2026/01/29 06:26:45 DEBUG : Waiting for deletions to finish 2026/01/29 06:26:45 ERROR : large: Got fatal error on delete: --max-delete-size threshold reached 2026/01/29 06:26:46 INFO : medium: Deleted 2026/01/29 06:26:46 INFO : small: Deleted --- PASS: TestMaxDeleteSizeLargeFile (8.28s) === RUN TestMaxDeleteSize run.go:185: Remote "Encrypted drive 'TestCryptDrive:rclone-test-fulajol8jiga'", Local "Local file system at /tmp/rclone1201838359", Modify Window "1ms" 2026/01/29 06:26:50 DEBUG : small: md5 = 6c15c7d1210031404dd2a71405ae0ebe OK 2026/01/29 06:26:52 DEBUG : medium: md5 = 1b03990fc3b2134fc600abf310bc190e OK 2026/01/29 06:26:53 DEBUG : large: md5 = 1e399379a5a25c80c179bc6df0264559 OK 2026/01/29 06:26:54 DEBUG : Waiting for deletions to finish 2026/01/29 06:26:55 ERROR : medium: Got fatal error on delete: --max-delete-size threshold reached 2026/01/29 06:26:55 INFO : small: Deleted 2026/01/29 06:26:55 INFO : large: Deleted --- PASS: TestMaxDeleteSize (9.34s) === RUN TestReadFile run.go:185: Remote "Encrypted drive 'TestCryptDrive:rclone-test-fulajol8jiga'", Local "Local file system at /tmp/rclone1201838359", Modify Window "1ms" 2026/01/29 06:26:59 DEBUG : ReadFile: md5 = cf5b86db5742e560ea3625bc02078478 OK panic: test timed out after 1h0m0s running tests: TestReadFile (4s) goroutine 5800 [running]: testing.(*M).startAlarm.func1() /usr/local/go/src/testing/testing.go:2484 +0x394 created by time.goFunc /usr/local/go/src/time/sleep.go:215 +0x2d goroutine 1 [chan receive]: testing.(*T).Run(0xc0018b5a40, {0x28e56ff?, 0xc00006bad8?}, 0x2a2ad60) /usr/local/go/src/testing/testing.go:1859 +0x431 testing.runTests.func1(0xc0018b5a40) /usr/local/go/src/testing/testing.go:2279 +0x37 testing.tRunner(0xc0018b5a40, 0xc00006bc18) /usr/local/go/src/testing/testing.go:1792 +0xf4 testing.runTests(0xc001b27320, {0x43b6b20, 0x6f, 0x6f}, {0xee10ce6a5?, 0x43cd460?, 0x43cd460?}) /usr/local/go/src/testing/testing.go:2277 +0x4b4 testing.(*M).Run(0xc0007917c0) /usr/local/go/src/testing/testing.go:2142 +0x64a github.com/rclone/rclone/fstest.TestMain(0xc0007917c0) /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:74 +0xa6 github.com/rclone/rclone/fs/operations_test.TestMain(...) /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:61 main.main() _testmain.go:269 +0xa9 goroutine 22 [syscall, 60 minutes]: os/signal.signal_recv() /usr/local/go/src/runtime/sigqueue.go:152 +0x29 os/signal.loop() /usr/local/go/src/os/signal/signal_unix.go:23 +0x13 created by os/signal.Notify.func1.1 in goroutine 1 /usr/local/go/src/os/signal/signal.go:152 +0x1f goroutine 23 [chan receive, 60 minutes]: github.com/rclone/rclone/fs/accounting.(*tokenBucket).startSignalHandler.func1() /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:24 +0x27 created by github.com/rclone/rclone/fs/accounting.(*tokenBucket).startSignalHandler in goroutine 1 /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:21 +0xa7 goroutine 1083 [chan receive (nil chan), 49 minutes]: github.com/rclone/rclone/fs/march.(*March).Run.func2() /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:257 +0x3c created by github.com/rclone/rclone/fs/march.(*March).Run in goroutine 1041 /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:255 +0x378 goroutine 604 [chan receive (nil chan), 50 minutes]: github.com/rclone/rclone/fs/march.(*March).Run.func2() /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:257 +0x3c created by github.com/rclone/rclone/fs/march.(*March).Run in goroutine 594 /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:255 +0x378 goroutine 5766 [select]: net/http.(*persistConn).roundTrip(0xc0005ddd40, 0xc001259b80) /usr/local/go/src/net/http/transport.go:2826 +0x785 net/http.(*Transport).roundTrip(0xc0000181a0, 0xc001458000) /usr/local/go/src/net/http/transport.go:696 +0xb9a net/http.(*Transport).RoundTrip(...) /usr/local/go/src/net/http/roundtrip.go:30 github.com/rclone/rclone/fs/fshttp.(*Transport).RoundTrip(0xc0001187e0, 0xc001458000) /home/rclone/go/src/github.com/rclone/rclone/fs/fshttp/http.go:496 +0x345 golang.org/x/oauth2.(*Transport).RoundTrip(0xc000281f00, 0xc00134de00) /home/rclone/go/pkg/mod/golang.org/x/oauth2@v0.33.0/transport.go:55 +0x15e net/http.send(0xc00134de00, {0x2d9ed00, 0xc000281f00}, {0x1?, 0x0?, 0x0?}) /usr/local/go/src/net/http/client.go:259 +0x5e2 net/http.(*Client).send(0xc000422fc0, 0xc00134de00, {0xc0005d30e8?, 0x6a3e0a?, 0x0?}) /usr/local/go/src/net/http/client.go:180 +0x91 net/http.(*Client).do(0xc000422fc0, 0xc00134de00) /usr/local/go/src/net/http/client.go:728 +0x989 net/http.(*Client).Do(...) /usr/local/go/src/net/http/client.go:587 google.golang.org/api/internal/gensupport.send({0x2dc4648, 0x43f0c80}, 0x28eecc8?, 0xc00134db80) /home/rclone/go/pkg/mod/google.golang.org/api@v0.255.0/internal/gensupport/send.go:89 +0xd9 google.golang.org/api/internal/gensupport.SendRequest({0x2dc4648, 0x43f0c80}, 0xc000422fc0, 0xc00134db80) /home/rclone/go/pkg/mod/google.golang.org/api@v0.255.0/internal/gensupport/send.go:82 +0xfc google.golang.org/api/drive/v3.(*FilesUpdateCall).doRequest(0xc001259ae0, {0x28cd358, 0x4}) /home/rclone/go/pkg/mod/google.golang.org/api@v0.255.0/drive/v3/drive-gen.go:7829 +0x778 google.golang.org/api/drive/v3.(*FilesUpdateCall).Do(0xc001259ae0, {0x0?, 0x43f0c80?, 0xc00141aa88?}) /home/rclone/go/pkg/mod/google.golang.org/api@v0.255.0/drive/v3/drive-gen.go:7839 +0x6f github.com/rclone/rclone/backend/drive.(*baseObject).Remove.(*Fs).delete.func1() /home/rclone/go/src/github.com/rclone/rclone/backend/drive/drive.go:2675 +0x105 github.com/rclone/rclone/fs.pacerInvoker(0x1, 0xa, 0x40?) /home/rclone/go/src/github.com/rclone/rclone/fs/pacer.go:86 +0x32 github.com/rclone/rclone/lib/pacer.(*Pacer).call(0xc0001188a0, 0xc00144a1c0, 0xa) /home/rclone/go/src/github.com/rclone/rclone/lib/pacer/pacer.go:216 +0xd2 github.com/rclone/rclone/lib/pacer.(*Pacer).Call(0xc0001188a0, 0xc00144a1c0) /home/rclone/go/src/github.com/rclone/rclone/lib/pacer/pacer.go:235 +0x93 github.com/rclone/rclone/backend/drive.(*Fs).delete(...) /home/rclone/go/src/github.com/rclone/rclone/backend/drive/drive.go:2666 github.com/rclone/rclone/backend/drive.(*baseObject).Remove(0xc0001adec0, {0x2dc4648, 0x43f0c80}) /home/rclone/go/src/github.com/rclone/rclone/backend/drive/drive.go:4546 +0xfa github.com/rclone/rclone/fstest.newRunIndividual.func1.1.1() /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:159 +0x24 github.com/rclone/rclone/fstest.retry(0xc0010d4e00, {0xc00181cca8, 0x18}, 0xc0005d3868) /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:126 +0xde github.com/rclone/rclone/fstest.newRunIndividual.func1.1({0xc001162e40?, 0x1?, 0x74f57826e0f8?}) /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:159 +0x29a github.com/rclone/rclone/fs/walk.listR.func1({0xc001162e40, 0x1, 0x1}) /home/rclone/go/src/github.com/rclone/rclone/fs/walk/walk.go:331 +0x1ca github.com/rclone/rclone/backend/crypt.(*Fs).ListR.func1({0xc001162e40?, 0xc0005d3b00?, 0x1123101?}) /home/rclone/go/src/github.com/rclone/rclone/backend/crypt/crypt.go:481 +0x5b github.com/rclone/rclone/fs/list.(*Helper).send(...) /home/rclone/go/src/github.com/rclone/rclone/fs/list/helpers.go:29 github.com/rclone/rclone/fs/list.(*Helper).Flush(0xc001219c20) /home/rclone/go/src/github.com/rclone/rclone/fs/list/helpers.go:47 +0x35 github.com/rclone/rclone/backend/drive.(*Fs).ListR(0xc000282248, {0x2dc4648, 0x43f0c80}, {0x0, 0x0}, 0xc001438660) /home/rclone/go/src/github.com/rclone/rclone/backend/drive/drive.go:2301 +0x685 github.com/rclone/rclone/backend/crypt.(*Fs).ListR(0xc001965e10, {0x2dc4648, 0x43f0c80}, {0x0, 0x0}, 0xc0012596d0) /home/rclone/go/src/github.com/rclone/rclone/backend/crypt/crypt.go:476 +0x118 github.com/rclone/rclone/fs/walk.listR({0x2dc4648, 0x43f0c80}, {0x2ddaac0, 0xc001965e10}, {0x0, 0x0}, 0x1, 0x3, 0xc001438600, 0xc0019a3b60, ...) /home/rclone/go/src/github.com/rclone/rclone/fs/walk/walk.go:299 +0x292 github.com/rclone/rclone/fs/walk.ListR({0x2dc4648, 0x43f0c80}, {0x2ddaac0, 0xc001965e10}, {0x0, 0x0}, 0x1, 0xffffffffffffffff, 0x3, 0xc001438600) /home/rclone/go/src/github.com/rclone/rclone/fs/walk/walk.go:163 +0x1d5 github.com/rclone/rclone/fstest.newRunIndividual.func1() /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:155 +0x107 github.com/rclone/rclone/fstest.(*Run).Finalise(0xc0004757a0) /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393 +0x1c github.com/rclone/rclone/fs/operations_test.TestReadFile(0xc0010d4e00) /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:510 +0x323 testing.tRunner(0xc0010d4e00, 0x2a2ad60) /usr/local/go/src/testing/testing.go:1792 +0xf4 created by testing.(*T).Run in goroutine 1 /usr/local/go/src/testing/testing.go:1851 +0x413 goroutine 4671 [IO wait]: internal/poll.runtime_pollWait(0x74f57858b3a8, 0x72) /usr/local/go/src/runtime/netpoll.go:351 +0x85 internal/poll.(*pollDesc).wait(0xc000a52480?, 0xc0006d1800?, 0x0) /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x27 internal/poll.(*pollDesc).waitRead(...) /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 internal/poll.(*FD).Read(0xc000a52480, {0xc0006d1800, 0x1800, 0x1800}) /usr/local/go/src/internal/poll/fd_unix.go:165 +0x27a net.(*netFD).Read(0xc000a52480, {0xc0006d1800?, 0x17fb?, 0xc000504160?}) /usr/local/go/src/net/fd_posix.go:55 +0x25 net.(*conn).Read(0xc00014e808, {0xc0006d1800?, 0xc0006d1800?, 0x5?}) /usr/local/go/src/net/net.go:194 +0x45 github.com/rclone/rclone/fs/fshttp.(*timeoutConn).Read(0xc001abaa20, {0xc0006d1800?, 0x74f57826d978?, 0x74f5c0a92a78?}) /home/rclone/go/src/github.com/rclone/rclone/fs/fshttp/dialer.go:111 +0x29 crypto/tls.(*atLeastReader).Read(0xc00111eac8, {0xc0006d1800?, 0x17fb?, 0x6?}) /usr/local/go/src/crypto/tls/conn.go:809 +0x3b bytes.(*Buffer).ReadFrom(0xc000c7f7b8, {0x2d9c5a0, 0xc00111eac8}) /usr/local/go/src/bytes/buffer.go:211 +0x98 crypto/tls.(*Conn).readFromUntil(0xc000c7f508, {0x74f57826d4f8, 0xc001abaa20}, 0x4426d4?) /usr/local/go/src/crypto/tls/conn.go:831 +0xde crypto/tls.(*Conn).readRecordOrCCS(0xc000c7f508, 0x0) /usr/local/go/src/crypto/tls/conn.go:629 +0x3cf crypto/tls.(*Conn).readRecord(...) /usr/local/go/src/crypto/tls/conn.go:591 crypto/tls.(*Conn).Read(0xc000c7f508, {0xc0007fd000, 0x1000, 0xc000431500?}) /usr/local/go/src/crypto/tls/conn.go:1385 +0x145 net/http.(*persistConn).Read(0xc0005ddd40, {0xc0007fd000?, 0x80f8a5?, 0x2d9a5e0?}) /usr/local/go/src/net/http/transport.go:2122 +0x47 bufio.(*Reader).fill(0xc001b72a80) /usr/local/go/src/bufio/bufio.go:113 +0x103 bufio.(*Reader).Peek(0xc001b72a80, 0x1) /usr/local/go/src/bufio/bufio.go:152 +0x53 net/http.(*persistConn).readLoop(0xc0005ddd40) /usr/local/go/src/net/http/transport.go:2275 +0x172 created by net/http.(*Transport).dialConn in goroutine 4665 /usr/local/go/src/net/http/transport.go:1944 +0x174f goroutine 356 [chan receive (nil chan), 60 minutes]: github.com/rclone/rclone/fs/march.(*March).Run.func2() /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:257 +0x3c created by github.com/rclone/rclone/fs/march.(*March).Run in goroutine 346 /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:255 +0x378 goroutine 144 [chan receive (nil chan), 60 minutes]: github.com/rclone/rclone/fs/march.(*March).Run.func2() /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:257 +0x3c created by github.com/rclone/rclone/fs/march.(*March).Run in goroutine 252 /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:255 +0x378 goroutine 155 [chan receive (nil chan), 60 minutes]: github.com/rclone/rclone/fs/march.(*March).Run.func2() /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:257 +0x3c created by github.com/rclone/rclone/fs/march.(*March).Run in goroutine 129 /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:255 +0x378 goroutine 439 [chan receive (nil chan), 60 minutes]: github.com/rclone/rclone/fs/march.(*March).Run.func2() /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:257 +0x3c created by github.com/rclone/rclone/fs/march.(*March).Run in goroutine 381 /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:255 +0x378 goroutine 1998 [chan receive, 48 minutes]: github.com/rclone/rclone/fs/accounting.(*tokenBucket).startSignalHandler.func1() /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:24 +0x27 created by github.com/rclone/rclone/fs/accounting.(*tokenBucket).startSignalHandler in goroutine 1598 /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:21 +0xa7 goroutine 2000 [chan receive, 48 minutes]: github.com/rclone/rclone/fs/accounting.(*tokenBucket).startSignalHandler.func1() /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:24 +0x27 created by github.com/rclone/rclone/fs/accounting.(*tokenBucket).startSignalHandler in goroutine 1598 /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:21 +0xa7 goroutine 3779 [IO wait]: internal/poll.runtime_pollWait(0x74f57858b5d8, 0x72) /usr/local/go/src/runtime/netpoll.go:351 +0x85 internal/poll.(*pollDesc).wait(0xc000051a80?, 0xc0006d0000?, 0x0) /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x27 internal/poll.(*pollDesc).waitRead(...) /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 internal/poll.(*FD).Read(0xc000051a80, {0xc0006d0000, 0x1800, 0x1800}) /usr/local/go/src/internal/poll/fd_unix.go:165 +0x27a net.(*netFD).Read(0xc000051a80, {0xc0006d0000?, 0x17fb?, 0xc001a66ba0?}) /usr/local/go/src/net/fd_posix.go:55 +0x25 net.(*conn).Read(0xc00014e688, {0xc0006d0000?, 0xc0006d0000?, 0x5?}) /usr/local/go/src/net/net.go:194 +0x45 github.com/rclone/rclone/fs/fshttp.(*timeoutConn).Read(0xc001b62240, {0xc0006d0000?, 0x74f5782450d8?, 0x74f5c0a92108?}) /home/rclone/go/src/github.com/rclone/rclone/fs/fshttp/dialer.go:111 +0x29 crypto/tls.(*atLeastReader).Read(0xc001b63aa0, {0xc0006d0000?, 0x17fb?, 0x1?}) /usr/local/go/src/crypto/tls/conn.go:809 +0x3b bytes.(*Buffer).ReadFrom(0xc0009d6638, {0x2d9c5a0, 0xc001b63aa0}) /usr/local/go/src/bytes/buffer.go:211 +0x98 crypto/tls.(*Conn).readFromUntil(0xc0009d6388, {0x74f57826d4f8, 0xc001b62240}, 0x4426d4?) /usr/local/go/src/crypto/tls/conn.go:831 +0xde crypto/tls.(*Conn).readRecordOrCCS(0xc0009d6388, 0x0) /usr/local/go/src/crypto/tls/conn.go:629 +0x3cf crypto/tls.(*Conn).readRecord(...) /usr/local/go/src/crypto/tls/conn.go:591 crypto/tls.(*Conn).Read(0xc0009d6388, {0xc0006e8000, 0x1000, 0xc000431500?}) /usr/local/go/src/crypto/tls/conn.go:1385 +0x145 net/http.(*persistConn).Read(0xc0005dc7e0, {0xc0006e8000?, 0x80f8a5?, 0x2d9a5e0?}) /usr/local/go/src/net/http/transport.go:2122 +0x47 bufio.(*Reader).fill(0xc0005ef860) /usr/local/go/src/bufio/bufio.go:113 +0x103 bufio.(*Reader).Peek(0xc0005ef860, 0x1) /usr/local/go/src/bufio/bufio.go:152 +0x53 net/http.(*persistConn).readLoop(0xc0005dc7e0) /usr/local/go/src/net/http/transport.go:2275 +0x172 created by net/http.(*Transport).dialConn in goroutine 3774 /usr/local/go/src/net/http/transport.go:1944 +0x174f goroutine 219 [chan receive (nil chan), 60 minutes]: github.com/rclone/rclone/fs/march.(*March).Run.func2() /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:257 +0x3c created by github.com/rclone/rclone/fs/march.(*March).Run in goroutine 209 /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:255 +0x378 goroutine 521 [chan receive (nil chan), 50 minutes]: github.com/rclone/rclone/fs/march.(*March).Run.func2() /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:257 +0x3c created by github.com/rclone/rclone/fs/march.(*March).Run in goroutine 447 /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:255 +0x378 goroutine 291 [chan receive (nil chan), 60 minutes]: github.com/rclone/rclone/fs/march.(*March).Run.func2() /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:257 +0x3c created by github.com/rclone/rclone/fs/march.(*March).Run in goroutine 280 /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:255 +0x378 goroutine 670 [chan receive (nil chan), 50 minutes]: github.com/rclone/rclone/fs/march.(*March).Run.func2() /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:257 +0x3c created by github.com/rclone/rclone/fs/march.(*March).Run in goroutine 660 /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:255 +0x378 goroutine 396 [chan receive (nil chan), 60 minutes]: github.com/rclone/rclone/fs/march.(*March).Run.func2() /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:257 +0x3c created by github.com/rclone/rclone/fs/march.(*March).Run in goroutine 386 /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:255 +0x378 goroutine 1053 [chan receive (nil chan), 49 minutes]: github.com/rclone/rclone/fs/march.(*March).Run.func2() /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:257 +0x3c created by github.com/rclone/rclone/fs/march.(*March).Run in goroutine 1043 /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:255 +0x378 goroutine 851 [chan receive (nil chan), 50 minutes]: github.com/rclone/rclone/fs/march.(*March).Run.func2() /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:257 +0x3c created by github.com/rclone/rclone/fs/march.(*March).Run in goroutine 841 /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:255 +0x378 goroutine 1029 [chan receive (nil chan), 49 minutes]: github.com/rclone/rclone/fs/march.(*March).Run.func2() /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:257 +0x3c created by github.com/rclone/rclone/fs/march.(*March).Run in goroutine 875 /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:255 +0x378 goroutine 643 [chan receive (nil chan), 50 minutes]: github.com/rclone/rclone/fs/march.(*March).Run.func2() /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:257 +0x3c created by github.com/rclone/rclone/fs/march.(*March).Run in goroutine 633 /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:255 +0x378 goroutine 486 [chan receive (nil chan), 50 minutes]: github.com/rclone/rclone/fs/march.(*March).Run.func2() /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:257 +0x3c created by github.com/rclone/rclone/fs/march.(*March).Run in goroutine 365 /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:255 +0x378 goroutine 1970 [chan receive, 48 minutes]: github.com/rclone/rclone/fs/accounting.(*tokenBucket).startSignalHandler.func1() /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:24 +0x27 created by github.com/rclone/rclone/fs/accounting.(*tokenBucket).startSignalHandler in goroutine 1598 /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:21 +0xa7 goroutine 1713 [chan receive, 48 minutes]: github.com/rclone/rclone/fs/accounting.(*tokenBucket).startSignalHandler.func1() /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:24 +0x27 created by github.com/rclone/rclone/fs/accounting.(*tokenBucket).startSignalHandler in goroutine 1598 /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:21 +0xa7 goroutine 1599 [chan receive, 48 minutes]: github.com/rclone/rclone/fs/accounting.(*tokenBucket).startSignalHandler.func1() /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:24 +0x27 created by github.com/rclone/rclone/fs/accounting.(*tokenBucket).startSignalHandler in goroutine 1598 /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:21 +0xa7 goroutine 950 [chan receive (nil chan), 50 minutes]: github.com/rclone/rclone/fs/march.(*March).Run.func2() /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:257 +0x3c created by github.com/rclone/rclone/fs/march.(*March).Run in goroutine 940 /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:255 +0x378 goroutine 1207 [chan receive (nil chan), 49 minutes]: github.com/rclone/rclone/fs/march.(*March).Run.func2() /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:257 +0x3c created by github.com/rclone/rclone/fs/march.(*March).Run in goroutine 1133 /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:255 +0x378 goroutine 2233 [chan receive, 48 minutes]: github.com/rclone/rclone/fs/accounting.(*tokenBucket).startSignalHandler.func1() /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:24 +0x27 created by github.com/rclone/rclone/fs/accounting.(*tokenBucket).startSignalHandler in goroutine 1598 /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:21 +0xa7 goroutine 762 [chan receive (nil chan), 50 minutes]: github.com/rclone/rclone/fs/march.(*March).Run.func2() /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:257 +0x3c created by github.com/rclone/rclone/fs/march.(*March).Run in goroutine 688 /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:255 +0x378 goroutine 1010 [chan receive (nil chan), 49 minutes]: github.com/rclone/rclone/fs/march.(*March).Run.func2() /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:257 +0x3c created by github.com/rclone/rclone/fs/march.(*March).Run in goroutine 1000 /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:255 +0x378 goroutine 3780 [select]: net/http.(*persistConn).writeLoop(0xc0005dc7e0) /usr/local/go/src/net/http/transport.go:2590 +0xe7 created by net/http.(*Transport).dialConn in goroutine 3774 /usr/local/go/src/net/http/transport.go:1945 +0x17a5 goroutine 819 [chan receive (nil chan), 50 minutes]: github.com/rclone/rclone/fs/march.(*March).Run.func2() /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:257 +0x3c created by github.com/rclone/rclone/fs/march.(*March).Run in goroutine 777 /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:255 +0x378 goroutine 1159 [chan receive (nil chan), 49 minutes]: github.com/rclone/rclone/fs/march.(*March).Run.func2() /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:257 +0x3c created by github.com/rclone/rclone/fs/march.(*March).Run in goroutine 1149 /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:255 +0x378 goroutine 1702 [chan receive, 48 minutes]: github.com/rclone/rclone/fs/accounting.(*tokenBucket).startSignalHandler.func1() /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:24 +0x27 created by github.com/rclone/rclone/fs/accounting.(*tokenBucket).startSignalHandler in goroutine 1598 /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:21 +0xa7 goroutine 2108 [chan receive, 48 minutes]: github.com/rclone/rclone/fs/accounting.(*tokenBucket).startSignalHandler.func1() /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:24 +0x27 created by github.com/rclone/rclone/fs/accounting.(*tokenBucket).startSignalHandler in goroutine 1598 /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:21 +0xa7 goroutine 1782 [chan receive, 48 minutes]: github.com/rclone/rclone/fs/accounting.(*tokenBucket).startSignalHandler.func1() /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:24 +0x27 created by github.com/rclone/rclone/fs/accounting.(*tokenBucket).startSignalHandler in goroutine 1598 /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:21 +0xa7 goroutine 1896 [chan receive, 48 minutes]: github.com/rclone/rclone/fs/accounting.(*tokenBucket).startSignalHandler.func1() /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:24 +0x27 created by github.com/rclone/rclone/fs/accounting.(*tokenBucket).startSignalHandler in goroutine 1598 /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:21 +0xa7 goroutine 4672 [select]: net/http.(*persistConn).writeLoop(0xc0005ddd40) /usr/local/go/src/net/http/transport.go:2590 +0xe7 created by net/http.(*Transport).dialConn in goroutine 4665 /usr/local/go/src/net/http/transport.go:1945 +0x17a5 "./operations.test -test.v -test.timeout 1h0m0s -remote TestCryptDrive: -verbose -fast-list" - Finished ERROR in 1h0m3.520183021s (try 1/5): exit status 2: Failed []