"./operations.test -test.v -test.timeout 1h0m0s -remote TestCryptDrive: -verbose" - Starting (try 1/5) 2025/11/15 03:21:49 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-varoqut0vomu" 2025/11/15 03:21:49 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/11/15 03:21:49 DEBUG : Creating backend with remote "TestDrive:crypt/i7rtc15m75acpkcu99de1eu6ik7sc5tlp31a4jpi4vfn339844bg" 2025/11/15 03:21:50 DEBUG : Creating backend with remote "/tmp/rclone2444379526" === RUN TestDoMultiThreadCopy --- PASS: TestDoMultiThreadCopy (0.00s) === RUN TestMultithreadCalculateNumChunks === RUN TestMultithreadCalculateNumChunks/{size:1_chunkSize:65536_wantNumChunks:1} === RUN TestMultithreadCalculateNumChunks/{size:1048576_chunkSize:1_wantNumChunks:1048576} === RUN TestMultithreadCalculateNumChunks/{size:1048576_chunkSize:2_wantNumChunks:524288} === RUN TestMultithreadCalculateNumChunks/{size:1048577_chunkSize:2_wantNumChunks:524289} === RUN TestMultithreadCalculateNumChunks/{size:1048575_chunkSize:2_wantNumChunks:524288} --- PASS: TestMultithreadCalculateNumChunks (0.00s) --- PASS: TestMultithreadCalculateNumChunks/{size:1_chunkSize:65536_wantNumChunks:1} (0.00s) --- PASS: TestMultithreadCalculateNumChunks/{size:1048576_chunkSize:1_wantNumChunks:1048576} (0.00s) --- PASS: TestMultithreadCalculateNumChunks/{size:1048576_chunkSize:2_wantNumChunks:524288} (0.00s) --- PASS: TestMultithreadCalculateNumChunks/{size:1048577_chunkSize:2_wantNumChunks:524289} (0.00s) --- PASS: TestMultithreadCalculateNumChunks/{size:1048575_chunkSize:2_wantNumChunks:524288} (0.00s) === RUN TestMultithreadCopy run.go:185: Remote "Encrypted drive 'TestCryptDrive:rclone-test-varoqut0vomu'", Local "Local file system at /tmp/rclone2444379526", Modify Window "1ms" multithread_test.go:119: multithread writing not supported --- SKIP: TestMultithreadCopy (0.28s) === RUN TestMultithreadCopyAbort run.go:185: Remote "Encrypted drive 'TestCryptDrive:rclone-test-varoqut0vomu'", Local "Local file system at /tmp/rclone2444379526", Modify Window "1ms" multithread_test.go:119: multithread writing not supported --- SKIP: TestMultithreadCopyAbort (0.23s) === RUN TestSizeDiffers 2025/11/15 03:21:51 DEBUG : a: size = 0 OK 2025/11/15 03:21:51 DEBUG : a: size = 1 (memory) 2025/11/15 03:21:51 DEBUG : a: size = 2 (memory) --- PASS: TestSizeDiffers (0.00s) === RUN TestReOpen === RUN TestReOpen/Normal === RUN TestReOpen/Normal/Basics 2025/11/15 03:21:51 DEBUG : potato: Seek from 10 to 0 === RUN TestReOpen/Normal/ErrorAtStart === RUN TestReOpen/Normal/WithErrors 2025/11/15 03:21:51 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error 2025/11/15 03:21:51 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error 2025/11/15 03:21:51 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error === RUN TestReOpen/Normal/TooManyErrors 2025/11/15 03:21:51 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error 2025/11/15 03:21:51 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error 2025/11/15 03:21:51 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error 2025/11/15 03:21:51 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries === RUN TestReOpen/Normal/ReadAt 2025/11/15 03:21:51 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2025/11/15 03:21:51 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2025/11/15 03:21:51 DEBUG : potato: Seek from 5 to 1 === RUN TestReOpen/Normal/Seek 2025/11/15 03:21:51 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2025/11/15 03:21:51 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2025/11/15 03:21:51 DEBUG : potato: Seek from 5 to 2 === RUN TestReOpen/Normal/AccountRead === RUN TestReOpen/Normal/AccountReadDelay 2025/11/15 03:21:51 DEBUG : potato: Seek from 10 to 0 2025/11/15 03:21:51 DEBUG : potato: Seek from 10 to 0 2025/11/15 03:21:51 DEBUG : potato: Seek from 10 to 0 === RUN TestReOpen/Normal/AccountReadError === RUN TestReOpen/WithRangeOption === RUN TestReOpen/WithRangeOption/Basics 2025/11/15 03:21:51 DEBUG : potato: Seek from 7 to 0 === RUN TestReOpen/WithRangeOption/ErrorAtStart === RUN TestReOpen/WithRangeOption/WithErrors 2025/11/15 03:21:51 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error 2025/11/15 03:21:51 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error 2025/11/15 03:21:51 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error === RUN TestReOpen/WithRangeOption/TooManyErrors 2025/11/15 03:21:51 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error 2025/11/15 03:21:51 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error 2025/11/15 03:21:51 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error 2025/11/15 03:21:51 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries === RUN TestReOpen/WithRangeOption/ReadAt 2025/11/15 03:21:51 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2025/11/15 03:21:51 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2025/11/15 03:21:51 DEBUG : potato: Seek from 5 to 1 === RUN TestReOpen/WithRangeOption/Seek 2025/11/15 03:21:51 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2025/11/15 03:21:51 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2025/11/15 03:21:51 DEBUG : potato: Seek from 5 to 2 2025/11/15 03:21:51 DEBUG : potato: Seek from 7 to 4 === RUN TestReOpen/WithRangeOption/AccountRead === RUN TestReOpen/WithRangeOption/AccountReadDelay 2025/11/15 03:21:51 DEBUG : potato: Seek from 7 to 0 2025/11/15 03:21:51 DEBUG : potato: Seek from 7 to 0 2025/11/15 03:21:51 DEBUG : potato: Seek from 7 to 0 === RUN TestReOpen/WithRangeOption/AccountReadError === RUN TestReOpen/WithSeekOption === RUN TestReOpen/WithSeekOption/Basics 2025/11/15 03:21:51 DEBUG : potato: Seek from 8 to 0 === RUN TestReOpen/WithSeekOption/ErrorAtStart === RUN TestReOpen/WithSeekOption/WithErrors 2025/11/15 03:21:51 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error 2025/11/15 03:21:51 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error 2025/11/15 03:21:51 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error === RUN TestReOpen/WithSeekOption/TooManyErrors 2025/11/15 03:21:51 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error 2025/11/15 03:21:51 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error 2025/11/15 03:21:51 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error 2025/11/15 03:21:51 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries === RUN TestReOpen/WithSeekOption/ReadAt 2025/11/15 03:21:51 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2025/11/15 03:21:51 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2025/11/15 03:21:51 DEBUG : potato: Seek from 5 to 1 === RUN TestReOpen/WithSeekOption/Seek 2025/11/15 03:21:51 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2025/11/15 03:21:51 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2025/11/15 03:21:51 DEBUG : potato: Seek from 5 to 2 2025/11/15 03:21:51 DEBUG : potato: Seek from 7 to 5 === RUN TestReOpen/WithSeekOption/AccountRead === RUN TestReOpen/WithSeekOption/AccountReadDelay 2025/11/15 03:21:51 DEBUG : potato: Seek from 8 to 0 2025/11/15 03:21:51 DEBUG : potato: Seek from 8 to 0 2025/11/15 03:21:51 DEBUG : potato: Seek from 8 to 0 === RUN TestReOpen/WithSeekOption/AccountReadError === RUN TestReOpen/UnknownSize === RUN TestReOpen/UnknownSize/Basics 2025/11/15 03:21:51 DEBUG : potato: Seek from 9 to 0 === RUN TestReOpen/UnknownSize/ErrorAtStart === RUN TestReOpen/UnknownSize/WithErrors 2025/11/15 03:21:51 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error 2025/11/15 03:21:51 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error 2025/11/15 03:21:51 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error === RUN TestReOpen/UnknownSize/TooManyErrors 2025/11/15 03:21:51 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error 2025/11/15 03:21:51 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error 2025/11/15 03:21:51 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error 2025/11/15 03:21:51 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries === RUN TestReOpen/UnknownSize/ReadAt 2025/11/15 03:21:51 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2025/11/15 03:21:51 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2025/11/15 03:21:51 DEBUG : potato: Seek from 5 to 1 === RUN TestReOpen/UnknownSize/Seek 2025/11/15 03:21:51 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2025/11/15 03:21:51 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2025/11/15 03:21:51 DEBUG : potato: Seek from 5 to 2 2025/11/15 03:21:51 DEBUG : potato: Seek from 7 to 6 === RUN TestReOpen/UnknownSize/AccountRead === RUN TestReOpen/UnknownSize/AccountReadDelay 2025/11/15 03:21:51 DEBUG : potato: Seek from 9 to 0 2025/11/15 03:21:51 DEBUG : potato: Seek from 9 to 0 2025/11/15 03:21:51 DEBUG : potato: Seek from 9 to 0 === RUN TestReOpen/UnknownSize/AccountReadError --- PASS: TestReOpen (0.01s) --- PASS: TestReOpen/Normal (0.00s) --- PASS: TestReOpen/Normal/Basics (0.00s) --- PASS: TestReOpen/Normal/ErrorAtStart (0.00s) --- PASS: TestReOpen/Normal/WithErrors (0.00s) --- PASS: TestReOpen/Normal/TooManyErrors (0.00s) --- PASS: TestReOpen/Normal/ReadAt (0.00s) --- PASS: TestReOpen/Normal/Seek (0.00s) --- PASS: TestReOpen/Normal/AccountRead (0.00s) --- PASS: TestReOpen/Normal/AccountReadDelay (0.00s) --- PASS: TestReOpen/Normal/AccountReadError (0.00s) --- PASS: TestReOpen/WithRangeOption (0.00s) --- PASS: TestReOpen/WithRangeOption/Basics (0.00s) --- PASS: TestReOpen/WithRangeOption/ErrorAtStart (0.00s) --- PASS: TestReOpen/WithRangeOption/WithErrors (0.00s) --- PASS: TestReOpen/WithRangeOption/TooManyErrors (0.00s) --- PASS: TestReOpen/WithRangeOption/ReadAt (0.00s) --- PASS: TestReOpen/WithRangeOption/Seek (0.00s) --- PASS: TestReOpen/WithRangeOption/AccountRead (0.00s) --- PASS: TestReOpen/WithRangeOption/AccountReadDelay (0.00s) --- PASS: TestReOpen/WithRangeOption/AccountReadError (0.00s) --- PASS: TestReOpen/WithSeekOption (0.00s) --- PASS: TestReOpen/WithSeekOption/Basics (0.00s) --- PASS: TestReOpen/WithSeekOption/ErrorAtStart (0.00s) --- PASS: TestReOpen/WithSeekOption/WithErrors (0.00s) --- PASS: TestReOpen/WithSeekOption/TooManyErrors (0.00s) --- PASS: TestReOpen/WithSeekOption/ReadAt (0.00s) --- PASS: TestReOpen/WithSeekOption/Seek (0.00s) --- PASS: TestReOpen/WithSeekOption/AccountRead (0.00s) --- PASS: TestReOpen/WithSeekOption/AccountReadDelay (0.00s) --- PASS: TestReOpen/WithSeekOption/AccountReadError (0.00s) --- PASS: TestReOpen/UnknownSize (0.00s) --- PASS: TestReOpen/UnknownSize/Basics (0.00s) --- PASS: TestReOpen/UnknownSize/ErrorAtStart (0.00s) --- PASS: TestReOpen/UnknownSize/WithErrors (0.00s) --- PASS: TestReOpen/UnknownSize/TooManyErrors (0.00s) --- PASS: TestReOpen/UnknownSize/ReadAt (0.00s) --- PASS: TestReOpen/UnknownSize/Seek (0.00s) --- PASS: TestReOpen/UnknownSize/AccountRead (0.00s) --- PASS: TestReOpen/UnknownSize/AccountReadDelay (0.00s) --- PASS: TestReOpen/UnknownSize/AccountReadError (0.00s) === RUN TestCheck run.go:185: Remote "Encrypted drive 'TestCryptDrive:rclone-test-varoqut0vomu'", Local "Local file system at /tmp/rclone2444379526", Modify Window "1ms" 2025/11/15 03:21:53 DEBUG : rutabaga: md5 = cc58ba6f69ea424d464bc153094aeeac OK === RUN TestCheck/1 === RUN TestCheck/2 2025/11/15 03:21:55 DEBUG : empty space: md5 = fc28663edb834d67ea83ecea24232f8b OK === RUN TestCheck/3 2025/11/15 03:21:57 DEBUG : potato2: md5 = 0506d8ed57097b8b4c56fdbc2f2e2c2a OK === RUN TestCheck/4 === RUN TestCheck/5 2025/11/15 03:22:00 DEBUG : remotepotato: md5 = a9499ea6ec3c4f0c834037852a18a860 OK === RUN TestCheck/6 === RUN TestCheck/7 --- PASS: TestCheck (12.23s) --- PASS: TestCheck/1 (0.23s) --- PASS: TestCheck/2 (0.28s) --- PASS: TestCheck/3 (0.25s) --- PASS: TestCheck/4 (0.26s) --- PASS: TestCheck/5 (0.29s) --- PASS: TestCheck/6 (0.27s) --- PASS: TestCheck/7 (0.27s) === RUN TestCheckFsError 2025/11/15 03:22:03 DEBUG : Creating backend with remote "nonexistent" 2025/11/15 03:22:03 DEBUG : Creating backend with remote "nonexistent" 2025/11/15 03:22:03 DEBUG : Local file system at /home/rclone/go/src/github.com/rclone/rclone/fs/operations/nonexistent: Waiting for checks to finish 2025/11/15 03:22:03 ERROR : Local file system at /home/rclone/go/src/github.com/rclone/rclone/fs/operations/nonexistent: error reading source root directory: directory not found 2025/11/15 03:22:03 NOTICE: Local file system at /home/rclone/go/src/github.com/rclone/rclone/fs/operations/nonexistent: 0 differences found 2025/11/15 03:22:03 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-varoqut0vomu'", Local "Local file system at /tmp/rclone2444379526", Modify Window "1ms" 2025/11/15 03:22:05 DEBUG : rutabaga: md5 = ec3bcad8a7da45dfdb356a0792a607f6 OK === RUN TestCheckDownload/1 === RUN TestCheckDownload/2 2025/11/15 03:22:09 DEBUG : empty space: md5 = abaced034fb92cbde2a333d59724e69a OK === RUN TestCheckDownload/3 2025/11/15 03:22:12 DEBUG : potato2: md5 = 772f267516737995169c1593ccf777c5 OK === RUN TestCheckDownload/4 === RUN TestCheckDownload/5 2025/11/15 03:22:17 DEBUG : remotepotato: md5 = 0ebcfc46452657e0bccb2f400cf46706 OK === RUN TestCheckDownload/6 === RUN TestCheckDownload/7 --- PASS: TestCheckDownload (18.53s) --- PASS: TestCheckDownload/1 (1.13s) --- PASS: TestCheckDownload/2 (1.13s) --- PASS: TestCheckDownload/3 (1.15s) --- PASS: TestCheckDownload/4 (1.76s) --- PASS: TestCheckDownload/5 (1.20s) --- PASS: TestCheckDownload/6 (0.91s) --- PASS: TestCheckDownload/7 (1.24s) === RUN TestCheckSizeOnly run.go:185: Remote "Encrypted drive 'TestCryptDrive:rclone-test-varoqut0vomu'", Local "Local file system at /tmp/rclone2444379526", Modify Window "1ms" 2025/11/15 03:22:23 DEBUG : rutabaga: md5 = c871492e5136bf63be819b40a9af807a OK === RUN TestCheckSizeOnly/1 === RUN TestCheckSizeOnly/2 2025/11/15 03:22:25 DEBUG : empty space: md5 = e91a2d8aa6d034413d0fee127d62a19d OK === RUN TestCheckSizeOnly/3 2025/11/15 03:22:27 DEBUG : potato2: md5 = a5c1646805ad9be2a69b45e266ef1e58 OK === RUN TestCheckSizeOnly/4 === RUN TestCheckSizeOnly/5 2025/11/15 03:22:30 DEBUG : remotepotato: md5 = d0b5db3594ef17694ff59b314426cf74 OK === RUN TestCheckSizeOnly/6 === RUN TestCheckSizeOnly/7 --- PASS: TestCheckSizeOnly (11.33s) --- PASS: TestCheckSizeOnly/1 (0.26s) --- PASS: TestCheckSizeOnly/2 (0.25s) --- PASS: TestCheckSizeOnly/3 (0.30s) --- PASS: TestCheckSizeOnly/4 (0.27s) --- PASS: TestCheckSizeOnly/5 (0.26s) --- PASS: TestCheckSizeOnly/6 (0.26s) --- PASS: TestCheckSizeOnly/7 (0.28s) === RUN TestCheckEqualReaders --- PASS: TestCheckEqualReaders (0.00s) === RUN TestParseSumFile run.go:185: Remote "Encrypted drive 'TestCryptDrive:rclone-test-varoqut0vomu'", Local "Local file system at /tmp/rclone2444379526", Modify Window "1ms" 2025/11/15 03:22:34 DEBUG : test.sum: md5 = ae923fc7dc8b88ae233fda5303ffb03a OK 2025/11/15 03:22:36 NOTICE: test.sum: improperly formatted checksum line 4 2025/11/15 03:22:36 NOTICE: test.sum: improperly formatted checksum line 5 2025/11/15 03:22:36 NOTICE: test.sum: improperly formatted checksum line 6 2025/11/15 03:22:36 NOTICE: test.sum: 2 warning(s) suppressed... 2025/11/15 03:22:37 DEBUG : test.sum: md5 = 1b223354f4d26969336001b5f6541c5c OK 2025/11/15 03:22:39 NOTICE: test.sum: improperly formatted checksum line 4 2025/11/15 03:22:39 NOTICE: test.sum: improperly formatted checksum line 5 2025/11/15 03:22:39 NOTICE: test.sum: improperly formatted checksum line 6 2025/11/15 03:22:39 NOTICE: test.sum: 2 warning(s) suppressed... --- PASS: TestParseSumFile (7.01s) === RUN TestCheckSum run.go:185: Remote "Encrypted drive 'TestCryptDrive:rclone-test-varoqut0vomu'", Local "Local file system at /tmp/rclone2444379526", Modify Window "1ms" 2025/11/15 03:22:40 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-varoqut0vomu/data" 2025/11/15 03:22:40 DEBUG : Creating backend with remote "TestDrive:crypt/i7rtc15m75acpkcu99de1eu6ik7sc5tlp31a4jpi4vfn339844bg/vjrnln8ratgmqakfosrqe8espk" check_test.go:350: Encrypted drive 'TestCryptDrive:rclone-test-varoqut0vomu/data' lacks md5, skipping --- SKIP: TestCheckSum (2.08s) === RUN TestCheckSumDownload run.go:185: Remote "Encrypted drive 'TestCryptDrive:rclone-test-varoqut0vomu'", Local "Local file system at /tmp/rclone2444379526", Modify Window "1ms" 2025/11/15 03:22:42 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-varoqut0vomu/data" 2025/11/15 03:22:42 DEBUG : Creating backend with remote "TestDrive:crypt/i7rtc15m75acpkcu99de1eu6ik7sc5tlp31a4jpi4vfn339844bg/vjrnln8ratgmqakfosrqe8espk" 2025/11/15 03:22:46 DEBUG : data/banana: md5 = 8d54b59477552cd472648a6c92465a36 OK 2025/11/15 03:22:47 DEBUG : test.sum: md5 = 982d264fe9c1cbd124ef67070a78b819 OK === RUN TestCheckSumDownload/subtest1 2025/11/15 03:22:52 DEBUG : data/potato: md5 = 067e9231be5928646c8938433b31cc46 OK 2025/11/15 03:22:53 DEBUG : test.sum: md5 = 8358414d8c9924e84248068d2d96d4b4 OK === RUN TestCheckSumDownload/subtest2 2025/11/15 03:22:58 DEBUG : test.sum: md5 = 350d0a2ff92c2f76638d9ab8f87e7780 OK === RUN TestCheckSumDownload/subtest3 2025/11/15 03:23:02 DEBUG : test.sum: md5 = 393c20e27ab35973b52f59f10e0fa00b OK === RUN TestCheckSumDownload/subtest4 2025/11/15 03:23:07 DEBUG : test.sum: md5 = a5d6b79314e86603edee5a35c70bb5d0 OK === RUN TestCheckSumDownload/subtest5 2025/11/15 03:23:13 DEBUG : test.sum: md5 = a5bb915ca160f98ce1517144a1aebfdc OK === RUN TestCheckSumDownload/subtest6 2025/11/15 03:23:17 DEBUG : data/banana: md5 = 123211e7ab7591ccc1bfc6f1afb02dc2 OK 2025/11/15 03:23:18 DEBUG : data/potato: md5 = 52d9148ab49821907a0c5ce54ca4597c OK 2025/11/15 03:23:20 DEBUG : test.sum: md5 = 5b70d2a1b0d598e6c91c37623665c1b1 OK === RUN TestCheckSumDownload/subtest7 2025/11/15 03:23:25 DEBUG : vjrnln8ratgmqakfosrqe8espk: Rmdir: contains trashed file: "igjr4utqjj43i7bn8okrqetjm0" 2025/11/15 03:23:25 DEBUG : vjrnln8ratgmqakfosrqe8espk: Rmdir: contains trashed file: "2m507n64kafsi59v9q71r281a4" --- PASS: TestCheckSumDownload (44.15s) --- PASS: TestCheckSumDownload/subtest1 (2.45s) --- PASS: TestCheckSumDownload/subtest2 (2.00s) --- PASS: TestCheckSumDownload/subtest3 (2.20s) --- PASS: TestCheckSumDownload/subtest4 (2.72s) --- PASS: TestCheckSumDownload/subtest5 (2.34s) --- PASS: TestCheckSumDownload/subtest6 (2.13s) --- PASS: TestCheckSumDownload/subtest7 (2.93s) === RUN TestApplyTransforms 2025/11/15 03:23:26 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-xulezig7yuke" 2025/11/15 03:23:26 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/11/15 03:23:26 DEBUG : Creating backend with remote "TestDrive:crypt/15g5323qpo8dusonka0os68ic0eno9qeb3tuj550q16ttergotbg" 2025/11/15 03:23:27 DEBUG : Creating backend with remote "/tmp/rclone4253821626" run.go:185: Remote "Encrypted drive 'TestCryptDrive:rclone-test-xulezig7yuke'", Local "Local file system at /tmp/rclone4253821626", Modify Window "1ms" 2025/11/15 03:23:30 DEBUG : hello, world!: md5 = e61adc974c5543402f5aeeb513ffcf5c OK upper checkfile vs. lower remote (without normalization) 2025/11/15 03:23:30 ERROR : hello, world!: sum not found 2025/11/15 03:23:30 ERROR : HELLO, WORLD!: file not in Encrypted drive 'TestCryptDrive:rclone-test-xulezig7yuke' 2025/11/15 03:23:30 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-xulezig7yuke': 1 files missing 2025/11/15 03:23:30 NOTICE: 1 hashes missing 2025/11/15 03:23:30 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-xulezig7yuke': 1 differences found 2025/11/15 03:23:30 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-xulezig7yuke': 2 errors while checking upper checkfile vs. lower remote (with normalization) 2025/11/15 03:23:31 DEBUG : hello, world!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2025/11/15 03:23:31 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-xulezig7yuke': 0 differences found 2025/11/15 03:23:31 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-xulezig7yuke': 1 matching files 2025/11/15 03:23:31 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-fajoxis5fopo" 2025/11/15 03:23:31 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/11/15 03:23:31 DEBUG : Creating backend with remote "TestDrive:crypt/9d34gcfecrbio3iti3a42p9qjvlevo4ln7n3r871v9275bf64r3g" 2025/11/15 03:23:32 DEBUG : Creating backend with remote "/tmp/rclone967097170" run.go:185: Remote "Encrypted drive 'TestCryptDrive:rclone-test-fajoxis5fopo'", Local "Local file system at /tmp/rclone967097170", Modify Window "1ms" 2025/11/15 03:23:35 DEBUG : HELLO, WORLD!: md5 = 8ee62408cfaa64e223cdb9f90409dfc2 OK lower checkfile vs. upper remote (without normalization) 2025/11/15 03:23:35 ERROR : HELLO, WORLD!: sum not found 2025/11/15 03:23:35 ERROR : hello, world!: file not in Encrypted drive 'TestCryptDrive:rclone-test-fajoxis5fopo' 2025/11/15 03:23:35 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-fajoxis5fopo': 1 files missing 2025/11/15 03:23:35 NOTICE: 1 hashes missing 2025/11/15 03:23:35 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-fajoxis5fopo': 1 differences found 2025/11/15 03:23:35 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-fajoxis5fopo': 2 errors while checking lower checkfile vs. upper remote (with normalization) 2025/11/15 03:23:37 DEBUG : HELLO, WORLD!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2025/11/15 03:23:37 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-fajoxis5fopo': 0 differences found 2025/11/15 03:23:37 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-fajoxis5fopo': 1 matching files 2025/11/15 03:23:37 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-dilavaz8seti" 2025/11/15 03:23:37 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/11/15 03:23:37 DEBUG : Creating backend with remote "TestDrive:crypt/tkksivqfqq25e0gifgfstp3jvglvt689vh8i29uljg3n49k38n90" 2025/11/15 03:23:38 DEBUG : Creating backend with remote "/tmp/rclone281258779" run.go:185: Remote "Encrypted drive 'TestCryptDrive:rclone-test-dilavaz8seti'", Local "Local file system at /tmp/rclone281258779", Modify Window "1ms" 2025/11/15 03:23:40 DEBUG : HeLlO, wOrLd!: md5 = 871f23c1615976201c4b8bc421b03b23 OK lower checkfile vs. upperlowermixed remote (without normalization) 2025/11/15 03:23:41 ERROR : HeLlO, wOrLd!: sum not found 2025/11/15 03:23:41 ERROR : hello, world!: file not in Encrypted drive 'TestCryptDrive:rclone-test-dilavaz8seti' 2025/11/15 03:23:41 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-dilavaz8seti': 1 files missing 2025/11/15 03:23:41 NOTICE: 1 hashes missing 2025/11/15 03:23:41 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-dilavaz8seti': 1 differences found 2025/11/15 03:23:41 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-dilavaz8seti': 2 errors while checking lower checkfile vs. upperlowermixed remote (with normalization) 2025/11/15 03:23:42 DEBUG : HeLlO, wOrLd!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2025/11/15 03:23:42 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-dilavaz8seti': 0 differences found 2025/11/15 03:23:42 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-dilavaz8seti': 1 matching files 2025/11/15 03:23:42 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-ripopip9gogu" 2025/11/15 03:23:42 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/11/15 03:23:42 DEBUG : Creating backend with remote "TestDrive:crypt/bosjsev0f1konr5ctcbn4r1ueo48fc327b7ff74i7cvj4re09750" 2025/11/15 03:23:43 DEBUG : Creating backend with remote "/tmp/rclone1962722831" run.go:185: Remote "Encrypted drive 'TestCryptDrive:rclone-test-ripopip9gogu'", Local "Local file system at /tmp/rclone1962722831", Modify Window "1ms" 2025/11/15 03:23:45 DEBUG : HELLO, WORLD!: md5 = ca4e5bd6952c0b24b31aaff6ad1682ae OK upperlowermixed checkfile vs. upper remote (without normalization) 2025/11/15 03:23:46 ERROR : HELLO, WORLD!: sum not found 2025/11/15 03:23:46 ERROR : HeLlO, wOrLd!: file not in Encrypted drive 'TestCryptDrive:rclone-test-ripopip9gogu' 2025/11/15 03:23:46 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-ripopip9gogu': 1 files missing 2025/11/15 03:23:46 NOTICE: 1 hashes missing 2025/11/15 03:23:46 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-ripopip9gogu': 1 differences found 2025/11/15 03:23:46 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-ripopip9gogu': 2 errors while checking upperlowermixed checkfile vs. upper remote (with normalization) 2025/11/15 03:23:47 DEBUG : HELLO, WORLD!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2025/11/15 03:23:47 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-ripopip9gogu': 0 differences found 2025/11/15 03:23:47 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-ripopip9gogu': 1 matching files 2025/11/15 03:23:47 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-yigopoj0wavu" 2025/11/15 03:23:47 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/11/15 03:23:47 DEBUG : Creating backend with remote "TestDrive:crypt/emb9o0hum8rl4c7q738ok5p0hkvi4j5m7mg57jfs3rp3cf8j4e9g" 2025/11/15 03:23:48 DEBUG : Creating backend with remote "/tmp/rclone1763933715" run.go:185: Remote "Encrypted drive 'TestCryptDrive:rclone-test-yigopoj0wavu'", Local "Local file system at /tmp/rclone1763933715", Modify Window "1ms" 2025/11/15 03:23:51 DEBUG : 測試_Русский___ě_áñ: md5 = 84be62b2ecc68f29eb631cc3d6870ac2 OK NFD checkfile vs. NFC remote (without normalization) 2025/11/15 03:23:52 ERROR : 測試_Русский___ě_áñ: sum not found 2025/11/15 03:23:52 ERROR : 測試_Русский___ě_áñ: file not in Encrypted drive 'TestCryptDrive:rclone-test-yigopoj0wavu' 2025/11/15 03:23:52 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-yigopoj0wavu': 1 files missing 2025/11/15 03:23:52 NOTICE: 1 hashes missing 2025/11/15 03:23:52 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-yigopoj0wavu': 1 differences found 2025/11/15 03:23:52 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-yigopoj0wavu': 2 errors while checking NFD checkfile vs. NFC remote (with normalization) 2025/11/15 03:23:53 DEBUG : 測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2025/11/15 03:23:53 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-yigopoj0wavu': 0 differences found 2025/11/15 03:23:53 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-yigopoj0wavu': 1 matching files 2025/11/15 03:23:53 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-xesenib1buro" 2025/11/15 03:23:53 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/11/15 03:23:53 DEBUG : Creating backend with remote "TestDrive:crypt/8curifmb7rc15a8pt4v21laea8lejj187ku6kc54861nlekljar0" 2025/11/15 03:23:54 DEBUG : Creating backend with remote "/tmp/rclone2571918352" run.go:185: Remote "Encrypted drive 'TestCryptDrive:rclone-test-xesenib1buro'", Local "Local file system at /tmp/rclone2571918352", Modify Window "1ms" 2025/11/15 03:23:56 DEBUG : 測試_Русский___ě_áñ: md5 = ab04827387bf0a23c704d0961dc87e7a OK NFC checkfile vs. NFD remote (without normalization) 2025/11/15 03:23:57 ERROR : 測試_Русский___ě_áñ: sum not found 2025/11/15 03:23:57 ERROR : 測試_Русский___ě_áñ: file not in Encrypted drive 'TestCryptDrive:rclone-test-xesenib1buro' 2025/11/15 03:23:57 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-xesenib1buro': 1 files missing 2025/11/15 03:23:57 NOTICE: 1 hashes missing 2025/11/15 03:23:57 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-xesenib1buro': 1 differences found 2025/11/15 03:23:57 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-xesenib1buro': 2 errors while checking NFC checkfile vs. NFD remote (with normalization) 2025/11/15 03:23:58 DEBUG : 測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2025/11/15 03:23:58 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-xesenib1buro': 0 differences found 2025/11/15 03:23:58 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-xesenib1buro': 1 matching files 2025/11/15 03:23:58 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-mizokij1fisu" 2025/11/15 03:23:58 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/11/15 03:23:58 DEBUG : Creating backend with remote "TestDrive:crypt/ri7a6sgi7ofisljss1f7ea9fadvvefkm674f67c0sj9n4decmhkg" 2025/11/15 03:23:59 DEBUG : Creating backend with remote "/tmp/rclone2396556197" run.go:185: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mizokij1fisu'", Local "Local file system at /tmp/rclone2396556197", Modify Window "1ms" 2025/11/15 03:24:02 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 2ee931661633c1e3b1af545e40afe441 OK NFDx2 checkfile vs. both remote (without normalization) 2025/11/15 03:24:02 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found 2025/11/15 03:24:02 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in Encrypted drive 'TestCryptDrive:rclone-test-mizokij1fisu' 2025/11/15 03:24:02 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-mizokij1fisu': 1 files missing 2025/11/15 03:24:02 NOTICE: 1 hashes missing 2025/11/15 03:24:02 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-mizokij1fisu': 1 differences found 2025/11/15 03:24:02 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-mizokij1fisu': 2 errors while checking NFDx2 checkfile vs. both remote (with normalization) 2025/11/15 03:24:03 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2025/11/15 03:24:03 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-mizokij1fisu': 0 differences found 2025/11/15 03:24:03 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-mizokij1fisu': 1 matching files 2025/11/15 03:24:03 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-jabufak3jemi" 2025/11/15 03:24:03 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/11/15 03:24:03 DEBUG : Creating backend with remote "TestDrive:crypt/ln2rh5jjja2ief9kefpsqh6fh9dh3tpdt203d866pecdo6e80uog" 2025/11/15 03:24:04 DEBUG : Creating backend with remote "/tmp/rclone1557614386" run.go:185: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jabufak3jemi'", Local "Local file system at /tmp/rclone1557614386", Modify Window "1ms" 2025/11/15 03:24:06 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 4ad69abfc41ede5e4562ebb474bc5ef8 OK NFCx2 checkfile vs. both remote (without normalization) 2025/11/15 03:24:07 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found 2025/11/15 03:24:07 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in Encrypted drive 'TestCryptDrive:rclone-test-jabufak3jemi' 2025/11/15 03:24:07 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-jabufak3jemi': 1 files missing 2025/11/15 03:24:07 NOTICE: 1 hashes missing 2025/11/15 03:24:07 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-jabufak3jemi': 1 differences found 2025/11/15 03:24:07 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-jabufak3jemi': 2 errors while checking NFCx2 checkfile vs. both remote (with normalization) 2025/11/15 03:24:08 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2025/11/15 03:24:08 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-jabufak3jemi': 0 differences found 2025/11/15 03:24:08 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-jabufak3jemi': 1 matching files 2025/11/15 03:24:08 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-hugayes7rugi" 2025/11/15 03:24:08 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/11/15 03:24:08 DEBUG : Creating backend with remote "TestDrive:crypt/u4ek9m8agobmbr8hipb39odrggd1ccq78lg9n7hn50drvgk0mjg0" 2025/11/15 03:24:09 DEBUG : Creating backend with remote "/tmp/rclone420714378" run.go:185: Remote "Encrypted drive 'TestCryptDrive:rclone-test-hugayes7rugi'", Local "Local file system at /tmp/rclone420714378", Modify Window "1ms" 2025/11/15 03:24:12 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = a8e30eaf02d6867c5fb7d4f86644f70f OK both checkfile vs. NFDx2 remote (without normalization) 2025/11/15 03:24:12 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found 2025/11/15 03:24:12 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in Encrypted drive 'TestCryptDrive:rclone-test-hugayes7rugi' 2025/11/15 03:24:12 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-hugayes7rugi': 1 files missing 2025/11/15 03:24:12 NOTICE: 1 hashes missing 2025/11/15 03:24:12 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-hugayes7rugi': 1 differences found 2025/11/15 03:24:12 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-hugayes7rugi': 2 errors while checking both checkfile vs. NFDx2 remote (with normalization) 2025/11/15 03:24:13 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2025/11/15 03:24:13 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-hugayes7rugi': 0 differences found 2025/11/15 03:24:13 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-hugayes7rugi': 1 matching files 2025/11/15 03:24:13 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-teweger6mesu" 2025/11/15 03:24:13 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/11/15 03:24:13 DEBUG : Creating backend with remote "TestDrive:crypt/8t31s5ek6m5tq6e6uq20pvmhcg6sqik91rdc4a99hn591anmrufg" 2025/11/15 03:24:14 DEBUG : Creating backend with remote "/tmp/rclone3099434385" run.go:185: Remote "Encrypted drive 'TestCryptDrive:rclone-test-teweger6mesu'", Local "Local file system at /tmp/rclone3099434385", Modify Window "1ms" 2025/11/15 03:24:17 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = e7d3d6d19328c81726cad4dc5c109060 OK both checkfile vs. NFCx2 remote (without normalization) 2025/11/15 03:24:17 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found 2025/11/15 03:24:17 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in Encrypted drive 'TestCryptDrive:rclone-test-teweger6mesu' 2025/11/15 03:24:17 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-teweger6mesu': 1 files missing 2025/11/15 03:24:17 NOTICE: 1 hashes missing 2025/11/15 03:24:17 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-teweger6mesu': 1 differences found 2025/11/15 03:24:17 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-teweger6mesu': 2 errors while checking both checkfile vs. NFCx2 remote (with normalization) 2025/11/15 03:24:19 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2025/11/15 03:24:19 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-teweger6mesu': 0 differences found 2025/11/15 03:24:19 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-teweger6mesu': 1 matching files 2025/11/15 03:24:19 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-teweger6mesu': Purge remote 2025/11/15 03:24:19 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-hugayes7rugi': Purge remote 2025/11/15 03:24:20 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-jabufak3jemi': Purge remote 2025/11/15 03:24:20 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-mizokij1fisu': Purge remote 2025/11/15 03:24:20 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-xesenib1buro': Purge remote 2025/11/15 03:24:21 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-yigopoj0wavu': Purge remote 2025/11/15 03:24:21 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-ripopip9gogu': Purge remote 2025/11/15 03:24:22 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-dilavaz8seti': Purge remote 2025/11/15 03:24:22 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-fajoxis5fopo': Purge remote 2025/11/15 03:24:23 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-xulezig7yuke': Purge remote --- PASS: TestApplyTransforms (57.02s) === RUN TestTruncateString --- PASS: TestTruncateString (0.00s) === RUN TestCopyFile run.go:185: Remote "Encrypted drive 'TestCryptDrive:rclone-test-varoqut0vomu'", Local "Local file system at /tmp/rclone2444379526", Modify Window "1ms" 2025/11/15 03:24:23 DEBUG : file1: Need to transfer - File not found at Destination 2025/11/15 03:24:26 DEBUG : sub/file2: md5 = a3a982a13c41d9ca382c0afa4941b12e OK 2025/11/15 03:24:26 DEBUG : sub/file2: size = 14 OK 2025/11/15 03:24:26 INFO : file1: Copied (new) to: sub/file2 2025/11/15 03:24:26 DEBUG : sub/file2: size = 14 OK 2025/11/15 03:24:26 DEBUG : file1: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2025/11/15 03:24:26 DEBUG : file1: Unchanged skipping 2025/11/15 03:24:27 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-varoqut0vomu': don't need to copy/move sub/file2, it is already at target location 2025/11/15 03:24:29 DEBUG : 150fuo3cn4j1uenq6r4g4qk6t4: Rmdir: contains trashed file: "d26uovf0miqhd1mu3c9jbseq00" --- PASS: TestCopyFile (6.41s) === RUN TestCopyLongFile run.go:185: Remote "Encrypted drive 'TestCryptDrive:rclone-test-varoqut0vomu'", Local "Local file system at /tmp/rclone2444379526", Modify Window "1ms" copy_test.go:154: Test only runs on local --- SKIP: TestCopyLongFile (0.54s) === RUN TestCopyFileBackupDir run.go:185: Remote "Encrypted drive 'TestCryptDrive:rclone-test-varoqut0vomu'", Local "Local file system at /tmp/rclone2444379526", Modify Window "1ms" 2025/11/15 03:24:32 DEBUG : dst/file1: md5 = 43188b8723c38a1db05549baa9f53def OK 2025/11/15 03:24:33 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-varoqut0vomu/backup" 2025/11/15 03:24:33 DEBUG : Creating backend with remote "TestDrive:crypt/i7rtc15m75acpkcu99de1eu6ik7sc5tlp31a4jpi4vfn339844bg/1nrff024r7pq65ecp72fc28jb0" 2025/11/15 03:24:35 DEBUG : dst/file1: size = 14 (Local file system at /tmp/rclone2444379526) 2025/11/15 03:24:35 DEBUG : dst/file1: size = 18 (Encrypted drive 'TestCryptDrive:rclone-test-varoqut0vomu') 2025/11/15 03:24:35 DEBUG : dst/file1: Sizes differ 2025/11/15 03:24:37 INFO : dst/file1: Moved (server-side) 2025/11/15 03:24:39 DEBUG : dst/file1: md5 = 5f23b4178da8cc71058b586415192578 OK 2025/11/15 03:24:39 DEBUG : dst/file1: size = 14 OK 2025/11/15 03:24:39 INFO : dst/file1: Copied (new) 2025/11/15 03:24:41 DEBUG : 31u3jie661vd5p8j7rtc3hgbh0: Rmdir: contains trashed file: "eer8kka55qnghc34cq76ca668g" 2025/11/15 03:24:42 DEBUG : 1nrff024r7pq65ecp72fc28jb0/31u3jie661vd5p8j7rtc3hgbh0: Rmdir: contains trashed file: "eer8kka55qnghc34cq76ca668g" 2025/11/15 03:24:43 DEBUG : 1nrff024r7pq65ecp72fc28jb0: Rmdir: contains trashed file: "31u3jie661vd5p8j7rtc3hgbh0" --- PASS: TestCopyFileBackupDir (13.46s) === RUN TestCopyFileCompareDest run.go:185: Remote "Encrypted drive 'TestCryptDrive:rclone-test-varoqut0vomu'", Local "Local file system at /tmp/rclone2444379526", Modify Window "1ms" 2025/11/15 03:24:44 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-varoqut0vomu/dst" 2025/11/15 03:24:44 DEBUG : Creating backend with remote "TestDrive:crypt/i7rtc15m75acpkcu99de1eu6ik7sc5tlp31a4jpi4vfn339844bg/31u3jie661vd5p8j7rtc3hgbh0" 2025/11/15 03:24:45 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-varoqut0vomu/CompareDest" 2025/11/15 03:24:45 DEBUG : Creating backend with remote "TestDrive:crypt/i7rtc15m75acpkcu99de1eu6ik7sc5tlp31a4jpi4vfn339844bg/gveqi14airsml4bgu7krj116o8" 2025/11/15 03:24:47 DEBUG : one: Need to transfer - File not found at Destination 2025/11/15 03:24:49 DEBUG : one: md5 = d29cf1d7d91d4adc07ee97f9dfad9690 OK 2025/11/15 03:24:49 DEBUG : one: size = 3 OK 2025/11/15 03:24:49 INFO : one: Copied (new) 2025/11/15 03:24:51 DEBUG : one: size = 5 (Local file system at /tmp/rclone2444379526) 2025/11/15 03:24:51 DEBUG : one: size = 3 (Encrypted drive 'TestCryptDrive:rclone-test-varoqut0vomu/dst') 2025/11/15 03:24:51 DEBUG : one: Sizes differ 2025/11/15 03:24:52 DEBUG : one: md5 = 78fe388008cb3a7cce90c680d9670f8a OK 2025/11/15 03:24:52 DEBUG : one: size = 5 OK 2025/11/15 03:24:52 INFO : one: Copied (replaced existing) 2025/11/15 03:24:54 DEBUG : dst/one: md5 = 0b3aaa931e4178ef1f22a96837ac2725 OK 2025/11/15 03:24:56 DEBUG : CompareDest/one: md5 = f46849cf68b97b5205c464c25a085561 OK 2025/11/15 03:24:57 DEBUG : one: size = 5 (Local file system at /tmp/rclone2444379526) 2025/11/15 03:24:57 DEBUG : one: size = 3 (Encrypted drive 'TestCryptDrive:rclone-test-varoqut0vomu/dst') 2025/11/15 03:24:57 DEBUG : one: Sizes differ 2025/11/15 03:24:57 DEBUG : one: size = 5 OK 2025/11/15 03:24:57 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2025/11/15 03:24:57 DEBUG : one: Destination found in --compare-dest, skipping 2025/11/15 03:25:00 DEBUG : CompareDest/two: md5 = 10bc7c1a972933d0312083adf719837a OK 2025/11/15 03:25:00 DEBUG : two: Need to transfer - File not found at Destination 2025/11/15 03:25:01 DEBUG : two: size = 3 OK 2025/11/15 03:25:01 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2025/11/15 03:25:01 DEBUG : two: Destination found in --compare-dest, skipping 2025/11/15 03:25:01 DEBUG : two: Need to transfer - File not found at Destination 2025/11/15 03:25:02 DEBUG : two: size = 3 OK 2025/11/15 03:25:02 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2025/11/15 03:25:02 DEBUG : two: Destination found in --compare-dest, skipping 2025/11/15 03:25:03 DEBUG : two: Need to transfer - File not found at Destination 2025/11/15 03:25:03 DEBUG : two: size = 5 (Local file system at /tmp/rclone2444379526) 2025/11/15 03:25:03 DEBUG : two: size = 3 (Encrypted drive 'TestCryptDrive:rclone-test-varoqut0vomu/CompareDest') 2025/11/15 03:25:03 DEBUG : two: Sizes differ 2025/11/15 03:25:05 DEBUG : two: md5 = 71bcc4049ef127ba45163f7c552f307b OK 2025/11/15 03:25:05 DEBUG : two: size = 5 OK 2025/11/15 03:25:05 INFO : two: Copied (new) 2025/11/15 03:25:08 DEBUG : 31u3jie661vd5p8j7rtc3hgbh0: Rmdir: contains trashed file: "keafetfvtbmgo6tsdvcbj32i0k" 2025/11/15 03:25:08 DEBUG : 31u3jie661vd5p8j7rtc3hgbh0: Rmdir: contains trashed file: "0828u6q2p8d62qeklrkkuf10uc" 2025/11/15 03:25:09 DEBUG : gveqi14airsml4bgu7krj116o8: Rmdir: contains trashed file: "keafetfvtbmgo6tsdvcbj32i0k" 2025/11/15 03:25:09 DEBUG : gveqi14airsml4bgu7krj116o8: Rmdir: contains trashed file: "0828u6q2p8d62qeklrkkuf10uc" --- PASS: TestCopyFileCompareDest (25.88s) === RUN TestCopyFileCopyDest run.go:185: Remote "Encrypted drive 'TestCryptDrive:rclone-test-varoqut0vomu'", Local "Local file system at /tmp/rclone2444379526", Modify Window "1ms" 2025/11/15 03:25:09 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-varoqut0vomu/dst" 2025/11/15 03:25:09 DEBUG : Creating backend with remote "TestDrive:crypt/i7rtc15m75acpkcu99de1eu6ik7sc5tlp31a4jpi4vfn339844bg/31u3jie661vd5p8j7rtc3hgbh0" 2025/11/15 03:25:11 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-varoqut0vomu/CopyDest" 2025/11/15 03:25:11 DEBUG : Creating backend with remote "TestDrive:crypt/i7rtc15m75acpkcu99de1eu6ik7sc5tlp31a4jpi4vfn339844bg/d09o6po3f7bm6ce32vdgs8h9ls" 2025/11/15 03:25:13 DEBUG : one: Need to transfer - File not found at Destination 2025/11/15 03:25:16 DEBUG : one: md5 = e561412bd3760b815cce19866892ff8d OK 2025/11/15 03:25:16 DEBUG : one: size = 3 OK 2025/11/15 03:25:16 INFO : one: Copied (new) 2025/11/15 03:25:17 DEBUG : one: size = 5 (Local file system at /tmp/rclone2444379526) 2025/11/15 03:25:17 DEBUG : one: size = 3 (Encrypted drive 'TestCryptDrive:rclone-test-varoqut0vomu/dst') 2025/11/15 03:25:17 DEBUG : one: Sizes differ 2025/11/15 03:25:19 DEBUG : one: md5 = af90fcf556cef4edcbc60a875c98749e OK 2025/11/15 03:25:19 DEBUG : one: size = 5 OK 2025/11/15 03:25:19 INFO : one: Copied (replaced existing) 2025/11/15 03:25:21 DEBUG : dst/one: md5 = e31be313670965a536124179fbaa29a9 OK 2025/11/15 03:25:23 DEBUG : CopyDest/one: md5 = 58e53ce2bb099c16c10acf7fd3879d84 OK 2025/11/15 03:25:24 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-varoqut0vomu/BackupDir" 2025/11/15 03:25:24 DEBUG : Creating backend with remote "TestDrive:crypt/i7rtc15m75acpkcu99de1eu6ik7sc5tlp31a4jpi4vfn339844bg/s6dbk3lfi7c9kfvo6j7bla9m0g" 2025/11/15 03:25:25 DEBUG : one: size = 5 (Local file system at /tmp/rclone2444379526) 2025/11/15 03:25:25 DEBUG : one: size = 3 (Encrypted drive 'TestCryptDrive:rclone-test-varoqut0vomu/dst') 2025/11/15 03:25:25 DEBUG : one: Sizes differ 2025/11/15 03:25:26 DEBUG : one: size = 5 OK 2025/11/15 03:25:26 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2025/11/15 03:25:26 DEBUG : one: size = 5 (Local file system at /tmp/rclone2444379526) 2025/11/15 03:25:26 DEBUG : one: size = 3 (Encrypted drive 'TestCryptDrive:rclone-test-varoqut0vomu/dst') 2025/11/15 03:25:26 DEBUG : one: Sizes differ 2025/11/15 03:25:27 INFO : one: Moved (server-side) 2025/11/15 03:25:28 DEBUG : one: size = 5 OK 2025/11/15 03:25:28 INFO : one: Copied (server-side copy) 2025/11/15 03:25:28 DEBUG : one: Destination found in --copy-dest, using server-side copy 2025/11/15 03:25:31 DEBUG : CopyDest/two: md5 = 67c4f213d3587fa7296dc876a3ee12fc OK 2025/11/15 03:25:32 DEBUG : two: Need to transfer - File not found at Destination 2025/11/15 03:25:32 DEBUG : two: size = 3 OK 2025/11/15 03:25:32 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2025/11/15 03:25:33 DEBUG : two: size = 3 OK 2025/11/15 03:25:33 INFO : two: Copied (server-side copy) 2025/11/15 03:25:33 DEBUG : two: Destination found in --copy-dest, using server-side copy 2025/11/15 03:25:34 DEBUG : two: size = 3 OK 2025/11/15 03:25:34 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2025/11/15 03:25:34 DEBUG : two: Unchanged skipping 2025/11/15 03:25:36 DEBUG : CopyDest/three: md5 = 6dba8b43801465c65ca42549057bb2ea OK 2025/11/15 03:25:37 DEBUG : three: Need to transfer - File not found at Destination 2025/11/15 03:25:37 DEBUG : three: size = 7 (Local file system at /tmp/rclone2444379526) 2025/11/15 03:25:37 DEBUG : three: size = 5 (Encrypted drive 'TestCryptDrive:rclone-test-varoqut0vomu/CopyDest') 2025/11/15 03:25:37 DEBUG : three: Sizes differ 2025/11/15 03:25:37 DEBUG : three: Destination not found in --copy-dest 2025/11/15 03:25:39 DEBUG : three: md5 = c1cbed7cd09029cf22a4a06aea6e90e5 OK 2025/11/15 03:25:39 DEBUG : three: size = 7 OK 2025/11/15 03:25:39 INFO : three: Copied (new) 2025/11/15 03:25:43 DEBUG : 31u3jie661vd5p8j7rtc3hgbh0: Rmdir: contains trashed file: "b6jdmn2b1adrhc8vikh7edr9os" 2025/11/15 03:25:43 DEBUG : 31u3jie661vd5p8j7rtc3hgbh0: Rmdir: contains trashed file: "keafetfvtbmgo6tsdvcbj32i0k" 2025/11/15 03:25:43 DEBUG : 31u3jie661vd5p8j7rtc3hgbh0: Rmdir: contains trashed file: "0828u6q2p8d62qeklrkkuf10uc" 2025/11/15 03:25:44 DEBUG : d09o6po3f7bm6ce32vdgs8h9ls: Rmdir: contains trashed file: "0828u6q2p8d62qeklrkkuf10uc" 2025/11/15 03:25:44 DEBUG : d09o6po3f7bm6ce32vdgs8h9ls: Rmdir: contains trashed file: "keafetfvtbmgo6tsdvcbj32i0k" 2025/11/15 03:25:44 DEBUG : d09o6po3f7bm6ce32vdgs8h9ls: Rmdir: contains trashed file: "b6jdmn2b1adrhc8vikh7edr9os" 2025/11/15 03:25:45 DEBUG : s6dbk3lfi7c9kfvo6j7bla9m0g: Rmdir: contains trashed file: "0828u6q2p8d62qeklrkkuf10uc" --- PASS: TestCopyFileCopyDest (36.16s) === RUN TestCopyInplace run.go:185: Remote "Encrypted drive 'TestCryptDrive:rclone-test-varoqut0vomu'", Local "Local file system at /tmp/rclone2444379526", Modify Window "1ms" copy_test.go:371: Partial uploads not supported --- SKIP: TestCopyInplace (0.84s) === RUN TestCopyLongFileName run.go:185: Remote "Encrypted drive 'TestCryptDrive:rclone-test-varoqut0vomu'", Local "Local file system at /tmp/rclone2444379526", Modify Window "1ms" copy_test.go:404: Partial uploads not supported --- SKIP: TestCopyLongFileName (0.47s) === RUN TestCopyLongFileNameCollision run.go:185: Remote "Encrypted drive 'TestCryptDrive:rclone-test-varoqut0vomu'", Local "Local file system at /tmp/rclone2444379526", Modify Window "1ms" copy_test.go:437: Partial uploads not supported --- SKIP: TestCopyLongFileNameCollision (0.50s) === RUN TestCopyFileMaxTransfer run.go:185: Remote "Encrypted drive 'TestCryptDrive:rclone-test-varoqut0vomu'", Local "Local file system at /tmp/rclone2444379526", Modify Window "1ms" 2025/11/15 03:25:48 DEBUG : TestCopyFileMaxTransfer/file1: Need to transfer - File not found at Destination 2025/11/15 03:25:50 DEBUG : TestCopyFileMaxTransfer/file1: md5 = 5ed7dda3a390912d3cc24e2f90e9a210 OK 2025/11/15 03:25:50 DEBUG : TestCopyFileMaxTransfer/file1: size = 14 OK 2025/11/15 03:25:50 INFO : TestCopyFileMaxTransfer/file1: Copied (new) 2025/11/15 03:25:51 DEBUG : TestCopyFileMaxTransfer/file2: Need to transfer - File not found at Destination 2025/11/15 03:25:51 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 2025/11/15 03:25:52 DEBUG : TestCopyFileMaxTransfer/file3: Need to transfer - File not found at Destination 2025/11/15 03:25:52 DEBUG : TestCopyFileMaxTransfer/file4: Need to transfer - File not found at Destination 2025/11/15 03:25:54 DEBUG : TestCopyFileMaxTransfer/file4: md5 = 38063ffdee0cd1f6ffb8775102ac1aa0 OK 2025/11/15 03:25:54 DEBUG : TestCopyFileMaxTransfer/file4: size = 2062 OK 2025/11/15 03:25:54 INFO : TestCopyFileMaxTransfer/file4: Copied (new) 2025/11/15 03:25:56 DEBUG : bl9cnu966tarhhpg22ft7c4qm1863k22qdncucii8kn8c3k4aut0: Rmdir: contains trashed file: "amq3bkuqh8mg4ohnbs2i3185uk" 2025/11/15 03:25:56 DEBUG : bl9cnu966tarhhpg22ft7c4qm1863k22qdncucii8kn8c3k4aut0: Rmdir: contains trashed file: "eer8kka55qnghc34cq76ca668g" --- PASS: TestCopyFileMaxTransfer (9.55s) === RUN TestDeduplicateInteractive run.go:185: Remote "Encrypted drive 'TestCryptDrive:rclone-test-varoqut0vomu'", Local "Local file system at /tmp/rclone2444379526", Modify Window "1ms" dedupe_test.go:36: Can't run this test without a hash --- SKIP: TestDeduplicateInteractive (0.51s) === RUN TestDeduplicateSkip run.go:185: Remote "Encrypted drive 'TestCryptDrive:rclone-test-varoqut0vomu'", Local "Local file system at /tmp/rclone2444379526", Modify Window "1ms" 2025/11/15 03:26:00 INFO : Encrypted drive 'TestCryptDrive:rclone-test-varoqut0vomu': Looking for duplicate names using skip mode. 2025/11/15 03:26:01 NOTICE: one: Found 2 files with duplicate names 2025/11/15 03:26:01 NOTICE: one: Skipping 2 files with duplicate names --- PASS: TestDeduplicateSkip (5.47s) === RUN TestDeduplicateSizeOnly run.go:185: Remote "Encrypted drive 'TestCryptDrive:rclone-test-varoqut0vomu'", Local "Local file system at /tmp/rclone2444379526", Modify Window "1ms" 2025/11/15 03:26:07 INFO : Encrypted drive 'TestCryptDrive:rclone-test-varoqut0vomu': Looking for duplicate names using skip mode. 2025/11/15 03:26:07 NOTICE: one: Found 3 files with duplicate names 2025/11/15 03:26:07 NOTICE: one: Deleting 1/2 identical duplicates (size 11) 2025/11/15 03:26:08 INFO : one: Deleted 2025/11/15 03:26:08 NOTICE: one: Skipping 2 files with duplicate names --- PASS: TestDeduplicateSizeOnly (6.65s) === RUN TestDeduplicateFirst run.go:185: Remote "Encrypted drive 'TestCryptDrive:rclone-test-varoqut0vomu'", Local "Local file system at /tmp/rclone2444379526", Modify Window "1ms" 2025/11/15 03:26:13 INFO : Encrypted drive 'TestCryptDrive:rclone-test-varoqut0vomu': Looking for duplicate names using first mode. 2025/11/15 03:26:14 NOTICE: one: Found 3 files with duplicate names 2025/11/15 03:26:15 INFO : one: Deleted 2025/11/15 03:26:15 INFO : one: Deleted 2025/11/15 03:26:15 NOTICE: one: Deleted 2 extra copies --- PASS: TestDeduplicateFirst (6.68s) === RUN TestDeduplicateNewest run.go:185: Remote "Encrypted drive 'TestCryptDrive:rclone-test-varoqut0vomu'", Local "Local file system at /tmp/rclone2444379526", Modify Window "1ms" 2025/11/15 03:26:21 INFO : Encrypted drive 'TestCryptDrive:rclone-test-varoqut0vomu': Looking for duplicate names using newest mode. 2025/11/15 03:26:21 NOTICE: one: Found 3 files with duplicate names 2025/11/15 03:26:22 INFO : one: Deleted 2025/11/15 03:26:22 INFO : one: Deleted 2025/11/15 03:26:22 NOTICE: one: Deleted 2 extra copies --- PASS: TestDeduplicateNewest (7.08s) === RUN TestDeduplicateNewestByHash run.go:185: Remote "Encrypted drive 'TestCryptDrive:rclone-test-varoqut0vomu'", Local "Local file system at /tmp/rclone2444379526", Modify Window "1ms" dedupe_test.go:36: Can't run this test without a hash --- SKIP: TestDeduplicateNewestByHash (0.46s) === RUN TestDeduplicateOldest run.go:185: Remote "Encrypted drive 'TestCryptDrive:rclone-test-varoqut0vomu'", Local "Local file system at /tmp/rclone2444379526", Modify Window "1ms" 2025/11/15 03:26:28 INFO : Encrypted drive 'TestCryptDrive:rclone-test-varoqut0vomu': Looking for duplicate names using oldest mode. 2025/11/15 03:26:28 NOTICE: one: Found 3 files with duplicate names 2025/11/15 03:26:29 INFO : one: Deleted 2025/11/15 03:26:29 INFO : one: Deleted 2025/11/15 03:26:29 NOTICE: one: Deleted 2 extra copies --- PASS: TestDeduplicateOldest (6.63s) === RUN TestDeduplicateLargest run.go:185: Remote "Encrypted drive 'TestCryptDrive:rclone-test-varoqut0vomu'", Local "Local file system at /tmp/rclone2444379526", Modify Window "1ms" 2025/11/15 03:26:34 INFO : Encrypted drive 'TestCryptDrive:rclone-test-varoqut0vomu': Looking for duplicate names using largest mode. 2025/11/15 03:26:35 NOTICE: one: Found 3 files with duplicate names 2025/11/15 03:26:36 INFO : one: Deleted 2025/11/15 03:26:36 INFO : one: Deleted 2025/11/15 03:26:36 NOTICE: one: Deleted 2 extra copies --- PASS: TestDeduplicateLargest (6.96s) === RUN TestDeduplicateSmallest run.go:185: Remote "Encrypted drive 'TestCryptDrive:rclone-test-varoqut0vomu'", Local "Local file system at /tmp/rclone2444379526", Modify Window "1ms" 2025/11/15 03:26:42 INFO : Encrypted drive 'TestCryptDrive:rclone-test-varoqut0vomu': Looking for duplicate names using smallest mode. 2025/11/15 03:26:42 NOTICE: one: Found 3 files with duplicate names 2025/11/15 03:26:43 INFO : one: Deleted 2025/11/15 03:26:43 INFO : one: Deleted 2025/11/15 03:26:43 NOTICE: one: Deleted 2 extra copies --- PASS: TestDeduplicateSmallest (7.02s) === RUN TestDeduplicateRename run.go:185: Remote "Encrypted drive 'TestCryptDrive:rclone-test-varoqut0vomu'", Local "Local file system at /tmp/rclone2444379526", Modify Window "1ms" 2025/11/15 03:26:50 INFO : Encrypted drive 'TestCryptDrive:rclone-test-varoqut0vomu': Looking for duplicate names using rename mode. 2025/11/15 03:26:51 NOTICE: one.txt: Found 3 files with duplicate names 2025/11/15 03:26:52 INFO : one-2.txt: renamed from: one.txt 2025/11/15 03:26:53 INFO : one-3.txt: renamed from: one.txt 2025/11/15 03:26:54 INFO : one-4.txt: renamed from: one.txt --- PASS: TestDeduplicateRename (12.45s) === RUN TestMergeDirs run.go:185: Remote "Encrypted drive 'TestCryptDrive:rclone-test-varoqut0vomu'", Local "Local file system at /tmp/rclone2444379526", Modify Window "1ms" 2025/11/15 03:26:59 DEBUG : dupe1/one.txt: md5 = bb145f8fd9766e3ddd22ed6f6e953cff OK 2025/11/15 03:27:02 DEBUG : dupe2/two.txt: md5 = de089d7e55e85dd0f31482ae6504be80 OK 2025/11/15 03:27:04 DEBUG : dupe3/three.txt: md5 = 02866b74785cc862e14d7da2e57042a6 OK 2025/11/15 03:27:05 INFO : urj8gsducqbtvekeq181ntd2u8: merging "sfrom47189m9qr3mt4qonu281c" 2025/11/15 03:27:06 INFO : urj8gsducqbtvekeq181ntd2u8: removing empty directory 2025/11/15 03:27:06 INFO : 1u1r4ei7c628fnjg9blqt3j60o: merging "tr2hj63d80ftlmvm6a952snjcc" 2025/11/15 03:27:07 INFO : 1u1r4ei7c628fnjg9blqt3j60o: removing empty directory 2025/11/15 03:27:11 DEBUG : 6kqidisnml4ml642h4iorebdng: Rmdir: contains trashed file: "tr2hj63d80ftlmvm6a952snjcc" 2025/11/15 03:27:11 DEBUG : 6kqidisnml4ml642h4iorebdng: Rmdir: contains trashed file: "sfrom47189m9qr3mt4qonu281c" 2025/11/15 03:27:11 DEBUG : 6kqidisnml4ml642h4iorebdng: Rmdir: contains trashed file: "o9f79a47tdo934l5lfe4jpsms8" --- PASS: TestMergeDirs (14.42s) === RUN TestListDirSorted run.go:185: Remote "Encrypted drive 'TestCryptDrive:rclone-test-varoqut0vomu'", Local "Local file system at /tmp/rclone2444379526", Modify Window "1ms" 2025/11/15 03:27:13 DEBUG : a.txt: md5 = 6b59ced14edc1c082501bf817d5b744e OK 2025/11/15 03:27:14 DEBUG : zend.txt: md5 = e2d7cc2c615329e7f2b7e42a00e2e188 OK 2025/11/15 03:27:17 DEBUG : sub dir/hello world: md5 = 483c9c533edb141c3ed04c6e7ac836d7 OK 2025/11/15 03:27:18 DEBUG : sub dir/hello world2: md5 = 1d6060e5ea6706e2a86f4427386db271 OK 2025/11/15 03:27:21 DEBUG : sub dir/ignore dir/.ignore: md5 = bca0cacbb0261ece5cfe666162643df1 OK 2025/11/15 03:27:22 DEBUG : sub dir/ignore dir/should be ignored: md5 = fce8c953143979f981c0389587fbbb94 OK 2025/11/15 03:27:25 DEBUG : sub dir/sub sub dir/hello world3: md5 = 8d9a33e1b2c5a7d012e7a226bc0b3c37 OK 2025/11/15 03:27:26 DEBUG : a.txt: Excluded (Size Filter) 2025/11/15 03:27:26 DEBUG : a.txt: Excluded 2025/11/15 03:27:27 DEBUG : sub dir/hello world2: Excluded (Size Filter) 2025/11/15 03:27:27 DEBUG : sub dir/hello world2: Excluded 2025/11/15 03:27:27 DEBUG : sub dir/hello world: Excluded (Size Filter) 2025/11/15 03:27:27 DEBUG : sub dir/hello world: Excluded 2025/11/15 03:27:28 DEBUG : sub dir/ignore dir: Excluded 2025/11/15 03:27:28 DEBUG : sub dir/hello world2: Excluded (Size Filter) 2025/11/15 03:27:28 DEBUG : sub dir/hello world2: Excluded 2025/11/15 03:27:28 DEBUG : sub dir/hello world: Excluded (Size Filter) 2025/11/15 03:27:28 DEBUG : sub dir/hello world: Excluded 2025/11/15 03:27:28 DEBUG : sub dir/ignore dir: Excluded 2025/11/15 03:27:33 DEBUG : 14begaa36ih249fgrnfk6nulg0/u72nb76vlceqmgie4q57rr656s: Rmdir: contains trashed file: "o5airla31uiui01blt74of9ves" 2025/11/15 03:27:34 DEBUG : 14begaa36ih249fgrnfk6nulg0/3fslb0r9seuggqgrrqv2gjhfj8: Rmdir: contains trashed file: "8qak2o3rl7h348s0lh02ghr71aajis56gocdjjbjfkc2j4vakju0" 2025/11/15 03:27:34 DEBUG : 14begaa36ih249fgrnfk6nulg0/3fslb0r9seuggqgrrqv2gjhfj8: Rmdir: contains trashed file: "i0t74httmdlljg425j6tg570d8" 2025/11/15 03:27:34 DEBUG : 14begaa36ih249fgrnfk6nulg0: Rmdir: contains trashed file: "u72nb76vlceqmgie4q57rr656s" 2025/11/15 03:27:34 DEBUG : 14begaa36ih249fgrnfk6nulg0: Rmdir: contains trashed file: "3fslb0r9seuggqgrrqv2gjhfj8" 2025/11/15 03:27:34 DEBUG : 14begaa36ih249fgrnfk6nulg0: Rmdir: contains trashed file: "mp1g12tj43cv7goud5b95fgju8" 2025/11/15 03:27:34 DEBUG : 14begaa36ih249fgrnfk6nulg0: Rmdir: contains trashed file: "8j9gk4b744l35einofkqsr2mvo" --- PASS: TestListDirSorted (23.85s) === RUN TestListDirSortedFn run.go:185: Remote "Encrypted drive 'TestCryptDrive:rclone-test-varoqut0vomu'", Local "Local file system at /tmp/rclone2444379526", Modify Window "1ms" 2025/11/15 03:27:37 DEBUG : a.txt: md5 = ec1ba1335aefd45b33486313c329c4f1 OK 2025/11/15 03:27:39 DEBUG : zend.txt: md5 = 3a892fece58b41b001f147eb9300b462 OK 2025/11/15 03:27:41 DEBUG : sub dir/hello world: md5 = 2204588131aed3e91c5bb1949b171b83 OK 2025/11/15 03:27:43 DEBUG : sub dir/hello world2: md5 = 7a58fdd0391f1169fe98c4e026b6ee0c OK 2025/11/15 03:27:46 DEBUG : sub dir/ignore dir/.ignore: md5 = f89b7923e0bd8c77e3cf3a207509b3b3 OK 2025/11/15 03:27:47 DEBUG : sub dir/ignore dir/should be ignored: md5 = 34e243aa6bbc6cf24268816053c80d70 OK 2025/11/15 03:27:49 DEBUG : sub dir/sub sub dir/hello world3: md5 = beb837633c30a86372d35bcc66d15f21 OK 2025/11/15 03:27:51 DEBUG : a.txt: Excluded (Size Filter) 2025/11/15 03:27:51 DEBUG : a.txt: Excluded 2025/11/15 03:27:51 DEBUG : sub dir/hello world: Excluded (Size Filter) 2025/11/15 03:27:51 DEBUG : sub dir/hello world: Excluded 2025/11/15 03:27:51 DEBUG : sub dir/hello world2: Excluded (Size Filter) 2025/11/15 03:27:51 DEBUG : sub dir/hello world2: Excluded 2025/11/15 03:27:52 DEBUG : sub dir/ignore dir: Excluded 2025/11/15 03:27:52 DEBUG : sub dir/hello world: Excluded (Size Filter) 2025/11/15 03:27:52 DEBUG : sub dir/hello world: Excluded 2025/11/15 03:27:52 DEBUG : sub dir/hello world2: Excluded (Size Filter) 2025/11/15 03:27:52 DEBUG : sub dir/hello world2: Excluded 2025/11/15 03:27:52 DEBUG : sub dir/ignore dir: Excluded 2025/11/15 03:27:57 DEBUG : 14begaa36ih249fgrnfk6nulg0/u72nb76vlceqmgie4q57rr656s: Rmdir: contains trashed file: "o5airla31uiui01blt74of9ves" 2025/11/15 03:27:58 DEBUG : 14begaa36ih249fgrnfk6nulg0/3fslb0r9seuggqgrrqv2gjhfj8: Rmdir: contains trashed file: "8qak2o3rl7h348s0lh02ghr71aajis56gocdjjbjfkc2j4vakju0" 2025/11/15 03:27:58 DEBUG : 14begaa36ih249fgrnfk6nulg0/3fslb0r9seuggqgrrqv2gjhfj8: Rmdir: contains trashed file: "i0t74httmdlljg425j6tg570d8" 2025/11/15 03:27:59 DEBUG : 14begaa36ih249fgrnfk6nulg0: Rmdir: contains trashed file: "u72nb76vlceqmgie4q57rr656s" 2025/11/15 03:27:59 DEBUG : 14begaa36ih249fgrnfk6nulg0: Rmdir: contains trashed file: "3fslb0r9seuggqgrrqv2gjhfj8" 2025/11/15 03:27:59 DEBUG : 14begaa36ih249fgrnfk6nulg0: Rmdir: contains trashed file: "8j9gk4b744l35einofkqsr2mvo" 2025/11/15 03:27:59 DEBUG : 14begaa36ih249fgrnfk6nulg0: Rmdir: contains trashed file: "mp1g12tj43cv7goud5b95fgju8" --- PASS: TestListDirSortedFn (24.26s) === RUN TestListJSON run.go:185: Remote "Encrypted drive 'TestCryptDrive:rclone-test-varoqut0vomu'", Local "Local file system at /tmp/rclone2444379526", Modify Window "1ms" 2025/11/15 03:28:01 DEBUG : file1: md5 = 823a50601cfe72aef05b98571ea9a7f6 OK 2025/11/15 03:28:03 DEBUG : sub/file2: md5 = b252700c288fbe97338eea6c20e0caa4 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 2025/11/15 03:28:06 ERROR : file1: Failed to read hash: hash type not supported === RUN TestListJSON/Metadata 2025/11/15 03:28:06 DEBUG : eer8kka55qnghc34cq76ca668g: Fetching metadata 2025/11/15 03:28:07 DEBUG : 150fuo3cn4j1uenq6r4g4qk6t4: Fetching metadata 2025/11/15 03:28:09 DEBUG : 150fuo3cn4j1uenq6r4g4qk6t4: Rmdir: contains trashed file: "d26uovf0miqhd1mu3c9jbseq00" --- PASS: TestListJSON (10.16s) --- PASS: TestListJSON/Default (0.26s) --- PASS: TestListJSON/FilesOnly (0.25s) --- PASS: TestListJSON/DirsOnly (0.27s) --- PASS: TestListJSON/Recurse (0.50s) --- PASS: TestListJSON/SubDir (0.28s) --- PASS: TestListJSON/NoModTime (0.25s) --- PASS: TestListJSON/NoMimeType (0.25s) --- PASS: TestListJSON/ShowHash (0.24s) --- PASS: TestListJSON/HashTypes (0.22s) --- PASS: TestListJSON/Metadata (0.67s) === RUN TestStatJSON run.go:185: Remote "Encrypted drive 'TestCryptDrive:rclone-test-varoqut0vomu'", Local "Local file system at /tmp/rclone2444379526", Modify Window "1ms" 2025/11/15 03:28:11 DEBUG : file1: md5 = 2ef3c474fe7b870300583ab9e94abb2d OK 2025/11/15 03:28:13 DEBUG : sub/file2: md5 = 8f66a02579e8cc565eaed93e8deef469 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 2025/11/15 03:28:18 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-varoqut0vomu/notfound" 2025/11/15 03:28:18 DEBUG : Creating backend with remote "TestDrive:crypt/i7rtc15m75acpkcu99de1eu6ik7sc5tlp31a4jpi4vfn339844bg/qe0i31qdkoejk60elps0ni5sqk" 2025/11/15 03:28:22 DEBUG : 150fuo3cn4j1uenq6r4g4qk6t4: Rmdir: contains trashed file: "d26uovf0miqhd1mu3c9jbseq00" --- PASS: TestStatJSON (12.82s) --- PASS: TestStatJSON/Root (0.24s) --- PASS: TestStatJSON/RootFilesOnly (0.00s) --- PASS: TestStatJSON/RootDirsOnly (0.25s) --- PASS: TestStatJSON/Dir (0.50s) --- PASS: TestStatJSON/DirWithTrailingSlash (0.26s) --- PASS: TestStatJSON/File (0.26s) --- PASS: TestStatJSON/NotFound (0.51s) --- PASS: TestStatJSON/DirFilesOnly (0.23s) --- PASS: TestStatJSON/FileFilesOnly (0.23s) --- PASS: TestStatJSON/NotFoundFilesOnly (0.35s) --- PASS: TestStatJSON/DirDirsOnly (0.24s) --- PASS: TestStatJSON/FileDirsOnly (0.40s) --- PASS: TestStatJSON/NotFoundDirsOnly (0.24s) --- PASS: TestStatJSON/RootNotFound (1.92s) === RUN TestMkdir run.go:185: Remote "Encrypted drive 'TestCryptDrive:rclone-test-varoqut0vomu'", Local "Local file system at /tmp/rclone2444379526", Modify Window "1ms" 2025/11/15 03:28:22 INFO : Encrypted drive 'TestCryptDrive:rclone-test-varoqut0vomu': Making directory 2025/11/15 03:28:23 INFO : Encrypted drive 'TestCryptDrive:rclone-test-varoqut0vomu': Making directory --- PASS: TestMkdir (0.75s) === RUN TestLsd run.go:185: Remote "Encrypted drive 'TestCryptDrive:rclone-test-varoqut0vomu'", Local "Local file system at /tmp/rclone2444379526", Modify Window "1ms" 2025/11/15 03:28:25 DEBUG : sub dir/hello world: md5 = 512bc92446289e0c7f9165d619c9577b OK 2025/11/15 03:28:28 DEBUG : 14begaa36ih249fgrnfk6nulg0: Rmdir: contains trashed file: "8j9gk4b744l35einofkqsr2mvo" --- PASS: TestLsd (5.31s) === RUN TestLs run.go:185: Remote "Encrypted drive 'TestCryptDrive:rclone-test-varoqut0vomu'", Local "Local file system at /tmp/rclone2444379526", Modify Window "1ms" 2025/11/15 03:28:30 DEBUG : potato2: md5 = d6772494714743950a3b98a026913c0a OK 2025/11/15 03:28:32 DEBUG : empty space: md5 = b42a9aa16dfff7eabfa7285034bd7723 OK --- PASS: TestLs (5.38s) === RUN TestLsWithFilesFrom run.go:185: Remote "Encrypted drive 'TestCryptDrive:rclone-test-varoqut0vomu'", Local "Local file system at /tmp/rclone2444379526", Modify Window "1ms" 2025/11/15 03:28:35 DEBUG : potato2: md5 = 12e770508eb8a76f039f6d2eaac8f47b OK 2025/11/15 03:28:37 DEBUG : empty space: md5 = 4de9fe6f4edb102a0bcc6f7265a9b464 OK 2025/11/15 03:28:38 DEBUG : empty space: Excluded (FilesFrom Filter) 2025/11/15 03:28:38 DEBUG : empty space: Excluded --- PASS: TestLsWithFilesFrom (5.68s) === RUN TestLsLong run.go:185: Remote "Encrypted drive 'TestCryptDrive:rclone-test-varoqut0vomu'", Local "Local file system at /tmp/rclone2444379526", Modify Window "1ms" 2025/11/15 03:28:41 DEBUG : potato2: md5 = 7550d16bf6d6ced6aa3be38186ec422d OK 2025/11/15 03:28:43 DEBUG : empty space: md5 = 4dade58e9f59510249f8ac12de659b94 OK --- PASS: TestLsLong (8.68s) === RUN TestHashSums run.go:185: Remote "Encrypted drive 'TestCryptDrive:rclone-test-varoqut0vomu'", Local "Local file system at /tmp/rclone2444379526", Modify Window "1ms" 2025/11/15 03:28:50 DEBUG : potato2: md5 = 2c3eb563a0d38f4d21e1b112e65b6729 OK 2025/11/15 03:28:51 DEBUG : empty space: md5 = 1db091175688b4c9c6b19e2e4dce4fcd OK --- PASS: TestHashSums (4.81s) === RUN TestHashSumsWithErrors 2025/11/15 03:28:53 DEBUG : Creating backend with remote ":memory:" 2025/11/15 03:28:53 DEBUG : Config file has changed externally - reloading 2025/11/15 03:28:53 ERROR : file1: hash unsupported: hash type not supported --- PASS: TestHashSumsWithErrors (0.00s) === RUN TestHashStream 2025/11/15 03:28:53 DEBUG : Creating md5 hash of 0 bytes read from input stream 2025/11/15 03:28:53 DEBUG : Creating md5 hash of 0 bytes read from input stream 2025/11/15 03:28:53 DEBUG : Creating sha1 hash of 0 bytes read from input stream 2025/11/15 03:28:53 DEBUG : Creating sha1 hash of 0 bytes read from input stream 2025/11/15 03:28:53 DEBUG : Creating md5 hash of 12 bytes read from input stream 2025/11/15 03:28:53 DEBUG : Creating md5 hash of 12 bytes read from input stream 2025/11/15 03:28:53 DEBUG : Creating sha1 hash of 12 bytes read from input stream 2025/11/15 03:28:53 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-varoqut0vomu'", Local "Local file system at /tmp/rclone2444379526", Modify Window "1ms" 2025/11/15 03:28:54 DEBUG : potato2: md5 = 716bfe3385e689d848b612eef68896e5 OK 2025/11/15 03:28:56 DEBUG : empty space: md5 = 9e7b9b8facb4e8a4c22b783cfd3c7b0c OK 2025/11/15 03:28:59 DEBUG : sub dir/potato3: md5 = bf099130311c44cd112c4473c0d25af1 OK 2025/11/15 03:29:02 DEBUG : 14begaa36ih249fgrnfk6nulg0: Rmdir: contains trashed file: "9atai6og4mljg3r5gjeh27k0q8" --- PASS: TestCount (9.37s) === RUN TestDelete run.go:185: Remote "Encrypted drive 'TestCryptDrive:rclone-test-varoqut0vomu'", Local "Local file system at /tmp/rclone2444379526", Modify Window "1ms" 2025/11/15 03:29:04 DEBUG : small: md5 = 15785c20f6dd8b65f023babc1ea11b0b OK 2025/11/15 03:29:05 DEBUG : medium: md5 = 81c280092a8651d124ffc2c6b9d0c889 OK 2025/11/15 03:29:07 DEBUG : large: md5 = 647b7719e9022fc7158ceb73f7a50401 OK 2025/11/15 03:29:07 DEBUG : Waiting for deletions to finish 2025/11/15 03:29:07 DEBUG : large: Excluded (Size Filter) 2025/11/15 03:29:08 INFO : small: Deleted 2025/11/15 03:29:08 INFO : medium: Deleted --- PASS: TestDelete (6.73s) === RUN TestMaxDelete run.go:185: Remote "Encrypted drive 'TestCryptDrive:rclone-test-varoqut0vomu'", Local "Local file system at /tmp/rclone2444379526", Modify Window "1ms" 2025/11/15 03:29:11 DEBUG : small: md5 = 9beead99b3d527afe24df56083117cc8 OK 2025/11/15 03:29:12 DEBUG : medium: md5 = d183c9fac9e3db5a01151f3a68cfab2f OK 2025/11/15 03:29:14 DEBUG : large: md5 = b3089be6b45e2319bd1a7bb838ad2fd2 OK 2025/11/15 03:29:14 DEBUG : Waiting for deletions to finish 2025/11/15 03:29:14 ERROR : large: Got fatal error on delete: --max-delete threshold reached 2025/11/15 03:29:15 INFO : medium: Deleted 2025/11/15 03:29:15 INFO : small: Deleted --- PASS: TestMaxDelete (7.74s) === RUN TestMaxDeleteSizeLargeFile run.go:185: Remote "Encrypted drive 'TestCryptDrive:rclone-test-varoqut0vomu'", Local "Local file system at /tmp/rclone2444379526", Modify Window "1ms" 2025/11/15 03:29:18 DEBUG : small: md5 = e504ebc0ff1b8cda2592299bfcb4d359 OK 2025/11/15 03:29:20 DEBUG : medium: md5 = 27d4c1f7d303867cc2d79e29e3a2fe7d OK 2025/11/15 03:29:21 DEBUG : large: md5 = 635a9c22234be0223f9114fb7a565dde OK 2025/11/15 03:29:22 DEBUG : Waiting for deletions to finish 2025/11/15 03:29:22 ERROR : large: Got fatal error on delete: --max-delete-size threshold reached 2025/11/15 03:29:23 INFO : small: Deleted 2025/11/15 03:29:24 INFO : medium: Deleted --- PASS: TestMaxDeleteSizeLargeFile (8.67s) === RUN TestMaxDeleteSize run.go:185: Remote "Encrypted drive 'TestCryptDrive:rclone-test-varoqut0vomu'", Local "Local file system at /tmp/rclone2444379526", Modify Window "1ms" 2025/11/15 03:29:27 DEBUG : small: md5 = ea4bfdd123452ec9bba145c6b6feb1d3 OK 2025/11/15 03:29:29 DEBUG : medium: md5 = 448583eec0ee47bad505dfbff95d143a OK 2025/11/15 03:29:30 DEBUG : large: md5 = a6ed2e7acab51fcbf98163bc1ba29ea6 OK 2025/11/15 03:29:30 DEBUG : Waiting for deletions to finish 2025/11/15 03:29:31 ERROR : large: Got fatal error on delete: --max-delete-size threshold reached 2025/11/15 03:29:31 INFO : medium: Deleted 2025/11/15 03:29:31 INFO : small: Deleted --- PASS: TestMaxDeleteSize (7.16s) === RUN TestReadFile run.go:185: Remote "Encrypted drive 'TestCryptDrive:rclone-test-varoqut0vomu'", Local "Local file system at /tmp/rclone2444379526", Modify Window "1ms" 2025/11/15 03:29:34 DEBUG : ReadFile: md5 = f6118be49cb30fc9da617743965ca9a2 OK --- PASS: TestReadFile (4.29s) === RUN TestRetry 2025/11/15 03:29:37 DEBUG : Received error: Wrapped EOF is retriable: EOF - low level retry 1/5 2025/11/15 03:29:37 DEBUG : Received error: Wrapped EOF is retriable: EOF - low level retry 2/5 2025/11/15 03:29:37 DEBUG : Sleeping for 10ms (as indicated by the server) to obey Retry-After error: BANG: trying again in 10ms 2025/11/15 03:29:37 DEBUG : Sleeping for 10ms (as indicated by the server) to obey Retry-After error: BANG: trying again in 10ms 2025/11/15 03:29:37 DEBUG : Sleeping for 10ms (as indicated by the server) to obey Retry-After error: BANG: trying again in 10ms 2025/11/15 03:29:37 DEBUG : Sleeping for 10ms (as indicated by the server) to obey Retry-After error: BANG: trying again in 10ms 2025/11/15 03:29:37 DEBUG : Sleeping for 10ms (as indicated by the server) to obey Retry-After error: BANG: trying again in 10ms --- PASS: TestRetry (0.05s) === RUN TestCat run.go:185: Remote "Encrypted drive 'TestCryptDrive:rclone-test-varoqut0vomu'", Local "Local file system at /tmp/rclone2444379526", Modify Window "1ms" 2025/11/15 03:29:38 DEBUG : file1: md5 = 647ac1bb3964b8d682b1e0ffd4e9705b OK 2025/11/15 03:29:40 DEBUG : file2: md5 = 78eeba940385ec658f114ed7a9fd28c3 OK --- PASS: TestCat (17.12s) === RUN TestPurge 2025/11/15 03:29:54 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-jeqimos9hogu" 2025/11/15 03:29:54 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/11/15 03:29:54 DEBUG : Creating backend with remote "TestDrive:crypt/enopo05pu5tocvls583jtarn7tcnc4hgpqr09rd3blahphemssc0" 2025/11/15 03:29:55 DEBUG : Creating backend with remote "/tmp/rclone2106968481" run.go:185: Remote "Encrypted drive 'TestCryptDrive:rclone-test-jeqimos9hogu'", Local "Local file system at /tmp/rclone2106968481", Modify Window "1ms" 2025/11/15 03:30:00 DEBUG : A1/B1/C1/one: md5 = d496dc7a05f918557cec590292969cf6 OK 2025/11/15 03:30:00 INFO : A2: Making directory 2025/11/15 03:30:01 INFO : A1/B2: Making directory 2025/11/15 03:30:02 INFO : A1/B2/C2: Making directory 2025/11/15 03:30:02 INFO : A1/B1/C3: Making directory 2025/11/15 03:30:03 INFO : A3: Making directory 2025/11/15 03:30:04 INFO : A3/B3: Making directory 2025/11/15 03:30:05 INFO : A3/B3/C4: Making directory 2025/11/15 03:30:07 DEBUG : A1/two: md5 = 8f7306000b289cadb1920399d70ad7a7 OK 2025/11/15 03:30:11 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-jeqimos9hogu': Purge remote 2025/11/15 03:30:11 NOTICE: purge failed: directory not found --- PASS: TestPurge (17.25s) === RUN TestRmdirsNoLeaveRoot run.go:185: Remote "Encrypted drive 'TestCryptDrive:rclone-test-varoqut0vomu'", Local "Local file system at /tmp/rclone2444379526", Modify Window "1ms" 2025/11/15 03:30:15 DEBUG : A1/B1/C1/one: md5 = 6fc1f86024ddbf35c8183c914cef2a71 OK 2025/11/15 03:30:15 INFO : A2: Making directory 2025/11/15 03:30:16 INFO : A1/B2: Making directory 2025/11/15 03:30:17 INFO : A1/B2/C2: Making directory 2025/11/15 03:30:18 INFO : A1/B1/C3: Making directory 2025/11/15 03:30:18 INFO : A3: Making directory 2025/11/15 03:30:19 INFO : A3/B3: Making directory 2025/11/15 03:30:20 INFO : A3/B3/C4: Making directory 2025/11/15 03:30:22 DEBUG : A1/two: md5 = c538d7eb5adb27d53b89970582b7defe OK 2025/11/15 03:30:23 DEBUG : removing 1 level 3 directories 2025/11/15 03:30:23 INFO : A3/B3/C4: Removing directory 2025/11/15 03:30:26 DEBUG : removing 2 level 3 directories 2025/11/15 03:30:26 INFO : A1/B2/C2: Removing directory 2025/11/15 03:30:26 INFO : A1/B1/C3: Removing directory 2025/11/15 03:30:27 DEBUG : removing 2 level 2 directories 2025/11/15 03:30:27 INFO : A3/B3: Removing directory 2025/11/15 03:30:27 INFO : A1/B2: Removing directory 2025/11/15 03:30:27 DEBUG : h0fgn6vkka25jj9fhu5roml6pg/avn2ljdtrs3fgdela1haatj3go: Rmdir: contains trashed file: "k43jqb5391s8afh9eje7baui0g" 2025/11/15 03:30:27 DEBUG : fppqbfrji50odnh2br069japlo/8j6vge4hggs2biai52vpe0kpik: Rmdir: contains trashed file: "ope427f0bb0h1ebd1s1fm4022k" 2025/11/15 03:30:28 DEBUG : removing 2 level 1 directories 2025/11/15 03:30:28 INFO : A3: Removing directory 2025/11/15 03:30:28 INFO : A2: Removing directory 2025/11/15 03:30:28 DEBUG : fppqbfrji50odnh2br069japlo: Rmdir: contains trashed file: "8j6vge4hggs2biai52vpe0kpik" 2025/11/15 03:30:32 DEBUG : removing 1 level 3 directories 2025/11/15 03:30:32 INFO : A1/B1/C1: Removing directory 2025/11/15 03:30:32 DEBUG : h0fgn6vkka25jj9fhu5roml6pg/rv5l12j0nanu5af9ivc97ofdk8/gtu1leijh9rlm05pcjtusqop2g: Rmdir: contains trashed file: "0828u6q2p8d62qeklrkkuf10uc" 2025/11/15 03:30:33 DEBUG : removing 1 level 2 directories 2025/11/15 03:30:33 INFO : A1/B1: Removing directory 2025/11/15 03:30:33 DEBUG : h0fgn6vkka25jj9fhu5roml6pg/rv5l12j0nanu5af9ivc97ofdk8: Rmdir: contains trashed file: "ui2vk9n52l82tdno99vhkfi87g" 2025/11/15 03:30:33 DEBUG : h0fgn6vkka25jj9fhu5roml6pg/rv5l12j0nanu5af9ivc97ofdk8: Rmdir: contains trashed file: "gtu1leijh9rlm05pcjtusqop2g" 2025/11/15 03:30:33 DEBUG : removing 1 level 1 directories 2025/11/15 03:30:33 INFO : A1: Removing directory 2025/11/15 03:30:34 DEBUG : h0fgn6vkka25jj9fhu5roml6pg: Rmdir: contains trashed file: "avn2ljdtrs3fgdela1haatj3go" 2025/11/15 03:30:34 DEBUG : h0fgn6vkka25jj9fhu5roml6pg: Rmdir: contains trashed file: "rv5l12j0nanu5af9ivc97ofdk8" 2025/11/15 03:30:34 DEBUG : h0fgn6vkka25jj9fhu5roml6pg: Rmdir: contains trashed file: "keafetfvtbmgo6tsdvcbj32i0k" 2025/11/15 03:30:34 DEBUG : removing 1 level 0 directories 2025/11/15 03:30:34 INFO : Encrypted drive 'TestCryptDrive:rclone-test-varoqut0vomu': Removing directory 2025/11/15 03:30:34 DEBUG : Rmdir: contains trashed file: "fppqbfrji50odnh2br069japlo" 2025/11/15 03:30:34 DEBUG : Rmdir: contains trashed file: "c3k31g9ua29pvlul21td3bpldo" 2025/11/15 03:30:34 DEBUG : Rmdir: contains trashed file: "h0fgn6vkka25jj9fhu5roml6pg" 2025/11/15 03:30:34 DEBUG : Rmdir: contains trashed file: "14begaa36ih249fgrnfk6nulg0" 2025/11/15 03:30:34 DEBUG : Rmdir: contains trashed file: "14begaa36ih249fgrnfk6nulg0" 2025/11/15 03:30:34 DEBUG : Rmdir: contains trashed file: "150fuo3cn4j1uenq6r4g4qk6t4" 2025/11/15 03:30:34 DEBUG : Rmdir: contains trashed file: "150fuo3cn4j1uenq6r4g4qk6t4" 2025/11/15 03:30:34 DEBUG : Rmdir: contains trashed file: "14begaa36ih249fgrnfk6nulg0" 2025/11/15 03:30:34 DEBUG : Rmdir: contains trashed file: "14begaa36ih249fgrnfk6nulg0" 2025/11/15 03:30:34 DEBUG : Rmdir: contains trashed file: "1u1r4ei7c628fnjg9blqt3j60o" 2025/11/15 03:30:34 DEBUG : Rmdir: contains trashed file: "urj8gsducqbtvekeq181ntd2u8" 2025/11/15 03:30:34 DEBUG : Rmdir: contains trashed file: "6kqidisnml4ml642h4iorebdng" 2025/11/15 03:30:34 DEBUG : Rmdir: contains trashed file: "bl9cnu966tarhhpg22ft7c4qm1863k22qdncucii8kn8c3k4aut0" 2025/11/15 03:30:34 DEBUG : Rmdir: contains trashed file: "s6dbk3lfi7c9kfvo6j7bla9m0g" 2025/11/15 03:30:34 DEBUG : Rmdir: contains trashed file: "d09o6po3f7bm6ce32vdgs8h9ls" 2025/11/15 03:30:34 DEBUG : Rmdir: contains trashed file: "31u3jie661vd5p8j7rtc3hgbh0" 2025/11/15 03:30:34 DEBUG : Rmdir: contains trashed file: "gveqi14airsml4bgu7krj116o8" 2025/11/15 03:30:34 DEBUG : Rmdir: contains trashed file: "31u3jie661vd5p8j7rtc3hgbh0" 2025/11/15 03:30:34 DEBUG : Rmdir: contains trashed file: "1nrff024r7pq65ecp72fc28jb0" 2025/11/15 03:30:34 DEBUG : Rmdir: contains trashed file: "31u3jie661vd5p8j7rtc3hgbh0" 2025/11/15 03:30:34 DEBUG : Rmdir: contains trashed file: "150fuo3cn4j1uenq6r4g4qk6t4" 2025/11/15 03:30:34 DEBUG : Rmdir: contains trashed file: "vjrnln8ratgmqakfosrqe8espk" 2025/11/15 03:30:34 DEBUG : Rmdir: contains trashed file: "to8qa1vn2r6dftei4e0knjo6o4" 2025/11/15 03:30:34 DEBUG : Rmdir: contains trashed file: "0828u6q2p8d62qeklrkkuf10uc" 2025/11/15 03:30:34 DEBUG : Rmdir: contains trashed file: "0828u6q2p8d62qeklrkkuf10uc" 2025/11/15 03:30:34 DEBUG : Rmdir: contains trashed file: "to8qa1vn2r6dftei4e0knjo6o4" 2025/11/15 03:30:34 DEBUG : Rmdir: contains trashed file: "0828u6q2p8d62qeklrkkuf10uc" 2025/11/15 03:30:34 DEBUG : Rmdir: contains trashed file: "c4shrkuhic7ob3njfv3g2s2vik" 2025/11/15 03:30:34 DEBUG : Rmdir: contains trashed file: "to8qa1vn2r6dftei4e0knjo6o4" 2025/11/15 03:30:34 DEBUG : Rmdir: contains trashed file: "0828u6q2p8d62qeklrkkuf10uc" 2025/11/15 03:30:34 DEBUG : Rmdir: contains trashed file: "bdfh2jnp94io268p9p6mr133n4" 2025/11/15 03:30:34 DEBUG : Rmdir: contains trashed file: "1atl8vl4e0t9vhahglkub15g9k" 2025/11/15 03:30:34 DEBUG : Rmdir: contains trashed file: "0828u6q2p8d62qeklrkkuf10uc" 2025/11/15 03:30:34 DEBUG : Rmdir: contains trashed file: "1atl8vl4e0t9vhahglkub15g9k" 2025/11/15 03:30:34 DEBUG : Rmdir: contains trashed file: "0828u6q2p8d62qeklrkkuf10uc" 2025/11/15 03:30:34 DEBUG : Rmdir: contains trashed file: "1atl8vl4e0t9vhahglkub15g9k" 2025/11/15 03:30:34 DEBUG : Rmdir: contains trashed file: "1atl8vl4e0t9vhahglkub15g9k" 2025/11/15 03:30:34 DEBUG : Rmdir: contains trashed file: "1atl8vl4e0t9vhahglkub15g9k" 2025/11/15 03:30:34 DEBUG : Rmdir: contains trashed file: "tg4p894oov5pg84onakp5iir70" 2025/11/15 03:30:34 DEBUG : Rmdir: contains trashed file: "tg4p894oov5pg84onakp5iir70" 2025/11/15 03:30:34 DEBUG : Rmdir: contains trashed file: "1atl8vl4e0t9vhahglkub15g9k" 2025/11/15 03:30:34 DEBUG : Rmdir: contains trashed file: "d26uovf0miqhd1mu3c9jbseq00" 2025/11/15 03:30:34 DEBUG : Rmdir: contains trashed file: "1atl8vl4e0t9vhahglkub15g9k" 2025/11/15 03:30:34 DEBUG : Rmdir: contains trashed file: "1atl8vl4e0t9vhahglkub15g9k" 2025/11/15 03:30:34 DEBUG : Rmdir: contains trashed file: "0828u6q2p8d62qeklrkkuf10uc" 2025/11/15 03:30:34 DEBUG : Rmdir: contains trashed file: "tg4p894oov5pg84onakp5iir70" 2025/11/15 03:30:34 DEBUG : Rmdir: contains trashed file: "tg4p894oov5pg84onakp5iir70" 2025/11/15 03:30:34 DEBUG : Rmdir: contains trashed file: "0828u6q2p8d62qeklrkkuf10uc" 2025/11/15 03:30:34 DEBUG : Rmdir: contains trashed file: "0828u6q2p8d62qeklrkkuf10uc" 2025/11/15 03:30:34 DEBUG : Rmdir: contains trashed file: "89l8renevh3mji1c8r1p7qn6tg" 2025/11/15 03:30:34 DEBUG : Rmdir: contains trashed file: "09ptdmd3vn9ng72gtig19k3tqg" 2025/11/15 03:30:34 DEBUG : Rmdir: contains trashed file: "89l8renevh3mji1c8r1p7qn6tg" 2025/11/15 03:30:34 DEBUG : Rmdir: contains trashed file: "m5t7ukfh84vcomkjqcneu2d7uo" 2025/11/15 03:30:34 DEBUG : Rmdir: contains trashed file: "0828u6q2p8d62qeklrkkuf10uc" 2025/11/15 03:30:34 DEBUG : Rmdir: contains trashed file: "09ptdmd3vn9ng72gtig19k3tqg" 2025/11/15 03:30:34 DEBUG : Rmdir: contains trashed file: "160p8jokk6e0sqr9khoaqluoic" 2025/11/15 03:30:34 DEBUG : Rmdir: contains trashed file: "0828u6q2p8d62qeklrkkuf10uc" 2025/11/15 03:30:34 DEBUG : Rmdir: contains trashed file: "0828u6q2p8d62qeklrkkuf10uc" 2025/11/15 03:30:34 DEBUG : Rmdir: contains trashed file: "89l8renevh3mji1c8r1p7qn6tg" 2025/11/15 03:30:34 DEBUG : Rmdir: contains trashed file: "0828u6q2p8d62qeklrkkuf10uc" 2025/11/15 03:30:34 DEBUG : Rmdir: contains trashed file: "09ptdmd3vn9ng72gtig19k3tqg" 2025/11/15 03:30:34 DEBUG : Rmdir: contains trashed file: "4u4fjlma3lemqjvkevbirqdmms" 2025/11/15 03:30:34 DEBUG : Rmdir: contains trashed file: "09ptdmd3vn9ng72gtig19k3tqg" 2025/11/15 03:30:34 DEBUG : Rmdir: contains trashed file: "89l8renevh3mji1c8r1p7qn6tg" 2025/11/15 03:30:34 DEBUG : Rmdir: contains trashed file: "e9p3gp5bt94lpg91gpij093918" 2025/11/15 03:30:34 DEBUG : Rmdir: contains trashed file: "89l8renevh3mji1c8r1p7qn6tg" 2025/11/15 03:30:34 DEBUG : Rmdir: contains trashed file: "q5ep28d6fiuvnifhronkrc1h8c" 2025/11/15 03:30:34 DEBUG : Rmdir: contains trashed file: "m5t7ukfh84vcomkjqcneu2d7uo" 2025/11/15 03:30:34 DEBUG : Rmdir: contains trashed file: "4u4fjlma3lemqjvkevbirqdmms" 2025/11/15 03:30:34 DEBUG : Rmdir: contains trashed file: "q5ep28d6fiuvnifhronkrc1h8c" 2025/11/15 03:30:34 DEBUG : Rmdir: contains trashed file: "160p8jokk6e0sqr9khoaqluoic" 2025/11/15 03:30:34 DEBUG : Rmdir: contains trashed file: "0828u6q2p8d62qeklrkkuf10uc" 2025/11/15 03:30:34 DEBUG : Rmdir: contains trashed file: "89l8renevh3mji1c8r1p7qn6tg" 2025/11/15 03:30:34 DEBUG : Rmdir: contains trashed file: "eer8kka55qnghc34cq76ca668g" 2025/11/15 03:30:34 DEBUG : Rmdir: contains trashed file: "0828u6q2p8d62qeklrkkuf10uc" 2025/11/15 03:30:34 DEBUG : Rmdir: contains trashed file: "0828u6q2p8d62qeklrkkuf10uc" 2025/11/15 03:30:34 DEBUG : Rmdir: contains trashed file: "89l8renevh3mji1c8r1p7qn6tg" 2025/11/15 03:30:34 DEBUG : Rmdir: contains trashed file: "0828u6q2p8d62qeklrkkuf10uc" 2025/11/15 03:30:34 DEBUG : Rmdir: contains trashed file: "q75kr6k6e4u4qcpfacnefr56vg" 2025/11/15 03:30:34 DEBUG : Rmdir: contains trashed file: "1hetg8lme4rcdmnfbr0koj9m74" 2025/11/15 03:30:34 DEBUG : Rmdir: contains trashed file: "2a4ubhneaj47tjcufnjilco248" 2025/11/15 03:30:34 DEBUG : Rmdir: contains trashed file: "q5ep28d6fiuvnifhronkrc1h8c" 2025/11/15 03:30:34 DEBUG : Rmdir: contains trashed file: "1hetg8lme4rcdmnfbr0koj9m74" 2025/11/15 03:30:34 DEBUG : Rmdir: contains trashed file: "eer8kka55qnghc34cq76ca668g" 2025/11/15 03:30:34 DEBUG : Rmdir: contains trashed file: "89l8renevh3mji1c8r1p7qn6tg" 2025/11/15 03:30:34 DEBUG : Rmdir: contains trashed file: "0828u6q2p8d62qeklrkkuf10uc" 2025/11/15 03:30:34 DEBUG : Rmdir: contains trashed file: "0828u6q2p8d62qeklrkkuf10uc" 2025/11/15 03:30:34 DEBUG : Rmdir: contains trashed file: "160p8jokk6e0sqr9khoaqluoic" 2025/11/15 03:30:34 DEBUG : Rmdir: contains trashed file: "eer8kka55qnghc34cq76ca668g" 2025/11/15 03:30:34 DEBUG : Rmdir: contains trashed file: "0828u6q2p8d62qeklrkkuf10uc" 2025/11/15 03:30:34 DEBUG : Rmdir: contains trashed file: "160p8jokk6e0sqr9khoaqluoic" --- PASS: TestRmdirsNoLeaveRoot (24.21s) === RUN TestRmdirsLeaveRoot run.go:185: Remote "Encrypted drive 'TestCryptDrive:rclone-test-varoqut0vomu'", Local "Local file system at /tmp/rclone2444379526", Modify Window "1ms" 2025/11/15 03:30:36 INFO : A1: Making directory 2025/11/15 03:30:37 INFO : A1/B1: Making directory 2025/11/15 03:30:38 INFO : A1/B1/C1: Making directory 2025/11/15 03:30:41 DEBUG : removing 1 level 3 directories 2025/11/15 03:30:41 INFO : A1/B1/C1: Removing directory 2025/11/15 03:30:42 DEBUG : removing 1 level 2 directories 2025/11/15 03:30:42 INFO : A1/B1: Removing directory 2025/11/15 03:30:42 DEBUG : h0fgn6vkka25jj9fhu5roml6pg/rv5l12j0nanu5af9ivc97ofdk8: Rmdir: contains trashed file: "gtu1leijh9rlm05pcjtusqop2g" 2025/11/15 03:30:43 DEBUG : h0fgn6vkka25jj9fhu5roml6pg: Rmdir: contains trashed file: "rv5l12j0nanu5af9ivc97ofdk8" --- PASS: TestRmdirsLeaveRoot (8.66s) === RUN TestRmdirsWithFilter run.go:185: Remote "Encrypted drive 'TestCryptDrive:rclone-test-varoqut0vomu'", Local "Local file system at /tmp/rclone2444379526", Modify Window "1ms" 2025/11/15 03:30:44 INFO : A1: Making directory 2025/11/15 03:30:45 INFO : A1/B1: Making directory 2025/11/15 03:30:46 INFO : A1/B1/C1: Making directory 2025/11/15 03:30:48 DEBUG : removing 1 level 3 directories 2025/11/15 03:30:48 INFO : A1/B1/C1: Removing directory 2025/11/15 03:30:49 DEBUG : removing 1 level 2 directories 2025/11/15 03:30:49 INFO : A1/B1: Removing directory 2025/11/15 03:30:49 DEBUG : h0fgn6vkka25jj9fhu5roml6pg/rv5l12j0nanu5af9ivc97ofdk8: Rmdir: contains trashed file: "gtu1leijh9rlm05pcjtusqop2g" 2025/11/15 03:30:51 DEBUG : h0fgn6vkka25jj9fhu5roml6pg: Rmdir: contains trashed file: "rv5l12j0nanu5af9ivc97ofdk8" --- PASS: TestRmdirsWithFilter (7.84s) === RUN TestCopyURL run.go:185: Remote "Encrypted drive 'TestCryptDrive:rclone-test-varoqut0vomu'", Local "Local file system at /tmp/rclone2444379526", Modify Window "1ms" 2025/11/15 03:30:54 DEBUG : file1: md5 = 1d7ffdc326e0fae81348e1407ef529f7 OK 2025/11/15 03:30:55 DEBUG : filename.txt: File name found in url 2025/11/15 03:30:56 DEBUG : filename.txt: md5 = 803e756214f682d77049090a0c4dd449 OK 2025/11/15 03:30:56 DEBUG : headerfilename.txt: filename found in Content-Disposition header. 2025/11/15 03:30:58 DEBUG : headerfilename.txt: md5 = 1ef7fdaf9566d9253d0f9c0862edcbf8 OK 2025/11/15 03:30:59 DEBUG : file2: md5 = eb29c771f20d8581682cd8bc5066f79d OK --- PASS: TestCopyURL (9.86s) === RUN TestCopyURLToWriter --- PASS: TestCopyURLToWriter (0.00s) === RUN TestMoveFile run.go:185: Remote "Encrypted drive 'TestCryptDrive:rclone-test-varoqut0vomu'", Local "Local file system at /tmp/rclone2444379526", Modify Window "1ms" 2025/11/15 03:31:02 DEBUG : file1: Need to transfer - File not found at Destination 2025/11/15 03:31:04 DEBUG : sub/file2: md5 = a1f3590539fcb5d7eae02ac1ad0903cb OK 2025/11/15 03:31:04 DEBUG : sub/file2: size = 14 OK 2025/11/15 03:31:04 INFO : file1: Copied (new) to: sub/file2 2025/11/15 03:31:04 INFO : file1: Deleted 2025/11/15 03:31:05 DEBUG : sub/file2: size = 14 OK 2025/11/15 03:31:05 DEBUG : file1: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2025/11/15 03:31:05 DEBUG : file1: Unchanged skipping 2025/11/15 03:31:05 INFO : file1: Deleted 2025/11/15 03:31:06 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-varoqut0vomu': don't need to copy/move sub/file2, it is already at target location 2025/11/15 03:31:07 DEBUG : 150fuo3cn4j1uenq6r4g4qk6t4: Rmdir: contains trashed file: "d26uovf0miqhd1mu3c9jbseq00" --- PASS: TestMoveFile (6.28s) === RUN TestMoveFileWithIgnoreExisting run.go:185: Remote "Encrypted drive 'TestCryptDrive:rclone-test-varoqut0vomu'", Local "Local file system at /tmp/rclone2444379526", Modify Window "1ms" 2025/11/15 03:31:08 DEBUG : file1: Need to transfer - File not found at Destination 2025/11/15 03:31:10 DEBUG : file1: md5 = 5d5e6918de0f676e347bea82624b1a9a OK 2025/11/15 03:31:10 DEBUG : file1: size = 14 OK 2025/11/15 03:31:10 INFO : file1: Copied (new) 2025/11/15 03:31:10 INFO : file1: Deleted 2025/11/15 03:31:11 DEBUG : file1: Destination exists, skipping 2025/11/15 03:31:11 DEBUG : file1: Not removing source file as destination file exists and --ignore-existing is set --- PASS: TestMoveFileWithIgnoreExisting (3.54s) === RUN TestCaseInsensitiveMoveFile run.go:185: Remote "Encrypted drive 'TestCryptDrive:rclone-test-varoqut0vomu'", Local "Local file system at /tmp/rclone2444379526", Modify Window "1ms" --- PASS: TestCaseInsensitiveMoveFile (0.56s) === RUN TestCaseInsensitiveMoveFileDryRun run.go:185: Remote "Encrypted drive 'TestCryptDrive:rclone-test-varoqut0vomu'", Local "Local file system at /tmp/rclone2444379526", Modify Window "1ms" --- PASS: TestCaseInsensitiveMoveFileDryRun (0.47s) === RUN TestMoveFileBackupDir run.go:185: Remote "Encrypted drive 'TestCryptDrive:rclone-test-varoqut0vomu'", Local "Local file system at /tmp/rclone2444379526", Modify Window "1ms" 2025/11/15 03:31:15 DEBUG : dst/file1: md5 = 190f284c383f781da85addd9f854d95a OK 2025/11/15 03:31:16 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-varoqut0vomu/backup" 2025/11/15 03:31:16 DEBUG : Creating backend with remote "TestDrive:crypt/i7rtc15m75acpkcu99de1eu6ik7sc5tlp31a4jpi4vfn339844bg/1nrff024r7pq65ecp72fc28jb0" 2025/11/15 03:31:17 DEBUG : dst/file1: size = 14 (Local file system at /tmp/rclone2444379526) 2025/11/15 03:31:17 DEBUG : dst/file1: size = 18 (Encrypted drive 'TestCryptDrive:rclone-test-varoqut0vomu') 2025/11/15 03:31:17 DEBUG : dst/file1: Sizes differ 2025/11/15 03:31:20 INFO : dst/file1: Moved (server-side) 2025/11/15 03:31:21 DEBUG : dst/file1: md5 = 2bf8b75b8bcafebcaae92ba52ddcd640 OK 2025/11/15 03:31:21 DEBUG : dst/file1: size = 14 OK 2025/11/15 03:31:21 INFO : dst/file1: Copied (new) 2025/11/15 03:31:21 INFO : dst/file1: Deleted 2025/11/15 03:31:24 DEBUG : 31u3jie661vd5p8j7rtc3hgbh0: Rmdir: contains trashed file: "eer8kka55qnghc34cq76ca668g" 2025/11/15 03:31:25 DEBUG : 1nrff024r7pq65ecp72fc28jb0/31u3jie661vd5p8j7rtc3hgbh0: Rmdir: contains trashed file: "eer8kka55qnghc34cq76ca668g" 2025/11/15 03:31:26 DEBUG : 1nrff024r7pq65ecp72fc28jb0: Rmdir: contains trashed file: "31u3jie661vd5p8j7rtc3hgbh0" --- PASS: TestMoveFileBackupDir (13.48s) === RUN TestSameConfig --- PASS: TestSameConfig (0.00s) === RUN TestSame --- PASS: TestSame (0.00s) === RUN TestOverlappingFilterCheckWithoutFilter --- PASS: TestOverlappingFilterCheckWithoutFilter (0.00s) === RUN TestOverlappingFilterCheckWithFilter --- PASS: TestOverlappingFilterCheckWithFilter (0.00s) === RUN TestListFormat --- PASS: TestListFormat (0.00s) === RUN TestDirMove run.go:185: Remote "Encrypted drive 'TestCryptDrive:rclone-test-varoqut0vomu'", Local "Local file system at /tmp/rclone2444379526", Modify Window "1ms" 2025/11/15 03:31:29 DEBUG : A1/one: md5 = cf785b382b1d202048c9d7599271569c OK 2025/11/15 03:31:30 DEBUG : A1/two: md5 = b77ba5d3514d65d3d2cbbeff3963a712 OK 2025/11/15 03:31:32 DEBUG : A1/B1/three: md5 = c3d43410551957dff1c2cd7bce4e8227 OK 2025/11/15 03:31:35 DEBUG : A1/B1/C1/four: md5 = e18fb57df2dc0d00e91e8346e9abff85 OK 2025/11/15 03:31:37 DEBUG : A1/B1/C2/five: md5 = de5c5864b42b7d348cbe60e05ed6dd44 OK 2025/11/15 03:31:37 INFO : A1/B2: Making directory 2025/11/15 03:31:38 INFO : A1/B1/C3: Making directory 2025/11/15 03:31:48 INFO : A2/one: Moved (server-side) to: A3/one 2025/11/15 03:31:48 INFO : A2/B1/C2/five: Moved (server-side) to: A3/B1/C2/five 2025/11/15 03:31:48 INFO : A2/B1/C1/four: Moved (server-side) to: A3/B1/C1/four 2025/11/15 03:31:48 INFO : A2/B1/three: Moved (server-side) to: A3/B1/three 2025/11/15 03:31:48 INFO : A2/two: Moved (server-side) to: A3/two 2025/11/15 03:31:51 DEBUG : c3k31g9ua29pvlul21td3bpldo/rv5l12j0nanu5af9ivc97ofdk8: Rmdir: contains trashed file: "ui2vk9n52l82tdno99vhkfi87g" 2025/11/15 03:31:51 DEBUG : c3k31g9ua29pvlul21td3bpldo/rv5l12j0nanu5af9ivc97ofdk8: Rmdir: contains trashed file: "k43jqb5391s8afh9eje7baui0g" 2025/11/15 03:31:51 DEBUG : c3k31g9ua29pvlul21td3bpldo/rv5l12j0nanu5af9ivc97ofdk8: Rmdir: contains trashed file: "gtu1leijh9rlm05pcjtusqop2g" 2025/11/15 03:31:52 DEBUG : c3k31g9ua29pvlul21td3bpldo: Rmdir: contains trashed file: "avn2ljdtrs3fgdela1haatj3go" 2025/11/15 03:31:52 DEBUG : c3k31g9ua29pvlul21td3bpldo: Rmdir: contains trashed file: "rv5l12j0nanu5af9ivc97ofdk8" 2025/11/15 03:31:54 INFO : Encrypted drive 'TestCryptDrive:rclone-test-varoqut0vomu': Can't DirMove - falling back to file moves: can't move directory - incompatible remotes 2025/11/15 03:32:01 INFO : A3/one: Moved (server-side) to: A4/one 2025/11/15 03:32:01 INFO : A3/B1/C2/five: Moved (server-side) to: A4/B1/C2/five 2025/11/15 03:32:01 INFO : A3/B1/C1/four: Moved (server-side) to: A4/B1/C1/four 2025/11/15 03:32:01 INFO : A3/B1/three: Moved (server-side) to: A4/B1/three 2025/11/15 03:32:01 INFO : A3/two: Moved (server-side) to: A4/two 2025/11/15 03:32:04 DEBUG : fppqbfrji50odnh2br069japlo/rv5l12j0nanu5af9ivc97ofdk8: Rmdir: contains trashed file: "ui2vk9n52l82tdno99vhkfi87g" 2025/11/15 03:32:04 DEBUG : fppqbfrji50odnh2br069japlo/rv5l12j0nanu5af9ivc97ofdk8: Rmdir: contains trashed file: "k43jqb5391s8afh9eje7baui0g" 2025/11/15 03:32:04 DEBUG : fppqbfrji50odnh2br069japlo/rv5l12j0nanu5af9ivc97ofdk8: Rmdir: contains trashed file: "gtu1leijh9rlm05pcjtusqop2g" 2025/11/15 03:32:05 DEBUG : fppqbfrji50odnh2br069japlo: Rmdir: contains trashed file: "avn2ljdtrs3fgdela1haatj3go" 2025/11/15 03:32:05 DEBUG : fppqbfrji50odnh2br069japlo: Rmdir: contains trashed file: "rv5l12j0nanu5af9ivc97ofdk8" 2025/11/15 03:32:11 DEBUG : kafked7s86nmf4k5e2iil1i668/rv5l12j0nanu5af9ivc97ofdk8/k43jqb5391s8afh9eje7baui0g: Rmdir: contains trashed file: "67t67jgahg44dflpavut88pang" 2025/11/15 03:32:12 DEBUG : kafked7s86nmf4k5e2iil1i668/rv5l12j0nanu5af9ivc97ofdk8/gtu1leijh9rlm05pcjtusqop2g: Rmdir: contains trashed file: "r2oabfeuilgbi5ic6kevi8chd0" 2025/11/15 03:32:13 DEBUG : kafked7s86nmf4k5e2iil1i668/rv5l12j0nanu5af9ivc97ofdk8: Rmdir: contains trashed file: "ui2vk9n52l82tdno99vhkfi87g" 2025/11/15 03:32:13 DEBUG : kafked7s86nmf4k5e2iil1i668/rv5l12j0nanu5af9ivc97ofdk8: Rmdir: contains trashed file: "k43jqb5391s8afh9eje7baui0g" 2025/11/15 03:32:13 DEBUG : kafked7s86nmf4k5e2iil1i668/rv5l12j0nanu5af9ivc97ofdk8: Rmdir: contains trashed file: "gtu1leijh9rlm05pcjtusqop2g" 2025/11/15 03:32:13 DEBUG : kafked7s86nmf4k5e2iil1i668/rv5l12j0nanu5af9ivc97ofdk8: Rmdir: contains trashed file: "b6jdmn2b1adrhc8vikh7edr9os" 2025/11/15 03:32:13 DEBUG : kafked7s86nmf4k5e2iil1i668: Rmdir: contains trashed file: "avn2ljdtrs3fgdela1haatj3go" 2025/11/15 03:32:13 DEBUG : kafked7s86nmf4k5e2iil1i668: Rmdir: contains trashed file: "rv5l12j0nanu5af9ivc97ofdk8" 2025/11/15 03:32:13 DEBUG : kafked7s86nmf4k5e2iil1i668: Rmdir: contains trashed file: "keafetfvtbmgo6tsdvcbj32i0k" 2025/11/15 03:32:13 DEBUG : kafked7s86nmf4k5e2iil1i668: Rmdir: contains trashed file: "0828u6q2p8d62qeklrkkuf10uc" --- PASS: TestDirMove (47.83s) === RUN TestGetFsInfo run.go:185: Remote "Encrypted drive 'TestCryptDrive:rclone-test-varoqut0vomu'", Local "Local file system at /tmp/rclone2444379526", Modify Window "1ms" --- PASS: TestGetFsInfo (0.49s) === RUN TestRcat === RUN TestRcat/withChecksum=false,ignoreChecksum=false run.go:185: Remote "Encrypted drive 'TestCryptDrive:rclone-test-varoqut0vomu'", Local "Local file system at /tmp/rclone2444379526", Modify Window "1ms" 2025/11/15 03:32:15 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-varoqut0vomu': File to upload is small (34 bytes), uploading instead of streaming 2025/11/15 03:32:16 DEBUG : no_checksum_small_file_from_pipe: md5 = d34f91868b838e12d57ffd373a61652b OK 2025/11/15 03:32:16 DEBUG : no_checksum_small_file_from_pipe: size = 34 OK 2025/11/15 03:32:16 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-varoqut0vomu': --checksum is in use but the source and destination have no hashes in common; falling back to --size-only 2025/11/15 03:32:16 DEBUG : no_checksum_small_file_from_pipe: Size of src and dst objects identical 2025/11/15 03:32:17 DEBUG : qc9k4uauqa9ls6a2r6lo8jf4b1m7r0510pkurois1cc70egbs4h0: Sending chunk 0 length 102465 2025/11/15 03:32:19 DEBUG : no_checksum_big_file_from_pipe: md5 = f6e2da3a0ceaecabb45178acec97e7e8 OK 2025/11/15 03:32:19 DEBUG : no_checksum_big_file_from_pipe: size = 102401 OK 2025/11/15 03:32:19 DEBUG : no_checksum_big_file_from_pipe: Size of src and dst objects identical === RUN TestRcat/withChecksum=true,ignoreChecksum=false run.go:185: Remote "Encrypted drive 'TestCryptDrive:rclone-test-varoqut0vomu'", Local "Local file system at /tmp/rclone2444379526", Modify Window "1ms" 2025/11/15 03:32:21 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-varoqut0vomu': File to upload is small (34 bytes), uploading instead of streaming 2025/11/15 03:32:23 DEBUG : with_checksum_small_file_from_pipe: md5 = 1e4f94885689fc036a09212775f78a5f OK 2025/11/15 03:32:23 DEBUG : with_checksum_small_file_from_pipe: size = 34 OK 2025/11/15 03:32:23 DEBUG : with_checksum_small_file_from_pipe: Size of src and dst objects identical 2025/11/15 03:32:23 DEBUG : e74jo8a7lr1aimg2u78fct67gnuetc1qujiofaci0c8fnq7rjt1d4i296pcklqhgdihs03etvgdi4: Sending chunk 0 length 102465 2025/11/15 03:32:24 DEBUG : with_checksum_big_file_from_pipe: md5 = 59273cb8b07b1bab362d414c25b7841a OK 2025/11/15 03:32:24 DEBUG : with_checksum_big_file_from_pipe: size = 102401 OK 2025/11/15 03:32:24 DEBUG : with_checksum_big_file_from_pipe: Size of src and dst objects identical === RUN TestRcat/withChecksum=false,ignoreChecksum=true run.go:185: Remote "Encrypted drive 'TestCryptDrive:rclone-test-varoqut0vomu'", Local "Local file system at /tmp/rclone2444379526", Modify Window "1ms" 2025/11/15 03:32:26 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-varoqut0vomu': File to upload is small (34 bytes), uploading instead of streaming 2025/11/15 03:32:28 DEBUG : ignore_checksum_small_file_from_pipe: size = 34 OK 2025/11/15 03:32:28 DEBUG : ignore_checksum_small_file_from_pipe: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2025/11/15 03:32:29 DEBUG : 1963rn6olajfbjn4nbqi9nacu7bk879vpf0d2bg8r0ndcqqsfntob0urt056p0qujf574rqnb5f62: Sending chunk 0 length 102465 2025/11/15 03:32:30 DEBUG : ignore_checksum_big_file_from_pipe: size = 102401 OK 2025/11/15 03:32:30 DEBUG : ignore_checksum_big_file_from_pipe: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) === RUN TestRcat/withChecksum=true,ignoreChecksum=true run.go:185: Remote "Encrypted drive 'TestCryptDrive:rclone-test-varoqut0vomu'", Local "Local file system at /tmp/rclone2444379526", Modify Window "1ms" 2025/11/15 03:32:32 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-varoqut0vomu': File to upload is small (34 bytes), uploading instead of streaming 2025/11/15 03:32:33 DEBUG : ignore_checksum_small_file_from_pipe: size = 34 OK 2025/11/15 03:32:33 DEBUG : ignore_checksum_small_file_from_pipe: Size of src and dst objects identical 2025/11/15 03:32:34 DEBUG : 1963rn6olajfbjn4nbqi9nacu7bk879vpf0d2bg8r0ndcqqsfntob0urt056p0qujf574rqnb5f62: Sending chunk 0 length 102465 2025/11/15 03:32:35 DEBUG : ignore_checksum_big_file_from_pipe: size = 102401 OK 2025/11/15 03:32:35 DEBUG : ignore_checksum_big_file_from_pipe: Size of src and dst objects identical --- PASS: TestRcat (22.16s) --- PASS: TestRcat/withChecksum=false,ignoreChecksum=false (6.08s) --- PASS: TestRcat/withChecksum=true,ignoreChecksum=false (5.53s) --- PASS: TestRcat/withChecksum=false,ignoreChecksum=true (5.30s) --- PASS: TestRcat/withChecksum=true,ignoreChecksum=true (5.25s) === RUN TestRcatMetadata run.go:185: Remote "Encrypted drive 'TestCryptDrive:rclone-test-varoqut0vomu'", Local "Local file system at /tmp/rclone2444379526", Modify Window "1ms" === RUN TestRcatMetadata/Normal 2025/11/15 03:32:37 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-varoqut0vomu': File to upload is small (48 bytes), uploading instead of streaming 2025/11/15 03:32:39 DEBUG : rcat_metadata: md5 = 6ed79398f82681579c5f872bb7f6580a OK 2025/11/15 03:32:39 DEBUG : rcat_metadata: size = 48 OK 2025/11/15 03:32:39 DEBUG : rcat_metadata: Size of src and dst objects identical === RUN TestRcatMetadata/ViaDisk 2025/11/15 03:32:41 DEBUG : rijssoobhbn85c5p1imhg55m6frank1nlplla6hkdjlqmqb2471g: Sending chunk 0 length 111 2025/11/15 03:32:42 DEBUG : rcat_metadata_uploadcutoff0: md5 = e36dcac36b6e0c561abd29441b80171c OK 2025/11/15 03:32:42 DEBUG : rcat_metadata_uploadcutoff0: size = 63 OK 2025/11/15 03:32:42 DEBUG : rcat_metadata_uploadcutoff0: Size of src and dst objects identical --- PASS: TestRcatMetadata (6.44s) --- PASS: TestRcatMetadata/Normal (2.89s) --- PASS: TestRcatMetadata/ViaDisk (2.97s) === RUN TestRcatSize run.go:185: Remote "Encrypted drive 'TestCryptDrive:rclone-test-varoqut0vomu'", Local "Local file system at /tmp/rclone2444379526", Modify Window "1ms" 2025/11/15 03:32:45 DEBUG : potato1: md5 = c7dfb564774ab6b105f504da08c100a0 OK 2025/11/15 03:32:45 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-varoqut0vomu': File to upload is small (60 bytes), uploading instead of streaming 2025/11/15 03:32:46 DEBUG : potato2: md5 = d475aae99baf860707c328b3621c965a OK 2025/11/15 03:32:46 DEBUG : potato2: size = 60 OK 2025/11/15 03:32:46 DEBUG : potato2: Size of src and dst objects identical --- PASS: TestRcatSize (4.76s) === RUN TestRcatSizeMetadata run.go:185: Remote "Encrypted drive 'TestCryptDrive:rclone-test-varoqut0vomu'", Local "Local file system at /tmp/rclone2444379526", Modify Window "1ms" 2025/11/15 03:32:50 DEBUG : potato1: md5 = 00cb3dcd2bf661b522f1407c50f8d314 OK 2025/11/15 03:32:50 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-varoqut0vomu': File to upload is small (60 bytes), uploading instead of streaming 2025/11/15 03:32:51 DEBUG : potato2: md5 = 309d3fa5c0aba187bf8b059a1a89064c OK 2025/11/15 03:32:51 DEBUG : potato2: size = 60 OK 2025/11/15 03:32:51 DEBUG : potato2: Size of src and dst objects identical --- PASS: TestRcatSizeMetadata (5.91s) === RUN TestTouchDir run.go:185: Remote "Encrypted drive 'TestCryptDrive:rclone-test-varoqut0vomu'", Local "Local file system at /tmp/rclone2444379526", Modify Window "1ms" 2025/11/15 03:32:55 DEBUG : potato2: md5 = 1f4b20e7209b8746301ee75b76d63681 OK 2025/11/15 03:32:57 DEBUG : empty space: md5 = fd43e36baab845136cb7a1a0a4d88b8a OK 2025/11/15 03:33:00 DEBUG : sub dir/potato3: md5 = 233e648f7197e5cc2fa1feb3ee121ef4 OK 2025/11/15 03:33:01 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-varoqut0vomu': Touching "sub dir/potato3" 2025/11/15 03:33:01 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-varoqut0vomu': Touching "empty space" 2025/11/15 03:33:01 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-varoqut0vomu': Touching "potato2" 2025/11/15 03:33:04 DEBUG : 14begaa36ih249fgrnfk6nulg0: Rmdir: contains trashed file: "9atai6og4mljg3r5gjeh27k0q8" --- PASS: TestTouchDir (11.21s) === RUN TestMkdirMetadata run.go:185: Remote "Encrypted drive 'TestCryptDrive:rclone-test-varoqut0vomu'", Local "Local file system at /tmp/rclone2444379526", Modify Window "1ms" 2025/11/15 03:33:05 DEBUG : dir with metadata: Making directory with metadata 2025/11/15 03:33:06 INFO : dir with metadata: Made directory with metadata (mtime=2001-02-03T04:05:06.499999999Z) --- PASS: TestMkdirMetadata (2.72s) === RUN TestMkdirModTime run.go:185: Remote "Encrypted drive 'TestCryptDrive:rclone-test-varoqut0vomu'", Local "Local file system at /tmp/rclone2444379526", Modify Window "1ms" 2025/11/15 03:33:08 DEBUG : directory with modtime: Making directory with metadata 2025/11/15 03:33:09 INFO : directory with modtime: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) --- PASS: TestMkdirModTime (2.49s) === RUN TestCopyDirMetadata run.go:185: Remote "Encrypted drive 'TestCryptDrive:rclone-test-varoqut0vomu'", Local "Local file system at /tmp/rclone2444379526", Modify Window "1ms" 2025/11/15 03:33:10 DEBUG : dir with metadata to be copied: Making directory with metadata 2025/11/15 03:33:10 INFO : dir with metadata to be copied: Made directory with metadata (mtime=2001-02-03T04:05:06.499999999Z) 2025/11/15 03:33:11 DEBUG : Google drive root 'crypt/i7rtc15m75acpkcu99de1eu6ik7sc5tlp31a4jpi4vfn339844bg': Skipping btime metadata as can't update it on an existing file: 2025-11-15T03:33:10.75700907Z 2025/11/15 03:33:11 INFO : non existent directory: Updated directory metadata 2025/11/15 03:33:13 DEBUG : Google drive root 'crypt/i7rtc15m75acpkcu99de1eu6ik7sc5tlp31a4jpi4vfn339844bg': Skipping btime metadata as can't update it on an existing file: 2025-11-15T03:33:10.75700907Z 2025/11/15 03:33:14 INFO : existing directory: Updated directory metadata --- PASS: TestCopyDirMetadata (5.09s) === RUN TestSetDirModTime run.go:185: Remote "Encrypted drive 'TestCryptDrive:rclone-test-varoqut0vomu'", Local "Local file system at /tmp/rclone2444379526", Modify Window "1ms" 2025/11/15 03:33:15 DEBUG : set modtime on non existent directory: Skipping set directory modification time as --no-update-dir-modtime is set 2025/11/15 03:33:17 INFO : set modtime on existing directory: Set directory modification time (using SetModTime) 2025/11/15 03:33:18 INFO : set modtime on existing directory: Set directory modification time (using DirSetModTime) --- PASS: TestSetDirModTime (4.41s) === RUN TestDirsEqual run.go:185: Remote "Encrypted drive 'TestCryptDrive:rclone-test-varoqut0vomu'", Local "Local file system at /tmp/rclone2444379526", Modify Window "1ms" 2025/11/15 03:33:20 DEBUG : dir with metadata to be copied: Making directory with metadata 2025/11/15 03:33:20 INFO : dir with metadata to be copied: Made directory with metadata (mtime=2001-02-03T04:05:06.499999999Z) 2025/11/15 03:33:20 DEBUG : dst: Making directory with metadata 2025/11/15 03:33:21 INFO : dst: Made directory with metadata (mtime=2001-02-03T04:05:06.499999999Z) 2025/11/15 03:33:21 DEBUG : dst: Directory modification time the same (differ by -999.999µs, within tolerance 1ms) 2025/11/15 03:33:21 INFO : dst: Set directory modification time (using SetModTime) 2025/11/15 03:33:22 INFO : dst: Set directory modification time (using SetModTime) 2025/11/15 03:33:22 DEBUG : dst: Directory modification time the same (differ by 1ns, within tolerance 1ms) 2025/11/15 03:33:22 INFO : dst: Set directory modification time (using SetModTime) 2025/11/15 03:33:22 DEBUG : dst: Destination directory is newer than source, skipping --- PASS: TestDirsEqual (3.72s) === RUN TestRemoveExisting run.go:185: Remote "Encrypted drive 'TestCryptDrive:rclone-test-varoqut0vomu'", Local "Local file system at /tmp/rclone2444379526", Modify Window "1ms" 2025/11/15 03:33:26 DEBUG : sub dir/test remove existing: md5 = 03355a7aac07d81c3eae458a86fe17c0 OK 2025/11/15 03:33:27 DEBUG : sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890123456789: md5 = 877f6306c7138181485e9c0213c0092e OK 2025/11/15 03:33:30 DEBUG : sub dir/test remove existing: TEST: renaming existing object to "sub dir/test remove existing.kijicag4" before starting 2025/11/15 03:33:31 DEBUG : sub dir/test remove existing.kijicag4: TEST: removing renamed existing file after operation 2025/11/15 03:33:32 DEBUG : sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890123456789: TEST: renaming existing object to "sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890.jamutuy2" before starting 2025/11/15 03:33:33 DEBUG : sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890.jamutuy2: TEST: renaming existing back after failed operation 2025/11/15 03:33:34 DEBUG : sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890123456789: TEST: renaming existing object to "sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890.fifegan5" before starting 2025/11/15 03:33:35 DEBUG : sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890.fifegan5: TEST: removing renamed existing file after operation 2025/11/15 03:33:37 DEBUG : 14begaa36ih249fgrnfk6nulg0: Rmdir: contains trashed file: "ht4jaici6bnetlo66r9meh1tdjosr4oukr5pfkvmkhi2qgi6uhqg" 2025/11/15 03:33:37 DEBUG : 14begaa36ih249fgrnfk6nulg0: Rmdir: contains trashed file: "nqqhil8ard8jvfq0glgt57j2mdlils7ubo337imfsdmgnli69vmomauqm6g1tcvui2k0qu02r6bd5fe09jilms46o4q6p191apgc7h7uassrm18e6kb2fn5i5qh0jph1pcdr115mk7q3b2m5odnfa412t1lgtct3spuungq3dv0efn2ce4f0" --- PASS: TestRemoveExisting (14.39s) === RUN TestRcAbout rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcAbout (0.00s) === RUN TestRcCleanup rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcCleanup (0.00s) === RUN TestRcCopyfile rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcCopyfile (0.00s) === RUN TestRcCopyurl rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcCopyurl (0.00s) === RUN TestRcDelete rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcDelete (0.00s) === RUN TestRcDeletefile rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcDeletefile (0.00s) === RUN TestRcList rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcList (0.00s) === RUN TestRcStat rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcStat (0.00s) === RUN TestRcSetTier rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcSetTier (0.00s) === RUN TestRcSetTierFile rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcSetTierFile (0.00s) === RUN TestRcMkdir rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcMkdir (0.00s) === RUN TestRcMovefile rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcMovefile (0.00s) === RUN TestRcPurge rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcPurge (0.00s) === RUN TestRcRmdir rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcRmdir (0.00s) === RUN TestRcRmdirs rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcRmdirs (0.00s) === RUN TestRcSize rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcSize (0.00s) === RUN TestRcPublicLink rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcPublicLink (0.00s) === RUN TestRcFsInfo rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcFsInfo (0.00s) === RUN TestUploadFile rc_test.go:30: Skipping test on non local remote --- SKIP: TestUploadFile (0.00s) === RUN TestRcCommand rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcCommand (0.00s) === RUN TestRcDu rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcDu (0.00s) === RUN TestRcCheck rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcCheck (0.00s) === RUN TestRcHashsum rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcHashsum (0.00s) === RUN TestRcHashsumFile rc_test.go:30: Skipping test on non local remote --- SKIP: TestRcHashsumFile (0.00s) PASS 2025/11/15 03:33:38 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-varoqut0vomu': Purge remote "./operations.test -test.v -test.timeout 1h0m0s -remote TestCryptDrive: -verbose" - Finished OK in 11m49.161576661s (try 1/5)